builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0030 starttime: 1490121044.96 results: success (0) buildid: 20170321103907 builduid: 3e2bdad172254bc6a51aa70de656b4bd revision: fbfd5cdc74f6c1075cf9e72065cdc203480a6a92 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 11:30:44.959815) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 11:30:44.960229) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 11:30:44.960496) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 11:30:44.985822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 11:30:44.986197) ========= 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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-03-21 11:30:44-- 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% 153M=0s 2017-03-21 11:30:45 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085857 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 11:30:45.095245) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 11:30:45.095560) ========= 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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.021397 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 11:30:45.143781) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 11:30:45.144128) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fbfd5cdc74f6c1075cf9e72065cdc203480a6a92 --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 fbfd5cdc74f6c1075cf9e72065cdc203480a6a92 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-03-21 11:30:45,162 truncating revision to first 12 chars 2017-03-21 11:30:45,162 Setting DEBUG logging. 2017-03-21 11:30:45,162 attempt 1/10 2017-03-21 11:30:45,162 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/fbfd5cdc74f6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-21 11:30:46,262 unpacking tar archive at: mozilla-beta-fbfd5cdc74f6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.375778 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 11:30:46.543788) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 11:30:46.544185) ========= script_repo_revision: fbfd5cdc74f6c1075cf9e72065cdc203480a6a92 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 11:30:46.544570) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 11:30:46.544831) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 11:30:46.564590) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-03-21 11:30:46.564931) ========= /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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 11:30:46 INFO - MultiFileLogger online at 20170321 11:30:46 in /builds/slave/test 11:30:46 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 11:30:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:30:46 INFO - {'append_to_log': False, 11:30:46 INFO - 'base_work_dir': '/builds/slave/test', 11:30:46 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:30:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:30:46 INFO - 'branch': 'Mozilla-Beta', 11:30:46 INFO - 'buildbot_json_path': 'buildprops.json', 11:30:46 INFO - 'config_files': ('talos/mac_config.py',), 11:30:46 INFO - 'default_actions': ('clobber', 11:30:46 INFO - 'read-buildbot-config', 11:30:46 INFO - 'download-and-extract', 11:30:46 INFO - 'populate-webroot', 11:30:46 INFO - 'create-virtualenv', 11:30:46 INFO - 'install', 11:30:46 INFO - 'run-tests'), 11:30:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:30:46 INFO - 'download_minidump_stackwalk': True, 11:30:46 INFO - 'download_symbols': 'ondemand', 11:30:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:30:46 INFO - 'tooltool.py': '/tools/tooltool.py', 11:30:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:30:46 INFO - '/tools/misc-python/virtualenv.py')}, 11:30:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:30:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:30:46 INFO - 'installer_path': 'installer.exe', 11:30:46 INFO - 'log_level': 'info', 11:30:46 INFO - 'log_name': 'talos', 11:30:46 INFO - 'log_to_console': True, 11:30:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:30:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:30:46 INFO - 'opt_config_files': (), 11:30:46 INFO - 'pip_index': False, 11:30:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:30:46 INFO - 'cmd': ('bash', 11:30:46 INFO - '-c', 11:30:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:30:46 INFO - 'enabled': True, 11:30:46 INFO - 'halt_on_failure': False, 11:30:46 INFO - 'name': 'check_screen_resolution'},), 11:30:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:30:46 INFO - 'cmd': ('bash', 11:30:46 INFO - '-c', 11:30:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:30:46 INFO - 'enabled': True, 11:30:46 INFO - 'halt_on_failure': False, 11:30:46 INFO - 'name': 'check_screen_resolution'},), 11:30:46 INFO - 'run_cmd_checks_enabled': True, 11:30:46 INFO - 'sps_profile': False, 11:30:46 INFO - 'sps_profile_interval': 0, 11:30:46 INFO - 'suite': 'dromaeojs-e10s', 11:30:46 INFO - 'system_bits': '32', 11:30:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:30:46 INFO - 'title': 't-yosemite-r7-0030', 11:30:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:30:46 INFO - 'use_talos_json': True, 11:30:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:30:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:30:46 INFO - 'work_dir': 'build'} 11:30:46 INFO - [mozharness: 2017-03-21 18:30:46.708824Z] Running clobber step. 11:30:46 INFO - Running pre-action listener: _resource_record_pre_action 11:30:46 INFO - Running main action method: clobber 11:30:46 INFO - rmtree: /builds/slave/test/build 11:30:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:30:46 INFO - Running post-action listener: _resource_record_post_action 11:30:46 INFO - [mozharness: 2017-03-21 18:30:46.909341Z] Finished clobber step (success) 11:30:46 INFO - [mozharness: 2017-03-21 18:30:46.909463Z] Running read-buildbot-config step. 11:30:46 INFO - Running pre-action listener: _resource_record_pre_action 11:30:46 INFO - Running main action method: read_buildbot_config 11:30:46 INFO - Using buildbot properties: 11:30:46 INFO - { 11:30:46 INFO - "project": "", 11:30:46 INFO - "product": "firefox", 11:30:46 INFO - "script_repo_revision": "production", 11:30:46 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 11:30:46 INFO - "repository": "", 11:30:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 11:30:46 INFO - "buildid": "20170321103907", 11:30:46 INFO - "pgo_build": "False", 11:30:46 INFO - "basedir": "/builds/slave/test", 11:30:46 INFO - "buildnumber": 12, 11:30:46 INFO - "slavename": "t-yosemite-r7-0030", 11:30:46 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:30:46 INFO - "platform": "yosemite_r7", 11:30:46 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 11:30:46 INFO - "branch": "mozilla-beta", 11:30:46 INFO - "repo_path": "releases/mozilla-beta", 11:30:46 INFO - "revision": "fbfd5cdc74f6c1075cf9e72065cdc203480a6a92", 11:30:46 INFO - "stage_platform": "macosx64", 11:30:46 INFO - "builduid": "3e2bdad172254bc6a51aa70de656b4bd", 11:30:46 INFO - "slavebuilddir": "test" 11:30:46 INFO - } 11:30:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 11:30:46 INFO - Running post-action listener: _resource_record_post_action 11:30:46 INFO - [mozharness: 2017-03-21 18:30:46.912866Z] Finished read-buildbot-config step (success) 11:30:46 INFO - [mozharness: 2017-03-21 18:30:46.912991Z] Running download-and-extract step. 11:30:46 INFO - Running pre-action listener: _resource_record_pre_action 11:30:46 INFO - Running main action method: download_and_extract 11:30:46 INFO - mkdir: /builds/slave/test/build/tests 11:30:46 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')]} 11:30:46 INFO - trying https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 11:30:46 INFO - Downloading https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 11:30:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 11:30:47 INFO - Downloaded 1165 bytes. 11:30:47 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 11:30:47 INFO - Using the following test package requirements: 11:30:47 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 11:30:47 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 11:30:47 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 11:30:47 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 11:30:47 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 11:30:47 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:30:47 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 11:30:47 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 11:30:47 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 11:30:47 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 11:30:47 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 11:30:47 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 11:30:47 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 11:30:47 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 11:30:47 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 11:30:47 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 11:30:47 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 11:30:47 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 11:30:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 11:30:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 11:30:47 INFO - Fetch https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 11:30:48 INFO - Expected file size: 16108176 11:30:48 INFO - Obtained file size: 16108176 11:30:55 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 11:30:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 11:30:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 11:30:55 INFO - Fetch https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 11:30:56 INFO - Expected file size: 16108176 11:30:56 INFO - Obtained file size: 16108176 11:31:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 11:31:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 11:31:02 INFO - Fetch https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 11:31:03 INFO - Expected file size: 14403663 11:31:03 INFO - Obtained file size: 14403663 11:31:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:03 INFO - trying https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 11:31:03 INFO - Downloading https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 11:31:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 11:31:05 INFO - Downloaded 58737307 bytes. 11:31:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 11:31:05 INFO - mkdir: /builds/slave/test/properties 11:31:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:31:05 INFO - Writing to file /builds/slave/test/properties/build_url 11:31:05 INFO - Contents: 11:31:05 INFO - build_url:https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 11:31:06 INFO - Running post-action listener: _resource_record_post_action 11:31:06 INFO - Running post-action listener: set_extra_try_arguments 11:31:06 INFO - [mozharness: 2017-03-21 18:31:06.320616Z] Finished download-and-extract step (success) 11:31:06 INFO - [mozharness: 2017-03-21 18:31:06.320743Z] Running populate-webroot step. 11:31:06 INFO - Running pre-action listener: _resource_record_pre_action 11:31:06 INFO - Running main action method: populate_webroot 11:31:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:31:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:31:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:31:06 INFO - '--tppagecycles', 11:31:06 INFO - '7'], 11:31:06 INFO - 'tests': ['tsvgm']}}, 11:31:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:31:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:31:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:31:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:31:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:31:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:31:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:31:06 INFO - 'talos_options': ['--disable-e10s'], 11:31:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:31:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:31:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:31:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:31:06 INFO - 'talos_options': ['--disable-e10s'], 11:31:06 INFO - 'tests': ['damp', 'tps']}, 11:31:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:31:06 INFO - 'tests': ['damp', 'tps']}, 11:31:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:31:06 INFO - 'tests': ['dromaeo_dom']}, 11:31:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:31:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:31:06 INFO - 'tests': ['basic_compositor_video']}, 11:31:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:31:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:31:06 INFO - 'tests': ['a11yr', 11:31:06 INFO - 'ts_paint', 11:31:06 INFO - 'tpaint', 11:31:06 INFO - 'sessionrestore', 11:31:06 INFO - 'sessionrestore_no_auto_restore', 11:31:06 INFO - 'tabpaint']}, 11:31:06 INFO - 'other-e10s': {'tests': ['a11yr', 11:31:06 INFO - 'ts_paint', 11:31:06 INFO - 'tpaint', 11:31:06 INFO - 'sessionrestore', 11:31:06 INFO - 'sessionrestore_no_auto_restore', 11:31:06 INFO - 'tabpaint']}, 11:31:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:31:06 INFO - 'ts_paint', 11:31:06 INFO - 'tpaint', 11:31:06 INFO - 'sessionrestore', 11:31:06 INFO - 'sessionrestore_no_auto_restore', 11:31:06 INFO - 'tabpaint']}, 11:31:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:31:06 INFO - 'ts_paint', 11:31:06 INFO - 'tpaint', 11:31:06 INFO - 'sessionrestore', 11:31:06 INFO - 'sessionrestore_no_auto_restore', 11:31:06 INFO - 'tabpaint']}, 11:31:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:31:06 INFO - 'tests': ['a11yr', 11:31:06 INFO - 'ts_paint', 11:31:06 INFO - 'tpaint', 11:31:06 INFO - 'sessionrestore', 11:31:06 INFO - 'sessionrestore_no_auto_restore', 11:31:06 INFO - 'tabpaint']}, 11:31:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:31:06 INFO - 'tests': ['a11yr', 11:31:06 INFO - 'ts_paint', 11:31:06 INFO - 'tpaint', 11:31:06 INFO - 'sessionrestore', 11:31:06 INFO - 'sessionrestore_no_auto_restore', 11:31:06 INFO - 'tabpaint']}, 11:31:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:31:06 INFO - 'tests': ['tsvgx', 11:31:06 INFO - 'tsvgr_opacity', 11:31:06 INFO - 'tart', 11:31:06 INFO - 'tscrollx', 11:31:06 INFO - 'cart', 11:31:06 INFO - 'tsvg_static']}, 11:31:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:31:06 INFO - 'tsvgr_opacity', 11:31:06 INFO - 'tart', 11:31:06 INFO - 'tscrollx', 11:31:06 INFO - 'cart', 11:31:06 INFO - 'tsvg_static']}, 11:31:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:31:06 INFO - 'talos_options': ['--disable-e10s'], 11:31:06 INFO - 'tests': ['tp5o']}, 11:31:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:31:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:31:06 INFO - 'talos_options': ['--disable-e10s', 11:31:06 INFO - '--xperf_path', 11:31:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:31:06 INFO - 'tests': ['tp5n']}, 11:31:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:31:06 INFO - 'talos_options': ['--xperf_path', 11:31:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:31:06 INFO - 'tests': ['tp5n']}}, 11:31:06 INFO - 'talos.zip': {'path': '', 11:31:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:31:06 INFO - Running post-action listener: _resource_record_post_action 11:31:06 INFO - [mozharness: 2017-03-21 18:31:06.338717Z] Finished populate-webroot step (success) 11:31:06 INFO - [mozharness: 2017-03-21 18:31:06.338820Z] Running create-virtualenv step. 11:31:06 INFO - Running pre-action listener: _resource_record_pre_action 11:31:06 INFO - Running main action method: create_virtualenv 11:31:06 INFO - Creating virtualenv /builds/slave/test/build/venv 11:31:06 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 11:31:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:31:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:31:06 INFO - Using real prefix '/tools/python27' 11:31:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:31:07 INFO - Installing distribute.............................................................................................................................................................................................done. 11:31:10 INFO - Installing pip.................done. 11:31:10 INFO - Return code: 0 11:31:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:31:10 INFO - Reading from file tmpfile_stdout 11:31:10 INFO - Output received: 11:31:10 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) 11:31:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:10 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 0x110129b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8a95aced0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff07d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefcbe8>, '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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 11:31:10 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 11:31:10 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 11:31:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7Vz2fimWe/Render', 11:31:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:10 INFO - 'HOME': '/Users/cltbld', 11:31:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:10 INFO - 'LOGNAME': 'cltbld', 11:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:10 INFO - 'MOZ_NO_REMOTE': '1', 11:31:10 INFO - 'NO_EM_RESTART': '1', 11:31:10 INFO - 'PAGER': '/bin/cat', 11:31:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:10 INFO - 'PWD': '/builds/slave/test', 11:31:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:10 INFO - 'SHELL': '/bin/bash', 11:31:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hWJ2WhTyBw/Listeners', 11:31:10 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 11:31:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:10 INFO - 'USER': 'cltbld', 11:31:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:10 INFO - 'XPC_FLAGS': '0x0', 11:31:10 INFO - 'XPC_SERVICE_NAME': '0', 11:31:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:10 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 11:31:10 INFO - Cleaning up... 11:31:10 INFO - Return code: 0 11:31:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:10 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 0x110129b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8a95aced0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff07d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefcbe8>, '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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 11:31:10 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 11:31:10 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 11:31:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7Vz2fimWe/Render', 11:31:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:10 INFO - 'HOME': '/Users/cltbld', 11:31:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:10 INFO - 'LOGNAME': 'cltbld', 11:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:10 INFO - 'MOZ_NO_REMOTE': '1', 11:31:10 INFO - 'NO_EM_RESTART': '1', 11:31:10 INFO - 'PAGER': '/bin/cat', 11:31:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:10 INFO - 'PWD': '/builds/slave/test', 11:31:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:10 INFO - 'SHELL': '/bin/bash', 11:31:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hWJ2WhTyBw/Listeners', 11:31:10 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 11:31:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:10 INFO - 'USER': 'cltbld', 11:31:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:10 INFO - 'XPC_FLAGS': '0x0', 11:31:10 INFO - 'XPC_SERVICE_NAME': '0', 11:31:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:10 INFO - Downloading/unpacking psutil>=3.1.1 11:31: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 11:31: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 11:31: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 11:31: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 11:31: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 11:31: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 11:31:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:31:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:31:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:31:14 INFO - Installing collected packages: psutil 11:31:14 INFO - Running setup.py install for psutil 11:31:14 INFO - building 'psutil._psutil_osx' extension 11:31:14 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 11:31:14 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 11:31:14 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 11:31:15 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 11:31:15 INFO - building 'psutil._psutil_posix' extension 11:31:15 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 11:31:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:31:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:31:15 INFO - ^ 11:31:15 INFO - 1 warning generated. 11:31:15 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 11:31:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:31:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:31:15 INFO - Successfully installed psutil 11:31:15 INFO - Cleaning up... 11:31:15 INFO - Return code: 0 11:31:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:31:15 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')]} 11:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:15 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')]} 11:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:15 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 0x110129b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8a95aced0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff07d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefcbe8>, '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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 11:31:15 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 11:31:15 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 11:31:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7Vz2fimWe/Render', 11:31:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:15 INFO - 'HOME': '/Users/cltbld', 11:31:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:15 INFO - 'LOGNAME': 'cltbld', 11:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:15 INFO - 'MOZ_NO_REMOTE': '1', 11:31:15 INFO - 'NO_EM_RESTART': '1', 11:31:15 INFO - 'PAGER': '/bin/cat', 11:31:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:15 INFO - 'PWD': '/builds/slave/test', 11:31:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:15 INFO - 'SHELL': '/bin/bash', 11:31:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hWJ2WhTyBw/Listeners', 11:31:15 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 11:31:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:15 INFO - 'USER': 'cltbld', 11:31:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:15 INFO - 'XPC_FLAGS': '0x0', 11:31:15 INFO - 'XPC_SERVICE_NAME': '0', 11:31:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:31:15 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 11:31:15 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 11:31:15 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 11:31:15 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 11:31:15 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 11:31:15 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 11:31:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:31:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:31:18 INFO - Installing collected packages: mozsystemmonitor 11:31:18 INFO - Running setup.py install for mozsystemmonitor 11:31:18 INFO - Successfully installed mozsystemmonitor 11:31:18 INFO - Cleaning up... 11:31:18 INFO - Return code: 0 11:31:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:31:18 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')]} 11:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:18 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')]} 11:31:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:18 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 0x110129b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8a95aced0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff07d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefcbe8>, '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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 11:31:18 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 11:31:18 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 11:31:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7Vz2fimWe/Render', 11:31:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:18 INFO - 'HOME': '/Users/cltbld', 11:31:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:18 INFO - 'LOGNAME': 'cltbld', 11:31:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:18 INFO - 'MOZ_NO_REMOTE': '1', 11:31:18 INFO - 'NO_EM_RESTART': '1', 11:31:18 INFO - 'PAGER': '/bin/cat', 11:31:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:18 INFO - 'PWD': '/builds/slave/test', 11:31:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:18 INFO - 'SHELL': '/bin/bash', 11:31:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hWJ2WhTyBw/Listeners', 11:31:18 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 11:31:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:18 INFO - 'USER': 'cltbld', 11:31:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:18 INFO - 'XPC_FLAGS': '0x0', 11:31:18 INFO - 'XPC_SERVICE_NAME': '0', 11:31:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:18 INFO - Downloading/unpacking jsonschema==2.5.1 11:31: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 11:31: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 11:31: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 11:31: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 11:31: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 11:31: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 11:31:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:31:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:31:21 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 11:31:21 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 11:31:21 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 11:31:21 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 11:31:21 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 11:31:21 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 11:31:21 INFO - Downloading functools32-3.2.3-2.tar.gz 11:31:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:31:22 INFO - warning: no files found matching '*.txt' 11:31:22 INFO - no previously-included directories found matching 'build' 11:31:22 INFO - no previously-included directories found matching 'dist' 11:31:22 INFO - no previously-included directories found matching '.git*' 11:31:22 INFO - Installing collected packages: jsonschema, functools32 11:31:22 INFO - Running setup.py install for functools32 11:31:22 INFO - warning: no files found matching '*.txt' 11:31:22 INFO - no previously-included directories found matching 'build' 11:31:22 INFO - no previously-included directories found matching 'dist' 11:31:22 INFO - no previously-included directories found matching '.git*' 11:31:22 INFO - Successfully installed jsonschema functools32 11:31:22 INFO - Cleaning up... 11:31:22 INFO - Return code: 0 11:31:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:22 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 0x110129b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8a95aced0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff07d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefcbe8>, '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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 11:31:22 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 11:31:22 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 11:31:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7Vz2fimWe/Render', 11:31:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:22 INFO - 'HOME': '/Users/cltbld', 11:31:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:22 INFO - 'LOGNAME': 'cltbld', 11:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:22 INFO - 'MOZ_NO_REMOTE': '1', 11:31:22 INFO - 'NO_EM_RESTART': '1', 11:31:22 INFO - 'PAGER': '/bin/cat', 11:31:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:22 INFO - 'PWD': '/builds/slave/test', 11:31:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:22 INFO - 'SHELL': '/bin/bash', 11:31:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hWJ2WhTyBw/Listeners', 11:31:22 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 11:31:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:22 INFO - 'USER': 'cltbld', 11:31:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:22 INFO - 'XPC_FLAGS': '0x0', 11:31:22 INFO - 'XPC_SERVICE_NAME': '0', 11:31:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:31:22 INFO - Cleaning up... 11:31:22 INFO - Return code: 0 11:31:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:22 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 0x110129b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8a95aced0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff07d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefcbe8>, '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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 11:31:22 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 11:31:22 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 11:31:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7Vz2fimWe/Render', 11:31:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:22 INFO - 'HOME': '/Users/cltbld', 11:31:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:22 INFO - 'LOGNAME': 'cltbld', 11:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:22 INFO - 'MOZ_NO_REMOTE': '1', 11:31:22 INFO - 'NO_EM_RESTART': '1', 11:31:22 INFO - 'PAGER': '/bin/cat', 11:31:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:22 INFO - 'PWD': '/builds/slave/test', 11:31:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:22 INFO - 'SHELL': '/bin/bash', 11:31:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hWJ2WhTyBw/Listeners', 11:31:22 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 11:31:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:22 INFO - 'USER': 'cltbld', 11:31:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:22 INFO - 'XPC_FLAGS': '0x0', 11:31:22 INFO - 'XPC_SERVICE_NAME': '0', 11:31:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:22 INFO - Downloading/unpacking blobuploader==1.2.4 11:31:22 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 11:31:22 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 11:31:22 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 11:31:22 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 11:31:22 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 11:31:22 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 11:31:25 INFO - Downloading blobuploader-1.2.4.tar.gz 11:31:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:31:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:31:25 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 11:31:25 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 11:31:25 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 11:31:25 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 11:31:25 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 11:31:25 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 11:31:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:31:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:31:26 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 11:31:26 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 11:31:26 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 11:31:26 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 11:31:26 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 11:31:26 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 11:31:26 INFO - Downloading docopt-0.6.1.tar.gz 11:31:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:31:26 INFO - Installing collected packages: blobuploader, requests, docopt 11:31:26 INFO - Running setup.py install for blobuploader 11:31:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:31:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:31:26 INFO - Running setup.py install for requests 11:31:27 INFO - Running setup.py install for docopt 11:31:27 INFO - Successfully installed blobuploader requests docopt 11:31:27 INFO - Cleaning up... 11:31:27 INFO - Return code: 0 11:31:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:27 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 0x110129b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8a95aced0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff07d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefcbe8>, '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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 11:31:27 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 11:31:27 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 11:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7Vz2fimWe/Render', 11:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:27 INFO - 'HOME': '/Users/cltbld', 11:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:27 INFO - 'LOGNAME': 'cltbld', 11:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:27 INFO - 'MOZ_NO_REMOTE': '1', 11:31:27 INFO - 'NO_EM_RESTART': '1', 11:31:27 INFO - 'PAGER': '/bin/cat', 11:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:27 INFO - 'PWD': '/builds/slave/test', 11:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:27 INFO - 'SHELL': '/bin/bash', 11:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hWJ2WhTyBw/Listeners', 11:31:27 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 11:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:27 INFO - 'USER': 'cltbld', 11:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:27 INFO - 'XPC_FLAGS': '0x0', 11:31:27 INFO - 'XPC_SERVICE_NAME': '0', 11:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:31:27 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-hbpuF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:31:27 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-uq3XJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:31:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-GV1ajl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:31:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-gAM_fk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:31:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-6ganVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:31:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-V8ucuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:31:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-trv_c6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:31:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-9YkQjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:31:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-DsrNsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:31:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-T31EmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:31:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-1Px0gM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:31:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-gm9DqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:31:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-GW79xo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:31:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-qBaIss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-MI3Snj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:31:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-9syVr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:31:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-IhZxTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:31:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:31:29 INFO - Running setup.py install for manifestparser 11:31:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:31:29 INFO - Running setup.py install for mozcrash 11:31:29 INFO - Running setup.py install for mozdebug 11:31:30 INFO - Running setup.py install for mozdevice 11:31:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:31:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:31:30 INFO - Running setup.py install for mozfile 11:31:30 INFO - Running setup.py install for mozhttpd 11:31:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:31:30 INFO - Running setup.py install for mozinfo 11:31:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:31:30 INFO - Running setup.py install for mozInstall 11:31:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:31:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:31:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:31:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:31:30 INFO - Running setup.py install for mozleak 11:31:31 INFO - Running setup.py install for mozlog 11:31:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:31:31 INFO - Running setup.py install for moznetwork 11:31:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:31:31 INFO - Running setup.py install for mozprocess 11:31:31 INFO - Running setup.py install for mozprofile 11:31:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:31:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:31:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:31:31 INFO - Running setup.py install for mozrunner 11:31:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:31:31 INFO - Running setup.py install for mozscreenshot 11:31:32 INFO - Running setup.py install for moztest 11:31:32 INFO - Running setup.py install for mozversion 11:31:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:31:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:31:32 INFO - Cleaning up... 11:31:32 INFO - Return code: 0 11:31:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:32 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 0x110129b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8a95aced0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff07d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefcbe8>, '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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 11:31:32 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 11:31:32 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 11:31:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7Vz2fimWe/Render', 11:31:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:32 INFO - 'HOME': '/Users/cltbld', 11:31:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:32 INFO - 'LOGNAME': 'cltbld', 11:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:32 INFO - 'MOZ_NO_REMOTE': '1', 11:31:32 INFO - 'NO_EM_RESTART': '1', 11:31:32 INFO - 'PAGER': '/bin/cat', 11:31:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:32 INFO - 'PWD': '/builds/slave/test', 11:31:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:32 INFO - 'SHELL': '/bin/bash', 11:31:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hWJ2WhTyBw/Listeners', 11:31:32 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 11:31:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:32 INFO - 'USER': 'cltbld', 11:31:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:32 INFO - 'XPC_FLAGS': '0x0', 11:31:32 INFO - 'XPC_SERVICE_NAME': '0', 11:31:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:31:32 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-gsh7Mv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:31:32 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)) 11:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:31:32 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-M3LzF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:31:32 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)) 11:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:31:32 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-tG5kDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:31:32 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)) 11:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:31:32 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-ytCyk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:31:33 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)) 11:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:31:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-w_Luhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:31:33 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)) 11:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:31:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-SLmYtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:31:33 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)) 11:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:31:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-g4pU4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:31:33 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)) 11:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:31:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-_vp9rP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:31:33 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)) 11:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:31:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-_WQ_V5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:31:33 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)) 11:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:31:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-P0_jBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:31:33 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)) 11:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:31:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-bdodVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:31:33 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)) 11:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:31:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-LwJYAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:31:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:31:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-l_lmmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:31:34 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)) 11:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:31:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-kaNmWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:31:34 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)) 11:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-mLOLY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:34 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)) 11:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:31:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-qakKSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:31:34 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)) 11:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:31:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-9YMC9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:31:34 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)) 11:31:34 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)) 11:31:34 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)) 11:31:34 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)) 11:31:34 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)) 11:31:34 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)) 11:31:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:31:34 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 11:31:34 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 11:31:34 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 11:31:34 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 11:31:34 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 11:31:34 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 11:31:37 INFO - Downloading blessings-1.6.tar.gz 11:31:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:31:37 INFO - Installing collected packages: blessings 11:31:37 INFO - Running setup.py install for blessings 11:31:37 INFO - Successfully installed blessings 11:31:37 INFO - Cleaning up... 11:31:37 INFO - Return code: 0 11:31:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:31:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:37 INFO - Reading from file tmpfile_stdout 11:31:37 INFO - Current package versions: 11:31:37 INFO - blessings == 1.6 11:31:37 INFO - blobuploader == 1.2.4 11:31:37 INFO - docopt == 0.6.1 11:31:37 INFO - functools32 == 3.2.3-2 11:31:37 INFO - jsonschema == 2.5.1 11:31:37 INFO - manifestparser == 1.1 11:31:37 INFO - mozInstall == 1.12 11:31:37 INFO - mozcrash == 1.0 11:31:37 INFO - mozdebug == 0.1 11:31:37 INFO - mozdevice == 0.49 11:31:37 INFO - mozfile == 1.2 11:31:37 INFO - mozhttpd == 0.7 11:31:37 INFO - mozinfo == 0.9 11:31:37 INFO - mozleak == 0.1 11:31:37 INFO - mozlog == 3.4 11:31:37 INFO - moznetwork == 0.27 11:31:37 INFO - mozprocess == 0.24 11:31:37 INFO - mozprofile == 0.28 11:31:37 INFO - mozrunner == 6.13 11:31:37 INFO - mozscreenshot == 0.1 11:31:37 INFO - mozsystemmonitor == 0.3 11:31:37 INFO - moztest == 0.8 11:31:37 INFO - mozversion == 1.4 11:31:37 INFO - psutil == 3.1.1 11:31:37 INFO - requests == 1.2.3 11:31:37 INFO - wsgiref == 0.1.2 11:31:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31: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')]} 11:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31: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')]} 11:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:37 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 0x110129b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8a95aced0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff07d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefcbe8>, '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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 11:31:37 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 11:31:37 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 11:31:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7Vz2fimWe/Render', 11:31:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:37 INFO - 'HOME': '/Users/cltbld', 11:31:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:37 INFO - 'LOGNAME': 'cltbld', 11:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:37 INFO - 'MOZ_NO_REMOTE': '1', 11:31:37 INFO - 'NO_EM_RESTART': '1', 11:31:37 INFO - 'PAGER': '/bin/cat', 11:31:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:37 INFO - 'PWD': '/builds/slave/test', 11:31:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:37 INFO - 'SHELL': '/bin/bash', 11:31:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hWJ2WhTyBw/Listeners', 11:31:37 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 11:31:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:37 INFO - 'USER': 'cltbld', 11:31:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:37 INFO - 'XPC_FLAGS': '0x0', 11:31:37 INFO - 'XPC_SERVICE_NAME': '0', 11:31:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:38 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)) 11:31:38 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)) 11:31:38 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)) 11:31:38 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)) 11:31:38 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)) 11:31:38 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)) 11:31:38 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)) 11:31:38 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)) 11:31:38 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)) 11:31:38 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)) 11:31:38 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)) 11:31:38 INFO - Cleaning up... 11:31:38 INFO - Return code: 0 11:31:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110129b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8a95aced0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff07d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefcbe8>, '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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 11:31:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:31:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7Vz2fimWe/Render', 11:31:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:38 INFO - 'HOME': '/Users/cltbld', 11:31:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:38 INFO - 'LOGNAME': 'cltbld', 11:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:38 INFO - 'MOZ_NO_REMOTE': '1', 11:31:38 INFO - 'NO_EM_RESTART': '1', 11:31:38 INFO - 'PAGER': '/bin/cat', 11:31:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:38 INFO - 'PWD': '/builds/slave/test', 11:31:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:38 INFO - 'SHELL': '/bin/bash', 11:31:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hWJ2WhTyBw/Listeners', 11:31:38 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 11:31:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:38 INFO - 'USER': 'cltbld', 11:31:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:38 INFO - 'XPC_FLAGS': '0x0', 11:31:38 INFO - 'XPC_SERVICE_NAME': '0', 11:31:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:31:38 INFO - Cleaning up... 11:31:38 INFO - Return code: 0 11:31:38 INFO - Running post-action listener: _resource_record_post_action 11:31:38 INFO - Running post-action listener: _start_resource_monitoring 11:31:38 INFO - Starting resource monitoring. 11:31:38 INFO - [mozharness: 2017-03-21 18:31:38.326241Z] Finished create-virtualenv step (success) 11:31:38 INFO - [mozharness: 2017-03-21 18:31:38.327205Z] Running install step. 11:31:38 INFO - Running pre-action listener: _resource_record_pre_action 11:31:38 INFO - Running main action method: install 11:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:38 INFO - Reading from file tmpfile_stdout 11:31:38 INFO - Detecting whether we're running mozinstall >=1.0... 11:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:31:38 INFO - Reading from file tmpfile_stdout 11:31:38 INFO - Output received: 11:31:38 INFO - Usage: mozinstall [options] installer 11:31:38 INFO - Options: 11:31:38 INFO - -h, --help show this help message and exit 11:31:38 INFO - -d DEST, --destination=DEST 11:31:38 INFO - Directory to install application into. [default: 11:31:38 INFO - "/builds/slave/test"] 11:31:38 INFO - --app=APP Application being installed. [default: firefox] 11:31:38 INFO - mkdir: /builds/slave/test/build/application 11:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 11:31:52 INFO - Reading from file tmpfile_stdout 11:31:52 INFO - Output received: 11:31:52 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 11:31:52 INFO - Running post-action listener: _resource_record_post_action 11:31:52 INFO - [mozharness: 2017-03-21 18:31:52.967854Z] Finished install step (success) 11:31:52 INFO - [mozharness: 2017-03-21 18:31:52.967994Z] Running run-tests step. 11:31:52 INFO - Running pre-action listener: _resource_record_pre_action 11:31:52 INFO - Running main action method: run_tests 11:31:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:31:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:31:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:31:52 INFO - 2017-03-21 11:31:52.983 screenresolution[1883:8700] starting screenresolution argv=screenresolution get 11:31:52 INFO - 2017-03-21 11:31:52.985 screenresolution[1883:8700] Display 0: 1600x1200x32@60 11:31:52 INFO - 2017-03-21 11:31:52.991 screenresolution[1884:8702] starting screenresolution argv=screenresolution list 11:31:52 INFO - Available Modes on Display 0 11:31:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:31:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:31:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:31:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:31:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:31:53 INFO - Intel Iris: 11:31:53 INFO - Chipset Model: Intel Iris 11:31:53 INFO - Type: GPU 11:31:53 INFO - Bus: Built-In 11:31:53 INFO - VRAM (Dynamic, Max): 1536 MB 11:31:53 INFO - Vendor: Intel (0x8086) 11:31:53 INFO - Device ID: 0x0a2e 11:31:53 INFO - Revision ID: 0x0009 11:31:53 INFO - Displays: 11:31:53 INFO - Display: 11:31:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:31:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:31:53 INFO - Display Serial Number: 1600x1200 60 11:31:53 INFO - Main Display: Yes 11:31:53 INFO - Mirror: Off 11:31:53 INFO - Online: Yes 11:31:53 INFO - Rotation: Supported 11:31:53 INFO - Return code: 0 11:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:31:53 INFO - Python 2.7.3 11:31:53 INFO - Return code: 0 11:31:53 INFO - grabbing minidump binary from tooltool 11:31: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')]} 11:31:53 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 0x10ff07d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fefcbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:31:53 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 11:31:53 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 11:31:53 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 11:31:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:31:53 INFO - Return code: 0 11:31:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:31:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:31:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:31:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:31:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:31:53 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/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 11:31:53 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/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0030 --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 11:31:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b7Vz2fimWe/Render', 11:31:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:53 INFO - 'HOME': '/Users/cltbld', 11:31:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:53 INFO - 'LOGNAME': 'cltbld', 11:31:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:53 INFO - 'MOZ_NO_REMOTE': '1', 11:31:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:53 INFO - 'NO_EM_RESTART': '1', 11:31:53 INFO - 'PAGER': '/bin/cat', 11:31:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:53 INFO - 'PWD': '/builds/slave/test', 11:31:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:53 INFO - 'SHELL': '/bin/bash', 11:31:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hWJ2WhTyBw/Listeners', 11:31:53 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 11:31:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:53 INFO - 'USER': 'cltbld', 11:31:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:53 INFO - 'XPC_FLAGS': '0x0', 11:31:53 INFO - 'XPC_SERVICE_NAME': '0', 11:31:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:53 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/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 11:31:53 INFO - mozversion application_buildid: 20170321103907 11:31:53 INFO - mozversion application_changeset: fbfd5cdc74f6c1075cf9e72065cdc203480a6a92 11:31:53 INFO - mozversion application_display_name: Firefox 11:31:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:31:53 INFO - mozversion application_name: Firefox 11:31:53 INFO - mozversion application_remotingname: firefox 11:31:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:31:53 INFO - mozversion application_vendor: Mozilla 11:31:53 INFO - mozversion application_version: 53.0 11:31:53 INFO - mozversion platform_buildid: 20170321103907 11:31:53 INFO - mozversion platform_changeset: fbfd5cdc74f6c1075cf9e72065cdc203480a6a92 11:31:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:31:53 INFO - mozversion platform_version: 53.0 11:31:53 INFO - using testdate: 1490121113 11:31:53 INFO - actual date: 1490121113 11:31:53 INFO - starting webserver on 'localhost:49409' 11:31:53 INFO - SUITE-START | Running 2 tests 11:31:53 INFO - TEST-START | dromaeo_css 11:31:53 INFO - operating with platform_type : mac_ 11:31:53 INFO - Initialising browser for dromaeo_css test... 11:31:53 INFO - TEST-INFO | started process 1894 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpkqhoXH/profile http://localhost:49409/getInfo.html) 11:31:56 INFO - PROCESS | 1894 | 2017-03-21 11:31:56.176 plugin-container[1897:8859] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9347, name = 'com.apple.tsm.portname' 11:31:56 INFO - PROCESS | 1894 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:31:56 INFO - PROCESS | 1894 | 2017-03-21 11:31:56.177 plugin-container[1897:8859] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a1b, name = 'com.apple.CFPasteboardClient' 11:31:56 INFO - PROCESS | 1894 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:31:56 INFO - PROCESS | 1894 | 2017-03-21 11:31:56.177 plugin-container[1897:8859] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:31:56 INFO - PROCESS | 1894 | __metrics Screen width/height:1600/1200 11:31:56 INFO - PROCESS | 1894 | colorDepth:24 11:31:56 INFO - PROCESS | 1894 | Browser inner width/height: 1024/689 11:31:56 INFO - PROCESS | 1894 | __metrics 11:31:56 ERROR - PROCESS | 1894 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 11:31:56 INFO - TEST-INFO | 1894: exit 0 11:31:56 INFO - Browser initialized. 11:31:56 INFO - Running cycle 1/1 for dromaeo_css test... 11:31:56 INFO - TEST-INFO | started process 1898 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpkqhoXH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:31:59 INFO - PROCESS | 1898 | 2017-03-21 11:31:59.299 plugin-container[1899:8987] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854f, name = 'com.apple.tsm.portname' 11:31:59 INFO - PROCESS | 1898 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:31:59 INFO - PROCESS | 1898 | 2017-03-21 11:31:59.300 plugin-container[1899:8987] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b13, name = 'com.apple.CFPasteboardClient' 11:31:59 INFO - PROCESS | 1898 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:31:59 INFO - PROCESS | 1898 | 2017-03-21 11:31:59.300 plugin-container[1899:8987] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:32:08 INFO - PROCESS | 1898 | RSS: Main: 172875776 11:32:08 INFO - PROCESS | 1898 | 11:35:10 INFO - PROCESS | 1898 | Cycle 1(1): loaded http://localhost:49409/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49409/tests/dromaeo/cssquery-ext.html) 11:35:12 INFO - PROCESS | 1898 | RSS: Main: 188682240 11:35:12 INFO - PROCESS | 1898 | 11:38:14 INFO - PROCESS | 1898 | Cycle 1(1): loaded http://localhost:49409/tests/dromaeo/cssquery-ext.html (next: http://localhost:49409/tests/dromaeo/cssquery-jquery.html) 11:38:15 INFO - PROCESS | 1898 | RSS: Main: 201539584 11:38:15 INFO - PROCESS | 1898 | 11:41:16 INFO - PROCESS | 1898 | Cycle 1(1): loaded http://localhost:49409/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49409/tests/dromaeo/cssquery-mootools.html) 11:41:17 INFO - PROCESS | 1898 | RSS: Main: 202940416 11:41:17 INFO - PROCESS | 1898 | 11:44:19 INFO - PROCESS | 1898 | Cycle 1(1): loaded http://localhost:49409/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49409/tests/dromaeo/cssquery-prototype.html) 11:44:20 INFO - PROCESS | 1898 | RSS: Main: 207032320 11:44:20 INFO - PROCESS | 1898 | 11:47:22 INFO - PROCESS | 1898 | Cycle 1(1): loaded http://localhost:49409/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49409/tests/dromaeo/cssquery-yui.html) 11:47:23 INFO - PROCESS | 1898 | RSS: Main: 208601088 11:47:23 INFO - PROCESS | 1898 | 11:50:26 INFO - PROCESS | 1898 | Cycle 1(1): loaded http://localhost:49409/tests/dromaeo/cssquery-yui.html (next: http://localhost:49409/tests/dromaeo/cssquery-dojo.html) 11:50:26 INFO - PROCESS | 1898 | RSS: Main: 208691200 11:50:26 INFO - PROCESS | 1898 | 11:50:26 INFO - PROCESS | 1898 | __start_tp_report 11:50:26 INFO - PROCESS | 1898 | _x_x_mozilla_page_load 11:50:26 INFO - PROCESS | 1898 | _x_x_mozilla_page_load_details 11:50:26 INFO - PROCESS | 1898 | |i|pagename|runs| 11:50:26 INFO - PROCESS | 1898 | |0;dojo.html;2757.4760795448865;2955.172551685528;3520.1551627609383;3537.239493508897;3553.5558055243096;5903.203067585875;5906.4388883056145;5927.962606543853;5996.130561068642;6033.366496517869;5947.851303717409;6014.428629280223;6039.214902062726;6056.212692349988;6059.848503787408;4782.689125206851;5289.365276166855;5350.5184533391985;5362.2224777407255;5413.512783849453;5427.348718153827;5450.700211488366;5454.427285135063;5462.863428414282;5466.289382380285;5466.644668096561;5467.289252397202;5470.316210473691;5471.972640136813;5484.478974497416;5261.86845328866;5273.578113750891;5392.622516423852;5420.24116623671;5445.918311225335;5035.8489245322335;5330.360356757175;5390.460953904618;5401.810936617199;5438.945610543923;6006.309274433409;6070.301915279755;6100.786472473486;6164.198654174974;6165.10605962133;6253.843653908643;6263.561550691454;6291.716872740738;6296.590721603081;6308.085327627514;6210.254769427653;6234.906476402857;6267.185265915402;6358.523110766711;6368.363163683641;6161.014237721943;6170.074488826667;6181.196444462236;6183.227096612924;6201.472874805602;6167.444929956236;6472;6503.414692677682;6506.3493650634555;6513.348665133449;6211.751529938751;6229.626222426578;6234.251889773256;6245.687715614201;6306;6353.332900045429;6366.204224471941;6367.968160159169;6370.108184854124;6382.234131904108;6236.501079913596;6262.405071518198;6289.842753931189;6291.811245662638;6302.275238347557;6113.081058500009;6197.101420294122;6212.47193988507;6252.937470625327;6261.154744109487;4163.708540402143;4666.673332866734;4954.232094025359;5153.742312884341;5164.793408263702;3882.9321936467695;4612.76936153191;4614.423197100363;4622.075584883037;4671.11248862715;5970.880582388328;6044.425779550936;6052.455279024909;6086.391360863879;6210.782622608187;6365.554411191172;6367.299597044322;6369;6374.075759014917;6404.455621272151;6018.548608854353;6110.633361998294;6135.693215339215;6147.692615369214;6171.907421388593;5592.245046918614;5617.887642247214;5630.97184514083;5675.3757086720425;5734.885302293931;6510.056041873901;6534.673266336569;6543.476521878239;6553.934460655523;6656.434203092791;6369.90445143314;6370;6371.171747672773;6372.776952806629;6383.872322553616;6241.875162496815;6315.179026726496;6383.712732926937;6410.102585637921;6437.871242575216;6282.654454004891;6296.275928268308;6305.337939516285;6309.211348581427;6327.082573026977;6322.683865806599;6351.079093531504;6367.713452894724;6369.777057802955;6450.258220304538;6170.629762214281;6174.969125154345;6215.9378406215355;6239.563230573816;6270.933941230093;6239.875202496016;6259.624422534663;6269.153664255267;6271.090691849748;6277.372262773686;5958.672273024852;6206.72069756853;6220.657863817352;6242.500599952084;6243.719032643451;6175.96912015437;6187.288461826947;6217.875642487034;6230.190075290183;6287.842803929939;6188.690565471618;6233.501319894307;6251.71867265965;6260.718267678057;6271.216097987752 11:50:26 INFO - PROCESS | 1898 | |1;ext.html;469.1719115760681;522.7438555107996;529.8489930369846;535.9973200134;594.3165359836186;28419;43531.12937741247;43720.12559748803;43824.9043773905;43931;46580.767096164556;46972.765136174356;47082.529174708245;47162;47171.76414117924;9126.954365228165;9628.903710962872;9833.31166818323;9835.262355323344;9862.950685246564;9180.954095229514;11841;12093.153479256456;12155.149139560235;12353;12100;12155.96674282687;12169.269843809356;12192.207506512048;12194.756104877897;11895.583654572136;11901;12023.218490798114;12134.696632584168;12159.51361945521;11655.708607284801;11694.649160525198;11825.822612660817;11874.109441791814;11922.046236301076;41474;43405.13189736219;43534.78232608816;43777.905552361124;43970.46103386366;4116.5060192777055;4184.079502509443;4216.55726148753;4301.956980430187;4434.024514606781;31298.061058168292;31509.054728358187;32272.354552909044;32397.51403728946;32677.50983735246;3411.232472693649;3412.607550131742;3428.9142771430916;3452.2750222453315;3518.700910422617;504038;509626;512027.8797212017;512460;515614;62322;62645;63040.684796575726;63108.68445657834;63176.36823631705;71579.64210178916;72079.91880121802;72755.63622181855;72870.54258914899;72963.2703672967;17733;18326.908365458356;18382.540436489195;18405.26378944854;18428.539286517946;12717.173383730027;13041.282729449971;13822.447102116004;14014.369353379123;14071.366788494543;18019;18295.359662411753;18560.535986600444;18592.907035465007;18643.533911652317;7732.806679833049;7839.05931288249;7870.81232101;7879.85742067397;7899.605019749105;909.5588639509832;910.854263317866;913.2100732866023;940.3276657190141;943.9433633981984;26527.734722652913;31444.52833207504;32337.838310808766;32430.675693242767;32551.674483254865;915.5513798238815;1089.2484185911696;1117.3742704085575;1138.419406102877;1252.0109113799997;15728;16609.83390166083;16853.325866965428;16906.239219235165;16936.745948810778;604252;613651;621869;625251;628134;66400.33599664038;66561.33438665551;66623;66650.33349666538;66894.33105668979;1309.8690130987015;1349.8920086393066;1477.0694462488564;1514.9697006059816;1517.9392824286904;31258.68741312558;31712;31930.042098737074;32069.518957215194;32547.511787322735;56331;56528.434715652314;56647.58381040507;56659.716701416226;56699.433005669416;554525;556082;557465.4253457413;557910;558863;37042.8147859259;39075.80462097671;39309.41035884521;44080.67757967266;44391.77804110959;41390.793046034574;49073.26390104223;49305.767355816395;50139.74930125326;50439;38302.85091447261;38303;39509.80245098756;40503;40780.38829417502;31425;39270.41094383644;39470.60529394669;40915.18169636531;40921.38617920793;38849.8345049649;38893.80553097217;39346.426142955104;39448.802755986035;39548.604513954495;32342;34549.37231590647;35255;35495.46756798599;35496;25675.486490269715;25833;26027.47945041051;26173.214803555922;26263.606045908946 11:50:26 INFO - PROCESS | 1898 | |2;jquery.html;396.83134667766194;401.1595706993847;405.1067396390958;406.59949949299937;427.7027465911193;21747.130114795407;21934.89032554839;22656.207032753857;22922.083116675356;23161.18935837246;18770.90614546929;21334.46663833405;23371.532569348645;23404.5319093618;23656.053757849666;6100.206973093492;6328.715207815636;6334.588251763644;6341.8097457076365;6369.426751592356;5878.588498805086;6175.907361389583;6232.345603711613;6235.251769787632;6248.6875656217235;5925.229720136398;6053.878922421549;6131.693415329216;6168.167297414859;6171.660558669271;6194.907076393858;6295.779647712308;6310.116583678288;6341.270753863296;6342.746290148389;6028;6207.131001659771;6211.596246243979;6239.2824825145235;6256.186695729526;14113.153210807282;14196.858031419657;14418.855811441961;26233.868830655916;27467.86266068677;2713.511567917774;2775.597538356927;2787.010611233003;2793.9022134225406;3025.031989763255;10433.217508686803;10477.947610261976;10481.79036419275;10896.40069796161;10948.781024379468;1841.4291569613463;1883.161992913154;1886.2455017992775;1886.7924528301876;1893.479293194369;137600.31199844036;140955;145964.27017864844;146032;146322;10094.981003799123;10665.573377064986;12216;12283.754324913452;12503.562090359725;16132.838671613368;16143.434979775651;16155.67688646244;16168.029918204946;16168.514944551682;9170.81658366829;9354.625814967325;9389.483578403253;9398.67104651334;9477.4787386693;9114.635414583476;9123.543822808833;9168.26653867689;9328.34701570897;9354.906450935403;4180.623743863067;4595.172868883633;4871.34236878017;4887.95112048882;5038.395392552844;1558.7428074367601;1818.7544681468098;1858.3774435564237;1860.990695046516;1864.5338665333666;901.7024381953939;974.7611835100446;1017.2624846985876;1038.4651904269308;1062.1768129699544;17375.73936390955;19394.612107757766;21896.138828199462;22819.85900704958;23252.186173483846;1052.5000624703357;1062.9362238265574;1090.7545802194443;1097.6377795327523;1097.7145942055124;11822.34977076252;11882.524699011943;11935.164538482222;11965.98289145415;12100;806271;807112;808810;810907;831777;111662.20844478953;135668;137533.93699094522;155084.2245788764;159328;998.1665309466531;1001.5042553935848;1026.3585259212991;1034.3225187502198;1036.8703912011;19045.428637140907;20121.899390502953;20125.698114528;20183.192672292942;20234.49413764609;45091.64725058254;58775.70612146912;66552.6672366635;66997;68281;86910;87039.56480217558;88782.55608721916;89964;90082.54958725165;8289.83420331602;8380.413371063967;8468.195521425454;8673.522956237468;8722.302215822592;12036.518539258683;12975.351232438528;13139.54011609589;13307.534236301683;13929;11495.310281383145;11799.882001179878;11897.643070707893;11954.163208575315;11968.46141923598;10148;11528.308301501937;11586.420678966186;12012;12066.39668016578;9152.725418237464;11981.76036479283;12089.39553022363;12133.575324863587;12173;7505.437092217996;7728.033995750531;8386.748397548083;8568.742937711879;8690.78273043179;5754.6547207167705;7906.920930790849;8188.2630563249595;11442.542298307975;11700.835345461117 11:50:26 INFO - PROCESS | 1898 | |3;mootools.html;1363.6113707593336;1431.6449440670106;1475.4835807467382;1482.747932851415;1486.8661820436166;7308.707651693926;7371.483996120261;8241.299489543388;8650.480971141727;8688.218060374566;8112.797180070501;8418.32653387729;8431.241188293052;8525.232729054384;8528.829423411527;2365.775251351119;2400.6279026750794;2401.891914863835;2403.3150552092657;2420.66110744495;2391.8445301055463;2420.4674971506242;2431.756824317563;2437.5124975004983;2444.7433019532873;2022.8583999120067;2387.462820865309;2389.2115601851347;2421.903123875043;2422.9515409691885;2020.8989550522488;2175.71715676961;2203.8567493112896;2333.603287441139;2344.9765502345012;2072.575122099966;2369.5142495788323;2412.44513761834;2412.9396765080837;2413.987930060356;7216.963915180443;8557.058723540405;8572.871406928902;8579.914200858037;8592.484450932901;5116.641835071547;5164.328637277167;5219.634625576211;5319.893602127974;5321.281626980385;3478.4260597001717;3631.8184090795353;3718;3721.925561488782;3726.664600185969;1229.2317301686446;1251.0546100813233;1312.6980794417339;1318.406716977364;1324.4966912573257;10931.781364372668;10966.725831854268;10979.1765617579;11002.064824489928;11017.228793984425;4238.3430568261965;4451.310046942729;4696.60078893291;4775.188218002948;4836.081144582518;9653.46905920181;9671.854922176173;9683.322167448352;9684.322097453252;9686;5369.140937449989;5399.244105825188;5408.945910540923;5410.188471729272;5419.3225846769155;3854.5181852268465;4292.227399068198;4833.516648335209;4866.7079975201605;4943.8022479101155;3290.4899740540254;3957.465742124834;3997.340438827619;4033.878983630496;4051.169510250392;1291.1349395904767;1294.9093563450656;1303.791393377055;1304.1588175626857;1306.3402981494476;933.8505839065853;940.6989763275823;949.6676163342775;952.0907533305676;970.3110791338088;5630.211770352072;5703.287089113861;5932.881342373128;6049.879002419927;6107.267127944586;579.6145563200424;580.6632153351046;591.7278052096084;600.0549135112216;609.3479976425272;3876.534815822063;3894.1432884765304;3894.6884249259992;3932.390479475686;3978.8607398740905;9752.21982241419;10078.445685487368;10079.34484258521;10085.89055203927;10184.745381365377;9998;10010.14913732341;10033.899661003297;10038.19694424459;10141.2394070445;7516.285952834472;7915.366770658334;7918.802029949297;7959.164287749819;8051.758447246592;5858.765649374148;5920.111983202381;5939.792107276225;5968.134620480093;6036.909446358396;7736.883946740691;8967.865482017645;9288.442693438416;9292;9309.767255818388;9065.546722663708;9319.720408387759;9331.393459425115;9337.813243735223;9444.433333999981;6706.631135287606;7091.290870912868;7224.096987876515;7235.602041887747;7383.372413344711;8001.559914204775;8076;8082.7171049013;8082.959585202037;8102.83794324122;7104.147502299757;7213.242609525924;7254.16577093641;7320.743773967885;7370;7031.40233080194;7100.502964792415;7121;7198.71205151788;7259.9274007259255;6476.838079048061;6487.935120648922;6567;6581.769638062645;6622.304658010839;5040.949590504195;5064.088464076502;5100;5170.276161337491;5196.922046169386;5066.923996139987;5080.974595127149;5144.614153938394;5176.275321455075;5256.500632439912 11:50:26 INFO - PROCESS | 1898 | |4;prototype.html;372.01787281576645;373.5330836454432;378.9374753165727;383.65279422122956;388.045408295593;4003.1793482336125;4722.008378240574;4809.807607695692;5313.9637770634745;5522.530584900283;4798;4976.278439626252;5204.895902081956;5205.895882082356;5481.287432633752;3405.7786243894066;3906.746061506007;4180.331147016478;4180.999999999993;4323.956760432387;3430.279641275335;3777.7166712496537;3929.351656976595;4202.949262684329;4316;4217.36739489076;4260.233158031553;4264.658827293824;4266.679999000072;4268.359746038088;3849.30712471755;3851.7111216658864;4087.2438598859158;4134.607212314855;4229.746215227096;3760.548734151892;3766.5856755756845;3775.244951009809;3800.0879788850752;3807.7524960877454;5113.821016264413;5245.8426247212265;5304.63155896005;5352.785888564492;5372.758225879845;4145.54399016108;4165.375193720935;4950.950490495121;4990.051890140898;4995.8501244962345;5003.849114703602;5147.536721695028;5810.941890581083;5855.916655418719;5935.584509084366;3700.3154416433044;3955.4462375267485;3987.8404863805413;3997.620226078518;4167.895802604929;6033.3664965178905;6062.242219722535;6100.847478813021;6119.296280927706;6135.59474491657;3433.639467855839;3621.456781482746;4005.559388467266;4031.4355990161403;4038.1519880824767;3349;3642.2699217871827;4071.1654110907193;4079.9592004080173;4102.2000709861295;4786.593139583175;4795.160846851787;4808.519148085164;4811.109944660249;4833.800620717565;5648.8870222595315;5895.941040589625;5924.792632257937;5949.464548190684;5964.970175149097;1180.5100883133575;1204.8734433304887;1269.911106222574;1303.81094741262;1320.7688654485426;775.313847245192;848.9490630562186;882.7042940614966;883.7494944653372;895.6014573514854;4718.245080787057;4950.702957822542;5234.293370395024;5282.181261904411;5294.41761406245;4827.831025914146;4897.751073476241;5259.789608415697;5368.731563421813;5651.689157096399;4739.881502962385;5049.82325618609;5063.797448102108;5274.202574146609;5298.708046482362;2974.8958786442804;3014.7437467815043;3046.390721855638;3052.4810782167083;3075.815451072943;5634.5210657093785;5793.870195311873;5899.705014749331;6043.920353761194;6065.5450841187085;5203.60972927032;5319.441458646864;5370.9731451342495;5844.912326315109;5870.096385903326;5144.61415393847;5838.9124163137585;5990.940090599125;6002.898985355091;6084.574079814459;4849.739067842316;5277.525022747971;5400.513953744182;5441.428649991853;5853.30747855991;5694.526338933165;5704.543636509153;5711.457411545896;5727.713614319351;5750.741216645345;4778.5699287064335;5924.229850119457;6004.49887528118;6027.945109605889;6072.240969878765;3179.8535988208328;3324.952639918418;3546.3793836078066;3732.328180927459;3817.8164768921724;4941.036497883022;5002.849914502571;5065.012322597058;5242.76407561653;5778.850526463004;5212.426633070435;5747.19539264495;5764.452377024176;5832.687645279778;5838.912416313674;4922.581580565548;5637.605367624391;5817.603775093862;5835.56233282514;5853.668285468638;5190.9897123119035;5550.806576586054;5609.859753506195;5730.656160630376;5746.827595172152;5391.218273350264;5801.1878337032;5818.418158184317;5836.766529338779;5839.678817664899;5464.6228890432785;5700.800471983461;5744.023516002206;5753.769849205984;5766.740496677576 11:50:26 INFO - PROCESS | 1898 | |5;yui.html;735.9227281135484;737.1449119021936;796.8127490039841;802.5024484819413;850.8213275212203;14422.999999999987;15327.616809579753;15551.53345399637;16803.579910502245;17682.557936051606;13419.329033548333;14384.496542621011;17221.913890430562;17448.91275543624;17601.295948162056;924.0528458330232;933.780561568031;940.05994005994;943.2359788571245;944.6976967370445;737.2516895351196;767.773506815488;899.3929097858952;930.9022552631977;933.7198840347869;981.3179840011195;993.7565296502331;995.3828626251734;995.412706503163;996.0238965813508;970.2529052629507;976.7118699983487;984.2962281968391;988.940663560185;989.2728844299434;721.0338146883149;736.2343163110359;736.7568702328219;739.2496615934831;739.6708464733196;16106.114163720991;16442.588935276595;16688.249028793613;16862.915685421496;17113.572160695956;12064.793520647865;14706.779398308927;14865.554033379016;15385.076895386313;15526;12115.212511186744;12121.909028187505;12203.93898030513;12213.269977285334;12214.083943704169;3837.2325534892852;3848.4034974578985;4009.4386785850006;4069.0844559974066;4070.7354021988476;117057.41471292674;120381;120902;123695;125550.37224813817;9223;11626.825597616042;11652.242604230698;11694.679969240322;11779.939805417554;20484.975751212678;22749.88625056864;23590.64614030791;24011;24055.158069467834;8262.132476090044;8341.749747507585;8359.958200209081;8393.286570641441;8420.368472364597;4513.052259025641;4523.192163641617;4558.88602784933;4589.495155532889;4591.334256532784;3788.9431658525145;3850.0952276215066;3858.614138586175;3894.805259737002;3898.2203559288255;699.9265077166824;712.5855326253808;726.57132291948;731.974380896666;733.0909672006674;576.6511260687307;589.8879212949525;606.9969650151721;616.1774031667774;637.9872402552015;9747.610095596097;10626.46867656614;10845.728856778485;10902.797520187025;10984.395858227874;579.9681017543991;608.8599622086945;620.3176505843626;625.9718312675849;714.1786945013276;4935.506449355035;5256.789728410898;5264.236685680555;5273.288106105703;5273.367195936512;103707;108185;109336;110051;112895.87104129019;90324;92013.53993229991;92484;94548;96668.06663866683;68479.3152068473;68935.31064689289;79064;80009.59995199987;81592;9970.417005482443;10033.297669163088;10090.596376145166;10129.949350253202;10152.898471015194;49131.5086849127;50279;52587.47412525979;54015;54628.18057729058;69001;69227.65386173037;70450;73061.6346918262;74864;13482.797758033834;13602.115862468909;13702;13759.724805504035;13866;15162.620934476285;15290.388384464497;15756.92121539385;15798.526044218692;16192.595185119995;12122.878771212529;14005.649858753613;14074.859251407355;14956.850431495981;14977.326020328894;11976.58081967127;13816.848059644857;14288.928555357157;14422.278886055865;14857.40570377216;11829.940850296038;13459.394327255493;14178.14931104137;14427.78358324647;14756.557303280919;9709.982852456516;11285.830712539482;11487.712807179887;11517.424128793695;11912.855685976261;9799.706008819747;11237;11611.6516504505;11725.296482211095;11760.35318057515 11:50:26 INFO - PROCESS | 1898 | __end_tp_report 11:50:26 INFO - PROCESS | 1898 | __start_cc_report 11:50:26 INFO - PROCESS | 1898 | _x_x_mozilla_cycle_collect,363 11:50:26 INFO - PROCESS | 1898 | __end_cc_report 11:50:26 INFO - PROCESS | 1898 | __startTimestamp1490122226554__endTimestamp 11:50:26 INFO - PROCESS | 1898 | 11:50:26 INFO - PROCESS | 1898 | ------- Summary: start ------- 11:50:26 INFO - PROCESS | 1898 | Number of tests: 6 11:50:26 INFO - PROCESS | 1898 | 11:50:26 INFO - PROCESS | 1898 | [#0] dojo.html Cycles:165 Average:5911.02 Median:6201.47 stddev:687.58 (11.1%) stddev-sans-first:643.64 11:50:26 INFO - PROCESS | 1898 | Values: 2757.5 2955.2 3520.2 3537.2 3553.6 5903.2 5906.4 5928.0 5996.1 6033.4 5947.9 6014.4 6039.2 6056.2 6059.8 4782.7 5289.4 5350.5 5362.2 5413.5 5427.3 5450.7 5454.4 5462.9 5466.3 5466.6 5467.3 5470.3 5472.0 5484.5 5261.9 5273.6 5392.6 5420.2 5445.9 5035.8 5330.4 5390.5 5401.8 5438.9 6006.3 6070.3 6100.8 6164.2 6165.1 6253.8 6263.6 6291.7 6296.6 6308.1 6210.3 6234.9 6267.2 6358.5 6368.4 6161.0 6170.1 6181.2 6183.2 6201.5 6167.4 6472.0 6503.4 6506.3 6513.3 6211.8 6229.6 6234.3 6245.7 6306.0 6353.3 6366.2 6368.0 6370.1 6382.2 6236.5 6262.4 6289.8 6291.8 6302.3 6113.1 6197.1 6212.5 6252.9 6261.2 4163.7 4666.7 4954.2 5153.7 5164.8 3882.9 4612.8 4614.4 4622.1 4671.1 5970.9 6044.4 6052.5 6086.4 6210.8 6365.6 6367.3 6369.0 6374.1 6404.5 6018.5 6110.6 6135.7 6147.7 6171.9 5592.2 5617.9 5631.0 5675.4 5734.9 6510.1 6534.7 6543.5 6553.9 6656.4 6369.9 6370.0 6371.2 6372.8 6383.9 6241.9 6315.2 6383.7 6410.1 6437.9 6282.7 6296.3 6305.3 6309.2 6327.1 6322.7 6351.1 6367.7 6369.8 6450.3 6170.6 6175.0 6215.9 6239.6 6270.9 6239.9 6259.6 6269.2 6271.1 6277.4 5958.7 6206.7 6220.7 6242.5 6243.7 6176.0 6187.3 6217.9 6230.2 6287.8 6188.7 6233.5 6251.7 6260.7 6271.2 11:50:26 INFO - PROCESS | 1898 | 11:50:26 INFO - PROCESS | 1898 | [#1] ext.html Cycles:180 Average:71690.15 Median:39490.20 stddev:150123.54 (380.2%) stddev-sans-first:150449.44 11:50:26 INFO - PROCESS | 1898 | Values: 469.2 522.7 529.8 536.0 594.3 28419.0 43531.1 43720.1 43824.9 43931.0 46580.8 46972.8 47082.5 47162.0 47171.8 9127.0 9628.9 9833.3 9835.3 9863.0 9181.0 11841.0 12093.2 12155.1 12353.0 12100.0 12156.0 12169.3 12192.2 12194.8 11895.6 11901.0 12023.2 12134.7 12159.5 11655.7 11694.6 11825.8 11874.1 11922.0 41474.0 43405.1 43534.8 43777.9 43970.5 4116.5 4184.1 4216.6 4302.0 4434.0 31298.1 31509.1 32272.4 32397.5 32677.5 3411.2 3412.6 3428.9 3452.3 3518.7 504038.0 509626.0 512027.9 512460.0 515614.0 62322.0 62645.0 63040.7 63108.7 63176.4 71579.6 72079.9 72755.6 72870.5 72963.3 17733.0 18326.9 18382.5 18405.3 18428.5 12717.2 13041.3 13822.4 14014.4 14071.4 18019.0 18295.4 18560.5 18592.9 18643.5 7732.8 7839.1 7870.8 7879.9 7899.6 909.6 910.9 913.2 940.3 943.9 26527.7 31444.5 32337.8 32430.7 32551.7 915.6 1089.2 1117.4 1138.4 1252.0 15728.0 16609.8 16853.3 16906.2 16936.7 604252.0 613651.0 621869.0 625251.0 628134.0 66400.3 66561.3 66623.0 66650.3 66894.3 1309.9 1349.9 1477.1 1515.0 1517.9 31258.7 31712.0 31930.0 32069.5 32547.5 56331.0 56528.4 56647.6 56659.7 56699.4 554525.0 556082.0 557465.4 557910.0 558863.0 37042.8 39075.8 39309.4 44080.7 44391.8 41390.8 49073.3 49305.8 50139.7 50439.0 38302.9 38303.0 39509.8 40503.0 40780.4 31425.0 39270.4 39470.6 40915.2 40921.4 38849.8 38893.8 39346.4 39448.8 39548.6 32342.0 34549.4 35255.0 35495.5 35496.0 25675.5 25833.0 26027.5 26173.2 26263.6 11:50:26 INFO - PROCESS | 1898 | 11:50:26 INFO - PROCESS | 1898 | [#2] jquery.html Cycles:180 Average:42745.32 Median:23041.64 stddev:134893.20 (585.4%) stddev-sans-first:135234.13 11:50:26 INFO - PROCESS | 1898 | Values: 396.8 401.2 405.1 406.6 427.7 21747.1 21934.9 22656.2 22922.1 23161.2 18770.9 21334.5 23371.5 23404.5 23656.1 6100.2 6328.7 6334.6 6341.8 6369.4 5878.6 6175.9 6232.3 6235.3 6248.7 5925.2 6053.9 6131.7 6168.2 6171.7 6194.9 6295.8 6310.1 6341.3 6342.7 6028.0 6207.1 6211.6 6239.3 6256.2 14113.2 14196.9 14418.9 26233.9 27467.9 2713.5 2775.6 2787.0 2793.9 3025.0 10433.2 10477.9 10481.8 10896.4 10948.8 1841.4 1883.2 1886.2 1886.8 1893.5 137600.3 140955.0 145964.3 146032.0 146322.0 10095.0 10665.6 12216.0 12283.8 12503.6 16132.8 16143.4 16155.7 16168.0 16168.5 9170.8 9354.6 9389.5 9398.7 9477.5 9114.6 9123.5 9168.3 9328.3 9354.9 4180.6 4595.2 4871.3 4888.0 5038.4 1558.7 1818.8 1858.4 1861.0 1864.5 901.7 974.8 1017.3 1038.5 1062.2 17375.7 19394.6 21896.1 22819.9 23252.2 1052.5 1062.9 1090.8 1097.6 1097.7 11822.3 11882.5 11935.2 11966.0 12100.0 806271.0 807112.0 808810.0 810907.0 831777.0 111662.2 135668.0 137533.9 155084.2 159328.0 998.2 1001.5 1026.4 1034.3 1036.9 19045.4 20121.9 20125.7 20183.2 20234.5 45091.6 58775.7 66552.7 66997.0 68281.0 86910.0 87039.6 88782.6 89964.0 90082.5 8289.8 8380.4 8468.2 8673.5 8722.3 12036.5 12975.4 13139.5 13307.5 13929.0 11495.3 11799.9 11897.6 11954.2 11968.5 10148.0 11528.3 11586.4 12012.0 12066.4 9152.7 11981.8 12089.4 12133.6 12173.0 7505.4 7728.0 8386.7 8568.7 8690.8 5754.7 7906.9 8188.3 11442.5 11700.8 11:50:26 INFO - PROCESS | 1898 | 11:50:26 INFO - PROCESS | 1898 | [#3] mootools.html Cycles:180 Average:5508.89 Median:5288.20 stddev:2982.73 (56.4%) stddev-sans-first:2974.82 11:50:26 INFO - PROCESS | 1898 | Values: 1363.6 1431.6 1475.5 1482.7 1486.9 7308.7 7371.5 8241.3 8650.5 8688.2 8112.8 8418.3 8431.2 8525.2 8528.8 2365.8 2400.6 2401.9 2403.3 2420.7 2391.8 2420.5 2431.8 2437.5 2444.7 2022.9 2387.5 2389.2 2421.9 2423.0 2020.9 2175.7 2203.9 2333.6 2345.0 2072.6 2369.5 2412.4 2412.9 2414.0 7217.0 8557.1 8572.9 8579.9 8592.5 5116.6 5164.3 5219.6 5319.9 5321.3 3478.4 3631.8 3718.0 3721.9 3726.7 1229.2 1251.1 1312.7 1318.4 1324.5 10931.8 10966.7 10979.2 11002.1 11017.2 4238.3 4451.3 4696.6 4775.2 4836.1 9653.5 9671.9 9683.3 9684.3 9686.0 5369.1 5399.2 5408.9 5410.2 5419.3 3854.5 4292.2 4833.5 4866.7 4943.8 3290.5 3957.5 3997.3 4033.9 4051.2 1291.1 1294.9 1303.8 1304.2 1306.3 933.9 940.7 949.7 952.1 970.3 5630.2 5703.3 5932.9 6049.9 6107.3 579.6 580.7 591.7 600.1 609.3 3876.5 3894.1 3894.7 3932.4 3978.9 9752.2 10078.4 10079.3 10085.9 10184.7 9998.0 10010.1 10033.9 10038.2 10141.2 7516.3 7915.4 7918.8 7959.2 8051.8 5858.8 5920.1 5939.8 5968.1 6036.9 7736.9 8967.9 9288.4 9292.0 9309.8 9065.5 9319.7 9331.4 9337.8 9444.4 6706.6 7091.3 7224.1 7235.6 7383.4 8001.6 8076.0 8082.7 8083.0 8102.8 7104.1 7213.2 7254.2 7320.7 7370.0 7031.4 7100.5 7121.0 7198.7 7259.9 6476.8 6487.9 6567.0 6581.8 6622.3 5040.9 5064.1 5100.0 5170.3 5196.9 5066.9 5081.0 5144.6 5176.3 5256.5 11:50:26 INFO - PROCESS | 1898 | 11:50:26 INFO - PROCESS | 1898 | [#4] prototype.html Cycles:180 Average:4607.70 Median:5146.08 stddev:1417.77 (27.6%) stddev-sans-first:1385.64 11:50:26 INFO - PROCESS | 1898 | Values: 372.0 373.5 378.9 383.7 388.0 4003.2 4722.0 4809.8 5314.0 5522.5 4798.0 4976.3 5204.9 5205.9 5481.3 3405.8 3906.7 4180.3 4181.0 4324.0 3430.3 3777.7 3929.4 4202.9 4316.0 4217.4 4260.2 4264.7 4266.7 4268.4 3849.3 3851.7 4087.2 4134.6 4229.7 3760.5 3766.6 3775.2 3800.1 3807.8 5113.8 5245.8 5304.6 5352.8 5372.8 4145.5 4165.4 4951.0 4990.1 4995.9 5003.8 5147.5 5810.9 5855.9 5935.6 3700.3 3955.4 3987.8 3997.6 4167.9 6033.4 6062.2 6100.8 6119.3 6135.6 3433.6 3621.5 4005.6 4031.4 4038.2 3349.0 3642.3 4071.2 4080.0 4102.2 4786.6 4795.2 4808.5 4811.1 4833.8 5648.9 5895.9 5924.8 5949.5 5965.0 1180.5 1204.9 1269.9 1303.8 1320.8 775.3 848.9 882.7 883.7 895.6 4718.2 4950.7 5234.3 5282.2 5294.4 4827.8 4897.8 5259.8 5368.7 5651.7 4739.9 5049.8 5063.8 5274.2 5298.7 2974.9 3014.7 3046.4 3052.5 3075.8 5634.5 5793.9 5899.7 6043.9 6065.5 5203.6 5319.4 5371.0 5844.9 5870.1 5144.6 5838.9 5990.9 6002.9 6084.6 4849.7 5277.5 5400.5 5441.4 5853.3 5694.5 5704.5 5711.5 5727.7 5750.7 4778.6 5924.2 6004.5 6027.9 6072.2 3179.9 3325.0 3546.4 3732.3 3817.8 4941.0 5002.8 5065.0 5242.8 5778.9 5212.4 5747.2 5764.5 5832.7 5838.9 4922.6 5637.6 5817.6 5835.6 5853.7 5191.0 5550.8 5609.9 5730.7 5746.8 5391.2 5801.2 5818.4 5836.8 5839.7 5464.6 5700.8 5744.0 5753.8 5766.7 11:50:26 INFO - PROCESS | 1898 | 11:50:26 INFO - PROCESS | 1898 | [#5] yui.html Cycles:180 Average:21648.16 Median:3896.51 stddev:31471.03 (807.7%) stddev-sans-first:31520.15 11:50:26 INFO - PROCESS | 1898 | Values: 735.9 737.1 796.8 802.5 850.8 14423.0 15327.6 15551.5 16803.6 17682.6 13419.3 14384.5 17221.9 17448.9 17601.3 924.1 933.8 940.1 943.2 944.7 737.3 767.8 899.4 930.9 933.7 981.3 993.8 995.4 995.4 996.0 970.3 976.7 984.3 988.9 989.3 721.0 736.2 736.8 739.2 739.7 16106.1 16442.6 16688.2 16862.9 17113.6 12064.8 14706.8 14865.6 15385.1 15526.0 12115.2 12121.9 12203.9 12213.3 12214.1 3837.2 3848.4 4009.4 4069.1 4070.7 117057.4 120381.0 120902.0 123695.0 125550.4 9223.0 11626.8 11652.2 11694.7 11779.9 20485.0 22749.9 23590.6 24011.0 24055.2 8262.1 8341.7 8360.0 8393.3 8420.4 4513.1 4523.2 4558.9 4589.5 4591.3 3788.9 3850.1 3858.6 3894.8 3898.2 699.9 712.6 726.6 732.0 733.1 576.7 589.9 607.0 616.2 638.0 9747.6 10626.5 10845.7 10902.8 10984.4 580.0 608.9 620.3 626.0 714.2 4935.5 5256.8 5264.2 5273.3 5273.4 103707.0 108185.0 109336.0 110051.0 112895.9 90324.0 92013.5 92484.0 94548.0 96668.1 68479.3 68935.3 79064.0 80009.6 81592.0 9970.4 10033.3 10090.6 10129.9 10152.9 49131.5 50279.0 52587.5 54015.0 54628.2 69001.0 69227.7 70450.0 73061.6 74864.0 13482.8 13602.1 13702.0 13759.7 13866.0 15162.6 15290.4 15756.9 15798.5 16192.6 12122.9 14005.6 14074.9 14956.9 14977.3 11976.6 13816.8 14288.9 14422.3 14857.4 11829.9 13459.4 14178.1 14427.8 14756.6 9710.0 11285.8 11487.7 11517.4 11912.9 9799.7 11237.0 11611.7 11725.3 11760.4 11:50:26 INFO - PROCESS | 1898 | -------- Summary: end -------- 11:50:26 INFO - PROCESS | 1898 | 11:50:27 INFO - TEST-INFO | 1898: exit 0 11:50:27 INFO - TEST-OK | dromaeo_css | took 1113584ms 11:50:27 INFO - TEST-START | kraken 11:50:27 INFO - operating with platform_type : mac_ 11:50:27 INFO - Initialising browser for kraken test... 11:50:27 INFO - TEST-INFO | started process 1905 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpe_1dFa/profile http://localhost:49409/getInfo.html) 11:50:29 INFO - PROCESS | 1905 | 2017-03-21 11:50:29.614 plugin-container[1907:10869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8533, name = 'com.apple.tsm.portname' 11:50:29 INFO - PROCESS | 1905 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:50:29 INFO - PROCESS | 1905 | 2017-03-21 11:50:29.616 plugin-container[1907:10869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 11:50:29 INFO - PROCESS | 1905 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:50:29 INFO - PROCESS | 1905 | 2017-03-21 11:50:29.616 plugin-container[1907:10869] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:50:29 INFO - PROCESS | 1905 | __metrics Screen width/height:1600/1200 11:50:29 INFO - PROCESS | 1905 | colorDepth:24 11:50:29 INFO - PROCESS | 1905 | Browser inner width/height: 1024/689 11:50:29 INFO - PROCESS | 1905 | __metrics 11:50:29 ERROR - PROCESS | 1905 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 11:50:30 INFO - TEST-INFO | 1905: exit 0 11:50:30 INFO - Browser initialized. 11:50:30 INFO - Running cycle 1/1 for kraken test... 11:50:30 INFO - TEST-INFO | started process 1908 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpe_1dFa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:50:32 INFO - PROCESS | 1908 | 2017-03-21 11:50:32.886 plugin-container[1909:10993] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 11:50:32 INFO - PROCESS | 1908 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:50:32 INFO - PROCESS | 1908 | 2017-03-21 11:50:32.886 plugin-container[1909:10993] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880f, name = 'com.apple.CFPasteboardClient' 11:50:32 INFO - PROCESS | 1908 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:50:32 INFO - PROCESS | 1908 | 2017-03-21 11:50:32.887 plugin-container[1909:10993] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:50:42 INFO - PROCESS | 1908 | RSS: Main: 173092864 11:50:42 INFO - PROCESS | 1908 | 11:50:45 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49409/tests/kraken/driver.html?testName=audio-beat-detection) 11:50:46 INFO - PROCESS | 1908 | RSS: Main: 186142720 11:50:46 INFO - PROCESS | 1908 | 11:50:49 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49409/tests/kraken/driver.html?testName=audio-dft) 11:50:49 INFO - PROCESS | 1908 | RSS: Main: 194289664 11:50:49 INFO - PROCESS | 1908 | 11:50:53 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49409/tests/kraken/driver.html?testName=audio-fft) 11:50:53 INFO - PROCESS | 1908 | RSS: Main: 197996544 11:50:53 INFO - PROCESS | 1908 | 11:50:55 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49409/tests/kraken/driver.html?testName=audio-oscillator) 11:50:56 INFO - PROCESS | 1908 | RSS: Main: 198299648 11:50:56 INFO - PROCESS | 1908 | 11:50:58 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49409/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:50:59 INFO - PROCESS | 1908 | RSS: Main: 199696384 11:50:59 INFO - PROCESS | 1908 | 11:51:02 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49409/tests/kraken/driver.html?testName=imaging-darkroom) 11:51:02 INFO - PROCESS | 1908 | RSS: Main: 203677696 11:51:02 INFO - PROCESS | 1908 | 11:51:05 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49409/tests/kraken/driver.html?testName=imaging-desaturate) 11:51:06 INFO - PROCESS | 1908 | RSS: Main: 202825728 11:51:06 INFO - PROCESS | 1908 | 11:51:09 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49409/tests/kraken/driver.html?testName=json-parse-financial) 11:51:10 INFO - PROCESS | 1908 | RSS: Main: 203042816 11:51:10 INFO - PROCESS | 1908 | 11:51:11 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49409/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:51:12 INFO - PROCESS | 1908 | RSS: Main: 202313728 11:51:12 INFO - PROCESS | 1908 | 11:51:14 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:51:15 INFO - PROCESS | 1908 | RSS: Main: 202280960 11:51:15 INFO - PROCESS | 1908 | 11:51:17 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:51:17 INFO - PROCESS | 1908 | RSS: Main: 202289152 11:51:17 INFO - PROCESS | 1908 | 11:51:20 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:51:21 INFO - PROCESS | 1908 | RSS: Main: 202706944 11:51:21 INFO - PROCESS | 1908 | 11:51:24 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:51:25 INFO - PROCESS | 1908 | RSS: Main: 202465280 11:51:25 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49409/tests/kraken/driver.html?testName=ai-astar) 11:51:26 INFO - PROCESS | 1908 | RSS: Main: 204386304 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | __start_tp_report 11:51:26 INFO - PROCESS | 1908 | _x_x_mozilla_page_load 11:51:26 INFO - PROCESS | 1908 | _x_x_mozilla_page_load_details 11:51:26 INFO - PROCESS | 1908 | |i|pagename|runs| 11:51:26 INFO - PROCESS | 1908 | |0;ai-astar;108;145;172;170;136;119;119;117;117;118 11:51:26 INFO - PROCESS | 1908 | |1;audio-beat-detection;145;109;111;150;110;112;112;116;162;146 11:51:26 INFO - PROCESS | 1908 | |2;audio-dft;201;195;198;197;198;242;197;213;189;281 11:51:26 INFO - PROCESS | 1908 | |3;audio-fft;86;59;62;91;61;59;60;58;70;60 11:51:26 INFO - PROCESS | 1908 | |4;audio-oscillator;82;72;83;74;75;102;113;99;102;112 11:51:26 INFO - PROCESS | 1908 | |5;imaging-gaussian-blur;91;132;123;101;88;84;90;85;76;132 11:51:26 INFO - PROCESS | 1908 | |6;imaging-darkroom;151;95;94;99;95;103;150;110;93;93 11:51:26 INFO - PROCESS | 1908 | |7;imaging-desaturate;141;91;105;92;138;144;139;92;91;92 11:51:26 INFO - PROCESS | 1908 | |8;json-parse-financial;58;57;60;62;62;61;60;63;61;61 11:51:26 INFO - PROCESS | 1908 | |9;json-stringify-tinderbox;85;100;69;105;60;80;99;105;102;90 11:51:26 INFO - PROCESS | 1908 | |10;stanford-crypto-aes;85;85;82;85;84;80;83;85;86;88 11:51:26 INFO - PROCESS | 1908 | |11;stanford-crypto-ccm;133;174;148;139;135;132;141;139;182;137 11:51:26 INFO - PROCESS | 1908 | |12;stanford-crypto-pbkdf2;148;200;201;166;166;170;172;202;165;164 11:51:26 INFO - PROCESS | 1908 | |13;stanford-crypto-sha256-iterative;64;65;64;65;65;64;66;65;65;63 11:51:26 INFO - PROCESS | 1908 | __end_tp_report 11:51:26 INFO - PROCESS | 1908 | __start_cc_report 11:51:26 INFO - PROCESS | 1908 | _x_x_mozilla_cycle_collect,946 11:51:26 INFO - PROCESS | 1908 | __end_cc_report 11:51:26 INFO - PROCESS | 1908 | __startTimestamp1490122286979__endTimestamp 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | ------- Summary: start ------- 11:51:26 INFO - PROCESS | 1908 | Number of tests: 14 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#0] ai-astar Cycles:10 Average:132.10 Median:127.50 stddev:23.07 (18.1%) stddev-sans-first:22.76 11:51:26 INFO - PROCESS | 1908 | Values: 108.0 145.0 172.0 170.0 136.0 119.0 119.0 117.0 117.0 118.0 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:130.50 stddev:20.76 (15.9%) stddev-sans-first:21.01 11:51:26 INFO - PROCESS | 1908 | Values: 145.0 109.0 111.0 150.0 110.0 112.0 112.0 116.0 162.0 146.0 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#2] audio-dft Cycles:10 Average:211.10 Median:199.50 stddev:28.74 (14.4%) stddev-sans-first:30.25 11:51:26 INFO - PROCESS | 1908 | Values: 201.0 195.0 198.0 197.0 198.0 242.0 197.0 213.0 189.0 281.0 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#3] audio-fft Cycles:10 Average:66.60 Median:61.50 stddev:12.08 (19.6%) stddev-sans-first:10.57 11:51:26 INFO - PROCESS | 1908 | Values: 86.0 59.0 62.0 91.0 61.0 59.0 60.0 58.0 70.0 60.0 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:74.50 stddev:15.92 (21.4%) stddev-sans-first:16.52 11:51:26 INFO - PROCESS | 1908 | Values: 82.0 72.0 83.0 74.0 75.0 102.0 113.0 99.0 102.0 112.0 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#5] imaging-gaussian-blur Cycles:10 Average:100.20 Median:84.50 stddev:20.98 (24.8%) stddev-sans-first:21.98 11:51:26 INFO - PROCESS | 1908 | Values: 91.0 132.0 123.0 101.0 88.0 84.0 90.0 85.0 76.0 132.0 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#6] imaging-darkroom Cycles:10 Average:108.30 Median:93.50 stddev:22.87 (24.5%) stddev-sans-first:18.30 11:51:26 INFO - PROCESS | 1908 | Values: 151.0 95.0 94.0 99.0 95.0 103.0 150.0 110.0 93.0 93.0 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#7] imaging-desaturate Cycles:10 Average:112.50 Median:91.00 stddev:24.49 (26.9%) stddev-sans-first:23.71 11:51:26 INFO - PROCESS | 1908 | Values: 141.0 91.0 105.0 92.0 138.0 144.0 139.0 92.0 91.0 92.0 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#8] json-parse-financial Cycles:10 Average:60.50 Median:61.00 stddev:1.84 (3.0%) stddev-sans-first:1.72 11:51:26 INFO - PROCESS | 1908 | Values: 58.0 57.0 60.0 62.0 62.0 61.0 60.0 63.0 61.0 61.0 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#9] json-stringify-tinderbox Cycles:10 Average:89.50 Median:74.50 stddev:15.77 (21.2%) stddev-sans-first:16.64 11:51:26 INFO - PROCESS | 1908 | Values: 85.0 100.0 69.0 105.0 60.0 80.0 99.0 105.0 102.0 90.0 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.00 stddev:2.21 (2.6%) stddev-sans-first:2.33 11:51:26 INFO - PROCESS | 1908 | Values: 85.0 85.0 82.0 85.0 84.0 80.0 83.0 85.0 86.0 88.0 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:140.00 stddev:17.56 (12.5%) stddev-sans-first:17.98 11:51:26 INFO - PROCESS | 1908 | Values: 133.0 174.0 148.0 139.0 135.0 132.0 141.0 139.0 182.0 137.0 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.40 Median:171.00 stddev:18.78 (11.0%) stddev-sans-first:17.10 11:51:26 INFO - PROCESS | 1908 | Values: 148.0 200.0 201.0 166.0 166.0 170.0 172.0 202.0 165.0 164.0 11:51:26 INFO - PROCESS | 1908 | 11:51:26 INFO - PROCESS | 1908 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:65.00 stddev:0.84 (1.3%) stddev-sans-first:0.87 11:51:26 INFO - PROCESS | 1908 | Values: 64.0 65.0 64.0 65.0 65.0 64.0 66.0 65.0 65.0 63.0 11:51:26 INFO - PROCESS | 1908 | -------- Summary: end -------- 11:51:26 INFO - PROCESS | 1908 | 11:51:27 INFO - TEST-INFO | 1908: exit 0 11:51:27 INFO - TEST-OK | kraken | took 60640ms 11:51:27 INFO - SUITE-END | took 1174s 11:51:28 INFO - Completed test suite (00:19:34) 11:51:28 INFO - javascript benchmark 11:51:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7568.892387325164, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2757.4760795448865, 2955.172551685528, 3520.1551627609383, 3537.239493508897, 3553.5558055243096, 5903.203067585875, 5906.4388883056145, 5927.962606543853, 5996.130561068642, 6033.366496517869, 5947.851303717409, 6014.428629280223, 6039.214902062726, 6056.212692349988, 6059.848503787408, 4782.689125206851, 5289.365276166855, 5350.5184533391985, 5362.2224777407255, 5413.512783849453, 5427.348718153827, 5450.700211488366, 5454.427285135063, 5462.863428414282, 5466.289382380285, 5466.644668096561, 5467.289252397202, 5470.316210473691, 5471.972640136813, 5484.478974497416, 5261.86845328866, 5273.578113750891, 5392.622516423852, 5420.24116623671, 5445.918311225335, 5035.8489245322335, 5330.360356757175, 5390.460953904618, 5401.810936617199, 5438.945610543923, 6006.309274433409, 6070.301915279755, 6100.786472473486, 6164.198654174974, 6165.10605962133, 6253.843653908643, 6263.561550691454, 6291.716872740738, 6296.590721603081, 6308.085327627514, 6210.254769427653, 6234.906476402857, 6267.185265915402, 6358.523110766711, 6368.363163683641, 6161.014237721943, 6170.074488826667, 6181.196444462236, 6183.227096612924, 6201.472874805602, 6167.444929956236, 6472.0, 6503.414692677682, 6506.3493650634555, 6513.348665133449, 6211.751529938751, 6229.626222426578, 6234.251889773256, 6245.687715614201, 6306.0, 6353.332900045429, 6366.204224471941, 6367.968160159169, 6370.108184854124, 6382.234131904108, 6236.501079913596, 6262.405071518198, 6289.842753931189, 6291.811245662638, 6302.275238347557, 6113.081058500009, 6197.101420294122, 6212.47193988507, 6252.937470625327, 6261.154744109487, 4163.708540402143, 4666.673332866734, 4954.232094025359, 5153.742312884341, 5164.793408263702, 3882.9321936467695, 4612.76936153191, 4614.423197100363, 4622.075584883037, 4671.11248862715, 5970.880582388328, 6044.425779550936, 6052.455279024909, 6086.391360863879, 6210.782622608187, 6365.554411191172, 6367.299597044322, 6369.0, 6374.075759014917, 6404.455621272151, 6018.548608854353, 6110.633361998294, 6135.693215339215, 6147.692615369214, 6171.907421388593, 5592.245046918614, 5617.887642247214, 5630.97184514083, 5675.3757086720425, 5734.885302293931, 6510.056041873901, 6534.673266336569, 6543.476521878239, 6553.934460655523, 6656.434203092791, 6369.90445143314, 6370.0, 6371.171747672773, 6372.776952806629, 6383.872322553616, 6241.875162496815, 6315.179026726496, 6383.712732926937, 6410.102585637921, 6437.871242575216, 6282.654454004891, 6296.275928268308, 6305.337939516285, 6309.211348581427, 6327.082573026977, 6322.683865806599, 6351.079093531504, 6367.713452894724, 6369.777057802955, 6450.258220304538, 6170.629762214281, 6174.969125154345, 6215.9378406215355, 6239.563230573816, 6270.933941230093, 6239.875202496016, 6259.624422534663, 6269.153664255267, 6271.090691849748, 6277.372262773686, 5958.672273024852, 6206.72069756853, 6220.657863817352, 6242.500599952084, 6243.719032643451, 6175.96912015437, 6187.288461826947, 6217.875642487034, 6230.190075290183, 6287.842803929939, 6188.690565471618, 6233.501319894307, 6251.71867265965, 6260.718267678057, 6271.216097987752], "value": 5863.187961616537, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.1719115760681, 522.7438555107996, 529.8489930369846, 535.9973200134, 594.3165359836186, 28419.0, 43531.12937741247, 43720.12559748803, 43824.9043773905, 43931.0, 46580.767096164556, 46972.765136174356, 47082.529174708245, 47162.0, 47171.76414117924, 9126.954365228165, 9628.903710962872, 9833.31166818323, 9835.262355323344, 9862.950685246564, 9180.954095229514, 11841.0, 12093.153479256456, 12155.149139560235, 12353.0, 12100.0, 12155.96674282687, 12169.269843809356, 12192.207506512048, 12194.756104877897, 11895.583654572136, 11901.0, 12023.218490798114, 12134.696632584168, 12159.51361945521, 11655.708607284801, 11694.649160525198, 11825.822612660817, 11874.109441791814, 11922.046236301076, 41474.0, 43405.13189736219, 43534.78232608816, 43777.905552361124, 43970.46103386366, 4116.5060192777055, 4184.079502509443, 4216.55726148753, 4301.956980430187, 4434.024514606781, 31298.061058168292, 31509.054728358187, 32272.354552909044, 32397.51403728946, 32677.50983735246, 3411.232472693649, 3412.607550131742, 3428.9142771430916, 3452.2750222453315, 3518.700910422617, 504038.0, 509626.0, 512027.8797212017, 512460.0, 515614.0, 62322.0, 62645.0, 63040.684796575726, 63108.68445657834, 63176.36823631705, 71579.64210178916, 72079.91880121802, 72755.63622181855, 72870.54258914899, 72963.2703672967, 17733.0, 18326.908365458356, 18382.540436489195, 18405.26378944854, 18428.539286517946, 12717.173383730027, 13041.282729449971, 13822.447102116004, 14014.369353379123, 14071.366788494543, 18019.0, 18295.359662411753, 18560.535986600444, 18592.907035465007, 18643.533911652317, 7732.806679833049, 7839.05931288249, 7870.81232101, 7879.85742067397, 7899.605019749105, 909.5588639509832, 910.854263317866, 913.2100732866023, 940.3276657190141, 943.9433633981984, 26527.734722652913, 31444.52833207504, 32337.838310808766, 32430.675693242767, 32551.674483254865, 915.5513798238815, 1089.2484185911696, 1117.3742704085575, 1138.419406102877, 1252.0109113799997, 15728.0, 16609.83390166083, 16853.325866965428, 16906.239219235165, 16936.745948810778, 604252.0, 613651.0, 621869.0, 625251.0, 628134.0, 66400.33599664038, 66561.33438665551, 66623.0, 66650.33349666538, 66894.33105668979, 1309.8690130987015, 1349.8920086393066, 1477.0694462488564, 1514.9697006059816, 1517.9392824286904, 31258.68741312558, 31712.0, 31930.042098737074, 32069.518957215194, 32547.511787322735, 56331.0, 56528.434715652314, 56647.58381040507, 56659.716701416226, 56699.433005669416, 554525.0, 556082.0, 557465.4253457413, 557910.0, 558863.0, 37042.8147859259, 39075.80462097671, 39309.41035884521, 44080.67757967266, 44391.77804110959, 41390.793046034574, 49073.26390104223, 49305.767355816395, 50139.74930125326, 50439.0, 38302.85091447261, 38303.0, 39509.80245098756, 40503.0, 40780.38829417502, 31425.0, 39270.41094383644, 39470.60529394669, 40915.18169636531, 40921.38617920793, 38849.8345049649, 38893.80553097217, 39346.426142955104, 39448.802755986035, 39548.604513954495, 32342.0, 34549.37231590647, 35255.0, 35495.46756798599, 35496.0, 25675.486490269715, 25833.0, 26027.47945041051, 26173.214803555922, 26263.606045908946], "value": 21589.381060604217, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.83134667766194, 401.1595706993847, 405.1067396390958, 406.59949949299937, 427.7027465911193, 21747.130114795407, 21934.89032554839, 22656.207032753857, 22922.083116675356, 23161.18935837246, 18770.90614546929, 21334.46663833405, 23371.532569348645, 23404.5319093618, 23656.053757849666, 6100.206973093492, 6328.715207815636, 6334.588251763644, 6341.8097457076365, 6369.426751592356, 5878.588498805086, 6175.907361389583, 6232.345603711613, 6235.251769787632, 6248.6875656217235, 5925.229720136398, 6053.878922421549, 6131.693415329216, 6168.167297414859, 6171.660558669271, 6194.907076393858, 6295.779647712308, 6310.116583678288, 6341.270753863296, 6342.746290148389, 6028.0, 6207.131001659771, 6211.596246243979, 6239.2824825145235, 6256.186695729526, 14113.153210807282, 14196.858031419657, 14418.855811441961, 26233.868830655916, 27467.86266068677, 2713.511567917774, 2775.597538356927, 2787.010611233003, 2793.9022134225406, 3025.031989763255, 10433.217508686803, 10477.947610261976, 10481.79036419275, 10896.40069796161, 10948.781024379468, 1841.4291569613463, 1883.161992913154, 1886.2455017992775, 1886.7924528301876, 1893.479293194369, 137600.31199844036, 140955.0, 145964.27017864844, 146032.0, 146322.0, 10094.981003799123, 10665.573377064986, 12216.0, 12283.754324913452, 12503.562090359725, 16132.838671613368, 16143.434979775651, 16155.67688646244, 16168.029918204946, 16168.514944551682, 9170.81658366829, 9354.625814967325, 9389.483578403253, 9398.67104651334, 9477.4787386693, 9114.635414583476, 9123.543822808833, 9168.26653867689, 9328.34701570897, 9354.906450935403, 4180.623743863067, 4595.172868883633, 4871.34236878017, 4887.95112048882, 5038.395392552844, 1558.7428074367601, 1818.7544681468098, 1858.3774435564237, 1860.990695046516, 1864.5338665333666, 901.7024381953939, 974.7611835100446, 1017.2624846985876, 1038.4651904269308, 1062.1768129699544, 17375.73936390955, 19394.612107757766, 21896.138828199462, 22819.85900704958, 23252.186173483846, 1052.5000624703357, 1062.9362238265574, 1090.7545802194443, 1097.6377795327523, 1097.7145942055124, 11822.34977076252, 11882.524699011943, 11935.164538482222, 11965.98289145415, 12100.0, 806271.0, 807112.0, 808810.0, 810907.0, 831777.0, 111662.20844478953, 135668.0, 137533.93699094522, 155084.2245788764, 159328.0, 998.1665309466531, 1001.5042553935848, 1026.3585259212991, 1034.3225187502198, 1036.8703912011, 19045.428637140907, 20121.899390502953, 20125.698114528, 20183.192672292942, 20234.49413764609, 45091.64725058254, 58775.70612146912, 66552.6672366635, 66997.0, 68281.0, 86910.0, 87039.56480217558, 88782.55608721916, 89964.0, 90082.54958725165, 8289.83420331602, 8380.413371063967, 8468.195521425454, 8673.522956237468, 8722.302215822592, 12036.518539258683, 12975.351232438528, 13139.54011609589, 13307.534236301683, 13929.0, 11495.310281383145, 11799.882001179878, 11897.643070707893, 11954.163208575315, 11968.46141923598, 10148.0, 11528.308301501937, 11586.420678966186, 12012.0, 12066.39668016578, 9152.725418237464, 11981.76036479283, 12089.39553022363, 12133.575324863587, 12173.0, 7505.437092217996, 7728.033995750531, 8386.748397548083, 8568.742937711879, 8690.78273043179, 5754.6547207167705, 7906.920930790849, 8188.2630563249595, 11442.542298307975, 11700.835345461117], "value": 10150.687207386423, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.6113707593336, 1431.6449440670106, 1475.4835807467382, 1482.747932851415, 1486.8661820436166, 7308.707651693926, 7371.483996120261, 8241.299489543388, 8650.480971141727, 8688.218060374566, 8112.797180070501, 8418.32653387729, 8431.241188293052, 8525.232729054384, 8528.829423411527, 2365.775251351119, 2400.6279026750794, 2401.891914863835, 2403.3150552092657, 2420.66110744495, 2391.8445301055463, 2420.4674971506242, 2431.756824317563, 2437.5124975004983, 2444.7433019532873, 2022.8583999120067, 2387.462820865309, 2389.2115601851347, 2421.903123875043, 2422.9515409691885, 2020.8989550522488, 2175.71715676961, 2203.8567493112896, 2333.603287441139, 2344.9765502345012, 2072.575122099966, 2369.5142495788323, 2412.44513761834, 2412.9396765080837, 2413.987930060356, 7216.963915180443, 8557.058723540405, 8572.871406928902, 8579.914200858037, 8592.484450932901, 5116.641835071547, 5164.328637277167, 5219.634625576211, 5319.893602127974, 5321.281626980385, 3478.4260597001717, 3631.8184090795353, 3718.0, 3721.925561488782, 3726.664600185969, 1229.2317301686446, 1251.0546100813233, 1312.6980794417339, 1318.406716977364, 1324.4966912573257, 10931.781364372668, 10966.725831854268, 10979.1765617579, 11002.064824489928, 11017.228793984425, 4238.3430568261965, 4451.310046942729, 4696.60078893291, 4775.188218002948, 4836.081144582518, 9653.46905920181, 9671.854922176173, 9683.322167448352, 9684.322097453252, 9686.0, 5369.140937449989, 5399.244105825188, 5408.945910540923, 5410.188471729272, 5419.3225846769155, 3854.5181852268465, 4292.227399068198, 4833.516648335209, 4866.7079975201605, 4943.8022479101155, 3290.4899740540254, 3957.465742124834, 3997.340438827619, 4033.878983630496, 4051.169510250392, 1291.1349395904767, 1294.9093563450656, 1303.791393377055, 1304.1588175626857, 1306.3402981494476, 933.8505839065853, 940.6989763275823, 949.6676163342775, 952.0907533305676, 970.3110791338088, 5630.211770352072, 5703.287089113861, 5932.881342373128, 6049.879002419927, 6107.267127944586, 579.6145563200424, 580.6632153351046, 591.7278052096084, 600.0549135112216, 609.3479976425272, 3876.534815822063, 3894.1432884765304, 3894.6884249259992, 3932.390479475686, 3978.8607398740905, 9752.21982241419, 10078.445685487368, 10079.34484258521, 10085.89055203927, 10184.745381365377, 9998.0, 10010.14913732341, 10033.899661003297, 10038.19694424459, 10141.2394070445, 7516.285952834472, 7915.366770658334, 7918.802029949297, 7959.164287749819, 8051.758447246592, 5858.765649374148, 5920.111983202381, 5939.792107276225, 5968.134620480093, 6036.909446358396, 7736.883946740691, 8967.865482017645, 9288.442693438416, 9292.0, 9309.767255818388, 9065.546722663708, 9319.720408387759, 9331.393459425115, 9337.813243735223, 9444.433333999981, 6706.631135287606, 7091.290870912868, 7224.096987876515, 7235.602041887747, 7383.372413344711, 8001.559914204775, 8076.0, 8082.7171049013, 8082.959585202037, 8102.83794324122, 7104.147502299757, 7213.242609525924, 7254.16577093641, 7320.743773967885, 7370.0, 7031.40233080194, 7100.502964792415, 7121.0, 7198.71205151788, 7259.9274007259255, 6476.838079048061, 6487.935120648922, 6567.0, 6581.769638062645, 6622.304658010839, 5040.949590504195, 5064.088464076502, 5100.0, 5170.276161337491, 5196.922046169386, 5066.923996139987, 5080.974595127149, 5144.614153938394, 5176.275321455075, 5256.500632439912], "value": 4451.278199416731, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.01787281576645, 373.5330836454432, 378.9374753165727, 383.65279422122956, 388.045408295593, 4003.1793482336125, 4722.008378240574, 4809.807607695692, 5313.9637770634745, 5522.530584900283, 4798.0, 4976.278439626252, 5204.895902081956, 5205.895882082356, 5481.287432633752, 3405.7786243894066, 3906.746061506007, 4180.331147016478, 4180.999999999993, 4323.956760432387, 3430.279641275335, 3777.7166712496537, 3929.351656976595, 4202.949262684329, 4316.0, 4217.36739489076, 4260.233158031553, 4264.658827293824, 4266.679999000072, 4268.359746038088, 3849.30712471755, 3851.7111216658864, 4087.2438598859158, 4134.607212314855, 4229.746215227096, 3760.548734151892, 3766.5856755756845, 3775.244951009809, 3800.0879788850752, 3807.7524960877454, 5113.821016264413, 5245.8426247212265, 5304.63155896005, 5352.785888564492, 5372.758225879845, 4145.54399016108, 4165.375193720935, 4950.950490495121, 4990.051890140898, 4995.8501244962345, 5003.849114703602, 5147.536721695028, 5810.941890581083, 5855.916655418719, 5935.584509084366, 3700.3154416433044, 3955.4462375267485, 3987.8404863805413, 3997.620226078518, 4167.895802604929, 6033.3664965178905, 6062.242219722535, 6100.847478813021, 6119.296280927706, 6135.59474491657, 3433.639467855839, 3621.456781482746, 4005.559388467266, 4031.4355990161403, 4038.1519880824767, 3349.0, 3642.2699217871827, 4071.1654110907193, 4079.9592004080173, 4102.2000709861295, 4786.593139583175, 4795.160846851787, 4808.519148085164, 4811.109944660249, 4833.800620717565, 5648.8870222595315, 5895.941040589625, 5924.792632257937, 5949.464548190684, 5964.970175149097, 1180.5100883133575, 1204.8734433304887, 1269.911106222574, 1303.81094741262, 1320.7688654485426, 775.313847245192, 848.9490630562186, 882.7042940614966, 883.7494944653372, 895.6014573514854, 4718.245080787057, 4950.702957822542, 5234.293370395024, 5282.181261904411, 5294.41761406245, 4827.831025914146, 4897.751073476241, 5259.789608415697, 5368.731563421813, 5651.689157096399, 4739.881502962385, 5049.82325618609, 5063.797448102108, 5274.202574146609, 5298.708046482362, 2974.8958786442804, 3014.7437467815043, 3046.390721855638, 3052.4810782167083, 3075.815451072943, 5634.5210657093785, 5793.870195311873, 5899.705014749331, 6043.920353761194, 6065.5450841187085, 5203.60972927032, 5319.441458646864, 5370.9731451342495, 5844.912326315109, 5870.096385903326, 5144.61415393847, 5838.9124163137585, 5990.940090599125, 6002.898985355091, 6084.574079814459, 4849.739067842316, 5277.525022747971, 5400.513953744182, 5441.428649991853, 5853.30747855991, 5694.526338933165, 5704.543636509153, 5711.457411545896, 5727.713614319351, 5750.741216645345, 4778.5699287064335, 5924.229850119457, 6004.49887528118, 6027.945109605889, 6072.240969878765, 3179.8535988208328, 3324.952639918418, 3546.3793836078066, 3732.328180927459, 3817.8164768921724, 4941.036497883022, 5002.849914502571, 5065.012322597058, 5242.76407561653, 5778.850526463004, 5212.426633070435, 5747.19539264495, 5764.452377024176, 5832.687645279778, 5838.912416313674, 4922.581580565548, 5637.605367624391, 5817.603775093862, 5835.56233282514, 5853.668285468638, 5190.9897123119035, 5550.806576586054, 5609.859753506195, 5730.656160630376, 5746.827595172152, 5391.218273350264, 5801.1878337032, 5818.418158184317, 5836.766529338779, 5839.678817664899, 5464.6228890432785, 5700.800471983461, 5744.023516002206, 5753.769849205984, 5766.740496677576], "value": 4167.575486800561, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.9227281135484, 737.1449119021936, 796.8127490039841, 802.5024484819413, 850.8213275212203, 14422.999999999987, 15327.616809579753, 15551.53345399637, 16803.579910502245, 17682.557936051606, 13419.329033548333, 14384.496542621011, 17221.913890430562, 17448.91275543624, 17601.295948162056, 924.0528458330232, 933.780561568031, 940.05994005994, 943.2359788571245, 944.6976967370445, 737.2516895351196, 767.773506815488, 899.3929097858952, 930.9022552631977, 933.7198840347869, 981.3179840011195, 993.7565296502331, 995.3828626251734, 995.412706503163, 996.0238965813508, 970.2529052629507, 976.7118699983487, 984.2962281968391, 988.940663560185, 989.2728844299434, 721.0338146883149, 736.2343163110359, 736.7568702328219, 739.2496615934831, 739.6708464733196, 16106.114163720991, 16442.588935276595, 16688.249028793613, 16862.915685421496, 17113.572160695956, 12064.793520647865, 14706.779398308927, 14865.554033379016, 15385.076895386313, 15526.0, 12115.212511186744, 12121.909028187505, 12203.93898030513, 12213.269977285334, 12214.083943704169, 3837.2325534892852, 3848.4034974578985, 4009.4386785850006, 4069.0844559974066, 4070.7354021988476, 117057.41471292674, 120381.0, 120902.0, 123695.0, 125550.37224813817, 9223.0, 11626.825597616042, 11652.242604230698, 11694.679969240322, 11779.939805417554, 20484.975751212678, 22749.88625056864, 23590.64614030791, 24011.0, 24055.158069467834, 8262.132476090044, 8341.749747507585, 8359.958200209081, 8393.286570641441, 8420.368472364597, 4513.052259025641, 4523.192163641617, 4558.88602784933, 4589.495155532889, 4591.334256532784, 3788.9431658525145, 3850.0952276215066, 3858.614138586175, 3894.805259737002, 3898.2203559288255, 699.9265077166824, 712.5855326253808, 726.57132291948, 731.974380896666, 733.0909672006674, 576.6511260687307, 589.8879212949525, 606.9969650151721, 616.1774031667774, 637.9872402552015, 9747.610095596097, 10626.46867656614, 10845.728856778485, 10902.797520187025, 10984.395858227874, 579.9681017543991, 608.8599622086945, 620.3176505843626, 625.9718312675849, 714.1786945013276, 4935.506449355035, 5256.789728410898, 5264.236685680555, 5273.288106105703, 5273.367195936512, 103707.0, 108185.0, 109336.0, 110051.0, 112895.87104129019, 90324.0, 92013.53993229991, 92484.0, 94548.0, 96668.06663866683, 68479.3152068473, 68935.31064689289, 79064.0, 80009.59995199987, 81592.0, 9970.417005482443, 10033.297669163088, 10090.596376145166, 10129.949350253202, 10152.898471015194, 49131.5086849127, 50279.0, 52587.47412525979, 54015.0, 54628.18057729058, 69001.0, 69227.65386173037, 70450.0, 73061.6346918262, 74864.0, 13482.797758033834, 13602.115862468909, 13702.0, 13759.724805504035, 13866.0, 15162.620934476285, 15290.388384464497, 15756.92121539385, 15798.526044218692, 16192.595185119995, 12122.878771212529, 14005.649858753613, 14074.859251407355, 14956.850431495981, 14977.326020328894, 11976.58081967127, 13816.848059644857, 14288.928555357157, 14422.278886055865, 14857.40570377216, 11829.940850296038, 13459.394327255493, 14178.14931104137, 14427.78358324647, 14756.557303280919, 9709.982852456516, 11285.830712539482, 11487.712807179887, 11517.424128793695, 11912.855685976261, 9799.706008819747, 11237.0, 11611.6516504505, 11725.296482211095, 11760.35318057515], "value": 7886.908296761495, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1569.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 145.0, 172.0, 170.0, 136.0, 119.0, 119.0, 117.0, 117.0, 118.0], "value": 132.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 109.0, 111.0, 150.0, 110.0, 112.0, 112.0, 116.0, 162.0, 146.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 195.0, 198.0, 197.0, 198.0, 242.0, 197.0, 213.0, 189.0, 281.0], "value": 211.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 59.0, 62.0, 91.0, 61.0, 59.0, 60.0, 58.0, 70.0, 60.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 72.0, 83.0, 74.0, 75.0, 102.0, 113.0, 99.0, 102.0, 112.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 132.0, 123.0, 101.0, 88.0, 84.0, 90.0, 85.0, 76.0, 132.0], "value": 100.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 95.0, 94.0, 99.0, 95.0, 103.0, 150.0, 110.0, 93.0, 93.0], "value": 108.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 91.0, 105.0, 92.0, 138.0, 144.0, 139.0, 92.0, 91.0, 92.0], "value": 112.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 60.0, 62.0, 62.0, 61.0, 60.0, 63.0, 61.0, 61.0], "value": 60.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 100.0, 69.0, 105.0, 60.0, 80.0, 99.0, 105.0, 102.0, 90.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 82.0, 85.0, 84.0, 80.0, 83.0, 85.0, 86.0, 88.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 174.0, 148.0, 139.0, 135.0, 132.0, 141.0, 139.0, 182.0, 137.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 200.0, 201.0, 166.0, 166.0, 170.0, 172.0, 202.0, 165.0, 164.0], "value": 175.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 65.0, 65.0, 64.0, 66.0, 65.0, 65.0, 63.0], "value": 64.6, "unit": "score"}]}]} 11:51:28 INFO - Return code: 0 11:51:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:51:28 ERROR - # TBPL SUCCESS # 11:51:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:51:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:51:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:51:28 INFO - 2017-03-21 11:51:28.277 screenresolution[1913:11370] starting screenresolution argv=screenresolution get 11:51:28 INFO - 2017-03-21 11:51:28.280 screenresolution[1913:11370] Display 0: 1600x1200x32@60 11:51:28 INFO - 2017-03-21 11:51:28.286 screenresolution[1914:11373] starting screenresolution argv=screenresolution list 11:51:28 INFO - Available Modes on Display 0 11:51:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:51:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:51:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:51:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:51:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:51:28 INFO - Intel Iris: 11:51:28 INFO - Chipset Model: Intel Iris 11:51:28 INFO - Type: GPU 11:51:28 INFO - Bus: Built-In 11:51:28 INFO - VRAM (Dynamic, Max): 1536 MB 11:51:28 INFO - Vendor: Intel (0x8086) 11:51:28 INFO - Device ID: 0x0a2e 11:51:28 INFO - Revision ID: 0x0009 11:51:28 INFO - Displays: 11:51:28 INFO - Display: 11:51:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:51:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:51:28 INFO - Display Serial Number: 1600x1200 60 11:51:28 INFO - Main Display: Yes 11:51:28 INFO - Mirror: Off 11:51:28 INFO - Online: Yes 11:51:28 INFO - Rotation: Supported 11:51:28 INFO - Return code: 0 11:51:28 INFO - Running post-action listener: _resource_record_post_action 11:51:28 INFO - [mozharness: 2017-03-21 18:51:28.522181Z] Finished run-tests step (success) 11:51:28 INFO - Running post-run listener: _resource_record_post_run 11:51:28 INFO - Total resource usage - Wall time: 1189s; CPU: 54.0%; Read bytes: 3437568; Write bytes: 426247680; Read time: 32; Write time: 1656 11:51:28 INFO - TinderboxPrint: CPU usage
54.5% 11:51:28 INFO - TinderboxPrint: I/O read bytes / time
3,437,568 / 32 11:51:28 INFO - TinderboxPrint: I/O write bytes / time
426,247,680 / 1,656 11:51:28 INFO - TinderboxPrint: CPU idle
2,166.6 (45.5%) 11:51:28 INFO - TinderboxPrint: CPU system
958.1 (20.1%) 11:51:28 INFO - TinderboxPrint: CPU user
1,633.0 (34.3%) 11:51:28 INFO - TinderboxPrint: Swap in / out
125,911,040 / 0 11:51:28 INFO - install - Wall time: 15s; CPU: 53.0%; Read bytes: 115151360; Write bytes: 112959488; Read time: 10095; Write time: 223 11:51:28 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 2573312; Write bytes: 302589440; Read time: 28; Write time: 1399 11:51:28 INFO - Running post-run listener: _upload_blobber_files 11:51:28 INFO - Blob upload gear active. 11:51:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:51:28 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 11:51:28 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'] 11:51:28 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 11:51:29 INFO - (blobuploader) - INFO - Open directory for files ... 11:51:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:51:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:51:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:30 INFO - (blobuploader) - INFO - Done attempting. 11:51:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:51:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:51:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:31 INFO - (blobuploader) - INFO - Done attempting. 11:51:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:51:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:51:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:32 INFO - (blobuploader) - INFO - Done attempting. 11:51:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:51:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:51:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:35 INFO - (blobuploader) - INFO - Done attempting. 11:51:35 INFO - (blobuploader) - INFO - Iteration through files over. 11:51:35 INFO - Return code: 0 11:51:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:51:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:51:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eff94bb1dbe1df693eae913a3e5ccacf11b1f8653244276cdd22b3272b84c15d3feeb250e7aed53a4ec3c4a3e2a61e8fc3cc568f81aea28bf0f471b2c0ff4a26", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b194daa9966c4da66b44ecceee16fb0d97e68b871a6c7178904477046233525b368b9561c3f7d6a194877da62645ea8b5021f6dad0076e95a1ec884a2c8978f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b92c1cc48a9d3fd6fa6efb6096a5ece714dd49390bf731e318dd48ed290aef2e444e5c107ccd11db2f6b0bc38b6dc5cf07e724baf799bae3c46db4f1def7c0bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/57e91b6e57ce11ccf293d337bddeee6b9f86447bd8175a650ca384673c9c749a8ab2d331f0771d60c33845eb9277ee9d02374a1564c8b2cdc13fbdd9bffdbafe"} 11:51:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:51:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:51:35 INFO - Contents: 11:51:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eff94bb1dbe1df693eae913a3e5ccacf11b1f8653244276cdd22b3272b84c15d3feeb250e7aed53a4ec3c4a3e2a61e8fc3cc568f81aea28bf0f471b2c0ff4a26", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b194daa9966c4da66b44ecceee16fb0d97e68b871a6c7178904477046233525b368b9561c3f7d6a194877da62645ea8b5021f6dad0076e95a1ec884a2c8978f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b92c1cc48a9d3fd6fa6efb6096a5ece714dd49390bf731e318dd48ed290aef2e444e5c107ccd11db2f6b0bc38b6dc5cf07e724baf799bae3c46db4f1def7c0bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/57e91b6e57ce11ccf293d337bddeee6b9f86447bd8175a650ca384673c9c749a8ab2d331f0771d60c33845eb9277ee9d02374a1564c8b2cdc13fbdd9bffdbafe"} 11:51:35 INFO - Running post-run listener: copy_logs_to_upload_dir 11:51:35 INFO - Copying logs to upload dir... 11:51:35 INFO - mkdir: /builds/slave/test/build/upload/logs 11:51:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.683259 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-03-21 11:51:35.300061) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 11:51:35.303427) ========= 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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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/eff94bb1dbe1df693eae913a3e5ccacf11b1f8653244276cdd22b3272b84c15d3feeb250e7aed53a4ec3c4a3e2a61e8fc3cc568f81aea28bf0f471b2c0ff4a26", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b194daa9966c4da66b44ecceee16fb0d97e68b871a6c7178904477046233525b368b9561c3f7d6a194877da62645ea8b5021f6dad0076e95a1ec884a2c8978f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b92c1cc48a9d3fd6fa6efb6096a5ece714dd49390bf731e318dd48ed290aef2e444e5c107ccd11db2f6b0bc38b6dc5cf07e724baf799bae3c46db4f1def7c0bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/57e91b6e57ce11ccf293d337bddeee6b9f86447bd8175a650ca384673c9c749a8ab2d331f0771d60c33845eb9277ee9d02374a1564c8b2cdc13fbdd9bffdbafe"} build_url:https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011167 build_url: 'https://queue.taskcluster.net/v1/task/GUi5Ox3lRbug93t4x7Yfvw/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eff94bb1dbe1df693eae913a3e5ccacf11b1f8653244276cdd22b3272b84c15d3feeb250e7aed53a4ec3c4a3e2a61e8fc3cc568f81aea28bf0f471b2c0ff4a26", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b194daa9966c4da66b44ecceee16fb0d97e68b871a6c7178904477046233525b368b9561c3f7d6a194877da62645ea8b5021f6dad0076e95a1ec884a2c8978f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b92c1cc48a9d3fd6fa6efb6096a5ece714dd49390bf731e318dd48ed290aef2e444e5c107ccd11db2f6b0bc38b6dc5cf07e724baf799bae3c46db4f1def7c0bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/57e91b6e57ce11ccf293d337bddeee6b9f86447bd8175a650ca384673c9c749a8ab2d331f0771d60c33845eb9277ee9d02374a1564c8b2cdc13fbdd9bffdbafe"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 11:51:35.338243) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 11:51:35.338605) ========= 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.b7Vz2fimWe/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.hWJ2WhTyBw/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.005366 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 11:51:35.388710) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 11:51:35.389119) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 11:51:35.389544) ========= ========= Total master_lag: 0.19 =========