builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0025 starttime: 1486100406.22 results: success (0) buildid: 20170202171409 builduid: c967aa5d53f54635a4860429e0f9e250 revision: c8102da699dba43d85eb80b70956e80c6793feca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:06.220797) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:06.221254) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:06.221556) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:06.248263) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:06.248664) ========= 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.uEHYKk24fD/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.5DUhapBxvX/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-02 21:40:06-- 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% 9.88M=0.001s 2017-02-02 21:40:06 (9.88 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.078760 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:06.351572) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:06.351890) ========= 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.uEHYKk24fD/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.5DUhapBxvX/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.026954 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:06.400858) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 21:40:06.401205) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev c8102da699dba43d85eb80b70956e80c6793feca --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev c8102da699dba43d85eb80b70956e80c6793feca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uEHYKk24fD/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.5DUhapBxvX/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-02 21:40:06,504 truncating revision to first 12 chars 2017-02-02 21:40:06,505 Setting DEBUG logging. 2017-02-02 21:40:06,505 attempt 1/10 2017-02-02 21:40:06,505 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/c8102da699db?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 21:40:07,863 unpacking tar archive at: larch-c8102da699db/testing/mozharness/ program finished with exit code 0 elapsedTime=1.628956 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 21:40:08.053617) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:08.053954) ========= script_repo_revision: c8102da699dba43d85eb80b70956e80c6793feca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:08.054357) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:08.054704) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:08.074854) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 23 mins, 28 secs) (at 2017-02-02 21:40:08.075247) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uEHYKk24fD/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.5DUhapBxvX/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 21:40:08 INFO - MultiFileLogger online at 20170202 21:40:08 in /builds/slave/test 21:40:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 21:40:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:40:08 INFO - {'append_to_log': False, 21:40:08 INFO - 'base_work_dir': '/builds/slave/test', 21:40:08 INFO - 'blob_upload_branch': 'Larch', 21:40:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:40:08 INFO - 'branch': 'Larch', 21:40:08 INFO - 'buildbot_json_path': 'buildprops.json', 21:40:08 INFO - 'config_files': ('talos/mac_config.py',), 21:40:08 INFO - 'default_actions': ('clobber', 21:40:08 INFO - 'read-buildbot-config', 21:40:08 INFO - 'download-and-extract', 21:40:08 INFO - 'populate-webroot', 21:40:08 INFO - 'create-virtualenv', 21:40:08 INFO - 'install', 21:40:08 INFO - 'run-tests'), 21:40:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:40:08 INFO - 'download_minidump_stackwalk': True, 21:40:08 INFO - 'download_symbols': 'ondemand', 21:40:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:40:08 INFO - 'tooltool.py': '/tools/tooltool.py', 21:40:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:40:08 INFO - '/tools/misc-python/virtualenv.py')}, 21:40:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:40:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:40:08 INFO - 'installer_path': 'installer.exe', 21:40:08 INFO - 'log_level': 'info', 21:40:08 INFO - 'log_name': 'talos', 21:40:08 INFO - 'log_to_console': True, 21:40:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:40:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:40:08 INFO - 'opt_config_files': (), 21:40:08 INFO - 'pip_index': False, 21:40:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:40:08 INFO - 'cmd': ('bash', 21:40:08 INFO - '-c', 21:40:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:40:08 INFO - 'enabled': True, 21:40:08 INFO - 'halt_on_failure': False, 21:40:08 INFO - 'name': 'check_screen_resolution'},), 21:40:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:40:08 INFO - 'cmd': ('bash', 21:40:08 INFO - '-c', 21:40:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:40:08 INFO - 'enabled': True, 21:40:08 INFO - 'halt_on_failure': False, 21:40:08 INFO - 'name': 'check_screen_resolution'},), 21:40:08 INFO - 'run_cmd_checks_enabled': True, 21:40:08 INFO - 'sps_profile': False, 21:40:08 INFO - 'sps_profile_interval': 0, 21:40:08 INFO - 'suite': 'dromaeojs', 21:40:08 INFO - 'system_bits': '32', 21:40:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:40:08 INFO - 'title': 't-yosemite-r7-0025', 21:40:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:40:08 INFO - 'use_talos_json': True, 21:40:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:40:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:40:08 INFO - 'work_dir': 'build'} 21:40:08 INFO - [mozharness: 2017-02-03 05:40:08.292018Z] Running clobber step. 21:40:08 INFO - Running pre-action listener: _resource_record_pre_action 21:40:08 INFO - Running main action method: clobber 21:40:08 INFO - rmtree: /builds/slave/test/build 21:40:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:40:10 INFO - Running post-action listener: _resource_record_post_action 21:40:10 INFO - [mozharness: 2017-02-03 05:40:10.769150Z] Finished clobber step (success) 21:40:10 INFO - [mozharness: 2017-02-03 05:40:10.769277Z] Running read-buildbot-config step. 21:40:10 INFO - Running pre-action listener: _resource_record_pre_action 21:40:10 INFO - Running main action method: read_buildbot_config 21:40:10 INFO - Using buildbot properties: 21:40:10 INFO - { 21:40:10 INFO - "project": "", 21:40:10 INFO - "product": "firefox", 21:40:10 INFO - "script_repo_revision": "production", 21:40:10 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 21:40:10 INFO - "repository": "", 21:40:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 21:40:10 INFO - "buildid": "20170202171409", 21:40:10 INFO - "pgo_build": "False", 21:40:10 INFO - "basedir": "/builds/slave/test", 21:40:10 INFO - "buildnumber": 1, 21:40:10 INFO - "slavename": "t-yosemite-r7-0025", 21:40:10 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 21:40:10 INFO - "platform": "yosemite_r7", 21:40:10 INFO - "scheduler": "tests-larch-macosx64-talos", 21:40:10 INFO - "branch": "larch", 21:40:10 INFO - "repo_path": "projects/larch", 21:40:10 INFO - "revision": "c8102da699dba43d85eb80b70956e80c6793feca", 21:40:10 INFO - "stage_platform": "macosx64", 21:40:10 INFO - "builduid": "c967aa5d53f54635a4860429e0f9e250", 21:40:10 INFO - "slavebuilddir": "test" 21:40:10 INFO - } 21:40:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 21:40:10 INFO - Running post-action listener: _resource_record_post_action 21:40:10 INFO - [mozharness: 2017-02-03 05:40:10.772696Z] Finished read-buildbot-config step (success) 21:40:10 INFO - [mozharness: 2017-02-03 05:40:10.772805Z] Running download-and-extract step. 21:40:10 INFO - Running pre-action listener: _resource_record_pre_action 21:40:10 INFO - Running main action method: download_and_extract 21:40:10 INFO - mkdir: /builds/slave/test/build/tests 21:40:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:40:10 INFO - trying https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 21:40:10 INFO - Downloading https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 21:40:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 21:40:11 INFO - Downloaded 1199 bytes. 21:40:11 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 21:40:11 INFO - Using the following test package requirements: 21:40:11 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 21:40:11 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:11 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 21:40:11 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:11 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 21:40:11 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:11 INFO - u'jsshell-mac.zip'], 21:40:11 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:11 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 21:40:11 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 21:40:11 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:11 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 21:40:11 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:11 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 21:40:11 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:11 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 21:40:11 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:11 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 21:40:11 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:40:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 21:40:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:40:11 INFO - Fetch https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 21:40:12 INFO - Expected file size: 34853121 21:40:12 INFO - Obtained file size: 34853121 21:40:19 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:40:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 21:40:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:40:19 INFO - Fetch https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 21:40:21 INFO - Expected file size: 34853121 21:40:21 INFO - Obtained file size: 34853121 21:40:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 21:40:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:40:27 INFO - Fetch https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 21:40:28 INFO - Expected file size: 14403454 21:40:28 INFO - Obtained file size: 14403454 21:40: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')]} 21:40:29 INFO - trying https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 21:40:29 INFO - Downloading https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 21:40:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 21:40:31 INFO - Downloaded 70183674 bytes. 21:40:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 21:40:31 INFO - mkdir: /builds/slave/test/properties 21:40:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:40:31 INFO - Writing to file /builds/slave/test/properties/build_url 21:40:31 INFO - Contents: 21:40:31 INFO - build_url:https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 21:40:31 INFO - Running post-action listener: _resource_record_post_action 21:40:31 INFO - Running post-action listener: set_extra_try_arguments 21:40:31 INFO - [mozharness: 2017-02-03 05:40:31.668168Z] Finished download-and-extract step (success) 21:40:31 INFO - [mozharness: 2017-02-03 05:40:31.668311Z] Running populate-webroot step. 21:40:31 INFO - Running pre-action listener: _resource_record_pre_action 21:40:31 INFO - Running main action method: populate_webroot 21:40:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:40:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:40:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:40:31 INFO - '--tppagecycles', 21:40:31 INFO - '7'], 21:40:31 INFO - 'tests': ['tsvgm']}}, 21:40:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:40:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:40:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:40:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:40:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:40:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:40:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:40:31 INFO - 'talos_options': ['--disable-e10s'], 21:40:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:40:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:40:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:40:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:40:31 INFO - 'talos_options': ['--disable-e10s'], 21:40:31 INFO - 'tests': ['damp', 'tps']}, 21:40:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:40:31 INFO - 'tests': ['damp', 'tps']}, 21:40:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:40:31 INFO - 'tests': ['dromaeo_dom']}, 21:40:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:40:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:40:31 INFO - 'tests': ['basic_compositor_video']}, 21:40:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:40:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:40:31 INFO - 'tests': ['a11yr', 21:40:31 INFO - 'ts_paint', 21:40:31 INFO - 'tpaint', 21:40:31 INFO - 'sessionrestore', 21:40:31 INFO - 'sessionrestore_no_auto_restore', 21:40:31 INFO - 'tabpaint']}, 21:40:31 INFO - 'other-e10s': {'tests': ['a11yr', 21:40:31 INFO - 'ts_paint', 21:40:31 INFO - 'tpaint', 21:40:31 INFO - 'sessionrestore', 21:40:31 INFO - 'sessionrestore_no_auto_restore', 21:40:31 INFO - 'tabpaint']}, 21:40:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:40:31 INFO - 'ts_paint', 21:40:31 INFO - 'tpaint', 21:40:31 INFO - 'sessionrestore', 21:40:31 INFO - 'sessionrestore_no_auto_restore', 21:40:31 INFO - 'tabpaint']}, 21:40:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:40:31 INFO - 'ts_paint', 21:40:31 INFO - 'tpaint', 21:40:31 INFO - 'sessionrestore', 21:40:31 INFO - 'sessionrestore_no_auto_restore', 21:40:31 INFO - 'tabpaint']}, 21:40:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:40:31 INFO - 'tests': ['a11yr', 21:40:31 INFO - 'ts_paint', 21:40:31 INFO - 'tpaint', 21:40:31 INFO - 'sessionrestore', 21:40:31 INFO - 'sessionrestore_no_auto_restore', 21:40:31 INFO - 'tabpaint']}, 21:40:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:40:31 INFO - 'tests': ['a11yr', 21:40:31 INFO - 'ts_paint', 21:40:31 INFO - 'tpaint', 21:40:31 INFO - 'sessionrestore', 21:40:31 INFO - 'sessionrestore_no_auto_restore', 21:40:31 INFO - 'tabpaint']}, 21:40:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:40:31 INFO - 'tests': ['tsvgx', 21:40:31 INFO - 'tsvgr_opacity', 21:40:31 INFO - 'tart', 21:40:31 INFO - 'tscrollx', 21:40:31 INFO - 'cart', 21:40:31 INFO - 'tsvg_static']}, 21:40:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:40:31 INFO - 'tsvgr_opacity', 21:40:31 INFO - 'tart', 21:40:31 INFO - 'tscrollx', 21:40:31 INFO - 'cart', 21:40:31 INFO - 'tsvg_static']}, 21:40:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:40:31 INFO - 'talos_options': ['--disable-e10s'], 21:40:31 INFO - 'tests': ['tp5o']}, 21:40:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:40:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:40:31 INFO - 'talos_options': ['--disable-e10s', 21:40:31 INFO - '--xperf_path', 21:40:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:40:31 INFO - 'tests': ['tp5n']}, 21:40:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:40:31 INFO - 'talos_options': ['--xperf_path', 21:40:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:40:31 INFO - 'tests': ['tp5n']}}, 21:40:31 INFO - 'talos.zip': {'path': '', 21:40:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:40:31 INFO - Running post-action listener: _resource_record_post_action 21:40:31 INFO - [mozharness: 2017-02-03 05:40:31.687030Z] Finished populate-webroot step (success) 21:40:31 INFO - [mozharness: 2017-02-03 05:40:31.687166Z] Running create-virtualenv step. 21:40:31 INFO - Running pre-action listener: _resource_record_pre_action 21:40:31 INFO - Running main action method: create_virtualenv 21:40:31 INFO - Creating virtualenv /builds/slave/test/build/venv 21:40:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:40:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:40:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:40:31 INFO - Using real prefix '/tools/python27' 21:40:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:40:32 INFO - Installing distribute.............................................................................................................................................................................................done. 21:40:35 INFO - Installing pip.................done. 21:40:35 INFO - Return code: 0 21:40:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:40:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:40:35 INFO - Reading from file tmpfile_stdout 21:40:35 INFO - Output received: 21:40:35 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:40:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:40: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')]} 21:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40: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')]} 21:40:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101088b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9993d74040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e66d30>, '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 0x100d8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e5bbe8>, '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.uEHYKk24fD/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.5DUhapBxvX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 21:40:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:40:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:40:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uEHYKk24fD/Render', 21:40:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:40:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:40:35 INFO - 'HOME': '/Users/cltbld', 21:40:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:40:35 INFO - 'LOGNAME': 'cltbld', 21:40:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:35 INFO - 'MOZ_NO_REMOTE': '1', 21:40:35 INFO - 'NO_EM_RESTART': '1', 21:40:35 INFO - 'PAGER': '/bin/cat', 21:40:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:40:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:35 INFO - 'PWD': '/builds/slave/test', 21:40:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:40:35 INFO - 'SHELL': '/bin/bash', 21:40:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DUhapBxvX/Listeners', 21:40:35 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 21:40:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:40:35 INFO - 'USER': 'cltbld', 21:40:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:40:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:40:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:35 INFO - 'XPC_FLAGS': '0x0', 21:40:35 INFO - 'XPC_SERVICE_NAME': '0', 21:40:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:40:35 INFO - Cleaning up... 21:40:35 INFO - Return code: 0 21:40:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:40: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')]} 21:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40: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')]} 21:40:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101088b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9993d74040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e66d30>, '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 0x100d8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e5bbe8>, '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.uEHYKk24fD/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.5DUhapBxvX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 21:40:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:40:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:40:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uEHYKk24fD/Render', 21:40:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:40:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:40:35 INFO - 'HOME': '/Users/cltbld', 21:40:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:40:35 INFO - 'LOGNAME': 'cltbld', 21:40:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:35 INFO - 'MOZ_NO_REMOTE': '1', 21:40:35 INFO - 'NO_EM_RESTART': '1', 21:40:35 INFO - 'PAGER': '/bin/cat', 21:40:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:40:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:35 INFO - 'PWD': '/builds/slave/test', 21:40:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:40:35 INFO - 'SHELL': '/bin/bash', 21:40:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DUhapBxvX/Listeners', 21:40:35 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 21:40:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:40:35 INFO - 'USER': 'cltbld', 21:40:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:40:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:40:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:35 INFO - 'XPC_FLAGS': '0x0', 21:40:35 INFO - 'XPC_SERVICE_NAME': '0', 21:40:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:40:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:36 INFO - Downloading/unpacking psutil>=3.1.1 21:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:40:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:40:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:40:39 INFO - Installing collected packages: psutil 21:40:39 INFO - Running setup.py install for psutil 21:40:39 INFO - building 'psutil._psutil_osx' extension 21:40:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 21:40:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 21:40:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 21:40:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 21:40:40 INFO - building 'psutil._psutil_posix' extension 21:40:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 21:40:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:40:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:40:40 INFO - ^ 21:40:40 INFO - 1 warning generated. 21:40:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 21:40:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:40:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:40:40 INFO - Successfully installed psutil 21:40:40 INFO - Cleaning up... 21:40:41 INFO - Return code: 0 21:40:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:40: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')]} 21:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40: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')]} 21:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101088b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9993d74040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e66d30>, '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 0x100d8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e5bbe8>, '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.uEHYKk24fD/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.5DUhapBxvX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 21:40: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:40: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 mozsystemmonitor==0.3 21:40:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uEHYKk24fD/Render', 21:40:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:40:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:40:41 INFO - 'HOME': '/Users/cltbld', 21:40:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:40:41 INFO - 'LOGNAME': 'cltbld', 21:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:41 INFO - 'MOZ_NO_REMOTE': '1', 21:40:41 INFO - 'NO_EM_RESTART': '1', 21:40:41 INFO - 'PAGER': '/bin/cat', 21:40:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:41 INFO - 'PWD': '/builds/slave/test', 21:40:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:40:41 INFO - 'SHELL': '/bin/bash', 21:40:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DUhapBxvX/Listeners', 21:40:41 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 21:40:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:40:41 INFO - 'USER': 'cltbld', 21:40:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:40:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:41 INFO - 'XPC_FLAGS': '0x0', 21:40:41 INFO - 'XPC_SERVICE_NAME': '0', 21:40:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:40:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:40: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 21:40: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 21:40: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 21:40: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 21:40: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 21:40: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 21:40:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:40:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:40:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:40:44 INFO - Installing collected packages: mozsystemmonitor 21:40:44 INFO - Running setup.py install for mozsystemmonitor 21:40:44 INFO - Successfully installed mozsystemmonitor 21:40:44 INFO - Cleaning up... 21:40:44 INFO - Return code: 0 21:40:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101088b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9993d74040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e66d30>, '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 0x100d8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e5bbe8>, '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.uEHYKk24fD/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.5DUhapBxvX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 21:40:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:40:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:40:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uEHYKk24fD/Render', 21:40:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:40:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:40:44 INFO - 'HOME': '/Users/cltbld', 21:40:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:40:44 INFO - 'LOGNAME': 'cltbld', 21:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:44 INFO - 'MOZ_NO_REMOTE': '1', 21:40:44 INFO - 'NO_EM_RESTART': '1', 21:40:44 INFO - 'PAGER': '/bin/cat', 21:40:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:44 INFO - 'PWD': '/builds/slave/test', 21:40:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:40:44 INFO - 'SHELL': '/bin/bash', 21:40:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DUhapBxvX/Listeners', 21:40:44 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 21:40:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:40:44 INFO - 'USER': 'cltbld', 21:40:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:40:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:44 INFO - 'XPC_FLAGS': '0x0', 21:40:44 INFO - 'XPC_SERVICE_NAME': '0', 21:40:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:44 INFO - Downloading/unpacking jsonschema==2.5.1 21:40: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 21:40: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 21:40: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 21:40: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 21:40: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 21:40: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 21:40:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:40:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:47 INFO - Downloading functools32-3.2.3-2.tar.gz 21:40:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:40:47 INFO - warning: no files found matching '*.txt' 21:40:47 INFO - no previously-included directories found matching 'build' 21:40:47 INFO - no previously-included directories found matching 'dist' 21:40:47 INFO - no previously-included directories found matching '.git*' 21:40:47 INFO - Installing collected packages: jsonschema, functools32 21:40:47 INFO - Running setup.py install for functools32 21:40:47 INFO - warning: no files found matching '*.txt' 21:40:47 INFO - no previously-included directories found matching 'build' 21:40:47 INFO - no previously-included directories found matching 'dist' 21:40:47 INFO - no previously-included directories found matching '.git*' 21:40:47 INFO - Successfully installed jsonschema functools32 21:40:47 INFO - Cleaning up... 21:40:47 INFO - Return code: 0 21:40:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:40: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')]} 21:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40: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')]} 21:40:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101088b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9993d74040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e66d30>, '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 0x100d8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e5bbe8>, '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.uEHYKk24fD/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.5DUhapBxvX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 21:40:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:40:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:40:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uEHYKk24fD/Render', 21:40:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:40:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:40:47 INFO - 'HOME': '/Users/cltbld', 21:40:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:40:47 INFO - 'LOGNAME': 'cltbld', 21:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:47 INFO - 'MOZ_NO_REMOTE': '1', 21:40:47 INFO - 'NO_EM_RESTART': '1', 21:40:47 INFO - 'PAGER': '/bin/cat', 21:40:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:47 INFO - 'PWD': '/builds/slave/test', 21:40:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:40:47 INFO - 'SHELL': '/bin/bash', 21:40:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DUhapBxvX/Listeners', 21:40:47 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 21:40:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:40:47 INFO - 'USER': 'cltbld', 21:40:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:40:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:47 INFO - 'XPC_FLAGS': '0x0', 21:40:47 INFO - 'XPC_SERVICE_NAME': '0', 21:40:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:40:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:40:48 INFO - Cleaning up... 21:40:48 INFO - Return code: 0 21:40:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:40:48 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')]} 21:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:48 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')]} 21:40:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:48 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 0x101088b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9993d74040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e66d30>, '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 0x100d8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e5bbe8>, '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.uEHYKk24fD/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.5DUhapBxvX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 21:40:48 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 21:40:48 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 21:40:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uEHYKk24fD/Render', 21:40:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:40:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:40:48 INFO - 'HOME': '/Users/cltbld', 21:40:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:40:48 INFO - 'LOGNAME': 'cltbld', 21:40:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:48 INFO - 'MOZ_NO_REMOTE': '1', 21:40:48 INFO - 'NO_EM_RESTART': '1', 21:40:48 INFO - 'PAGER': '/bin/cat', 21:40:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:40:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:48 INFO - 'PWD': '/builds/slave/test', 21:40:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:40:48 INFO - 'SHELL': '/bin/bash', 21:40:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DUhapBxvX/Listeners', 21:40:48 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 21:40:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:40:48 INFO - 'USER': 'cltbld', 21:40:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:40:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:40:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:48 INFO - 'XPC_FLAGS': '0x0', 21:40:48 INFO - 'XPC_SERVICE_NAME': '0', 21:40:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:40:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:48 INFO - Downloading/unpacking blobuploader==1.2.4 21:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:50 INFO - Downloading blobuploader-1.2.4.tar.gz 21:40:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:40:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:40:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:52 INFO - Downloading docopt-0.6.1.tar.gz 21:40:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:40:52 INFO - Installing collected packages: blobuploader, requests, docopt 21:40:52 INFO - Running setup.py install for blobuploader 21:40:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:40:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:40:52 INFO - Running setup.py install for requests 21:40:52 INFO - Running setup.py install for docopt 21:40:52 INFO - Successfully installed blobuploader requests docopt 21:40:52 INFO - Cleaning up... 21:40:53 INFO - Return code: 0 21:40:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:40:53 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')]} 21:40:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:53 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')]} 21:40:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:53 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 0x101088b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9993d74040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e66d30>, '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 0x100d8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e5bbe8>, '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.uEHYKk24fD/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.5DUhapBxvX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 21:40:53 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 21:40:53 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 21:40:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uEHYKk24fD/Render', 21:40:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:40:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:40:53 INFO - 'HOME': '/Users/cltbld', 21:40:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:40:53 INFO - 'LOGNAME': 'cltbld', 21:40:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:53 INFO - 'MOZ_NO_REMOTE': '1', 21:40:53 INFO - 'NO_EM_RESTART': '1', 21:40:53 INFO - 'PAGER': '/bin/cat', 21:40:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:40:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:53 INFO - 'PWD': '/builds/slave/test', 21:40:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:40:53 INFO - 'SHELL': '/bin/bash', 21:40:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DUhapBxvX/Listeners', 21:40:53 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 21:40:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:40:53 INFO - 'USER': 'cltbld', 21:40:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:40:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:40:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:53 INFO - 'XPC_FLAGS': '0x0', 21:40:53 INFO - 'XPC_SERVICE_NAME': '0', 21:40:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:40:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:40:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-JxX2uQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:40:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-L5OwwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:40:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-pxVDp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:40:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-IlhnRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:40:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-BF4rUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:40:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-bpueeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:40:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-nWQRji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:40:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-XEBODY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:40:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-jOnPLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:40:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Xeuo0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:40:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-q5_zCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:40:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-qyqvSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:40:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-CbafMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:40:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-1rPQ2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:40:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-hNgIQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:40:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-4Q6JX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:40:55 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-fdmCIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:40:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:40:55 INFO - Running setup.py install for manifestparser 21:40:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:40:55 INFO - Running setup.py install for mozcrash 21:40:55 INFO - Running setup.py install for mozdebug 21:40:55 INFO - Running setup.py install for mozdevice 21:40:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:40:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:40:55 INFO - Running setup.py install for mozfile 21:40:55 INFO - Running setup.py install for mozhttpd 21:40:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:40:56 INFO - Running setup.py install for mozinfo 21:40:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:40:56 INFO - Running setup.py install for mozInstall 21:40:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:40:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:40:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:40:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:40:56 INFO - Running setup.py install for mozleak 21:40:56 INFO - Running setup.py install for mozlog 21:40:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:40:56 INFO - Running setup.py install for moznetwork 21:40:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:40:56 INFO - Running setup.py install for mozprocess 21:40:56 INFO - Running setup.py install for mozprofile 21:40:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:40:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:40:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:40:57 INFO - Running setup.py install for mozrunner 21:40:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:40:57 INFO - Running setup.py install for mozscreenshot 21:40:57 INFO - Running setup.py install for moztest 21:40:57 INFO - Running setup.py install for mozversion 21:40:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:40:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:40:57 INFO - Cleaning up... 21:40:57 INFO - Return code: 0 21:40:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:40: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')]} 21:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40: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')]} 21:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101088b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9993d74040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e66d30>, '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 0x100d8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e5bbe8>, '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.uEHYKk24fD/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.5DUhapBxvX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 21:40:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:40:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:40:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uEHYKk24fD/Render', 21:40:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:40:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:40:57 INFO - 'HOME': '/Users/cltbld', 21:40:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:40:57 INFO - 'LOGNAME': 'cltbld', 21:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:57 INFO - 'MOZ_NO_REMOTE': '1', 21:40:57 INFO - 'NO_EM_RESTART': '1', 21:40:57 INFO - 'PAGER': '/bin/cat', 21:40:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:57 INFO - 'PWD': '/builds/slave/test', 21:40:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:40:57 INFO - 'SHELL': '/bin/bash', 21:40:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DUhapBxvX/Listeners', 21:40:57 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 21:40:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:40:57 INFO - 'USER': 'cltbld', 21:40:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:40:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:57 INFO - 'XPC_FLAGS': '0x0', 21:40:57 INFO - 'XPC_SERVICE_NAME': '0', 21:40:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:40:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:40:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-C44cxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:40:58 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)) 21:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:40:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-2L2nDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:40:58 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)) 21:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:40:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Gt4Ack-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:40:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:40:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-1vMgLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:40:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:40:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Ke88yd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:40:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:40:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-9Ib1lE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:40:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:40:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-2nOm2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:40:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:40:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ZfPABp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:40:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:40:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-B9Sw3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:40:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:40:59 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-y97Ht7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:40:59 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)) 21:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:40:59 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-FRl1sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:40:59 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)) 21:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:40:59 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-5smQui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 21:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:40:59 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-0Tbr_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:40:59 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-yoj_Qm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:40:59 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-A8x369-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:40:59 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-5AZ6Wg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:40:59 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-wDA6kO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:40:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:41:02 INFO - Downloading blessings-1.6.tar.gz 21:41:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:41:02 INFO - Installing collected packages: blessings 21:41:02 INFO - Running setup.py install for blessings 21:41:02 INFO - Successfully installed blessings 21:41:02 INFO - Cleaning up... 21:41:02 INFO - Return code: 0 21:41:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:41:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:41:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:41:03 INFO - Reading from file tmpfile_stdout 21:41:03 INFO - Current package versions: 21:41:03 INFO - blessings == 1.6 21:41:03 INFO - blobuploader == 1.2.4 21:41:03 INFO - docopt == 0.6.1 21:41:03 INFO - functools32 == 3.2.3-2 21:41:03 INFO - jsonschema == 2.5.1 21:41:03 INFO - manifestparser == 1.1 21:41:03 INFO - mozInstall == 1.12 21:41:03 INFO - mozcrash == 1.0 21:41:03 INFO - mozdebug == 0.1 21:41:03 INFO - mozdevice == 0.49 21:41:03 INFO - mozfile == 1.2 21:41:03 INFO - mozhttpd == 0.7 21:41:03 INFO - mozinfo == 0.9 21:41:03 INFO - mozleak == 0.1 21:41:03 INFO - mozlog == 3.4 21:41:03 INFO - moznetwork == 0.27 21:41:03 INFO - mozprocess == 0.24 21:41:03 INFO - mozprofile == 0.28 21:41:03 INFO - mozrunner == 6.13 21:41:03 INFO - mozscreenshot == 0.1 21:41:03 INFO - mozsystemmonitor == 0.3 21:41:03 INFO - moztest == 0.8 21:41:03 INFO - mozversion == 1.4 21:41:03 INFO - psutil == 3.1.1 21:41:03 INFO - requests == 1.2.3 21:41:03 INFO - wsgiref == 0.1.2 21:41:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:41:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101088b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9993d74040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e66d30>, '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 0x100d8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e5bbe8>, '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.uEHYKk24fD/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.5DUhapBxvX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 21:41:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:41:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:41:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uEHYKk24fD/Render', 21:41:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:03 INFO - 'HOME': '/Users/cltbld', 21:41:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:03 INFO - 'LOGNAME': 'cltbld', 21:41:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:03 INFO - 'MOZ_NO_REMOTE': '1', 21:41:03 INFO - 'NO_EM_RESTART': '1', 21:41:03 INFO - 'PAGER': '/bin/cat', 21:41:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:03 INFO - 'PWD': '/builds/slave/test', 21:41:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:03 INFO - 'SHELL': '/bin/bash', 21:41:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DUhapBxvX/Listeners', 21:41:03 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 21:41:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:03 INFO - 'USER': 'cltbld', 21:41:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:03 INFO - 'XPC_FLAGS': '0x0', 21:41:03 INFO - 'XPC_SERVICE_NAME': '0', 21:41:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:41:04 INFO - Cleaning up... 21:41:04 INFO - Return code: 0 21:41:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:41: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')]} 21:41:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41: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')]} 21:41:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101088b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9993d74040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e66d30>, '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 0x100d8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e5bbe8>, '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.uEHYKk24fD/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.5DUhapBxvX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 21:41:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:41:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:41:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uEHYKk24fD/Render', 21:41:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:04 INFO - 'HOME': '/Users/cltbld', 21:41:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:04 INFO - 'LOGNAME': 'cltbld', 21:41:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:04 INFO - 'MOZ_NO_REMOTE': '1', 21:41:04 INFO - 'NO_EM_RESTART': '1', 21:41:04 INFO - 'PAGER': '/bin/cat', 21:41:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:04 INFO - 'PWD': '/builds/slave/test', 21:41:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:04 INFO - 'SHELL': '/bin/bash', 21:41:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DUhapBxvX/Listeners', 21:41:04 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 21:41:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:04 INFO - 'USER': 'cltbld', 21:41:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:04 INFO - 'XPC_FLAGS': '0x0', 21:41:04 INFO - 'XPC_SERVICE_NAME': '0', 21:41:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:41:04 INFO - Cleaning up... 21:41:04 INFO - Return code: 0 21:41:04 INFO - Running post-action listener: _resource_record_post_action 21:41:04 INFO - Running post-action listener: _start_resource_monitoring 21:41:04 INFO - Starting resource monitoring. 21:41:04 INFO - [mozharness: 2017-02-03 05:41:04.815201Z] Finished create-virtualenv step (success) 21:41:04 INFO - [mozharness: 2017-02-03 05:41:04.816133Z] Running install step. 21:41:04 INFO - Running pre-action listener: _resource_record_pre_action 21:41:04 INFO - Running main action method: install 21:41:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:41:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:41:05 INFO - Reading from file tmpfile_stdout 21:41:05 INFO - Detecting whether we're running mozinstall >=1.0... 21:41:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:41:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:41:05 INFO - Reading from file tmpfile_stdout 21:41:05 INFO - Output received: 21:41:05 INFO - Usage: mozinstall [options] installer 21:41:05 INFO - Options: 21:41:05 INFO - -h, --help show this help message and exit 21:41:05 INFO - -d DEST, --destination=DEST 21:41:05 INFO - Directory to install application into. [default: 21:41:05 INFO - "/builds/slave/test"] 21:41:05 INFO - --app=APP Application being installed. [default: firefox] 21:41:05 INFO - mkdir: /builds/slave/test/build/application 21:41:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:41:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:41:26 INFO - Reading from file tmpfile_stdout 21:41:26 INFO - Output received: 21:41:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:41:26 INFO - Running post-action listener: _resource_record_post_action 21:41:26 INFO - [mozharness: 2017-02-03 05:41:26.999805Z] Finished install step (success) 21:41:26 INFO - [mozharness: 2017-02-03 05:41:26.999940Z] Running run-tests step. 21:41:27 INFO - Running pre-action listener: _resource_record_pre_action 21:41:27 INFO - Running main action method: run_tests 21:41:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:41:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:41:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:41:27 INFO - 2017-02-02 21:41:27.015 screenresolution[1618:7283] starting screenresolution argv=screenresolution get 21:41:27 INFO - 2017-02-02 21:41:27.017 screenresolution[1618:7283] Display 0: 1600x1200x32@60 21:41:27 INFO - 2017-02-02 21:41:27.023 screenresolution[1619:7285] starting screenresolution argv=screenresolution list 21:41:27 INFO - Available Modes on Display 0 21:41:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:41:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:41:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:41:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:41:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:41:27 INFO - Intel Iris: 21:41:27 INFO - Chipset Model: Intel Iris 21:41:27 INFO - Type: GPU 21:41:27 INFO - Bus: Built-In 21:41:27 INFO - VRAM (Dynamic, Max): 1536 MB 21:41:27 INFO - Vendor: Intel (0x8086) 21:41:27 INFO - Device ID: 0x0a2e 21:41:27 INFO - Revision ID: 0x0009 21:41:27 INFO - Displays: 21:41:27 INFO - Display: 21:41:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:41:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:41:27 INFO - Display Serial Number: 1600x1200 60 21:41:27 INFO - Main Display: Yes 21:41:27 INFO - Mirror: Off 21:41:27 INFO - Online: Yes 21:41:27 INFO - Rotation: Supported 21:41:27 INFO - Return code: 0 21:41:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:41:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:41:27 INFO - Python 2.7.3 21:41:27 INFO - Return code: 0 21:41:27 INFO - grabbing minidump binary from tooltool 21:41:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:27 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 0x100e66d30>, '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 0x100d8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e5bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:41:27 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 21:41:27 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 21:41:27 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 21:41:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:41:27 INFO - Return code: 0 21:41:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:41:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:41:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:41:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:41:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:41:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--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 21:41:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0025 --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 21:41:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uEHYKk24fD/Render', 21:41:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:27 INFO - 'HOME': '/Users/cltbld', 21:41:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:27 INFO - 'LOGNAME': 'cltbld', 21:41:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:41:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:41:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:27 INFO - 'MOZ_NO_REMOTE': '1', 21:41:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:41:27 INFO - 'NO_EM_RESTART': '1', 21:41:27 INFO - 'PAGER': '/bin/cat', 21:41:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:27 INFO - 'PWD': '/builds/slave/test', 21:41:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:41:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:27 INFO - 'SHELL': '/bin/bash', 21:41:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DUhapBxvX/Listeners', 21:41:27 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 21:41:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:27 INFO - 'USER': 'cltbld', 21:41:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:27 INFO - 'XPC_FLAGS': '0x0', 21:41:27 INFO - 'XPC_SERVICE_NAME': '0', 21:41:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--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 21:41:27 INFO - mozversion application_buildid: 20170202171409 21:41:27 INFO - mozversion application_changeset: c8102da699dba43d85eb80b70956e80c6793feca 21:41:27 INFO - mozversion application_display_name: Nightly 21:41:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:41:27 INFO - mozversion application_name: Firefox 21:41:27 INFO - mozversion application_remotingname: firefox 21:41:27 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 21:41:27 INFO - mozversion application_vendor: Mozilla 21:41:27 INFO - mozversion application_version: 54.0a1 21:41:27 INFO - mozversion platform_buildid: 20170202171409 21:41:27 INFO - mozversion platform_changeset: c8102da699dba43d85eb80b70956e80c6793feca 21:41:27 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 21:41:27 INFO - mozversion platform_version: 54.0a1 21:41:27 INFO - using testdate: 1486100487 21:41:27 INFO - actual date: 1486100487 21:41:27 INFO - starting webserver on 'localhost:49235' 21:41:27 INFO - SUITE-START | Running 2 tests 21:41:27 INFO - TEST-START | dromaeo_css 21:41:27 INFO - operating with platform_type : mac_ 21:41:27 INFO - Initialising browser for dromaeo_css test... 21:41:27 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp1ND8uM/profile http://localhost:49235/getInfo.html) 21:41:29 INFO - PROCESS | 1629 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 21:41:29 INFO - PROCESS | 1629 | __metrics Screen width/height:1600/1200 21:41:29 INFO - PROCESS | 1629 | colorDepth:24 21:41:29 INFO - PROCESS | 1629 | Browser inner width/height: 1024/689 21:41:29 INFO - PROCESS | 1629 | __metrics 21:41:30 INFO - PROCESS | 1629 | 1486100489996 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 21:41:30 INFO - PROCESS | 1629 | 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" 21:41:30 INFO - PROCESS | 1629 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:41:30 INFO - TEST-INFO | 1629: exit 0 21:41:30 INFO - Browser initialized. 21:41:30 INFO - Running cycle 1/1 for dromaeo_css test... 21:41:30 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp1ND8uM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:41:42 INFO - PROCESS | 1631 | RSS: Main: 236859392 21:41:42 INFO - PROCESS | 1631 | 21:44:45 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 21:44:48 INFO - PROCESS | 1631 | RSS: Main: 431124480 21:44:48 INFO - PROCESS | 1631 | 21:46:32 INFO - PROCESS | 1631 | ************************* 21:46:32 INFO - PROCESS | 1631 | A coding exception was thrown and uncaught in a Task. 21:46:32 INFO - PROCESS | 1631 | 21:46:32 ERROR - PROCESS | 1631 | Full message: TypeError: NetworkError when attempting to fetch resource. 21:46:32 INFO - PROCESS | 1631 | Full stack: 21:46:32 INFO - PROCESS | 1631 | ************************* 21:47:51 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 21:47:51 INFO - PROCESS | 1631 | RSS: Main: 370290688 21:47:51 INFO - PROCESS | 1631 | 21:50:54 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 21:50:55 INFO - PROCESS | 1631 | RSS: Main: 340606976 21:50:55 INFO - PROCESS | 1631 | 21:53:57 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 21:53:57 INFO - PROCESS | 1631 | RSS: Main: 357621760 21:53:57 INFO - PROCESS | 1631 | 21:57:01 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 21:57:01 INFO - PROCESS | 1631 | RSS: Main: 332746752 21:57:01 INFO - PROCESS | 1631 | 22:00:05 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 22:00:05 INFO - PROCESS | 1631 | RSS: Main: 394948608 22:00:05 INFO - PROCESS | 1631 | 22:00:05 INFO - PROCESS | 1631 | __start_tp_report 22:00:05 INFO - PROCESS | 1631 | _x_x_mozilla_page_load 22:00:05 INFO - PROCESS | 1631 | _x_x_mozilla_page_load_details 22:00:05 INFO - PROCESS | 1631 | |i|pagename|runs| 22:00:05 INFO - PROCESS | 1631 | |0;dojo.html;2776.8195067320626;3283.917902052447;3292.2427841596423;3310.13936376542;3313.6189338226113;5301.992621401931;5381.623286369956;5526.6131370804005;5609.242752228448;5621.241132447119;5443.564514838813;5468.808591699291;5481.972590137054;5498.9826882026755;5532.059549876521;4949.505049495057;4959.429665588458;5114.309568208281;5174.844754657366;5186.299849520305;4999.450060493361;5136.537711605954;5137.434882162959;5147.047796157723;5252.894942101175;4887.36464259644;4944.505549445044;4966.751662416883;5013;5032.84901452957;4915.26271059344;4951.158303088484;4952.281919121743;5014.674046186986;5027.421846487664;4862.294967229767;4878.146324393217;4903.730294833783;4926.827561035347;4929.112759703288;4747.596454301389;5370.785168593252;5375.543078838304;5378.46215378463;5401.405845357008;6044.335123136451;6045.33501314855;6091.573589848713;6100.969495152496;6118.01953385572;6106.175666285084;6153.384661533855;6168.1056246843955;6169.598976066542;6181.567290289681;5678.971605141948;5689.34572524165;5692.800751973661;5698.116791897262;5728.283964504437;6087.1173679816175;6106.339326077157;6133.539984501181;6166.1983942087245;6168.784092556739;5002.799888004512;5927.051671732502;6030.849228769316;6072.271327440647;6135.5398345124295;6142;6149.446549810538;6155.292141403706;6157.384261573897;6157.507399408037;5845.09401042839;5927.940720592739;5981.880362392728;6066.029435290332;6073.726682299307;6038.275406951194;6069.393060693984;6116.785912493041;6127.877442451126;6148.9692551536955;4901.166801643729;4902.583280421133;4922.187839006596;4926.187179115477;4940.6788558743565;4317.481902171697;4321.827126914888;4329.437173167468;4332.805023773938;4351.086271882877;5435.592330575223;5509.393966663663;5521.365043020024;5581.637193582485;5617.21359009739;6148.354422785543;6194.845128871814;6215.378462153749;6224.190855188796;6236.158118654014;4471.776411179428;5573.164025396223;5653.886922261614;5665.065264231438;5682.40334764852;5208.609354298443;5211.400688920747;5269.736513174403;5334.51989320963;5357.7589008494715;6155.692215389213;6186.628802271968;6202.72087756043;6232.844178895564;6261.812145635638;6024.759009639565;6119;6121.724522396414;6194.38056194377;6200.224971878515;6183.0107182850525;6202.379762023762;6205.286392064971;6246.5002799775;6267.96866015667;5817.883642327045;5864.354920958605;6117.204763380732;6129.632222066691;6139.57023008403;5942.851428714143;6079.969600151971;6120.877582448237;6128.3503509891225;6197.287311959182;5805.651660900359;5961.254843144607;5976.970115149396;5982.7307771149535;6003.609765365237;5782.190493330853;5792.797252096157;5922.111683247547;5933.080372542263;5936.317323507852;4691.202495575692;5599.776008959759;5646.0683987141665;5699.7435115419075;5707.914381284367;5768.711564421678;5791.971040144772;5811.94188058114;5817.79637712695;5822;5624.971875140598;5642.040853054908;5648.068068768607;5673.574481913791;5678.3753787084215 22:00:05 INFO - PROCESS | 1631 | |1;ext.html;446.4843106212324;504.26125725811687;514.4932241741886;522.1462908145182;534.4014703532044;36778.26443471129;39097.41353879691;39116.41325380121;39122;39201.60798392022;39589.40615890764;40153.799231003875;40184.995375115635;40271.798641006826;40287;7614.73348432805;8581.39930204886;8973.685920992766;9138.862917056233;11080.390578518178;11117.22179447439;11228.831567526493;11231.98912097911;11234.943825280861;11248.212625116244;11212.327260364364;11262.380569068699;11265.662030139109;11267.21129520934;11271.887281127207;8454.830903381899;8632.697855575025;11001.449927503592;11160.330380177174;11213.71965700864;11098.056665183469;11184.384858832842;11195.944020279929;11202.047825934806;11204.383758893258;38723.61276387228;38933;38954;38994.80502597497;38996.61003389958;3577.3454628426493;3913.471681323042;4006.0185254612516;4014.0767623446477;4174.958250417518;24947.750522494724;29171.270718232;30208.54687179672;31187.688123118933;31227.84386078055;3055.511118221074;3188.187012311869;3230.0471360948577;3299.5215693724276;3301.818399988;390625;460315;473647.99999999657;474387;476009;56575.43424565784;57812.42187578155;57850.42149578535;64422.67788661027;65564;67811.3218867805;70637;70979.29020709726;71426;71774.9233761494;16253.918730406273;16371;16371.42700005494;16372.67254654924;16381.754273685905;12224.205426647239;12327.93836030814;12332.19840710369;12339.259644421363;12361;16847.57881052959;20559.97200139987;20657.070431830303;20747.23648489865;20775.68836467454;7460.147187227142;9033.277337813091;9040.954795225982;9120.315976301672;9126.36115471924;851.4550687559966;859.4284800607651;868.8218915122385;875.7635438431588;892.6920212526668;28685.856570717013;28833.56749648757;28863;29411.264718381783;29508.409831803245;898.2948385517337;965.5896244096231;1038.361407734238;1059.332620449112;1200.2318516149671;14883.33025013877;15007.549773506813;15040.473583424528;15283.395243499272;15295.694086118216;613348;668183;684123;684920;847017.9438336611;51472.74263628658;51997.70005749886;51998;52067.73966130145;52088.21867671988;1362.141850634114;1376.5250988408982;1395.8534353892694;1400.6358346829898;1404.0031577579828;27600.447991039666;27631.585526217525;27689.646380057922;28355;28359.858200708866;44062;44464.777676111415;44498.77750611226;44569;44641.55358446374;571068;577202.2279777316;577492;577917;578191;21922.671159932932;22355.77644223537;22400.32799016032;24183.51632967366;24641.630375544024;37109;39641.40537891992;39933;40293.79853100716;40352.78941631756;33343.66656333405;33490.83254583712;33717.76221399049;34192;34229;33680.66319336873;33788.32423351568;34279;34364.48453273253;34481;32537.674623253464;33086.66913330836;33206.83396583002;33265.168370790925;33331.666683333824;28477.715222847506;29952;30121.246968825955;30420.543691844196;30468.94728242295;22426.891559547446;22630.321090367317;22729.354145098405;23042.884785575963;23172.3048308551 22:00:05 INFO - PROCESS | 1631 | |2;jquery.html;383.95200599925005;402.6175133623059;404.5246834968911;410.96712263018964;433.4192182475482;11772.941135294312;12106.970907472853;20483.078261478233;21745.23891663792;21750.89124554377;22106;22374.216902408418;22448.438789030282;23160.42098947527;23282;6369.617822930616;6681.231658359278;6764.830879228034;6790.287019862917;6839.62382068986;6516.413522782948;6741.191057073158;6790.0493930849725;6820.556663816861;6890.552114112566;6719.160104986877;6790.388865002149;6800.353966373212;6809.727610895558;6849.589024658537;6536.215654121512;6818.454523638097;6857.5199736018;6864.485163612724;6873.828154296132;6501.732162228367;6528.412442880163;6616.172978377703;6704.698288577026;6710.93289067108;22794.544109117724;27407.588886166723;28395.85802070997;29142.85428572865;29247.56128658072;2415.335782659765;2738.3564862257354;2745.679110563053;2956.2018255071225;2959.2010157257637;16115.274812633459;17115.657686846193;17347.39284125062;17585.7362139568;17783.021933793636;1921.6733155363622;1961.9509512262166;1964.607078584289;1965.685490321556;1978.1197367171615;76617.61691191564;99101.61394955697;128105.99999999907;129444.7055529442;140970.59029409676;16890.577735556708;17359.479215623553;22343.10083642876;22442.438939026328;22504.673270889874;32563.348733025206;33760.66239337575;39463.46170190465;39579;39684;8190.7133250335955;9836.06557377048;10332.328398654063;10722.088622467021;10886.891131088587;7967.960160199162;8156.265936065783;8671.523066231417;8706;8892.377533572588;10632.574697012033;10749.62376316825;10752.569897204181;10870.456477176267;10880.401577913133;3593.748437609342;3643.4716966039778;4014.498187726534;4034.9307433529552;4064.4106604542767;900.3742399032609;1013.6452241715341;1023.4575674892319;1026.3892983674702;1071.6999240212754;21035.789642103384;21293.46766330823;21367;21445.139874822682;21786.455338616663;936.4849332867033;973.1825266775942;1019.1999280564721;1051.9105876000472;1054.272551939175;8880.422772519765;11129.387883666313;11259.662210133709;11265.94367028176;11275.887241127484;663392;669418.6529067324;669620;669729;688490;103734.44398334032;126735;129507;129507.35246323896;131163;998.191464913437;998.7702828349375;1034.1933292032227;1034.7723500829804;1038.2161468091695;18222.817771822643;18323;18573.81426185721;18634.534136646693;18637.161327740556;38739.30994543213;40678.38982415325;49962.750186248835;54016.45983540272;55675.721621393255;73030.26969730234;74728.27445529928;80182.99542511484;81576.72407328812;83655.12483377122;6459.9031014533875;6714.865702686016;8316.126806685454;8323.458975166595;8435.367347448842;9256.676016339396;12194.146409751233;12927.870721293042;13018.934905325414;13524;9398.483118333757;9678.419294842332;9689;10447.895521044693;11311.943440282746;8620.91379086201;11010.339379637247;11135.832962505407;11498.367589782642;11533.192676512563;11225.4387280634;11317.151213658844;11657.591984280509;11679.532818687157;11779.17545771822;8178.836423271382;8239.546824924686;8462.365322600706;8627;8642.740717778477;9789;11044.282121662067;11451.656450306504;11492.195546311677;11608.941955290507 22:00:05 INFO - PROCESS | 1631 | |3;mootools.html;1413.8303403591572;1424.928753562323;1429.6497358147253;1437.7483940310449;1438.1443041390369;8000.679972801088;8292.419530632858;8432.241098301152;8437.620307086181;8439.198276163763;8088.797780055502;8223.876641850376;8324.917760691116;8344.165583441652;8432.620532076056;2284.497410569676;2328.39461739948;2329.6039673255586;2331.3239160643393;2335.065973610552;2238.294937094818;2332.486852892361;2344.660024296476;2345.0854166874933;2345.659879317498;2056.516718571135;2126.851120421571;2128.5104425982036;2233.6649502574664;2307.123293148601;2288.4164538042774;2312.710911136108;2314.143766806276;2314.305708287524;2317.4553979814646;2261.547690461914;2338.1582630253097;2344.2029709898716;2347.0729062020405;2347.8943447544902;8262.83474330516;8294.170582941719;8318.085010648823;8336.8332633347;8341.499510029356;4968.062455642303;5120.15517439622;5124.333836601218;5126.051680439094;5127.615428842814;3289.7861638993627;3350.8659653613827;3484.9128771780656;3546.804925729084;3556.3598552260582;1209.1982945411273;1213.4175595714107;1245.0848626259692;1267.0243912187564;1273.051576575448;10592.788144237073;10647.520861561248;10666.360018398844;10687.519061642244;10691;3887.5140607424073;4244.214820258262;4452.666050046195;4468;4529.773511324486;8609.182127697859;8610.268127209258;8619.569021548898;8624.482531048157;8628.094050124771;5009.223570346602;5080.517350851663;5116.616253780981;5119.513646203604;5133.79464821403;4431.689781715313;4486.3494793254795;4586.839460618862;4587.9082418351445;4640.443146822403;3657.76224545409;3659.3779057559746;3669.2478042001326;3690.1881586051563;3729.4032954727113;1136.6419577832955;1140.4297851074464;1145.335705290946;1146.7362506623356;1148.5807680196835;722.7181399254295;749.9137599176056;765.2615226306641;780.6721177105629;842.9367797415218;5278;5334.546563542065;5348.9465105348445;5576.1356989666665;5680.630759000652;953.6042542344987;960.7694153403272;965.2769805896442;969.1762002298132;986.9013098690074;3639.454081887738;3734.3838266686234;3754.6245375462236;3819.0282106608815;3865.5747867734526;9635.56639951204;9852.064053914866;9954.303198776017;9976.102150806462;9980.201583873273;9911.504424778732;9915.157211637092;9927.404355738534;9933.453660048623;9949.35329203614;7251.1661158966335;7511.812204694927;7690.500117492345;7691.115521714963;7701.614919254015;5363.249145119608;5508.834734957958;5558.888822223614;5631.014572449887;5765.769369225184;9045.231155351605;9048.457092574467;9227.677031303872;9283.953580232055;9299.070092990647;8981;9450.858237126586;9456.527173641152;9457.905420945703;9466.337356385262;6988.021676965331;7131.6077615729555;7133.0013798067275;7139.107611548557;7147;7301.488895777245;7701.037370328709;7706.7687969361;7800.336971357498;7805.024371953506;7010.789676309719;7082.256363081802;7084;7129.073220481304;7149.60677162761;6863.210730765826;6951.7914462566205;6951.860962780817;6999.160100787938;7013.824654383681;6257.687115644108;6359.586626869239;6365.777197798055;6377.17096777416;6380.330065343258;4125.566815484331;4784.114938736345;4931.038447502702;4941.825596737424;4974.104661161025;4779.593734532604;4829.806807727651;4842.346283251716;4880.804767809248;5086.3896332440345 22:00:05 INFO - PROCESS | 1631 | |4;prototype.html;353.5421628990457;362.358625233337;370.4696920036153;372.56223936874176;379.7835233916665;5126.743662816858;5372.629979355259;5414.83755487335;5419.3225846769155;5510.492725809757;4680.057776022955;5313.555764348394;5338.250800837562;5351.545118664918;5378.220158077076;3802.8098595070273;4049;4208.452901122858;4213.494380674308;4227.556106608808;4050.9189816203657;4069.69477289203;4078.4086307485395;4098.815553300109;4105.384192371138;3820.6943444524372;4045.554988951213;4061.73598716084;4079.3065178919655;4083.4487344208596;4073.877783666495;4090.627436087092;4099.831548008818;4101.569335219819;4104.958950410502;3401.540791993074;3468.1849765305133;3510.6489351064943;3512.1044133745986;3516.472529120627;4381.707396318094;4437.383532421067;4819.542143496362;4857.125717370872;5183.377994640668;4560.521145279764;4649.88375290617;4650.488446270908;4682.2508398656055;4683.555062269079;4407.815261124379;4984.675996060245;5620.92025950401;5686.2039314496005;5706.9429305707245;3319.8340082995996;3547.875824346161;3563.625819288963;3864.188520410717;3885.7279990400684;4519.118771839492;5648.074588161229;5817.912731309034;5835.328937172195;5850.6782126983;3367.410703126943;3743.494628225209;3880.3985382265796;3922.646961773454;3948.2893079245437;3657.341678497896;3935.6064393560414;3953.6639385651965;3957.584453632385;3958.4656071430563;4477.537603346908;4624.560666736722;4639.652026097989;4646.210144275481;4659.184642687516;5081.0092032054135;5318.521333080042;5463.590230732711;5701.657900525982;5705.058665320175;1112.4382186995676;1274.2160974635585;1287.6008437384442;1287.7637468030796;1292.5217669462359;811.5374236685151;826.5990994367727;849.5285116760134;867.2151702709058;885.818407226517;4369;4424.159409712144;4999.675021123615;5040.001199286492;5048.368953880765;4958.305837182797;4980.509745127436;4991.401031876126;5561.833145005657;5573.801632649;4776.897941778817;5037.622178336639;5040.621953353513;5053.292539044537;5064.974675126601;2434.7443518430664;2478.8388754731245;2495.9750402496106;2909.894240188715;2954.1728316071535;5133.358330208724;5155.510226528549;5686.744096515667;5804.82585522435;5829.533637309005;5055.671381360274;5171;5294.10883000994;5511.807086751945;5750.4249575042995;5353.384360798558;5793.507551858139;5793.7392817323325;5796.304443466811;5819.563532734977;4728.62171026324;5110.66780659256;5158.767855446439;5433.103537916358;5551.25058117149;4694.43666759991;4763;4777.665563410528;5567.721613919207;5680.375158732619;5052.46949070357;5728.684922329146;5904.793332233351;5926.880817831738;5953.732082056231;3892.5134358205223;3910.9635946474777;3921.254961557409;3935.35066713989;3936.5472970607602;4671.159191345455;5805.477507024388;5839.182514447895;5853;5871.941280587311;4384.934225986676;5603.495685388335;5635.380108188178;5663.773449061991;5668.63952651369;5591.6365436246515;5639.800477813738;5640.407757185437;5657.236273103078;5658.405867383865;5248.448912864094;5504.889902202013;5526.309211348582;5540.335159780852;5579.163125531203;4948.802047918043;5673.404292549223;5674.858128546654;5713.257276554022;5719.656820590778;4165.708400412063;5264.86837829042;5286.524212820865;5557.41647127062;5580.274564306615 22:00:05 INFO - PROCESS | 1631 | |5;yui.html;720.9495335326529;726.3970904149555;730.5908691132969;773.7330620935778;834.7078522517118;16628.833711662894;17301.480955571322;17355.132243387827;17370.392036278732;17398.478045658652;16508.587285317844;16600.169991500436;16651.33394664215;16658.58353541162;16679.749803752955;841.7769291137853;861.625193041026;875.299760191844;876.6931573949099;879.4942907827992;867.1848462445281;884.3234925282164;885.3802338363139;888.3958907942597;891.8305521950841;927;932.1191474057019;932.6502561539422;932.9906700933005;933.9626414943394;922.3174850610567;923.1230331185401;925.0610630210342;927.508420537113;927.5825878354767;690.2166041542835;691.7405972760215;692.8337199072237;693.4868197533789;694.4583225084442;15683.059016459049;15898.443554475538;15959.760603590956;16106;16260.43088491909;14541.854581454261;14678;14701.926490367587;14706.191159486223;14843.183624900626;11850.466728997217;11893.354209298039;11902.821457678141;11960.1627886048;12287.877121228763;3698.33429982603;3774.358359078936;3804.6766024887916;3836.76979381238;3904.4533765272886;103928;109682;110469;110877;111947.88052119456;9492.620295188253;10936;11476.368799716096;11716.297022178696;11745.412729363497;22179.889100554716;24108.87945560261;24116.51766964651;24158.275251742474;24189.51620967571;8071.515709057476;8168.7957801055445;8180.263776260165;8272.214139656722;8347.874781878276;4375.47494300686;4385.407969924083;4415.359772832921;4447.288433850568;4512.842050528216;3127.9218019549694;3652.0687224757517;3705.203381273039;3714.257148570297;3748.9437658435145;668.8963210702349;669.2404121322237;676.1649363036646;683.4566519616917;685.5886468119188;643.7038962077497;649.6394501051867;669.3373560175392;684.948628852838;699.7026263837848;9246.398984066014;9831.803363932824;10111.59553617847;10224.846627300596;10253.84619230712;677.4952660268132;702.0697575712202;703.7747920665338;704.5772536478072;706.6678661029308;4607.9078418431445;4902.240152776325;4905.656604037683;4916.262560615936;4920.975395123001;70518;88031.55984220166;100343;102385;103440;90633;91624.54187729019;91625.62561561582;91900;95216.52391738136;81967.18032819715;82294;82843;82957.17042829616;83611.16388836277;7969.481983671043;9568.56941437623;9585.568649410654;9590.08894155054;9596.568154432927;50610.98778024493;51379;51975.565375617305;52277.47722522726;53369.932601346976;66120.66939665271;67165;68614;68926;69730.30269697239;11687.240329378563;11865.703357416134;11973.102017348909;12255.69360765988;12362.44369003415;12507.172115811714;12809.487620495076;12902.096853220184;13286.80069798973;13327.466901323838;11847.76304473923;11920.880791191978;11933.940330298294;12046.879531204575;12129.332886691316;11769.764604708029;11867;11888.940555297515;11912.225705329125;12381.195222310522;11738;11809.704757381134;11857.16999810005;11912.344821034927;11953.581624643095;9946.950265248628;9975.850362244428;10025.498725063864;10235.897641023494;10752.408617525798;8565.443246188977;9357.859632105388;9862.651822867596;9874.160696340892;9928.652497162564 22:00:05 INFO - PROCESS | 1631 | __end_tp_report 22:00:05 INFO - PROCESS | 1631 | __start_cc_report 22:00:05 INFO - PROCESS | 1631 | _x_x_mozilla_cycle_collect,2359 22:00:05 INFO - PROCESS | 1631 | __end_cc_report 22:00:05 INFO - PROCESS | 1631 | __startTimestamp1486101605361__endTimestamp 22:00:05 INFO - PROCESS | 1631 | 22:00:05 INFO - PROCESS | 1631 | ------- Summary: start ------- 22:00:05 INFO - PROCESS | 1631 | Number of tests: 6 22:00:05 INFO - PROCESS | 1631 | 22:00:05 INFO - PROCESS | 1631 | [#0] dojo.html Cycles:165 Average:5566.40 Median:5698.12 stddev:660.45 (11.6%) stddev-sans-first:625.17 22:00:05 INFO - PROCESS | 1631 | Values: 2776.8 3283.9 3292.2 3310.1 3313.6 5302.0 5381.6 5526.6 5609.2 5621.2 5443.6 5468.8 5482.0 5499.0 5532.1 4949.5 4959.4 5114.3 5174.8 5186.3 4999.5 5136.5 5137.4 5147.0 5252.9 4887.4 4944.5 4966.8 5013.0 5032.8 4915.3 4951.2 4952.3 5014.7 5027.4 4862.3 4878.1 4903.7 4926.8 4929.1 4747.6 5370.8 5375.5 5378.5 5401.4 6044.3 6045.3 6091.6 6101.0 6118.0 6106.2 6153.4 6168.1 6169.6 6181.6 5679.0 5689.3 5692.8 5698.1 5728.3 6087.1 6106.3 6133.5 6166.2 6168.8 5002.8 5927.1 6030.8 6072.3 6135.5 6142.0 6149.4 6155.3 6157.4 6157.5 5845.1 5927.9 5981.9 6066.0 6073.7 6038.3 6069.4 6116.8 6127.9 6149.0 4901.2 4902.6 4922.2 4926.2 4940.7 4317.5 4321.8 4329.4 4332.8 4351.1 5435.6 5509.4 5521.4 5581.6 5617.2 6148.4 6194.8 6215.4 6224.2 6236.2 4471.8 5573.2 5653.9 5665.1 5682.4 5208.6 5211.4 5269.7 5334.5 5357.8 6155.7 6186.6 6202.7 6232.8 6261.8 6024.8 6119.0 6121.7 6194.4 6200.2 6183.0 6202.4 6205.3 6246.5 6268.0 5817.9 5864.4 6117.2 6129.6 6139.6 5942.9 6080.0 6120.9 6128.4 6197.3 5805.7 5961.3 5977.0 5982.7 6003.6 5782.2 5792.8 5922.1 5933.1 5936.3 4691.2 5599.8 5646.1 5699.7 5707.9 5768.7 5792.0 5811.9 5817.8 5822.0 5625.0 5642.0 5648.1 5673.6 5678.4 22:00:05 INFO - PROCESS | 1631 | 22:00:05 INFO - PROCESS | 1631 | [#1] ext.html Cycles:180 Average:69853.01 Median:33753.04 stddev:157595.78 (466.9%) stddev-sans-first:157951.72 22:00:05 INFO - PROCESS | 1631 | Values: 446.5 504.3 514.5 522.1 534.4 36778.3 39097.4 39116.4 39122.0 39201.6 39589.4 40153.8 40185.0 40271.8 40287.0 7614.7 8581.4 8973.7 9138.9 11080.4 11117.2 11228.8 11232.0 11234.9 11248.2 11212.3 11262.4 11265.7 11267.2 11271.9 8454.8 8632.7 11001.4 11160.3 11213.7 11098.1 11184.4 11195.9 11202.0 11204.4 38723.6 38933.0 38954.0 38994.8 38996.6 3577.3 3913.5 4006.0 4014.1 4175.0 24947.8 29171.3 30208.5 31187.7 31227.8 3055.5 3188.2 3230.0 3299.5 3301.8 390625.0 460315.0 473648.0 474387.0 476009.0 56575.4 57812.4 57850.4 64422.7 65564.0 67811.3 70637.0 70979.3 71426.0 71774.9 16253.9 16371.0 16371.4 16372.7 16381.8 12224.2 12327.9 12332.2 12339.3 12361.0 16847.6 20560.0 20657.1 20747.2 20775.7 7460.1 9033.3 9041.0 9120.3 9126.4 851.5 859.4 868.8 875.8 892.7 28685.9 28833.6 28863.0 29411.3 29508.4 898.3 965.6 1038.4 1059.3 1200.2 14883.3 15007.5 15040.5 15283.4 15295.7 613348.0 668183.0 684123.0 684920.0 847017.9 51472.7 51997.7 51998.0 52067.7 52088.2 1362.1 1376.5 1395.9 1400.6 1404.0 27600.4 27631.6 27689.6 28355.0 28359.9 44062.0 44464.8 44498.8 44569.0 44641.6 571068.0 577202.2 577492.0 577917.0 578191.0 21922.7 22355.8 22400.3 24183.5 24641.6 37109.0 39641.4 39933.0 40293.8 40352.8 33343.7 33490.8 33717.8 34192.0 34229.0 33680.7 33788.3 34279.0 34364.5 34481.0 32537.7 33086.7 33206.8 33265.2 33331.7 28477.7 29952.0 30121.2 30420.5 30468.9 22426.9 22630.3 22729.4 23042.9 23172.3 22:00:05 INFO - PROCESS | 1631 | 22:00:05 INFO - PROCESS | 1631 | [#2] jquery.html Cycles:180 Average:38249.79 Median:29195.21 stddev:111223.40 (381.0%) stddev-sans-first:111499.07 22:00:05 INFO - PROCESS | 1631 | Values: 384.0 402.6 404.5 411.0 433.4 11772.9 12107.0 20483.1 21745.2 21750.9 22106.0 22374.2 22448.4 23160.4 23282.0 6369.6 6681.2 6764.8 6790.3 6839.6 6516.4 6741.2 6790.0 6820.6 6890.6 6719.2 6790.4 6800.4 6809.7 6849.6 6536.2 6818.5 6857.5 6864.5 6873.8 6501.7 6528.4 6616.2 6704.7 6710.9 22794.5 27407.6 28395.9 29142.9 29247.6 2415.3 2738.4 2745.7 2956.2 2959.2 16115.3 17115.7 17347.4 17585.7 17783.0 1921.7 1962.0 1964.6 1965.7 1978.1 76617.6 99101.6 128106.0 129444.7 140970.6 16890.6 17359.5 22343.1 22442.4 22504.7 32563.3 33760.7 39463.5 39579.0 39684.0 8190.7 9836.1 10332.3 10722.1 10886.9 7968.0 8156.3 8671.5 8706.0 8892.4 10632.6 10749.6 10752.6 10870.5 10880.4 3593.7 3643.5 4014.5 4034.9 4064.4 900.4 1013.6 1023.5 1026.4 1071.7 21035.8 21293.5 21367.0 21445.1 21786.5 936.5 973.2 1019.2 1051.9 1054.3 8880.4 11129.4 11259.7 11265.9 11275.9 663392.0 669418.7 669620.0 669729.0 688490.0 103734.4 126735.0 129507.0 129507.4 131163.0 998.2 998.8 1034.2 1034.8 1038.2 18222.8 18323.0 18573.8 18634.5 18637.2 38739.3 40678.4 49962.8 54016.5 55675.7 73030.3 74728.3 80183.0 81576.7 83655.1 6459.9 6714.9 8316.1 8323.5 8435.4 9256.7 12194.1 12927.9 13018.9 13524.0 9398.5 9678.4 9689.0 10447.9 11311.9 8620.9 11010.3 11135.8 11498.4 11533.2 11225.4 11317.2 11657.6 11679.5 11779.2 8178.8 8239.5 8462.4 8627.0 8642.7 9789.0 11044.3 11451.7 11492.2 11608.9 22:00:05 INFO - PROCESS | 1631 | 22:00:05 INFO - PROCESS | 1631 | [#3] mootools.html Cycles:180 Average:5357.26 Median:5306.27 stddev:2923.83 (55.1%) stddev-sans-first:2917.02 22:00:05 INFO - PROCESS | 1631 | Values: 1413.8 1424.9 1429.6 1437.7 1438.1 8000.7 8292.4 8432.2 8437.6 8439.2 8088.8 8223.9 8324.9 8344.2 8432.6 2284.5 2328.4 2329.6 2331.3 2335.1 2238.3 2332.5 2344.7 2345.1 2345.7 2056.5 2126.9 2128.5 2233.7 2307.1 2288.4 2312.7 2314.1 2314.3 2317.5 2261.5 2338.2 2344.2 2347.1 2347.9 8262.8 8294.2 8318.1 8336.8 8341.5 4968.1 5120.2 5124.3 5126.1 5127.6 3289.8 3350.9 3484.9 3546.8 3556.4 1209.2 1213.4 1245.1 1267.0 1273.1 10592.8 10647.5 10666.4 10687.5 10691.0 3887.5 4244.2 4452.7 4468.0 4529.8 8609.2 8610.3 8619.6 8624.5 8628.1 5009.2 5080.5 5116.6 5119.5 5133.8 4431.7 4486.3 4586.8 4587.9 4640.4 3657.8 3659.4 3669.2 3690.2 3729.4 1136.6 1140.4 1145.3 1146.7 1148.6 722.7 749.9 765.3 780.7 842.9 5278.0 5334.5 5348.9 5576.1 5680.6 953.6 960.8 965.3 969.2 986.9 3639.5 3734.4 3754.6 3819.0 3865.6 9635.6 9852.1 9954.3 9976.1 9980.2 9911.5 9915.2 9927.4 9933.5 9949.4 7251.2 7511.8 7690.5 7691.1 7701.6 5363.2 5508.8 5558.9 5631.0 5765.8 9045.2 9048.5 9227.7 9284.0 9299.1 8981.0 9450.9 9456.5 9457.9 9466.3 6988.0 7131.6 7133.0 7139.1 7147.0 7301.5 7701.0 7706.8 7800.3 7805.0 7010.8 7082.3 7084.0 7129.1 7149.6 6863.2 6951.8 6951.9 6999.2 7013.8 6257.7 6359.6 6365.8 6377.2 6380.3 4125.6 4784.1 4931.0 4941.8 4974.1 4779.6 4829.8 4842.3 4880.8 5086.4 22:00:05 INFO - PROCESS | 1631 | 22:00:05 INFO - PROCESS | 1631 | [#4] prototype.html Cycles:180 Average:4482.57 Median:4988.04 stddev:1366.44 (27.4%) stddev-sans-first:1334.67 22:00:05 INFO - PROCESS | 1631 | Values: 353.5 362.4 370.5 372.6 379.8 5126.7 5372.6 5414.8 5419.3 5510.5 4680.1 5313.6 5338.3 5351.5 5378.2 3802.8 4049.0 4208.5 4213.5 4227.6 4050.9 4069.7 4078.4 4098.8 4105.4 3820.7 4045.6 4061.7 4079.3 4083.4 4073.9 4090.6 4099.8 4101.6 4105.0 3401.5 3468.2 3510.6 3512.1 3516.5 4381.7 4437.4 4819.5 4857.1 5183.4 4560.5 4649.9 4650.5 4682.3 4683.6 4407.8 4984.7 5620.9 5686.2 5706.9 3319.8 3547.9 3563.6 3864.2 3885.7 4519.1 5648.1 5817.9 5835.3 5850.7 3367.4 3743.5 3880.4 3922.6 3948.3 3657.3 3935.6 3953.7 3957.6 3958.5 4477.5 4624.6 4639.7 4646.2 4659.2 5081.0 5318.5 5463.6 5701.7 5705.1 1112.4 1274.2 1287.6 1287.8 1292.5 811.5 826.6 849.5 867.2 885.8 4369.0 4424.2 4999.7 5040.0 5048.4 4958.3 4980.5 4991.4 5561.8 5573.8 4776.9 5037.6 5040.6 5053.3 5065.0 2434.7 2478.8 2496.0 2909.9 2954.2 5133.4 5155.5 5686.7 5804.8 5829.5 5055.7 5171.0 5294.1 5511.8 5750.4 5353.4 5793.5 5793.7 5796.3 5819.6 4728.6 5110.7 5158.8 5433.1 5551.3 4694.4 4763.0 4777.7 5567.7 5680.4 5052.5 5728.7 5904.8 5926.9 5953.7 3892.5 3911.0 3921.3 3935.4 3936.5 4671.2 5805.5 5839.2 5853.0 5871.9 4384.9 5603.5 5635.4 5663.8 5668.6 5591.6 5639.8 5640.4 5657.2 5658.4 5248.4 5504.9 5526.3 5540.3 5579.2 4948.8 5673.4 5674.9 5713.3 5719.7 4165.7 5264.9 5286.5 5557.4 5580.3 22:00:05 INFO - PROCESS | 1631 | 22:00:05 INFO - PROCESS | 1631 | [#5] yui.html Cycles:180 Average:20563.15 Median:4910.96 stddev:29601.49 (602.8%) stddev-sans-first:29647.04 22:00:05 INFO - PROCESS | 1631 | Values: 720.9 726.4 730.6 773.7 834.7 16628.8 17301.5 17355.1 17370.4 17398.5 16508.6 16600.2 16651.3 16658.6 16679.7 841.8 861.6 875.3 876.7 879.5 867.2 884.3 885.4 888.4 891.8 927.0 932.1 932.7 933.0 934.0 922.3 923.1 925.1 927.5 927.6 690.2 691.7 692.8 693.5 694.5 15683.1 15898.4 15959.8 16106.0 16260.4 14541.9 14678.0 14701.9 14706.2 14843.2 11850.5 11893.4 11902.8 11960.2 12287.9 3698.3 3774.4 3804.7 3836.8 3904.5 103928.0 109682.0 110469.0 110877.0 111947.9 9492.6 10936.0 11476.4 11716.3 11745.4 22179.9 24108.9 24116.5 24158.3 24189.5 8071.5 8168.8 8180.3 8272.2 8347.9 4375.5 4385.4 4415.4 4447.3 4512.8 3127.9 3652.1 3705.2 3714.3 3748.9 668.9 669.2 676.2 683.5 685.6 643.7 649.6 669.3 684.9 699.7 9246.4 9831.8 10111.6 10224.8 10253.8 677.5 702.1 703.8 704.6 706.7 4607.9 4902.2 4905.7 4916.3 4921.0 70518.0 88031.6 100343.0 102385.0 103440.0 90633.0 91624.5 91625.6 91900.0 95216.5 81967.2 82294.0 82843.0 82957.2 83611.2 7969.5 9568.6 9585.6 9590.1 9596.6 50611.0 51379.0 51975.6 52277.5 53369.9 66120.7 67165.0 68614.0 68926.0 69730.3 11687.2 11865.7 11973.1 12255.7 12362.4 12507.2 12809.5 12902.1 13286.8 13327.5 11847.8 11920.9 11933.9 12046.9 12129.3 11769.8 11867.0 11888.9 11912.2 12381.2 11738.0 11809.7 11857.2 11912.3 11953.6 9947.0 9975.9 10025.5 10235.9 10752.4 8565.4 9357.9 9862.7 9874.2 9928.7 22:00:05 INFO - PROCESS | 1631 | -------- Summary: end -------- 22:00:05 INFO - PROCESS | 1631 | 22:00:05 INFO - TEST-INFO | 1631: exit 0 22:00:05 INFO - TEST-OK | dromaeo_css | took 1118374ms 22:00:05 INFO - TEST-START | kraken 22:00:05 INFO - operating with platform_type : mac_ 22:00:05 INFO - Initialising browser for kraken test... 22:00:05 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpGYy0xc/profile http://localhost:49235/getInfo.html) 22:00:08 INFO - PROCESS | 1641 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 22:00:08 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 22:00:08 INFO - PROCESS | 1641 | colorDepth:24 22:00:08 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 22:00:08 INFO - PROCESS | 1641 | __metrics 22:00:08 INFO - PROCESS | 1641 | 1486101608442 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 22:00:08 INFO - PROCESS | 1641 | 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" 22:00:08 INFO - PROCESS | 1641 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:00:09 INFO - TEST-INFO | 1641: exit 0 22:00:09 INFO - Browser initialized. 22:00:09 INFO - Running cycle 1/1 for kraken test... 22:00:09 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpGYy0xc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:00:21 INFO - PROCESS | 1642 | RSS: Main: 237010944 22:00:21 INFO - PROCESS | 1642 | 22:00:24 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 22:00:25 INFO - PROCESS | 1642 | RSS: Main: 288452608 22:00:25 INFO - PROCESS | 1642 | 22:00:27 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 22:00:28 INFO - PROCESS | 1642 | RSS: Main: 290115584 22:00:28 INFO - PROCESS | 1642 | 22:00:31 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 22:00:32 INFO - PROCESS | 1642 | RSS: Main: 382173184 22:00:32 INFO - PROCESS | 1642 | 22:00:34 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 22:00:34 INFO - PROCESS | 1642 | RSS: Main: 283983872 22:00:34 INFO - PROCESS | 1642 | 22:00:36 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:00:37 INFO - PROCESS | 1642 | RSS: Main: 287449088 22:00:37 INFO - PROCESS | 1642 | 22:00:40 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 22:00:40 INFO - PROCESS | 1642 | RSS: Main: 329506816 22:00:40 INFO - PROCESS | 1642 | 22:00:43 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 22:00:44 INFO - PROCESS | 1642 | RSS: Main: 330768384 22:00:44 INFO - PROCESS | 1642 | 22:00:47 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 22:00:47 INFO - PROCESS | 1642 | RSS: Main: 328425472 22:00:47 INFO - PROCESS | 1642 | 22:00:49 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:00:49 INFO - PROCESS | 1642 | RSS: Main: 305414144 22:00:49 INFO - PROCESS | 1642 | 22:00:51 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:00:52 INFO - PROCESS | 1642 | RSS: Main: 310956032 22:00:52 INFO - PROCESS | 1642 | 22:00:54 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:00:55 INFO - PROCESS | 1642 | RSS: Main: 312815616 22:00:55 INFO - PROCESS | 1642 | 22:00:58 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:00:59 INFO - PROCESS | 1642 | RSS: Main: 330506240 22:00:59 INFO - PROCESS | 1642 | 22:01:02 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:01:02 INFO - PROCESS | 1642 | RSS: Main: 324751360 22:01:02 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 22:01:04 INFO - PROCESS | 1642 | RSS: Main: 369893376 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | __start_tp_report 22:01:04 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 22:01:04 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 22:01:04 INFO - PROCESS | 1642 | |i|pagename|runs| 22:01:04 INFO - PROCESS | 1642 | |0;ai-astar;114;114;114;114;114;113;114;114;114;113 22:01:04 INFO - PROCESS | 1642 | |1;audio-beat-detection;140;109;108;165;142;106;110;109;128;109 22:01:04 INFO - PROCESS | 1642 | |2;audio-dft;185;182;182;184;185;186;179;184;183;181 22:01:04 INFO - PROCESS | 1642 | |3;audio-fft;78;58;58;87;58;57;58;57;74;58 22:01:04 INFO - PROCESS | 1642 | |4;audio-oscillator;89;84;69;57;82;92;117;90;68;82 22:01:04 INFO - PROCESS | 1642 | |5;imaging-gaussian-blur;86;84;86;84;86;84;87;83;86;84 22:01:04 INFO - PROCESS | 1642 | |6;imaging-darkroom;92;107;113;94;93;93;92;94;93;93 22:01:04 INFO - PROCESS | 1642 | |7;imaging-desaturate;85;85;85;86;86;86;86;85;85;84 22:01:04 INFO - PROCESS | 1642 | |8;json-parse-financial;60;59;58;58;59;60;59;60;59;61 22:01:04 INFO - PROCESS | 1642 | |9;json-stringify-tinderbox;64;74;75;75;73;69;69;70;70;72 22:01:04 INFO - PROCESS | 1642 | |10;stanford-crypto-aes;87;86;86;90;86;86;85;87;90;87 22:01:04 INFO - PROCESS | 1642 | |11;stanford-crypto-ccm;161;147;151;139;136;139;141;145;197;186 22:01:04 INFO - PROCESS | 1642 | |12;stanford-crypto-pbkdf2;177;175;174;173;173;241;234;215;171;174 22:01:04 INFO - PROCESS | 1642 | |13;stanford-crypto-sha256-iterative;68;69;66;74;74;85;66;67;67;67 22:01:04 INFO - PROCESS | 1642 | __end_tp_report 22:01:04 INFO - PROCESS | 1642 | __start_cc_report 22:01:04 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,1975 22:01:04 INFO - PROCESS | 1642 | __end_cc_report 22:01:04 INFO - PROCESS | 1642 | __startTimestamp1486101664828__endTimestamp 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | ------- Summary: start ------- 22:01:04 INFO - PROCESS | 1642 | Number of tests: 14 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:01:04 INFO - PROCESS | 1642 | Values: 114.0 114.0 114.0 114.0 114.0 113.0 114.0 114.0 114.0 113.0 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#1] audio-beat-detection Cycles:10 Average:122.60 Median:119.00 stddev:20.30 (17.1%) stddev-sans-first:20.53 22:01:04 INFO - PROCESS | 1642 | Values: 140.0 109.0 108.0 165.0 142.0 106.0 110.0 109.0 128.0 109.0 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#2] audio-dft Cycles:10 Average:183.10 Median:184.00 stddev:2.13 (1.2%) stddev-sans-first:2.15 22:01:04 INFO - PROCESS | 1642 | Values: 185.0 182.0 182.0 184.0 185.0 186.0 179.0 184.0 183.0 181.0 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#3] audio-fft Cycles:10 Average:64.30 Median:58.00 stddev:11.07 (19.1%) stddev-sans-first:10.57 22:01:04 INFO - PROCESS | 1642 | Values: 78.0 58.0 58.0 87.0 58.0 57.0 58.0 57.0 74.0 58.0 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#4] audio-oscillator Cycles:10 Average:83.00 Median:83.00 stddev:16.40 (19.8%) stddev-sans-first:17.26 22:01:04 INFO - PROCESS | 1642 | Values: 89.0 84.0 69.0 57.0 82.0 92.0 117.0 90.0 68.0 82.0 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#5] imaging-gaussian-blur Cycles:10 Average:85.00 Median:86.00 stddev:1.33 (1.6%) stddev-sans-first:1.36 22:01:04 INFO - PROCESS | 1642 | Values: 86.0 84.0 86.0 84.0 86.0 84.0 87.0 83.0 86.0 84.0 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#6] imaging-darkroom Cycles:10 Average:96.40 Median:93.00 stddev:7.34 (7.9%) stddev-sans-first:7.61 22:01:04 INFO - PROCESS | 1642 | Values: 92.0 107.0 113.0 94.0 93.0 93.0 92.0 94.0 93.0 93.0 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:0.67 (0.8%) stddev-sans-first:0.71 22:01:04 INFO - PROCESS | 1642 | Values: 85.0 85.0 85.0 86.0 86.0 86.0 86.0 85.0 85.0 84.0 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:59.50 stddev:0.95 (1.6%) stddev-sans-first:0.97 22:01:04 INFO - PROCESS | 1642 | Values: 60.0 59.0 58.0 58.0 59.0 60.0 59.0 60.0 59.0 61.0 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:72.50 stddev:3.41 (4.7%) stddev-sans-first:2.47 22:01:04 INFO - PROCESS | 1642 | Values: 64.0 74.0 75.0 75.0 73.0 69.0 69.0 70.0 70.0 72.0 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:87.00 stddev:1.70 (2.0%) stddev-sans-first:1.80 22:01:04 INFO - PROCESS | 1642 | Values: 87.0 86.0 86.0 90.0 86.0 86.0 85.0 87.0 90.0 87.0 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#11] stanford-crypto-ccm Cycles:10 Average:154.20 Median:149.00 stddev:21.09 (14.2%) stddev-sans-first:22.23 22:01:04 INFO - PROCESS | 1642 | Values: 161.0 147.0 151.0 139.0 136.0 139.0 141.0 145.0 197.0 186.0 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.70 Median:176.00 stddev:27.89 (15.8%) stddev-sans-first:29.14 22:01:04 INFO - PROCESS | 1642 | Values: 177.0 175.0 174.0 173.0 173.0 241.0 234.0 215.0 171.0 174.0 22:01:04 INFO - PROCESS | 1642 | 22:01:04 INFO - PROCESS | 1642 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:68.50 stddev:5.96 (8.7%) stddev-sans-first:6.27 22:01:04 INFO - PROCESS | 1642 | Values: 68.0 69.0 66.0 74.0 74.0 85.0 66.0 67.0 67.0 67.0 22:01:04 INFO - PROCESS | 1642 | -------- Summary: end -------- 22:01:04 INFO - PROCESS | 1642 | 22:01:05 INFO - TEST-INFO | 1642: exit 0 22:01:05 INFO - TEST-OK | kraken | took 59746ms 22:01:05 INFO - SUITE-END | took 1178s 22:01:05 INFO - Completed test suite (00:19:38) 22:01:05 INFO - javascript benchmark 22:01:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7348.967127886685, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2776.8195067320626, 3283.917902052447, 3292.2427841596423, 3310.13936376542, 3313.6189338226113, 5301.992621401931, 5381.623286369956, 5526.6131370804005, 5609.242752228448, 5621.241132447119, 5443.564514838813, 5468.808591699291, 5481.972590137054, 5498.9826882026755, 5532.059549876521, 4949.505049495057, 4959.429665588458, 5114.309568208281, 5174.844754657366, 5186.299849520305, 4999.450060493361, 5136.537711605954, 5137.434882162959, 5147.047796157723, 5252.894942101175, 4887.36464259644, 4944.505549445044, 4966.751662416883, 5013.0, 5032.84901452957, 4915.26271059344, 4951.158303088484, 4952.281919121743, 5014.674046186986, 5027.421846487664, 4862.294967229767, 4878.146324393217, 4903.730294833783, 4926.827561035347, 4929.112759703288, 4747.596454301389, 5370.785168593252, 5375.543078838304, 5378.46215378463, 5401.405845357008, 6044.335123136451, 6045.33501314855, 6091.573589848713, 6100.969495152496, 6118.01953385572, 6106.175666285084, 6153.384661533855, 6168.1056246843955, 6169.598976066542, 6181.567290289681, 5678.971605141948, 5689.34572524165, 5692.800751973661, 5698.116791897262, 5728.283964504437, 6087.1173679816175, 6106.339326077157, 6133.539984501181, 6166.1983942087245, 6168.784092556739, 5002.799888004512, 5927.051671732502, 6030.849228769316, 6072.271327440647, 6135.5398345124295, 6142.0, 6149.446549810538, 6155.292141403706, 6157.384261573897, 6157.507399408037, 5845.09401042839, 5927.940720592739, 5981.880362392728, 6066.029435290332, 6073.726682299307, 6038.275406951194, 6069.393060693984, 6116.785912493041, 6127.877442451126, 6148.9692551536955, 4901.166801643729, 4902.583280421133, 4922.187839006596, 4926.187179115477, 4940.6788558743565, 4317.481902171697, 4321.827126914888, 4329.437173167468, 4332.805023773938, 4351.086271882877, 5435.592330575223, 5509.393966663663, 5521.365043020024, 5581.637193582485, 5617.21359009739, 6148.354422785543, 6194.845128871814, 6215.378462153749, 6224.190855188796, 6236.158118654014, 4471.776411179428, 5573.164025396223, 5653.886922261614, 5665.065264231438, 5682.40334764852, 5208.609354298443, 5211.400688920747, 5269.736513174403, 5334.51989320963, 5357.7589008494715, 6155.692215389213, 6186.628802271968, 6202.72087756043, 6232.844178895564, 6261.812145635638, 6024.759009639565, 6119.0, 6121.724522396414, 6194.38056194377, 6200.224971878515, 6183.0107182850525, 6202.379762023762, 6205.286392064971, 6246.5002799775, 6267.96866015667, 5817.883642327045, 5864.354920958605, 6117.204763380732, 6129.632222066691, 6139.57023008403, 5942.851428714143, 6079.969600151971, 6120.877582448237, 6128.3503509891225, 6197.287311959182, 5805.651660900359, 5961.254843144607, 5976.970115149396, 5982.7307771149535, 6003.609765365237, 5782.190493330853, 5792.797252096157, 5922.111683247547, 5933.080372542263, 5936.317323507852, 4691.202495575692, 5599.776008959759, 5646.0683987141665, 5699.7435115419075, 5707.914381284367, 5768.711564421678, 5791.971040144772, 5811.94188058114, 5817.79637712695, 5822.0, 5624.971875140598, 5642.040853054908, 5648.068068768607, 5673.574481913791, 5678.3753787084215], "value": 5522.75437484276, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [446.4843106212324, 504.26125725811687, 514.4932241741886, 522.1462908145182, 534.4014703532044, 36778.26443471129, 39097.41353879691, 39116.41325380121, 39122.0, 39201.60798392022, 39589.40615890764, 40153.799231003875, 40184.995375115635, 40271.798641006826, 40287.0, 7614.73348432805, 8581.39930204886, 8973.685920992766, 9138.862917056233, 11080.390578518178, 11117.22179447439, 11228.831567526493, 11231.98912097911, 11234.943825280861, 11248.212625116244, 11212.327260364364, 11262.380569068699, 11265.662030139109, 11267.21129520934, 11271.887281127207, 8454.830903381899, 8632.697855575025, 11001.449927503592, 11160.330380177174, 11213.71965700864, 11098.056665183469, 11184.384858832842, 11195.944020279929, 11202.047825934806, 11204.383758893258, 38723.61276387228, 38933.0, 38954.0, 38994.80502597497, 38996.61003389958, 3577.3454628426493, 3913.471681323042, 4006.0185254612516, 4014.0767623446477, 4174.958250417518, 24947.750522494724, 29171.270718232, 30208.54687179672, 31187.688123118933, 31227.84386078055, 3055.511118221074, 3188.187012311869, 3230.0471360948577, 3299.5215693724276, 3301.818399988, 390625.0, 460315.0, 473647.99999999657, 474387.0, 476009.0, 56575.43424565784, 57812.42187578155, 57850.42149578535, 64422.67788661027, 65564.0, 67811.3218867805, 70637.0, 70979.29020709726, 71426.0, 71774.9233761494, 16253.918730406273, 16371.0, 16371.42700005494, 16372.67254654924, 16381.754273685905, 12224.205426647239, 12327.93836030814, 12332.19840710369, 12339.259644421363, 12361.0, 16847.57881052959, 20559.97200139987, 20657.070431830303, 20747.23648489865, 20775.68836467454, 7460.147187227142, 9033.277337813091, 9040.954795225982, 9120.315976301672, 9126.36115471924, 851.4550687559966, 859.4284800607651, 868.8218915122385, 875.7635438431588, 892.6920212526668, 28685.856570717013, 28833.56749648757, 28863.0, 29411.264718381783, 29508.409831803245, 898.2948385517337, 965.5896244096231, 1038.361407734238, 1059.332620449112, 1200.2318516149671, 14883.33025013877, 15007.549773506813, 15040.473583424528, 15283.395243499272, 15295.694086118216, 613348.0, 668183.0, 684123.0, 684920.0, 847017.9438336611, 51472.74263628658, 51997.70005749886, 51998.0, 52067.73966130145, 52088.21867671988, 1362.141850634114, 1376.5250988408982, 1395.8534353892694, 1400.6358346829898, 1404.0031577579828, 27600.447991039666, 27631.585526217525, 27689.646380057922, 28355.0, 28359.858200708866, 44062.0, 44464.777676111415, 44498.77750611226, 44569.0, 44641.55358446374, 571068.0, 577202.2279777316, 577492.0, 577917.0, 578191.0, 21922.671159932932, 22355.77644223537, 22400.32799016032, 24183.51632967366, 24641.630375544024, 37109.0, 39641.40537891992, 39933.0, 40293.79853100716, 40352.78941631756, 33343.66656333405, 33490.83254583712, 33717.76221399049, 34192.0, 34229.0, 33680.66319336873, 33788.32423351568, 34279.0, 34364.48453273253, 34481.0, 32537.674623253464, 33086.66913330836, 33206.83396583002, 33265.168370790925, 33331.666683333824, 28477.715222847506, 29952.0, 30121.246968825955, 30420.543691844196, 30468.94728242295, 22426.891559547446, 22630.321090367317, 22729.354145098405, 23042.884785575963, 23172.3048308551], "value": 19607.80838168028, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.95200599925005, 402.6175133623059, 404.5246834968911, 410.96712263018964, 433.4192182475482, 11772.941135294312, 12106.970907472853, 20483.078261478233, 21745.23891663792, 21750.89124554377, 22106.0, 22374.216902408418, 22448.438789030282, 23160.42098947527, 23282.0, 6369.617822930616, 6681.231658359278, 6764.830879228034, 6790.287019862917, 6839.62382068986, 6516.413522782948, 6741.191057073158, 6790.0493930849725, 6820.556663816861, 6890.552114112566, 6719.160104986877, 6790.388865002149, 6800.353966373212, 6809.727610895558, 6849.589024658537, 6536.215654121512, 6818.454523638097, 6857.5199736018, 6864.485163612724, 6873.828154296132, 6501.732162228367, 6528.412442880163, 6616.172978377703, 6704.698288577026, 6710.93289067108, 22794.544109117724, 27407.588886166723, 28395.85802070997, 29142.85428572865, 29247.56128658072, 2415.335782659765, 2738.3564862257354, 2745.679110563053, 2956.2018255071225, 2959.2010157257637, 16115.274812633459, 17115.657686846193, 17347.39284125062, 17585.7362139568, 17783.021933793636, 1921.6733155363622, 1961.9509512262166, 1964.607078584289, 1965.685490321556, 1978.1197367171615, 76617.61691191564, 99101.61394955697, 128105.99999999907, 129444.7055529442, 140970.59029409676, 16890.577735556708, 17359.479215623553, 22343.10083642876, 22442.438939026328, 22504.673270889874, 32563.348733025206, 33760.66239337575, 39463.46170190465, 39579.0, 39684.0, 8190.7133250335955, 9836.06557377048, 10332.328398654063, 10722.088622467021, 10886.891131088587, 7967.960160199162, 8156.265936065783, 8671.523066231417, 8706.0, 8892.377533572588, 10632.574697012033, 10749.62376316825, 10752.569897204181, 10870.456477176267, 10880.401577913133, 3593.748437609342, 3643.4716966039778, 4014.498187726534, 4034.9307433529552, 4064.4106604542767, 900.3742399032609, 1013.6452241715341, 1023.4575674892319, 1026.3892983674702, 1071.6999240212754, 21035.789642103384, 21293.46766330823, 21367.0, 21445.139874822682, 21786.455338616663, 936.4849332867033, 973.1825266775942, 1019.1999280564721, 1051.9105876000472, 1054.272551939175, 8880.422772519765, 11129.387883666313, 11259.662210133709, 11265.94367028176, 11275.887241127484, 663392.0, 669418.6529067324, 669620.0, 669729.0, 688490.0, 103734.44398334032, 126735.0, 129507.0, 129507.35246323896, 131163.0, 998.191464913437, 998.7702828349375, 1034.1933292032227, 1034.7723500829804, 1038.2161468091695, 18222.817771822643, 18323.0, 18573.81426185721, 18634.534136646693, 18637.161327740556, 38739.30994543213, 40678.38982415325, 49962.750186248835, 54016.45983540272, 55675.721621393255, 73030.26969730234, 74728.27445529928, 80182.99542511484, 81576.72407328812, 83655.12483377122, 6459.9031014533875, 6714.865702686016, 8316.126806685454, 8323.458975166595, 8435.367347448842, 9256.676016339396, 12194.146409751233, 12927.870721293042, 13018.934905325414, 13524.0, 9398.483118333757, 9678.419294842332, 9689.0, 10447.895521044693, 11311.943440282746, 8620.91379086201, 11010.339379637247, 11135.832962505407, 11498.367589782642, 11533.192676512563, 11225.4387280634, 11317.151213658844, 11657.591984280509, 11679.532818687157, 11779.17545771822, 8178.836423271382, 8239.546824924686, 8462.365322600706, 8627.0, 8642.740717778477, 9789.0, 11044.282121662067, 11451.656450306504, 11492.195546311677, 11608.941955290507], "value": 10951.08708348267, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.8303403591572, 1424.928753562323, 1429.6497358147253, 1437.7483940310449, 1438.1443041390369, 8000.679972801088, 8292.419530632858, 8432.241098301152, 8437.620307086181, 8439.198276163763, 8088.797780055502, 8223.876641850376, 8324.917760691116, 8344.165583441652, 8432.620532076056, 2284.497410569676, 2328.39461739948, 2329.6039673255586, 2331.3239160643393, 2335.065973610552, 2238.294937094818, 2332.486852892361, 2344.660024296476, 2345.0854166874933, 2345.659879317498, 2056.516718571135, 2126.851120421571, 2128.5104425982036, 2233.6649502574664, 2307.123293148601, 2288.4164538042774, 2312.710911136108, 2314.143766806276, 2314.305708287524, 2317.4553979814646, 2261.547690461914, 2338.1582630253097, 2344.2029709898716, 2347.0729062020405, 2347.8943447544902, 8262.83474330516, 8294.170582941719, 8318.085010648823, 8336.8332633347, 8341.499510029356, 4968.062455642303, 5120.15517439622, 5124.333836601218, 5126.051680439094, 5127.615428842814, 3289.7861638993627, 3350.8659653613827, 3484.9128771780656, 3546.804925729084, 3556.3598552260582, 1209.1982945411273, 1213.4175595714107, 1245.0848626259692, 1267.0243912187564, 1273.051576575448, 10592.788144237073, 10647.520861561248, 10666.360018398844, 10687.519061642244, 10691.0, 3887.5140607424073, 4244.214820258262, 4452.666050046195, 4468.0, 4529.773511324486, 8609.182127697859, 8610.268127209258, 8619.569021548898, 8624.482531048157, 8628.094050124771, 5009.223570346602, 5080.517350851663, 5116.616253780981, 5119.513646203604, 5133.79464821403, 4431.689781715313, 4486.3494793254795, 4586.839460618862, 4587.9082418351445, 4640.443146822403, 3657.76224545409, 3659.3779057559746, 3669.2478042001326, 3690.1881586051563, 3729.4032954727113, 1136.6419577832955, 1140.4297851074464, 1145.335705290946, 1146.7362506623356, 1148.5807680196835, 722.7181399254295, 749.9137599176056, 765.2615226306641, 780.6721177105629, 842.9367797415218, 5278.0, 5334.546563542065, 5348.9465105348445, 5576.1356989666665, 5680.630759000652, 953.6042542344987, 960.7694153403272, 965.2769805896442, 969.1762002298132, 986.9013098690074, 3639.454081887738, 3734.3838266686234, 3754.6245375462236, 3819.0282106608815, 3865.5747867734526, 9635.56639951204, 9852.064053914866, 9954.303198776017, 9976.102150806462, 9980.201583873273, 9911.504424778732, 9915.157211637092, 9927.404355738534, 9933.453660048623, 9949.35329203614, 7251.1661158966335, 7511.812204694927, 7690.500117492345, 7691.115521714963, 7701.614919254015, 5363.249145119608, 5508.834734957958, 5558.888822223614, 5631.014572449887, 5765.769369225184, 9045.231155351605, 9048.457092574467, 9227.677031303872, 9283.953580232055, 9299.070092990647, 8981.0, 9450.858237126586, 9456.527173641152, 9457.905420945703, 9466.337356385262, 6988.021676965331, 7131.6077615729555, 7133.0013798067275, 7139.107611548557, 7147.0, 7301.488895777245, 7701.037370328709, 7706.7687969361, 7800.336971357498, 7805.024371953506, 7010.789676309719, 7082.256363081802, 7084.0, 7129.073220481304, 7149.60677162761, 6863.210730765826, 6951.7914462566205, 6951.860962780817, 6999.160100787938, 7013.824654383681, 6257.687115644108, 6359.586626869239, 6365.777197798055, 6377.17096777416, 6380.330065343258, 4125.566815484331, 4784.114938736345, 4931.038447502702, 4941.825596737424, 4974.104661161025, 4779.593734532604, 4829.806807727651, 4842.346283251716, 4880.804767809248, 5086.3896332440345], "value": 4345.794528123877, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.5421628990457, 362.358625233337, 370.4696920036153, 372.56223936874176, 379.7835233916665, 5126.743662816858, 5372.629979355259, 5414.83755487335, 5419.3225846769155, 5510.492725809757, 4680.057776022955, 5313.555764348394, 5338.250800837562, 5351.545118664918, 5378.220158077076, 3802.8098595070273, 4049.0, 4208.452901122858, 4213.494380674308, 4227.556106608808, 4050.9189816203657, 4069.69477289203, 4078.4086307485395, 4098.815553300109, 4105.384192371138, 3820.6943444524372, 4045.554988951213, 4061.73598716084, 4079.3065178919655, 4083.4487344208596, 4073.877783666495, 4090.627436087092, 4099.831548008818, 4101.569335219819, 4104.958950410502, 3401.540791993074, 3468.1849765305133, 3510.6489351064943, 3512.1044133745986, 3516.472529120627, 4381.707396318094, 4437.383532421067, 4819.542143496362, 4857.125717370872, 5183.377994640668, 4560.521145279764, 4649.88375290617, 4650.488446270908, 4682.2508398656055, 4683.555062269079, 4407.815261124379, 4984.675996060245, 5620.92025950401, 5686.2039314496005, 5706.9429305707245, 3319.8340082995996, 3547.875824346161, 3563.625819288963, 3864.188520410717, 3885.7279990400684, 4519.118771839492, 5648.074588161229, 5817.912731309034, 5835.328937172195, 5850.6782126983, 3367.410703126943, 3743.494628225209, 3880.3985382265796, 3922.646961773454, 3948.2893079245437, 3657.341678497896, 3935.6064393560414, 3953.6639385651965, 3957.584453632385, 3958.4656071430563, 4477.537603346908, 4624.560666736722, 4639.652026097989, 4646.210144275481, 4659.184642687516, 5081.0092032054135, 5318.521333080042, 5463.590230732711, 5701.657900525982, 5705.058665320175, 1112.4382186995676, 1274.2160974635585, 1287.6008437384442, 1287.7637468030796, 1292.5217669462359, 811.5374236685151, 826.5990994367727, 849.5285116760134, 867.2151702709058, 885.818407226517, 4369.0, 4424.159409712144, 4999.675021123615, 5040.001199286492, 5048.368953880765, 4958.305837182797, 4980.509745127436, 4991.401031876126, 5561.833145005657, 5573.801632649, 4776.897941778817, 5037.622178336639, 5040.621953353513, 5053.292539044537, 5064.974675126601, 2434.7443518430664, 2478.8388754731245, 2495.9750402496106, 2909.894240188715, 2954.1728316071535, 5133.358330208724, 5155.510226528549, 5686.744096515667, 5804.82585522435, 5829.533637309005, 5055.671381360274, 5171.0, 5294.10883000994, 5511.807086751945, 5750.4249575042995, 5353.384360798558, 5793.507551858139, 5793.7392817323325, 5796.304443466811, 5819.563532734977, 4728.62171026324, 5110.66780659256, 5158.767855446439, 5433.103537916358, 5551.25058117149, 4694.43666759991, 4763.0, 4777.665563410528, 5567.721613919207, 5680.375158732619, 5052.46949070357, 5728.684922329146, 5904.793332233351, 5926.880817831738, 5953.732082056231, 3892.5134358205223, 3910.9635946474777, 3921.254961557409, 3935.35066713989, 3936.5472970607602, 4671.159191345455, 5805.477507024388, 5839.182514447895, 5853.0, 5871.941280587311, 4384.934225986676, 5603.495685388335, 5635.380108188178, 5663.773449061991, 5668.63952651369, 5591.6365436246515, 5639.800477813738, 5640.407757185437, 5657.236273103078, 5658.405867383865, 5248.448912864094, 5504.889902202013, 5526.309211348582, 5540.335159780852, 5579.163125531203, 4948.802047918043, 5673.404292549223, 5674.858128546654, 5713.257276554022, 5719.656820590778, 4165.708400412063, 5264.86837829042, 5286.524212820865, 5557.41647127062, 5580.274564306615], "value": 4059.358040679774, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.9495335326529, 726.3970904149555, 730.5908691132969, 773.7330620935778, 834.7078522517118, 16628.833711662894, 17301.480955571322, 17355.132243387827, 17370.392036278732, 17398.478045658652, 16508.587285317844, 16600.169991500436, 16651.33394664215, 16658.58353541162, 16679.749803752955, 841.7769291137853, 861.625193041026, 875.299760191844, 876.6931573949099, 879.4942907827992, 867.1848462445281, 884.3234925282164, 885.3802338363139, 888.3958907942597, 891.8305521950841, 927.0, 932.1191474057019, 932.6502561539422, 932.9906700933005, 933.9626414943394, 922.3174850610567, 923.1230331185401, 925.0610630210342, 927.508420537113, 927.5825878354767, 690.2166041542835, 691.7405972760215, 692.8337199072237, 693.4868197533789, 694.4583225084442, 15683.059016459049, 15898.443554475538, 15959.760603590956, 16106.0, 16260.43088491909, 14541.854581454261, 14678.0, 14701.926490367587, 14706.191159486223, 14843.183624900626, 11850.466728997217, 11893.354209298039, 11902.821457678141, 11960.1627886048, 12287.877121228763, 3698.33429982603, 3774.358359078936, 3804.6766024887916, 3836.76979381238, 3904.4533765272886, 103928.0, 109682.0, 110469.0, 110877.0, 111947.88052119456, 9492.620295188253, 10936.0, 11476.368799716096, 11716.297022178696, 11745.412729363497, 22179.889100554716, 24108.87945560261, 24116.51766964651, 24158.275251742474, 24189.51620967571, 8071.515709057476, 8168.7957801055445, 8180.263776260165, 8272.214139656722, 8347.874781878276, 4375.47494300686, 4385.407969924083, 4415.359772832921, 4447.288433850568, 4512.842050528216, 3127.9218019549694, 3652.0687224757517, 3705.203381273039, 3714.257148570297, 3748.9437658435145, 668.8963210702349, 669.2404121322237, 676.1649363036646, 683.4566519616917, 685.5886468119188, 643.7038962077497, 649.6394501051867, 669.3373560175392, 684.948628852838, 699.7026263837848, 9246.398984066014, 9831.803363932824, 10111.59553617847, 10224.846627300596, 10253.84619230712, 677.4952660268132, 702.0697575712202, 703.7747920665338, 704.5772536478072, 706.6678661029308, 4607.9078418431445, 4902.240152776325, 4905.656604037683, 4916.262560615936, 4920.975395123001, 70518.0, 88031.55984220166, 100343.0, 102385.0, 103440.0, 90633.0, 91624.54187729019, 91625.62561561582, 91900.0, 95216.52391738136, 81967.18032819715, 82294.0, 82843.0, 82957.17042829616, 83611.16388836277, 7969.481983671043, 9568.56941437623, 9585.568649410654, 9590.08894155054, 9596.568154432927, 50610.98778024493, 51379.0, 51975.565375617305, 52277.47722522726, 53369.932601346976, 66120.66939665271, 67165.0, 68614.0, 68926.0, 69730.30269697239, 11687.240329378563, 11865.703357416134, 11973.102017348909, 12255.69360765988, 12362.44369003415, 12507.172115811714, 12809.487620495076, 12902.096853220184, 13286.80069798973, 13327.466901323838, 11847.76304473923, 11920.880791191978, 11933.940330298294, 12046.879531204575, 12129.332886691316, 11769.764604708029, 11867.0, 11888.940555297515, 11912.225705329125, 12381.195222310522, 11738.0, 11809.704757381134, 11857.16999810005, 11912.344821034927, 11953.581624643095, 9946.950265248628, 9975.850362244428, 10025.498725063864, 10235.897641023494, 10752.408617525798, 8565.443246188977, 9357.859632105388, 9862.651822867596, 9874.160696340892, 9928.652497162564], "value": 7529.026214418045, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1466.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 113.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 109.0, 108.0, 165.0, 142.0, 106.0, 110.0, 109.0, 128.0, 109.0], "value": 122.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 182.0, 184.0, 185.0, 186.0, 179.0, 184.0, 183.0, 181.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 58.0, 87.0, 58.0, 57.0, 58.0, 57.0, 74.0, 58.0], "value": 64.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 69.0, 57.0, 82.0, 92.0, 117.0, 90.0, 68.0, 82.0], "value": 83.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 86.0, 84.0, 87.0, 83.0, 86.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 107.0, 113.0, 94.0, 93.0, 93.0, 92.0, 94.0, 93.0, 93.0], "value": 96.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 86.0, 86.0, 86.0, 86.0, 85.0, 85.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 58.0, 59.0, 60.0, 59.0, 60.0, 59.0, 61.0], "value": 59.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 75.0, 75.0, 73.0, 69.0, 69.0, 70.0, 70.0, 72.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 90.0, 86.0, 86.0, 85.0, 87.0, 90.0, 87.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 147.0, 151.0, 139.0, 136.0, 139.0, 141.0, 145.0, 197.0, 186.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 175.0, 174.0, 173.0, 173.0, 241.0, 234.0, 215.0, 171.0, 174.0], "value": 190.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 66.0, 74.0, 74.0, 85.0, 66.0, 67.0, 67.0, 67.0], "value": 70.3, "unit": "score"}]}]} 22:01:06 INFO - Return code: 0 22:01:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:01:06 ERROR - # TBPL SUCCESS # 22:01:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:01:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:01:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:01:06 INFO - 2017-02-02 22:01:06.127 screenresolution[1646:9772] starting screenresolution argv=screenresolution get 22:01:06 INFO - 2017-02-02 22:01:06.130 screenresolution[1646:9772] Display 0: 1600x1200x32@60 22:01:06 INFO - 2017-02-02 22:01:06.136 screenresolution[1647:9775] starting screenresolution argv=screenresolution list 22:01:06 INFO - Available Modes on Display 0 22:01:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:01:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:01:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:01:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:01:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:01:06 INFO - Intel Iris: 22:01:06 INFO - Chipset Model: Intel Iris 22:01:06 INFO - Type: GPU 22:01:06 INFO - Bus: Built-In 22:01:06 INFO - VRAM (Dynamic, Max): 1536 MB 22:01:06 INFO - Vendor: Intel (0x8086) 22:01:06 INFO - Device ID: 0x0a2e 22:01:06 INFO - Revision ID: 0x0009 22:01:06 INFO - Displays: 22:01:06 INFO - Display: 22:01:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:01:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:01:06 INFO - Display Serial Number: 1600x1200 60 22:01:06 INFO - Main Display: Yes 22:01:06 INFO - Mirror: Off 22:01:06 INFO - Online: Yes 22:01:06 INFO - Rotation: Supported 22:01:06 INFO - Return code: 0 22:01:06 INFO - Running post-action listener: _resource_record_post_action 22:01:06 INFO - [mozharness: 2017-02-03 06:01:06.373687Z] Finished run-tests step (success) 22:01:06 INFO - Running post-run listener: _resource_record_post_run 22:01:06 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 38867968; Write bytes: 554462208; Read time: 377; Write time: 1617 22:01:06 INFO - TinderboxPrint: CPU usage
53.6% 22:01:06 INFO - TinderboxPrint: I/O read bytes / time
38,867,968 / 377 22:01:06 INFO - TinderboxPrint: I/O write bytes / time
554,462,208 / 1,617 22:01:06 INFO - TinderboxPrint: CPU idle
2,228.9 (46.4%) 22:01:06 INFO - TinderboxPrint: CPU system
957.3 (19.9%) 22:01:06 INFO - TinderboxPrint: CPU user
1,619.5 (33.7%) 22:01:06 INFO - TinderboxPrint: Swap in / out
290,869,248 / 0 22:01:06 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250884608; Write bytes: 244659712; Read time: 18071; Write time: 475 22:01:06 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34456576; Write bytes: 298436096; Read time: 343; Write time: 1106 22:01:06 INFO - Running post-run listener: _upload_blobber_files 22:01:06 INFO - Blob upload gear active. 22:01:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:01:06 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 22:01:06 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:01:06 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:03:36 INFO - Traceback (most recent call last): 22:03:36 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 22:03:36 INFO - main() 22:03:36 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 22:03:36 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 22:03:36 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 68, in get_server_whitelist 22:03:36 INFO - response = requests.get(url, verify=cert.where()) 22:03:36 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/api.py", line 55, in get 22:03:36 INFO - return request('get', url, **kwargs) 22:03:36 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/api.py", line 44, in request 22:03:36 INFO - return session.request(method=method, url=url, **kwargs) 22:03:36 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/sessions.py", line 335, in request 22:03:36 INFO - resp = self.send(prep, **send_kwargs) 22:03:36 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/sessions.py", line 438, in send 22:03:36 INFO - r = adapter.send(request, **kwargs) 22:03:36 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/adapters.py", line 327, in send 22:03:36 INFO - raise ConnectionError(e) 22:03:36 INFO - requests.exceptions.ConnectionError: HTTPSConnectionPool(host='blobupload.elasticbeanstalk.com', port=443): Max retries exceeded with url: /blobs/whitelist (Caused by : [Errno 60] Operation timed out) 22:03:36 ERROR - Return code: 1 22:03:36 INFO - Setting buildbot property blobber_files to {} 22:03:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:03:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:03:36 INFO - Contents: 22:03:36 INFO - blobber_files:{} 22:03:36 INFO - Running post-run listener: copy_logs_to_upload_dir 22:03:36 INFO - Copying logs to upload dir... 22:03:36 INFO - mkdir: /builds/slave/test/build/upload/logs 22:03:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1408.918765 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 23 mins, 28 secs) (at 2017-02-02 22:03:37.037049) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 22:03:37.040774) ========= 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.uEHYKk24fD/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.5DUhapBxvX/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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:{} build_url:https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010824 build_url: 'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 22:03:37.075391) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 22:03:37.075759) ========= 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.uEHYKk24fD/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.5DUhapBxvX/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.005129 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 22:03:37.124509) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 22:03:37.124831) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 22:03:37.125153) ========= ========= Total master_lag: 0.18 =========