builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0369 starttime: 1480554244.29 results: success (0) buildid: 20161130144745 builduid: bf0e0039912146a5893729d35524dee4 revision: fa39ccc915c12f39eb51bd55871266f23fb5e999 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 17:04:04.294674) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 17:04:04.295167) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 17:04:04.295442) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 17:04:04.322214) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:04:04.322808) ========= 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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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-30 17:04:04-- 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% 18.4M=0.001s 2016-11-30 17:04:04 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135001 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:04:04.481701) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:04:04.482032) ========= 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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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.026144 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:04:04.532468) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-30 17:04:04.533083) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fa39ccc915c12f39eb51bd55871266f23fb5e999 --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 fa39ccc915c12f39eb51bd55871266f23fb5e999 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Bm2xrc6FSc/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.zqOcHYsznU/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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-30 17:04:04,633 truncating revision to first 12 chars 2016-11-30 17:04:04,634 Setting DEBUG logging. 2016-11-30 17:04:04,634 attempt 1/10 2016-11-30 17:04:04,634 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fa39ccc915c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-30 17:04:05,624 unpacking tar archive at: mozilla-aurora-fa39ccc915c1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.268346 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-30 17:04:05.826082) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 17:04:05.826397) ========= script_repo_revision: fa39ccc915c12f39eb51bd55871266f23fb5e999 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 17:04:05.826771) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 17:04:05.827049) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 17:04:05.847269) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-30 17:04:05.847999) ========= /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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 17:04:06 INFO - MultiFileLogger online at 20161130 17:04:06 in /builds/slave/test 17:04:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:04:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:04:06 INFO - {'append_to_log': False, 17:04:06 INFO - 'base_work_dir': '/builds/slave/test', 17:04:06 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:04:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:04:06 INFO - 'branch': 'Mozilla-Aurora', 17:04:06 INFO - 'buildbot_json_path': 'buildprops.json', 17:04:06 INFO - 'config_files': ('talos/mac_config.py',), 17:04:06 INFO - 'default_actions': ('clobber', 17:04:06 INFO - 'read-buildbot-config', 17:04:06 INFO - 'download-and-extract', 17:04:06 INFO - 'populate-webroot', 17:04:06 INFO - 'create-virtualenv', 17:04:06 INFO - 'install', 17:04:06 INFO - 'run-tests'), 17:04:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:04:06 INFO - 'download_minidump_stackwalk': True, 17:04:06 INFO - 'download_symbols': 'ondemand', 17:04:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:04:06 INFO - 'tooltool.py': '/tools/tooltool.py', 17:04:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:04:06 INFO - '/tools/misc-python/virtualenv.py')}, 17:04:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:04:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:04:06 INFO - 'installer_path': 'installer.exe', 17:04:06 INFO - 'log_level': 'info', 17:04:06 INFO - 'log_name': 'talos', 17:04:06 INFO - 'log_to_console': True, 17:04:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:04:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:04:06 INFO - 'opt_config_files': (), 17:04:06 INFO - 'pip_index': False, 17:04:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:04:06 INFO - 'cmd': ('bash', 17:04:06 INFO - '-c', 17:04:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:04:06 INFO - 'enabled': True, 17:04:06 INFO - 'halt_on_failure': False, 17:04:06 INFO - 'name': 'check_screen_resolution'},), 17:04:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:04:06 INFO - 'cmd': ('bash', 17:04:06 INFO - '-c', 17:04:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:04:06 INFO - 'enabled': True, 17:04:06 INFO - 'halt_on_failure': False, 17:04:06 INFO - 'name': 'check_screen_resolution'},), 17:04:06 INFO - 'run_cmd_checks_enabled': True, 17:04:06 INFO - 'sps_profile': False, 17:04:06 INFO - 'sps_profile_interval': 0, 17:04:06 INFO - 'suite': 'dromaeojs', 17:04:06 INFO - 'system_bits': '32', 17:04:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:04:06 INFO - 'title': 't-yosemite-r7-0369', 17:04:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:04:06 INFO - 'use_talos_json': True, 17:04:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:04:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:04:06 INFO - 'work_dir': 'build'} 17:04:06 INFO - [mozharness: 2016-12-01 01:04:06.058708Z] Running clobber step. 17:04:06 INFO - Running pre-action listener: _resource_record_pre_action 17:04:06 INFO - Running main action method: clobber 17:04:06 INFO - rmtree: /builds/slave/test/build 17:04:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:04:08 INFO - Running post-action listener: _resource_record_post_action 17:04:08 INFO - [mozharness: 2016-12-01 01:04:08.059704Z] Finished clobber step (success) 17:04:08 INFO - [mozharness: 2016-12-01 01:04:08.059951Z] Running read-buildbot-config step. 17:04:08 INFO - Running pre-action listener: _resource_record_pre_action 17:04:08 INFO - Running main action method: read_buildbot_config 17:04:08 INFO - Using buildbot properties: 17:04:08 INFO - { 17:04:08 INFO - "project": "", 17:04:08 INFO - "product": "firefox", 17:04:08 INFO - "script_repo_revision": "production", 17:04:08 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 17:04:08 INFO - "repository": "", 17:04:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 17:04:08 INFO - "buildid": "20161130144745", 17:04:08 INFO - "pgo_build": "False", 17:04:08 INFO - "basedir": "/builds/slave/test", 17:04:08 INFO - "buildnumber": 12, 17:04:08 INFO - "slavename": "t-yosemite-r7-0369", 17:04:08 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 17:04:08 INFO - "platform": "yosemite_r7", 17:04:08 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 17:04:08 INFO - "branch": "mozilla-aurora", 17:04:08 INFO - "repo_path": "releases/mozilla-aurora", 17:04:08 INFO - "revision": "fa39ccc915c12f39eb51bd55871266f23fb5e999", 17:04:08 INFO - "stage_platform": "macosx64", 17:04:08 INFO - "builduid": "bf0e0039912146a5893729d35524dee4", 17:04:08 INFO - "slavebuilddir": "test" 17:04:08 INFO - } 17:04:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 17:04:08 INFO - Running post-action listener: _resource_record_post_action 17:04:08 INFO - [mozharness: 2016-12-01 01:04:08.065355Z] Finished read-buildbot-config step (success) 17:04:08 INFO - [mozharness: 2016-12-01 01:04:08.065533Z] Running download-and-extract step. 17:04:08 INFO - Running pre-action listener: _resource_record_pre_action 17:04:08 INFO - Running main action method: download_and_extract 17:04:08 INFO - mkdir: /builds/slave/test/build/tests 17:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:08 INFO - trying https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 17:04:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/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 17:04:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/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 17:04:09 INFO - Downloaded 1199 bytes. 17:04:09 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 17:04:09 INFO - Using the following test package requirements: 17:04:09 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 17:04:09 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:04:09 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 17:04:09 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:04:09 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 17:04:09 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:04:09 INFO - u'jsshell-mac.zip'], 17:04:09 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:04:09 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 17:04:09 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 17:04:09 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:04:09 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 17:04:09 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:04:09 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 17:04:09 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:04:09 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 17:04:09 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:04:09 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 17:04:09 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 17:04:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 17:04:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:04:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 17:04:09 INFO - Http code: 200 17:04:09 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 17:04:09 INFO - x-amz-cf-id: 37CPWceOMZlYgkMXyvm3OEGwgqq-kjYwGvocVpRx60Lnb5KE9w1QIw== 17:04:09 INFO - x-amz-version-id: tXxNspwGobvQf9UC_JZlU34aYTqNt_5_ 17:04:10 INFO - Expected file size: 16285305 17:04:10 INFO - Obtained file size: 16285305 17:04:16 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 17:04:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 17:04:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:04:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 17:04:17 INFO - Http code: 200 17:04:17 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 17:04:17 INFO - x-amz-cf-id: B7B7hTHL28NVWtDl7I16uihT9pol-vhfRx-O0NHkeJnQC-i0Y78z6w== 17:04:17 INFO - x-amz-version-id: tXxNspwGobvQf9UC_JZlU34aYTqNt_5_ 17:04:17 INFO - Expected file size: 16285305 17:04:17 INFO - Obtained file size: 16285305 17:04:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 17:04:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 17:04:23 INFO - Fetch https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 17:04:24 INFO - Http code: 200 17:04:24 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 17:04:24 INFO - x-amz-cf-id: VH5jxai6VmITZ26SQL3OoVoBIsUrSIw4VjBbjhO062FL5P3zAnuMdQ== 17:04:24 INFO - x-amz-version-id: FNiVDiv4ztrpr6ARZbm.jASlQTMVVnhh 17:04:24 INFO - Expected file size: 14409038 17:04:24 INFO - Obtained file size: 14409038 17:04:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:25 INFO - trying https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:04:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 17:04:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/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 17:04:27 INFO - Downloaded 109759238 bytes. 17:04:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:04:27 INFO - mkdir: /builds/slave/test/properties 17:04:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:04:27 INFO - Writing to file /builds/slave/test/properties/build_url 17:04:27 INFO - Contents: 17:04:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:04:28 INFO - Running post-action listener: _resource_record_post_action 17:04:28 INFO - Running post-action listener: set_extra_try_arguments 17:04:28 INFO - [mozharness: 2016-12-01 01:04:28.655639Z] Finished download-and-extract step (success) 17:04:28 INFO - [mozharness: 2016-12-01 01:04:28.655752Z] Running populate-webroot step. 17:04:28 INFO - Running pre-action listener: _resource_record_pre_action 17:04:28 INFO - Running main action method: populate_webroot 17:04:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:04:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:04:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:04:28 INFO - '--tppagecycles', 17:04:28 INFO - '7'], 17:04:28 INFO - 'tests': ['tsvgm']}}, 17:04:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:04:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:04:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:04:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:04:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:04:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:04:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:04:28 INFO - 'talos_options': ['--disable-e10s'], 17:04:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:04:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:04:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:04:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:04:28 INFO - 'talos_options': ['--disable-e10s'], 17:04:28 INFO - 'tests': ['damp', 'tps']}, 17:04:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:04:28 INFO - 'tests': ['damp', 'tps']}, 17:04:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:04:28 INFO - 'tests': ['dromaeo_dom']}, 17:04:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:04:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:04:28 INFO - 'tests': ['basic_compositor_video']}, 17:04:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:04:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:04:28 INFO - 'tests': ['a11yr', 17:04:28 INFO - 'ts_paint', 17:04:28 INFO - 'tpaint', 17:04:28 INFO - 'sessionrestore', 17:04:28 INFO - 'sessionrestore_no_auto_restore', 17:04:28 INFO - 'tabpaint']}, 17:04:28 INFO - 'other-e10s': {'tests': ['a11yr', 17:04:28 INFO - 'ts_paint', 17:04:28 INFO - 'tpaint', 17:04:28 INFO - 'sessionrestore', 17:04:28 INFO - 'sessionrestore_no_auto_restore', 17:04:28 INFO - 'tabpaint']}, 17:04:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:04:28 INFO - 'ts_paint', 17:04:28 INFO - 'tpaint', 17:04:28 INFO - 'sessionrestore', 17:04:28 INFO - 'sessionrestore_no_auto_restore', 17:04:28 INFO - 'tabpaint']}, 17:04:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:04:28 INFO - 'ts_paint', 17:04:28 INFO - 'tpaint', 17:04:28 INFO - 'sessionrestore', 17:04:28 INFO - 'sessionrestore_no_auto_restore', 17:04:28 INFO - 'tabpaint']}, 17:04:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:04:28 INFO - 'tests': ['a11yr', 17:04:28 INFO - 'ts_paint', 17:04:28 INFO - 'tpaint', 17:04:28 INFO - 'sessionrestore', 17:04:28 INFO - 'sessionrestore_no_auto_restore', 17:04:28 INFO - 'tabpaint']}, 17:04:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:04:28 INFO - 'tests': ['a11yr', 17:04:28 INFO - 'ts_paint', 17:04:28 INFO - 'tpaint', 17:04:28 INFO - 'sessionrestore', 17:04:28 INFO - 'sessionrestore_no_auto_restore', 17:04:28 INFO - 'tabpaint']}, 17:04:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:04:28 INFO - 'tests': ['tsvgx', 17:04:28 INFO - 'tsvgr_opacity', 17:04:28 INFO - 'tart', 17:04:28 INFO - 'tscrollx', 17:04:28 INFO - 'cart']}, 17:04:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:04:28 INFO - 'tsvgr_opacity', 17:04:28 INFO - 'tart', 17:04:28 INFO - 'tscrollx', 17:04:28 INFO - 'cart']}, 17:04:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:04:28 INFO - 'talos_options': ['--disable-e10s'], 17:04:28 INFO - 'tests': ['tp5o']}, 17:04:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:04:28 INFO - 'tests': ['tp5o']}, 17:04:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:04:28 INFO - 'talos_options': ['--disable-e10s', 17:04:28 INFO - '--xperf_path', 17:04:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:04:28 INFO - 'tests': ['tp5n']}, 17:04:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:04:28 INFO - 'talos_options': ['--xperf_path', 17:04:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:04:28 INFO - 'tests': ['tp5n']}}, 17:04:28 INFO - 'talos.zip': {'path': '', 17:04:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:04:28 INFO - Running post-action listener: _resource_record_post_action 17:04:28 INFO - [mozharness: 2016-12-01 01:04:28.674588Z] Finished populate-webroot step (success) 17:04:28 INFO - [mozharness: 2016-12-01 01:04:28.674671Z] Running create-virtualenv step. 17:04:28 INFO - Running pre-action listener: _resource_record_pre_action 17:04:28 INFO - Running main action method: create_virtualenv 17:04:28 INFO - Creating virtualenv /builds/slave/test/build/venv 17:04:28 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 17:04:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:04:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:04:28 INFO - Using real prefix '/tools/python27' 17:04:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:04:29 INFO - Installing distribute.............................................................................................................................................................................................done. 17:04:32 INFO - Installing pip.................done. 17:04:32 INFO - Return code: 0 17:04:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:04:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:04:32 INFO - Reading from file tmpfile_stdout 17:04:32 INFO - Output received: 17:04:32 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) 17:04:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:04:32 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')]} 17:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:32 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')]} 17:04:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:32 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 0x109f52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ec8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e8573bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d66d30>, '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 0x109bccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5eb20>, '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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 17:04:32 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 17:04:32 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 17:04:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bm2xrc6FSc/Render', 17:04:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:32 INFO - 'HOME': '/Users/cltbld', 17:04:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:32 INFO - 'LOGNAME': 'cltbld', 17:04:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:32 INFO - 'MOZ_NO_REMOTE': '1', 17:04:32 INFO - 'NO_EM_RESTART': '1', 17:04:32 INFO - 'PAGER': '/bin/cat', 17:04:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:32 INFO - 'PWD': '/builds/slave/test', 17:04:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:32 INFO - 'SHELL': '/bin/bash', 17:04:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqOcHYsznU/Listeners', 17:04:32 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 17:04:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:32 INFO - 'USER': 'cltbld', 17:04:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:32 INFO - 'XPC_FLAGS': '0x0', 17:04:32 INFO - 'XPC_SERVICE_NAME': '0', 17:04:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:32 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 17:04:32 INFO - Cleaning up... 17:04:33 INFO - Return code: 0 17:04:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:04:33 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')]} 17:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:33 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')]} 17:04:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:33 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 0x109f52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ec8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e8573bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d66d30>, '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 0x109bccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5eb20>, '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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 17:04:33 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 17:04:33 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 17:04:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bm2xrc6FSc/Render', 17:04:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:33 INFO - 'HOME': '/Users/cltbld', 17:04:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:33 INFO - 'LOGNAME': 'cltbld', 17:04:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:33 INFO - 'MOZ_NO_REMOTE': '1', 17:04:33 INFO - 'NO_EM_RESTART': '1', 17:04:33 INFO - 'PAGER': '/bin/cat', 17:04:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:33 INFO - 'PWD': '/builds/slave/test', 17:04:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:33 INFO - 'SHELL': '/bin/bash', 17:04:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqOcHYsznU/Listeners', 17:04:33 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 17:04:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:33 INFO - 'USER': 'cltbld', 17:04:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:33 INFO - 'XPC_FLAGS': '0x0', 17:04:33 INFO - 'XPC_SERVICE_NAME': '0', 17:04:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:33 INFO - Downloading/unpacking psutil>=3.1.1 17:04:33 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 17:04:33 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 17:04:33 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 17:04:33 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 17:04:33 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 17:04:33 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 17:04:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:04:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:04:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:04:36 INFO - Installing collected packages: psutil 17:04:36 INFO - Running setup.py install for psutil 17:04:36 INFO - building 'psutil._psutil_osx' extension 17:04:36 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 17:04:37 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 17:04:37 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 17:04:37 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 17:04:37 INFO - building 'psutil._psutil_posix' extension 17:04:37 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 17:04:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:04:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:04:37 INFO - ^ 17:04:37 INFO - 1 warning generated. 17:04:37 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 17:04:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:04:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:04:37 INFO - Successfully installed psutil 17:04:37 INFO - Cleaning up... 17:04:38 INFO - Return code: 0 17:04:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:38 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 0x109f52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ec8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e8573bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d66d30>, '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 0x109bccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5eb20>, '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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 17:04:38 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 17:04:38 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 17:04:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bm2xrc6FSc/Render', 17:04:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:38 INFO - 'HOME': '/Users/cltbld', 17:04:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:38 INFO - 'LOGNAME': 'cltbld', 17:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:38 INFO - 'MOZ_NO_REMOTE': '1', 17:04:38 INFO - 'NO_EM_RESTART': '1', 17:04:38 INFO - 'PAGER': '/bin/cat', 17:04:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:38 INFO - 'PWD': '/builds/slave/test', 17:04:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:38 INFO - 'SHELL': '/bin/bash', 17:04:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqOcHYsznU/Listeners', 17:04:38 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 17:04:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:38 INFO - 'USER': 'cltbld', 17:04:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:38 INFO - 'XPC_FLAGS': '0x0', 17:04:38 INFO - 'XPC_SERVICE_NAME': '0', 17:04:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:04:38 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 17:04:38 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 17:04:38 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 17:04:38 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 17:04:38 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 17:04:38 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 17:04:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:04:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:04:40 INFO - Installing collected packages: mozsystemmonitor 17:04:40 INFO - Running setup.py install for mozsystemmonitor 17:04:41 INFO - Successfully installed mozsystemmonitor 17:04:41 INFO - Cleaning up... 17:04:41 INFO - Return code: 0 17:04:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:41 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 0x109f52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ec8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e8573bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d66d30>, '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 0x109bccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5eb20>, '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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 17:04:41 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 17:04:41 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 17:04:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bm2xrc6FSc/Render', 17:04:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:41 INFO - 'HOME': '/Users/cltbld', 17:04:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:41 INFO - 'LOGNAME': 'cltbld', 17:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:41 INFO - 'MOZ_NO_REMOTE': '1', 17:04:41 INFO - 'NO_EM_RESTART': '1', 17:04:41 INFO - 'PAGER': '/bin/cat', 17:04:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:41 INFO - 'PWD': '/builds/slave/test', 17:04:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:41 INFO - 'SHELL': '/bin/bash', 17:04:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqOcHYsznU/Listeners', 17:04:41 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 17:04:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:41 INFO - 'USER': 'cltbld', 17:04:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:41 INFO - 'XPC_FLAGS': '0x0', 17:04:41 INFO - 'XPC_SERVICE_NAME': '0', 17:04:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:41 INFO - Downloading/unpacking jsonschema==2.5.1 17:04: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 17:04: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 17:04: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 17:04: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 17:04: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 17:04: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 17:04:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:04:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:04: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 17:04: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 17:04: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 17:04: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 17:04: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 17:04: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 17:04:44 INFO - Downloading functools32-3.2.3-2.tar.gz 17:04:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:04:44 INFO - warning: no files found matching '*.txt' 17:04:44 INFO - no previously-included directories found matching 'build' 17:04:44 INFO - no previously-included directories found matching 'dist' 17:04:44 INFO - no previously-included directories found matching '.git*' 17:04:44 INFO - Installing collected packages: jsonschema, functools32 17:04:44 INFO - Running setup.py install for functools32 17:04:44 INFO - warning: no files found matching '*.txt' 17:04:44 INFO - no previously-included directories found matching 'build' 17:04:44 INFO - no previously-included directories found matching 'dist' 17:04:44 INFO - no previously-included directories found matching '.git*' 17:04:44 INFO - Successfully installed jsonschema functools32 17:04:44 INFO - Cleaning up... 17:04:44 INFO - Return code: 0 17:04:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:04:44 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')]} 17:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:44 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')]} 17:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:44 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 0x109f52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ec8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e8573bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d66d30>, '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 0x109bccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5eb20>, '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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 17:04:44 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 17:04:44 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 17:04:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bm2xrc6FSc/Render', 17:04:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:44 INFO - 'HOME': '/Users/cltbld', 17:04:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:44 INFO - 'LOGNAME': 'cltbld', 17:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:44 INFO - 'MOZ_NO_REMOTE': '1', 17:04:44 INFO - 'NO_EM_RESTART': '1', 17:04:44 INFO - 'PAGER': '/bin/cat', 17:04:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:44 INFO - 'PWD': '/builds/slave/test', 17:04:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:44 INFO - 'SHELL': '/bin/bash', 17:04:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqOcHYsznU/Listeners', 17:04:44 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 17:04:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:44 INFO - 'USER': 'cltbld', 17:04:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:44 INFO - 'XPC_FLAGS': '0x0', 17:04:44 INFO - 'XPC_SERVICE_NAME': '0', 17:04:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:04:45 INFO - Cleaning up... 17:04:45 INFO - Return code: 0 17:04:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109f52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ec8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e8573bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d66d30>, '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 0x109bccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5eb20>, '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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 17:04:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:04:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bm2xrc6FSc/Render', 17:04:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:45 INFO - 'HOME': '/Users/cltbld', 17:04:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:45 INFO - 'LOGNAME': 'cltbld', 17:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:45 INFO - 'MOZ_NO_REMOTE': '1', 17:04:45 INFO - 'NO_EM_RESTART': '1', 17:04:45 INFO - 'PAGER': '/bin/cat', 17:04:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:45 INFO - 'PWD': '/builds/slave/test', 17:04:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:45 INFO - 'SHELL': '/bin/bash', 17:04:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqOcHYsznU/Listeners', 17:04:45 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 17:04:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:45 INFO - 'USER': 'cltbld', 17:04:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:45 INFO - 'XPC_FLAGS': '0x0', 17:04:45 INFO - 'XPC_SERVICE_NAME': '0', 17:04:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:45 INFO - Downloading/unpacking blobuploader==1.2.4 17:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:47 INFO - Downloading blobuploader-1.2.4.tar.gz 17:04:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:04:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:04: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 17:04: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 17:04: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 17:04: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 17:04: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 17:04: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 17:04:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:04:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:04: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 17:04: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 17:04: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 17:04: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 17:04: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 17:04: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 17:04:49 INFO - Downloading docopt-0.6.1.tar.gz 17:04:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:04:49 INFO - Installing collected packages: blobuploader, requests, docopt 17:04:49 INFO - Running setup.py install for blobuploader 17:04:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:04:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:04:49 INFO - Running setup.py install for requests 17:04:49 INFO - Running setup.py install for docopt 17:04:50 INFO - Successfully installed blobuploader requests docopt 17:04:50 INFO - Cleaning up... 17:04:50 INFO - Return code: 0 17:04:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:50 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 0x109f52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ec8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e8573bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d66d30>, '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 0x109bccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5eb20>, '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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 17:04:50 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 17:04:50 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 17:04:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bm2xrc6FSc/Render', 17:04:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:50 INFO - 'HOME': '/Users/cltbld', 17:04:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:50 INFO - 'LOGNAME': 'cltbld', 17:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:50 INFO - 'MOZ_NO_REMOTE': '1', 17:04:50 INFO - 'NO_EM_RESTART': '1', 17:04:50 INFO - 'PAGER': '/bin/cat', 17:04:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:50 INFO - 'PWD': '/builds/slave/test', 17:04:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:50 INFO - 'SHELL': '/bin/bash', 17:04:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqOcHYsznU/Listeners', 17:04:50 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 17:04:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:50 INFO - 'USER': 'cltbld', 17:04:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:50 INFO - 'XPC_FLAGS': '0x0', 17:04:50 INFO - 'XPC_SERVICE_NAME': '0', 17:04:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:04:50 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-N45NKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:04:50 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-g8SF6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:04:50 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-fif2TI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:04:50 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-trwPwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:04:50 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-kD8pSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:04:50 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-FLINzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:04:51 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-Zwa18x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:04:51 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-OdT1cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:04:51 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-QWNseu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:04:51 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-FiKRwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:04:51 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-oNtLbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:04:51 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-4QHEyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:04:51 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-yR9lun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:04:51 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-KfvBWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:04:51 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-Dcxs0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:04:52 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-pZu3eU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:04:52 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-YRIKr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:04:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:04:52 INFO - Running setup.py install for manifestparser 17:04:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:04:52 INFO - Running setup.py install for mozcrash 17:04:52 INFO - Running setup.py install for mozdebug 17:04:52 INFO - Running setup.py install for mozdevice 17:04:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:04:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:04:52 INFO - Running setup.py install for mozfile 17:04:52 INFO - Running setup.py install for mozhttpd 17:04:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:04:53 INFO - Running setup.py install for mozinfo 17:04:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:04:53 INFO - Running setup.py install for mozInstall 17:04:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:04:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:04:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:04:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:04:53 INFO - Running setup.py install for mozleak 17:04:53 INFO - Running setup.py install for mozlog 17:04:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:04:53 INFO - Running setup.py install for moznetwork 17:04:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:04:53 INFO - Running setup.py install for mozprocess 17:04:54 INFO - Running setup.py install for mozprofile 17:04:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:04:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:04:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:04:54 INFO - Running setup.py install for mozrunner 17:04:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:04:54 INFO - Running setup.py install for mozscreenshot 17:04:54 INFO - Running setup.py install for moztest 17:04:54 INFO - Running setup.py install for mozversion 17:04:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:04:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:04:54 INFO - Cleaning up... 17:04:54 INFO - Return code: 0 17:04:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:54 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 0x109f52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ec8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e8573bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d66d30>, '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 0x109bccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5eb20>, '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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 17:04:54 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 17:04:54 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 17:04:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bm2xrc6FSc/Render', 17:04:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:54 INFO - 'HOME': '/Users/cltbld', 17:04:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:54 INFO - 'LOGNAME': 'cltbld', 17:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:54 INFO - 'MOZ_NO_REMOTE': '1', 17:04:54 INFO - 'NO_EM_RESTART': '1', 17:04:54 INFO - 'PAGER': '/bin/cat', 17:04:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:54 INFO - 'PWD': '/builds/slave/test', 17:04:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:54 INFO - 'SHELL': '/bin/bash', 17:04:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqOcHYsznU/Listeners', 17:04:54 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 17:04:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:54 INFO - 'USER': 'cltbld', 17:04:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:54 INFO - 'XPC_FLAGS': '0x0', 17:04:54 INFO - 'XPC_SERVICE_NAME': '0', 17:04:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:04:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-y5A9Ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:04:55 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)) 17:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:04:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-bLNL2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:04:55 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)) 17:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:04:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-lM9XqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:04:55 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)) 17:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:04:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-4OhqJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:04:55 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)) 17:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:04:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-qMrTi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:04:55 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)) 17:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:04:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-Grj1Bo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:04:55 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)) 17:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:04:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-hc5_RO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:04:55 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)) 17:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:04:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-qQ7eR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:04:55 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)) 17:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:04:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-2uMrzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:04:56 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)) 17:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:04:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-N3yIon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:04:56 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)) 17:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:04:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-18OHo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:04:56 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)) 17:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:04:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-_0ESU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:04:56 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)) 17:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:04:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-WOrtcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:04:56 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)) 17:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:04:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-fvWL2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:04:56 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)) 17:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:04:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-w_k96P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:04:56 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)) 17:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:04:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-ieU7oO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:04:56 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)) 17:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:04:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-jr65Ba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:04:56 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)) 17:04:56 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)) 17:04:56 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)) 17:04:56 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)) 17:04:56 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)) 17:04:56 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)) 17:04:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:04:56 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 17:04:56 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 17:04:56 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 17:04:56 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 17:04:56 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 17:04:56 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 17:04:59 INFO - Downloading blessings-1.6.tar.gz 17:04:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:04:59 INFO - Installing collected packages: blessings 17:04:59 INFO - Running setup.py install for blessings 17:04:59 INFO - Successfully installed blessings 17:04:59 INFO - Cleaning up... 17:04:59 INFO - Return code: 0 17:04:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:04:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:04:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:05:00 INFO - Reading from file tmpfile_stdout 17:05:00 INFO - Current package versions: 17:05:00 INFO - blessings == 1.6 17:05:00 INFO - blobuploader == 1.2.4 17:05:00 INFO - docopt == 0.6.1 17:05:00 INFO - functools32 == 3.2.3-2 17:05:00 INFO - jsonschema == 2.5.1 17:05:00 INFO - manifestparser == 1.1 17:05:00 INFO - mozInstall == 1.12 17:05:00 INFO - mozcrash == 1.0 17:05:00 INFO - mozdebug == 0.1 17:05:00 INFO - mozdevice == 0.48 17:05:00 INFO - mozfile == 1.2 17:05:00 INFO - mozhttpd == 0.7 17:05:00 INFO - mozinfo == 0.9 17:05:00 INFO - mozleak == 0.1 17:05:00 INFO - mozlog == 3.4 17:05:00 INFO - moznetwork == 0.27 17:05:00 INFO - mozprocess == 0.23 17:05:00 INFO - mozprofile == 0.28 17:05:00 INFO - mozrunner == 6.13 17:05:00 INFO - mozscreenshot == 0.1 17:05:00 INFO - mozsystemmonitor == 0.3 17:05:00 INFO - moztest == 0.7 17:05:00 INFO - mozversion == 1.4 17:05:00 INFO - psutil == 3.1.1 17:05:00 INFO - requests == 1.2.3 17:05:00 INFO - wsgiref == 0.1.2 17:05:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:05:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:01 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 0x109f52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ec8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e8573bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d66d30>, '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 0x109bccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5eb20>, '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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 17:05:01 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 17:05:01 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 17:05:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bm2xrc6FSc/Render', 17:05:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:01 INFO - 'HOME': '/Users/cltbld', 17:05:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:01 INFO - 'LOGNAME': 'cltbld', 17:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:01 INFO - 'MOZ_NO_REMOTE': '1', 17:05:01 INFO - 'NO_EM_RESTART': '1', 17:05:01 INFO - 'PAGER': '/bin/cat', 17:05:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:01 INFO - 'PWD': '/builds/slave/test', 17:05:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:01 INFO - 'SHELL': '/bin/bash', 17:05:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqOcHYsznU/Listeners', 17:05:01 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 17:05:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:01 INFO - 'USER': 'cltbld', 17:05:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:01 INFO - 'XPC_FLAGS': '0x0', 17:05:01 INFO - 'XPC_SERVICE_NAME': '0', 17:05:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:01 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)) 17:05:01 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)) 17:05:01 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)) 17:05:01 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)) 17:05:01 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)) 17:05:01 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)) 17:05:01 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)) 17:05:01 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)) 17:05:01 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)) 17:05:01 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)) 17:05:01 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)) 17:05:01 INFO - Cleaning up... 17:05:01 INFO - Return code: 0 17:05:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:02 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 0x109f52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ec8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e8573bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d66d30>, '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 0x109bccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5eb20>, '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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 17:05:02 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 17:05:02 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 17:05:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bm2xrc6FSc/Render', 17:05:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:02 INFO - 'HOME': '/Users/cltbld', 17:05:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:02 INFO - 'LOGNAME': 'cltbld', 17:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:02 INFO - 'MOZ_NO_REMOTE': '1', 17:05:02 INFO - 'NO_EM_RESTART': '1', 17:05:02 INFO - 'PAGER': '/bin/cat', 17:05:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:02 INFO - 'PWD': '/builds/slave/test', 17:05:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:02 INFO - 'SHELL': '/bin/bash', 17:05:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqOcHYsznU/Listeners', 17:05:02 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 17:05:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:02 INFO - 'USER': 'cltbld', 17:05:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:02 INFO - 'XPC_FLAGS': '0x0', 17:05:02 INFO - 'XPC_SERVICE_NAME': '0', 17:05:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:05:02 INFO - Cleaning up... 17:05:02 INFO - Return code: 0 17:05:02 INFO - Running post-action listener: _resource_record_post_action 17:05:02 INFO - Running post-action listener: _start_resource_monitoring 17:05:02 INFO - Starting resource monitoring. 17:05:02 INFO - [mozharness: 2016-12-01 01:05:02.311195Z] Finished create-virtualenv step (success) 17:05:02 INFO - [mozharness: 2016-12-01 01:05:02.312249Z] Running install step. 17:05:02 INFO - Running pre-action listener: _resource_record_pre_action 17:05:02 INFO - Running main action method: install 17:05:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:05:02 INFO - Reading from file tmpfile_stdout 17:05:02 INFO - Detecting whether we're running mozinstall >=1.0... 17:05:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:05:02 INFO - Reading from file tmpfile_stdout 17:05:02 INFO - Output received: 17:05:02 INFO - Usage: mozinstall [options] installer 17:05:02 INFO - Options: 17:05:02 INFO - -h, --help show this help message and exit 17:05:02 INFO - -d DEST, --destination=DEST 17:05:02 INFO - Directory to install application into. [default: 17:05:02 INFO - "/builds/slave/test"] 17:05:02 INFO - --app=APP Application being installed. [default: firefox] 17:05:02 INFO - mkdir: /builds/slave/test/build/application 17:05:02 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'] 17:05:02 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 17:05:34 INFO - Reading from file tmpfile_stdout 17:05:34 INFO - Output received: 17:05:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:05:34 INFO - Running post-action listener: _resource_record_post_action 17:05:34 INFO - [mozharness: 2016-12-01 01:05:34.041782Z] Finished install step (success) 17:05:34 INFO - [mozharness: 2016-12-01 01:05:34.041966Z] Running run-tests step. 17:05:34 INFO - Running pre-action listener: _resource_record_pre_action 17:05:34 INFO - Running main action method: run_tests 17:05:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:05:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:05:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:05:34 INFO - 2016-11-30 17:05:34.056 screenresolution[1624:7507] starting screenresolution argv=screenresolution get 17:05:34 INFO - 2016-11-30 17:05:34.059 screenresolution[1624:7507] Display 0: 1600x1200x32@60 17:05:34 INFO - 2016-11-30 17:05:34.064 screenresolution[1625:7510] starting screenresolution argv=screenresolution list 17:05:34 INFO - Available Modes on Display 0 17:05:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:05:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:05:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:05:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:05:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:05:34 INFO - Intel Iris: 17:05:34 INFO - Chipset Model: Intel Iris 17:05:34 INFO - Type: GPU 17:05:34 INFO - Bus: Built-In 17:05:34 INFO - VRAM (Dynamic, Max): 1536 MB 17:05:34 INFO - Vendor: Intel (0x8086) 17:05:34 INFO - Device ID: 0x0a2e 17:05:34 INFO - Revision ID: 0x0009 17:05:34 INFO - Displays: 17:05:34 INFO - Display: 17:05:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:05:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:05:34 INFO - Display Serial Number: 1600x1200 60 17:05:34 INFO - Main Display: Yes 17:05:34 INFO - Mirror: Off 17:05:34 INFO - Online: Yes 17:05:34 INFO - Rotation: Supported 17:05:34 INFO - Return code: 0 17:05:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:05:34 INFO - Python 2.7.3 17:05:34 INFO - Return code: 0 17:05:34 INFO - grabbing minidump binary from tooltool 17:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:34 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 0x109d66d30>, '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 0x109bccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:05:34 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 17:05:34 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 17:05:34 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 17:05:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:05:34 INFO - Return code: 0 17:05:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:05:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:05:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:05:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:05:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:05:34 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/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0369', '--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 17:05:34 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/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0369 --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 17:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bm2xrc6FSc/Render', 17:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:34 INFO - 'HOME': '/Users/cltbld', 17:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:34 INFO - 'LOGNAME': 'cltbld', 17:05:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:05:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:34 INFO - 'MOZ_NO_REMOTE': '1', 17:05:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:05:34 INFO - 'NO_EM_RESTART': '1', 17:05:34 INFO - 'PAGER': '/bin/cat', 17:05:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:34 INFO - 'PWD': '/builds/slave/test', 17:05:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:34 INFO - 'SHELL': '/bin/bash', 17:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zqOcHYsznU/Listeners', 17:05:34 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 17:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:34 INFO - 'USER': 'cltbld', 17:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:34 INFO - 'XPC_FLAGS': '0x0', 17:05:34 INFO - 'XPC_SERVICE_NAME': '0', 17:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:34 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/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0369', '--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 17:05:34 INFO - mozversion application_buildid: 20161130144745 17:05:34 INFO - mozversion application_changeset: fa39ccc915c12f39eb51bd55871266f23fb5e999 17:05:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:05:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:05:34 INFO - mozversion application_name: Firefox 17:05:34 INFO - mozversion application_remotingname: firefox-dev 17:05:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:05:34 INFO - mozversion application_vendor: Mozilla 17:05:34 INFO - mozversion application_version: 52.0a2 17:05:34 INFO - mozversion platform_buildid: 20161130144745 17:05:34 INFO - mozversion platform_changeset: fa39ccc915c12f39eb51bd55871266f23fb5e999 17:05:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:05:34 INFO - mozversion platform_version: 52.0a2 17:05:34 INFO - using testdate: 1480554334 17:05:34 INFO - actual date: 1480554334 17:05:34 INFO - starting webserver on 'localhost:49238' 17:05:34 INFO - SUITE-START | Running 2 tests 17:05:34 INFO - TEST-START | dromaeo_css 17:05:34 INFO - operating with platform_type : mac_ 17:05:34 INFO - Initialising browser for dromaeo_css test... 17:05:34 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpT8Q1m6/profile http://localhost:49238/getInfo.html) 17:05:37 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 17:05:37 INFO - PROCESS | 1635 | colorDepth:24 17:05:37 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 17:05:37 INFO - PROCESS | 1635 | __metrics 17:05:37 INFO - PROCESS | 1635 | 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" 17:05:37 INFO - PROCESS | 1635 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:05:38 INFO - TEST-INFO | 1635: exit 0 17:05:38 INFO - Browser initialized. 17:05:38 INFO - Running cycle 1/1 for dromaeo_css test... 17:05:38 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpT8Q1m6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:05:50 INFO - PROCESS | 1637 | RSS: Main: 222572544 17:05:50 INFO - PROCESS | 1637 | 17:06:42 INFO - PROCESS | 1637 | 1480554402323 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] 17:08:55 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 17:08:57 INFO - PROCESS | 1637 | RSS: Main: 354402304 17:08:57 INFO - PROCESS | 1637 | 17:12:00 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 17:12:00 INFO - PROCESS | 1637 | RSS: Main: 349749248 17:12:00 INFO - PROCESS | 1637 | 17:15:02 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 17:15:03 INFO - PROCESS | 1637 | RSS: Main: 323981312 17:15:03 INFO - PROCESS | 1637 | 17:18:06 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 17:18:06 INFO - PROCESS | 1637 | RSS: Main: 331354112 17:18:06 INFO - PROCESS | 1637 | 17:21:09 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 17:21:10 INFO - PROCESS | 1637 | RSS: Main: 312303616 17:21:10 INFO - PROCESS | 1637 | 17:24:13 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 17:24:14 INFO - PROCESS | 1637 | RSS: Main: 361553920 17:24:14 INFO - PROCESS | 1637 | 17:24:14 INFO - PROCESS | 1637 | __start_tp_report 17:24:14 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 17:24:14 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 17:24:14 INFO - PROCESS | 1637 | |i|pagename|runs| 17:24:14 INFO - PROCESS | 1637 | |0;dojo.html;2741.259859837844;2880.6460963347217;3108.5026395776667;3272.051105179498;3302.1414432247607;4517.412736344275;5575.776968921238;5596.3564190118095;5605.355384130821;5620.606557540964;5519.227308176858;5581.8604534886645;5599.496045355927;5604.131359639263;5608.971955140229;5052.4947505249365;5107.080725469414;5157.71632560209;5174.689518628876;5180.119379705459;4958.132326842806;4964.87587810304;4977.751112444382;5067.847964561069;5110.1312776827845;5095.541401273885;5163.096458119833;5171.818986335479;5195.090859099642;5221.791128354861;5171.689698618095;5172.741362931876;5173.198154286073;5182.300389447433;5222.086134926372;4955.925661115086;4993;5097.337346145015;5105.234214867763;5121.8719532012;5491.780328786844;5511.090670039439;5557.1108622620595;5582.665040097607;5599.244102046212;6035.637861728266;6067.393260673942;6070.939290607082;6075.331713511554;6118.1434599156155;6186.103015062836;6188.164597779287;6200.596961197552;6208.782692605737;6220.253569571635;5476.91784623235;5557.916631250535;5565.2486914266865;5637.069883469264;5639.887202255973;6184.8763024739255;6309.621422714652;6310.274318453345;6319.0837328587095;6335.841603959938;5992.0712289595185;5995.910061349084;6006.879862402728;6008.729607167688;6010.604601978238;6027;6068.089786532056;6083.7870674526175;6139.048447490557;6158.907616385758;5953.315368732565;5994.730237139339;6001.549883758736;6066.181065556182;6085.148079268906;5919.408059194132;6034.5172386209;6050.092486127028;6055.757769689251;6059.939400606026;4741.478437371955;4764.499727528629;4831.613470922318;4833.661643684908;4838.830640927621;4310.612044915973;4371.868843934623;4394.340848872631;4404.4494438195225;4422.778861056934;5597.944020559824;5606.411326810708;5611.579131565068;5613.887722245532;5617.21359009739;6091.7563297467605;6094.421030002231;6098.878022439526;6105.694715264219;6107.236595425572;5559.63862348946;5603.943960560342;5609.411011843697;5613.045782217033;5613.438656134435;5239.083160446907;5261.789528418821;5263.236830659533;5263.473652634782;5269.051570717231;6226.62640241587;6241.126242326078;6255.24937007562;6257.155284036688;6261.405166509083;5705.57360659835;6068.21113255274;6075;6083.66539840313;6145.201123853871;6345.809625711236;6347.428731414195;6352.618842869442;6372.171617689671;6507.837304067247;5887.146363777314;5914.201582786269;5920;6003.879922401615;6047.304559975483;5972.731227094878;6100.206973093469;6135.202044401974;6146.446819786241;6168.444839964245;5818.883622327615;5925.496332811759;5967.492763115184;5979.790707325223;5988.311344195473;5709.25779648643;5774.7690092397515;5955.553333499918;5960.5827592068135;5965.135055416853;5685.886282274414;5693.914591281051;5744.798932037359;5756.30924289088;5826.533877289891;5951.375105613848;5952.077427998667;5965.940340596539;5968.582199246011;5970.761169553343;5771.105478650816;5771.134329850556;5775;5780.219670344618;5801.506871915934 17:24:14 INFO - PROCESS | 1637 | |1;ext.html;461.1031543647605;495.51192075805324;506.799814073441;506.91635880079775;519.1408219396894;29508.114756557312;30243.639036243367;31878.68121318786;39341.99999999996;40164.99587510314;41558.58441415585;41670.74987750365;41710.74867753965;41735.373969390486;41798.791006045;9241.861372079424;9424.528773561295;9448.57481413338;9458;9463.81072378552;8468.280196183332;8706.042335343107;8751.693690720826;8772.429792063527;8812.779680508007;11204.327740335566;11462.541498340057;11491.770164596703;11633.127515436334;11638.650840474756;11272.549098036068;11351.091912646969;11357.204995650307;11367.204295699305;11372.203945723802;8408.789780255482;8634.00708918476;10967.780644387147;11317;11401.942990284995;39550.60449395498;39919.401208981886;39925.201495969915;39953.400698989244;39954.80022599897;3268.3953468608147;3303.9504407433906;3310.2220978070136;3334.61651910031;3366.2762506061313;29200.269993250127;29399;29807.10578682643;30263.69736302631;30800.38399232025;3146.449371360026;3161.525771134325;3181.06158683189;3217.565628640127;3315.1214928044087;427630;500694;504394;505193;505500;58518.707406463545;58937.70531147317;58983.11525327124;59067.70466147728;59082.70458647679;61671;61709.69145154246;61731.691341543905;61750.691246544375;61917.38082619116;15480.5355839325;15982.041077535385;15984.520464386087;16002;16006.519804405887;12404.131710780339;12444.004479641608;12451.813222801666;12453.875461245452;12468.940140088012;13615;14132.717345653029;14133.434662613587;15534.766978495332;15840;5790.797322093791;5998.400159983967;6569.80290591283;7050.330218629222;7078.716851325992;865.6321063547967;930.8039169666946;961.485605201214;1042.5412818359898;1086.9402182880017;28976.27559310992;29111.56332655012;29408.705912940597;29814.85092574523;29911.70088299133;1051.994740026295;1052.3265110329396;1062.3785085724783;1096.4736926275432;1149.4769879704713;14882;15260.771088433683;15302.464413745689;15576.376944922304;15584;627279;628917;631543;632358;633449;48708.5129148704;49876;54938.72530637321;55982.44017559772;56011.71994140085;1341.2690083904101;1360.8843248323806;1382.910110842792;1384.252503648059;1386.5978866128912;29529.11412657538;30484;30498;30647.846760766944;31199.376012480076;48783.0243395123;49263.01473970429;49424.50575494343;49444.75277623589;49458;519007;520797;521234;521238;526451;38278.23443531169;38701.419478708405;39050.02374940649;39072.41391379075;39216;37158.8142059288;44710.77644611756;45906.770466147456;46325.30512042255;46441;37489;37809;37937.620623794515;37994.81002594969;38064.23871522499;29613.703862961094;36202.80840825217;36287.911362659164;37596.6240337604;38215.61784382121;35438.29123417569;35612.28775424529;36512.63487365199;37052.258954820216;38465.61534384732;32400.703971841805;32735.18162045968;33825;33843.66156338504;33902.491462627586;25152.74847251504;25165.496690066462;25276.494470110127;25343.741727973545;25694.61458078093 17:24:14 INFO - PROCESS | 1637 | |2;jquery.html;304.4003313472459;342.842905194967;361.55348145040887;373.0840785870687;396.3757082594912;13563.050586458952;14463.264408271009;18663.3467828626;19319.84080955142;20948.37154885356;20214.89892550539;20547.794522054774;20679.586408271825;20827;20955.58088838226;5680.091185410325;5897.321807992072;5965.254343207099;5984.401559844025;6001.699915004232;5553.916691249614;5765.481106700396;5879.7648094076185;5907.172995780573;5908.409159084079;5978.461938425541;5978.611390259641;5986.191864098357;5998.730057147439;6004.819855404323;5844.532437404998;5976.760929562813;6364.427201551836;6453.9677301613665;6467.482601391877;5727.255456790632;5732.455416735404;5740.282464691913;5740.282464691913;5742.655440673573;12919.35403229844;13340.86659133406;13497.865021349857;13508.527201547997;13518.053736238468;2224.232639739287;2313.2828823064906;2371.822113341489;2820.887164513417;2911.403162351713;9951.402915825074;9965.601375945025;10426.635067772668;10434;10537.367757934548;1807.9638407231914;1862.3202531076195;1874.5594785225462;1876.7841698204609;1897.8007309232607;111741;117135.82864171335;122374.3881280588;125339;126152;11054.778904421866;11268.774624507463;11701.531938722355;11703.765924681458;11874.168808183344;14408.207548584718;14771.96596238253;14867.851321486647;14874.553763387115;14912.179830109231;8239.588020598947;8500.319974402033;8517.48895066298;8622.611982460805;8684.478931264144;8340.37447191463;8368.330533557422;8372.916270837213;8518.701845435504;8539.932508436445;4460.487043989918;4503.864884053483;4543.886402839956;4607.3319368691355;4677.204875171229;1625.8455446732542;1824.4344253281524;1894.5737209127835;1896.9336073237646;1897.7722673279295;800.4636893281515;880.4321212817697;915.1142407225911;960.9663661771805;1001.4942454060653;20272.087756051013;20786.48033799167;21325.68011479829;21428.250011249533;21443.46391340229;863.5293764898146;871.6556960000764;879.6745204274331;900.9684661036963;913.7167478081816;10763.461826908624;11134.94218049284;11380.601678941197;11406.37264950419;11454.713632159262;634056.659433409;634131;639591;641660;652833;117448;120525.39737301257;124818.46900124213;125915;128353;927.1284992107533;949.539473355422;958.2046901072233;962.0235461007084;963.5230560872413;17751.289948401918;18633.53416164607;18696.3456279035;18741.53146171302;19038;38820.41769373518;48346.5165348342;48703.5129648699;49915.25127123169;54547.72726136344;65852.670736646;72892;73324;74022.88965665628;78125.2187478118;7300.342969132804;8116.472429292077;8126.553039582644;8434.32525397979;8510.61702223389;10914.890851091704;11724.355160466257;12599.496020159091;13081;13585.52450664222;11371.43142842871;11412.600558980395;11558;11588.956993870592;11807.468663910317;4954.2326118786505;5186.222066690027;7619.123800762984;11837.644870653894;11908.464119114833;11284.379359134999;11379.122444796516;11770.176087674123;11849.585264515701;11940.46267917963;8204.384671149568;8211.630476628685;8289.04675962272;8437.198466145706;8714.651413943371;10405.271630985759;11024.669259922215;11033.117350611772;11579.768404631692;11637.476313565741 17:24:14 INFO - PROCESS | 1637 | |3;mootools.html;1300.0509627971587;1425.4084554909714;1459.4745891479065;1523.8247601525825;1814.328698381599;7449.366803821675;7842.803929901741;7844.803879902998;7846.843063138734;7849.5682737449415;6742.4268937140405;7670.92329076709;8310.210529999655;8322.542260175687;8416.91583084169;2299.9770002300015;2330.2659662206433;2331.428799944016;2331.8367714260007;2332.5917964356254;2311.2835021143414;2352.752960939102;2381.118985975183;2381.84518006329;2383.380321116505;1920.7118932160217;2280.304507125326;2326.9767302326927;2329.801966832821;2351.27110595715;2292.7707229277107;2293.8967746451367;2308.376738280663;2314.1090680088287;2314.67594536765;2319.246244970391;2346.16711067572;2350.4123969007746;2351.776581224781;2354.446705024318;8335.95832020836;8349.41554091214;8415.663373465053;8438.789030274293;8450.61972211252;4905.337779399807;5075.898482030376;5239.738013099368;5263.157894736823;5267.789288428458;3193.4730769423018;3615.94576081359;3639.872604458831;3642.690371318441;3646.4894914711963;1054.8734151901745;1074.752806854428;1099.3184225780067;1102.6251074634583;1113.8941800528937;9800.607975680965;9922.660440840466;10186.745331366776;11010.944945275221;11070.114390848712;4354.129174165179;4507.2788353863225;4529.6376289896725;4550.795214215368;4562.566556177224;8771.254443372258;8784.912150878537;8794.252488538417;8795.692150774692;8801.295896328278;5063.721495317719;5076.74616269185;5103.693778373309;5136.1525348317855;5136.332276803992;4029.8992525187105;4077.767643995195;4706.905861882811;4804.303376010529;4815.133276010281;3284.178955261185;3637.4180131179414;3682.2635472905527;3762.2099359134886;3782.224643948039;1006.7281833904805;1056.3767377247461;1112.6606385052637;1153.9423028848523;1167.0605162843822;719.6186021408557;856.259335674637;874.6370256343546;891.9732408027769;933.2207606648388;4943.604511639061;5590.944090559124;5688.089905615082;5958.761649534057;6051.818445446644;859.5444414460346;893.7050773244814;895.8175208724551;915.5879854065697;932.787376410677;3670.908227294339;3885.106425522146;3929.9017524562114;3956.5756327721997;3996.18078293949;9830.508474576243;10335.483225838678;10348.327358721659;10354;10356.378617282988;10252.333598316085;10285.43430111351;10301.124404425707;10301.433421161908;10302.969703029787;7574.431917606092;7739.883901741478;7859.921400786034;7864.6854125834325;7906.5651389173;5369.543588794996;5648.745806438638;5695.857603559944;5774.797882074276;5799.942000580109;8834.602442889956;9370.484623345781;9468.195203407511;9533;9549.618015279311;9502.334836561375;9573.32986690925;9605.47169905662;9692.079252471003;9746.463944482837;7106;7298.963505182653;7623;7804.453688241769;7841.764747057598;7755.991721076224;7982.441229113906;7988.440809143304;7997.7600671979935;8015.038195416354;7111.893321600283;7168.820779480555;7216.278372162742;7320.414366850746;7367.889481657672;6188.566800324114;7018.403435708022;7089.468289878383;7095.184053833876;7229.1325040993315;6712.53012289135;6723.798286051427;6728.764493242713;6808.761693340709;6819.89770153458;4896.11869863428;4917.729524876023;4945.579625731853;4973.776180071978;4978.775955081957;4867.780949857336;4885.438174609965;4912.287718280901;4916.311716359641;5090.160123579568 17:24:14 INFO - PROCESS | 1637 | |4;prototype.html;293.2083374887803;295.0911193523945;299.18770537989354;314.5250671486057;316.22367088797006;4436.401085853413;4601.964557974454;5019.849404517861;5029.473473873516;5233.319668443102;4402.823887044522;4848.127337079324;4984.059404951078;5140.820071297505;5163.664361816479;3800.5819359870393;4001.599840015997;4023.396490526415;4031.1937612477477;4067.32889073304;3538.3454060998797;3632.654897784706;3636.5454318210222;3908.2965066288057;4010.919781604366;3185.729213016896;3981.3828856527284;4003.219372222418;4003.459532963042;4004.779737114472;3583.605803361615;3635.363811333006;3689.2107327945923;3980.482537270136;4013.5585085640555;3151.369726054775;3317.4857897026;3458.429359155736;3498.3178280235365;3557.2707594943236;4032.5160980682213;4193.622573968327;5059.2916991621205;5059.671121377136;5063;4507.752073635948;4525.321201819754;4532.5042735897405;4532.93200601988;4545.20458919691;5050.772715227824;5352.4915133062295;5484.725763711798;5490.599897026273;5526.364468086141;2909.96302588187;3464.72282217422;3688.483612294281;3741.420079887622;3759.6052414496357;4358.934615980763;5466.972665136688;5635;5692.800751973702;5709;3802.0989505247376;3812.046988252937;3814.4087666411197;3815.198808250243;3865.43951127085;2961.348503329264;3776.8300426480873;3841.8847434577006;3852.3836186210074;3874.089588946595;4430.889227769331;4431.290993441034;4445.911081778347;4456.977715111404;4510.233260345749;4601.3558101865765;5495.175723641486;5536.723163841792;5543.695096769636;5586.525145362609;1075.6181555547823;1188.263276768409;1192.6839387562302;1219.5913719653813;1246.6945598328468;718.8202949262684;833.3083540661237;859.9140085991476;861.3496809908479;861.4149964066148;4026.4765580474937;4309.806058727365;4337.284348082531;4704.764761761899;4790.976045119822;4436.423264975532;4477.574630410106;4781.402324709411;5188.9740551297;5367.463253674679;4339.7396156230725;4439.866803995821;4753.786079626425;4866.902661946741;4921;2285.108807565051;2576.471823276224;2578.1234380310784;2801.6918139004692;2810.018936649657;4520.59314661682;5430.456954304617;5508.650380656764;5518.668879867221;5639.351474580394;4998.700597725015;5265.921011184835;5427.240186373911;5484.424135465719;5495.340559132929;5093.923591146062;5211.602662775166;5216.452272511484;5361.1422172453;5428.619996600279;4736;4768.0451015083645;5275.86568887691;5298.81454149103;5329;4245.490541135084;4600.447946246338;4624.237000894815;5167.483251674803;5271.630985831109;4647.908441201405;4708.398075472335;4736.73947932867;4749.24012158053;5323.737411600132;3235.7249633781394;3515.4902539132195;3559.843051008381;3595.5685317762036;3658.2866341063236;4233.470816147981;5014.323066385991;5272.420033796203;5346.117890548172;5353.019382828378;5104.273880067497;5131.692098474104;5145.768440420115;5174.223866420067;5183.734247424099;4797.664163508521;4812.542808433193;5024.447310795883;5085.46602606721;5168.382132507335;3927.2145570885364;4469.530699276529;4931.72875491837;4955.603551715782;5001.574866136273;4630.536946305478;4856.295837103529;5156.458571850052;5157.484251574812;5159.638825282194;4153.362614739602;4393.912121757611;4568.634509239187;4971.353724015855;5018.974905125451 17:24:14 INFO - PROCESS | 1637 | |5;yui.html;676.5129107042927;688.1088989758266;696.2863065358007;727.9917314519392;749.3967356278193;13953.372098255577;14360.138391696506;14371.49699760506;14403.567892963214;14414.207218602985;12732.80900786489;12770.68073298168;12838.358082095881;12861.485540578366;12876.871231287685;633.303366297073;656.1306269193315;657.3262406033806;790.3203245209115;802.6749166587541;805.569020573993;806.435495153395;807.8222790985974;808.6522795198092;809.3808236698901;865.3855762408666;865.8917635295588;866;866.2853146154416;866.4627930682954;657.470736057476;685.3763075601179;689.4759982413352;689.6689588997309;863.602742738338;516.6693316277585;517.7566543724444;615.667539528654;637.7353398339684;643.3052303512195;13227.669308267274;13444.596662100153;13595.456181752817;13647.317634118255;13666;12689.302088385135;12779.552715655;12799.296038717866;12855.16441431304;12996.220226786423;11225.494852731646;11692.94153529227;11710.76578468426;11929;12005.099617528633;4271.039016221325;4319.0713996490595;4349.84775532858;4473.597376236122;4494.056248187884;87428.56285718594;88201.55899220527;89802.55098724464;90139;90644.54677726635;10232.079112879877;10287.331323463952;10321.84517232242;10328.690139295833;10354.92901419719;18826.90586547086;19110.33113841009;19202.13590388436;19275.710864337045;19339.61320773605;7278.381337586258;7396.667149978156;7425.405967522585;7606.467547271748;7618.085829700361;3828.2343531293855;3990.4812374391145;4058.939115913264;4111.116110036357;4116.0121570822785;3170.1599076244815;3252.219467327824;3278.131295206772;3280.6555311691927;3284.014795561322;546.1780021068267;622.1352320274822;624.6158612453308;639.68015992004;639.8304449320933;543.9020976224239;548.7612888393562;578.4562511239421;591.5149577346534;614.1126072824815;7263.673134708951;8104.756857294291;8244.422890397735;8514.74455766328;8521.93475815523;525.5244004176227;572.7937942340754;588.2617315269395;592.2212290837535;627.4102343796816;3798.164403831153;4131.338985762323;4173.7078404512;4241.87274381769;4257.148570285955;111535;116066.41966790112;116503.41748291205;117683.41158294326;119471;33134.83432582822;33293.66706332954;59058;89469;92342.53828730814;84271;85033;85955.57022214848;88135.55932220299;89336.10663893408;8008.239217274349;8123.7969050771835;8187.222213889671;8187.71343002992;8334.08325084228;52238.73880630572;54980.72509637426;57548.71225643845;57812.84374312574;60500.09249861167;39841;46903.76548117238;60365.69817150886;62168;70544.472776362;12031.15781895294;12102.152849300464;12202.81695774582;12570.182938108994;12582.18215815969;13229.338533073116;13343;14107.294635268401;14122.78815817784;14475.27623618836;8445.268719912281;12248.38758062111;13100.148490348098;13565.321733913463;13771.173729576258;12815.615531533696;12855.935720321339;12994.870051299366;13205.867941320848;13801.240931748851;13157.210567365988;13404.664883377993;13728.725425491633;13844.861551384358;13919.234442105377;9148;10918.727031824268;11082.889171108509;11424.48589813477;11464.770704586028;9218.907810921804;10476.738081548023;11007.504662290055;11126;11408.657740267805 17:24:14 INFO - PROCESS | 1637 | __end_tp_report 17:24:14 INFO - PROCESS | 1637 | __start_cc_report 17:24:14 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1531 17:24:14 INFO - PROCESS | 1637 | __end_cc_report 17:24:14 INFO - PROCESS | 1637 | __startTimestamp1480555454074__endTimestamp 17:24:14 INFO - PROCESS | 1637 | 17:24:14 INFO - PROCESS | 1637 | ------- Summary: start ------- 17:24:14 INFO - PROCESS | 1637 | Number of tests: 6 17:24:14 INFO - PROCESS | 1637 | 17:24:14 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5611.18 Median:5771.13 stddev:660.51 (11.4%) stddev-sans-first:622.99 17:24:14 INFO - PROCESS | 1637 | Values: 2741.3 2880.6 3108.5 3272.1 3302.1 4517.4 5575.8 5596.4 5605.4 5620.6 5519.2 5581.9 5599.5 5604.1 5609.0 5052.5 5107.1 5157.7 5174.7 5180.1 4958.1 4964.9 4977.8 5067.8 5110.1 5095.5 5163.1 5171.8 5195.1 5221.8 5171.7 5172.7 5173.2 5182.3 5222.1 4955.9 4993.0 5097.3 5105.2 5121.9 5491.8 5511.1 5557.1 5582.7 5599.2 6035.6 6067.4 6070.9 6075.3 6118.1 6186.1 6188.2 6200.6 6208.8 6220.3 5476.9 5557.9 5565.2 5637.1 5639.9 6184.9 6309.6 6310.3 6319.1 6335.8 5992.1 5995.9 6006.9 6008.7 6010.6 6027.0 6068.1 6083.8 6139.0 6158.9 5953.3 5994.7 6001.5 6066.2 6085.1 5919.4 6034.5 6050.1 6055.8 6059.9 4741.5 4764.5 4831.6 4833.7 4838.8 4310.6 4371.9 4394.3 4404.4 4422.8 5597.9 5606.4 5611.6 5613.9 5617.2 6091.8 6094.4 6098.9 6105.7 6107.2 5559.6 5603.9 5609.4 5613.0 5613.4 5239.1 5261.8 5263.2 5263.5 5269.1 6226.6 6241.1 6255.2 6257.2 6261.4 5705.6 6068.2 6075.0 6083.7 6145.2 6345.8 6347.4 6352.6 6372.2 6507.8 5887.1 5914.2 5920.0 6003.9 6047.3 5972.7 6100.2 6135.2 6146.4 6168.4 5818.9 5925.5 5967.5 5979.8 5988.3 5709.3 5774.8 5955.6 5960.6 5965.1 5685.9 5693.9 5744.8 5756.3 5826.5 5951.4 5952.1 5965.9 5968.6 5970.8 5771.1 5771.1 5775.0 5780.2 5801.5 17:24:14 INFO - PROCESS | 1637 | 17:24:14 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:67995.07 Median:37323.91 stddev:147019.77 (393.9%) stddev-sans-first:147344.76 17:24:14 INFO - PROCESS | 1637 | Values: 461.1 495.5 506.8 506.9 519.1 29508.1 30243.6 31878.7 39342.0 40165.0 41558.6 41670.7 41710.7 41735.4 41798.8 9241.9 9424.5 9448.6 9458.0 9463.8 8468.3 8706.0 8751.7 8772.4 8812.8 11204.3 11462.5 11491.8 11633.1 11638.7 11272.5 11351.1 11357.2 11367.2 11372.2 8408.8 8634.0 10967.8 11317.0 11401.9 39550.6 39919.4 39925.2 39953.4 39954.8 3268.4 3304.0 3310.2 3334.6 3366.3 29200.3 29399.0 29807.1 30263.7 30800.4 3146.4 3161.5 3181.1 3217.6 3315.1 427630.0 500694.0 504394.0 505193.0 505500.0 58518.7 58937.7 58983.1 59067.7 59082.7 61671.0 61709.7 61731.7 61750.7 61917.4 15480.5 15982.0 15984.5 16002.0 16006.5 12404.1 12444.0 12451.8 12453.9 12468.9 13615.0 14132.7 14133.4 15534.8 15840.0 5790.8 5998.4 6569.8 7050.3 7078.7 865.6 930.8 961.5 1042.5 1086.9 28976.3 29111.6 29408.7 29814.9 29911.7 1052.0 1052.3 1062.4 1096.5 1149.5 14882.0 15260.8 15302.5 15576.4 15584.0 627279.0 628917.0 631543.0 632358.0 633449.0 48708.5 49876.0 54938.7 55982.4 56011.7 1341.3 1360.9 1382.9 1384.3 1386.6 29529.1 30484.0 30498.0 30647.8 31199.4 48783.0 49263.0 49424.5 49444.8 49458.0 519007.0 520797.0 521234.0 521238.0 526451.0 38278.2 38701.4 39050.0 39072.4 39216.0 37158.8 44710.8 45906.8 46325.3 46441.0 37489.0 37809.0 37937.6 37994.8 38064.2 29613.7 36202.8 36287.9 37596.6 38215.6 35438.3 35612.3 36512.6 37052.3 38465.6 32400.7 32735.2 33825.0 33843.7 33902.5 25152.7 25165.5 25276.5 25343.7 25694.6 17:24:14 INFO - PROCESS | 1637 | 17:24:14 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:35446.69 Median:1607.90 stddev:106475.56 (6622.0%) stddev-sans-first:106741.56 17:24:14 INFO - PROCESS | 1637 | Values: 304.4 342.8 361.6 373.1 396.4 13563.1 14463.3 18663.3 19319.8 20948.4 20214.9 20547.8 20679.6 20827.0 20955.6 5680.1 5897.3 5965.3 5984.4 6001.7 5553.9 5765.5 5879.8 5907.2 5908.4 5978.5 5978.6 5986.2 5998.7 6004.8 5844.5 5976.8 6364.4 6454.0 6467.5 5727.3 5732.5 5740.3 5740.3 5742.7 12919.4 13340.9 13497.9 13508.5 13518.1 2224.2 2313.3 2371.8 2820.9 2911.4 9951.4 9965.6 10426.6 10434.0 10537.4 1808.0 1862.3 1874.6 1876.8 1897.8 111741.0 117135.8 122374.4 125339.0 126152.0 11054.8 11268.8 11701.5 11703.8 11874.2 14408.2 14772.0 14867.9 14874.6 14912.2 8239.6 8500.3 8517.5 8622.6 8684.5 8340.4 8368.3 8372.9 8518.7 8539.9 4460.5 4503.9 4543.9 4607.3 4677.2 1625.8 1824.4 1894.6 1896.9 1897.8 800.5 880.4 915.1 961.0 1001.5 20272.1 20786.5 21325.7 21428.3 21443.5 863.5 871.7 879.7 901.0 913.7 10763.5 11134.9 11380.6 11406.4 11454.7 634056.7 634131.0 639591.0 641660.0 652833.0 117448.0 120525.4 124818.5 125915.0 128353.0 927.1 949.5 958.2 962.0 963.5 17751.3 18633.5 18696.3 18741.5 19038.0 38820.4 48346.5 48703.5 49915.3 54547.7 65852.7 72892.0 73324.0 74022.9 78125.2 7300.3 8116.5 8126.6 8434.3 8510.6 10914.9 11724.4 12599.5 13081.0 13585.5 11371.4 11412.6 11558.0 11589.0 11807.5 4954.2 5186.2 7619.1 11837.6 11908.5 11284.4 11379.1 11770.2 11849.6 11940.5 8204.4 8211.6 8289.0 8437.2 8714.7 10405.3 11024.7 11033.1 11579.8 11637.5 17:24:14 INFO - PROCESS | 1637 | 17:24:14 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5429.42 Median:5083.45 stddev:2970.46 (58.4%) stddev-sans-first:2962.58 17:24:14 INFO - PROCESS | 1637 | Values: 1300.1 1425.4 1459.5 1523.8 1814.3 7449.4 7842.8 7844.8 7846.8 7849.6 6742.4 7670.9 8310.2 8322.5 8416.9 2300.0 2330.3 2331.4 2331.8 2332.6 2311.3 2352.8 2381.1 2381.8 2383.4 1920.7 2280.3 2327.0 2329.8 2351.3 2292.8 2293.9 2308.4 2314.1 2314.7 2319.2 2346.2 2350.4 2351.8 2354.4 8336.0 8349.4 8415.7 8438.8 8450.6 4905.3 5075.9 5239.7 5263.2 5267.8 3193.5 3615.9 3639.9 3642.7 3646.5 1054.9 1074.8 1099.3 1102.6 1113.9 9800.6 9922.7 10186.7 11010.9 11070.1 4354.1 4507.3 4529.6 4550.8 4562.6 8771.3 8784.9 8794.3 8795.7 8801.3 5063.7 5076.7 5103.7 5136.2 5136.3 4029.9 4077.8 4706.9 4804.3 4815.1 3284.2 3637.4 3682.3 3762.2 3782.2 1006.7 1056.4 1112.7 1153.9 1167.1 719.6 856.3 874.6 892.0 933.2 4943.6 5590.9 5688.1 5958.8 6051.8 859.5 893.7 895.8 915.6 932.8 3670.9 3885.1 3929.9 3956.6 3996.2 9830.5 10335.5 10348.3 10354.0 10356.4 10252.3 10285.4 10301.1 10301.4 10303.0 7574.4 7739.9 7859.9 7864.7 7906.6 5369.5 5648.7 5695.9 5774.8 5799.9 8834.6 9370.5 9468.2 9533.0 9549.6 9502.3 9573.3 9605.5 9692.1 9746.5 7106.0 7299.0 7623.0 7804.5 7841.8 7756.0 7982.4 7988.4 7997.8 8015.0 7111.9 7168.8 7216.3 7320.4 7367.9 6188.6 7018.4 7089.5 7095.2 7229.1 6712.5 6723.8 6728.8 6808.8 6819.9 4896.1 4917.7 4945.6 4973.8 4978.8 4867.8 4885.4 4912.3 4916.3 5090.2 17:24:14 INFO - PROCESS | 1637 | 17:24:14 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4214.62 Median:4600.90 stddev:1272.05 (27.6%) stddev-sans-first:1241.10 17:24:14 INFO - PROCESS | 1637 | Values: 293.2 295.1 299.2 314.5 316.2 4436.4 4602.0 5019.8 5029.5 5233.3 4402.8 4848.1 4984.1 5140.8 5163.7 3800.6 4001.6 4023.4 4031.2 4067.3 3538.3 3632.7 3636.5 3908.3 4010.9 3185.7 3981.4 4003.2 4003.5 4004.8 3583.6 3635.4 3689.2 3980.5 4013.6 3151.4 3317.5 3458.4 3498.3 3557.3 4032.5 4193.6 5059.3 5059.7 5063.0 4507.8 4525.3 4532.5 4532.9 4545.2 5050.8 5352.5 5484.7 5490.6 5526.4 2910.0 3464.7 3688.5 3741.4 3759.6 4358.9 5467.0 5635.0 5692.8 5709.0 3802.1 3812.0 3814.4 3815.2 3865.4 2961.3 3776.8 3841.9 3852.4 3874.1 4430.9 4431.3 4445.9 4457.0 4510.2 4601.4 5495.2 5536.7 5543.7 5586.5 1075.6 1188.3 1192.7 1219.6 1246.7 718.8 833.3 859.9 861.3 861.4 4026.5 4309.8 4337.3 4704.8 4791.0 4436.4 4477.6 4781.4 5189.0 5367.5 4339.7 4439.9 4753.8 4866.9 4921.0 2285.1 2576.5 2578.1 2801.7 2810.0 4520.6 5430.5 5508.7 5518.7 5639.4 4998.7 5265.9 5427.2 5484.4 5495.3 5093.9 5211.6 5216.5 5361.1 5428.6 4736.0 4768.0 5275.9 5298.8 5329.0 4245.5 4600.4 4624.2 5167.5 5271.6 4647.9 4708.4 4736.7 4749.2 5323.7 3235.7 3515.5 3559.8 3595.6 3658.3 4233.5 5014.3 5272.4 5346.1 5353.0 5104.3 5131.7 5145.8 5174.2 5183.7 4797.7 4812.5 5024.4 5085.5 5168.4 3927.2 4469.5 4931.7 4955.6 5001.6 4630.5 4856.3 5156.5 5157.5 5159.6 4153.4 4393.9 4568.6 4971.4 5019.0 17:24:14 INFO - PROCESS | 1637 | 17:24:14 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19249.72 Median:4295.06 stddev:28570.66 (665.2%) stddev-sans-first:28616.77 17:24:14 INFO - PROCESS | 1637 | Values: 676.5 688.1 696.3 728.0 749.4 13953.4 14360.1 14371.5 14403.6 14414.2 12732.8 12770.7 12838.4 12861.5 12876.9 633.3 656.1 657.3 790.3 802.7 805.6 806.4 807.8 808.7 809.4 865.4 865.9 866.0 866.3 866.5 657.5 685.4 689.5 689.7 863.6 516.7 517.8 615.7 637.7 643.3 13227.7 13444.6 13595.5 13647.3 13666.0 12689.3 12779.6 12799.3 12855.2 12996.2 11225.5 11692.9 11710.8 11929.0 12005.1 4271.0 4319.1 4349.8 4473.6 4494.1 87428.6 88201.6 89802.6 90139.0 90644.5 10232.1 10287.3 10321.8 10328.7 10354.9 18826.9 19110.3 19202.1 19275.7 19339.6 7278.4 7396.7 7425.4 7606.5 7618.1 3828.2 3990.5 4058.9 4111.1 4116.0 3170.2 3252.2 3278.1 3280.7 3284.0 546.2 622.1 624.6 639.7 639.8 543.9 548.8 578.5 591.5 614.1 7263.7 8104.8 8244.4 8514.7 8521.9 525.5 572.8 588.3 592.2 627.4 3798.2 4131.3 4173.7 4241.9 4257.1 111535.0 116066.4 116503.4 117683.4 119471.0 33134.8 33293.7 59058.0 89469.0 92342.5 84271.0 85033.0 85955.6 88135.6 89336.1 8008.2 8123.8 8187.2 8187.7 8334.1 52238.7 54980.7 57548.7 57812.8 60500.1 39841.0 46903.8 60365.7 62168.0 70544.5 12031.2 12102.2 12202.8 12570.2 12582.2 13229.3 13343.0 14107.3 14122.8 14475.3 8445.3 12248.4 13100.1 13565.3 13771.2 12815.6 12855.9 12994.9 13205.9 13801.2 13157.2 13404.7 13728.7 13844.9 13919.2 9148.0 10918.7 11082.9 11424.5 11464.8 9218.9 10476.7 11007.5 11126.0 11408.7 17:24:14 INFO - PROCESS | 1637 | -------- Summary: end -------- 17:24:14 INFO - PROCESS | 1637 | 17:24:14 INFO - TEST-INFO | 1637: exit 0 17:24:14 INFO - TEST-OK | dromaeo_css | took 1120076ms 17:24:14 INFO - TEST-START | kraken 17:24:14 INFO - operating with platform_type : mac_ 17:24:14 INFO - Initialising browser for kraken test... 17:24:14 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmp1Ff12K/profile http://localhost:49238/getInfo.html) 17:24:17 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 17:24:17 INFO - PROCESS | 1647 | colorDepth:24 17:24:17 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 17:24:17 INFO - PROCESS | 1647 | __metrics 17:24:17 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:24:17 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:24:18 INFO - TEST-INFO | 1647: exit 0 17:24:18 INFO - Browser initialized. 17:24:18 INFO - Running cycle 1/1 for kraken test... 17:24:18 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmp1Ff12K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:24:30 INFO - PROCESS | 1648 | RSS: Main: 222474240 17:24:30 INFO - PROCESS | 1648 | 17:24:33 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 17:24:34 INFO - PROCESS | 1648 | RSS: Main: 272543744 17:24:34 INFO - PROCESS | 1648 | 17:24:37 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 17:24:37 INFO - PROCESS | 1648 | RSS: Main: 282972160 17:24:37 INFO - PROCESS | 1648 | 17:24:40 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 17:24:41 INFO - PROCESS | 1648 | RSS: Main: 380596224 17:24:41 INFO - PROCESS | 1648 | 17:24:43 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 17:24:44 INFO - PROCESS | 1648 | RSS: Main: 283418624 17:24:44 INFO - PROCESS | 1648 | 17:24:46 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:24:46 INFO - PROCESS | 1648 | RSS: Main: 285118464 17:24:46 INFO - PROCESS | 1648 | 17:24:49 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 17:24:50 INFO - PROCESS | 1648 | RSS: Main: 324141056 17:24:50 INFO - PROCESS | 1648 | 17:24:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 17:24:53 INFO - PROCESS | 1648 | RSS: Main: 326279168 17:24:53 INFO - PROCESS | 1648 | 17:24:56 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 17:24:57 INFO - PROCESS | 1648 | RSS: Main: 324952064 17:24:57 INFO - PROCESS | 1648 | 17:24:58 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:24:59 INFO - PROCESS | 1648 | RSS: Main: 300138496 17:24:59 INFO - PROCESS | 1648 | 17:25:01 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:25:02 INFO - PROCESS | 1648 | RSS: Main: 301490176 17:25:02 INFO - PROCESS | 1648 | 17:25:04 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:25:04 INFO - PROCESS | 1648 | RSS: Main: 304140288 17:25:04 INFO - PROCESS | 1648 | 17:25:07 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:25:08 INFO - PROCESS | 1648 | RSS: Main: 313335808 17:25:08 INFO - PROCESS | 1648 | 17:25:11 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:25:12 INFO - PROCESS | 1648 | RSS: Main: 313544704 17:25:12 INFO - PROCESS | 1648 | 17:25:13 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 17:25:14 INFO - PROCESS | 1648 | RSS: Main: 357138432 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | __start_tp_report 17:25:14 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 17:25:14 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 17:25:14 INFO - PROCESS | 1648 | |i|pagename|runs| 17:25:14 INFO - PROCESS | 1648 | |0;ai-astar;115;113;115;114;115;114;114;114;121;170 17:25:14 INFO - PROCESS | 1648 | |1;audio-beat-detection;152;116;114;164;145;115;114;114;143;117 17:25:14 INFO - PROCESS | 1648 | |2;audio-dft;184;185;184;185;182;184;187;220;183;186 17:25:14 INFO - PROCESS | 1648 | |3;audio-fft;81;59;59;89;63;59;59;59;82;72 17:25:14 INFO - PROCESS | 1648 | |4;audio-oscillator;81;87;127;98;76;69;88;67;68;91 17:25:14 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;88;85;93;128;116;84;89;87;88;85 17:25:14 INFO - PROCESS | 1648 | |6;imaging-darkroom;94;94;96;95;95;94;94;94;95;94 17:25:14 INFO - PROCESS | 1648 | |7;imaging-desaturate;82;82;87;122;84;85;83;79;80;81 17:25:14 INFO - PROCESS | 1648 | |8;json-parse-financial;55;54;53;53;57;57;55;56;55;56 17:25:14 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;65;76;79;74;72;70;71;71;70;72 17:25:14 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;86;88;84;98;80;84;84;83;87;96 17:25:14 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;144;133;170;145;150;157;148;149;188;186 17:25:14 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;168;166;172;168;168;166;165;167;170;167 17:25:14 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;71;70;69;69;68;68;68;67;69;67 17:25:14 INFO - PROCESS | 1648 | __end_tp_report 17:25:14 INFO - PROCESS | 1648 | __start_cc_report 17:25:14 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,2113 17:25:14 INFO - PROCESS | 1648 | __end_cc_report 17:25:14 INFO - PROCESS | 1648 | __startTimestamp1480555514125__endTimestamp 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | ------- Summary: start ------- 17:25:14 INFO - PROCESS | 1648 | Number of tests: 14 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:120.50 Median:115.00 stddev:17.53 (15.2%) stddev-sans-first:18.48 17:25:14 INFO - PROCESS | 1648 | Values: 115.0 113.0 115.0 114.0 115.0 114.0 114.0 114.0 121.0 170.0 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:130.00 stddev:19.40 (14.9%) stddev-sans-first:18.78 17:25:14 INFO - PROCESS | 1648 | Values: 152.0 116.0 114.0 164.0 145.0 115.0 114.0 114.0 143.0 117.0 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:188.00 Median:185.00 stddev:11.33 (6.1%) stddev-sans-first:11.93 17:25:14 INFO - PROCESS | 1648 | Values: 184.0 185.0 184.0 185.0 182.0 184.0 187.0 220.0 183.0 186.0 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:68.20 Median:67.50 stddev:11.79 (17.5%) stddev-sans-first:11.56 17:25:14 INFO - PROCESS | 1648 | Values: 81.0 59.0 59.0 89.0 63.0 59.0 59.0 59.0 82.0 72.0 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:85.20 Median:84.00 stddev:18.10 (21.5%) stddev-sans-first:19.13 17:25:14 INFO - PROCESS | 1648 | Values: 81.0 87.0 127.0 98.0 76.0 69.0 88.0 67.0 68.0 91.0 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:94.30 Median:87.50 stddev:15.09 (17.2%) stddev-sans-first:15.83 17:25:14 INFO - PROCESS | 1648 | Values: 88.0 85.0 93.0 128.0 116.0 84.0 89.0 87.0 88.0 85.0 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 17:25:14 INFO - PROCESS | 1648 | Values: 94.0 94.0 96.0 95.0 95.0 94.0 94.0 94.0 95.0 94.0 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:86.50 Median:82.50 stddev:12.70 (15.4%) stddev-sans-first:13.36 17:25:14 INFO - PROCESS | 1648 | Values: 82.0 82.0 87.0 122.0 84.0 85.0 83.0 79.0 80.0 81.0 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.50 stddev:1.45 (2.6%) stddev-sans-first:1.54 17:25:14 INFO - PROCESS | 1648 | Values: 55.0 54.0 53.0 53.0 57.0 57.0 55.0 56.0 55.0 56.0 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.00 stddev:3.77 (5.2%) stddev-sans-first:3.03 17:25:14 INFO - PROCESS | 1648 | Values: 65.0 76.0 79.0 74.0 72.0 70.0 71.0 71.0 70.0 72.0 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:86.50 stddev:5.73 (6.6%) stddev-sans-first:6.07 17:25:14 INFO - PROCESS | 1648 | Values: 86.0 88.0 84.0 98.0 80.0 84.0 84.0 83.0 87.0 96.0 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:157.00 Median:153.50 stddev:18.42 (12.0%) stddev-sans-first:18.93 17:25:14 INFO - PROCESS | 1648 | Values: 144.0 133.0 170.0 145.0 150.0 157.0 148.0 149.0 188.0 186.0 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.70 Median:168.00 stddev:2.06 (1.2%) stddev-sans-first:2.18 17:25:14 INFO - PROCESS | 1648 | Values: 168.0 166.0 172.0 168.0 168.0 166.0 165.0 167.0 170.0 167.0 17:25:14 INFO - PROCESS | 1648 | 17:25:14 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:1.26 (1.8%) stddev-sans-first:1.00 17:25:14 INFO - PROCESS | 1648 | Values: 71.0 70.0 69.0 69.0 68.0 68.0 68.0 67.0 69.0 67.0 17:25:14 INFO - PROCESS | 1648 | -------- Summary: end -------- 17:25:14 INFO - PROCESS | 1648 | 17:25:15 INFO - TEST-INFO | 1648: exit 0 17:25:15 INFO - TEST-OK | kraken | took 60456ms 17:25:15 INFO - SUITE-END | took 1180s 17:25:15 INFO - Completed test suite (00:19:40) 17:25:15 INFO - javascript benchmark 17:25:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2741.259859837844, 2880.6460963347217, 3108.5026395776667, 3272.051105179498, 3302.1414432247607, 4517.412736344275, 5575.776968921238, 5596.3564190118095, 5605.355384130821, 5620.606557540964, 5519.227308176858, 5581.8604534886645, 5599.496045355927, 5604.131359639263, 5608.971955140229, 5052.4947505249365, 5107.080725469414, 5157.71632560209, 5174.689518628876, 5180.119379705459, 4958.132326842806, 4964.87587810304, 4977.751112444382, 5067.847964561069, 5110.1312776827845, 5095.541401273885, 5163.096458119833, 5171.818986335479, 5195.090859099642, 5221.791128354861, 5171.689698618095, 5172.741362931876, 5173.198154286073, 5182.300389447433, 5222.086134926372, 4955.925661115086, 4993.0, 5097.337346145015, 5105.234214867763, 5121.8719532012, 5491.780328786844, 5511.090670039439, 5557.1108622620595, 5582.665040097607, 5599.244102046212, 6035.637861728266, 6067.393260673942, 6070.939290607082, 6075.331713511554, 6118.1434599156155, 6186.103015062836, 6188.164597779287, 6200.596961197552, 6208.782692605737, 6220.253569571635, 5476.91784623235, 5557.916631250535, 5565.2486914266865, 5637.069883469264, 5639.887202255973, 6184.8763024739255, 6309.621422714652, 6310.274318453345, 6319.0837328587095, 6335.841603959938, 5992.0712289595185, 5995.910061349084, 6006.879862402728, 6008.729607167688, 6010.604601978238, 6027.0, 6068.089786532056, 6083.7870674526175, 6139.048447490557, 6158.907616385758, 5953.315368732565, 5994.730237139339, 6001.549883758736, 6066.181065556182, 6085.148079268906, 5919.408059194132, 6034.5172386209, 6050.092486127028, 6055.757769689251, 6059.939400606026, 4741.478437371955, 4764.499727528629, 4831.613470922318, 4833.661643684908, 4838.830640927621, 4310.612044915973, 4371.868843934623, 4394.340848872631, 4404.4494438195225, 4422.778861056934, 5597.944020559824, 5606.411326810708, 5611.579131565068, 5613.887722245532, 5617.21359009739, 6091.7563297467605, 6094.421030002231, 6098.878022439526, 6105.694715264219, 6107.236595425572, 5559.63862348946, 5603.943960560342, 5609.411011843697, 5613.045782217033, 5613.438656134435, 5239.083160446907, 5261.789528418821, 5263.236830659533, 5263.473652634782, 5269.051570717231, 6226.62640241587, 6241.126242326078, 6255.24937007562, 6257.155284036688, 6261.405166509083, 5705.57360659835, 6068.21113255274, 6075.0, 6083.66539840313, 6145.201123853871, 6345.809625711236, 6347.428731414195, 6352.618842869442, 6372.171617689671, 6507.837304067247, 5887.146363777314, 5914.201582786269, 5920.0, 6003.879922401615, 6047.304559975483, 5972.731227094878, 6100.206973093469, 6135.202044401974, 6146.446819786241, 6168.444839964245, 5818.883622327615, 5925.496332811759, 5967.492763115184, 5979.790707325223, 5988.311344195473, 5709.25779648643, 5774.7690092397515, 5955.553333499918, 5960.5827592068135, 5965.135055416853, 5685.886282274414, 5693.914591281051, 5744.798932037359, 5756.30924289088, 5826.533877289891, 5951.375105613848, 5952.077427998667, 5965.940340596539, 5968.582199246011, 5970.761169553343, 5771.105478650816, 5771.134329850556, 5775.0, 5780.219670344618, 5801.506871915934], "value": 5564.891189530342, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.1031543647605, 495.51192075805324, 506.799814073441, 506.91635880079775, 519.1408219396894, 29508.114756557312, 30243.639036243367, 31878.68121318786, 39341.99999999996, 40164.99587510314, 41558.58441415585, 41670.74987750365, 41710.74867753965, 41735.373969390486, 41798.791006045, 9241.861372079424, 9424.528773561295, 9448.57481413338, 9458.0, 9463.81072378552, 8468.280196183332, 8706.042335343107, 8751.693690720826, 8772.429792063527, 8812.779680508007, 11204.327740335566, 11462.541498340057, 11491.770164596703, 11633.127515436334, 11638.650840474756, 11272.549098036068, 11351.091912646969, 11357.204995650307, 11367.204295699305, 11372.203945723802, 8408.789780255482, 8634.00708918476, 10967.780644387147, 11317.0, 11401.942990284995, 39550.60449395498, 39919.401208981886, 39925.201495969915, 39953.400698989244, 39954.80022599897, 3268.3953468608147, 3303.9504407433906, 3310.2220978070136, 3334.61651910031, 3366.2762506061313, 29200.269993250127, 29399.0, 29807.10578682643, 30263.69736302631, 30800.38399232025, 3146.449371360026, 3161.525771134325, 3181.06158683189, 3217.565628640127, 3315.1214928044087, 427630.0, 500694.0, 504394.0, 505193.0, 505500.0, 58518.707406463545, 58937.70531147317, 58983.11525327124, 59067.70466147728, 59082.70458647679, 61671.0, 61709.69145154246, 61731.691341543905, 61750.691246544375, 61917.38082619116, 15480.5355839325, 15982.041077535385, 15984.520464386087, 16002.0, 16006.519804405887, 12404.131710780339, 12444.004479641608, 12451.813222801666, 12453.875461245452, 12468.940140088012, 13615.0, 14132.717345653029, 14133.434662613587, 15534.766978495332, 15840.0, 5790.797322093791, 5998.400159983967, 6569.80290591283, 7050.330218629222, 7078.716851325992, 865.6321063547967, 930.8039169666946, 961.485605201214, 1042.5412818359898, 1086.9402182880017, 28976.27559310992, 29111.56332655012, 29408.705912940597, 29814.85092574523, 29911.70088299133, 1051.994740026295, 1052.3265110329396, 1062.3785085724783, 1096.4736926275432, 1149.4769879704713, 14882.0, 15260.771088433683, 15302.464413745689, 15576.376944922304, 15584.0, 627279.0, 628917.0, 631543.0, 632358.0, 633449.0, 48708.5129148704, 49876.0, 54938.72530637321, 55982.44017559772, 56011.71994140085, 1341.2690083904101, 1360.8843248323806, 1382.910110842792, 1384.252503648059, 1386.5978866128912, 29529.11412657538, 30484.0, 30498.0, 30647.846760766944, 31199.376012480076, 48783.0243395123, 49263.01473970429, 49424.50575494343, 49444.75277623589, 49458.0, 519007.0, 520797.0, 521234.0, 521238.0, 526451.0, 38278.23443531169, 38701.419478708405, 39050.02374940649, 39072.41391379075, 39216.0, 37158.8142059288, 44710.77644611756, 45906.770466147456, 46325.30512042255, 46441.0, 37489.0, 37809.0, 37937.620623794515, 37994.81002594969, 38064.23871522499, 29613.703862961094, 36202.80840825217, 36287.911362659164, 37596.6240337604, 38215.61784382121, 35438.29123417569, 35612.28775424529, 36512.63487365199, 37052.258954820216, 38465.61534384732, 32400.703971841805, 32735.18162045968, 33825.0, 33843.66156338504, 33902.491462627586, 25152.74847251504, 25165.496690066462, 25276.494470110127, 25343.741727973545, 25694.61458078093], "value": 19703.933483473946, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.4003313472459, 342.842905194967, 361.55348145040887, 373.0840785870687, 396.3757082594912, 13563.050586458952, 14463.264408271009, 18663.3467828626, 19319.84080955142, 20948.37154885356, 20214.89892550539, 20547.794522054774, 20679.586408271825, 20827.0, 20955.58088838226, 5680.091185410325, 5897.321807992072, 5965.254343207099, 5984.401559844025, 6001.699915004232, 5553.916691249614, 5765.481106700396, 5879.7648094076185, 5907.172995780573, 5908.409159084079, 5978.461938425541, 5978.611390259641, 5986.191864098357, 5998.730057147439, 6004.819855404323, 5844.532437404998, 5976.760929562813, 6364.427201551836, 6453.9677301613665, 6467.482601391877, 5727.255456790632, 5732.455416735404, 5740.282464691913, 5740.282464691913, 5742.655440673573, 12919.35403229844, 13340.86659133406, 13497.865021349857, 13508.527201547997, 13518.053736238468, 2224.232639739287, 2313.2828823064906, 2371.822113341489, 2820.887164513417, 2911.403162351713, 9951.402915825074, 9965.601375945025, 10426.635067772668, 10434.0, 10537.367757934548, 1807.9638407231914, 1862.3202531076195, 1874.5594785225462, 1876.7841698204609, 1897.8007309232607, 111741.0, 117135.82864171335, 122374.3881280588, 125339.0, 126152.0, 11054.778904421866, 11268.774624507463, 11701.531938722355, 11703.765924681458, 11874.168808183344, 14408.207548584718, 14771.96596238253, 14867.851321486647, 14874.553763387115, 14912.179830109231, 8239.588020598947, 8500.319974402033, 8517.48895066298, 8622.611982460805, 8684.478931264144, 8340.37447191463, 8368.330533557422, 8372.916270837213, 8518.701845435504, 8539.932508436445, 4460.487043989918, 4503.864884053483, 4543.886402839956, 4607.3319368691355, 4677.204875171229, 1625.8455446732542, 1824.4344253281524, 1894.5737209127835, 1896.9336073237646, 1897.7722673279295, 800.4636893281515, 880.4321212817697, 915.1142407225911, 960.9663661771805, 1001.4942454060653, 20272.087756051013, 20786.48033799167, 21325.68011479829, 21428.250011249533, 21443.46391340229, 863.5293764898146, 871.6556960000764, 879.6745204274331, 900.9684661036963, 913.7167478081816, 10763.461826908624, 11134.94218049284, 11380.601678941197, 11406.37264950419, 11454.713632159262, 634056.659433409, 634131.0, 639591.0, 641660.0, 652833.0, 117448.0, 120525.39737301257, 124818.46900124213, 125915.0, 128353.0, 927.1284992107533, 949.539473355422, 958.2046901072233, 962.0235461007084, 963.5230560872413, 17751.289948401918, 18633.53416164607, 18696.3456279035, 18741.53146171302, 19038.0, 38820.41769373518, 48346.5165348342, 48703.5129648699, 49915.25127123169, 54547.72726136344, 65852.670736646, 72892.0, 73324.0, 74022.88965665628, 78125.2187478118, 7300.342969132804, 8116.472429292077, 8126.553039582644, 8434.32525397979, 8510.61702223389, 10914.890851091704, 11724.355160466257, 12599.496020159091, 13081.0, 13585.52450664222, 11371.43142842871, 11412.600558980395, 11558.0, 11588.956993870592, 11807.468663910317, 4954.2326118786505, 5186.222066690027, 7619.123800762984, 11837.644870653894, 11908.464119114833, 11284.379359134999, 11379.122444796516, 11770.176087674123, 11849.585264515701, 11940.46267917963, 8204.384671149568, 8211.630476628685, 8289.04675962272, 8437.198466145706, 8714.651413943371, 10405.271630985759, 11024.669259922215, 11033.117350611772, 11579.768404631692, 11637.476313565741], "value": 9297.620054029845, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.0509627971587, 1425.4084554909714, 1459.4745891479065, 1523.8247601525825, 1814.328698381599, 7449.366803821675, 7842.803929901741, 7844.803879902998, 7846.843063138734, 7849.5682737449415, 6742.4268937140405, 7670.92329076709, 8310.210529999655, 8322.542260175687, 8416.91583084169, 2299.9770002300015, 2330.2659662206433, 2331.428799944016, 2331.8367714260007, 2332.5917964356254, 2311.2835021143414, 2352.752960939102, 2381.118985975183, 2381.84518006329, 2383.380321116505, 1920.7118932160217, 2280.304507125326, 2326.9767302326927, 2329.801966832821, 2351.27110595715, 2292.7707229277107, 2293.8967746451367, 2308.376738280663, 2314.1090680088287, 2314.67594536765, 2319.246244970391, 2346.16711067572, 2350.4123969007746, 2351.776581224781, 2354.446705024318, 8335.95832020836, 8349.41554091214, 8415.663373465053, 8438.789030274293, 8450.61972211252, 4905.337779399807, 5075.898482030376, 5239.738013099368, 5263.157894736823, 5267.789288428458, 3193.4730769423018, 3615.94576081359, 3639.872604458831, 3642.690371318441, 3646.4894914711963, 1054.8734151901745, 1074.752806854428, 1099.3184225780067, 1102.6251074634583, 1113.8941800528937, 9800.607975680965, 9922.660440840466, 10186.745331366776, 11010.944945275221, 11070.114390848712, 4354.129174165179, 4507.2788353863225, 4529.6376289896725, 4550.795214215368, 4562.566556177224, 8771.254443372258, 8784.912150878537, 8794.252488538417, 8795.692150774692, 8801.295896328278, 5063.721495317719, 5076.74616269185, 5103.693778373309, 5136.1525348317855, 5136.332276803992, 4029.8992525187105, 4077.767643995195, 4706.905861882811, 4804.303376010529, 4815.133276010281, 3284.178955261185, 3637.4180131179414, 3682.2635472905527, 3762.2099359134886, 3782.224643948039, 1006.7281833904805, 1056.3767377247461, 1112.6606385052637, 1153.9423028848523, 1167.0605162843822, 719.6186021408557, 856.259335674637, 874.6370256343546, 891.9732408027769, 933.2207606648388, 4943.604511639061, 5590.944090559124, 5688.089905615082, 5958.761649534057, 6051.818445446644, 859.5444414460346, 893.7050773244814, 895.8175208724551, 915.5879854065697, 932.787376410677, 3670.908227294339, 3885.106425522146, 3929.9017524562114, 3956.5756327721997, 3996.18078293949, 9830.508474576243, 10335.483225838678, 10348.327358721659, 10354.0, 10356.378617282988, 10252.333598316085, 10285.43430111351, 10301.124404425707, 10301.433421161908, 10302.969703029787, 7574.431917606092, 7739.883901741478, 7859.921400786034, 7864.6854125834325, 7906.5651389173, 5369.543588794996, 5648.745806438638, 5695.857603559944, 5774.797882074276, 5799.942000580109, 8834.602442889956, 9370.484623345781, 9468.195203407511, 9533.0, 9549.618015279311, 9502.334836561375, 9573.32986690925, 9605.47169905662, 9692.079252471003, 9746.463944482837, 7106.0, 7298.963505182653, 7623.0, 7804.453688241769, 7841.764747057598, 7755.991721076224, 7982.441229113906, 7988.440809143304, 7997.7600671979935, 8015.038195416354, 7111.893321600283, 7168.820779480555, 7216.278372162742, 7320.414366850746, 7367.889481657672, 6188.566800324114, 7018.403435708022, 7089.468289878383, 7095.184053833876, 7229.1325040993315, 6712.53012289135, 6723.798286051427, 6728.764493242713, 6808.761693340709, 6819.89770153458, 4896.11869863428, 4917.729524876023, 4945.579625731853, 4973.776180071978, 4978.775955081957, 4867.780949857336, 4885.438174609965, 4912.287718280901, 4916.311716359641, 5090.160123579568], "value": 4390.431631432683, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.2083374887803, 295.0911193523945, 299.18770537989354, 314.5250671486057, 316.22367088797006, 4436.401085853413, 4601.964557974454, 5019.849404517861, 5029.473473873516, 5233.319668443102, 4402.823887044522, 4848.127337079324, 4984.059404951078, 5140.820071297505, 5163.664361816479, 3800.5819359870393, 4001.599840015997, 4023.396490526415, 4031.1937612477477, 4067.32889073304, 3538.3454060998797, 3632.654897784706, 3636.5454318210222, 3908.2965066288057, 4010.919781604366, 3185.729213016896, 3981.3828856527284, 4003.219372222418, 4003.459532963042, 4004.779737114472, 3583.605803361615, 3635.363811333006, 3689.2107327945923, 3980.482537270136, 4013.5585085640555, 3151.369726054775, 3317.4857897026, 3458.429359155736, 3498.3178280235365, 3557.2707594943236, 4032.5160980682213, 4193.622573968327, 5059.2916991621205, 5059.671121377136, 5063.0, 4507.752073635948, 4525.321201819754, 4532.5042735897405, 4532.93200601988, 4545.20458919691, 5050.772715227824, 5352.4915133062295, 5484.725763711798, 5490.599897026273, 5526.364468086141, 2909.96302588187, 3464.72282217422, 3688.483612294281, 3741.420079887622, 3759.6052414496357, 4358.934615980763, 5466.972665136688, 5635.0, 5692.800751973702, 5709.0, 3802.0989505247376, 3812.046988252937, 3814.4087666411197, 3815.198808250243, 3865.43951127085, 2961.348503329264, 3776.8300426480873, 3841.8847434577006, 3852.3836186210074, 3874.089588946595, 4430.889227769331, 4431.290993441034, 4445.911081778347, 4456.977715111404, 4510.233260345749, 4601.3558101865765, 5495.175723641486, 5536.723163841792, 5543.695096769636, 5586.525145362609, 1075.6181555547823, 1188.263276768409, 1192.6839387562302, 1219.5913719653813, 1246.6945598328468, 718.8202949262684, 833.3083540661237, 859.9140085991476, 861.3496809908479, 861.4149964066148, 4026.4765580474937, 4309.806058727365, 4337.284348082531, 4704.764761761899, 4790.976045119822, 4436.423264975532, 4477.574630410106, 4781.402324709411, 5188.9740551297, 5367.463253674679, 4339.7396156230725, 4439.866803995821, 4753.786079626425, 4866.902661946741, 4921.0, 2285.108807565051, 2576.471823276224, 2578.1234380310784, 2801.6918139004692, 2810.018936649657, 4520.59314661682, 5430.456954304617, 5508.650380656764, 5518.668879867221, 5639.351474580394, 4998.700597725015, 5265.921011184835, 5427.240186373911, 5484.424135465719, 5495.340559132929, 5093.923591146062, 5211.602662775166, 5216.452272511484, 5361.1422172453, 5428.619996600279, 4736.0, 4768.0451015083645, 5275.86568887691, 5298.81454149103, 5329.0, 4245.490541135084, 4600.447946246338, 4624.237000894815, 5167.483251674803, 5271.630985831109, 4647.908441201405, 4708.398075472335, 4736.73947932867, 4749.24012158053, 5323.737411600132, 3235.7249633781394, 3515.4902539132195, 3559.843051008381, 3595.5685317762036, 3658.2866341063236, 4233.470816147981, 5014.323066385991, 5272.420033796203, 5346.117890548172, 5353.019382828378, 5104.273880067497, 5131.692098474104, 5145.768440420115, 5174.223866420067, 5183.734247424099, 4797.664163508521, 4812.542808433193, 5024.447310795883, 5085.46602606721, 5168.382132507335, 3927.2145570885364, 4469.530699276529, 4931.72875491837, 4955.603551715782, 5001.574866136273, 4630.536946305478, 4856.295837103529, 5156.458571850052, 5157.484251574812, 5159.638825282194, 4153.362614739602, 4393.912121757611, 4568.634509239187, 4971.353724015855, 5018.974905125451], "value": 3813.124577621888, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.5129107042927, 688.1088989758266, 696.2863065358007, 727.9917314519392, 749.3967356278193, 13953.372098255577, 14360.138391696506, 14371.49699760506, 14403.567892963214, 14414.207218602985, 12732.80900786489, 12770.68073298168, 12838.358082095881, 12861.485540578366, 12876.871231287685, 633.303366297073, 656.1306269193315, 657.3262406033806, 790.3203245209115, 802.6749166587541, 805.569020573993, 806.435495153395, 807.8222790985974, 808.6522795198092, 809.3808236698901, 865.3855762408666, 865.8917635295588, 866.0, 866.2853146154416, 866.4627930682954, 657.470736057476, 685.3763075601179, 689.4759982413352, 689.6689588997309, 863.602742738338, 516.6693316277585, 517.7566543724444, 615.667539528654, 637.7353398339684, 643.3052303512195, 13227.669308267274, 13444.596662100153, 13595.456181752817, 13647.317634118255, 13666.0, 12689.302088385135, 12779.552715655, 12799.296038717866, 12855.16441431304, 12996.220226786423, 11225.494852731646, 11692.94153529227, 11710.76578468426, 11929.0, 12005.099617528633, 4271.039016221325, 4319.0713996490595, 4349.84775532858, 4473.597376236122, 4494.056248187884, 87428.56285718594, 88201.55899220527, 89802.55098724464, 90139.0, 90644.54677726635, 10232.079112879877, 10287.331323463952, 10321.84517232242, 10328.690139295833, 10354.92901419719, 18826.90586547086, 19110.33113841009, 19202.13590388436, 19275.710864337045, 19339.61320773605, 7278.381337586258, 7396.667149978156, 7425.405967522585, 7606.467547271748, 7618.085829700361, 3828.2343531293855, 3990.4812374391145, 4058.939115913264, 4111.116110036357, 4116.0121570822785, 3170.1599076244815, 3252.219467327824, 3278.131295206772, 3280.6555311691927, 3284.014795561322, 546.1780021068267, 622.1352320274822, 624.6158612453308, 639.68015992004, 639.8304449320933, 543.9020976224239, 548.7612888393562, 578.4562511239421, 591.5149577346534, 614.1126072824815, 7263.673134708951, 8104.756857294291, 8244.422890397735, 8514.74455766328, 8521.93475815523, 525.5244004176227, 572.7937942340754, 588.2617315269395, 592.2212290837535, 627.4102343796816, 3798.164403831153, 4131.338985762323, 4173.7078404512, 4241.87274381769, 4257.148570285955, 111535.0, 116066.41966790112, 116503.41748291205, 117683.41158294326, 119471.0, 33134.83432582822, 33293.66706332954, 59058.0, 89469.0, 92342.53828730814, 84271.0, 85033.0, 85955.57022214848, 88135.55932220299, 89336.10663893408, 8008.239217274349, 8123.7969050771835, 8187.222213889671, 8187.71343002992, 8334.08325084228, 52238.73880630572, 54980.72509637426, 57548.71225643845, 57812.84374312574, 60500.09249861167, 39841.0, 46903.76548117238, 60365.69817150886, 62168.0, 70544.472776362, 12031.15781895294, 12102.152849300464, 12202.81695774582, 12570.182938108994, 12582.18215815969, 13229.338533073116, 13343.0, 14107.294635268401, 14122.78815817784, 14475.27623618836, 8445.268719912281, 12248.38758062111, 13100.148490348098, 13565.321733913463, 13771.173729576258, 12815.615531533696, 12855.935720321339, 12994.870051299366, 13205.867941320848, 13801.240931748851, 13157.210567365988, 13404.664883377993, 13728.725425491633, 13844.861551384358, 13919.234442105377, 9148.0, 10918.727031824268, 11082.889171108509, 11424.48589813477, 11464.770704586028, 9218.907810921804, 10476.738081548023, 11007.504662290055, 11126.0, 11408.657740267805], "value": 6942.548665591268, "unit": "score"}], "name": "dromaeo_css", "value": 7008.468878355167, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 115.0, 114.0, 115.0, 114.0, 114.0, 114.0, 121.0, 170.0], "value": 120.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 116.0, 114.0, 164.0, 145.0, 115.0, 114.0, 114.0, 143.0, 117.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 184.0, 185.0, 182.0, 184.0, 187.0, 220.0, 183.0, 186.0], "value": 188.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 89.0, 63.0, 59.0, 59.0, 59.0, 82.0, 72.0], "value": 68.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 87.0, 127.0, 98.0, 76.0, 69.0, 88.0, 67.0, 68.0, 91.0], "value": 85.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 93.0, 128.0, 116.0, 84.0, 89.0, 87.0, 88.0, 85.0], "value": 94.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 87.0, 122.0, 84.0, 85.0, 83.0, 79.0, 80.0, 81.0], "value": 86.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 53.0, 57.0, 57.0, 55.0, 56.0, 55.0, 56.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 79.0, 74.0, 72.0, 70.0, 71.0, 71.0, 70.0, 72.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 84.0, 98.0, 80.0, 84.0, 84.0, 83.0, 87.0, 96.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 133.0, 170.0, 145.0, 150.0, 157.0, 148.0, 149.0, 188.0, 186.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 172.0, 168.0, 168.0, 166.0, 165.0, 167.0, 170.0, 167.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 69.0, 69.0, 68.0, 68.0, 68.0, 67.0, 69.0, 67.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1473.9999999999998, "alertThreshold": 2.0}]} 17:25:15 INFO - Return code: 0 17:25:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:25:15 INFO - # TBPL SUCCESS # 17:25:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:25:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:25:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:25:15 INFO - 2016-11-30 17:25:15.480 screenresolution[1652:10001] starting screenresolution argv=screenresolution get 17:25:15 INFO - 2016-11-30 17:25:15.482 screenresolution[1652:10001] Display 0: 1600x1200x32@60 17:25:15 INFO - 2016-11-30 17:25:15.489 screenresolution[1653:10004] starting screenresolution argv=screenresolution list 17:25:15 INFO - Available Modes on Display 0 17:25:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:25:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:25:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:25:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:25:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:25:15 INFO - Intel Iris: 17:25:15 INFO - Chipset Model: Intel Iris 17:25:15 INFO - Type: GPU 17:25:15 INFO - Bus: Built-In 17:25:15 INFO - VRAM (Dynamic, Max): 1536 MB 17:25:15 INFO - Vendor: Intel (0x8086) 17:25:15 INFO - Device ID: 0x0a2e 17:25:15 INFO - Revision ID: 0x0009 17:25:15 INFO - Displays: 17:25:15 INFO - Display: 17:25:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:25:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:25:15 INFO - Display Serial Number: 1600x1200 60 17:25:15 INFO - Main Display: Yes 17:25:15 INFO - Mirror: Off 17:25:15 INFO - Online: Yes 17:25:15 INFO - Rotation: Supported 17:25:15 INFO - Return code: 0 17:25:15 INFO - Running post-action listener: _resource_record_post_action 17:25:15 INFO - [mozharness: 2016-12-01 01:25:15.734460Z] Finished run-tests step (success) 17:25:15 INFO - Running post-run listener: _resource_record_post_run 17:25:15 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37692416; Write bytes: 653611520; Read time: 243; Write time: 1870 17:25:15 INFO - TinderboxPrint: CPU usage
53.6% 17:25:15 INFO - TinderboxPrint: I/O read bytes / time
37,692,416 / 243 17:25:15 INFO - TinderboxPrint: I/O write bytes / time
653,611,520 / 1,870 17:25:15 INFO - TinderboxPrint: CPU idle
2,249.2 (46.4%) 17:25:15 INFO - TinderboxPrint: CPU system
974.0 (20.1%) 17:25:15 INFO - TinderboxPrint: CPU user
1,626.6 (33.5%) 17:25:15 INFO - TinderboxPrint: Swap in / out
386,396,160 / 0 17:25:15 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 340476416; Write bytes: 334628864; Read time: 26960; Write time: 671 17:25:15 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 36713472; Write bytes: 302291456; Read time: 226; Write time: 1145 17:25:16 INFO - Running post-run listener: _upload_blobber_files 17:25:16 INFO - Blob upload gear active. 17:25:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:25:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:25:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:25:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:25:16 INFO - (blobuploader) - INFO - Open directory for files ... 17:25:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:25:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:25:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:17 INFO - (blobuploader) - INFO - Done attempting. 17:25:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:25:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:25:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:18 INFO - (blobuploader) - INFO - Done attempting. 17:25:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:25:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:25:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:21 INFO - (blobuploader) - INFO - Done attempting. 17:25:21 INFO - (blobuploader) - INFO - Iteration through files over. 17:25:21 INFO - Return code: 0 17:25:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:25:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:25:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ca31d92f785853aa1731e41be908ce847014de1bc217de9ea18dfefe598997a47d13fc48d94dda3b21f648341d88e7690c1e764adb95b894be605405290388d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdea5c85677d7adb04d724260d266ceff50b4e04ae59b57b4f70a1ed258b7177025b70f668ee5f216b54861c494ac56965cfe3d68d40ba659878cb6ac340b3ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7670c69c210187b5a28ce3f97233e9c2fad0459f5277411855e160b8595edd1278b127d9ce09da23014c53c26aaccbf1e5d4f06d421dc5051fc3c442c68c1c34"} 17:25:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:25:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:25:21 INFO - Contents: 17:25:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ca31d92f785853aa1731e41be908ce847014de1bc217de9ea18dfefe598997a47d13fc48d94dda3b21f648341d88e7690c1e764adb95b894be605405290388d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdea5c85677d7adb04d724260d266ceff50b4e04ae59b57b4f70a1ed258b7177025b70f668ee5f216b54861c494ac56965cfe3d68d40ba659878cb6ac340b3ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7670c69c210187b5a28ce3f97233e9c2fad0459f5277411855e160b8595edd1278b127d9ce09da23014c53c26aaccbf1e5d4f06d421dc5051fc3c442c68c1c34"} 17:25:21 INFO - Running post-run listener: copy_logs_to_upload_dir 17:25:21 INFO - Copying logs to upload dir... 17:25:21 INFO - mkdir: /builds/slave/test/build/upload/logs 17:25:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.402690 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-30 17:25:21.292264) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 17:25:21.295434) ========= 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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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/3ca31d92f785853aa1731e41be908ce847014de1bc217de9ea18dfefe598997a47d13fc48d94dda3b21f648341d88e7690c1e764adb95b894be605405290388d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdea5c85677d7adb04d724260d266ceff50b4e04ae59b57b4f70a1ed258b7177025b70f668ee5f216b54861c494ac56965cfe3d68d40ba659878cb6ac340b3ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7670c69c210187b5a28ce3f97233e9c2fad0459f5277411855e160b8595edd1278b127d9ce09da23014c53c26aaccbf1e5d4f06d421dc5051fc3c442c68c1c34"} build_url:https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010896 build_url: 'https://queue.taskcluster.net/v1/task/Hg6TLJGISPudrexOcaqctA/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/3ca31d92f785853aa1731e41be908ce847014de1bc217de9ea18dfefe598997a47d13fc48d94dda3b21f648341d88e7690c1e764adb95b894be605405290388d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdea5c85677d7adb04d724260d266ceff50b4e04ae59b57b4f70a1ed258b7177025b70f668ee5f216b54861c494ac56965cfe3d68d40ba659878cb6ac340b3ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7670c69c210187b5a28ce3f97233e9c2fad0459f5277411855e160b8595edd1278b127d9ce09da23014c53c26aaccbf1e5d4f06d421dc5051fc3c442c68c1c34"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 17:25:21.330089) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:25:21.330435) ========= 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.Bm2xrc6FSc/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.zqOcHYsznU/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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.004938 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:25:21.379131) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 17:25:21.379461) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 17:25:21.379788) ========= ========= Total master_lag: 0.18 =========