builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0105 starttime: 1479953641.03 results: success (0) buildid: 20161123150736 builduid: 63ab215d18974ae997fd70b326b24b52 revision: 116d83d90ce4ca5cb214e3e33c5da6735d59b848 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 18:14:01.027289) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 18:14:01.027779) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 18:14:01.028059) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 18:14:01.053849) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:14:01.054325) ========= 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.BQ7FBJKEma/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.GMGcq69RDo/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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-23 18:14:01-- 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% 21.0M=0.001s 2016-11-23 18:14:01 (21.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139499 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:14:01.218134) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:14:01.218539) ========= 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.BQ7FBJKEma/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.GMGcq69RDo/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.019209 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:14:01.267286) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 18:14:01.267683) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 116d83d90ce4ca5cb214e3e33c5da6735d59b848 --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 116d83d90ce4ca5cb214e3e33c5da6735d59b848 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BQ7FBJKEma/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.GMGcq69RDo/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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-23 18:14:01,342 truncating revision to first 12 chars 2016-11-23 18:14:01,342 Setting DEBUG logging. 2016-11-23 18:14:01,342 attempt 1/10 2016-11-23 18:14:01,342 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/116d83d90ce4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-23 18:14:02,544 unpacking tar archive at: mozilla-aurora-116d83d90ce4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.461484 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 18:14:02.756445) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 18:14:02.756793) ========= script_repo_revision: 116d83d90ce4ca5cb214e3e33c5da6735d59b848 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 18:14:02.757226) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 18:14:02.757499) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 18:14:02.777934) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-23 18:14:02.778424) ========= /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.BQ7FBJKEma/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.GMGcq69RDo/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:14:02 INFO - MultiFileLogger online at 20161123 18:14:02 in /builds/slave/test 18:14:02 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 18:14:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:14:02 INFO - {'append_to_log': False, 18:14:02 INFO - 'base_work_dir': '/builds/slave/test', 18:14:02 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:14:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:14:02 INFO - 'branch': 'Mozilla-Aurora', 18:14:02 INFO - 'buildbot_json_path': 'buildprops.json', 18:14:02 INFO - 'config_files': ('talos/mac_config.py',), 18:14:02 INFO - 'default_actions': ('clobber', 18:14:02 INFO - 'read-buildbot-config', 18:14:02 INFO - 'download-and-extract', 18:14:02 INFO - 'populate-webroot', 18:14:02 INFO - 'create-virtualenv', 18:14:02 INFO - 'install', 18:14:02 INFO - 'run-tests'), 18:14:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:14:02 INFO - 'download_minidump_stackwalk': True, 18:14:02 INFO - 'download_symbols': 'ondemand', 18:14:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:14:02 INFO - 'tooltool.py': '/tools/tooltool.py', 18:14:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:14:02 INFO - '/tools/misc-python/virtualenv.py')}, 18:14:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:14:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:14:02 INFO - 'installer_path': 'installer.exe', 18:14:02 INFO - 'log_level': 'info', 18:14:02 INFO - 'log_name': 'talos', 18:14:02 INFO - 'log_to_console': True, 18:14:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:14:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:14:02 INFO - 'opt_config_files': (), 18:14:02 INFO - 'pip_index': False, 18:14:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:14:02 INFO - 'cmd': ('bash', 18:14:02 INFO - '-c', 18:14:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:14:02 INFO - 'enabled': True, 18:14:02 INFO - 'halt_on_failure': False, 18:14:02 INFO - 'name': 'check_screen_resolution'},), 18:14:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:14:02 INFO - 'cmd': ('bash', 18:14:02 INFO - '-c', 18:14:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:14:02 INFO - 'enabled': True, 18:14:02 INFO - 'halt_on_failure': False, 18:14:02 INFO - 'name': 'check_screen_resolution'},), 18:14:02 INFO - 'run_cmd_checks_enabled': True, 18:14:02 INFO - 'sps_profile': False, 18:14:02 INFO - 'sps_profile_interval': 0, 18:14:02 INFO - 'suite': 'dromaeojs', 18:14:02 INFO - 'system_bits': '32', 18:14:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:14:02 INFO - 'title': 't-yosemite-r7-0105', 18:14:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:14:02 INFO - 'use_talos_json': True, 18:14:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:14:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:14:02 INFO - 'work_dir': 'build'} 18:14:02 INFO - [mozharness: 2016-11-24 02:14:02.978530Z] Running clobber step. 18:14:02 INFO - Running pre-action listener: _resource_record_pre_action 18:14:02 INFO - Running main action method: clobber 18:14:02 INFO - rmtree: /builds/slave/test/build 18:14:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:14:04 INFO - Running post-action listener: _resource_record_post_action 18:14:04 INFO - [mozharness: 2016-11-24 02:14:04.487749Z] Finished clobber step (success) 18:14:04 INFO - [mozharness: 2016-11-24 02:14:04.487940Z] Running read-buildbot-config step. 18:14:04 INFO - Running pre-action listener: _resource_record_pre_action 18:14:04 INFO - Running main action method: read_buildbot_config 18:14:04 INFO - Using buildbot properties: 18:14:04 INFO - { 18:14:04 INFO - "project": "", 18:14:04 INFO - "product": "firefox", 18:14:04 INFO - "script_repo_revision": "production", 18:14:04 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:14:04 INFO - "repository": "", 18:14:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:14:04 INFO - "buildid": "20161123150736", 18:14:04 INFO - "pgo_build": "False", 18:14:04 INFO - "basedir": "/builds/slave/test", 18:14:04 INFO - "buildnumber": 32, 18:14:04 INFO - "slavename": "t-yosemite-r7-0105", 18:14:04 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:14:04 INFO - "platform": "yosemite_r7", 18:14:04 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:14:04 INFO - "branch": "mozilla-aurora", 18:14:04 INFO - "repo_path": "releases/mozilla-aurora", 18:14:04 INFO - "revision": "116d83d90ce4ca5cb214e3e33c5da6735d59b848", 18:14:04 INFO - "stage_platform": "macosx64", 18:14:04 INFO - "builduid": "63ab215d18974ae997fd70b326b24b52", 18:14:04 INFO - "slavebuilddir": "test" 18:14:04 INFO - } 18:14:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 18:14:04 INFO - Running post-action listener: _resource_record_post_action 18:14:04 INFO - [mozharness: 2016-11-24 02:14:04.491302Z] Finished read-buildbot-config step (success) 18:14:04 INFO - [mozharness: 2016-11-24 02:14:04.491439Z] Running download-and-extract step. 18:14:04 INFO - Running pre-action listener: _resource_record_pre_action 18:14:04 INFO - Running main action method: download_and_extract 18:14:04 INFO - mkdir: /builds/slave/test/build/tests 18:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:04 INFO - trying https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 18:14:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/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 18:14:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/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 18:14:05 INFO - Downloaded 1199 bytes. 18:14:05 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 18:14:05 INFO - Using the following test package requirements: 18:14:05 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:14:05 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:14:05 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 18:14:05 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:14:05 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 18:14:05 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:14:05 INFO - u'jsshell-mac.zip'], 18:14:05 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:14:05 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 18:14:05 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:14:05 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:14:05 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 18:14:05 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:14:05 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 18:14:05 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:14:05 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 18:14:05 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:14:05 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 18:14:05 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:14:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:14:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:14:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:14:06 INFO - Http code: 200 18:14:06 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 18:14:06 INFO - x-amz-cf-id: kbr6FiLj7VddVEvcqD3TYLGaVJgIOxI6WgwFLas5lznEM8MlDFmn0g== 18:14:06 INFO - x-amz-version-id: aaQI8Ou79JdQHhNzoiN3dw86FVF5GyxI 18:14:06 INFO - Expected file size: 16278314 18:14:06 INFO - Obtained file size: 16278314 18:14:13 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 18:14:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:14:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:14:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:14:13 INFO - Http code: 200 18:14:13 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 18:14:13 INFO - x-amz-cf-id: u5fWLKte85qpDgj7nTrnGgd8QnBFTSvWtGEk5CwkeLjWrX39y4pPMw== 18:14:13 INFO - x-amz-version-id: aaQI8Ou79JdQHhNzoiN3dw86FVF5GyxI 18:14:14 INFO - Expected file size: 16278314 18:14:14 INFO - Obtained file size: 16278314 18:14:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 18:14:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:14:20 INFO - Fetch https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 18:14:22 INFO - Http code: 200 18:14:22 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 18:14:22 INFO - x-amz-cf-id: c7H4zYkGniNqVkZ4gG8Hec89sYpb5Jbd8W07fG7GtW-65f7FLDpc2Q== 18:14:22 INFO - x-amz-version-id: JaZpp6qEjLS9DGJRP507u1nx6Z9rTYtJ 18:14:22 INFO - Expected file size: 14409038 18:14:22 INFO - Obtained file size: 14409038 18:14:22 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')]} 18:14:22 INFO - trying https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:14:22 INFO - Downloading https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 18:14:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/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 18:14:25 INFO - Downloaded 109683609 bytes. 18:14:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:14:25 INFO - mkdir: /builds/slave/test/properties 18:14:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:14:25 INFO - Writing to file /builds/slave/test/properties/build_url 18:14:25 INFO - Contents: 18:14:25 INFO - build_url:https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:14:26 INFO - Running post-action listener: _resource_record_post_action 18:14:26 INFO - Running post-action listener: set_extra_try_arguments 18:14:26 INFO - [mozharness: 2016-11-24 02:14:26.152031Z] Finished download-and-extract step (success) 18:14:26 INFO - [mozharness: 2016-11-24 02:14:26.152148Z] Running populate-webroot step. 18:14:26 INFO - Running pre-action listener: _resource_record_pre_action 18:14:26 INFO - Running main action method: populate_webroot 18:14:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:14:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:14:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:14:26 INFO - '--tppagecycles', 18:14:26 INFO - '7'], 18:14:26 INFO - 'tests': ['tsvgm']}}, 18:14:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:14:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:14:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:14:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:14:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:14:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:14:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:26 INFO - 'talos_options': ['--disable-e10s'], 18:14:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:14:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:14:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:26 INFO - 'talos_options': ['--disable-e10s'], 18:14:26 INFO - 'tests': ['damp', 'tps']}, 18:14:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:26 INFO - 'tests': ['damp', 'tps']}, 18:14:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:14:26 INFO - 'tests': ['dromaeo_dom']}, 18:14:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:14:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:14:26 INFO - 'tests': ['basic_compositor_video']}, 18:14:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:14:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:14:26 INFO - 'tests': ['a11yr', 18:14:26 INFO - 'ts_paint', 18:14:26 INFO - 'tpaint', 18:14:26 INFO - 'sessionrestore', 18:14:26 INFO - 'sessionrestore_no_auto_restore', 18:14:26 INFO - 'tabpaint']}, 18:14:26 INFO - 'other-e10s': {'tests': ['a11yr', 18:14:26 INFO - 'ts_paint', 18:14:26 INFO - 'tpaint', 18:14:26 INFO - 'sessionrestore', 18:14:26 INFO - 'sessionrestore_no_auto_restore', 18:14:26 INFO - 'tabpaint']}, 18:14:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:14:26 INFO - 'ts_paint', 18:14:26 INFO - 'tpaint', 18:14:26 INFO - 'sessionrestore', 18:14:26 INFO - 'sessionrestore_no_auto_restore', 18:14:26 INFO - 'tabpaint']}, 18:14:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:14:26 INFO - 'ts_paint', 18:14:26 INFO - 'tpaint', 18:14:26 INFO - 'sessionrestore', 18:14:26 INFO - 'sessionrestore_no_auto_restore', 18:14:26 INFO - 'tabpaint']}, 18:14:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:14:26 INFO - 'tests': ['a11yr', 18:14:26 INFO - 'ts_paint', 18:14:26 INFO - 'tpaint', 18:14:26 INFO - 'sessionrestore', 18:14:26 INFO - 'sessionrestore_no_auto_restore', 18:14:26 INFO - 'tabpaint']}, 18:14:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:14:26 INFO - 'tests': ['a11yr', 18:14:26 INFO - 'ts_paint', 18:14:26 INFO - 'tpaint', 18:14:26 INFO - 'sessionrestore', 18:14:26 INFO - 'sessionrestore_no_auto_restore', 18:14:26 INFO - 'tabpaint']}, 18:14:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:14:26 INFO - 'tests': ['tsvgx', 18:14:26 INFO - 'tsvgr_opacity', 18:14:26 INFO - 'tart', 18:14:26 INFO - 'tscrollx', 18:14:26 INFO - 'cart']}, 18:14:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:14:26 INFO - 'tsvgr_opacity', 18:14:26 INFO - 'tart', 18:14:26 INFO - 'tscrollx', 18:14:26 INFO - 'cart']}, 18:14:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:26 INFO - 'talos_options': ['--disable-e10s'], 18:14:26 INFO - 'tests': ['tp5o']}, 18:14:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:26 INFO - 'tests': ['tp5o']}, 18:14:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:26 INFO - 'talos_options': ['--disable-e10s', 18:14:26 INFO - '--xperf_path', 18:14:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:14:26 INFO - 'tests': ['tp5n']}, 18:14:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:26 INFO - 'talos_options': ['--xperf_path', 18:14:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:14:26 INFO - 'tests': ['tp5n']}}, 18:14:26 INFO - 'talos.zip': {'path': '', 18:14:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:14:26 INFO - Running post-action listener: _resource_record_post_action 18:14:26 INFO - [mozharness: 2016-11-24 02:14:26.170561Z] Finished populate-webroot step (success) 18:14:26 INFO - [mozharness: 2016-11-24 02:14:26.170699Z] Running create-virtualenv step. 18:14:26 INFO - Running pre-action listener: _resource_record_pre_action 18:14:26 INFO - Running main action method: create_virtualenv 18:14:26 INFO - Creating virtualenv /builds/slave/test/build/venv 18:14:26 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 18:14:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:14:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:14:26 INFO - Using real prefix '/tools/python27' 18:14:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:14:27 INFO - Installing distribute.............................................................................................................................................................................................done. 18:14:30 INFO - Installing pip.................done. 18:14:30 INFO - Return code: 0 18:14:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:14:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:14:30 INFO - Reading from file tmpfile_stdout 18:14:30 INFO - Output received: 18:14:30 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) 18:14:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:14:30 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')]} 18:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:30 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')]} 18:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:30 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 0x10b03c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd26acaef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae50d30>, '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 0x10acb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae48b20>, '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.BQ7FBJKEma/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.GMGcq69RDo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:14:30 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 18:14:30 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 18:14:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQ7FBJKEma/Render', 18:14:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:30 INFO - 'HOME': '/Users/cltbld', 18:14:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:30 INFO - 'LOGNAME': 'cltbld', 18:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:30 INFO - 'MOZ_NO_REMOTE': '1', 18:14:30 INFO - 'NO_EM_RESTART': '1', 18:14:30 INFO - 'PAGER': '/bin/cat', 18:14:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:30 INFO - 'PWD': '/builds/slave/test', 18:14:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:30 INFO - 'SHELL': '/bin/bash', 18:14:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GMGcq69RDo/Listeners', 18:14:30 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:14:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:30 INFO - 'USER': 'cltbld', 18:14:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:30 INFO - 'XPC_FLAGS': '0x0', 18:14:30 INFO - 'XPC_SERVICE_NAME': '0', 18:14:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:30 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 18:14:30 INFO - Cleaning up... 18:14:30 INFO - Return code: 0 18:14:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:14:30 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')]} 18:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:30 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')]} 18:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:30 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 0x10b03c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd26acaef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae50d30>, '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 0x10acb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae48b20>, '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.BQ7FBJKEma/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.GMGcq69RDo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:14:30 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 18:14:30 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 18:14:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQ7FBJKEma/Render', 18:14:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:30 INFO - 'HOME': '/Users/cltbld', 18:14:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:30 INFO - 'LOGNAME': 'cltbld', 18:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:30 INFO - 'MOZ_NO_REMOTE': '1', 18:14:30 INFO - 'NO_EM_RESTART': '1', 18:14:30 INFO - 'PAGER': '/bin/cat', 18:14:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:30 INFO - 'PWD': '/builds/slave/test', 18:14:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:30 INFO - 'SHELL': '/bin/bash', 18:14:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GMGcq69RDo/Listeners', 18:14:30 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:14:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:30 INFO - 'USER': 'cltbld', 18:14:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:30 INFO - 'XPC_FLAGS': '0x0', 18:14:30 INFO - 'XPC_SERVICE_NAME': '0', 18:14:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:30 INFO - Downloading/unpacking psutil>=3.1.1 18:14:30 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 18:14:30 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 18:14:30 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 18:14:30 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 18:14:30 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 18:14:30 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 18:14:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:14:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:14:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:14:34 INFO - Installing collected packages: psutil 18:14:34 INFO - Running setup.py install for psutil 18:14:34 INFO - building 'psutil._psutil_osx' extension 18:14:34 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 18:14:34 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 18:14:34 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 18:14:34 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 18:14:34 INFO - building 'psutil._psutil_posix' extension 18:14:34 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 18:14:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:14:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:14:34 INFO - ^ 18:14:34 INFO - 1 warning generated. 18:14:34 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 18:14:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:14:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:14:35 INFO - Successfully installed psutil 18:14:35 INFO - Cleaning up... 18:14:35 INFO - Return code: 0 18:14:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:14:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b03c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd26acaef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae50d30>, '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 0x10acb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae48b20>, '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.BQ7FBJKEma/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.GMGcq69RDo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:14:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:14:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:14:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQ7FBJKEma/Render', 18:14:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:35 INFO - 'HOME': '/Users/cltbld', 18:14:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:35 INFO - 'LOGNAME': 'cltbld', 18:14:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:35 INFO - 'MOZ_NO_REMOTE': '1', 18:14:35 INFO - 'NO_EM_RESTART': '1', 18:14:35 INFO - 'PAGER': '/bin/cat', 18:14:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:35 INFO - 'PWD': '/builds/slave/test', 18:14:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:35 INFO - 'SHELL': '/bin/bash', 18:14:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GMGcq69RDo/Listeners', 18:14:35 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:14:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:35 INFO - 'USER': 'cltbld', 18:14:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:35 INFO - 'XPC_FLAGS': '0x0', 18:14:35 INFO - 'XPC_SERVICE_NAME': '0', 18:14:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:14:38 INFO - Installing collected packages: mozsystemmonitor 18:14:38 INFO - Running setup.py install for mozsystemmonitor 18:14:38 INFO - Successfully installed mozsystemmonitor 18:14:38 INFO - Cleaning up... 18:14:38 INFO - Return code: 0 18:14:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:14: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')]} 18:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14: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')]} 18:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14: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', '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 0x10b03c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd26acaef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae50d30>, '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 0x10acb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae48b20>, '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.BQ7FBJKEma/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.GMGcq69RDo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:14: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:14: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 jsonschema==2.5.1 18:14:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQ7FBJKEma/Render', 18:14:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:38 INFO - 'HOME': '/Users/cltbld', 18:14:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:38 INFO - 'LOGNAME': 'cltbld', 18:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:38 INFO - 'MOZ_NO_REMOTE': '1', 18:14:38 INFO - 'NO_EM_RESTART': '1', 18:14:38 INFO - 'PAGER': '/bin/cat', 18:14:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:38 INFO - 'PWD': '/builds/slave/test', 18:14:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:38 INFO - 'SHELL': '/bin/bash', 18:14:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GMGcq69RDo/Listeners', 18:14:38 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:14:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:38 INFO - 'USER': 'cltbld', 18:14:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:38 INFO - 'XPC_FLAGS': '0x0', 18:14:38 INFO - 'XPC_SERVICE_NAME': '0', 18:14:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:38 INFO - Downloading/unpacking jsonschema==2.5.1 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:14:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14:41 INFO - Downloading functools32-3.2.3-2.tar.gz 18:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:14:41 INFO - warning: no files found matching '*.txt' 18:14:41 INFO - no previously-included directories found matching 'build' 18:14:41 INFO - no previously-included directories found matching 'dist' 18:14:41 INFO - no previously-included directories found matching '.git*' 18:14:41 INFO - Installing collected packages: jsonschema, functools32 18:14:41 INFO - Running setup.py install for functools32 18:14:41 INFO - warning: no files found matching '*.txt' 18:14:41 INFO - no previously-included directories found matching 'build' 18:14:41 INFO - no previously-included directories found matching 'dist' 18:14:41 INFO - no previously-included directories found matching '.git*' 18:14:41 INFO - Successfully installed jsonschema functools32 18:14:41 INFO - Cleaning up... 18:14:41 INFO - Return code: 0 18:14:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:14: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')]} 18:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14: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')]} 18:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14: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', '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 0x10b03c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd26acaef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae50d30>, '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 0x10acb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae48b20>, '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.BQ7FBJKEma/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.GMGcq69RDo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:14: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:14: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 functools32==3.2.3-2 18:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQ7FBJKEma/Render', 18:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:41 INFO - 'HOME': '/Users/cltbld', 18:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:41 INFO - 'LOGNAME': 'cltbld', 18:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:41 INFO - 'MOZ_NO_REMOTE': '1', 18:14:41 INFO - 'NO_EM_RESTART': '1', 18:14:41 INFO - 'PAGER': '/bin/cat', 18:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:41 INFO - 'PWD': '/builds/slave/test', 18:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:41 INFO - 'SHELL': '/bin/bash', 18:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GMGcq69RDo/Listeners', 18:14:41 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:41 INFO - 'USER': 'cltbld', 18:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:41 INFO - 'XPC_FLAGS': '0x0', 18:14:41 INFO - 'XPC_SERVICE_NAME': '0', 18:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:14:42 INFO - Cleaning up... 18:14:42 INFO - Return code: 0 18:14:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b03c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd26acaef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae50d30>, '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 0x10acb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae48b20>, '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.BQ7FBJKEma/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.GMGcq69RDo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:14:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:14:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQ7FBJKEma/Render', 18:14:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:42 INFO - 'HOME': '/Users/cltbld', 18:14:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:42 INFO - 'LOGNAME': 'cltbld', 18:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:42 INFO - 'MOZ_NO_REMOTE': '1', 18:14:42 INFO - 'NO_EM_RESTART': '1', 18:14:42 INFO - 'PAGER': '/bin/cat', 18:14:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:42 INFO - 'PWD': '/builds/slave/test', 18:14:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:42 INFO - 'SHELL': '/bin/bash', 18:14:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GMGcq69RDo/Listeners', 18:14:42 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:14:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:42 INFO - 'USER': 'cltbld', 18:14:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:42 INFO - 'XPC_FLAGS': '0x0', 18:14:42 INFO - 'XPC_SERVICE_NAME': '0', 18:14:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:42 INFO - Downloading/unpacking blobuploader==1.2.4 18:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:44 INFO - Downloading blobuploader-1.2.4.tar.gz 18:14:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:14:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:14:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14:46 INFO - Downloading docopt-0.6.1.tar.gz 18:14:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:14:46 INFO - Installing collected packages: blobuploader, requests, docopt 18:14:46 INFO - Running setup.py install for blobuploader 18:14:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:14:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:14:46 INFO - Running setup.py install for requests 18:14:46 INFO - Running setup.py install for docopt 18:14:46 INFO - Successfully installed blobuploader requests docopt 18:14:46 INFO - Cleaning up... 18:14:47 INFO - Return code: 0 18:14:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:47 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 0x10b03c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd26acaef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae50d30>, '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 0x10acb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae48b20>, '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.BQ7FBJKEma/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.GMGcq69RDo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:14:47 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 18:14:47 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 18:14:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQ7FBJKEma/Render', 18:14:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:47 INFO - 'HOME': '/Users/cltbld', 18:14:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:47 INFO - 'LOGNAME': 'cltbld', 18:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:47 INFO - 'MOZ_NO_REMOTE': '1', 18:14:47 INFO - 'NO_EM_RESTART': '1', 18:14:47 INFO - 'PAGER': '/bin/cat', 18:14:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:47 INFO - 'PWD': '/builds/slave/test', 18:14:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:47 INFO - 'SHELL': '/bin/bash', 18:14:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GMGcq69RDo/Listeners', 18:14:47 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:14:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:47 INFO - 'USER': 'cltbld', 18:14:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:47 INFO - 'XPC_FLAGS': '0x0', 18:14:47 INFO - 'XPC_SERVICE_NAME': '0', 18:14:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:14:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-MCO9QL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:14:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-DXZwxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:14:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-DyDdTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Yk2WrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-9sfhZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-lsKuAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-7zLM3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:48 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-PoGtrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:48 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Tn3oHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:48 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-arU4jD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:48 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-pvTTJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:48 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-b1FVRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:48 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-81TmuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:48 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-5QB3Lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:48 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-2bjo9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:48 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-MqCidW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:49 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-3OJFlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:14:49 INFO - Running setup.py install for manifestparser 18:14:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:14:49 INFO - Running setup.py install for mozcrash 18:14:49 INFO - Running setup.py install for mozdebug 18:14:49 INFO - Running setup.py install for mozdevice 18:14:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:14:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:14:49 INFO - Running setup.py install for mozfile 18:14:49 INFO - Running setup.py install for mozhttpd 18:14:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:14:50 INFO - Running setup.py install for mozinfo 18:14:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:14:50 INFO - Running setup.py install for mozInstall 18:14:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:14:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:14:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:14:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:14:50 INFO - Running setup.py install for mozleak 18:14:50 INFO - Running setup.py install for mozlog 18:14:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:14:50 INFO - Running setup.py install for moznetwork 18:14:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:14:50 INFO - Running setup.py install for mozprocess 18:14:50 INFO - Running setup.py install for mozprofile 18:14:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:14:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:14:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:14:51 INFO - Running setup.py install for mozrunner 18:14:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:14:51 INFO - Running setup.py install for mozscreenshot 18:14:51 INFO - Running setup.py install for moztest 18:14:51 INFO - Running setup.py install for mozversion 18:14:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:14:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:14:51 INFO - Cleaning up... 18:14:51 INFO - Return code: 0 18:14:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:51 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')]} 18:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:51 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')]} 18:14:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:51 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 0x10b03c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd26acaef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae50d30>, '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 0x10acb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae48b20>, '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.BQ7FBJKEma/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.GMGcq69RDo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:14:51 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 18:14:51 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 18:14:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQ7FBJKEma/Render', 18:14:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:51 INFO - 'HOME': '/Users/cltbld', 18:14:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:51 INFO - 'LOGNAME': 'cltbld', 18:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:51 INFO - 'MOZ_NO_REMOTE': '1', 18:14:51 INFO - 'NO_EM_RESTART': '1', 18:14:51 INFO - 'PAGER': '/bin/cat', 18:14:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:51 INFO - 'PWD': '/builds/slave/test', 18:14:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:51 INFO - 'SHELL': '/bin/bash', 18:14:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GMGcq69RDo/Listeners', 18:14:51 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:14:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:51 INFO - 'USER': 'cltbld', 18:14:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:51 INFO - 'XPC_FLAGS': '0x0', 18:14:51 INFO - 'XPC_SERVICE_NAME': '0', 18:14:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:14:51 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-EVhnKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:14:52 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)) 18:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:14:52 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-X3yan5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:14:52 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)) 18:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:14:52 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-scDZmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:52 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)) 18:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:52 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-REgjPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:52 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)) 18:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:52 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-CC6RPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:52 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)) 18:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:52 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-uUPWiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:52 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)) 18:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:52 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-LftiqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:52 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)) 18:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:52 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-8VqPfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:52 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)) 18:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:52 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-u8RUd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:52 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)) 18:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:52 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-qllilq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:53 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)) 18:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-3OfF1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:53 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)) 18:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-pkeEeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:53 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)) 18:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-4P38CS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:53 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)) 18:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-bpIv4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:53 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)) 18:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-HulbqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:53 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)) 18:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-EwCxOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:53 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)) 18:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ZQbVC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:53 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)) 18:14:53 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)) 18:14:53 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)) 18:14:53 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)) 18:14:53 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)) 18:14:53 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)) 18:14:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:14:53 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 18:14:53 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 18:14:53 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 18:14:53 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 18:14:53 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 18:14:53 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 18:14:56 INFO - Downloading blessings-1.6.tar.gz 18:14:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:14:56 INFO - Installing collected packages: blessings 18:14:56 INFO - Running setup.py install for blessings 18:14:57 INFO - Successfully installed blessings 18:14:57 INFO - Cleaning up... 18:14:57 INFO - Return code: 0 18:14:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:14:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:57 INFO - Reading from file tmpfile_stdout 18:14:57 INFO - Current package versions: 18:14:57 INFO - blessings == 1.6 18:14:57 INFO - blobuploader == 1.2.4 18:14:57 INFO - docopt == 0.6.1 18:14:57 INFO - functools32 == 3.2.3-2 18:14:57 INFO - jsonschema == 2.5.1 18:14:57 INFO - manifestparser == 1.1 18:14:57 INFO - mozInstall == 1.12 18:14:57 INFO - mozcrash == 1.0 18:14:57 INFO - mozdebug == 0.1 18:14:57 INFO - mozdevice == 0.48 18:14:57 INFO - mozfile == 1.2 18:14:57 INFO - mozhttpd == 0.7 18:14:57 INFO - mozinfo == 0.9 18:14:57 INFO - mozleak == 0.1 18:14:57 INFO - mozlog == 3.4 18:14:57 INFO - moznetwork == 0.27 18:14:57 INFO - mozprocess == 0.23 18:14:57 INFO - mozprofile == 0.28 18:14:57 INFO - mozrunner == 6.13 18:14:57 INFO - mozscreenshot == 0.1 18:14:57 INFO - mozsystemmonitor == 0.3 18:14:57 INFO - moztest == 0.7 18:14:57 INFO - mozversion == 1.4 18:14:57 INFO - psutil == 3.1.1 18:14:57 INFO - requests == 1.2.3 18:14:57 INFO - wsgiref == 0.1.2 18:14:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:57 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 0x10b03c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd26acaef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae50d30>, '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 0x10acb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae48b20>, '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.BQ7FBJKEma/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.GMGcq69RDo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:14:57 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 18:14:57 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 18:14:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQ7FBJKEma/Render', 18:14:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:57 INFO - 'HOME': '/Users/cltbld', 18:14:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:57 INFO - 'LOGNAME': 'cltbld', 18:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:57 INFO - 'MOZ_NO_REMOTE': '1', 18:14:57 INFO - 'NO_EM_RESTART': '1', 18:14:57 INFO - 'PAGER': '/bin/cat', 18:14:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:57 INFO - 'PWD': '/builds/slave/test', 18:14:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:57 INFO - 'SHELL': '/bin/bash', 18:14:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GMGcq69RDo/Listeners', 18:14:57 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:14:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:57 INFO - 'USER': 'cltbld', 18:14:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:57 INFO - 'XPC_FLAGS': '0x0', 18:14:57 INFO - 'XPC_SERVICE_NAME': '0', 18:14:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:58 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)) 18:14:58 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)) 18:14:58 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)) 18:14:58 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)) 18:14:58 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)) 18:14:58 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)) 18:14:58 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)) 18:14:58 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)) 18:14:58 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)) 18:14:58 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)) 18:14:58 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)) 18:14:58 INFO - Cleaning up... 18:14:58 INFO - Return code: 0 18:14:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:58 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 0x10b03c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afb2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd26acaef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae50d30>, '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 0x10acb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae48b20>, '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.BQ7FBJKEma/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.GMGcq69RDo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:14:58 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 18:14:58 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 18:14:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQ7FBJKEma/Render', 18:14:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:58 INFO - 'HOME': '/Users/cltbld', 18:14:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:58 INFO - 'LOGNAME': 'cltbld', 18:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:58 INFO - 'MOZ_NO_REMOTE': '1', 18:14:58 INFO - 'NO_EM_RESTART': '1', 18:14:58 INFO - 'PAGER': '/bin/cat', 18:14:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:58 INFO - 'PWD': '/builds/slave/test', 18:14:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:58 INFO - 'SHELL': '/bin/bash', 18:14:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GMGcq69RDo/Listeners', 18:14:58 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:14:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:58 INFO - 'USER': 'cltbld', 18:14:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:58 INFO - 'XPC_FLAGS': '0x0', 18:14:58 INFO - 'XPC_SERVICE_NAME': '0', 18:14:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:14:58 INFO - Cleaning up... 18:14:58 INFO - Return code: 0 18:14:58 INFO - Running post-action listener: _resource_record_post_action 18:14:58 INFO - Running post-action listener: _start_resource_monitoring 18:14:58 INFO - Starting resource monitoring. 18:14:58 INFO - [mozharness: 2016-11-24 02:14:58.375613Z] Finished create-virtualenv step (success) 18:14:58 INFO - [mozharness: 2016-11-24 02:14:58.376617Z] Running install step. 18:14:58 INFO - Running pre-action listener: _resource_record_pre_action 18:14:58 INFO - Running main action method: install 18:14:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:58 INFO - Reading from file tmpfile_stdout 18:14:58 INFO - Detecting whether we're running mozinstall >=1.0... 18:14:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:14:58 INFO - Reading from file tmpfile_stdout 18:14:58 INFO - Output received: 18:14:58 INFO - Usage: mozinstall [options] installer 18:14:58 INFO - Options: 18:14:58 INFO - -h, --help show this help message and exit 18:14:58 INFO - -d DEST, --destination=DEST 18:14:58 INFO - Directory to install application into. [default: 18:14:58 INFO - "/builds/slave/test"] 18:14:58 INFO - --app=APP Application being installed. [default: firefox] 18:14:58 INFO - mkdir: /builds/slave/test/build/application 18:14:58 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'] 18:14:58 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 18:15:29 INFO - Reading from file tmpfile_stdout 18:15:29 INFO - Output received: 18:15:29 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:15:29 INFO - Running post-action listener: _resource_record_post_action 18:15:29 INFO - [mozharness: 2016-11-24 02:15:29.622833Z] Finished install step (success) 18:15:29 INFO - [mozharness: 2016-11-24 02:15:29.622974Z] Running run-tests step. 18:15:29 INFO - Running pre-action listener: _resource_record_pre_action 18:15:29 INFO - Running main action method: run_tests 18:15:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:15:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:15:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:15:29 INFO - 2016-11-23 18:15:29.637 screenresolution[1899:21084] starting screenresolution argv=screenresolution get 18:15:29 INFO - 2016-11-23 18:15:29.640 screenresolution[1899:21084] Display 0: 1600x1200x32@60 18:15:29 INFO - 2016-11-23 18:15:29.646 screenresolution[1900:21086] starting screenresolution argv=screenresolution list 18:15:29 INFO - Available Modes on Display 0 18:15:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:15:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:15:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:15:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:15:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:15:29 INFO - Intel Iris: 18:15:29 INFO - Chipset Model: Intel Iris 18:15:29 INFO - Type: GPU 18:15:29 INFO - Bus: Built-In 18:15:29 INFO - VRAM (Dynamic, Max): 1536 MB 18:15:29 INFO - Vendor: Intel (0x8086) 18:15:29 INFO - Device ID: 0x0a2e 18:15:29 INFO - Revision ID: 0x0009 18:15:29 INFO - Displays: 18:15:29 INFO - Display: 18:15:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:15:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:15:29 INFO - Display Serial Number: 1600x1200 60 18:15:29 INFO - Main Display: Yes 18:15:29 INFO - Mirror: Off 18:15:29 INFO - Online: Yes 18:15:29 INFO - Rotation: Supported 18:15:29 INFO - Return code: 0 18:15:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:15:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:15:29 INFO - Python 2.7.3 18:15:29 INFO - Return code: 0 18:15:29 INFO - grabbing minidump binary from tooltool 18:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:15:29 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 0x10ae50d30>, '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 0x10acb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae48b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:15:29 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 18:15:29 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 18:15:29 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 18:15:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:15:30 INFO - Return code: 0 18:15:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:15:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:15:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:15:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:15:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:15:30 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/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 18:15:30 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/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0105 --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 18:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQ7FBJKEma/Render', 18:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:15:30 INFO - 'HOME': '/Users/cltbld', 18:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:15:30 INFO - 'LOGNAME': 'cltbld', 18:15:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:15:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:30 INFO - 'MOZ_NO_REMOTE': '1', 18:15:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:15:30 INFO - 'NO_EM_RESTART': '1', 18:15:30 INFO - 'PAGER': '/bin/cat', 18:15:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:30 INFO - 'PWD': '/builds/slave/test', 18:15:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:15:30 INFO - 'SHELL': '/bin/bash', 18:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GMGcq69RDo/Listeners', 18:15:30 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:15:30 INFO - 'USER': 'cltbld', 18:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:30 INFO - 'XPC_FLAGS': '0x0', 18:15:30 INFO - 'XPC_SERVICE_NAME': '0', 18:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:15:30 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/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 18:15:30 INFO - mozversion application_buildid: 20161123150736 18:15:30 INFO - mozversion application_changeset: 116d83d90ce4ca5cb214e3e33c5da6735d59b848 18:15:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:15:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:15:30 INFO - mozversion application_name: Firefox 18:15:30 INFO - mozversion application_remotingname: firefox-dev 18:15:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:15:30 INFO - mozversion application_vendor: Mozilla 18:15:30 INFO - mozversion application_version: 52.0a2 18:15:30 INFO - mozversion platform_buildid: 20161123150736 18:15:30 INFO - mozversion platform_changeset: 116d83d90ce4ca5cb214e3e33c5da6735d59b848 18:15:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:15:30 INFO - mozversion platform_version: 52.0a2 18:15:30 INFO - using testdate: 1479953730 18:15:30 INFO - actual date: 1479953730 18:15:30 INFO - starting webserver on 'localhost:56091' 18:15:30 INFO - SUITE-START | Running 2 tests 18:15:30 INFO - TEST-START | dromaeo_css 18:15:30 INFO - operating with platform_type : mac_ 18:15:30 INFO - Initialising browser for dromaeo_css test... 18:15:30 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpR9RJKS/profile http://localhost:56091/getInfo.html) 18:15:32 ERROR - PROCESS | 1910 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 18:15:32 INFO - PROCESS | 1910 | __metrics Screen width/height:1600/1200 18:15:32 INFO - PROCESS | 1910 | colorDepth:24 18:15:32 INFO - PROCESS | 1910 | Browser inner width/height: 1024/689 18:15:32 INFO - PROCESS | 1910 | __metrics 18:15:33 INFO - PROCESS | 1910 | 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" 18:15:33 INFO - PROCESS | 1910 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:15:33 INFO - TEST-INFO | 1910: exit 0 18:15:33 INFO - Browser initialized. 18:15:33 INFO - Running cycle 1/1 for dromaeo_css test... 18:15:33 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpR9RJKS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:15:46 INFO - PROCESS | 1912 | RSS: Main: 222720000 18:15:46 INFO - PROCESS | 1912 | 18:16:37 INFO - PROCESS | 1912 | 1479953797682 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] 18:18:50 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56091/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56091/tests/dromaeo/cssquery-ext.html) 18:18:52 INFO - PROCESS | 1912 | RSS: Main: 339357696 18:18:52 INFO - PROCESS | 1912 | 18:21:55 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56091/tests/dromaeo/cssquery-ext.html (next: http://localhost:56091/tests/dromaeo/cssquery-jquery.html) 18:21:55 INFO - PROCESS | 1912 | RSS: Main: 347095040 18:21:55 INFO - PROCESS | 1912 | 18:24:58 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56091/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56091/tests/dromaeo/cssquery-mootools.html) 18:24:58 INFO - PROCESS | 1912 | RSS: Main: 325873664 18:24:58 INFO - PROCESS | 1912 | 18:28:01 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56091/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56091/tests/dromaeo/cssquery-prototype.html) 18:28:02 INFO - PROCESS | 1912 | RSS: Main: 315990016 18:28:02 INFO - PROCESS | 1912 | 18:31:05 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56091/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56091/tests/dromaeo/cssquery-yui.html) 18:31:06 INFO - PROCESS | 1912 | RSS: Main: 314585088 18:31:06 INFO - PROCESS | 1912 | 18:34:09 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56091/tests/dromaeo/cssquery-yui.html (next: http://localhost:56091/tests/dromaeo/cssquery-dojo.html) 18:34:09 INFO - PROCESS | 1912 | RSS: Main: 448819200 18:34:09 INFO - PROCESS | 1912 | 18:34:09 INFO - PROCESS | 1912 | __start_tp_report 18:34:09 INFO - PROCESS | 1912 | _x_x_mozilla_page_load 18:34:09 INFO - PROCESS | 1912 | _x_x_mozilla_page_load_details 18:34:09 INFO - PROCESS | 1912 | |i|pagename|runs| 18:34:09 INFO - PROCESS | 1912 | |0;dojo.html;3213.791103578267;3266.559014533038;3288.9177770555716;3304.983475082627;3338.4491558892782;5497.587680923927;5532.806351777688;5542.196381524676;5576.247206627109;5584.078627026546;5547.223388725582;5576.9163462547995;5620.353659329178;5633.746481408336;5651.350094739107;5070.188769796824;5088.669236499634;5113.360829896263;5160;5240.056789777838;5038.319826823387;5055.77249023793;5070.340855688755;5133.30700355451;5215.6609820361555;5076.314697515843;5081.187010078388;5095.057414378333;5119.667221630582;5149.794008239666;4576.695641742121;4866.7079975201605;5088.084144853943;5127.0258650856595;5139.254808052811;4856.101621200054;4900.877478063041;5076.974615126937;5108.514691104377;5113.488651134894;5405;5408.432114627947;5451.318585176853;5485.396606373296;5515.365732940683;6047.546434017422;6101.206843110367;6104.328523862371;6112.938870611281;6147.262328520606;5988.730507127189;6075.2405949256345;6187.412195841388;6191.0713392990965;6223.471004964584;5626.324841019063;5644.604877658525;5679.148127780783;5703.40114288002;5758.021136406821;6232.283287421914;6245.375462453719;6252.624842509464;6276.529260305495;6282.905756413657;5788.652680839163;6062.9996050652035;6077.75688972445;6081.270247570232;6089.8477538061015;6004.609700369462;6010.939890601038;6065.332813390523;6068.969655151696;6079.179310793074;5978.461938425563;5995.850103747354;5996.460318571262;5997.460228579449;5999.310079340845;6031.276246850406;6033.668148251801;6048.455638992512;6079.574429789872;6162.938370616326;4849.927251091236;4857.247126695368;4865.9026819463415;4877.731724755173;4878.512148785093;4335.349697545394;4340.218760623118;4355;4397.824087036547;4399.120175964756;4779.689320194246;5443.646162999393;5452.509274165344;5514.228008078872;5565.805196818092;6142.375998161017;6165.876682466325;6167.105769663463;6179.783707570214;6181.443670069625;5579.7489112990015;5588.441155884379;5608.074667679787;5608.775648974077;5610;5167.870803229949;5190.896182076337;5217.713025783542;5308.256844041837;5309.938012397536;6108.402780883668;6117.602355846945;6119.015143218386;6123.84690382744;6151.753929842756;5988.341282459013;6108.602940808833;6170.629762214102;6181;6188.133661287513;5215.29593504872;6182.659953702409;6230.43926046658;6241.78153764616;6250.593711408744;5832.708364581669;6048.213732214784;6063.211782468427;6064;6075.453209211192;5818.2436283282905;6073.635581865102;6076.088586712029;6123.877522449615;6131.743695906945;5877.94122058774;5879.441453061952;5945.881082378415;5980.970095149496;5993.280806303272;5898;5900.527957763283;5907.586468947133;5930.436608522184;5937.970310148595;5560.916586251128;5617.74720137603;5720.227769251098;5727.7422515986045;5734.483896449339;5950.16697662319;5975.820725378246;5976.820695379146;5977.521798256216;5979.431953964367;5777.39337369587;5795.652260864362;5797.4782269595935;5803.912941305968;5832.9708351457975 18:34:09 INFO - PROCESS | 1912 | |1;ext.html;502.5853670721655;515.3120584020332;530.6259087343424;538.9083855744523;557.1114073053478;25518.85165167567;30909;31719.04842854712;31750.841245793767;31913.88301409451;32297;32936.67063329372;32983.50524742131;32985;33090;9137.45175289483;9478.71563853082;9486.051394860528;9494.003129671388;9497.667581634609;9388.530573471333;9490.905090949105;9494.620215191384;9505.382150160218;9505.952470237638;9468.81062378752;9484;9511.524423778817;9529;9569.186619137381;9395.389299695566;9436.575354109047;9459.621615135386;9485.383450075724;9504.239660827152;9371.76570585734;9446.52767361636;9447.574859131355;9448.244140468745;9455.007224241494;38691.226175476615;39024;39039;39076.02309942274;39174;3263.4452143135723;3366.6969972702327;3372.5447064646205;3436.1924947637285;3581.767185132958;28275.293117672016;28432.431351373063;29290.70709292901;30384;30659;3140.277736120682;3194.185482701896;3206.599175103112;3256.0720194744385;3300.5214243934734;432364;436478;437115;437149;438029;33179;34266.657333426345;59636.40363596395;60104.69947650234;60121.398786012454;34250.82874585611;34714.65285347114;34732.47901281483;34744.652553474145;34792.30415391678;14019.859801402059;14119.717605648035;14130.364133614012;14155.787663185061;14164.079334843202;11793.88206117945;11827.704307392383;11875.881241187477;11908;11920.046396288277;7604.201558836243;7648.1204661463535;7701.422393320411;7709.38324934004;7711.03612048494;3100.891468798581;3125.312431265118;3139.8744050237733;3167.0973772474736;3171.413288541627;847.8770578266117;873.702941000063;892.4779004282437;929.3355250995571;969.7284760267137;27774.88900444;28055.298617534725;28265.57601635977;28638.856805716256;28726.281842953675;792.610220812704;962.7689354554857;1005.4821766790109;1005.6731562242154;1108.4845546820768;14547.709045819236;14943.476978305707;14989.625259368602;15343.616409589626;15352.539423817303;635327;638463;639843;640392;641836;56359.43640563624;56624;56853.431465685644;56937.43062569321;57006.14490782641;1094.9890501094887;1192.3025030357164;1224.4734764051366;1300.8699130086916;1324.4900713225286;28851.855740721163;29078.27304317409;29536.409271814013;29614.40771184521;30244;49050.264246037055;49735.75132124316;49884.501154987986;49896.750516247186;49906;504614;506781;508942;626359;635885;38768.41847372348;38906.6109338903;38980.6101938977;39112;39597.80201098976;42488;42503.574964249965;42914.57085429106;42931.35602966021;43734;36243.27513449664;36456.81771591125;36499.27001459903;36713.08217294589;36874.63125368712;31713.20716982094;32424.513632295064;32445.02664920056;32894;35693;37025;37688.43467347937;38113.80943095267;38172.80913595414;38292.80853595714;31375.68624313819;34022;34036;34067.4889876657;34289;24706.382340441633;24792.25623231234;25079.122230721838;25138.24585262445;25800.612990804777 18:34:09 INFO - PROCESS | 1912 | |2;jquery.html;351.8645321551205;374.4944325161032;377.4093543604261;379.27558363525657;402.57126160638916;14143.29283535823;15126.168060756654;15694.76457853133;19925.501862453446;20302.39092827214;19374.709379359287;19780.802191978077;20268.695969560467;20590.89704551475;20695.793042069574;5785.595008349417;6009;6063.6361818290825;6072.666003369813;6073.119397687333;4978.800847966095;6577.835554111138;6660.800175994728;6688.063671086052;6708.530402871801;6648.900266496006;6649.50128740344;6660.400563949243;7421.888671669929;7712.691492340327;7720.459567830254;7753.379729621645;7906.486078404885;7933.642986065641;7958.960205198995;7638.8472230555635;7747;7753.147153813076;7763.456558040939;7769.10655274645;27926.860365698245;28153;28515.857420712764;28684.42631147386;28841;2765.9308517287027;3108.2850944282713;3193.760467964912;3205.423023855705;3410.36908171989;14613.415463381452;15160.393584256726;16466.176691165514;16478.83521164785;17025.57436064096;1603.470854617985;1941.7184508246366;1990.7113468547125;2034.7253120828648;2087.885166315852;66122;127369.36315318364;133560.33219833937;136287.999999999;140751;17586.648267034845;17591.384301549384;17674.5581360467;20528.486787830127;21787.019584118752;35223.471647925304;36065.81967090148;36086.458703119475;36092.819535902156;36131.63868361335;10188.847167292497;10360.948195258976;10552.683419497427;10591.682249532527;10737.51681174349;7591.696332146763;8246.587670616445;8595.5702214889;8727.643536245305;8760.78098047554;8934.972478164964;9150.63397464094;9842.409455432697;9854.458004809661;9856;2975.910722678323;3629.130997536271;3669.43123815809;3708.4437334400054;3734.477173195755;838.2581415447373;872.2455076358904;890.4301247201796;894.4007514964989;964.2382517810851;16806.495805125865;20143.39569812908;20822.23011044048;20878.060487277806;21352.893235533724;841.9410641255176;845.3448577352601;943.613118621362;969.2488321550854;1016.1261315268863;10620.203484738675;11029;11093.611723589796;11141.777164456666;11141.832872506919;547231;548259;552610;565868;573811;113624;117985.4100729508;118921;119838.40080799714;121802.3909880445;974.4786539201493;1005.1857995024054;1015.5176291261591;1018.2464975917768;1019.2101121630795;16190.271437785563;17479.47561573155;17863.196156172744;18873.961932093316;19315.51711207231;52990.735046324524;53067;53855.73072134614;54576;55582.72208638931;77120;77614;80360.59819700864;80585;81728;7968.0836703779805;8134.593270336578;8161.632726527439;8334.749957501284;8459.238668519863;10849.837252441062;11882.702932426759;12564.748705026032;12663;12983.221006739626;10669.46652667348;10674.092702120406;10976.670699879016;11147.442627868737;11263.042641375574;10836.512356943798;10845.295055821347;11326.093912486816;11441.48513316886;11559.768804623693;10498.265121441425;11286.604968826052;11370.602028928948;11614.535418583162;11742.941285293518;8232.876506852523;8241.79395515117;8302.750917472486;8422.536760477988;8428.325733941147;9293.953530232577;11156.609518667132;11364;11393;11432.94283528577 18:34:09 INFO - PROCESS | 1912 | |3;mootools.html;1350.4530665080642;1480.045370735875;1612.5887898585859;1775.7158854583254;1811.420345489444;7868.685252589897;8270.255676989069;8272.214139656731;8273.50358978461;8345.707900223493;7553.20691327411;7832.177621349748;7846.176151504082;8183.181681831814;8415.957920210405;2257.401788526042;2290.141197051106;2294.7934685878267;2296.2996286132725;2298.678185054094;2038.4292398128548;2318.1654604342425;2325.790678838904;2376.251480783548;2382.725986511556;2124.3839286606867;2205.470687035108;2310.480141968052;2323.825713071518;2346.730126035502;2273.783990520898;2276.829237807163;2306.3542208181734;2309.58427483053;2310.595645761993;2343.3438637181616;2360.433495960974;2362.1968530699473;2364.692589963311;2378.0131245533084;8432.747017589421;8440.324774018063;8535.957320213422;8540.530270835101;8560.743177704679;5070.467600901926;5229.346331708536;5246.9768395162955;5250.921236181461;5254.448282930313;3281.8030918144987;3505.509228707983;3535.469679548088;3635.4546817977252;3637.672609465161;1099.9120070394429;1121.2151493954184;1733.1507561295;1764.5941433470246;1839.4665546991357;10590.78818423635;10756.19328550362;10897.291676041032;11091.168162387854;11143.777124457465;4581.450225972904;4606.470255920612;4798.184308667535;4803.5916947059895;4820.68665536739;9589.90410095904;9593.28050396223;9597;9597.47213903242;9616;5322.201669749568;5325.440828712929;5326.9467305327225;5335.813246536352;5337.866553336197;4359.869203923888;4518.254488009507;4546.158960592301;4815.3499277597775;4871.48849370811;3794.1463170786906;3798.924021519554;3912.765234085964;3920.5295364556437;3934.409838524187;1069.6363236499628;1240.9565665201674;1246.9127161098818;1263.5514392390753;1265.1523479268913;758.616898466282;847.8728190771434;885.5926273913943;891.8350105230553;918.4626993209048;5455.427180146107;5471.7264136793;5622.606417550814;5734.455226753452;5844.094165404369;922.6678395777515;937.2174234514121;959.6353385713523;1001.6944831826363;1038.9506598335736;3647.434647629622;3662.956057523593;3848.2688289224957;3869.188848983749;3884.708646851441;9992.050755178247;10206.897931020743;10245.590176392861;10270.435126067989;10337.948310258551;9739;10024.448655324028;10170.847437288447;10206.183505319557;10208.540615672164;6818.522703410714;7728.536287822749;7951.403644726553;7965.442419030613;8002.759917202493;5523.834284971457;5543.9168412474655;5577.386487486455;5702.1468022891895;5945.286565612154;9542.856857147288;9587.280953928623;9588.232941364535;9591.376560523544;9612.086851749073;9231.030741772018;9625.56684949165;9633.566489507848;9650.806983860424;9661.758456038655;6677.83305417349;6894.3795058442975;6907.79276621682;7092.822679433056;7250.02124713157;7670.463067585216;7815.804604884924;7950.4832185907735;7974.561399123104;7988.84022319562;6969.5469794465225;7093.645317733989;7113.359797618239;7165.283471652793;7240.167380751281;6821.624810635462;6915.066466027022;6964.700751775321;7011.018457416068;7086.929130708627;6613.900791488035;6639.170103737033;6747.224069232101;6751.93248067513;6755.290694477009;4086.9591304087767;4707.576318131385;4710.351377018016;4790.976045119752;4929.309896614405;4785.832495862628;4810.63920205979;4819.228923372244;4931.112399768076;4943.233798761198 18:34:09 INFO - PROCESS | 1912 | |4;prototype.html;303.7084398976982;308.2770902234261;311.46117217214226;314.44029627263467;316.99366012679735;4545.863624091278;4757.524247575245;5203.895922081556;5299.60252981026;5356.892862142755;5183.870403239921;5197.896042079156;5239.607029472786;5249.816256431026;5254.842354729355;3818.579956204815;3932.5870783567743;3946.2304850554156;3949.526056873179;3957.366821308591;3119.8440077996124;3910.7262491625597;3911.6309291747943;3929.8231579578987;4004.8998775030714;3958.841646334143;3974.105826189099;3983.6215559521797;3993.7404068735586;4020.758754474726;3065.800722953001;3378.3918894598964;3945.230680017412;3986.302397080514;3988.1883272293253;3123.406552754969;3312.2381852173894;3464.428369319059;3525.065857547752;3528.4651176738203;4311.892202694927;4383.408239887606;4965.900681986376;5131.384233891957;5138.430784607696;4567.497575266718;4595.172868883599;4618;4621.8151273949;4629.953700462986;4379.58393952574;4841.346418233528;5261.789788348663;5447.346318441772;5496.367917689477;3897.7661340319387;3899.2396482685886;3907.0037140529266;3909.6090390960962;3917.275304068756;4597.103564804865;5119.974400127976;5377.1553339362545;5743.684097374643;5748.878968601124;3209.5185722141864;3213.53403756454;3296.967030329714;3648.4709717090873;3797.411401232813;3681.9690486663776;3704.5369328833895;3796.924061518754;4010.358342665218;4022.316206244887;4115.403266526337;4475.70907890986;4508.932366014513;4559.977200113978;4572.199865023674;4302.978485107555;4536.841210557614;5562.330081560874;5568.939492387762;5573.777048918079;1141.9665202991307;1212.2665787198794;1230.0712961713848;1249.3690686203408;1261.3602316987879;786.3001928283832;829.274384913201;858.3090612057229;863.3826813828017;869.8869147010848;4560.566746159109;4661.836835710734;4751.59611433032;4894.437139728977;4935.703857768545;4626;5223.395856858755;5342.572594192456;5364.892702145935;5408.322340808209;4053.574374690674;4526.600901522199;4854.538818812207;4910.48439913811;4929.7781599828095;2458.754124587527;2706.807485936398;2779.8741509688607;2820.4923113839295;2829.8160619559662;4776.9761151194025;4796.8081276748535;4813.975930120327;5170.750723435318;5516.938099354817;4554.225781617133;4732.432108147044;5115.335006449138;5468.37113731918;5496.532794712494;4693.413323334583;4885.461079759864;5239.607029472881;5389.51494365509;5521.420250873681;4741.708753578017;5051.646384753032;5217.843464696065;5373.307408166415;5387.649802762808;5097.770600323069;5107.387113546398;5291.285676433632;5302.628815982845;5368.979893820162;5423.457654234703;5482.91775623358;5484.917726234189;5666.34836993751;5678.93955821573;2908.505554055858;3651.707863370977;3696.0020794385905;3754.511913451234;3765.004547589782;4954.603631709527;5287.867803304948;5587.860303492445;5716.685582293014;5718.228039214653;4760.285957106462;4955.432088895989;5261.710605916712;5367.651102678469;5543.944560554504;4606.591122569208;4823.782929768239;5495.697736624524;5505.504504594605;5515.558755299647;4630.865437967719;5001.549860512571;5397.757100930389;5397.7641687563;5451.64564303319;4761;5113.411957624772;5162.941596972687;5357.357117145967;5611.859703507445;5464.388005538296;5495.642783219078;5505;5519.889602207853;5524.861878453071 18:34:09 INFO - PROCESS | 1912 | |5;yui.html;690.9170899492061;705.3722187253343;738.833762403459;771.0131032278686;787.4599963396957;13928.094673846206;14467.927660361709;14505.129692218474;14515.129092254472;14559.27203639819;12855.100142989995;12962.287074210915;12991.090623656348;12997.74004519909;13036;636.360457739973;658.618001559097;660.168188083014;790.3440144679917;799.7041094794934;775.5114278004849;804;811.4401063266357;812.3460614205562;813.5240884082818;855.0508935082084;857.237059017475;858.8711693246;859.2395729779159;860.1226748716306;687.089606271687;855.1363123245504;855.2986551028159;856.6359297298685;857.5840717252128;520.3209811196388;521.3587287636228;523.2517499975035;524.396943514957;615.9507239420881;13809.930950345184;14179;14192;14193.716125677429;14196.787048194285;13005.349732513336;13098.148620339647;13192.934035329858;13312.068155229137;13446.126001809851;11634.418279086012;11769.41152942358;11940;11993.820092698617;12078.456469458895;4364.629006534448;4398.868033958986;4442.37806707058;4454.086912182995;4473.4855491618555;86466;89193;93449.5327523358;94253.11493770086;98063.99999999929;9846.212303015742;9988.400695958266;10042.347247428894;10473.738156546147;10711.625093121704;18843.528911777314;19006.33477828297;19186.040697965047;19300.517487062934;19316.227350905807;7125.3230943060325;7128.536645118051;7151.427885769126;7284.526505777108;7285.854282914312;3948.407738839197;3979.880603581897;3997.1006523532556;4003.3394489908837;4014.879553613396;3268.084936217863;3344.1138098403944;3401.0477066420945;3403.863845446204;3412.897613071612;503.66002948009947;553.3138907754355;571.7798647520651;574.7241324164424;580.4195804195804;565.2406735288878;616.9383061693884;642.3415998601471;653.4739534674633;671.7951024937344;8750.212480876624;8910.108989101167;9047.68333108338;9128.634854605742;9441.858372124423;635.4979566142788;660.1283472434235;671.4762485261406;678.3691167214538;688.579966220605;4297.205017071852;4316.2446571849805;4330.242207613718;4363.759993200341;4463.821447142078;78824.81762773563;105755;109185.36221956677;111783.44108279406;113249;77777.44445111066;82337.76493352605;96864;99970.00029999607;101604;86324;88106.67839982406;88983.11016890008;89249.55375223212;90842.09157908469;6742.831429214113;8140.982377202849;8172.346212302882;8353.58232088381;8745.60644770974;54929.72535137299;55600.721996391374;56139.8772024549;56381.43618563762;57596.71201644133;68393;68561.62876742537;76949.61525192339;80379;81594;11813.586524471602;11969.581064662694;12020.879791202326;12170.878291216974;12540.435680394587;12721.681957951125;13345.399457024263;13738.725225495635;13771.104878182887;13896.791548126988;12169;12186.87813121893;12527;12748.49006039748;12972.481100755864;12050.156489045683;13165.210087394787;13484;13724.107932984323;13910.58268251954;11195.763495116724;12180.0864935132;12710.809337860124;13267.2703001332;13336.866631333562;10007.449590272314;10287.897121028993;10885.292455990335;11116;11440;8932.642694292155;9350.438973661601;11083.72290692739;11101.500432480396;11209.439528023404 18:34:09 INFO - PROCESS | 1912 | __end_tp_report 18:34:09 INFO - PROCESS | 1912 | __start_cc_report 18:34:09 INFO - PROCESS | 1912 | _x_x_mozilla_cycle_collect,1394 18:34:09 INFO - PROCESS | 1912 | __end_cc_report 18:34:09 INFO - PROCESS | 1912 | __startTimestamp1479954849572__endTimestamp 18:34:09 INFO - PROCESS | 1912 | 18:34:09 INFO - PROCESS | 1912 | ------- Summary: start ------- 18:34:09 INFO - PROCESS | 1912 | Number of tests: 6 18:34:09 INFO - PROCESS | 1912 | 18:34:09 INFO - PROCESS | 1912 | [#0] dojo.html Cycles:165 Average:5597.82 Median:5788.65 stddev:630.93 (10.9%) stddev-sans-first:604.51 18:34:09 INFO - PROCESS | 1912 | Values: 3213.8 3266.6 3288.9 3305.0 3338.4 5497.6 5532.8 5542.2 5576.2 5584.1 5547.2 5576.9 5620.4 5633.7 5651.4 5070.2 5088.7 5113.4 5160.0 5240.1 5038.3 5055.8 5070.3 5133.3 5215.7 5076.3 5081.2 5095.1 5119.7 5149.8 4576.7 4866.7 5088.1 5127.0 5139.3 4856.1 4900.9 5077.0 5108.5 5113.5 5405.0 5408.4 5451.3 5485.4 5515.4 6047.5 6101.2 6104.3 6112.9 6147.3 5988.7 6075.2 6187.4 6191.1 6223.5 5626.3 5644.6 5679.1 5703.4 5758.0 6232.3 6245.4 6252.6 6276.5 6282.9 5788.7 6063.0 6077.8 6081.3 6089.8 6004.6 6010.9 6065.3 6069.0 6079.2 5978.5 5995.9 5996.5 5997.5 5999.3 6031.3 6033.7 6048.5 6079.6 6162.9 4849.9 4857.2 4865.9 4877.7 4878.5 4335.3 4340.2 4355.0 4397.8 4399.1 4779.7 5443.6 5452.5 5514.2 5565.8 6142.4 6165.9 6167.1 6179.8 6181.4 5579.7 5588.4 5608.1 5608.8 5610.0 5167.9 5190.9 5217.7 5308.3 5309.9 6108.4 6117.6 6119.0 6123.8 6151.8 5988.3 6108.6 6170.6 6181.0 6188.1 5215.3 6182.7 6230.4 6241.8 6250.6 5832.7 6048.2 6063.2 6064.0 6075.5 5818.2 6073.6 6076.1 6123.9 6131.7 5877.9 5879.4 5945.9 5981.0 5993.3 5898.0 5900.5 5907.6 5930.4 5938.0 5560.9 5617.7 5720.2 5727.7 5734.5 5950.2 5975.8 5976.8 5977.5 5979.4 5777.4 5795.7 5797.5 5803.9 5833.0 18:34:09 INFO - PROCESS | 1912 | 18:34:09 INFO - PROCESS | 1912 | [#1] ext.html Cycles:180 Average:65815.30 Median:37356.72 stddev:147795.76 (395.6%) stddev-sans-first:148129.02 18:34:09 INFO - PROCESS | 1912 | Values: 502.6 515.3 530.6 538.9 557.1 25518.9 30909.0 31719.0 31750.8 31913.9 32297.0 32936.7 32983.5 32985.0 33090.0 9137.5 9478.7 9486.1 9494.0 9497.7 9388.5 9490.9 9494.6 9505.4 9506.0 9468.8 9484.0 9511.5 9529.0 9569.2 9395.4 9436.6 9459.6 9485.4 9504.2 9371.8 9446.5 9447.6 9448.2 9455.0 38691.2 39024.0 39039.0 39076.0 39174.0 3263.4 3366.7 3372.5 3436.2 3581.8 28275.3 28432.4 29290.7 30384.0 30659.0 3140.3 3194.2 3206.6 3256.1 3300.5 432364.0 436478.0 437115.0 437149.0 438029.0 33179.0 34266.7 59636.4 60104.7 60121.4 34250.8 34714.7 34732.5 34744.7 34792.3 14019.9 14119.7 14130.4 14155.8 14164.1 11793.9 11827.7 11875.9 11908.0 11920.0 7604.2 7648.1 7701.4 7709.4 7711.0 3100.9 3125.3 3139.9 3167.1 3171.4 847.9 873.7 892.5 929.3 969.7 27774.9 28055.3 28265.6 28638.9 28726.3 792.6 962.8 1005.5 1005.7 1108.5 14547.7 14943.5 14989.6 15343.6 15352.5 635327.0 638463.0 639843.0 640392.0 641836.0 56359.4 56624.0 56853.4 56937.4 57006.1 1095.0 1192.3 1224.5 1300.9 1324.5 28851.9 29078.3 29536.4 29614.4 30244.0 49050.3 49735.8 49884.5 49896.8 49906.0 504614.0 506781.0 508942.0 626359.0 635885.0 38768.4 38906.6 38980.6 39112.0 39597.8 42488.0 42503.6 42914.6 42931.4 43734.0 36243.3 36456.8 36499.3 36713.1 36874.6 31713.2 32424.5 32445.0 32894.0 35693.0 37025.0 37688.4 38113.8 38172.8 38292.8 31375.7 34022.0 34036.0 34067.5 34289.0 24706.4 24792.3 25079.1 25138.2 25800.6 18:34:09 INFO - PROCESS | 1912 | 18:34:09 INFO - PROCESS | 1912 | [#2] jquery.html Cycles:180 Average:35111.31 Median:3151.02 stddev:93179.54 (2957.1%) stddev-sans-first:93404.38 18:34:09 INFO - PROCESS | 1912 | Values: 351.9 374.5 377.4 379.3 402.6 14143.3 15126.2 15694.8 19925.5 20302.4 19374.7 19780.8 20268.7 20590.9 20695.8 5785.6 6009.0 6063.6 6072.7 6073.1 4978.8 6577.8 6660.8 6688.1 6708.5 6648.9 6649.5 6660.4 7421.9 7712.7 7720.5 7753.4 7906.5 7933.6 7959.0 7638.8 7747.0 7753.1 7763.5 7769.1 27926.9 28153.0 28515.9 28684.4 28841.0 2765.9 3108.3 3193.8 3205.4 3410.4 14613.4 15160.4 16466.2 16478.8 17025.6 1603.5 1941.7 1990.7 2034.7 2087.9 66122.0 127369.4 133560.3 136288.0 140751.0 17586.6 17591.4 17674.6 20528.5 21787.0 35223.5 36065.8 36086.5 36092.8 36131.6 10188.8 10360.9 10552.7 10591.7 10737.5 7591.7 8246.6 8595.6 8727.6 8760.8 8935.0 9150.6 9842.4 9854.5 9856.0 2975.9 3629.1 3669.4 3708.4 3734.5 838.3 872.2 890.4 894.4 964.2 16806.5 20143.4 20822.2 20878.1 21352.9 841.9 845.3 943.6 969.2 1016.1 10620.2 11029.0 11093.6 11141.8 11141.8 547231.0 548259.0 552610.0 565868.0 573811.0 113624.0 117985.4 118921.0 119838.4 121802.4 974.5 1005.2 1015.5 1018.2 1019.2 16190.3 17479.5 17863.2 18874.0 19315.5 52990.7 53067.0 53855.7 54576.0 55582.7 77120.0 77614.0 80360.6 80585.0 81728.0 7968.1 8134.6 8161.6 8334.7 8459.2 10849.8 11882.7 12564.7 12663.0 12983.2 10669.5 10674.1 10976.7 11147.4 11263.0 10836.5 10845.3 11326.1 11441.5 11559.8 10498.3 11286.6 11370.6 11614.5 11742.9 8232.9 8241.8 8302.8 8422.5 8428.3 9294.0 11156.6 11364.0 11393.0 11432.9 18:34:09 INFO - PROCESS | 1912 | 18:34:09 INFO - PROCESS | 1912 | [#3] mootools.html Cycles:180 Average:5483.83 Median:5149.91 stddev:2985.77 (58.0%) stddev-sans-first:2977.98 18:34:09 INFO - PROCESS | 1912 | Values: 1350.5 1480.0 1612.6 1775.7 1811.4 7868.7 8270.3 8272.2 8273.5 8345.7 7553.2 7832.2 7846.2 8183.2 8416.0 2257.4 2290.1 2294.8 2296.3 2298.7 2038.4 2318.2 2325.8 2376.3 2382.7 2124.4 2205.5 2310.5 2323.8 2346.7 2273.8 2276.8 2306.4 2309.6 2310.6 2343.3 2360.4 2362.2 2364.7 2378.0 8432.7 8440.3 8536.0 8540.5 8560.7 5070.5 5229.3 5247.0 5250.9 5254.4 3281.8 3505.5 3535.5 3635.5 3637.7 1099.9 1121.2 1733.2 1764.6 1839.5 10590.8 10756.2 10897.3 11091.2 11143.8 4581.5 4606.5 4798.2 4803.6 4820.7 9589.9 9593.3 9597.0 9597.5 9616.0 5322.2 5325.4 5326.9 5335.8 5337.9 4359.9 4518.3 4546.2 4815.3 4871.5 3794.1 3798.9 3912.8 3920.5 3934.4 1069.6 1241.0 1246.9 1263.6 1265.2 758.6 847.9 885.6 891.8 918.5 5455.4 5471.7 5622.6 5734.5 5844.1 922.7 937.2 959.6 1001.7 1039.0 3647.4 3663.0 3848.3 3869.2 3884.7 9992.1 10206.9 10245.6 10270.4 10337.9 9739.0 10024.4 10170.8 10206.2 10208.5 6818.5 7728.5 7951.4 7965.4 8002.8 5523.8 5543.9 5577.4 5702.1 5945.3 9542.9 9587.3 9588.2 9591.4 9612.1 9231.0 9625.6 9633.6 9650.8 9661.8 6677.8 6894.4 6907.8 7092.8 7250.0 7670.5 7815.8 7950.5 7974.6 7988.8 6969.5 7093.6 7113.4 7165.3 7240.2 6821.6 6915.1 6964.7 7011.0 7086.9 6613.9 6639.2 6747.2 6751.9 6755.3 4087.0 4707.6 4710.4 4791.0 4929.3 4785.8 4810.6 4819.2 4931.1 4943.2 18:34:09 INFO - PROCESS | 1912 | 18:34:09 INFO - PROCESS | 1912 | [#4] prototype.html Cycles:180 Average:4323.92 Median:4760.64 stddev:1314.95 (27.6%) stddev-sans-first:1283.55 18:34:09 INFO - PROCESS | 1912 | Values: 303.7 308.3 311.5 314.4 317.0 4545.9 4757.5 5203.9 5299.6 5356.9 5183.9 5197.9 5239.6 5249.8 5254.8 3818.6 3932.6 3946.2 3949.5 3957.4 3119.8 3910.7 3911.6 3929.8 4004.9 3958.8 3974.1 3983.6 3993.7 4020.8 3065.8 3378.4 3945.2 3986.3 3988.2 3123.4 3312.2 3464.4 3525.1 3528.5 4311.9 4383.4 4965.9 5131.4 5138.4 4567.5 4595.2 4618.0 4621.8 4630.0 4379.6 4841.3 5261.8 5447.3 5496.4 3897.8 3899.2 3907.0 3909.6 3917.3 4597.1 5120.0 5377.2 5743.7 5748.9 3209.5 3213.5 3297.0 3648.5 3797.4 3682.0 3704.5 3796.9 4010.4 4022.3 4115.4 4475.7 4508.9 4560.0 4572.2 4303.0 4536.8 5562.3 5568.9 5573.8 1142.0 1212.3 1230.1 1249.4 1261.4 786.3 829.3 858.3 863.4 869.9 4560.6 4661.8 4751.6 4894.4 4935.7 4626.0 5223.4 5342.6 5364.9 5408.3 4053.6 4526.6 4854.5 4910.5 4929.8 2458.8 2706.8 2779.9 2820.5 2829.8 4777.0 4796.8 4814.0 5170.8 5516.9 4554.2 4732.4 5115.3 5468.4 5496.5 4693.4 4885.5 5239.6 5389.5 5521.4 4741.7 5051.6 5217.8 5373.3 5387.6 5097.8 5107.4 5291.3 5302.6 5369.0 5423.5 5482.9 5484.9 5666.3 5678.9 2908.5 3651.7 3696.0 3754.5 3765.0 4954.6 5287.9 5587.9 5716.7 5718.2 4760.3 4955.4 5261.7 5367.7 5543.9 4606.6 4823.8 5495.7 5505.5 5515.6 4630.9 5001.5 5397.8 5397.8 5451.6 4761.0 5113.4 5162.9 5357.4 5611.9 5464.4 5495.6 5505.0 5519.9 5524.9 18:34:09 INFO - PROCESS | 1912 | 18:34:09 INFO - PROCESS | 1912 | [#5] yui.html Cycles:180 Average:20428.52 Median:2488.57 stddev:30047.26 (1207.4%) stddev-sans-first:30095.01 18:34:09 INFO - PROCESS | 1912 | Values: 690.9 705.4 738.8 771.0 787.5 13928.1 14467.9 14505.1 14515.1 14559.3 12855.1 12962.3 12991.1 12997.7 13036.0 636.4 658.6 660.2 790.3 799.7 775.5 804.0 811.4 812.3 813.5 855.1 857.2 858.9 859.2 860.1 687.1 855.1 855.3 856.6 857.6 520.3 521.4 523.3 524.4 616.0 13809.9 14179.0 14192.0 14193.7 14196.8 13005.3 13098.1 13192.9 13312.1 13446.1 11634.4 11769.4 11940.0 11993.8 12078.5 4364.6 4398.9 4442.4 4454.1 4473.5 86466.0 89193.0 93449.5 94253.1 98064.0 9846.2 9988.4 10042.3 10473.7 10711.6 18843.5 19006.3 19186.0 19300.5 19316.2 7125.3 7128.5 7151.4 7284.5 7285.9 3948.4 3979.9 3997.1 4003.3 4014.9 3268.1 3344.1 3401.0 3403.9 3412.9 503.7 553.3 571.8 574.7 580.4 565.2 616.9 642.3 653.5 671.8 8750.2 8910.1 9047.7 9128.6 9441.9 635.5 660.1 671.5 678.4 688.6 4297.2 4316.2 4330.2 4363.8 4463.8 78824.8 105755.0 109185.4 111783.4 113249.0 77777.4 82337.8 96864.0 99970.0 101604.0 86324.0 88106.7 88983.1 89249.6 90842.1 6742.8 8141.0 8172.3 8353.6 8745.6 54929.7 55600.7 56139.9 56381.4 57596.7 68393.0 68561.6 76949.6 80379.0 81594.0 11813.6 11969.6 12020.9 12170.9 12540.4 12721.7 13345.4 13738.7 13771.1 13896.8 12169.0 12186.9 12527.0 12748.5 12972.5 12050.2 13165.2 13484.0 13724.1 13910.6 11195.8 12180.1 12710.8 13267.3 13336.9 10007.4 10287.9 10885.3 11116.0 11440.0 8932.6 9350.4 11083.7 11101.5 11209.4 18:34:09 INFO - PROCESS | 1912 | -------- Summary: end -------- 18:34:09 INFO - PROCESS | 1912 | 18:34:10 INFO - TEST-INFO | 1912: exit 0 18:34:10 INFO - TEST-OK | dromaeo_css | took 1120048ms 18:34:10 INFO - TEST-START | kraken 18:34:10 INFO - operating with platform_type : mac_ 18:34:10 INFO - Initialising browser for kraken test... 18:34:10 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpVfxJAA/profile http://localhost:56091/getInfo.html) 18:34:12 ERROR - PROCESS | 1915 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 18:34:12 INFO - PROCESS | 1915 | __metrics Screen width/height:1600/1200 18:34:12 INFO - PROCESS | 1915 | colorDepth:24 18:34:12 INFO - PROCESS | 1915 | Browser inner width/height: 1024/689 18:34:12 INFO - PROCESS | 1915 | __metrics 18:34:12 INFO - PROCESS | 1915 | 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" 18:34:12 INFO - PROCESS | 1915 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:34:13 INFO - TEST-INFO | 1915: exit 0 18:34:13 INFO - Browser initialized. 18:34:13 INFO - Running cycle 1/1 for kraken test... 18:34:13 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpVfxJAA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:34:25 INFO - PROCESS | 1916 | RSS: Main: 219189248 18:34:25 INFO - PROCESS | 1916 | 18:34:28 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56091/tests/kraken/driver.html?testName=audio-beat-detection) 18:34:29 INFO - PROCESS | 1916 | RSS: Main: 269029376 18:34:29 INFO - PROCESS | 1916 | 18:34:32 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56091/tests/kraken/driver.html?testName=audio-dft) 18:34:32 INFO - PROCESS | 1916 | RSS: Main: 283086848 18:34:32 INFO - PROCESS | 1916 | 18:34:36 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56091/tests/kraken/driver.html?testName=audio-fft) 18:34:36 INFO - PROCESS | 1916 | RSS: Main: 380973056 18:34:36 INFO - PROCESS | 1916 | 18:34:38 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56091/tests/kraken/driver.html?testName=audio-oscillator) 18:34:39 INFO - PROCESS | 1916 | RSS: Main: 283262976 18:34:39 INFO - PROCESS | 1916 | 18:34:41 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56091/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:34:42 INFO - PROCESS | 1916 | RSS: Main: 284565504 18:34:42 INFO - PROCESS | 1916 | 18:34:44 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56091/tests/kraken/driver.html?testName=imaging-darkroom) 18:34:45 INFO - PROCESS | 1916 | RSS: Main: 325865472 18:34:45 INFO - PROCESS | 1916 | 18:34:48 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56091/tests/kraken/driver.html?testName=imaging-desaturate) 18:34:48 INFO - PROCESS | 1916 | RSS: Main: 326098944 18:34:48 INFO - PROCESS | 1916 | 18:34:51 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56091/tests/kraken/driver.html?testName=json-parse-financial) 18:34:52 INFO - PROCESS | 1916 | RSS: Main: 322916352 18:34:52 INFO - PROCESS | 1916 | 18:34:53 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56091/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:34:54 INFO - PROCESS | 1916 | RSS: Main: 300646400 18:34:54 INFO - PROCESS | 1916 | 18:34:56 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56091/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:34:57 INFO - PROCESS | 1916 | RSS: Main: 303357952 18:34:57 INFO - PROCESS | 1916 | 18:34:59 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56091/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:34:59 INFO - PROCESS | 1916 | RSS: Main: 305827840 18:34:59 INFO - PROCESS | 1916 | 18:35:02 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56091/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:35:03 INFO - PROCESS | 1916 | RSS: Main: 315424768 18:35:03 INFO - PROCESS | 1916 | 18:35:06 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56091/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:35:07 INFO - PROCESS | 1916 | RSS: Main: 315777024 18:35:07 INFO - PROCESS | 1916 | 18:35:08 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56091/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56091/tests/kraken/driver.html?testName=ai-astar) 18:35:09 INFO - PROCESS | 1916 | RSS: Main: 359673856 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | __start_tp_report 18:35:09 INFO - PROCESS | 1916 | _x_x_mozilla_page_load 18:35:09 INFO - PROCESS | 1916 | _x_x_mozilla_page_load_details 18:35:09 INFO - PROCESS | 1916 | |i|pagename|runs| 18:35:09 INFO - PROCESS | 1916 | |0;ai-astar;97;114;114;116;113;112;158;164;112;113 18:35:09 INFO - PROCESS | 1916 | |1;audio-beat-detection;165;138;141;149;118;111;113;141;173;115 18:35:09 INFO - PROCESS | 1916 | |2;audio-dft;185;185;183;184;181;182;183;187;182;185 18:35:09 INFO - PROCESS | 1916 | |3;audio-fft;82;59;59;89;61;59;58;61;82;57 18:35:09 INFO - PROCESS | 1916 | |4;audio-oscillator;90;69;92;91;54;68;112;97;92;126 18:35:09 INFO - PROCESS | 1916 | |5;imaging-gaussian-blur;88;84;87;84;86;84;88;84;88;84 18:35:09 INFO - PROCESS | 1916 | |6;imaging-darkroom;94;94;96;93;94;93;105;149;94;93 18:35:09 INFO - PROCESS | 1916 | |7;imaging-desaturate;82;82;80;81;79;80;83;84;83;81 18:35:09 INFO - PROCESS | 1916 | |8;json-parse-financial;52;48;47;48;52;56;55;56;56;55 18:35:09 INFO - PROCESS | 1916 | |9;json-stringify-tinderbox;67;80;97;111;92;69;71;71;71;71 18:35:09 INFO - PROCESS | 1916 | |10;stanford-crypto-aes;84;74;83;90;101;101;101;85;85;84 18:35:09 INFO - PROCESS | 1916 | |11;stanford-crypto-ccm;143;146;166;150;151;155;150;157;220;169 18:35:09 INFO - PROCESS | 1916 | |12;stanford-crypto-pbkdf2;169;167;166;167;177;202;168;165;165;166 18:35:09 INFO - PROCESS | 1916 | |13;stanford-crypto-sha256-iterative;67;68;66;66;65;67;65;74;73;80 18:35:09 INFO - PROCESS | 1916 | __end_tp_report 18:35:09 INFO - PROCESS | 1916 | __start_cc_report 18:35:09 INFO - PROCESS | 1916 | _x_x_mozilla_cycle_collect,1951 18:35:09 INFO - PROCESS | 1916 | __end_cc_report 18:35:09 INFO - PROCESS | 1916 | __startTimestamp1479954909247__endTimestamp 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | ------- Summary: start ------- 18:35:09 INFO - PROCESS | 1916 | Number of tests: 14 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#0] ai-astar Cycles:10 Average:121.30 Median:115.00 stddev:21.62 (18.8%) stddev-sans-first:21.07 18:35:09 INFO - PROCESS | 1916 | Values: 97.0 114.0 114.0 116.0 113.0 112.0 158.0 164.0 112.0 113.0 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#1] audio-beat-detection Cycles:10 Average:136.40 Median:141.00 stddev:21.99 (15.6%) stddev-sans-first:20.74 18:35:09 INFO - PROCESS | 1916 | Values: 165.0 138.0 141.0 149.0 118.0 111.0 113.0 141.0 173.0 115.0 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.50 stddev:1.83 (1.0%) stddev-sans-first:1.88 18:35:09 INFO - PROCESS | 1916 | Values: 185.0 185.0 183.0 184.0 181.0 182.0 183.0 187.0 182.0 185.0 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#3] audio-fft Cycles:10 Average:66.70 Median:61.00 stddev:12.37 (20.3%) stddev-sans-first:11.82 18:35:09 INFO - PROCESS | 1916 | Values: 82.0 59.0 59.0 89.0 61.0 59.0 58.0 61.0 82.0 57.0 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#4] audio-oscillator Cycles:10 Average:89.10 Median:90.50 stddev:21.22 (23.4%) stddev-sans-first:22.50 18:35:09 INFO - PROCESS | 1916 | Values: 90.0 69.0 92.0 91.0 54.0 68.0 112.0 97.0 92.0 126.0 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.50 stddev:1.89 (2.2%) stddev-sans-first:1.81 18:35:09 INFO - PROCESS | 1916 | Values: 88.0 84.0 87.0 84.0 86.0 84.0 88.0 84.0 88.0 84.0 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:94.00 stddev:17.42 (18.5%) stddev-sans-first:18.32 18:35:09 INFO - PROCESS | 1916 | Values: 94.0 94.0 96.0 93.0 94.0 93.0 105.0 149.0 94.0 93.0 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#7] imaging-desaturate Cycles:10 Average:81.50 Median:82.00 stddev:1.58 (1.9%) stddev-sans-first:1.67 18:35:09 INFO - PROCESS | 1916 | Values: 82.0 82.0 80.0 81.0 79.0 80.0 83.0 84.0 83.0 81.0 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:55.00 stddev:3.66 (6.7%) stddev-sans-first:3.88 18:35:09 INFO - PROCESS | 1916 | Values: 52.0 48.0 47.0 48.0 52.0 56.0 55.0 56.0 56.0 55.0 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#9] json-stringify-tinderbox Cycles:10 Average:80.00 Median:71.00 stddev:14.94 (21.0%) stddev-sans-first:15.08 18:35:09 INFO - PROCESS | 1916 | Values: 67.0 80.0 97.0 111.0 92.0 69.0 71.0 71.0 71.0 71.0 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:84.00 stddev:9.28 (11.1%) stddev-sans-first:9.68 18:35:09 INFO - PROCESS | 1916 | Values: 84.0 74.0 83.0 90.0 101.0 101.0 101.0 85.0 85.0 84.0 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#11] stanford-crypto-ccm Cycles:10 Average:160.70 Median:156.00 stddev:22.39 (14.4%) stddev-sans-first:22.81 18:35:09 INFO - PROCESS | 1916 | Values: 143.0 146.0 166.0 150.0 151.0 155.0 150.0 157.0 220.0 169.0 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:167.50 stddev:11.37 (6.8%) stddev-sans-first:12.03 18:35:09 INFO - PROCESS | 1916 | Values: 169.0 167.0 166.0 167.0 177.0 202.0 168.0 165.0 165.0 166.0 18:35:09 INFO - PROCESS | 1916 | 18:35:09 INFO - PROCESS | 1916 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:67.50 stddev:4.95 (7.3%) stddev-sans-first:5.20 18:35:09 INFO - PROCESS | 1916 | Values: 67.0 68.0 66.0 66.0 65.0 67.0 65.0 74.0 73.0 80.0 18:35:09 INFO - PROCESS | 1916 | -------- Summary: end -------- 18:35:09 INFO - PROCESS | 1916 | 18:35:10 INFO - TEST-INFO | 1916: exit 0 18:35:10 INFO - TEST-OK | kraken | took 60128ms 18:35:10 INFO - SUITE-END | took 1180s 18:35:10 INFO - Completed test suite (00:19:40) 18:35:10 INFO - javascript benchmark 18:35:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3213.791103578267, 3266.559014533038, 3288.9177770555716, 3304.983475082627, 3338.4491558892782, 5497.587680923927, 5532.806351777688, 5542.196381524676, 5576.247206627109, 5584.078627026546, 5547.223388725582, 5576.9163462547995, 5620.353659329178, 5633.746481408336, 5651.350094739107, 5070.188769796824, 5088.669236499634, 5113.360829896263, 5160.0, 5240.056789777838, 5038.319826823387, 5055.77249023793, 5070.340855688755, 5133.30700355451, 5215.6609820361555, 5076.314697515843, 5081.187010078388, 5095.057414378333, 5119.667221630582, 5149.794008239666, 4576.695641742121, 4866.7079975201605, 5088.084144853943, 5127.0258650856595, 5139.254808052811, 4856.101621200054, 4900.877478063041, 5076.974615126937, 5108.514691104377, 5113.488651134894, 5405.0, 5408.432114627947, 5451.318585176853, 5485.396606373296, 5515.365732940683, 6047.546434017422, 6101.206843110367, 6104.328523862371, 6112.938870611281, 6147.262328520606, 5988.730507127189, 6075.2405949256345, 6187.412195841388, 6191.0713392990965, 6223.471004964584, 5626.324841019063, 5644.604877658525, 5679.148127780783, 5703.40114288002, 5758.021136406821, 6232.283287421914, 6245.375462453719, 6252.624842509464, 6276.529260305495, 6282.905756413657, 5788.652680839163, 6062.9996050652035, 6077.75688972445, 6081.270247570232, 6089.8477538061015, 6004.609700369462, 6010.939890601038, 6065.332813390523, 6068.969655151696, 6079.179310793074, 5978.461938425563, 5995.850103747354, 5996.460318571262, 5997.460228579449, 5999.310079340845, 6031.276246850406, 6033.668148251801, 6048.455638992512, 6079.574429789872, 6162.938370616326, 4849.927251091236, 4857.247126695368, 4865.9026819463415, 4877.731724755173, 4878.512148785093, 4335.349697545394, 4340.218760623118, 4355.0, 4397.824087036547, 4399.120175964756, 4779.689320194246, 5443.646162999393, 5452.509274165344, 5514.228008078872, 5565.805196818092, 6142.375998161017, 6165.876682466325, 6167.105769663463, 6179.783707570214, 6181.443670069625, 5579.7489112990015, 5588.441155884379, 5608.074667679787, 5608.775648974077, 5610.0, 5167.870803229949, 5190.896182076337, 5217.713025783542, 5308.256844041837, 5309.938012397536, 6108.402780883668, 6117.602355846945, 6119.015143218386, 6123.84690382744, 6151.753929842756, 5988.341282459013, 6108.602940808833, 6170.629762214102, 6181.0, 6188.133661287513, 5215.29593504872, 6182.659953702409, 6230.43926046658, 6241.78153764616, 6250.593711408744, 5832.708364581669, 6048.213732214784, 6063.211782468427, 6064.0, 6075.453209211192, 5818.2436283282905, 6073.635581865102, 6076.088586712029, 6123.877522449615, 6131.743695906945, 5877.94122058774, 5879.441453061952, 5945.881082378415, 5980.970095149496, 5993.280806303272, 5898.0, 5900.527957763283, 5907.586468947133, 5930.436608522184, 5937.970310148595, 5560.916586251128, 5617.74720137603, 5720.227769251098, 5727.7422515986045, 5734.483896449339, 5950.16697662319, 5975.820725378246, 5976.820695379146, 5977.521798256216, 5979.431953964367, 5777.39337369587, 5795.652260864362, 5797.4782269595935, 5803.912941305968, 5832.9708351457975], "value": 5557.180776567498, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.5853670721655, 515.3120584020332, 530.6259087343424, 538.9083855744523, 557.1114073053478, 25518.85165167567, 30909.0, 31719.04842854712, 31750.841245793767, 31913.88301409451, 32297.0, 32936.67063329372, 32983.50524742131, 32985.0, 33090.0, 9137.45175289483, 9478.71563853082, 9486.051394860528, 9494.003129671388, 9497.667581634609, 9388.530573471333, 9490.905090949105, 9494.620215191384, 9505.382150160218, 9505.952470237638, 9468.81062378752, 9484.0, 9511.524423778817, 9529.0, 9569.186619137381, 9395.389299695566, 9436.575354109047, 9459.621615135386, 9485.383450075724, 9504.239660827152, 9371.76570585734, 9446.52767361636, 9447.574859131355, 9448.244140468745, 9455.007224241494, 38691.226175476615, 39024.0, 39039.0, 39076.02309942274, 39174.0, 3263.4452143135723, 3366.6969972702327, 3372.5447064646205, 3436.1924947637285, 3581.767185132958, 28275.293117672016, 28432.431351373063, 29290.70709292901, 30384.0, 30659.0, 3140.277736120682, 3194.185482701896, 3206.599175103112, 3256.0720194744385, 3300.5214243934734, 432364.0, 436478.0, 437115.0, 437149.0, 438029.0, 33179.0, 34266.657333426345, 59636.40363596395, 60104.69947650234, 60121.398786012454, 34250.82874585611, 34714.65285347114, 34732.47901281483, 34744.652553474145, 34792.30415391678, 14019.859801402059, 14119.717605648035, 14130.364133614012, 14155.787663185061, 14164.079334843202, 11793.88206117945, 11827.704307392383, 11875.881241187477, 11908.0, 11920.046396288277, 7604.201558836243, 7648.1204661463535, 7701.422393320411, 7709.38324934004, 7711.03612048494, 3100.891468798581, 3125.312431265118, 3139.8744050237733, 3167.0973772474736, 3171.413288541627, 847.8770578266117, 873.702941000063, 892.4779004282437, 929.3355250995571, 969.7284760267137, 27774.88900444, 28055.298617534725, 28265.57601635977, 28638.856805716256, 28726.281842953675, 792.610220812704, 962.7689354554857, 1005.4821766790109, 1005.6731562242154, 1108.4845546820768, 14547.709045819236, 14943.476978305707, 14989.625259368602, 15343.616409589626, 15352.539423817303, 635327.0, 638463.0, 639843.0, 640392.0, 641836.0, 56359.43640563624, 56624.0, 56853.431465685644, 56937.43062569321, 57006.14490782641, 1094.9890501094887, 1192.3025030357164, 1224.4734764051366, 1300.8699130086916, 1324.4900713225286, 28851.855740721163, 29078.27304317409, 29536.409271814013, 29614.40771184521, 30244.0, 49050.264246037055, 49735.75132124316, 49884.501154987986, 49896.750516247186, 49906.0, 504614.0, 506781.0, 508942.0, 626359.0, 635885.0, 38768.41847372348, 38906.6109338903, 38980.6101938977, 39112.0, 39597.80201098976, 42488.0, 42503.574964249965, 42914.57085429106, 42931.35602966021, 43734.0, 36243.27513449664, 36456.81771591125, 36499.27001459903, 36713.08217294589, 36874.63125368712, 31713.20716982094, 32424.513632295064, 32445.02664920056, 32894.0, 35693.0, 37025.0, 37688.43467347937, 38113.80943095267, 38172.80913595414, 38292.80853595714, 31375.68624313819, 34022.0, 34036.0, 34067.4889876657, 34289.0, 24706.382340441633, 24792.25623231234, 25079.122230721838, 25138.24585262445, 25800.612990804777], "value": 17926.95468302597, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.8645321551205, 374.4944325161032, 377.4093543604261, 379.27558363525657, 402.57126160638916, 14143.29283535823, 15126.168060756654, 15694.76457853133, 19925.501862453446, 20302.39092827214, 19374.709379359287, 19780.802191978077, 20268.695969560467, 20590.89704551475, 20695.793042069574, 5785.595008349417, 6009.0, 6063.6361818290825, 6072.666003369813, 6073.119397687333, 4978.800847966095, 6577.835554111138, 6660.800175994728, 6688.063671086052, 6708.530402871801, 6648.900266496006, 6649.50128740344, 6660.400563949243, 7421.888671669929, 7712.691492340327, 7720.459567830254, 7753.379729621645, 7906.486078404885, 7933.642986065641, 7958.960205198995, 7638.8472230555635, 7747.0, 7753.147153813076, 7763.456558040939, 7769.10655274645, 27926.860365698245, 28153.0, 28515.857420712764, 28684.42631147386, 28841.0, 2765.9308517287027, 3108.2850944282713, 3193.760467964912, 3205.423023855705, 3410.36908171989, 14613.415463381452, 15160.393584256726, 16466.176691165514, 16478.83521164785, 17025.57436064096, 1603.470854617985, 1941.7184508246366, 1990.7113468547125, 2034.7253120828648, 2087.885166315852, 66122.0, 127369.36315318364, 133560.33219833937, 136287.999999999, 140751.0, 17586.648267034845, 17591.384301549384, 17674.5581360467, 20528.486787830127, 21787.019584118752, 35223.471647925304, 36065.81967090148, 36086.458703119475, 36092.819535902156, 36131.63868361335, 10188.847167292497, 10360.948195258976, 10552.683419497427, 10591.682249532527, 10737.51681174349, 7591.696332146763, 8246.587670616445, 8595.5702214889, 8727.643536245305, 8760.78098047554, 8934.972478164964, 9150.63397464094, 9842.409455432697, 9854.458004809661, 9856.0, 2975.910722678323, 3629.130997536271, 3669.43123815809, 3708.4437334400054, 3734.477173195755, 838.2581415447373, 872.2455076358904, 890.4301247201796, 894.4007514964989, 964.2382517810851, 16806.495805125865, 20143.39569812908, 20822.23011044048, 20878.060487277806, 21352.893235533724, 841.9410641255176, 845.3448577352601, 943.613118621362, 969.2488321550854, 1016.1261315268863, 10620.203484738675, 11029.0, 11093.611723589796, 11141.777164456666, 11141.832872506919, 547231.0, 548259.0, 552610.0, 565868.0, 573811.0, 113624.0, 117985.4100729508, 118921.0, 119838.40080799714, 121802.3909880445, 974.4786539201493, 1005.1857995024054, 1015.5176291261591, 1018.2464975917768, 1019.2101121630795, 16190.271437785563, 17479.47561573155, 17863.196156172744, 18873.961932093316, 19315.51711207231, 52990.735046324524, 53067.0, 53855.73072134614, 54576.0, 55582.72208638931, 77120.0, 77614.0, 80360.59819700864, 80585.0, 81728.0, 7968.0836703779805, 8134.593270336578, 8161.632726527439, 8334.749957501284, 8459.238668519863, 10849.837252441062, 11882.702932426759, 12564.748705026032, 12663.0, 12983.221006739626, 10669.46652667348, 10674.092702120406, 10976.670699879016, 11147.442627868737, 11263.042641375574, 10836.512356943798, 10845.295055821347, 11326.093912486816, 11441.48513316886, 11559.768804623693, 10498.265121441425, 11286.604968826052, 11370.602028928948, 11614.535418583162, 11742.941285293518, 8232.876506852523, 8241.79395515117, 8302.750917472486, 8422.536760477988, 8428.325733941147, 9293.953530232577, 11156.609518667132, 11364.0, 11393.0, 11432.94283528577], "value": 10811.926963197882, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.4530665080642, 1480.045370735875, 1612.5887898585859, 1775.7158854583254, 1811.420345489444, 7868.685252589897, 8270.255676989069, 8272.214139656731, 8273.50358978461, 8345.707900223493, 7553.20691327411, 7832.177621349748, 7846.176151504082, 8183.181681831814, 8415.957920210405, 2257.401788526042, 2290.141197051106, 2294.7934685878267, 2296.2996286132725, 2298.678185054094, 2038.4292398128548, 2318.1654604342425, 2325.790678838904, 2376.251480783548, 2382.725986511556, 2124.3839286606867, 2205.470687035108, 2310.480141968052, 2323.825713071518, 2346.730126035502, 2273.783990520898, 2276.829237807163, 2306.3542208181734, 2309.58427483053, 2310.595645761993, 2343.3438637181616, 2360.433495960974, 2362.1968530699473, 2364.692589963311, 2378.0131245533084, 8432.747017589421, 8440.324774018063, 8535.957320213422, 8540.530270835101, 8560.743177704679, 5070.467600901926, 5229.346331708536, 5246.9768395162955, 5250.921236181461, 5254.448282930313, 3281.8030918144987, 3505.509228707983, 3535.469679548088, 3635.4546817977252, 3637.672609465161, 1099.9120070394429, 1121.2151493954184, 1733.1507561295, 1764.5941433470246, 1839.4665546991357, 10590.78818423635, 10756.19328550362, 10897.291676041032, 11091.168162387854, 11143.777124457465, 4581.450225972904, 4606.470255920612, 4798.184308667535, 4803.5916947059895, 4820.68665536739, 9589.90410095904, 9593.28050396223, 9597.0, 9597.47213903242, 9616.0, 5322.201669749568, 5325.440828712929, 5326.9467305327225, 5335.813246536352, 5337.866553336197, 4359.869203923888, 4518.254488009507, 4546.158960592301, 4815.3499277597775, 4871.48849370811, 3794.1463170786906, 3798.924021519554, 3912.765234085964, 3920.5295364556437, 3934.409838524187, 1069.6363236499628, 1240.9565665201674, 1246.9127161098818, 1263.5514392390753, 1265.1523479268913, 758.616898466282, 847.8728190771434, 885.5926273913943, 891.8350105230553, 918.4626993209048, 5455.427180146107, 5471.7264136793, 5622.606417550814, 5734.455226753452, 5844.094165404369, 922.6678395777515, 937.2174234514121, 959.6353385713523, 1001.6944831826363, 1038.9506598335736, 3647.434647629622, 3662.956057523593, 3848.2688289224957, 3869.188848983749, 3884.708646851441, 9992.050755178247, 10206.897931020743, 10245.590176392861, 10270.435126067989, 10337.948310258551, 9739.0, 10024.448655324028, 10170.847437288447, 10206.183505319557, 10208.540615672164, 6818.522703410714, 7728.536287822749, 7951.403644726553, 7965.442419030613, 8002.759917202493, 5523.834284971457, 5543.9168412474655, 5577.386487486455, 5702.1468022891895, 5945.286565612154, 9542.856857147288, 9587.280953928623, 9588.232941364535, 9591.376560523544, 9612.086851749073, 9231.030741772018, 9625.56684949165, 9633.566489507848, 9650.806983860424, 9661.758456038655, 6677.83305417349, 6894.3795058442975, 6907.79276621682, 7092.822679433056, 7250.02124713157, 7670.463067585216, 7815.804604884924, 7950.4832185907735, 7974.561399123104, 7988.84022319562, 6969.5469794465225, 7093.645317733989, 7113.359797618239, 7165.283471652793, 7240.167380751281, 6821.624810635462, 6915.066466027022, 6964.700751775321, 7011.018457416068, 7086.929130708627, 6613.900791488035, 6639.170103737033, 6747.224069232101, 6751.93248067513, 6755.290694477009, 4086.9591304087767, 4707.576318131385, 4710.351377018016, 4790.976045119752, 4929.309896614405, 4785.832495862628, 4810.63920205979, 4819.228923372244, 4931.112399768076, 4943.233798761198], "value": 4477.636514882437, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.7084398976982, 308.2770902234261, 311.46117217214226, 314.44029627263467, 316.99366012679735, 4545.863624091278, 4757.524247575245, 5203.895922081556, 5299.60252981026, 5356.892862142755, 5183.870403239921, 5197.896042079156, 5239.607029472786, 5249.816256431026, 5254.842354729355, 3818.579956204815, 3932.5870783567743, 3946.2304850554156, 3949.526056873179, 3957.366821308591, 3119.8440077996124, 3910.7262491625597, 3911.6309291747943, 3929.8231579578987, 4004.8998775030714, 3958.841646334143, 3974.105826189099, 3983.6215559521797, 3993.7404068735586, 4020.758754474726, 3065.800722953001, 3378.3918894598964, 3945.230680017412, 3986.302397080514, 3988.1883272293253, 3123.406552754969, 3312.2381852173894, 3464.428369319059, 3525.065857547752, 3528.4651176738203, 4311.892202694927, 4383.408239887606, 4965.900681986376, 5131.384233891957, 5138.430784607696, 4567.497575266718, 4595.172868883599, 4618.0, 4621.8151273949, 4629.953700462986, 4379.58393952574, 4841.346418233528, 5261.789788348663, 5447.346318441772, 5496.367917689477, 3897.7661340319387, 3899.2396482685886, 3907.0037140529266, 3909.6090390960962, 3917.275304068756, 4597.103564804865, 5119.974400127976, 5377.1553339362545, 5743.684097374643, 5748.878968601124, 3209.5185722141864, 3213.53403756454, 3296.967030329714, 3648.4709717090873, 3797.411401232813, 3681.9690486663776, 3704.5369328833895, 3796.924061518754, 4010.358342665218, 4022.316206244887, 4115.403266526337, 4475.70907890986, 4508.932366014513, 4559.977200113978, 4572.199865023674, 4302.978485107555, 4536.841210557614, 5562.330081560874, 5568.939492387762, 5573.777048918079, 1141.9665202991307, 1212.2665787198794, 1230.0712961713848, 1249.3690686203408, 1261.3602316987879, 786.3001928283832, 829.274384913201, 858.3090612057229, 863.3826813828017, 869.8869147010848, 4560.566746159109, 4661.836835710734, 4751.59611433032, 4894.437139728977, 4935.703857768545, 4626.0, 5223.395856858755, 5342.572594192456, 5364.892702145935, 5408.322340808209, 4053.574374690674, 4526.600901522199, 4854.538818812207, 4910.48439913811, 4929.7781599828095, 2458.754124587527, 2706.807485936398, 2779.8741509688607, 2820.4923113839295, 2829.8160619559662, 4776.9761151194025, 4796.8081276748535, 4813.975930120327, 5170.750723435318, 5516.938099354817, 4554.225781617133, 4732.432108147044, 5115.335006449138, 5468.37113731918, 5496.532794712494, 4693.413323334583, 4885.461079759864, 5239.607029472881, 5389.51494365509, 5521.420250873681, 4741.708753578017, 5051.646384753032, 5217.843464696065, 5373.307408166415, 5387.649802762808, 5097.770600323069, 5107.387113546398, 5291.285676433632, 5302.628815982845, 5368.979893820162, 5423.457654234703, 5482.91775623358, 5484.917726234189, 5666.34836993751, 5678.93955821573, 2908.505554055858, 3651.707863370977, 3696.0020794385905, 3754.511913451234, 3765.004547589782, 4954.603631709527, 5287.867803304948, 5587.860303492445, 5716.685582293014, 5718.228039214653, 4760.285957106462, 4955.432088895989, 5261.710605916712, 5367.651102678469, 5543.944560554504, 4606.591122569208, 4823.782929768239, 5495.697736624524, 5505.504504594605, 5515.558755299647, 4630.865437967719, 5001.549860512571, 5397.757100930389, 5397.7641687563, 5451.64564303319, 4761.0, 5113.411957624772, 5162.941596972687, 5357.357117145967, 5611.859703507445, 5464.388005538296, 5495.642783219078, 5505.0, 5519.889602207853, 5524.861878453071], "value": 3907.9335320987793, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.9170899492061, 705.3722187253343, 738.833762403459, 771.0131032278686, 787.4599963396957, 13928.094673846206, 14467.927660361709, 14505.129692218474, 14515.129092254472, 14559.27203639819, 12855.100142989995, 12962.287074210915, 12991.090623656348, 12997.74004519909, 13036.0, 636.360457739973, 658.618001559097, 660.168188083014, 790.3440144679917, 799.7041094794934, 775.5114278004849, 804.0, 811.4401063266357, 812.3460614205562, 813.5240884082818, 855.0508935082084, 857.237059017475, 858.8711693246, 859.2395729779159, 860.1226748716306, 687.089606271687, 855.1363123245504, 855.2986551028159, 856.6359297298685, 857.5840717252128, 520.3209811196388, 521.3587287636228, 523.2517499975035, 524.396943514957, 615.9507239420881, 13809.930950345184, 14179.0, 14192.0, 14193.716125677429, 14196.787048194285, 13005.349732513336, 13098.148620339647, 13192.934035329858, 13312.068155229137, 13446.126001809851, 11634.418279086012, 11769.41152942358, 11940.0, 11993.820092698617, 12078.456469458895, 4364.629006534448, 4398.868033958986, 4442.37806707058, 4454.086912182995, 4473.4855491618555, 86466.0, 89193.0, 93449.5327523358, 94253.11493770086, 98063.99999999929, 9846.212303015742, 9988.400695958266, 10042.347247428894, 10473.738156546147, 10711.625093121704, 18843.528911777314, 19006.33477828297, 19186.040697965047, 19300.517487062934, 19316.227350905807, 7125.3230943060325, 7128.536645118051, 7151.427885769126, 7284.526505777108, 7285.854282914312, 3948.407738839197, 3979.880603581897, 3997.1006523532556, 4003.3394489908837, 4014.879553613396, 3268.084936217863, 3344.1138098403944, 3401.0477066420945, 3403.863845446204, 3412.897613071612, 503.66002948009947, 553.3138907754355, 571.7798647520651, 574.7241324164424, 580.4195804195804, 565.2406735288878, 616.9383061693884, 642.3415998601471, 653.4739534674633, 671.7951024937344, 8750.212480876624, 8910.108989101167, 9047.68333108338, 9128.634854605742, 9441.858372124423, 635.4979566142788, 660.1283472434235, 671.4762485261406, 678.3691167214538, 688.579966220605, 4297.205017071852, 4316.2446571849805, 4330.242207613718, 4363.759993200341, 4463.821447142078, 78824.81762773563, 105755.0, 109185.36221956677, 111783.44108279406, 113249.0, 77777.44445111066, 82337.76493352605, 96864.0, 99970.00029999607, 101604.0, 86324.0, 88106.67839982406, 88983.11016890008, 89249.55375223212, 90842.09157908469, 6742.831429214113, 8140.982377202849, 8172.346212302882, 8353.58232088381, 8745.60644770974, 54929.72535137299, 55600.721996391374, 56139.8772024549, 56381.43618563762, 57596.71201644133, 68393.0, 68561.62876742537, 76949.61525192339, 80379.0, 81594.0, 11813.586524471602, 11969.581064662694, 12020.879791202326, 12170.878291216974, 12540.435680394587, 12721.681957951125, 13345.399457024263, 13738.725225495635, 13771.104878182887, 13896.791548126988, 12169.0, 12186.87813121893, 12527.0, 12748.49006039748, 12972.481100755864, 12050.156489045683, 13165.210087394787, 13484.0, 13724.107932984323, 13910.58268251954, 11195.763495116724, 12180.0864935132, 12710.809337860124, 13267.2703001332, 13336.866631333562, 10007.449590272314, 10287.897121028993, 10885.292455990335, 11116.0, 11440.0, 8932.642694292155, 9350.438973661601, 11083.72290692739, 11101.500432480396, 11209.439528023404], "value": 7130.708117776077, "unit": "score"}], "name": "dromaeo_css", "value": 7157.148837154444, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 114.0, 114.0, 116.0, 113.0, 112.0, 158.0, 164.0, 112.0, 113.0], "value": 121.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 138.0, 141.0, 149.0, 118.0, 111.0, 113.0, 141.0, 173.0, 115.0], "value": 136.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 184.0, 181.0, 182.0, 183.0, 187.0, 182.0, 185.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 89.0, 61.0, 59.0, 58.0, 61.0, 82.0, 57.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 92.0, 91.0, 54.0, 68.0, 112.0, 97.0, 92.0, 126.0], "value": 89.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 84.0, 86.0, 84.0, 88.0, 84.0, 88.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 93.0, 94.0, 93.0, 105.0, 149.0, 94.0, 93.0], "value": 100.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 80.0, 81.0, 79.0, 80.0, 83.0, 84.0, 83.0, 81.0], "value": 81.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 48.0, 47.0, 48.0, 52.0, 56.0, 55.0, 56.0, 56.0, 55.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 80.0, 97.0, 111.0, 92.0, 69.0, 71.0, 71.0, 71.0, 71.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 74.0, 83.0, 90.0, 101.0, 101.0, 101.0, 85.0, 85.0, 84.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 146.0, 166.0, 150.0, 151.0, 155.0, 150.0, 157.0, 220.0, 169.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 166.0, 167.0, 177.0, 202.0, 168.0, 165.0, 165.0, 166.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 66.0, 65.0, 67.0, 65.0, 74.0, 73.0, 80.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1487.2, "alertThreshold": 2.0}]} 18:35:10 INFO - Return code: 0 18:35:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:35:11 ERROR - # TBPL SUCCESS # 18:35:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:35:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:35:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:35:11 INFO - 2016-11-23 18:35:11.041 screenresolution[1920:23182] starting screenresolution argv=screenresolution get 18:35:11 INFO - 2016-11-23 18:35:11.044 screenresolution[1920:23182] Display 0: 1600x1200x32@60 18:35:11 INFO - 2016-11-23 18:35:11.050 screenresolution[1921:23186] starting screenresolution argv=screenresolution list 18:35:11 INFO - Available Modes on Display 0 18:35:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:35:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:35:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:35:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:35:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:35:11 INFO - Intel Iris: 18:35:11 INFO - Chipset Model: Intel Iris 18:35:11 INFO - Type: GPU 18:35:11 INFO - Bus: Built-In 18:35:11 INFO - VRAM (Dynamic, Max): 1536 MB 18:35:11 INFO - Vendor: Intel (0x8086) 18:35:11 INFO - Device ID: 0x0a2e 18:35:11 INFO - Revision ID: 0x0009 18:35:11 INFO - Displays: 18:35:11 INFO - Display: 18:35:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:35:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:35:11 INFO - Display Serial Number: 1600x1200 60 18:35:11 INFO - Main Display: Yes 18:35:11 INFO - Mirror: Off 18:35:11 INFO - Online: Yes 18:35:11 INFO - Rotation: Supported 18:35:11 INFO - Return code: 0 18:35:11 INFO - Running post-action listener: _resource_record_post_action 18:35:11 INFO - [mozharness: 2016-11-24 02:35:11.285858Z] Finished run-tests step (success) 18:35:11 INFO - Running post-run listener: _resource_record_post_run 18:35:11 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 409600; Write bytes: 657001984; Read time: 5; Write time: 2079 18:35:11 INFO - TinderboxPrint: CPU usage
53.6% 18:35:11 INFO - TinderboxPrint: I/O read bytes / time
409,600 / 5 18:35:11 INFO - TinderboxPrint: I/O write bytes / time
657,001,984 / 2,079 18:35:11 INFO - TinderboxPrint: CPU idle
2,250.6 (46.4%) 18:35:11 INFO - TinderboxPrint: CPU system
973.3 (20.1%) 18:35:11 INFO - TinderboxPrint: CPU user
1,625.8 (33.5%) 18:35:11 INFO - TinderboxPrint: Swap in / out
348,213,248 / 0 18:35:11 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 347419136; Write bytes: 345027072; Read time: 26807; Write time: 621 18:35:11 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 319488; Write bytes: 304712704; Read time: 4; Write time: 1426 18:35:11 INFO - Running post-run listener: _upload_blobber_files 18:35:11 INFO - Blob upload gear active. 18:35:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:35:11 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 18:35:11 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'] 18:35:11 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 18:35:12 INFO - (blobuploader) - INFO - Open directory for files ... 18:35:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:35:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:35:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:35:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:13 INFO - (blobuploader) - INFO - Done attempting. 18:35:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:35:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:35:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:35:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:14 INFO - (blobuploader) - INFO - Done attempting. 18:35:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:35:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:35:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:35:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:16 INFO - (blobuploader) - INFO - Done attempting. 18:35:16 INFO - (blobuploader) - INFO - Iteration through files over. 18:35:16 INFO - Return code: 0 18:35:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:35:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:35:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f0a9bf7fb2d3a010d38258021f83844019235a984d0cf7a3af5295996051035683e3fa2e6cdf6a5ccb0639012b6f6058fbd39051eaef61973bc504593fda1c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a13aa07739fec1a2da52ed71fa5dcd6ee2887abdb7a41594e19107a6ba8dbb338a5dc5cb51a7c1ea801e196d384cb3d9ee3828c4765aa922af13299596c103dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba753ec690ba22a793d9b8adce7a7160781ab73ab2ee632f87618b72bfc32fa8de154fd8b3bb4de0cca52361e4fb1fdbf42ddd750a781785c9e181fdfae1859d"} 18:35:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:35:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:35:16 INFO - Contents: 18:35:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f0a9bf7fb2d3a010d38258021f83844019235a984d0cf7a3af5295996051035683e3fa2e6cdf6a5ccb0639012b6f6058fbd39051eaef61973bc504593fda1c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a13aa07739fec1a2da52ed71fa5dcd6ee2887abdb7a41594e19107a6ba8dbb338a5dc5cb51a7c1ea801e196d384cb3d9ee3828c4765aa922af13299596c103dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba753ec690ba22a793d9b8adce7a7160781ab73ab2ee632f87618b72bfc32fa8de154fd8b3bb4de0cca52361e4fb1fdbf42ddd750a781785c9e181fdfae1859d"} 18:35:16 INFO - Running post-run listener: copy_logs_to_upload_dir 18:35:16 INFO - Copying logs to upload dir... 18:35:16 INFO - mkdir: /builds/slave/test/build/upload/logs 18:35:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.668228 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-23 18:35:16.487790) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 18:35:16.491590) ========= 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.BQ7FBJKEma/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.GMGcq69RDo/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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/f0a9bf7fb2d3a010d38258021f83844019235a984d0cf7a3af5295996051035683e3fa2e6cdf6a5ccb0639012b6f6058fbd39051eaef61973bc504593fda1c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a13aa07739fec1a2da52ed71fa5dcd6ee2887abdb7a41594e19107a6ba8dbb338a5dc5cb51a7c1ea801e196d384cb3d9ee3828c4765aa922af13299596c103dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba753ec690ba22a793d9b8adce7a7160781ab73ab2ee632f87618b72bfc32fa8de154fd8b3bb4de0cca52361e4fb1fdbf42ddd750a781785c9e181fdfae1859d"} build_url:https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010803 build_url: 'https://queue.taskcluster.net/v1/task/Pl0kBxBMSIuGBlPmmPDasQ/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/f0a9bf7fb2d3a010d38258021f83844019235a984d0cf7a3af5295996051035683e3fa2e6cdf6a5ccb0639012b6f6058fbd39051eaef61973bc504593fda1c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a13aa07739fec1a2da52ed71fa5dcd6ee2887abdb7a41594e19107a6ba8dbb338a5dc5cb51a7c1ea801e196d384cb3d9ee3828c4765aa922af13299596c103dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba753ec690ba22a793d9b8adce7a7160781ab73ab2ee632f87618b72bfc32fa8de154fd8b3bb4de0cca52361e4fb1fdbf42ddd750a781785c9e181fdfae1859d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 18:35:16.526670) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:35:16.527022) ========= 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.BQ7FBJKEma/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.GMGcq69RDo/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.004741 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:35:16.576455) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 18:35:16.576805) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 18:35:16.577200) ========= ========= Total master_lag: 0.19 =========