builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0339 starttime: 1492704544.1 results: success (0) buildid: 20170420075327 builduid: c87fefb306284369aaf91d4886814dbf revision: c1ef9597ea513c00df914df119d146c45a5cc784 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 09:09:04.103273) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 09:09:04.103693) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 09:09:04.103967) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 09:09:04.130417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 09:09:04.130829) ========= 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.rOsvWri6To/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.TPgfIipPMy/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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-04-20 09:09:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2017-04-20 09:09:04 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095214 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 09:09:04.250570) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 09:09:04.250924) ========= 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.rOsvWri6To/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.TPgfIipPMy/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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.027633 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 09:09:04.300394) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-20 09:09:04.300790) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c1ef9597ea513c00df914df119d146c45a5cc784 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c1ef9597ea513c00df914df119d146c45a5cc784 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rOsvWri6To/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.TPgfIipPMy/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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-04-20 09:09:04,408 truncating revision to first 12 chars 2017-04-20 09:09:04,409 Setting DEBUG logging. 2017-04-20 09:09:04,409 attempt 1/10 2017-04-20 09:09:04,409 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c1ef9597ea51?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-20 09:09:05,901 unpacking tar archive at: mozilla-beta-c1ef9597ea51/testing/mozharness/ program finished with exit code 0 elapsedTime=1.766644 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-20 09:09:06.091304) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 09:09:06.091672) ========= script_repo_revision: c1ef9597ea513c00df914df119d146c45a5cc784 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 09:09:06.092151) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 09:09:06.092428) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 09:09:06.112882) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-04-20 09:09:06.113301) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rOsvWri6To/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.TPgfIipPMy/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:09:06 INFO - MultiFileLogger online at 20170420 09:09:06 in /builds/slave/test 09:09:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:09:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:09:06 INFO - {'append_to_log': False, 09:09:06 INFO - 'base_work_dir': '/builds/slave/test', 09:09:06 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:09:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:09:06 INFO - 'branch': 'Mozilla-Beta', 09:09:06 INFO - 'buildbot_json_path': 'buildprops.json', 09:09:06 INFO - 'config_files': ('talos/mac_config.py',), 09:09:06 INFO - 'default_actions': ('clobber', 09:09:06 INFO - 'read-buildbot-config', 09:09:06 INFO - 'download-and-extract', 09:09:06 INFO - 'populate-webroot', 09:09:06 INFO - 'create-virtualenv', 09:09:06 INFO - 'install', 09:09:06 INFO - 'run-tests'), 09:09:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:09:06 INFO - 'download_minidump_stackwalk': True, 09:09:06 INFO - 'download_symbols': 'ondemand', 09:09:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:09:06 INFO - 'tooltool.py': '/tools/tooltool.py', 09:09:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:09:06 INFO - '/tools/misc-python/virtualenv.py')}, 09:09:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:09:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:09:06 INFO - 'gecko_profile': False, 09:09:06 INFO - 'gecko_profile_interval': 0, 09:09:06 INFO - 'installer_path': 'installer.exe', 09:09:06 INFO - 'log_level': 'info', 09:09:06 INFO - 'log_name': 'talos', 09:09:06 INFO - 'log_to_console': True, 09:09:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:09:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:09:06 INFO - 'opt_config_files': (), 09:09:06 INFO - 'pip_index': False, 09:09:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:09:06 INFO - 'cmd': ('bash', 09:09:06 INFO - '-c', 09:09:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:09:06 INFO - 'enabled': True, 09:09:06 INFO - 'halt_on_failure': False, 09:09:06 INFO - 'name': 'check_screen_resolution'},), 09:09:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:09:06 INFO - 'cmd': ('bash', 09:09:06 INFO - '-c', 09:09:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:09:06 INFO - 'enabled': True, 09:09:06 INFO - 'halt_on_failure': False, 09:09:06 INFO - 'name': 'check_screen_resolution'},), 09:09:06 INFO - 'run_cmd_checks_enabled': True, 09:09:06 INFO - 'suite': 'dromaeojs-e10s', 09:09:06 INFO - 'system_bits': '32', 09:09:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:09:06 INFO - 'title': 't-yosemite-r7-0339', 09:09:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:09:06 INFO - 'use_talos_json': True, 09:09:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:09:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:09:06 INFO - 'work_dir': 'build'} 09:09:06 INFO - [mozharness: 2017-04-20 16:09:06.331074Z] Running clobber step. 09:09:06 INFO - Running pre-action listener: _resource_record_pre_action 09:09:06 INFO - Running main action method: clobber 09:09:06 INFO - rmtree: /builds/slave/test/build 09:09:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:09:08 INFO - Running post-action listener: _resource_record_post_action 09:09:08 INFO - [mozharness: 2017-04-20 16:09:08.897177Z] Finished clobber step (success) 09:09:08 INFO - [mozharness: 2017-04-20 16:09:08.897417Z] Running read-buildbot-config step. 09:09:08 INFO - Running pre-action listener: _resource_record_pre_action 09:09:08 INFO - Running main action method: read_buildbot_config 09:09:08 INFO - Using buildbot properties: 09:09:08 INFO - { 09:09:08 INFO - "project": "", 09:09:08 INFO - "product": "firefox", 09:09:08 INFO - "script_repo_revision": "production", 09:09:08 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:09:08 INFO - "repository": "", 09:09:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:09:08 INFO - "buildid": "20170420075327", 09:09:08 INFO - "pgo_build": "False", 09:09:08 INFO - "basedir": "/builds/slave/test", 09:09:08 INFO - "buildnumber": 23, 09:09:08 INFO - "slavename": "t-yosemite-r7-0339", 09:09:08 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:09:08 INFO - "platform": "yosemite_r7", 09:09:08 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:09:08 INFO - "branch": "mozilla-beta", 09:09:08 INFO - "repo_path": "releases/mozilla-beta", 09:09:08 INFO - "revision": "c1ef9597ea513c00df914df119d146c45a5cc784", 09:09:08 INFO - "stage_platform": "macosx64", 09:09:08 INFO - "builduid": "c87fefb306284369aaf91d4886814dbf", 09:09:08 INFO - "slavebuilddir": "test" 09:09:08 INFO - } 09:09:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:09:08 INFO - Running post-action listener: _resource_record_post_action 09:09:08 INFO - [mozharness: 2017-04-20 16:09:08.901881Z] Finished read-buildbot-config step (success) 09:09:08 INFO - [mozharness: 2017-04-20 16:09:08.902070Z] Running download-and-extract step. 09:09:08 INFO - Running pre-action listener: _resource_record_pre_action 09:09:08 INFO - Running main action method: download_and_extract 09:09:08 INFO - mkdir: /builds/slave/test/build/tests 09:09:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:08 INFO - trying https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:09:08 INFO - Downloading https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:09:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 09:09:10 INFO - Downloaded 1165 bytes. 09:09:10 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:09:10 INFO - Using the following test package requirements: 09:09:10 INFO - {u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:09:10 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:09:10 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:09:10 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:09:10 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:09:10 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:09:10 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:09:10 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:09:10 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:09:10 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:09:10 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:09:10 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:09:10 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:09:10 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:09:10 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:09:10 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:09:10 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:09:10 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:09:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:09:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:09:10 INFO - Fetch https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:09:11 INFO - Expected file size: 51221017 09:09:11 INFO - Obtained file size: 51221017 09:09:27 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:09:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:09:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:09:27 INFO - Fetch https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:09:29 INFO - Expected file size: 51221017 09:09:29 INFO - Obtained file size: 51221017 09:09:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:09:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:09:44 INFO - Fetch https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:09:45 INFO - Expected file size: 14404424 09:09:45 INFO - Obtained file size: 14404424 09:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:45 INFO - trying https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:09:45 INFO - Downloading https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:09:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 09:09:47 INFO - Downloaded 59225866 bytes. 09:09:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:09:47 INFO - mkdir: /builds/slave/test/properties 09:09:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:09:47 INFO - Writing to file /builds/slave/test/properties/build_url 09:09:47 INFO - Contents: 09:09:47 INFO - build_url:https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:09:48 INFO - Running post-action listener: _resource_record_post_action 09:09:48 INFO - Running post-action listener: set_extra_try_arguments 09:09:48 INFO - [mozharness: 2017-04-20 16:09:48.996832Z] Finished download-and-extract step (success) 09:09:48 INFO - [mozharness: 2017-04-20 16:09:48.996956Z] Running populate-webroot step. 09:09:48 INFO - Running pre-action listener: _resource_record_pre_action 09:09:48 INFO - Running main action method: populate_webroot 09:09:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:09:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:09:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:09:49 INFO - '--tppagecycles', 09:09:49 INFO - '7'], 09:09:49 INFO - 'tests': ['tsvgm']}}, 09:09:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:09:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:09:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:09:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:09:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:09:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:09:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:09:49 INFO - 'talos_options': ['--disable-e10s'], 09:09:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:09:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:09:49 INFO - 'talos_options': ['--disable-e10s'], 09:09:49 INFO - 'tests': ['damp', 'tps']}, 09:09:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:09:49 INFO - 'tests': ['damp', 'tps']}, 09:09:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:09:49 INFO - 'tests': ['dromaeo_dom']}, 09:09:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:09:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:09:49 INFO - 'tests': ['basic_compositor_video']}, 09:09:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:09:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:09:49 INFO - 'tests': ['a11yr', 09:09:49 INFO - 'ts_paint', 09:09:49 INFO - 'tpaint', 09:09:49 INFO - 'sessionrestore', 09:09:49 INFO - 'sessionrestore_no_auto_restore', 09:09:49 INFO - 'tabpaint']}, 09:09:49 INFO - 'other-e10s': {'tests': ['a11yr', 09:09:49 INFO - 'ts_paint', 09:09:49 INFO - 'tpaint', 09:09:49 INFO - 'sessionrestore', 09:09:49 INFO - 'sessionrestore_no_auto_restore', 09:09:49 INFO - 'tabpaint']}, 09:09:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:09:49 INFO - 'ts_paint', 09:09:49 INFO - 'tpaint', 09:09:49 INFO - 'sessionrestore', 09:09:49 INFO - 'sessionrestore_no_auto_restore', 09:09:49 INFO - 'tabpaint']}, 09:09:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:09:49 INFO - 'ts_paint', 09:09:49 INFO - 'tpaint', 09:09:49 INFO - 'sessionrestore', 09:09:49 INFO - 'sessionrestore_no_auto_restore', 09:09:49 INFO - 'tabpaint']}, 09:09:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:09:49 INFO - 'tests': ['a11yr', 09:09:49 INFO - 'ts_paint', 09:09:49 INFO - 'tpaint', 09:09:49 INFO - 'sessionrestore', 09:09:49 INFO - 'sessionrestore_no_auto_restore', 09:09:49 INFO - 'tabpaint']}, 09:09:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:09:49 INFO - 'tests': ['a11yr', 09:09:49 INFO - 'ts_paint', 09:09:49 INFO - 'tpaint', 09:09:49 INFO - 'sessionrestore', 09:09:49 INFO - 'sessionrestore_no_auto_restore', 09:09:49 INFO - 'tabpaint']}, 09:09:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:09:49 INFO - 'tests': ['tsvgx', 09:09:49 INFO - 'tsvgr_opacity', 09:09:49 INFO - 'tart', 09:09:49 INFO - 'tscrollx', 09:09:49 INFO - 'cart', 09:09:49 INFO - 'tsvg_static']}, 09:09:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:09:49 INFO - 'tsvgr_opacity', 09:09:49 INFO - 'tart', 09:09:49 INFO - 'tscrollx', 09:09:49 INFO - 'cart', 09:09:49 INFO - 'tsvg_static']}, 09:09:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:09:49 INFO - 'talos_options': ['--disable-e10s'], 09:09:49 INFO - 'tests': ['tp5o']}, 09:09:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:09:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:09:49 INFO - 'talos_options': ['--disable-e10s', 09:09:49 INFO - '--xperf_path', 09:09:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:49 INFO - 'tests': ['tp5n']}, 09:09:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:09:49 INFO - 'talos_options': ['--xperf_path', 09:09:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:49 INFO - 'tests': ['tp5n']}}, 09:09:49 INFO - 'talos.zip': {'path': '', 09:09:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:09:49 INFO - Running post-action listener: _resource_record_post_action 09:09:49 INFO - [mozharness: 2017-04-20 16:09:49.016136Z] Finished populate-webroot step (success) 09:09:49 INFO - [mozharness: 2017-04-20 16:09:49.016273Z] Running create-virtualenv step. 09:09:49 INFO - Running pre-action listener: _resource_record_pre_action 09:09:49 INFO - Running main action method: create_virtualenv 09:09:49 INFO - Creating virtualenv /builds/slave/test/build/venv 09:09:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:09:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:09:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:09:49 INFO - Using real prefix '/tools/python27' 09:09:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:09:49 INFO - Installing distribute.............................................................................................................................................................................................done. 09:09:52 INFO - Installing pip.................done. 09:09:52 INFO - Return code: 0 09:09:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:09:52 INFO - Reading from file tmpfile_stdout 09:09:52 INFO - Output received: 09:09:52 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:09:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:53 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 0x108db6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108db9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd673556b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b94d30>, '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 0x108abbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b89be8>, '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.rOsvWri6To/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.TPgfIipPMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:09:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rOsvWri6To/Render', 09:09:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:53 INFO - 'HOME': '/Users/cltbld', 09:09:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:53 INFO - 'LOGNAME': 'cltbld', 09:09:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:53 INFO - 'MOZ_NO_REMOTE': '1', 09:09:53 INFO - 'NO_EM_RESTART': '1', 09:09:53 INFO - 'PAGER': '/bin/cat', 09:09:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:53 INFO - 'PWD': '/builds/slave/test', 09:09:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:53 INFO - 'SHELL': '/bin/bash', 09:09:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TPgfIipPMy/Listeners', 09:09:53 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 09:09:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:53 INFO - 'USER': 'cltbld', 09:09:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:53 INFO - 'XPC_FLAGS': '0x0', 09:09:53 INFO - 'XPC_SERVICE_NAME': '0', 09:09:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:09:53 INFO - Cleaning up... 09:09:53 INFO - Return code: 0 09:09:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:09: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')]} 09:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09: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')]} 09:09:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:53 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 0x108db6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108db9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd673556b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b94d30>, '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 0x108abbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b89be8>, '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.rOsvWri6To/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.TPgfIipPMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:09:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rOsvWri6To/Render', 09:09:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:53 INFO - 'HOME': '/Users/cltbld', 09:09:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:53 INFO - 'LOGNAME': 'cltbld', 09:09:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:53 INFO - 'MOZ_NO_REMOTE': '1', 09:09:53 INFO - 'NO_EM_RESTART': '1', 09:09:53 INFO - 'PAGER': '/bin/cat', 09:09:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:53 INFO - 'PWD': '/builds/slave/test', 09:09:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:53 INFO - 'SHELL': '/bin/bash', 09:09:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TPgfIipPMy/Listeners', 09:09:53 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 09:09:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:53 INFO - 'USER': 'cltbld', 09:09:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:53 INFO - 'XPC_FLAGS': '0x0', 09:09:53 INFO - 'XPC_SERVICE_NAME': '0', 09:09:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:53 INFO - Downloading/unpacking psutil>=3.1.1 09:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:09:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:57 INFO - Installing collected packages: psutil 09:09:57 INFO - Running setup.py install for psutil 09:09:57 INFO - building 'psutil._psutil_osx' extension 09:09:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:09:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:09:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:09:57 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:09:57 INFO - building 'psutil._psutil_posix' extension 09:09:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:09:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:09:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:09:57 INFO - ^ 09:09:57 INFO - 1 warning generated. 09:09:57 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:09:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:58 INFO - Successfully installed psutil 09:09:58 INFO - Cleaning up... 09:09:58 INFO - Return code: 0 09:09:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108db6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108db9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd673556b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b94d30>, '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 0x108abbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b89be8>, '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.rOsvWri6To/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.TPgfIipPMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:09:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rOsvWri6To/Render', 09:09:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:58 INFO - 'HOME': '/Users/cltbld', 09:09:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:58 INFO - 'LOGNAME': 'cltbld', 09:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:58 INFO - 'MOZ_NO_REMOTE': '1', 09:09:58 INFO - 'NO_EM_RESTART': '1', 09:09:58 INFO - 'PAGER': '/bin/cat', 09:09:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:58 INFO - 'PWD': '/builds/slave/test', 09:09:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:58 INFO - 'SHELL': '/bin/bash', 09:09:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TPgfIipPMy/Listeners', 09:09:58 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 09:09:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:58 INFO - 'USER': 'cltbld', 09:09:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:58 INFO - 'XPC_FLAGS': '0x0', 09:09:58 INFO - 'XPC_SERVICE_NAME': '0', 09:09:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:10:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:10:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:10:01 INFO - Installing collected packages: mozsystemmonitor 09:10:01 INFO - Running setup.py install for mozsystemmonitor 09:10:01 INFO - Successfully installed mozsystemmonitor 09:10:01 INFO - Cleaning up... 09:10:01 INFO - Return code: 0 09:10:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108db6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108db9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd673556b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b94d30>, '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 0x108abbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b89be8>, '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.rOsvWri6To/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.TPgfIipPMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:10:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rOsvWri6To/Render', 09:10:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:02 INFO - 'HOME': '/Users/cltbld', 09:10:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:02 INFO - 'LOGNAME': 'cltbld', 09:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:02 INFO - 'MOZ_NO_REMOTE': '1', 09:10:02 INFO - 'NO_EM_RESTART': '1', 09:10:02 INFO - 'PAGER': '/bin/cat', 09:10:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:02 INFO - 'PWD': '/builds/slave/test', 09:10:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:02 INFO - 'SHELL': '/bin/bash', 09:10:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TPgfIipPMy/Listeners', 09:10:02 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 09:10:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:02 INFO - 'USER': 'cltbld', 09:10:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:02 INFO - 'XPC_FLAGS': '0x0', 09:10:02 INFO - 'XPC_SERVICE_NAME': '0', 09:10:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:02 INFO - Downloading/unpacking jsonschema==2.5.1 09:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:10:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:05 INFO - Downloading functools32-3.2.3-2.tar.gz 09:10:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:10:05 INFO - warning: no files found matching '*.txt' 09:10:05 INFO - no previously-included directories found matching 'build' 09:10:05 INFO - no previously-included directories found matching 'dist' 09:10:05 INFO - no previously-included directories found matching '.git*' 09:10:05 INFO - Installing collected packages: jsonschema, functools32 09:10:05 INFO - Running setup.py install for functools32 09:10:06 INFO - warning: no files found matching '*.txt' 09:10:06 INFO - no previously-included directories found matching 'build' 09:10:06 INFO - no previously-included directories found matching 'dist' 09:10:06 INFO - no previously-included directories found matching '.git*' 09:10:06 INFO - Successfully installed jsonschema functools32 09:10:06 INFO - Cleaning up... 09:10:06 INFO - Return code: 0 09:10:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:06 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 0x108db6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108db9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd673556b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b94d30>, '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 0x108abbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b89be8>, '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.rOsvWri6To/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.TPgfIipPMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rOsvWri6To/Render', 09:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:06 INFO - 'HOME': '/Users/cltbld', 09:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:06 INFO - 'LOGNAME': 'cltbld', 09:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:06 INFO - 'MOZ_NO_REMOTE': '1', 09:10:06 INFO - 'NO_EM_RESTART': '1', 09:10:06 INFO - 'PAGER': '/bin/cat', 09:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:06 INFO - 'PWD': '/builds/slave/test', 09:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:06 INFO - 'SHELL': '/bin/bash', 09:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TPgfIipPMy/Listeners', 09:10:06 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 09:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:06 INFO - 'USER': 'cltbld', 09:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:06 INFO - 'XPC_FLAGS': '0x0', 09:10:06 INFO - 'XPC_SERVICE_NAME': '0', 09:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:10:06 INFO - Cleaning up... 09:10:06 INFO - Return code: 0 09:10:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:06 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 0x108db6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108db9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd673556b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b94d30>, '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 0x108abbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b89be8>, '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.rOsvWri6To/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.TPgfIipPMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rOsvWri6To/Render', 09:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:06 INFO - 'HOME': '/Users/cltbld', 09:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:06 INFO - 'LOGNAME': 'cltbld', 09:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:06 INFO - 'MOZ_NO_REMOTE': '1', 09:10:06 INFO - 'NO_EM_RESTART': '1', 09:10:06 INFO - 'PAGER': '/bin/cat', 09:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:06 INFO - 'PWD': '/builds/slave/test', 09:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:06 INFO - 'SHELL': '/bin/bash', 09:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TPgfIipPMy/Listeners', 09:10:06 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 09:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:06 INFO - 'USER': 'cltbld', 09:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:06 INFO - 'XPC_FLAGS': '0x0', 09:10:06 INFO - 'XPC_SERVICE_NAME': '0', 09:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:06 INFO - Downloading/unpacking blobuploader==1.2.4 09:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:09 INFO - Downloading blobuploader-1.2.4.tar.gz 09:10:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:10:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:10:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:10 INFO - Downloading docopt-0.6.1.tar.gz 09:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:10:10 INFO - Installing collected packages: blobuploader, requests, docopt 09:10:10 INFO - Running setup.py install for blobuploader 09:10:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:10:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:10:10 INFO - Running setup.py install for requests 09:10:11 INFO - Running setup.py install for docopt 09:10:11 INFO - Successfully installed blobuploader requests docopt 09:10:11 INFO - Cleaning up... 09:10:11 INFO - Return code: 0 09:10:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:11 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 0x108db6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108db9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd673556b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b94d30>, '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 0x108abbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b89be8>, '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.rOsvWri6To/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.TPgfIipPMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rOsvWri6To/Render', 09:10:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:11 INFO - 'HOME': '/Users/cltbld', 09:10:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:11 INFO - 'LOGNAME': 'cltbld', 09:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:11 INFO - 'MOZ_NO_REMOTE': '1', 09:10:11 INFO - 'NO_EM_RESTART': '1', 09:10:11 INFO - 'PAGER': '/bin/cat', 09:10:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:11 INFO - 'PWD': '/builds/slave/test', 09:10:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:11 INFO - 'SHELL': '/bin/bash', 09:10:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TPgfIipPMy/Listeners', 09:10:11 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 09:10:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:11 INFO - 'USER': 'cltbld', 09:10:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:11 INFO - 'XPC_FLAGS': '0x0', 09:10:11 INFO - 'XPC_SERVICE_NAME': '0', 09:10:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:11 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-AHhzxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:11 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-DAqPb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:11 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-YgaCRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-0njN5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-pIkAKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-w8gleN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-91pvLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-QE3c1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-2Ez_T4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-LiyLkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-Aj5Lrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-ufn94j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:13 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-D1daAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:13 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-eK1TQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:13 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-OBcOFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:10:13 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-58hNkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:10:13 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-w6nxuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:10:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:10:13 INFO - Running setup.py install for manifestparser 09:10:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:10:13 INFO - Running setup.py install for mozcrash 09:10:13 INFO - Running setup.py install for mozdebug 09:10:13 INFO - Running setup.py install for mozdevice 09:10:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:10:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:10:14 INFO - Running setup.py install for mozfile 09:10:14 INFO - Running setup.py install for mozhttpd 09:10:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:10:14 INFO - Running setup.py install for mozinfo 09:10:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:10:14 INFO - Running setup.py install for mozInstall 09:10:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:10:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:10:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:10:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:10:14 INFO - Running setup.py install for mozleak 09:10:14 INFO - Running setup.py install for mozlog 09:10:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:10:15 INFO - Running setup.py install for moznetwork 09:10:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:10:15 INFO - Running setup.py install for mozprocess 09:10:15 INFO - Running setup.py install for mozprofile 09:10:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:10:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:10:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:10:15 INFO - Running setup.py install for mozrunner 09:10:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:10:15 INFO - Running setup.py install for mozscreenshot 09:10:15 INFO - Running setup.py install for moztest 09:10:16 INFO - Running setup.py install for mozversion 09:10:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:10:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:10:16 INFO - Cleaning up... 09:10:16 INFO - Return code: 0 09:10:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:16 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 0x108db6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108db9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd673556b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b94d30>, '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 0x108abbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b89be8>, '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.rOsvWri6To/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.TPgfIipPMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:10:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rOsvWri6To/Render', 09:10:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:16 INFO - 'HOME': '/Users/cltbld', 09:10:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:16 INFO - 'LOGNAME': 'cltbld', 09:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:16 INFO - 'MOZ_NO_REMOTE': '1', 09:10:16 INFO - 'NO_EM_RESTART': '1', 09:10:16 INFO - 'PAGER': '/bin/cat', 09:10:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:16 INFO - 'PWD': '/builds/slave/test', 09:10:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:16 INFO - 'SHELL': '/bin/bash', 09:10:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TPgfIipPMy/Listeners', 09:10:16 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 09:10:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:16 INFO - 'USER': 'cltbld', 09:10:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:16 INFO - 'XPC_FLAGS': '0x0', 09:10:16 INFO - 'XPC_SERVICE_NAME': '0', 09:10:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:16 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-oW5zC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:16 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-QBUZ4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:16 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-NxH2YX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:16 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-HcDHRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:16 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)) 09:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:16 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-IMokHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:16 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-G308ZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:17 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-_q4mUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:17 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-VngT9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:17 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-HmeCIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:17 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-i5i1GN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:17 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-VWKtz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:17 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-9C3kja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:17 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-u1tEiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:17 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-vfO5Id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:17 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-5DiQIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:10:18 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-hDYlX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:10:18 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)) 09:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:10:18 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-W4aX7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:10:18 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)) 09:10:18 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)) 09:10:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:20 INFO - Downloading blessings-1.6.tar.gz 09:10:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:10:21 INFO - Installing collected packages: blessings 09:10:21 INFO - Running setup.py install for blessings 09:10:21 INFO - Successfully installed blessings 09:10:21 INFO - Cleaning up... 09:10:21 INFO - Return code: 0 09:10:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:10:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:10:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:10:21 INFO - Reading from file tmpfile_stdout 09:10:21 INFO - Current package versions: 09:10:21 INFO - blessings == 1.6 09:10:21 INFO - blobuploader == 1.2.4 09:10:21 INFO - docopt == 0.6.1 09:10:21 INFO - functools32 == 3.2.3-2 09:10:21 INFO - jsonschema == 2.5.1 09:10:21 INFO - manifestparser == 1.1 09:10:21 INFO - mozInstall == 1.12 09:10:21 INFO - mozcrash == 1.0 09:10:21 INFO - mozdebug == 0.1 09:10:21 INFO - mozdevice == 0.49 09:10:21 INFO - mozfile == 1.2 09:10:21 INFO - mozhttpd == 0.7 09:10:21 INFO - mozinfo == 0.9 09:10:21 INFO - mozleak == 0.1 09:10:21 INFO - mozlog == 3.4 09:10:21 INFO - moznetwork == 0.27 09:10:21 INFO - mozprocess == 0.25 09:10:21 INFO - mozprofile == 0.28 09:10:21 INFO - mozrunner == 6.13 09:10:21 INFO - mozscreenshot == 0.1 09:10:21 INFO - mozsystemmonitor == 0.3 09:10:21 INFO - moztest == 0.8 09:10:21 INFO - mozversion == 1.4 09:10:21 INFO - psutil == 3.1.1 09:10:21 INFO - requests == 1.2.3 09:10:21 INFO - wsgiref == 0.1.2 09:10:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:21 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 0x108db6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108db9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd673556b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b94d30>, '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 0x108abbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b89be8>, '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.rOsvWri6To/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.TPgfIipPMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:10:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rOsvWri6To/Render', 09:10:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:21 INFO - 'HOME': '/Users/cltbld', 09:10:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:21 INFO - 'LOGNAME': 'cltbld', 09:10:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:21 INFO - 'MOZ_NO_REMOTE': '1', 09:10:21 INFO - 'NO_EM_RESTART': '1', 09:10:21 INFO - 'PAGER': '/bin/cat', 09:10:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:21 INFO - 'PWD': '/builds/slave/test', 09:10:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:21 INFO - 'SHELL': '/bin/bash', 09:10:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TPgfIipPMy/Listeners', 09:10:21 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 09:10:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:21 INFO - 'USER': 'cltbld', 09:10:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:21 INFO - 'XPC_FLAGS': '0x0', 09:10:21 INFO - 'XPC_SERVICE_NAME': '0', 09:10:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:10:21 INFO - Cleaning up... 09:10:21 INFO - Return code: 0 09:10:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:21 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 0x108db6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108db9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd673556b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b94d30>, '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 0x108abbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b89be8>, '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.rOsvWri6To/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.TPgfIipPMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:10:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:10:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rOsvWri6To/Render', 09:10:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:21 INFO - 'HOME': '/Users/cltbld', 09:10:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:21 INFO - 'LOGNAME': 'cltbld', 09:10:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:21 INFO - 'MOZ_NO_REMOTE': '1', 09:10:21 INFO - 'NO_EM_RESTART': '1', 09:10:21 INFO - 'PAGER': '/bin/cat', 09:10:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:21 INFO - 'PWD': '/builds/slave/test', 09:10:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:21 INFO - 'SHELL': '/bin/bash', 09:10:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TPgfIipPMy/Listeners', 09:10:21 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 09:10:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:21 INFO - 'USER': 'cltbld', 09:10:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:21 INFO - 'XPC_FLAGS': '0x0', 09:10:21 INFO - 'XPC_SERVICE_NAME': '0', 09:10:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:10:22 INFO - Cleaning up... 09:10:22 INFO - Return code: 0 09:10:22 INFO - Running post-action listener: _resource_record_post_action 09:10:22 INFO - Running post-action listener: _start_resource_monitoring 09:10:22 INFO - Starting resource monitoring. 09:10:22 INFO - [mozharness: 2017-04-20 16:10:22.119101Z] Finished create-virtualenv step (success) 09:10:22 INFO - [mozharness: 2017-04-20 16:10:22.120108Z] Running install step. 09:10:22 INFO - Running pre-action listener: _resource_record_pre_action 09:10:22 INFO - Running main action method: install 09:10:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:10:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:10:22 INFO - Reading from file tmpfile_stdout 09:10:22 INFO - Detecting whether we're running mozinstall >=1.0... 09:10:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:10:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:10:22 INFO - Reading from file tmpfile_stdout 09:10:22 INFO - Output received: 09:10:22 INFO - Usage: mozinstall [options] installer 09:10:22 INFO - Options: 09:10:22 INFO - -h, --help show this help message and exit 09:10:22 INFO - -d DEST, --destination=DEST 09:10:22 INFO - Directory to install application into. [default: 09:10:22 INFO - "/builds/slave/test"] 09:10:22 INFO - --app=APP Application being installed. [default: firefox] 09:10:22 INFO - mkdir: /builds/slave/test/build/application 09:10:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:10:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:10:37 INFO - Reading from file tmpfile_stdout 09:10:37 INFO - Output received: 09:10:37 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:10:37 INFO - Running post-action listener: _resource_record_post_action 09:10:37 INFO - [mozharness: 2017-04-20 16:10:37.372901Z] Finished install step (success) 09:10:37 INFO - [mozharness: 2017-04-20 16:10:37.373020Z] Running run-tests step. 09:10:37 INFO - Running pre-action listener: _resource_record_pre_action 09:10:37 INFO - Running main action method: run_tests 09:10:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:10:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:10:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:10:37 INFO - 2017-04-20 09:10:37.385 screenresolution[1642:7504] starting screenresolution argv=screenresolution get 09:10:37 INFO - 2017-04-20 09:10:37.387 screenresolution[1642:7504] Display 0: 1600x1200x32@60 09:10:37 INFO - 2017-04-20 09:10:37.393 screenresolution[1643:7507] starting screenresolution argv=screenresolution list 09:10:37 INFO - Available Modes on Display 0 09:10:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:10:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:10:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:10:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:10:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:10:37 INFO - Intel Iris: 09:10:37 INFO - Chipset Model: Intel Iris 09:10:37 INFO - Type: GPU 09:10:37 INFO - Bus: Built-In 09:10:37 INFO - VRAM (Dynamic, Max): 1536 MB 09:10:37 INFO - Vendor: Intel (0x8086) 09:10:37 INFO - Device ID: 0x0a2e 09:10:37 INFO - Revision ID: 0x0009 09:10:37 INFO - Displays: 09:10:37 INFO - Display: 09:10:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:10:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:10:37 INFO - Display Serial Number: 1600x1200 60 09:10:37 INFO - Main Display: Yes 09:10:37 INFO - Mirror: Off 09:10:37 INFO - Online: Yes 09:10:37 INFO - Rotation: Supported 09:10:37 INFO - Return code: 0 09:10:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:10:37 INFO - Python 2.7.3 09:10:37 INFO - Return code: 0 09:10:37 INFO - grabbing minidump binary from tooltool 09:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:37 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 0x108b94d30>, '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 0x108abbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b89be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:10:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:10:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:10:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:10:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:10:37 INFO - Return code: 0 09:10:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:10:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:10:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:10:37 INFO - ENV: RUST_BACKTRACE is now 1 09:10:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:10:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:10:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0339', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0339 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:10:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rOsvWri6To/Render', 09:10:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:37 INFO - 'HOME': '/Users/cltbld', 09:10:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:37 INFO - 'LOGNAME': 'cltbld', 09:10:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:10:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:10:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:37 INFO - 'MOZ_NO_REMOTE': '1', 09:10:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:10:37 INFO - 'NO_EM_RESTART': '1', 09:10:37 INFO - 'PAGER': '/bin/cat', 09:10:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:37 INFO - 'PWD': '/builds/slave/test', 09:10:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:10:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:37 INFO - 'RUST_BACKTRACE': '1', 09:10:37 INFO - 'SHELL': '/bin/bash', 09:10:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TPgfIipPMy/Listeners', 09:10:37 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 09:10:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:37 INFO - 'USER': 'cltbld', 09:10:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:37 INFO - 'XPC_FLAGS': '0x0', 09:10:37 INFO - 'XPC_SERVICE_NAME': '0', 09:10:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0339', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:10:37 INFO - mozversion application_buildid: 20170420075327 09:10:37 INFO - mozversion application_changeset: c1ef9597ea513c00df914df119d146c45a5cc784 09:10:37 INFO - mozversion application_display_name: Firefox 09:10:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:10:37 INFO - mozversion application_name: Firefox 09:10:37 INFO - mozversion application_remotingname: firefox 09:10:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:10:37 INFO - mozversion application_vendor: Mozilla 09:10:37 INFO - mozversion application_version: 54.0 09:10:37 INFO - mozversion platform_buildid: 20170420075327 09:10:37 INFO - mozversion platform_changeset: c1ef9597ea513c00df914df119d146c45a5cc784 09:10:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:10:37 INFO - mozversion platform_version: 54.0 09:10:37 INFO - using testdate: 1492704637 09:10:37 INFO - actual date: 1492704637 09:10:37 INFO - starting webserver on 'localhost:49244' 09:10:37 INFO - SUITE-START | Running 2 tests 09:10:37 INFO - TEST-START | dromaeo_css 09:10:37 INFO - operating with platform_type : mac_ 09:10:37 INFO - Initialising browser for dromaeo_css test... 09:10:38 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmpejhUjS/profile http://localhost:49244/getInfo.html) 09:10:40 INFO - PROCESS | 1653 | 2017-04-20 09:10:40.587 plugin-container[1656:7689] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 09:10:40 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:40 INFO - PROCESS | 1653 | 2017-04-20 09:10:40.588 plugin-container[1656:7689] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 09:10:40 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:40 INFO - PROCESS | 1653 | 2017-04-20 09:10:40.588 plugin-container[1656:7689] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:10:40 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 09:10:40 INFO - PROCESS | 1653 | colorDepth:24 09:10:40 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 09:10:40 INFO - PROCESS | 1653 | __metrics 09:10:40 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 09:10:41 INFO - TEST-INFO | 1653: exit 0 09:10:41 INFO - Browser initialized. 09:10:41 INFO - Running cycle 1/1 for dromaeo_css test... 09:10:41 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmpejhUjS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:10:43 INFO - PROCESS | 1657 | 2017-04-20 09:10:43.517 plugin-container[1658:7824] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854b, name = 'com.apple.tsm.portname' 09:10:43 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:43 INFO - PROCESS | 1657 | 2017-04-20 09:10:43.518 plugin-container[1658:7824] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430f, name = 'com.apple.CFPasteboardClient' 09:10:43 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:43 INFO - PROCESS | 1657 | 2017-04-20 09:10:43.518 plugin-container[1658:7824] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:10:53 INFO - PROCESS | 1657 | RSS: Main: 174657536 09:10:53 INFO - PROCESS | 1657 | 09:13:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 09:13:57 INFO - PROCESS | 1657 | RSS: Main: 190283776 09:13:57 INFO - PROCESS | 1657 | 09:17:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 09:17:00 INFO - PROCESS | 1657 | RSS: Main: 201494528 09:17:00 INFO - PROCESS | 1657 | 09:20:02 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 09:20:03 INFO - PROCESS | 1657 | RSS: Main: 204849152 09:20:03 INFO - PROCESS | 1657 | 09:23:05 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 09:23:06 INFO - PROCESS | 1657 | RSS: Main: 207962112 09:23:06 INFO - PROCESS | 1657 | 09:26:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 09:26:09 INFO - PROCESS | 1657 | RSS: Main: 210370560 09:26:09 INFO - PROCESS | 1657 | 09:29:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 09:29:12 INFO - PROCESS | 1657 | RSS: Main: 208277504 09:29:12 INFO - PROCESS | 1657 | 09:29:12 INFO - PROCESS | 1657 | __start_tp_report 09:29:12 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 09:29:12 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 09:29:12 INFO - PROCESS | 1657 | |i|pagename|runs| 09:29:12 INFO - PROCESS | 1657 | |0;dojo.html;3299.1257316811034;3645.453182022697;3658.945115823263;3670.119171398865;3692.316921369547;6137.324894261633;6168.907466388007;6181.412765787249;6188.8143355699285;6225.657588832612;6197;6214.906776398358;6246.437820596146;6263.498920086394;6273.435390814826;4818.421789385278;5600.887982240352;5679.977604031273;5702.71688870003;5749.396313387076;4897.363342765436;5757.337906140785;5761.683107429089;5781.537477001851;5816.33112192097;5527.585431092664;5536.114221724525;5753.510951569122;5754.1944127822135;5787.04513755232;5663.4336566343445;5699.458551437628;5771.422857714237;5809.854753631151;5834.474897259224;5548.001359755242;5673.546116310686;5692.345380281279;5695.373508914016;5770.01909675352;6027.668478233695;6043.516518678495;6189.071639254103;6207.720652570645;6243.938530449789;6332.430081292659;6354.332795056499;6355.237371515402;6411.903821442682;6425.45383642391;6614.702338394735;6638.6016838989335;6643.435307998826;6651.401373876326;6680.164979377578;6286.182796236506;6289.842753931142;6293.496520278367;6304.338044505353;6320.683965801692;6344.524160687874;6487.091807147003;6498.805035848932;6597.637129957802;6734.158230221223;6451.1936007999;6451.9354806452275;6455.4835613150835;6458;6464.095026696266;6162.845928851725;6206.348333424925;6583.539152259296;6666.200055993312;6674.733010679615;5294.205869119663;6023.969880150571;6275.466585340206;6370.522210834206;6535.705893234721;4933.802647894116;6333.493320534346;6387.169667943138;6443.452306553995;6472.967635161888;5100;5144.794208231704;5213.400458947193;5251.894962100737;5259.342582177228;4273.504273504279;4553.453585569753;4652.860414187512;4751.121042607059;4751.952480475151;6338.809835704937;6340.397662222082;6345.397187267202;6347.873042539123;6374.330695277022;6288.339724328971;6384.3934826191435;6524.445422139076;6525.706343214567;6662.800115996528;5402.756875940592;6316.115743795872;6330.936690633127;6336.334684858116;6347.492200623939;5847.210626565445;5855.736491857877;5864.882702346014;5867.530597552271;5879.118132280192;6138.987067133873;6683.933160668331;6770.52606317553;6967.268436814062;6998.090248267693;5637.9718101409235;6552.049952756728;6566.9343306566325;6716.026176204326;6739.66301684904;6976.02335672996;7016.26329235423;7017.5087743859485;7017.964910175417;7027.964860175666;6274.466670333073;6452.161219041494;6581.111549940888;6585.275619681927;6628.237752658313;6271.560990730605;6436.259830119409;6605.57063790852;6665.733370665119;6689.76585819494;6417.96791016042;6421.1010458536775;6423.710933007932;6431.228252609717;6432.131662225727;6293.905591416224;6429.260635027032;6430.967845160744;6460.418562329225;6467.902981455188;5320;6185.876282474415;6209.130721699056;6222.751089956351;6251.3123556407845;6388.265349484869;6399.2640846303275;6407.679616019273;6411.038344248213;6465.870682586416;6188.566800323934;6206.503479721521;6251.562390632612;6313.873722525615;6318.304986451395 09:29:12 INFO - PROCESS | 1657 | |1;ext.html;740.1931894235282;752.6086435053772;785.9350579964148;989.1592146675326;989.2679417231251;40163.598364016354;40537;42046;42161.57838421615;42494.78752606236;38715.03212419684;38728;38824.22351552967;38903;38914.41628375577;8351.290140338064;11281.887181128166;11569.247998880046;11713.531458741683;11738.178327517076;11419.371934543597;11490.540378384854;11546.364949927793;11552.1913466057;11560.82658760115;11455.255408398469;11461.885381146165;11550.480228389742;11552;11557.595484158057;9948.253880958957;11459.36973466459;11459.656210313662;11511.827322590168;11525;11142.108631309477;11170.27393219438;11212.66362009141;11231.60689375876;11243.437828108561;30275.848620756755;35932.10169745751;35943.640563594294;36027.81986090079;36100.63899360999;3913.236918800835;3936.822842972073;3948.802559871995;3966.4050392441077;4032.1330913853367;26316.73683263162;26550.46899062008;26660.33349166267;27975.58036629452;28898.711012889813;2797.5943488194303;3211.5022171563446;3305.7851239669344;3321.551590535271;3399.881004164867;346311;349990;360793;362729;446275.5372446234;45814.31278530825;45831.62505124851;46037.76981115073;46163.30755038677;46214.76892615516;47776.28335574969;48194;48371;48438;48471;14386.28068596566;14459.13245205291;14462.421503139967;14497.420103195966;14536.05515641501;12607.49570017189;12707.555235566711;12733.872661273268;12743.299118548384;12743.490260389666;11286.435678216056;11377.772444551063;12388.389509363906;12457.626271211879;12508.249505029728;5741.937741517832;5744.913826292609;5794.971025144931;5796.913046304308;5812.38969908162;1127.808272593661;1132.7621199548187;1190.214458457414;1219.6341097670663;1229.6679896427913;27755.722442775314;27760;28366.85816570945;28408.573871391945;28497.71502284992;1066.8666416697913;1281.4938099450665;1308.1235572166672;1309.461382875118;1311.475409836054;15043.623909402353;16378.935369200964;16396.590085247964;16777.496675099766;17116.828831711773;487439;489479;489877;490817;491489;56824;57354.42645573491;57368.71315643479;57460.425395746344;57472.71263643738;1370.7601169795246;1503.812023497063;1547.388781431351;1552.750036220853;1564.9530514084595;28378.85810570934;29316.560251595816;29628.962986295377;30065;31134.37731245408;49062.75468622634;49161;49165.75417122892;49282.26076608925;49377;495825.0417495923;498858;499175;499213;499300.00699992536;28068.578971315037;28071.0175143869;33090.834545827114;34310.82844585761;34542;40502;40554.1889162221;40886.79556602198;41716;41718;28973.7102628971;29349.55975660324;29894.402111957203;30320.54519182258;30323.241918952204;29368.559471607514;29485.410291793614;29502.114936551938;30280;30337.393252134392;33114.66885331116;34323.06757873573;34560.82719586386;34725.82637086798;34799.65200347964;28338.866445341955;28464.85767571149;28823.56764648574;28868.133955981353;29507.967221147155;18656.160472778487;21035.789642103384;22586.887065564566;23505;24003.559786412872 09:29:12 INFO - PROCESS | 1657 | |2;jquery.html;427.38456622463633;434.30944797771537;442.9424174857268;448.5245639622;452.71478968250017;19297.807021929777;19558.31545895894;21163.365099047016;22536.43658908526;23156.07375704974;23047.654285185694;23368;23619.645705314433;24155;24429.900654470548;6783.287754785751;6795.422389096921;7078.362947334739;7087.397571206454;7191.820204494903;7051.541649792747;7095;7102.218755936843;7146.428285737128;7155.3202445767565;6798.558093723892;7070.292970702914;7135.750248741295;7149.320814522612;7153.89269160963;6918.861622767517;7127.0734804475605;7140.928860670889;7229.313215244543;7230.313120253594;6715.395614394679;6734.595924244512;6748.7637932672615;6758.391744742948;6761.492888033417;27006.72993270062;27204.727952720616;27353.179404617895;27388.452230955467;27612;3474.513568100468;3522.8590856365713;3533.752637315389;3563.750537462379;3650.799206043693;16213.513594592063;17324.740128898076;17501.7374739379;17602.471925842245;17972;2121.840861935361;2161.5136594266164;2187.0923566719794;2205.6029914615365;2389.602082781575;101133.49433252787;126739.36630316882;142517;144495.27752361275;153047;19421.805781942;21491.46271343229;21556.56886862254;21591.02840372187;21718.78281217199;32315.838420807744;32616.34767304688;32667;32893.50659740106;32904.506432403534;9248.583813728399;10902.727431814268;10922.962318579723;10947;11020.50407731654;7809.492382995087;8336.749897503085;8623.611937462703;9204;9382.67160649374;9449.622015119317;9718.61125554984;9758.072983066597;9806.803863922823;9815.509224538888;3763.6989040876665;4110.753354798721;4195.832166713297;4278.465191851019;4311.956880431218;822.8601137806587;824.1484463755473;905.7735566108473;916.9037251088673;930.265090578449;19510.512237194183;20696.896515517627;21091.578168436547;21161.075587298696;21180.364589062352;903.7198468474794;951.4671783801093;953.8664586957832;983.1986930651639;983.6114734679904;10922.440115361127;11054.00513953748;11154.21920465576;11236.438178091063;11258.774824503627;596124.1550338025;726179;755229;765065;784326;120982.54453841888;144853;149940;151560;154617;1098.5441041967495;1184.3498026896602;1265.6266401411606;1270.142653708743;1270.3394234997934;19596;19861.30485433003;20123.597528049064;20146;20218.090185941375;58748.706256468446;59150;59263;59338.40661593329;60411.48971275753;81216.78174827264;82271;83546.58226708828;85002;85128.57435712781;9895;9960.701178964642;11515.884841151481;11572.94213528927;11774.058075354122;12921.806172907602;13010.284434356201;13141.277229752455;13271.004674649246;13446.529371471952;11293;11300.434978251218;11500.654980350602;11551.595694150665;11684.18210725241;9359.672411465566;10647.574097036031;10880.075193608633;11003.724906877393;11017.228793984345;10327.535260913428;10420.843687344544;10481.794593621526;11618.767624647291;11757.294562326288;11643.708907277385;11897.524099035942;12220.75558488843;12236.632901012983;12268.815967760669;11568.826467602825;11691.473883675426;11696.649100526998;11894.643160705193;11950.641480755592 09:29:12 INFO - PROCESS | 1657 | |3;mootools.html;1593.8246792852776;1635.0189886068356;1636.7299395599719;1640.2618821530314;1659.3943210728085;11017.61438349657;11471.713207169825;11532.827007594893;11645.359505227209;11741.823872641897;11635.418229088533;11978.40107994599;12086;12104.09219308551;12141.392930353491;2454.079720104965;2487.3657217409536;2496.0015993602524;2506.6741323627994;2511.196417146514;2441.56051910656;2447.7919376852988;2483.801295896324;2486.8880900359436;2492.4142826435864;2162.772908844572;2436.4396188876567;2456.9017239310406;2469.71598266199;2490.8380955237944;2149.7312835895514;2380.690620158923;2416.9758302417013;2431.55016321981;2445.08309383981;2369.3484291819714;2389.661789397943;2391.8564886106888;2397.724261709891;2401.267613377919;11364.772704545945;11551.306921584732;11575.247608905394;11588;11595.768084638345;5985.042393217064;6144.446999769997;6202.3797620238065;6205.131281620576;6219.40915613016;3584.4981702561663;3703.6851867591286;3766.510353654035;3791.545014598238;3834.328992426342;1252.7995520716734;1264.2856785915928;1274.528424482938;1295.4300107952565;1332.7068045030176;14145.787813182811;14409.927950360287;14956.850431495763;15636.843631563652;15974.28115734783;4960.355153830051;5043.36957880265;5080;5088.160453525127;5140.306058682105;13498.86501134976;13514.324283785969;13521.526746564019;13541.661458463617;13541.932290338682;5969.343372229051;6084.117802918552;6135.877282454415;6163.568550201532;6167.876642467126;5444.128939369682;5487.75305111271;5493.176023596413;5520.558355331564;5542.251796007487;4096.774677392772;4235.364695295669;4237.78811059452;4296.763677997677;4321.719088259253;1037.766502536923;1119.7592517608591;1171.2211379432752;1174.5008371442102;1182.261086820737;964.6912987843823;980.7793246519476;1004.3271008424373;1004.8794144702537;1009.7071849163807;6554.475641948633;6593.274739778621;6741.59550426976;6748.392644662004;6813.284605116491;953.8855337359561;992.9453880036523;1007.8639383683255;1020.3622735790132;1021.213665477578;3788.791616461121;4068.3694027425795;4319.114581510783;4428.778561071934;4537.6143027843;13661.726765464635;14309.713805724035;14311.212883291519;14340.641483962985;14443.350049247809;13748.93125534386;13796.241206733524;14104.576862694135;14157.504487342894;14173;10362.792744145076;10526.368417895104;10579.471026448648;10595.94702026485;10597.84103238452;5667.3482549507335;6653.168353955755;6728.562643428161;6777.01733248686;6802.387785099167;12260.32568208757;12522;12567.18313309632;12576.748465030465;12606.810897836356;10608;11841.407929603658;12624.936875315565;12975.351232438528;13199.340032998503;9418.152366287068;9581.377210481296;9619.759506012406;9643.180329672057;9662.420254784736;10485.213608979204;10790.56837726482;10994.230403871652;11049.72375690582;11059.889401105886;7718.498297610638;8978.422134104205;9100.577483127783;9187.632494700138;9333.206677432494;8811.074837142009;8846.823063538823;8878.73363799087;8900.287976961697;8904.510251935946;8176.509409435453;8267.83464330722;8289.253967142746;8343.499390036375;8345.123762004901;6032.366601506954;6051.4553690168095;6136.539759518143;6186.505079593531;6347.301796802256;6025.578209525291;6116.541259405473;6147.66187859672;6206.06908963659;6269.7178626962805 09:29:12 INFO - PROCESS | 1657 | |4;prototype.html;390.05606432433507;391.44805823788465;391.6455607675054;394.38869751884573;405.3231104056226;4486.730796152233;4659.557342052503;5255.737213139342;5295.735213239333;5488.341399032121;5342.946570534294;5412.161115027177;5420.756065977031;5455.527007707917;5455.945440545604;4121.949757179696;4337.587929146742;4352.782360881959;4513.458384993805;4577.748223847687;4482.148391805563;4505.7296562206375;4509.571590698878;4545.4545454545505;4622.768861556926;3954.387070004154;3991.261616600938;4421;4561.657875659339;4607.76961151941;3715.16408808016;3955.804528486689;4351.6953813233085;4472.127935052666;4593.701409408378;3574.195031509097;3810.447485114643;4072.898177545585;4078.3678529827926;4102.261592913274;4702.788374523155;5252.4222335543445;5257.106291930343;5310.734463276859;5315.362156541201;4131.648809851166;4598.505485717122;4936.284238785356;5149.974250128763;5202.3497062867145;5223.112070947948;5748;5767.855803604902;5776;5782.624129431573;4199.811008504625;4246.617804397619;4628.472604040659;4640.628749700049;4641.234196357597;4606.646718850688;5775.364709881909;5899.0561510158595;6083.7566497340495;6130.356312587159;4653.906921861611;4671.212007692143;4689.390379250675;4728.929066064012;4752.388184979441;4211.389348544444;4430.645548356124;4730.030343779517;4757.666963312535;4783.234682450791;4518.932216016762;4789.653069995747;4860.73265970375;4884.8290309838985;5064.5441910228255;5056.926660069427;5456.154296084113;5758.62568933018;5761.822678558012;5845.503132233808;1557.769362203869;1565.8434156584478;1578.7552929295978;1580.857722804953;1636.4599682104877;985.4530735441755;1017.0693815159194;1096.6216655254084;1222.5232159457819;1226.2642414551199;5106.438226878443;5533.118739628546;5551.639143455663;5586.525145362609;5595.328560572757;5281.234221037928;5747.712614369265;5965.463108320272;5998.940010599838;6054;4381.910752919184;5038.546530812245;5223.503767142115;5509.421510741395;5515.7242137892945;2793.6787269463866;2805.382815780525;2940.5442156465783;3102.402262834642;3117.890873819451;5119.846404607868;5278.683279003272;5724.767340737116;6068.544859135583;6347;4594.1271158479785;5865.501432378211;5876.412358764175;6048.848778780478;6058.27300723916;5188.351456067991;5376.650517716336;6151.2618485781995;6259.4679452246155;6347.444876005408;5179.093658609803;5310.937812437451;5996.970015149896;6010.655844220845;6024.349286314008;5170.508801663836;5213.661112027706;5511.629999100425;5551.167324901297;5858.677772722371;5697.857553561194;5715.314162300384;6176.170774120074;6277.278113016879;6419;4800.66395352322;4830.2996065571;5038.256387718077;5261.710605916712;5272;4627;5209.453007434165;5226.372835259793;6044.697765111815;6075.605085669417;4752.714837109785;4870;5214.452482489284;5645.95549823284;5808.854778630568;5129.717865517432;5461.5357694596405;5603.691796951044;5607.317804658667;5649.6327738696855;4931.654784165074;5220.817271395483;5322.973385133049;5626.662400255997;5632.2678051853;5029.29589857413;5805.564582656233;5853.853653658693;5868.2664666916635;5923.289205295392;4969.82605608802;5376.704281264411;5524.530414914777;5617;5784.686125549813 09:29:12 INFO - PROCESS | 1657 | |5;yui.html;755.4636208292112;768.55039801716;794.6622685358722;899.3030401438884;910.7677542226734;16526.752098718527;17915.372961946334;17920.820791792077;17931.282748690068;17985.64028719425;16317.918410407932;16386.344546218137;16502.17489125545;16580.917095414537;16584.75122873158;849.9575021248944;997.0677416615488;1006.1699098243942;1037.501999040461;1041.9062284394404;1039.682896716501;1041.4376236832102;1045.0229035851464;1045.0646671229244;1046.6022911293699;1084.0677017764715;1088.787686401152;1089.4716062709579;1089.6894385100247;1091.683411810578;1075.7955988362226;1083.864516935383;1085.67972448128;1086.277625379122;1088.983665245022;796.5618909599697;798.9280964713204;804.5454318310136;805.0942689474341;806.1615919443772;16790;17000.659986800318;17285.135743212915;17412.912935435368;17690.823091769045;14605.6348591285;14724.263786810616;14847.183404912725;14897.77653335201;15017.549473515812;11833.763324733543;12110.39448027604;12141.93929030358;12425.689357766038;12911.354432278442;3818.2745278397015;3872.0707030312806;3887.5140607424073;3896.5129358830145;4003.2794097062515;98930;103230.99999999924;130473;132446.67553324538;133203.33398333043;10777.137828973664;11608.187426880037;11739.119566032581;12042.337671428155;12350.691232719253;21452.78547214539;23350.64974025391;23486.530269394516;23515.529689406118;23519.88240058789;8124.471909325875;8170.714025009096;8386.454880432751;8456.2812160967;8568.314534837198;4579.519150489217;4630.791614377361;4635.7218566886095;4651.813927442864;4813.205821039489;3303.7357011439026;3719.34911390511;3771.735978481535;3794.165283637595;3803.372443546784;674.4671709349657;674.865026994603;680.0920770771075;702.1013103227876;705.5237714542664;679.8572299817093;701.7298340139083;713.6895450479058;718.2960698515379;753.2279413600993;8843.646254149762;10689.625863094754;10801.83797243042;10965.122790176767;11206.327620342805;663.2339647706906;686.6463771157858;696.4532841719226;716.8566286742672;728.6575309604477;5024.346834911438;5141.126008578551;5146.408163061295;5154.484551544891;5161.148410512224;107016.71933122743;108690;111436;113562.43218783854;117073;62039;65135.34864651388;73233;83462.58268708739;85284;74503.62748186225;78754;81814.77277840837;84496.15503845006;85000;9165.954170229106;11092.944535277273;11333.093352531943;11517.308961462339;11740.354280514654;41280;50384;53094.469055310496;54287;54701.179482307;67572.32427675858;68936.65531672309;69014.65492672505;69670.65164674145;69997.65001175166;12885.742285154058;13212.801807973081;13341.799873002106;13615;13858.792118118035;15287.235638218268;15307.787095840564;15389.230538473255;15725.370985160465;15737.842621573638;14049.22729249901;14062.085964412281;14510.492132775302;14905.552833415015;14906.552803415914;13654.93172534131;14313.355898984362;14316.713665726418;14455.204963727096;14473.493427729978;13399.263040532864;13464.596062118153;14147.080439771382;14182.219977900902;14307.785383219469;9981;10124.544395502073;10281;10368.326058806153;10509.316894402145;8951.910480895107;11325.71685707864;11596.304221746723;11619.47712352963;11715.355655439032 09:29:12 INFO - PROCESS | 1657 | __end_tp_report 09:29:12 INFO - PROCESS | 1657 | __start_cc_report 09:29:12 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,397 09:29:12 INFO - PROCESS | 1657 | __end_cc_report 09:29:12 INFO - PROCESS | 1657 | __startTimestamp1492705752403__endTimestamp 09:29:12 INFO - PROCESS | 1657 | 09:29:12 INFO - PROCESS | 1657 | ------- Summary: start ------- 09:29:12 INFO - PROCESS | 1657 | Number of tests: 6 09:29:12 INFO - PROCESS | 1657 | 09:29:12 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:6082.82 Median:6293.50 stddev:679.17 (10.8%) stddev-sans-first:645.19 09:29:12 INFO - PROCESS | 1657 | Values: 3299.1 3645.5 3658.9 3670.1 3692.3 6137.3 6168.9 6181.4 6188.8 6225.7 6197.0 6214.9 6246.4 6263.5 6273.4 4818.4 5600.9 5680.0 5702.7 5749.4 4897.4 5757.3 5761.7 5781.5 5816.3 5527.6 5536.1 5753.5 5754.2 5787.0 5663.4 5699.5 5771.4 5809.9 5834.5 5548.0 5673.5 5692.3 5695.4 5770.0 6027.7 6043.5 6189.1 6207.7 6243.9 6332.4 6354.3 6355.2 6411.9 6425.5 6614.7 6638.6 6643.4 6651.4 6680.2 6286.2 6289.8 6293.5 6304.3 6320.7 6344.5 6487.1 6498.8 6597.6 6734.2 6451.2 6451.9 6455.5 6458.0 6464.1 6162.8 6206.3 6583.5 6666.2 6674.7 5294.2 6024.0 6275.5 6370.5 6535.7 4933.8 6333.5 6387.2 6443.5 6473.0 5100.0 5144.8 5213.4 5251.9 5259.3 4273.5 4553.5 4652.9 4751.1 4752.0 6338.8 6340.4 6345.4 6347.9 6374.3 6288.3 6384.4 6524.4 6525.7 6662.8 5402.8 6316.1 6330.9 6336.3 6347.5 5847.2 5855.7 5864.9 5867.5 5879.1 6139.0 6683.9 6770.5 6967.3 6998.1 5638.0 6552.0 6566.9 6716.0 6739.7 6976.0 7016.3 7017.5 7018.0 7028.0 6274.5 6452.2 6581.1 6585.3 6628.2 6271.6 6436.3 6605.6 6665.7 6689.8 6418.0 6421.1 6423.7 6431.2 6432.1 6293.9 6429.3 6431.0 6460.4 6467.9 5320.0 6185.9 6209.1 6222.8 6251.3 6388.3 6399.3 6407.7 6411.0 6465.9 6188.6 6206.5 6251.6 6313.9 6318.3 09:29:12 INFO - PROCESS | 1657 | 09:29:12 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:58597.32 Median:30170.42 stddev:121779.96 (403.6%) stddev-sans-first:122044.11 09:29:12 INFO - PROCESS | 1657 | Values: 740.2 752.6 785.9 989.2 989.3 40163.6 40537.0 42046.0 42161.6 42494.8 38715.0 38728.0 38824.2 38903.0 38914.4 8351.3 11281.9 11569.2 11713.5 11738.2 11419.4 11490.5 11546.4 11552.2 11560.8 11455.3 11461.9 11550.5 11552.0 11557.6 9948.3 11459.4 11459.7 11511.8 11525.0 11142.1 11170.3 11212.7 11231.6 11243.4 30275.8 35932.1 35943.6 36027.8 36100.6 3913.2 3936.8 3948.8 3966.4 4032.1 26316.7 26550.5 26660.3 27975.6 28898.7 2797.6 3211.5 3305.8 3321.6 3399.9 346311.0 349990.0 360793.0 362729.0 446275.5 45814.3 45831.6 46037.8 46163.3 46214.8 47776.3 48194.0 48371.0 48438.0 48471.0 14386.3 14459.1 14462.4 14497.4 14536.1 12607.5 12707.6 12733.9 12743.3 12743.5 11286.4 11377.8 12388.4 12457.6 12508.2 5741.9 5744.9 5795.0 5796.9 5812.4 1127.8 1132.8 1190.2 1219.6 1229.7 27755.7 27760.0 28366.9 28408.6 28497.7 1066.9 1281.5 1308.1 1309.5 1311.5 15043.6 16378.9 16396.6 16777.5 17116.8 487439.0 489479.0 489877.0 490817.0 491489.0 56824.0 57354.4 57368.7 57460.4 57472.7 1370.8 1503.8 1547.4 1552.8 1565.0 28378.9 29316.6 29629.0 30065.0 31134.4 49062.8 49161.0 49165.8 49282.3 49377.0 495825.0 498858.0 499175.0 499213.0 499300.0 28068.6 28071.0 33090.8 34310.8 34542.0 40502.0 40554.2 40886.8 41716.0 41718.0 28973.7 29349.6 29894.4 30320.5 30323.2 29368.6 29485.4 29502.1 30280.0 30337.4 33114.7 34323.1 34560.8 34725.8 34799.7 28338.9 28464.9 28823.6 28868.1 29508.0 18656.2 21035.8 22586.9 23505.0 24003.6 09:29:12 INFO - PROCESS | 1657 | 09:29:12 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:41656.98 Median:25718.32 stddev:120976.46 (470.4%) stddev-sans-first:121276.22 09:29:12 INFO - PROCESS | 1657 | Values: 427.4 434.3 442.9 448.5 452.7 19297.8 19558.3 21163.4 22536.4 23156.1 23047.7 23368.0 23619.6 24155.0 24429.9 6783.3 6795.4 7078.4 7087.4 7191.8 7051.5 7095.0 7102.2 7146.4 7155.3 6798.6 7070.3 7135.8 7149.3 7153.9 6918.9 7127.1 7140.9 7229.3 7230.3 6715.4 6734.6 6748.8 6758.4 6761.5 27006.7 27204.7 27353.2 27388.5 27612.0 3474.5 3522.9 3533.8 3563.8 3650.8 16213.5 17324.7 17501.7 17602.5 17972.0 2121.8 2161.5 2187.1 2205.6 2389.6 101133.5 126739.4 142517.0 144495.3 153047.0 19421.8 21491.5 21556.6 21591.0 21718.8 32315.8 32616.3 32667.0 32893.5 32904.5 9248.6 10902.7 10923.0 10947.0 11020.5 7809.5 8336.7 8623.6 9204.0 9382.7 9449.6 9718.6 9758.1 9806.8 9815.5 3763.7 4110.8 4195.8 4278.5 4312.0 822.9 824.1 905.8 916.9 930.3 19510.5 20696.9 21091.6 21161.1 21180.4 903.7 951.5 953.9 983.2 983.6 10922.4 11054.0 11154.2 11236.4 11258.8 596124.2 726179.0 755229.0 765065.0 784326.0 120982.5 144853.0 149940.0 151560.0 154617.0 1098.5 1184.3 1265.6 1270.1 1270.3 19596.0 19861.3 20123.6 20146.0 20218.1 58748.7 59150.0 59263.0 59338.4 60411.5 81216.8 82271.0 83546.6 85002.0 85128.6 9895.0 9960.7 11515.9 11572.9 11774.1 12921.8 13010.3 13141.3 13271.0 13446.5 11293.0 11300.4 11500.7 11551.6 11684.2 9359.7 10647.6 10880.1 11003.7 11017.2 10327.5 10420.8 10481.8 11618.8 11757.3 11643.7 11897.5 12220.8 12236.6 12268.8 11568.8 11691.5 11696.6 11894.6 11950.6 09:29:12 INFO - PROCESS | 1657 | 09:29:12 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:7006.48 Median:2455.49 stddev:4372.46 (178.1%) stddev-sans-first:4365.81 09:29:12 INFO - PROCESS | 1657 | Values: 1593.8 1635.0 1636.7 1640.3 1659.4 11017.6 11471.7 11532.8 11645.4 11741.8 11635.4 11978.4 12086.0 12104.1 12141.4 2454.1 2487.4 2496.0 2506.7 2511.2 2441.6 2447.8 2483.8 2486.9 2492.4 2162.8 2436.4 2456.9 2469.7 2490.8 2149.7 2380.7 2417.0 2431.6 2445.1 2369.3 2389.7 2391.9 2397.7 2401.3 11364.8 11551.3 11575.2 11588.0 11595.8 5985.0 6144.4 6202.4 6205.1 6219.4 3584.5 3703.7 3766.5 3791.5 3834.3 1252.8 1264.3 1274.5 1295.4 1332.7 14145.8 14409.9 14956.9 15636.8 15974.3 4960.4 5043.4 5080.0 5088.2 5140.3 13498.9 13514.3 13521.5 13541.7 13541.9 5969.3 6084.1 6135.9 6163.6 6167.9 5444.1 5487.8 5493.2 5520.6 5542.3 4096.8 4235.4 4237.8 4296.8 4321.7 1037.8 1119.8 1171.2 1174.5 1182.3 964.7 980.8 1004.3 1004.9 1009.7 6554.5 6593.3 6741.6 6748.4 6813.3 953.9 992.9 1007.9 1020.4 1021.2 3788.8 4068.4 4319.1 4428.8 4537.6 13661.7 14309.7 14311.2 14340.6 14443.4 13748.9 13796.2 14104.6 14157.5 14173.0 10362.8 10526.4 10579.5 10595.9 10597.8 5667.3 6653.2 6728.6 6777.0 6802.4 12260.3 12522.0 12567.2 12576.7 12606.8 10608.0 11841.4 12624.9 12975.4 13199.3 9418.2 9581.4 9619.8 9643.2 9662.4 10485.2 10790.6 10994.2 11049.7 11059.9 7718.5 8978.4 9100.6 9187.6 9333.2 8811.1 8846.8 8878.7 8900.3 8904.5 8176.5 8267.8 8289.3 8343.5 8345.1 6032.4 6051.5 6136.5 6186.5 6347.3 6025.6 6116.5 6147.7 6206.1 6269.7 09:29:12 INFO - PROCESS | 1657 | 09:29:12 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4776.91 Median:5195.35 stddev:1341.94 (25.8%) stddev-sans-first:1304.69 09:29:12 INFO - PROCESS | 1657 | Values: 390.1 391.4 391.6 394.4 405.3 4486.7 4659.6 5255.7 5295.7 5488.3 5342.9 5412.2 5420.8 5455.5 5455.9 4121.9 4337.6 4352.8 4513.5 4577.7 4482.1 4505.7 4509.6 4545.5 4622.8 3954.4 3991.3 4421.0 4561.7 4607.8 3715.2 3955.8 4351.7 4472.1 4593.7 3574.2 3810.4 4072.9 4078.4 4102.3 4702.8 5252.4 5257.1 5310.7 5315.4 4131.6 4598.5 4936.3 5150.0 5202.3 5223.1 5748.0 5767.9 5776.0 5782.6 4199.8 4246.6 4628.5 4640.6 4641.2 4606.6 5775.4 5899.1 6083.8 6130.4 4653.9 4671.2 4689.4 4728.9 4752.4 4211.4 4430.6 4730.0 4757.7 4783.2 4518.9 4789.7 4860.7 4884.8 5064.5 5056.9 5456.2 5758.6 5761.8 5845.5 1557.8 1565.8 1578.8 1580.9 1636.5 985.5 1017.1 1096.6 1222.5 1226.3 5106.4 5533.1 5551.6 5586.5 5595.3 5281.2 5747.7 5965.5 5998.9 6054.0 4381.9 5038.5 5223.5 5509.4 5515.7 2793.7 2805.4 2940.5 3102.4 3117.9 5119.8 5278.7 5724.8 6068.5 6347.0 4594.1 5865.5 5876.4 6048.8 6058.3 5188.4 5376.7 6151.3 6259.5 6347.4 5179.1 5310.9 5997.0 6010.7 6024.3 5170.5 5213.7 5511.6 5551.2 5858.7 5697.9 5715.3 6176.2 6277.3 6419.0 4800.7 4830.3 5038.3 5261.7 5272.0 4627.0 5209.5 5226.4 6044.7 6075.6 4752.7 4870.0 5214.5 5646.0 5808.9 5129.7 5461.5 5603.7 5607.3 5649.6 4931.7 5220.8 5323.0 5626.7 5632.3 5029.3 5805.6 5853.9 5868.3 5923.3 4969.8 5376.7 5524.5 5617.0 5784.7 09:29:12 INFO - PROCESS | 1657 | 09:29:12 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:21302.32 Median:17142.90 stddev:30681.39 (179.0%) stddev-sans-first:30728.67 09:29:12 INFO - PROCESS | 1657 | Values: 755.5 768.6 794.7 899.3 910.8 16526.8 17915.4 17920.8 17931.3 17985.6 16317.9 16386.3 16502.2 16580.9 16584.8 850.0 997.1 1006.2 1037.5 1041.9 1039.7 1041.4 1045.0 1045.1 1046.6 1084.1 1088.8 1089.5 1089.7 1091.7 1075.8 1083.9 1085.7 1086.3 1089.0 796.6 798.9 804.5 805.1 806.2 16790.0 17000.7 17285.1 17412.9 17690.8 14605.6 14724.3 14847.2 14897.8 15017.5 11833.8 12110.4 12141.9 12425.7 12911.4 3818.3 3872.1 3887.5 3896.5 4003.3 98930.0 103231.0 130473.0 132446.7 133203.3 10777.1 11608.2 11739.1 12042.3 12350.7 21452.8 23350.6 23486.5 23515.5 23519.9 8124.5 8170.7 8386.5 8456.3 8568.3 4579.5 4630.8 4635.7 4651.8 4813.2 3303.7 3719.3 3771.7 3794.2 3803.4 674.5 674.9 680.1 702.1 705.5 679.9 701.7 713.7 718.3 753.2 8843.6 10689.6 10801.8 10965.1 11206.3 663.2 686.6 696.5 716.9 728.7 5024.3 5141.1 5146.4 5154.5 5161.1 107016.7 108690.0 111436.0 113562.4 117073.0 62039.0 65135.3 73233.0 83462.6 85284.0 74503.6 78754.0 81814.8 84496.2 85000.0 9166.0 11092.9 11333.1 11517.3 11740.4 41280.0 50384.0 53094.5 54287.0 54701.2 67572.3 68936.7 69014.7 69670.7 69997.7 12885.7 13212.8 13341.8 13615.0 13858.8 15287.2 15307.8 15389.2 15725.4 15737.8 14049.2 14062.1 14510.5 14905.6 14906.6 13654.9 14313.4 14316.7 14455.2 14473.5 13399.3 13464.6 14147.1 14182.2 14307.8 9981.0 10124.5 10281.0 10368.3 10509.3 8951.9 11325.7 11596.3 11619.5 11715.4 09:29:12 INFO - PROCESS | 1657 | -------- Summary: end -------- 09:29:12 INFO - PROCESS | 1657 | 09:29:12 INFO - TEST-INFO | 1657: exit 0 09:29:12 INFO - TEST-OK | dromaeo_css | took 1114988ms 09:29:12 INFO - TEST-START | kraken 09:29:12 INFO - operating with platform_type : mac_ 09:29:12 INFO - Initialising browser for kraken test... 09:29:12 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmpeWzV_K/profile http://localhost:49244/getInfo.html) 09:29:15 INFO - PROCESS | 1668 | 2017-04-20 09:29:15.497 plugin-container[1670:10236] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 09:29:15 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:29:15 INFO - PROCESS | 1668 | 2017-04-20 09:29:15.497 plugin-container[1670:10236] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 09:29:15 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:29:15 INFO - PROCESS | 1668 | 2017-04-20 09:29:15.497 plugin-container[1670:10236] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:29:15 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 09:29:15 INFO - PROCESS | 1668 | colorDepth:24 09:29:15 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 09:29:15 INFO - PROCESS | 1668 | __metrics 09:29:15 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 09:29:16 INFO - TEST-INFO | 1668: exit 0 09:29:16 INFO - Browser initialized. 09:29:16 INFO - Running cycle 1/1 for kraken test... 09:29:16 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmpeWzV_K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:29:18 INFO - PROCESS | 1671 | 2017-04-20 09:29:18.664 plugin-container[1672:10360] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x842f, name = 'com.apple.tsm.portname' 09:29:18 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:29:18 INFO - PROCESS | 1671 | 2017-04-20 09:29:18.665 plugin-container[1672:10360] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450b, name = 'com.apple.CFPasteboardClient' 09:29:18 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:29:18 INFO - PROCESS | 1671 | 2017-04-20 09:29:18.665 plugin-container[1672:10360] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:29:28 INFO - PROCESS | 1671 | RSS: Main: 174727168 09:29:28 INFO - PROCESS | 1671 | 09:29:31 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 09:29:32 INFO - PROCESS | 1671 | RSS: Main: 187944960 09:29:32 INFO - PROCESS | 1671 | 09:29:35 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 09:29:35 INFO - PROCESS | 1671 | RSS: Main: 195870720 09:29:35 INFO - PROCESS | 1671 | 09:29:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 09:29:39 INFO - PROCESS | 1671 | RSS: Main: 197545984 09:29:39 INFO - PROCESS | 1671 | 09:29:41 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 09:29:42 INFO - PROCESS | 1671 | RSS: Main: 200286208 09:29:42 INFO - PROCESS | 1671 | 09:29:44 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:29:44 INFO - PROCESS | 1671 | RSS: Main: 200925184 09:29:44 INFO - PROCESS | 1671 | 09:29:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 09:29:48 INFO - PROCESS | 1671 | RSS: Main: 205344768 09:29:48 INFO - PROCESS | 1671 | 09:29:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 09:29:52 INFO - PROCESS | 1671 | RSS: Main: 204173312 09:29:52 INFO - PROCESS | 1671 | 09:29:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 09:29:55 INFO - PROCESS | 1671 | RSS: Main: 204230656 09:29:55 INFO - PROCESS | 1671 | 09:29:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:29:58 INFO - PROCESS | 1671 | RSS: Main: 203886592 09:29:58 INFO - PROCESS | 1671 | 09:30:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:30:00 INFO - PROCESS | 1671 | RSS: Main: 203931648 09:30:00 INFO - PROCESS | 1671 | 09:30:02 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:30:03 INFO - PROCESS | 1671 | RSS: Main: 203960320 09:30:03 INFO - PROCESS | 1671 | 09:30:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:30:07 INFO - PROCESS | 1671 | RSS: Main: 204382208 09:30:07 INFO - PROCESS | 1671 | 09:30:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:30:10 INFO - PROCESS | 1671 | RSS: Main: 204447744 09:30:10 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 09:30:12 INFO - PROCESS | 1671 | RSS: Main: 206426112 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | __start_tp_report 09:30:12 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 09:30:12 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 09:30:12 INFO - PROCESS | 1671 | |i|pagename|runs| 09:30:12 INFO - PROCESS | 1671 | |0;ai-astar;97;133;168;121;123;122;122;121;123;121 09:30:12 INFO - PROCESS | 1671 | |1;audio-beat-detection;142;131;130;142;111;121;129;132;128;113 09:30:12 INFO - PROCESS | 1671 | |2;audio-dft;209;265;202;199;198;197;199;222;264;242 09:30:12 INFO - PROCESS | 1671 | |3;audio-fft;81;76;79;100;61;58;58;77;91;59 09:30:12 INFO - PROCESS | 1671 | |4;audio-oscillator;89;70;83;72;72;69;81;92;97;97 09:30:12 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;111;121;122;120;90;92;90;89;90;90 09:30:12 INFO - PROCESS | 1671 | |6;imaging-darkroom;101;98;99;98;97;128;98;109;140;103 09:30:12 INFO - PROCESS | 1671 | |7;imaging-desaturate;87;93;95;89;91;92;91;104;116;92 09:30:12 INFO - PROCESS | 1671 | |8;json-parse-financial;65;66;66;66;67;69;67;67;68;68 09:30:12 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;57;59;76;65;70;84;77;80;84;57 09:30:12 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;84;82;86;84;84;87;95;96;86;87 09:30:12 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;144;144;138;135;136;135;137;140;212;140 09:30:12 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;200;200;164;166;160;206;144;206;163;210 09:30:12 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;75;80;82;80;82;82;65;62;64;64 09:30:12 INFO - PROCESS | 1671 | __end_tp_report 09:30:12 INFO - PROCESS | 1671 | __start_cc_report 09:30:12 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,984 09:30:12 INFO - PROCESS | 1671 | __end_cc_report 09:30:12 INFO - PROCESS | 1671 | __startTimestamp1492705812759__endTimestamp 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | ------- Summary: start ------- 09:30:12 INFO - PROCESS | 1671 | Number of tests: 14 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:125.10 Median:123.00 stddev:17.55 (14.3%) stddev-sans-first:15.38 09:30:12 INFO - PROCESS | 1671 | Values: 97.0 133.0 168.0 121.0 123.0 122.0 122.0 121.0 123.0 121.0 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:130.50 stddev:10.46 (8.0%) stddev-sans-first:9.77 09:30:12 INFO - PROCESS | 1671 | Values: 142.0 131.0 130.0 142.0 111.0 121.0 129.0 132.0 128.0 113.0 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:219.70 Median:215.50 stddev:27.46 (12.7%) stddev-sans-first:28.86 09:30:12 INFO - PROCESS | 1671 | Values: 209.0 265.0 202.0 199.0 198.0 197.0 199.0 222.0 264.0 242.0 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:74.00 Median:76.50 stddev:14.75 (19.3%) stddev-sans-first:15.43 09:30:12 INFO - PROCESS | 1671 | Values: 81.0 76.0 79.0 100.0 61.0 58.0 58.0 77.0 91.0 59.0 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:82.20 Median:86.00 stddev:11.12 (12.9%) stddev-sans-first:11.52 09:30:12 INFO - PROCESS | 1671 | Values: 89.0 70.0 83.0 72.0 72.0 69.0 81.0 92.0 97.0 97.0 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:101.50 Median:90.00 stddev:14.94 (16.6%) stddev-sans-first:15.44 09:30:12 INFO - PROCESS | 1671 | Values: 111.0 121.0 122.0 120.0 90.0 92.0 90.0 89.0 90.0 90.0 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:107.10 Median:97.50 stddev:14.88 (15.3%) stddev-sans-first:15.62 09:30:12 INFO - PROCESS | 1671 | Values: 101.0 98.0 99.0 98.0 97.0 128.0 98.0 109.0 140.0 103.0 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:91.50 stddev:8.67 (9.5%) stddev-sans-first:8.70 09:30:12 INFO - PROCESS | 1671 | Values: 87.0 93.0 95.0 89.0 91.0 92.0 91.0 104.0 116.0 92.0 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:66.90 Median:67.00 stddev:1.20 (1.8%) stddev-sans-first:1.05 09:30:12 INFO - PROCESS | 1671 | Values: 65.0 66.0 66.0 66.0 67.0 69.0 67.0 67.0 68.0 68.0 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:76.50 stddev:10.82 (14.1%) stddev-sans-first:10.24 09:30:12 INFO - PROCESS | 1671 | Values: 57.0 59.0 76.0 65.0 70.0 84.0 77.0 80.0 84.0 57.0 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:86.50 stddev:4.70 (5.4%) stddev-sans-first:4.85 09:30:12 INFO - PROCESS | 1671 | Values: 84.0 82.0 86.0 84.0 84.0 87.0 95.0 96.0 86.0 87.0 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:140.00 stddev:23.39 (16.7%) stddev-sans-first:24.79 09:30:12 INFO - PROCESS | 1671 | Values: 144.0 144.0 138.0 135.0 136.0 135.0 137.0 140.0 212.0 140.0 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:200.00 stddev:24.61 (12.3%) stddev-sans-first:25.22 09:30:12 INFO - PROCESS | 1671 | Values: 200.0 200.0 164.0 166.0 160.0 206.0 144.0 206.0 163.0 210.0 09:30:12 INFO - PROCESS | 1671 | 09:30:12 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:80.00 stddev:8.75 (10.9%) stddev-sans-first:9.26 09:30:12 INFO - PROCESS | 1671 | Values: 75.0 80.0 82.0 80.0 82.0 82.0 65.0 62.0 64.0 64.0 09:30:12 INFO - PROCESS | 1671 | -------- Summary: end -------- 09:30:12 INFO - PROCESS | 1671 | 09:30:13 INFO - TEST-INFO | 1671: exit 0 09:30:13 INFO - TEST-OK | kraken | took 60662ms 09:30:13 INFO - SUITE-END | took 1175s 09:30:13 INFO - Completed test suite (00:19:35) 09:30:13 INFO - javascript benchmark 09:30:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7975.81714477687, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3299.1257316811034, 3645.453182022697, 3658.945115823263, 3670.119171398865, 3692.316921369547, 6137.324894261633, 6168.907466388007, 6181.412765787249, 6188.8143355699285, 6225.657588832612, 6197.0, 6214.906776398358, 6246.437820596146, 6263.498920086394, 6273.435390814826, 4818.421789385278, 5600.887982240352, 5679.977604031273, 5702.71688870003, 5749.396313387076, 4897.363342765436, 5757.337906140785, 5761.683107429089, 5781.537477001851, 5816.33112192097, 5527.585431092664, 5536.114221724525, 5753.510951569122, 5754.1944127822135, 5787.04513755232, 5663.4336566343445, 5699.458551437628, 5771.422857714237, 5809.854753631151, 5834.474897259224, 5548.001359755242, 5673.546116310686, 5692.345380281279, 5695.373508914016, 5770.01909675352, 6027.668478233695, 6043.516518678495, 6189.071639254103, 6207.720652570645, 6243.938530449789, 6332.430081292659, 6354.332795056499, 6355.237371515402, 6411.903821442682, 6425.45383642391, 6614.702338394735, 6638.6016838989335, 6643.435307998826, 6651.401373876326, 6680.164979377578, 6286.182796236506, 6289.842753931142, 6293.496520278367, 6304.338044505353, 6320.683965801692, 6344.524160687874, 6487.091807147003, 6498.805035848932, 6597.637129957802, 6734.158230221223, 6451.1936007999, 6451.9354806452275, 6455.4835613150835, 6458.0, 6464.095026696266, 6162.845928851725, 6206.348333424925, 6583.539152259296, 6666.200055993312, 6674.733010679615, 5294.205869119663, 6023.969880150571, 6275.466585340206, 6370.522210834206, 6535.705893234721, 4933.802647894116, 6333.493320534346, 6387.169667943138, 6443.452306553995, 6472.967635161888, 5100.0, 5144.794208231704, 5213.400458947193, 5251.894962100737, 5259.342582177228, 4273.504273504279, 4553.453585569753, 4652.860414187512, 4751.121042607059, 4751.952480475151, 6338.809835704937, 6340.397662222082, 6345.397187267202, 6347.873042539123, 6374.330695277022, 6288.339724328971, 6384.3934826191435, 6524.445422139076, 6525.706343214567, 6662.800115996528, 5402.756875940592, 6316.115743795872, 6330.936690633127, 6336.334684858116, 6347.492200623939, 5847.210626565445, 5855.736491857877, 5864.882702346014, 5867.530597552271, 5879.118132280192, 6138.987067133873, 6683.933160668331, 6770.52606317553, 6967.268436814062, 6998.090248267693, 5637.9718101409235, 6552.049952756728, 6566.9343306566325, 6716.026176204326, 6739.66301684904, 6976.02335672996, 7016.26329235423, 7017.5087743859485, 7017.964910175417, 7027.964860175666, 6274.466670333073, 6452.161219041494, 6581.111549940888, 6585.275619681927, 6628.237752658313, 6271.560990730605, 6436.259830119409, 6605.57063790852, 6665.733370665119, 6689.76585819494, 6417.96791016042, 6421.1010458536775, 6423.710933007932, 6431.228252609717, 6432.131662225727, 6293.905591416224, 6429.260635027032, 6430.967845160744, 6460.418562329225, 6467.902981455188, 5320.0, 6185.876282474415, 6209.130721699056, 6222.751089956351, 6251.3123556407845, 6388.265349484869, 6399.2640846303275, 6407.679616019273, 6411.038344248213, 6465.870682586416, 6188.566800323934, 6206.503479721521, 6251.562390632612, 6313.873722525615, 6318.304986451395], "value": 6041.994177134095, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.1931894235282, 752.6086435053772, 785.9350579964148, 989.1592146675326, 989.2679417231251, 40163.598364016354, 40537.0, 42046.0, 42161.57838421615, 42494.78752606236, 38715.03212419684, 38728.0, 38824.22351552967, 38903.0, 38914.41628375577, 8351.290140338064, 11281.887181128166, 11569.247998880046, 11713.531458741683, 11738.178327517076, 11419.371934543597, 11490.540378384854, 11546.364949927793, 11552.1913466057, 11560.82658760115, 11455.255408398469, 11461.885381146165, 11550.480228389742, 11552.0, 11557.595484158057, 9948.253880958957, 11459.36973466459, 11459.656210313662, 11511.827322590168, 11525.0, 11142.108631309477, 11170.27393219438, 11212.66362009141, 11231.60689375876, 11243.437828108561, 30275.848620756755, 35932.10169745751, 35943.640563594294, 36027.81986090079, 36100.63899360999, 3913.236918800835, 3936.822842972073, 3948.802559871995, 3966.4050392441077, 4032.1330913853367, 26316.73683263162, 26550.46899062008, 26660.33349166267, 27975.58036629452, 28898.711012889813, 2797.5943488194303, 3211.5022171563446, 3305.7851239669344, 3321.551590535271, 3399.881004164867, 346311.0, 349990.0, 360793.0, 362729.0, 446275.5372446234, 45814.31278530825, 45831.62505124851, 46037.76981115073, 46163.30755038677, 46214.76892615516, 47776.28335574969, 48194.0, 48371.0, 48438.0, 48471.0, 14386.28068596566, 14459.13245205291, 14462.421503139967, 14497.420103195966, 14536.05515641501, 12607.49570017189, 12707.555235566711, 12733.872661273268, 12743.299118548384, 12743.490260389666, 11286.435678216056, 11377.772444551063, 12388.389509363906, 12457.626271211879, 12508.249505029728, 5741.937741517832, 5744.913826292609, 5794.971025144931, 5796.913046304308, 5812.38969908162, 1127.808272593661, 1132.7621199548187, 1190.214458457414, 1219.6341097670663, 1229.6679896427913, 27755.722442775314, 27760.0, 28366.85816570945, 28408.573871391945, 28497.71502284992, 1066.8666416697913, 1281.4938099450665, 1308.1235572166672, 1309.461382875118, 1311.475409836054, 15043.623909402353, 16378.935369200964, 16396.590085247964, 16777.496675099766, 17116.828831711773, 487439.0, 489479.0, 489877.0, 490817.0, 491489.0, 56824.0, 57354.42645573491, 57368.71315643479, 57460.425395746344, 57472.71263643738, 1370.7601169795246, 1503.812023497063, 1547.388781431351, 1552.750036220853, 1564.9530514084595, 28378.85810570934, 29316.560251595816, 29628.962986295377, 30065.0, 31134.37731245408, 49062.75468622634, 49161.0, 49165.75417122892, 49282.26076608925, 49377.0, 495825.0417495923, 498858.0, 499175.0, 499213.0, 499300.00699992536, 28068.578971315037, 28071.0175143869, 33090.834545827114, 34310.82844585761, 34542.0, 40502.0, 40554.1889162221, 40886.79556602198, 41716.0, 41718.0, 28973.7102628971, 29349.55975660324, 29894.402111957203, 30320.54519182258, 30323.241918952204, 29368.559471607514, 29485.410291793614, 29502.114936551938, 30280.0, 30337.393252134392, 33114.66885331116, 34323.06757873573, 34560.82719586386, 34725.82637086798, 34799.65200347964, 28338.866445341955, 28464.85767571149, 28823.56764648574, 28868.133955981353, 29507.967221147155, 18656.160472778487, 21035.789642103384, 22586.887065564566, 23505.0, 24003.559786412872], "value": 19230.55539254229, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.38456622463633, 434.30944797771537, 442.9424174857268, 448.5245639622, 452.71478968250017, 19297.807021929777, 19558.31545895894, 21163.365099047016, 22536.43658908526, 23156.07375704974, 23047.654285185694, 23368.0, 23619.645705314433, 24155.0, 24429.900654470548, 6783.287754785751, 6795.422389096921, 7078.362947334739, 7087.397571206454, 7191.820204494903, 7051.541649792747, 7095.0, 7102.218755936843, 7146.428285737128, 7155.3202445767565, 6798.558093723892, 7070.292970702914, 7135.750248741295, 7149.320814522612, 7153.89269160963, 6918.861622767517, 7127.0734804475605, 7140.928860670889, 7229.313215244543, 7230.313120253594, 6715.395614394679, 6734.595924244512, 6748.7637932672615, 6758.391744742948, 6761.492888033417, 27006.72993270062, 27204.727952720616, 27353.179404617895, 27388.452230955467, 27612.0, 3474.513568100468, 3522.8590856365713, 3533.752637315389, 3563.750537462379, 3650.799206043693, 16213.513594592063, 17324.740128898076, 17501.7374739379, 17602.471925842245, 17972.0, 2121.840861935361, 2161.5136594266164, 2187.0923566719794, 2205.6029914615365, 2389.602082781575, 101133.49433252787, 126739.36630316882, 142517.0, 144495.27752361275, 153047.0, 19421.805781942, 21491.46271343229, 21556.56886862254, 21591.02840372187, 21718.78281217199, 32315.838420807744, 32616.34767304688, 32667.0, 32893.50659740106, 32904.506432403534, 9248.583813728399, 10902.727431814268, 10922.962318579723, 10947.0, 11020.50407731654, 7809.492382995087, 8336.749897503085, 8623.611937462703, 9204.0, 9382.67160649374, 9449.622015119317, 9718.61125554984, 9758.072983066597, 9806.803863922823, 9815.509224538888, 3763.6989040876665, 4110.753354798721, 4195.832166713297, 4278.465191851019, 4311.956880431218, 822.8601137806587, 824.1484463755473, 905.7735566108473, 916.9037251088673, 930.265090578449, 19510.512237194183, 20696.896515517627, 21091.578168436547, 21161.075587298696, 21180.364589062352, 903.7198468474794, 951.4671783801093, 953.8664586957832, 983.1986930651639, 983.6114734679904, 10922.440115361127, 11054.00513953748, 11154.21920465576, 11236.438178091063, 11258.774824503627, 596124.1550338025, 726179.0, 755229.0, 765065.0, 784326.0, 120982.54453841888, 144853.0, 149940.0, 151560.0, 154617.0, 1098.5441041967495, 1184.3498026896602, 1265.6266401411606, 1270.142653708743, 1270.3394234997934, 19596.0, 19861.30485433003, 20123.597528049064, 20146.0, 20218.090185941375, 58748.706256468446, 59150.0, 59263.0, 59338.40661593329, 60411.48971275753, 81216.78174827264, 82271.0, 83546.58226708828, 85002.0, 85128.57435712781, 9895.0, 9960.701178964642, 11515.884841151481, 11572.94213528927, 11774.058075354122, 12921.806172907602, 13010.284434356201, 13141.277229752455, 13271.004674649246, 13446.529371471952, 11293.0, 11300.434978251218, 11500.654980350602, 11551.595694150665, 11684.18210725241, 9359.672411465566, 10647.574097036031, 10880.075193608633, 11003.724906877393, 11017.228793984345, 10327.535260913428, 10420.843687344544, 10481.794593621526, 11618.767624647291, 11757.294562326288, 11643.708907277385, 11897.524099035942, 12220.75558488843, 12236.632901012983, 12268.815967760669, 11568.826467602825, 11691.473883675426, 11696.649100526998, 11894.643160705193, 11950.641480755592], "value": 11732.469654553166, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1593.8246792852776, 1635.0189886068356, 1636.7299395599719, 1640.2618821530314, 1659.3943210728085, 11017.61438349657, 11471.713207169825, 11532.827007594893, 11645.359505227209, 11741.823872641897, 11635.418229088533, 11978.40107994599, 12086.0, 12104.09219308551, 12141.392930353491, 2454.079720104965, 2487.3657217409536, 2496.0015993602524, 2506.6741323627994, 2511.196417146514, 2441.56051910656, 2447.7919376852988, 2483.801295896324, 2486.8880900359436, 2492.4142826435864, 2162.772908844572, 2436.4396188876567, 2456.9017239310406, 2469.71598266199, 2490.8380955237944, 2149.7312835895514, 2380.690620158923, 2416.9758302417013, 2431.55016321981, 2445.08309383981, 2369.3484291819714, 2389.661789397943, 2391.8564886106888, 2397.724261709891, 2401.267613377919, 11364.772704545945, 11551.306921584732, 11575.247608905394, 11588.0, 11595.768084638345, 5985.042393217064, 6144.446999769997, 6202.3797620238065, 6205.131281620576, 6219.40915613016, 3584.4981702561663, 3703.6851867591286, 3766.510353654035, 3791.545014598238, 3834.328992426342, 1252.7995520716734, 1264.2856785915928, 1274.528424482938, 1295.4300107952565, 1332.7068045030176, 14145.787813182811, 14409.927950360287, 14956.850431495763, 15636.843631563652, 15974.28115734783, 4960.355153830051, 5043.36957880265, 5080.0, 5088.160453525127, 5140.306058682105, 13498.86501134976, 13514.324283785969, 13521.526746564019, 13541.661458463617, 13541.932290338682, 5969.343372229051, 6084.117802918552, 6135.877282454415, 6163.568550201532, 6167.876642467126, 5444.128939369682, 5487.75305111271, 5493.176023596413, 5520.558355331564, 5542.251796007487, 4096.774677392772, 4235.364695295669, 4237.78811059452, 4296.763677997677, 4321.719088259253, 1037.766502536923, 1119.7592517608591, 1171.2211379432752, 1174.5008371442102, 1182.261086820737, 964.6912987843823, 980.7793246519476, 1004.3271008424373, 1004.8794144702537, 1009.7071849163807, 6554.475641948633, 6593.274739778621, 6741.59550426976, 6748.392644662004, 6813.284605116491, 953.8855337359561, 992.9453880036523, 1007.8639383683255, 1020.3622735790132, 1021.213665477578, 3788.791616461121, 4068.3694027425795, 4319.114581510783, 4428.778561071934, 4537.6143027843, 13661.726765464635, 14309.713805724035, 14311.212883291519, 14340.641483962985, 14443.350049247809, 13748.93125534386, 13796.241206733524, 14104.576862694135, 14157.504487342894, 14173.0, 10362.792744145076, 10526.368417895104, 10579.471026448648, 10595.94702026485, 10597.84103238452, 5667.3482549507335, 6653.168353955755, 6728.562643428161, 6777.01733248686, 6802.387785099167, 12260.32568208757, 12522.0, 12567.18313309632, 12576.748465030465, 12606.810897836356, 10608.0, 11841.407929603658, 12624.936875315565, 12975.351232438528, 13199.340032998503, 9418.152366287068, 9581.377210481296, 9619.759506012406, 9643.180329672057, 9662.420254784736, 10485.213608979204, 10790.56837726482, 10994.230403871652, 11049.72375690582, 11059.889401105886, 7718.498297610638, 8978.422134104205, 9100.577483127783, 9187.632494700138, 9333.206677432494, 8811.074837142009, 8846.823063538823, 8878.73363799087, 8900.287976961697, 8904.510251935946, 8176.509409435453, 8267.83464330722, 8289.253967142746, 8343.499390036375, 8345.123762004901, 6032.366601506954, 6051.4553690168095, 6136.539759518143, 6186.505079593531, 6347.301796802256, 6025.578209525291, 6116.541259405473, 6147.66187859672, 6206.06908963659, 6269.7178626962805], "value": 5345.420027725772, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.05606432433507, 391.44805823788465, 391.6455607675054, 394.38869751884573, 405.3231104056226, 4486.730796152233, 4659.557342052503, 5255.737213139342, 5295.735213239333, 5488.341399032121, 5342.946570534294, 5412.161115027177, 5420.756065977031, 5455.527007707917, 5455.945440545604, 4121.949757179696, 4337.587929146742, 4352.782360881959, 4513.458384993805, 4577.748223847687, 4482.148391805563, 4505.7296562206375, 4509.571590698878, 4545.4545454545505, 4622.768861556926, 3954.387070004154, 3991.261616600938, 4421.0, 4561.657875659339, 4607.76961151941, 3715.16408808016, 3955.804528486689, 4351.6953813233085, 4472.127935052666, 4593.701409408378, 3574.195031509097, 3810.447485114643, 4072.898177545585, 4078.3678529827926, 4102.261592913274, 4702.788374523155, 5252.4222335543445, 5257.106291930343, 5310.734463276859, 5315.362156541201, 4131.648809851166, 4598.505485717122, 4936.284238785356, 5149.974250128763, 5202.3497062867145, 5223.112070947948, 5748.0, 5767.855803604902, 5776.0, 5782.624129431573, 4199.811008504625, 4246.617804397619, 4628.472604040659, 4640.628749700049, 4641.234196357597, 4606.646718850688, 5775.364709881909, 5899.0561510158595, 6083.7566497340495, 6130.356312587159, 4653.906921861611, 4671.212007692143, 4689.390379250675, 4728.929066064012, 4752.388184979441, 4211.389348544444, 4430.645548356124, 4730.030343779517, 4757.666963312535, 4783.234682450791, 4518.932216016762, 4789.653069995747, 4860.73265970375, 4884.8290309838985, 5064.5441910228255, 5056.926660069427, 5456.154296084113, 5758.62568933018, 5761.822678558012, 5845.503132233808, 1557.769362203869, 1565.8434156584478, 1578.7552929295978, 1580.857722804953, 1636.4599682104877, 985.4530735441755, 1017.0693815159194, 1096.6216655254084, 1222.5232159457819, 1226.2642414551199, 5106.438226878443, 5533.118739628546, 5551.639143455663, 5586.525145362609, 5595.328560572757, 5281.234221037928, 5747.712614369265, 5965.463108320272, 5998.940010599838, 6054.0, 4381.910752919184, 5038.546530812245, 5223.503767142115, 5509.421510741395, 5515.7242137892945, 2793.6787269463866, 2805.382815780525, 2940.5442156465783, 3102.402262834642, 3117.890873819451, 5119.846404607868, 5278.683279003272, 5724.767340737116, 6068.544859135583, 6347.0, 4594.1271158479785, 5865.501432378211, 5876.412358764175, 6048.848778780478, 6058.27300723916, 5188.351456067991, 5376.650517716336, 6151.2618485781995, 6259.4679452246155, 6347.444876005408, 5179.093658609803, 5310.937812437451, 5996.970015149896, 6010.655844220845, 6024.349286314008, 5170.508801663836, 5213.661112027706, 5511.629999100425, 5551.167324901297, 5858.677772722371, 5697.857553561194, 5715.314162300384, 6176.170774120074, 6277.278113016879, 6419.0, 4800.66395352322, 4830.2996065571, 5038.256387718077, 5261.710605916712, 5272.0, 4627.0, 5209.453007434165, 5226.372835259793, 6044.697765111815, 6075.605085669417, 4752.714837109785, 4870.0, 5214.452482489284, 5645.95549823284, 5808.854778630568, 5129.717865517432, 5461.5357694596405, 5603.691796951044, 5607.317804658667, 5649.6327738696855, 4931.654784165074, 5220.817271395483, 5322.973385133049, 5626.662400255997, 5632.2678051853, 5029.29589857413, 5805.564582656233, 5853.853653658693, 5868.2664666916635, 5923.289205295392, 4969.82605608802, 5376.704281264411, 5524.530414914777, 5617.0, 5784.686125549813], "value": 4382.88223903146, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.4636208292112, 768.55039801716, 794.6622685358722, 899.3030401438884, 910.7677542226734, 16526.752098718527, 17915.372961946334, 17920.820791792077, 17931.282748690068, 17985.64028719425, 16317.918410407932, 16386.344546218137, 16502.17489125545, 16580.917095414537, 16584.75122873158, 849.9575021248944, 997.0677416615488, 1006.1699098243942, 1037.501999040461, 1041.9062284394404, 1039.682896716501, 1041.4376236832102, 1045.0229035851464, 1045.0646671229244, 1046.6022911293699, 1084.0677017764715, 1088.787686401152, 1089.4716062709579, 1089.6894385100247, 1091.683411810578, 1075.7955988362226, 1083.864516935383, 1085.67972448128, 1086.277625379122, 1088.983665245022, 796.5618909599697, 798.9280964713204, 804.5454318310136, 805.0942689474341, 806.1615919443772, 16790.0, 17000.659986800318, 17285.135743212915, 17412.912935435368, 17690.823091769045, 14605.6348591285, 14724.263786810616, 14847.183404912725, 14897.77653335201, 15017.549473515812, 11833.763324733543, 12110.39448027604, 12141.93929030358, 12425.689357766038, 12911.354432278442, 3818.2745278397015, 3872.0707030312806, 3887.5140607424073, 3896.5129358830145, 4003.2794097062515, 98930.0, 103230.99999999924, 130473.0, 132446.67553324538, 133203.33398333043, 10777.137828973664, 11608.187426880037, 11739.119566032581, 12042.337671428155, 12350.691232719253, 21452.78547214539, 23350.64974025391, 23486.530269394516, 23515.529689406118, 23519.88240058789, 8124.471909325875, 8170.714025009096, 8386.454880432751, 8456.2812160967, 8568.314534837198, 4579.519150489217, 4630.791614377361, 4635.7218566886095, 4651.813927442864, 4813.205821039489, 3303.7357011439026, 3719.34911390511, 3771.735978481535, 3794.165283637595, 3803.372443546784, 674.4671709349657, 674.865026994603, 680.0920770771075, 702.1013103227876, 705.5237714542664, 679.8572299817093, 701.7298340139083, 713.6895450479058, 718.2960698515379, 753.2279413600993, 8843.646254149762, 10689.625863094754, 10801.83797243042, 10965.122790176767, 11206.327620342805, 663.2339647706906, 686.6463771157858, 696.4532841719226, 716.8566286742672, 728.6575309604477, 5024.346834911438, 5141.126008578551, 5146.408163061295, 5154.484551544891, 5161.148410512224, 107016.71933122743, 108690.0, 111436.0, 113562.43218783854, 117073.0, 62039.0, 65135.34864651388, 73233.0, 83462.58268708739, 85284.0, 74503.62748186225, 78754.0, 81814.77277840837, 84496.15503845006, 85000.0, 9165.954170229106, 11092.944535277273, 11333.093352531943, 11517.308961462339, 11740.354280514654, 41280.0, 50384.0, 53094.469055310496, 54287.0, 54701.179482307, 67572.32427675858, 68936.65531672309, 69014.65492672505, 69670.65164674145, 69997.65001175166, 12885.742285154058, 13212.801807973081, 13341.799873002106, 13615.0, 13858.792118118035, 15287.235638218268, 15307.787095840564, 15389.230538473255, 15725.370985160465, 15737.842621573638, 14049.22729249901, 14062.085964412281, 14510.492132775302, 14905.552833415015, 14906.552803415914, 13654.93172534131, 14313.355898984362, 14316.713665726418, 14455.204963727096, 14473.493427729978, 13399.263040532864, 13464.596062118153, 14147.080439771382, 14182.219977900902, 14307.785383219469, 9981.0, 10124.544395502073, 10281.0, 10368.326058806153, 10509.316894402145, 8951.910480895107, 11325.71685707864, 11596.304221746723, 11619.47712352963, 11715.355655439032], "value": 8059.515644726359, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1559.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 133.0, 168.0, 121.0, 123.0, 122.0, 122.0, 121.0, 123.0, 121.0], "value": 125.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 131.0, 130.0, 142.0, 111.0, 121.0, 129.0, 132.0, 128.0, 113.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 265.0, 202.0, 199.0, 198.0, 197.0, 199.0, 222.0, 264.0, 242.0], "value": 219.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 76.0, 79.0, 100.0, 61.0, 58.0, 58.0, 77.0, 91.0, 59.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 83.0, 72.0, 72.0, 69.0, 81.0, 92.0, 97.0, 97.0], "value": 82.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 121.0, 122.0, 120.0, 90.0, 92.0, 90.0, 89.0, 90.0, 90.0], "value": 101.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 98.0, 99.0, 98.0, 97.0, 128.0, 98.0, 109.0, 140.0, 103.0], "value": 107.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 93.0, 95.0, 89.0, 91.0, 92.0, 91.0, 104.0, 116.0, 92.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 66.0, 67.0, 69.0, 67.0, 67.0, 68.0, 68.0], "value": 66.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 76.0, 65.0, 70.0, 84.0, 77.0, 80.0, 84.0, 57.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 86.0, 84.0, 84.0, 87.0, 95.0, 96.0, 86.0, 87.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 144.0, 138.0, 135.0, 136.0, 135.0, 137.0, 140.0, 212.0, 140.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 200.0, 164.0, 166.0, 160.0, 206.0, 144.0, 206.0, 163.0, 210.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 80.0, 82.0, 80.0, 82.0, 82.0, 65.0, 62.0, 64.0, 64.0], "value": 73.6, "unit": "score"}]}]} 09:30:14 INFO - Return code: 0 09:30:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:30:14 ERROR - # TBPL SUCCESS # 09:30:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:30:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:30:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:30:14 INFO - 2017-04-20 09:30:14.096 screenresolution[1677:10749] starting screenresolution argv=screenresolution get 09:30:14 INFO - 2017-04-20 09:30:14.099 screenresolution[1677:10749] Display 0: 1600x1200x32@60 09:30:14 INFO - 2017-04-20 09:30:14.105 screenresolution[1678:10752] starting screenresolution argv=screenresolution list 09:30:14 INFO - Available Modes on Display 0 09:30:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:30:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:30:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:30:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:30:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:30:14 INFO - Intel Iris: 09:30:14 INFO - Chipset Model: Intel Iris 09:30:14 INFO - Type: GPU 09:30:14 INFO - Bus: Built-In 09:30:14 INFO - VRAM (Dynamic, Max): 1536 MB 09:30:14 INFO - Vendor: Intel (0x8086) 09:30:14 INFO - Device ID: 0x0a2e 09:30:14 INFO - Revision ID: 0x0009 09:30:14 INFO - Displays: 09:30:14 INFO - Display: 09:30:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:30:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:30:14 INFO - Display Serial Number: 1600x1200 60 09:30:14 INFO - Main Display: Yes 09:30:14 INFO - Mirror: Off 09:30:14 INFO - Online: Yes 09:30:14 INFO - Rotation: Supported 09:30:14 INFO - Return code: 0 09:30:14 INFO - Running post-action listener: _resource_record_post_action 09:30:14 INFO - [mozharness: 2017-04-20 16:30:14.351053Z] Finished run-tests step (success) 09:30:14 INFO - Running post-run listener: _resource_record_post_run 09:30:14 INFO - Total resource usage - Wall time: 1191s; CPU: 55.0%; Read bytes: 40314880; Write bytes: 424183808; Read time: 257; Write time: 1415 09:30:14 INFO - TinderboxPrint: CPU usage
54.7% 09:30:14 INFO - TinderboxPrint: I/O read bytes / time
40,314,880 / 257 09:30:14 INFO - TinderboxPrint: I/O write bytes / time
424,183,808 / 1,415 09:30:14 INFO - TinderboxPrint: CPU idle
2,158.0 (45.3%) 09:30:14 INFO - TinderboxPrint: CPU system
955.6 (20.1%) 09:30:14 INFO - TinderboxPrint: CPU user
1,652.2 (34.7%) 09:30:14 INFO - TinderboxPrint: Swap in / out
161,095,680 / 0 09:30:14 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121397760; Write bytes: 115684864; Read time: 11147; Write time: 249 09:30:14 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 38103040; Write bytes: 298353152; Read time: 235; Write time: 1133 09:30:14 INFO - Running post-run listener: _upload_blobber_files 09:30:14 INFO - Blob upload gear active. 09:30:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:30:14 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 09:30:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:30:15 INFO - (blobuploader) - INFO - Open directory for files ... 09:30:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:30:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:30:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:16 INFO - (blobuploader) - INFO - Done attempting. 09:30:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:30:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:30:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:17 INFO - (blobuploader) - INFO - Done attempting. 09:30:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:30:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:30:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:18 INFO - (blobuploader) - INFO - Done attempting. 09:30:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:30:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:30:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:20 INFO - (blobuploader) - INFO - Done attempting. 09:30:20 INFO - (blobuploader) - INFO - Iteration through files over. 09:30:20 INFO - Return code: 0 09:30:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:30:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:30:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1d4bac8a08863c2bbf8891391e8d16321e12b515addf6d2fc6497bdc07210617bd974aafcb74334071adbf55da78e549da1f090642c15988b87293a93cd771d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc329773667243259ded6c9d28313049c996afbfc196426c743e28504cafd114c68890b9771f7d4fcef8677552768a8199b13abcbf99afdb47c6a50b2094b476", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/374126788d5ad0a6fe91719c43b99f11b3783eb0f0ba67e2ee6b13177ea11ef1b951deda2012d759c88af583dc50e85da57a7ad32328ba1c9925dfe5d839ec43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ebef13071a568844b219cb17bbda00dd372b1ed3395d3c54d8be556453444f6469891286cb30bf5639ba92b356541f3419dfd89080da3e2113c2a9a15e91464"} 09:30:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:30:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:30:20 INFO - Contents: 09:30:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1d4bac8a08863c2bbf8891391e8d16321e12b515addf6d2fc6497bdc07210617bd974aafcb74334071adbf55da78e549da1f090642c15988b87293a93cd771d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc329773667243259ded6c9d28313049c996afbfc196426c743e28504cafd114c68890b9771f7d4fcef8677552768a8199b13abcbf99afdb47c6a50b2094b476", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/374126788d5ad0a6fe91719c43b99f11b3783eb0f0ba67e2ee6b13177ea11ef1b951deda2012d759c88af583dc50e85da57a7ad32328ba1c9925dfe5d839ec43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ebef13071a568844b219cb17bbda00dd372b1ed3395d3c54d8be556453444f6469891286cb30bf5639ba92b356541f3419dfd89080da3e2113c2a9a15e91464"} 09:30:20 INFO - Running post-run listener: copy_logs_to_upload_dir 09:30:20 INFO - Copying logs to upload dir... 09:30:20 INFO - mkdir: /builds/slave/test/build/upload/logs 09:30:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.751843 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-04-20 09:30:20.906638) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 09:30:20.910595) ========= 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.rOsvWri6To/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.TPgfIipPMy/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1d4bac8a08863c2bbf8891391e8d16321e12b515addf6d2fc6497bdc07210617bd974aafcb74334071adbf55da78e549da1f090642c15988b87293a93cd771d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc329773667243259ded6c9d28313049c996afbfc196426c743e28504cafd114c68890b9771f7d4fcef8677552768a8199b13abcbf99afdb47c6a50b2094b476", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/374126788d5ad0a6fe91719c43b99f11b3783eb0f0ba67e2ee6b13177ea11ef1b951deda2012d759c88af583dc50e85da57a7ad32328ba1c9925dfe5d839ec43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ebef13071a568844b219cb17bbda00dd372b1ed3395d3c54d8be556453444f6469891286cb30bf5639ba92b356541f3419dfd89080da3e2113c2a9a15e91464"} build_url:https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011383 build_url: 'https://queue.taskcluster.net/v1/task/a2NzZgdZRyKFOOolSwp-3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1d4bac8a08863c2bbf8891391e8d16321e12b515addf6d2fc6497bdc07210617bd974aafcb74334071adbf55da78e549da1f090642c15988b87293a93cd771d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc329773667243259ded6c9d28313049c996afbfc196426c743e28504cafd114c68890b9771f7d4fcef8677552768a8199b13abcbf99afdb47c6a50b2094b476", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/374126788d5ad0a6fe91719c43b99f11b3783eb0f0ba67e2ee6b13177ea11ef1b951deda2012d759c88af583dc50e85da57a7ad32328ba1c9925dfe5d839ec43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ebef13071a568844b219cb17bbda00dd372b1ed3395d3c54d8be556453444f6469891286cb30bf5639ba92b356541f3419dfd89080da3e2113c2a9a15e91464"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 09:30:20.944956) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 09:30:20.945307) ========= 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.rOsvWri6To/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.TPgfIipPMy/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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.005211 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 09:30:20.994118) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 09:30:20.994462) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 09:30:20.994806) ========= ========= Total master_lag: 0.18 =========