builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0343 starttime: 1490713668.96 results: success (0) buildid: 20170328063959 builduid: f46e9c03ec394e279f13f756cb1abedd revision: f758f44a68deac39fe3bc178f1f4da0ea0118b48 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 08:07:48.959643) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 08:07:48.960091) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 08:07:48.960372) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 08:07:48.988093) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 08:07:48.988735) ========= 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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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-28 08:07:49-- 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% 20.7M=0.001s 2017-03-28 08:07:49 (20.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088052 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 08:07:49.101984) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 08:07:49.102303) ========= 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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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.026472 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 08:07:49.151724) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-28 08:07:49.152082) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f758f44a68deac39fe3bc178f1f4da0ea0118b48 --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 f758f44a68deac39fe3bc178f1f4da0ea0118b48 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dBPOKqJUMK/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.vRYJJmQS2L/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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-28 08:07:49,267 truncating revision to first 12 chars 2017-03-28 08:07:49,268 Setting DEBUG logging. 2017-03-28 08:07:49,268 attempt 1/10 2017-03-28 08:07:49,268 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f758f44a68de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 08:07:50,606 unpacking tar archive at: mozilla-beta-f758f44a68de/testing/mozharness/ program finished with exit code 0 elapsedTime=1.602764 ========= master_lag: 2.30 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-28 08:07:53.059408) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 08:07:53.059759) ========= script_repo_revision: f758f44a68deac39fe3bc178f1f4da0ea0118b48 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 08:07:53.063094) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 08:07:53.063371) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 08:07:53.584754) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-03-28 08:07:53.585133) ========= /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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 08:07:53 INFO - MultiFileLogger online at 20170328 08:07:53 in /builds/slave/test 08:07:53 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 08:07:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:07:53 INFO - {'append_to_log': False, 08:07:53 INFO - 'base_work_dir': '/builds/slave/test', 08:07:53 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:07:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:07:53 INFO - 'branch': 'Mozilla-Beta', 08:07:53 INFO - 'buildbot_json_path': 'buildprops.json', 08:07:53 INFO - 'config_files': ('talos/mac_config.py',), 08:07:53 INFO - 'default_actions': ('clobber', 08:07:53 INFO - 'read-buildbot-config', 08:07:53 INFO - 'download-and-extract', 08:07:53 INFO - 'populate-webroot', 08:07:53 INFO - 'create-virtualenv', 08:07:53 INFO - 'install', 08:07:53 INFO - 'run-tests'), 08:07:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:07:53 INFO - 'download_minidump_stackwalk': True, 08:07:53 INFO - 'download_symbols': 'ondemand', 08:07:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:07:53 INFO - 'tooltool.py': '/tools/tooltool.py', 08:07:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:07:53 INFO - '/tools/misc-python/virtualenv.py')}, 08:07:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:07:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:07:53 INFO - 'installer_path': 'installer.exe', 08:07:53 INFO - 'log_level': 'info', 08:07:53 INFO - 'log_name': 'talos', 08:07:53 INFO - 'log_to_console': True, 08:07:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:07:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:07:53 INFO - 'opt_config_files': (), 08:07:53 INFO - 'pip_index': False, 08:07:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:07:53 INFO - 'cmd': ('bash', 08:07:53 INFO - '-c', 08:07:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:07:53 INFO - 'enabled': True, 08:07:53 INFO - 'halt_on_failure': False, 08:07:53 INFO - 'name': 'check_screen_resolution'},), 08:07:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:07:53 INFO - 'cmd': ('bash', 08:07:53 INFO - '-c', 08:07:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:07:53 INFO - 'enabled': True, 08:07:53 INFO - 'halt_on_failure': False, 08:07:53 INFO - 'name': 'check_screen_resolution'},), 08:07:53 INFO - 'run_cmd_checks_enabled': True, 08:07:53 INFO - 'sps_profile': False, 08:07:53 INFO - 'sps_profile_interval': 0, 08:07:53 INFO - 'suite': 'dromaeojs-e10s', 08:07:53 INFO - 'system_bits': '32', 08:07:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:07:53 INFO - 'title': 't-yosemite-r7-0343', 08:07:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:07:53 INFO - 'use_talos_json': True, 08:07:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:07:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:07:53 INFO - 'work_dir': 'build'} 08:07:53 INFO - [mozharness: 2017-03-28 15:07:53.806782Z] Running clobber step. 08:07:53 INFO - Running pre-action listener: _resource_record_pre_action 08:07:53 INFO - Running main action method: clobber 08:07:53 INFO - rmtree: /builds/slave/test/build 08:07:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:07:56 INFO - Running post-action listener: _resource_record_post_action 08:07:56 INFO - [mozharness: 2017-03-28 15:07:56.234357Z] Finished clobber step (success) 08:07:56 INFO - [mozharness: 2017-03-28 15:07:56.234526Z] Running read-buildbot-config step. 08:07:56 INFO - Running pre-action listener: _resource_record_pre_action 08:07:56 INFO - Running main action method: read_buildbot_config 08:07:56 INFO - Using buildbot properties: 08:07:56 INFO - { 08:07:56 INFO - "project": "", 08:07:56 INFO - "product": "firefox", 08:07:56 INFO - "script_repo_revision": "production", 08:07:56 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 08:07:56 INFO - "repository": "", 08:07:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 08:07:56 INFO - "buildid": "20170328063959", 08:07:56 INFO - "pgo_build": "False", 08:07:56 INFO - "basedir": "/builds/slave/test", 08:07:56 INFO - "buildnumber": 14, 08:07:56 INFO - "slavename": "t-yosemite-r7-0343", 08:07:56 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:07:56 INFO - "platform": "yosemite_r7", 08:07:56 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 08:07:56 INFO - "branch": "mozilla-beta", 08:07:56 INFO - "repo_path": "releases/mozilla-beta", 08:07:56 INFO - "revision": "f758f44a68deac39fe3bc178f1f4da0ea0118b48", 08:07:56 INFO - "stage_platform": "macosx64", 08:07:56 INFO - "builduid": "f46e9c03ec394e279f13f756cb1abedd", 08:07:56 INFO - "slavebuilddir": "test" 08:07:56 INFO - } 08:07:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 08:07:56 INFO - Running post-action listener: _resource_record_post_action 08:07:56 INFO - [mozharness: 2017-03-28 15:07:56.238043Z] Finished read-buildbot-config step (success) 08:07:56 INFO - [mozharness: 2017-03-28 15:07:56.238174Z] Running download-and-extract step. 08:07:56 INFO - Running pre-action listener: _resource_record_pre_action 08:07:56 INFO - Running main action method: download_and_extract 08:07:56 INFO - mkdir: /builds/slave/test/build/tests 08:07:56 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')]} 08:07:56 INFO - trying https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 08:07:56 INFO - Downloading https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/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 08:07:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/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 08:07:56 INFO - Downloaded 1165 bytes. 08:07:56 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 08:07:56 INFO - Using the following test package requirements: 08:07:56 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 08:07:56 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:07:56 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 08:07:56 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:07:56 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 08:07:56 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:07:56 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:07:56 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 08:07:56 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 08:07:56 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:07:56 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 08:07:56 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:07:56 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 08:07:56 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:07:56 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 08:07:56 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:07:56 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 08:07:56 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 08:07:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 08:07:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 08:07:56 INFO - Fetch https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 08:07:57 INFO - Expected file size: 16108824 08:07:57 INFO - Obtained file size: 16108824 08:08:04 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 08:08:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 08:08:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 08:08:04 INFO - Fetch https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 08:08:05 INFO - Expected file size: 16108824 08:08:05 INFO - Obtained file size: 16108824 08:08:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 08:08:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 08:08:11 INFO - Fetch https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 08:08:12 INFO - Expected file size: 14403663 08:08:12 INFO - Obtained file size: 14403663 08:08:12 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')]} 08:08:12 INFO - trying https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 08:08:12 INFO - Downloading https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 08:08:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/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 08:08:14 INFO - Downloaded 58679108 bytes. 08:08:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 08:08:14 INFO - mkdir: /builds/slave/test/properties 08:08:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:08:14 INFO - Writing to file /builds/slave/test/properties/build_url 08:08:14 INFO - Contents: 08:08:14 INFO - build_url:https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 08:08:15 INFO - Running post-action listener: _resource_record_post_action 08:08:15 INFO - Running post-action listener: set_extra_try_arguments 08:08:15 INFO - [mozharness: 2017-03-28 15:08:15.148756Z] Finished download-and-extract step (success) 08:08:15 INFO - [mozharness: 2017-03-28 15:08:15.148880Z] Running populate-webroot step. 08:08:15 INFO - Running pre-action listener: _resource_record_pre_action 08:08:15 INFO - Running main action method: populate_webroot 08:08:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:08:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:08:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:08:15 INFO - '--tppagecycles', 08:08:15 INFO - '7'], 08:08:15 INFO - 'tests': ['tsvgm']}}, 08:08:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:08:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:08:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:08:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:08:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:08:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:08:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:08:15 INFO - 'talos_options': ['--disable-e10s'], 08:08:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:08:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:08:15 INFO - 'talos_options': ['--disable-e10s'], 08:08:15 INFO - 'tests': ['damp', 'tps']}, 08:08:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:08:15 INFO - 'tests': ['damp', 'tps']}, 08:08:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:08:15 INFO - 'tests': ['dromaeo_dom']}, 08:08:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:08:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:08:15 INFO - 'tests': ['basic_compositor_video']}, 08:08:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:08:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:08:15 INFO - 'tests': ['a11yr', 08:08:15 INFO - 'ts_paint', 08:08:15 INFO - 'tpaint', 08:08:15 INFO - 'sessionrestore', 08:08:15 INFO - 'sessionrestore_no_auto_restore', 08:08:15 INFO - 'tabpaint']}, 08:08:15 INFO - 'other-e10s': {'tests': ['a11yr', 08:08:15 INFO - 'ts_paint', 08:08:15 INFO - 'tpaint', 08:08:15 INFO - 'sessionrestore', 08:08:15 INFO - 'sessionrestore_no_auto_restore', 08:08:15 INFO - 'tabpaint']}, 08:08:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:08:15 INFO - 'ts_paint', 08:08:15 INFO - 'tpaint', 08:08:15 INFO - 'sessionrestore', 08:08:15 INFO - 'sessionrestore_no_auto_restore', 08:08:15 INFO - 'tabpaint']}, 08:08:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:08:15 INFO - 'ts_paint', 08:08:15 INFO - 'tpaint', 08:08:15 INFO - 'sessionrestore', 08:08:15 INFO - 'sessionrestore_no_auto_restore', 08:08:15 INFO - 'tabpaint']}, 08:08:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:08:15 INFO - 'tests': ['a11yr', 08:08:15 INFO - 'ts_paint', 08:08:15 INFO - 'tpaint', 08:08:15 INFO - 'sessionrestore', 08:08:15 INFO - 'sessionrestore_no_auto_restore', 08:08:15 INFO - 'tabpaint']}, 08:08:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:08:15 INFO - 'tests': ['a11yr', 08:08:15 INFO - 'ts_paint', 08:08:15 INFO - 'tpaint', 08:08:15 INFO - 'sessionrestore', 08:08:15 INFO - 'sessionrestore_no_auto_restore', 08:08:15 INFO - 'tabpaint']}, 08:08:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:08:15 INFO - 'tests': ['tsvgx', 08:08:15 INFO - 'tsvgr_opacity', 08:08:15 INFO - 'tart', 08:08:15 INFO - 'tscrollx', 08:08:15 INFO - 'cart', 08:08:15 INFO - 'tsvg_static']}, 08:08:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:08:15 INFO - 'tsvgr_opacity', 08:08:15 INFO - 'tart', 08:08:15 INFO - 'tscrollx', 08:08:15 INFO - 'cart', 08:08:15 INFO - 'tsvg_static']}, 08:08:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:08:15 INFO - 'talos_options': ['--disable-e10s'], 08:08:15 INFO - 'tests': ['tp5o']}, 08:08:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:08:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:08:15 INFO - 'talos_options': ['--disable-e10s', 08:08:15 INFO - '--xperf_path', 08:08:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:15 INFO - 'tests': ['tp5n']}, 08:08:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:08:15 INFO - 'talos_options': ['--xperf_path', 08:08:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:15 INFO - 'tests': ['tp5n']}}, 08:08:15 INFO - 'talos.zip': {'path': '', 08:08:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:08:15 INFO - Running post-action listener: _resource_record_post_action 08:08:15 INFO - [mozharness: 2017-03-28 15:08:15.167714Z] Finished populate-webroot step (success) 08:08:15 INFO - [mozharness: 2017-03-28 15:08:15.167819Z] Running create-virtualenv step. 08:08:15 INFO - Running pre-action listener: _resource_record_pre_action 08:08:15 INFO - Running main action method: create_virtualenv 08:08:15 INFO - Creating virtualenv /builds/slave/test/build/venv 08:08:15 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 08:08:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:08:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:08:15 INFO - Using real prefix '/tools/python27' 08:08:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:08:16 INFO - Installing distribute.............................................................................................................................................................................................done. 08:08:18 INFO - Installing pip.................done. 08:08:18 INFO - Return code: 0 08:08:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:08:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:08:19 INFO - Reading from file tmpfile_stdout 08:08:19 INFO - Output received: 08:08:19 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) 08:08:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:08:19 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')]} 08:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:19 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')]} 08:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:19 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 0x100e81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe511cd13a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c5fd30>, '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 0x100b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c54be8>, '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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 08:08:19 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 08:08:19 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 08:08:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBPOKqJUMK/Render', 08:08:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:19 INFO - 'HOME': '/Users/cltbld', 08:08:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:19 INFO - 'LOGNAME': 'cltbld', 08:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:19 INFO - 'MOZ_NO_REMOTE': '1', 08:08:19 INFO - 'NO_EM_RESTART': '1', 08:08:19 INFO - 'PAGER': '/bin/cat', 08:08:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:19 INFO - 'PWD': '/builds/slave/test', 08:08:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:19 INFO - 'SHELL': '/bin/bash', 08:08:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vRYJJmQS2L/Listeners', 08:08:19 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 08:08:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:19 INFO - 'USER': 'cltbld', 08:08:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:19 INFO - 'XPC_FLAGS': '0x0', 08:08:19 INFO - 'XPC_SERVICE_NAME': '0', 08:08:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:19 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 08:08:19 INFO - Cleaning up... 08:08:19 INFO - Return code: 0 08:08:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:08:19 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')]} 08:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:19 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')]} 08:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:19 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 0x100e81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe511cd13a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c5fd30>, '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 0x100b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c54be8>, '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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 08:08:19 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 08:08:19 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 08:08:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBPOKqJUMK/Render', 08:08:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:19 INFO - 'HOME': '/Users/cltbld', 08:08:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:19 INFO - 'LOGNAME': 'cltbld', 08:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:19 INFO - 'MOZ_NO_REMOTE': '1', 08:08:19 INFO - 'NO_EM_RESTART': '1', 08:08:19 INFO - 'PAGER': '/bin/cat', 08:08:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:19 INFO - 'PWD': '/builds/slave/test', 08:08:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:19 INFO - 'SHELL': '/bin/bash', 08:08:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vRYJJmQS2L/Listeners', 08:08:19 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 08:08:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:19 INFO - 'USER': 'cltbld', 08:08:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:19 INFO - 'XPC_FLAGS': '0x0', 08:08:19 INFO - 'XPC_SERVICE_NAME': '0', 08:08:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:19 INFO - Downloading/unpacking psutil>=3.1.1 08:08:19 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 08:08:19 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 08:08:19 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 08:08:19 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 08:08:19 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 08:08:19 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 08:08:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:08:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:08:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:08:23 INFO - Installing collected packages: psutil 08:08:23 INFO - Running setup.py install for psutil 08:08:23 INFO - building 'psutil._psutil_osx' extension 08:08:23 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 08:08:23 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 08:08:23 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 08:08:23 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 08:08:24 INFO - building 'psutil._psutil_posix' extension 08:08:24 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 08:08:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:08:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:08:24 INFO - ^ 08:08:24 INFO - 1 warning generated. 08:08:24 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 08:08:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:08:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:08:24 INFO - Successfully installed psutil 08:08:24 INFO - Cleaning up... 08:08:24 INFO - Return code: 0 08:08:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:08:24 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')]} 08:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:24 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')]} 08:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:24 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 0x100e81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe511cd13a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c5fd30>, '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 0x100b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c54be8>, '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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 08:08:24 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 08:08:24 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 08:08:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBPOKqJUMK/Render', 08:08:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:24 INFO - 'HOME': '/Users/cltbld', 08:08:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:24 INFO - 'LOGNAME': 'cltbld', 08:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:24 INFO - 'MOZ_NO_REMOTE': '1', 08:08:24 INFO - 'NO_EM_RESTART': '1', 08:08:24 INFO - 'PAGER': '/bin/cat', 08:08:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:24 INFO - 'PWD': '/builds/slave/test', 08:08:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:24 INFO - 'SHELL': '/bin/bash', 08:08:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vRYJJmQS2L/Listeners', 08:08:24 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 08:08:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:24 INFO - 'USER': 'cltbld', 08:08:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:24 INFO - 'XPC_FLAGS': '0x0', 08:08:24 INFO - 'XPC_SERVICE_NAME': '0', 08:08:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:08:24 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 08:08:24 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 08:08:24 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 08:08:24 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 08:08:24 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 08:08:24 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 08:08:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:08:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:08:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:08:27 INFO - Installing collected packages: mozsystemmonitor 08:08:27 INFO - Running setup.py install for mozsystemmonitor 08:08:27 INFO - Successfully installed mozsystemmonitor 08:08:27 INFO - Cleaning up... 08:08:27 INFO - Return code: 0 08:08:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:08: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')]} 08:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08: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')]} 08:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:27 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 0x100e81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe511cd13a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c5fd30>, '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 0x100b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c54be8>, '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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 08:08:27 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 08:08:27 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 08:08:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBPOKqJUMK/Render', 08:08:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:27 INFO - 'HOME': '/Users/cltbld', 08:08:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:27 INFO - 'LOGNAME': 'cltbld', 08:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:27 INFO - 'MOZ_NO_REMOTE': '1', 08:08:27 INFO - 'NO_EM_RESTART': '1', 08:08:27 INFO - 'PAGER': '/bin/cat', 08:08:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:27 INFO - 'PWD': '/builds/slave/test', 08:08:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:27 INFO - 'SHELL': '/bin/bash', 08:08:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vRYJJmQS2L/Listeners', 08:08:27 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 08:08:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:27 INFO - 'USER': 'cltbld', 08:08:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:27 INFO - 'XPC_FLAGS': '0x0', 08:08:27 INFO - 'XPC_SERVICE_NAME': '0', 08:08:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:27 INFO - Downloading/unpacking jsonschema==2.5.1 08:08:27 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 08:08:27 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 08:08:27 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 08:08:27 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 08:08:27 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 08:08:27 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 08:08:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:08:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:30 INFO - Downloading functools32-3.2.3-2.tar.gz 08:08:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:08:31 INFO - warning: no files found matching '*.txt' 08:08:31 INFO - no previously-included directories found matching 'build' 08:08:31 INFO - no previously-included directories found matching 'dist' 08:08:31 INFO - no previously-included directories found matching '.git*' 08:08:31 INFO - Installing collected packages: jsonschema, functools32 08:08:31 INFO - Running setup.py install for functools32 08:08:31 INFO - warning: no files found matching '*.txt' 08:08:31 INFO - no previously-included directories found matching 'build' 08:08:31 INFO - no previously-included directories found matching 'dist' 08:08:31 INFO - no previously-included directories found matching '.git*' 08:08:31 INFO - Successfully installed jsonschema functools32 08:08:31 INFO - Cleaning up... 08:08:31 INFO - Return code: 0 08:08:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:08:31 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')]} 08:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:31 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')]} 08:08:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:31 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 0x100e81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe511cd13a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c5fd30>, '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 0x100b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c54be8>, '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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 08:08:31 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 08:08:31 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 08:08:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBPOKqJUMK/Render', 08:08:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:31 INFO - 'HOME': '/Users/cltbld', 08:08:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:31 INFO - 'LOGNAME': 'cltbld', 08:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:31 INFO - 'MOZ_NO_REMOTE': '1', 08:08:31 INFO - 'NO_EM_RESTART': '1', 08:08:31 INFO - 'PAGER': '/bin/cat', 08:08:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:31 INFO - 'PWD': '/builds/slave/test', 08:08:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:31 INFO - 'SHELL': '/bin/bash', 08:08:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vRYJJmQS2L/Listeners', 08:08:31 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 08:08:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:31 INFO - 'USER': 'cltbld', 08:08:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:31 INFO - 'XPC_FLAGS': '0x0', 08:08:31 INFO - 'XPC_SERVICE_NAME': '0', 08:08:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:08:31 INFO - Cleaning up... 08:08:31 INFO - Return code: 0 08:08:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:08:31 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')]} 08:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:31 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')]} 08:08:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:31 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 0x100e81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe511cd13a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c5fd30>, '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 0x100b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c54be8>, '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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 08:08:31 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 08:08:31 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 08:08:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBPOKqJUMK/Render', 08:08:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:31 INFO - 'HOME': '/Users/cltbld', 08:08:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:31 INFO - 'LOGNAME': 'cltbld', 08:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:31 INFO - 'MOZ_NO_REMOTE': '1', 08:08:31 INFO - 'NO_EM_RESTART': '1', 08:08:31 INFO - 'PAGER': '/bin/cat', 08:08:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:31 INFO - 'PWD': '/builds/slave/test', 08:08:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:31 INFO - 'SHELL': '/bin/bash', 08:08:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vRYJJmQS2L/Listeners', 08:08:31 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 08:08:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:31 INFO - 'USER': 'cltbld', 08:08:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:31 INFO - 'XPC_FLAGS': '0x0', 08:08:31 INFO - 'XPC_SERVICE_NAME': '0', 08:08:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:31 INFO - Downloading/unpacking blobuploader==1.2.4 08:08:31 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 08:08:31 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 08:08:31 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 08:08:31 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 08:08:31 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 08:08:31 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 08:08:34 INFO - Downloading blobuploader-1.2.4.tar.gz 08:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:08:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:08:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:35 INFO - Downloading docopt-0.6.1.tar.gz 08:08:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:08:35 INFO - Installing collected packages: blobuploader, requests, docopt 08:08:35 INFO - Running setup.py install for blobuploader 08:08:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:08:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:08:36 INFO - Running setup.py install for requests 08:08:36 INFO - Running setup.py install for docopt 08:08:36 INFO - Successfully installed blobuploader requests docopt 08:08:36 INFO - Cleaning up... 08:08:36 INFO - Return code: 0 08:08:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:08:36 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')]} 08:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:36 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')]} 08:08:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:36 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 0x100e81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe511cd13a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c5fd30>, '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 0x100b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c54be8>, '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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 08:08:36 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 08:08:36 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 08:08:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBPOKqJUMK/Render', 08:08:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:36 INFO - 'HOME': '/Users/cltbld', 08:08:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:36 INFO - 'LOGNAME': 'cltbld', 08:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:36 INFO - 'MOZ_NO_REMOTE': '1', 08:08:36 INFO - 'NO_EM_RESTART': '1', 08:08:36 INFO - 'PAGER': '/bin/cat', 08:08:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:36 INFO - 'PWD': '/builds/slave/test', 08:08:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:36 INFO - 'SHELL': '/bin/bash', 08:08:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vRYJJmQS2L/Listeners', 08:08:36 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 08:08:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:36 INFO - 'USER': 'cltbld', 08:08:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:36 INFO - 'XPC_FLAGS': '0x0', 08:08:36 INFO - 'XPC_SERVICE_NAME': '0', 08:08:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:08:37 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-PqLdnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:08:37 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-z0n8LK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:08:37 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-s5fpy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:08:37 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-M84OTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:08:37 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-MOlrkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:08:37 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-u8Vh9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:08:37 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-0OWFPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:08:37 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-oYlgNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:08:37 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-UFT345-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:08:38 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-hczQLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:08:38 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-V2GX3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:08:38 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-uePCaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:08:38 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-OC_W2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:08:38 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-M0lJvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:38 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-9Iw5Cr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:08:38 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-a13jw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:08:38 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-KCloTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:08:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:08:38 INFO - Running setup.py install for manifestparser 08:08:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:08:38 INFO - Running setup.py install for mozcrash 08:08:39 INFO - Running setup.py install for mozdebug 08:08:39 INFO - Running setup.py install for mozdevice 08:08:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:08:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:08:39 INFO - Running setup.py install for mozfile 08:08:39 INFO - Running setup.py install for mozhttpd 08:08:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:08:39 INFO - Running setup.py install for mozinfo 08:08:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:08:39 INFO - Running setup.py install for mozInstall 08:08:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:08:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:08:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:08:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:08:40 INFO - Running setup.py install for mozleak 08:08:40 INFO - Running setup.py install for mozlog 08:08:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:08:40 INFO - Running setup.py install for moznetwork 08:08:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:08:40 INFO - Running setup.py install for mozprocess 08:08:40 INFO - Running setup.py install for mozprofile 08:08:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:08:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:08:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:08:40 INFO - Running setup.py install for mozrunner 08:08:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:08:40 INFO - Running setup.py install for mozscreenshot 08:08:41 INFO - Running setup.py install for moztest 08:08:41 INFO - Running setup.py install for mozversion 08:08:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:08:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:08:41 INFO - Cleaning up... 08:08:41 INFO - Return code: 0 08:08:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:41 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 0x100e81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe511cd13a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c5fd30>, '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 0x100b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c54be8>, '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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 08:08:41 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 08:08:41 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 08:08:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBPOKqJUMK/Render', 08:08:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:41 INFO - 'HOME': '/Users/cltbld', 08:08:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:41 INFO - 'LOGNAME': 'cltbld', 08:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:41 INFO - 'MOZ_NO_REMOTE': '1', 08:08:41 INFO - 'NO_EM_RESTART': '1', 08:08:41 INFO - 'PAGER': '/bin/cat', 08:08:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:41 INFO - 'PWD': '/builds/slave/test', 08:08:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:41 INFO - 'SHELL': '/bin/bash', 08:08:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vRYJJmQS2L/Listeners', 08:08:41 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 08:08:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:41 INFO - 'USER': 'cltbld', 08:08:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:41 INFO - 'XPC_FLAGS': '0x0', 08:08:41 INFO - 'XPC_SERVICE_NAME': '0', 08:08:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:08:41 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-gfBoQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:08:41 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)) 08:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:08:41 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-fhKkh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:08:41 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)) 08:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:08:41 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-7AYCQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:08:42 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)) 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:08:42 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-1vnlur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:08:42 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)) 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:08:42 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-5MObYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:08:42 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)) 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:08:42 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-KCszXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:08:42 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)) 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:08:42 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-Etk_Fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:08:42 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)) 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:08:42 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-2eCMEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:08:42 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)) 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:08:42 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-aJFg1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:08:42 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)) 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:08:42 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-bZfq57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:08:42 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)) 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:08:42 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-rKGtMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:08:42 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)) 08:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:08:42 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-vApVMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:08:43 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)) 08:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:08:43 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-IVOSN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:08:43 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)) 08:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:08:43 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-a8XXB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:08:43 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)) 08:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:43 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-J4D52C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:43 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)) 08:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:08:43 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-wmPce2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:08:43 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)) 08:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:08:43 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-xJD85W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:08:43 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)) 08:08:43 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)) 08:08:43 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)) 08:08:43 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)) 08:08:43 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)) 08:08:43 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)) 08:08:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:08:43 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 08:08:43 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 08:08:43 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 08:08:43 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 08:08:43 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 08:08:43 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 08:08:46 INFO - Downloading blessings-1.6.tar.gz 08:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:08:46 INFO - Installing collected packages: blessings 08:08:46 INFO - Running setup.py install for blessings 08:08:46 INFO - Successfully installed blessings 08:08:46 INFO - Cleaning up... 08:08:46 INFO - Return code: 0 08:08:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:08:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:08:47 INFO - Reading from file tmpfile_stdout 08:08:47 INFO - Current package versions: 08:08:47 INFO - blessings == 1.6 08:08:47 INFO - blobuploader == 1.2.4 08:08:47 INFO - docopt == 0.6.1 08:08:47 INFO - functools32 == 3.2.3-2 08:08:47 INFO - jsonschema == 2.5.1 08:08:47 INFO - manifestparser == 1.1 08:08:47 INFO - mozInstall == 1.12 08:08:47 INFO - mozcrash == 1.0 08:08:47 INFO - mozdebug == 0.1 08:08:47 INFO - mozdevice == 0.49 08:08:47 INFO - mozfile == 1.2 08:08:47 INFO - mozhttpd == 0.7 08:08:47 INFO - mozinfo == 0.9 08:08:47 INFO - mozleak == 0.1 08:08:47 INFO - mozlog == 3.4 08:08:47 INFO - moznetwork == 0.27 08:08:47 INFO - mozprocess == 0.24 08:08:47 INFO - mozprofile == 0.28 08:08:47 INFO - mozrunner == 6.13 08:08:47 INFO - mozscreenshot == 0.1 08:08:47 INFO - mozsystemmonitor == 0.3 08:08:47 INFO - moztest == 0.8 08:08:47 INFO - mozversion == 1.4 08:08:47 INFO - psutil == 3.1.1 08:08:47 INFO - requests == 1.2.3 08:08:47 INFO - wsgiref == 0.1.2 08:08:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:47 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 0x100e81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe511cd13a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c5fd30>, '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 0x100b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c54be8>, '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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 08:08:47 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 08:08:47 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 08:08:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBPOKqJUMK/Render', 08:08:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:47 INFO - 'HOME': '/Users/cltbld', 08:08:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:47 INFO - 'LOGNAME': 'cltbld', 08:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:47 INFO - 'MOZ_NO_REMOTE': '1', 08:08:47 INFO - 'NO_EM_RESTART': '1', 08:08:47 INFO - 'PAGER': '/bin/cat', 08:08:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:47 INFO - 'PWD': '/builds/slave/test', 08:08:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:47 INFO - 'SHELL': '/bin/bash', 08:08:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vRYJJmQS2L/Listeners', 08:08:47 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 08:08:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:47 INFO - 'USER': 'cltbld', 08:08:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:47 INFO - 'XPC_FLAGS': '0x0', 08:08:47 INFO - 'XPC_SERVICE_NAME': '0', 08:08:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:47 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)) 08:08:47 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)) 08:08:47 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)) 08:08:47 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)) 08:08:47 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)) 08:08:47 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)) 08:08:47 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)) 08:08:47 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)) 08:08:47 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)) 08:08:47 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)) 08:08:47 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)) 08:08:47 INFO - Cleaning up... 08:08:47 INFO - Return code: 0 08:08:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100e81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe511cd13a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c5fd30>, '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 0x100b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c54be8>, '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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 08:08:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:08:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBPOKqJUMK/Render', 08:08:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:47 INFO - 'HOME': '/Users/cltbld', 08:08:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:47 INFO - 'LOGNAME': 'cltbld', 08:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:47 INFO - 'MOZ_NO_REMOTE': '1', 08:08:47 INFO - 'NO_EM_RESTART': '1', 08:08:47 INFO - 'PAGER': '/bin/cat', 08:08:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:47 INFO - 'PWD': '/builds/slave/test', 08:08:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:47 INFO - 'SHELL': '/bin/bash', 08:08:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vRYJJmQS2L/Listeners', 08:08:47 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 08:08:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:47 INFO - 'USER': 'cltbld', 08:08:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:47 INFO - 'XPC_FLAGS': '0x0', 08:08:47 INFO - 'XPC_SERVICE_NAME': '0', 08:08:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:08:48 INFO - Cleaning up... 08:08:48 INFO - Return code: 0 08:08:48 INFO - Running post-action listener: _resource_record_post_action 08:08:48 INFO - Running post-action listener: _start_resource_monitoring 08:08:48 INFO - Starting resource monitoring. 08:08:48 INFO - [mozharness: 2017-03-28 15:08:48.110895Z] Finished create-virtualenv step (success) 08:08:48 INFO - [mozharness: 2017-03-28 15:08:48.111833Z] Running install step. 08:08:48 INFO - Running pre-action listener: _resource_record_pre_action 08:08:48 INFO - Running main action method: install 08:08:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:08:48 INFO - Reading from file tmpfile_stdout 08:08:48 INFO - Detecting whether we're running mozinstall >=1.0... 08:08:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:08:48 INFO - Reading from file tmpfile_stdout 08:08:48 INFO - Output received: 08:08:48 INFO - Usage: mozinstall [options] installer 08:08:48 INFO - Options: 08:08:48 INFO - -h, --help show this help message and exit 08:08:48 INFO - -d DEST, --destination=DEST 08:08:48 INFO - Directory to install application into. [default: 08:08:48 INFO - "/builds/slave/test"] 08:08:48 INFO - --app=APP Application being installed. [default: firefox] 08:08:48 INFO - mkdir: /builds/slave/test/build/application 08:08:48 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'] 08:08:48 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 08:09:03 INFO - Reading from file tmpfile_stdout 08:09:03 INFO - Output received: 08:09:03 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:09:03 INFO - Running post-action listener: _resource_record_post_action 08:09:03 INFO - [mozharness: 2017-03-28 15:09:03.378637Z] Finished install step (success) 08:09:03 INFO - [mozharness: 2017-03-28 15:09:03.378802Z] Running run-tests step. 08:09:03 INFO - Running pre-action listener: _resource_record_pre_action 08:09:03 INFO - Running main action method: run_tests 08:09:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:09:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:09:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:09:03 INFO - 2017-03-28 08:09:03.393 screenresolution[1858:7944] starting screenresolution argv=screenresolution get 08:09:03 INFO - 2017-03-28 08:09:03.396 screenresolution[1858:7944] Display 0: 1600x1200x32@60 08:09:03 INFO - 2017-03-28 08:09:03.401 screenresolution[1859:7946] starting screenresolution argv=screenresolution list 08:09:03 INFO - Available Modes on Display 0 08:09:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:09:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:09:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:09:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:09:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:09:03 INFO - Intel Iris: 08:09:03 INFO - Chipset Model: Intel Iris 08:09:03 INFO - Type: GPU 08:09:03 INFO - Bus: Built-In 08:09:03 INFO - VRAM (Dynamic, Max): 1536 MB 08:09:03 INFO - Vendor: Intel (0x8086) 08:09:03 INFO - Device ID: 0x0a2e 08:09:03 INFO - Revision ID: 0x0009 08:09:03 INFO - Displays: 08:09:03 INFO - Display: 08:09:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:09:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:09:03 INFO - Display Serial Number: 1600x1200 60 08:09:03 INFO - Main Display: Yes 08:09:03 INFO - Mirror: Off 08:09:03 INFO - Online: Yes 08:09:03 INFO - Rotation: Supported 08:09:03 INFO - Return code: 0 08:09:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:09:03 INFO - Python 2.7.3 08:09:03 INFO - Return code: 0 08:09:03 INFO - grabbing minidump binary from tooltool 08:09: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')]} 08:09:03 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 0x100c5fd30>, '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 0x100b86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c54be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:09:03 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 08:09:03 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 08:09:03 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 08:09:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:09:03 INFO - Return code: 0 08:09:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:09:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:09:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:09:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:09:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:09:03 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/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0343', '--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 08:09:03 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/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0343 --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 08:09:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBPOKqJUMK/Render', 08:09:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:03 INFO - 'HOME': '/Users/cltbld', 08:09:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:03 INFO - 'LOGNAME': 'cltbld', 08:09:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:09:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:03 INFO - 'MOZ_NO_REMOTE': '1', 08:09:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:09:03 INFO - 'NO_EM_RESTART': '1', 08:09:03 INFO - 'PAGER': '/bin/cat', 08:09:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:03 INFO - 'PWD': '/builds/slave/test', 08:09:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:09:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:03 INFO - 'SHELL': '/bin/bash', 08:09:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vRYJJmQS2L/Listeners', 08:09:03 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 08:09:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:03 INFO - 'USER': 'cltbld', 08:09:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:03 INFO - 'XPC_FLAGS': '0x0', 08:09:03 INFO - 'XPC_SERVICE_NAME': '0', 08:09:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:03 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/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0343', '--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 08:09:03 INFO - mozversion application_buildid: 20170328063959 08:09:03 INFO - mozversion application_changeset: f758f44a68deac39fe3bc178f1f4da0ea0118b48 08:09:03 INFO - mozversion application_display_name: Firefox 08:09:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:09:03 INFO - mozversion application_name: Firefox 08:09:03 INFO - mozversion application_remotingname: firefox 08:09:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:09:03 INFO - mozversion application_vendor: Mozilla 08:09:03 INFO - mozversion application_version: 53.0 08:09:03 INFO - mozversion platform_buildid: 20170328063959 08:09:03 INFO - mozversion platform_changeset: f758f44a68deac39fe3bc178f1f4da0ea0118b48 08:09:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:09:03 INFO - mozversion platform_version: 53.0 08:09:03 INFO - using testdate: 1490713743 08:09:03 INFO - actual date: 1490713743 08:09:03 INFO - starting webserver on 'localhost:49254' 08:09:03 INFO - SUITE-START | Running 2 tests 08:09:03 INFO - TEST-START | dromaeo_css 08:09:03 INFO - operating with platform_type : mac_ 08:09:03 INFO - Initialising browser for dromaeo_css test... 08:09:04 INFO - TEST-INFO | started process 1869 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/tmpJdpfoO/profile http://localhost:49254/getInfo.html) 08:09:07 INFO - PROCESS | 1869 | 2017-03-28 08:09:07.087 plugin-container[1872:8114] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 08:09:07 INFO - PROCESS | 1869 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:09:07 INFO - PROCESS | 1869 | 2017-03-28 08:09:07.088 plugin-container[1872:8114] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 08:09:07 INFO - PROCESS | 1869 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:09:07 INFO - PROCESS | 1869 | 2017-03-28 08:09:07.088 plugin-container[1872:8114] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:09:07 INFO - PROCESS | 1869 | __metrics Screen width/height:1600/1200 08:09:07 INFO - PROCESS | 1869 | colorDepth:24 08:09:07 INFO - PROCESS | 1869 | Browser inner width/height: 1024/689 08:09:07 INFO - PROCESS | 1869 | __metrics 08:09:07 ERROR - PROCESS | 1869 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 08:09:07 INFO - TEST-INFO | 1869: exit 0 08:09:07 INFO - Browser initialized. 08:09:07 INFO - Running cycle 1/1 for dromaeo_css test... 08:09:07 INFO - TEST-INFO | started process 1873 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/tmpJdpfoO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:09:10 INFO - PROCESS | 1873 | 2017-03-28 08:09:10.179 plugin-container[1874:8253] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 08:09:10 INFO - PROCESS | 1873 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:09:10 INFO - PROCESS | 1873 | 2017-03-28 08:09:10.179 plugin-container[1874:8253] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.CFPasteboardClient' 08:09:10 INFO - PROCESS | 1873 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:09:10 INFO - PROCESS | 1873 | 2017-03-28 08:09:10.179 plugin-container[1874:8253] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:09:19 INFO - PROCESS | 1873 | RSS: Main: 172908544 08:09:19 INFO - PROCESS | 1873 | 08:12:21 INFO - PROCESS | 1873 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49254/tests/dromaeo/cssquery-ext.html) 08:12:23 INFO - PROCESS | 1873 | RSS: Main: 188821504 08:12:23 INFO - PROCESS | 1873 | 08:15:25 INFO - PROCESS | 1873 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-ext.html (next: http://localhost:49254/tests/dromaeo/cssquery-jquery.html) 08:15:26 INFO - PROCESS | 1873 | RSS: Main: 202276864 08:15:26 INFO - PROCESS | 1873 | 08:18:28 INFO - PROCESS | 1873 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49254/tests/dromaeo/cssquery-mootools.html) 08:18:28 INFO - PROCESS | 1873 | RSS: Main: 203984896 08:18:28 INFO - PROCESS | 1873 | 08:21:30 INFO - PROCESS | 1873 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49254/tests/dromaeo/cssquery-prototype.html) 08:21:31 INFO - PROCESS | 1873 | RSS: Main: 207167488 08:21:31 INFO - PROCESS | 1873 | 08:24:34 INFO - PROCESS | 1873 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49254/tests/dromaeo/cssquery-yui.html) 08:24:34 INFO - PROCESS | 1873 | RSS: Main: 208740352 08:24:34 INFO - PROCESS | 1873 | 08:27:37 INFO - PROCESS | 1873 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-yui.html (next: http://localhost:49254/tests/dromaeo/cssquery-dojo.html) 08:27:37 INFO - PROCESS | 1873 | RSS: Main: 208920576 08:27:37 INFO - PROCESS | 1873 | 08:27:37 INFO - PROCESS | 1873 | __start_tp_report 08:27:37 INFO - PROCESS | 1873 | _x_x_mozilla_page_load 08:27:37 INFO - PROCESS | 1873 | _x_x_mozilla_page_load_details 08:27:37 INFO - PROCESS | 1873 | |i|pagename|runs| 08:27:37 INFO - PROCESS | 1873 | |0;dojo.html;3057.5413687946807;3466.445368741002;3524.3479956208103;3526.8412921418567;3527.770694904831;4620.445546534412;5751.878383715177;5786.33457152427;5794.130880367941;5901.5573831962665;5057.924131138036;5837.299524057119;5837.795677151301;5911.674857882815;5924.822255332336;4196.475440569929;4818.734969576672;5149.124648809692;5196.896062078757;5352.705601191933;4432.778361081949;5332.653377530448;5366.356037275513;5382.650127741704;5404.945950540484;5230.895382092356;5342.919856202141;5367.141257398817;5379.677219366831;5406.567474602022;4549.31760235964;5135.666181698215;5201.349831271091;5307.628466007381;5391.7034563099405;5151.8196863109615;5271.472852714736;5295.629305948585;5306.946930530684;5329.946700532983;5473.726313684339;5651.011073062197;5848.824535263949;5877.353491116017;5974.163617093611;6150.7232174552255;6202.534809889276;6209.975354066573;6220.129181914535;6243.157173781527;6272.8118156455375;6276.686165691697;6289.748410063592;6291.307956124822;6309.4636955858805;5998.970005149947;6013.789517366871;6038.667873266969;6053.878922421527;6057.909131363033;6191.690415479298;6199.597026193283;6251;6282.748690052438;6393.488520918316;6219.626822390671;6253.749850005949;6259.593126446766;6271.843203919939;6289.119523266746;6237.750489980441;6254.34329395416;6274.215723034621;6275.748970041147;6280.152179455807;6184.134221209034;6193.225846769154;6208.161898143693;6216.658083805434;6235.469985051231;6201.875962480725;6218.9378106218355;6252.406021428048;6254.468370188494;6272.2159730033745;5038.96701176258;5039.79840806371;5048.318477005557;5050.848474545696;5070.163423035232;4389.297712366006;4418.513963464016;4516.322551617283;4544.818207271738;4556;5226.320578324793;5943.851403714855;5958.374370691102;5980.5514586405325;5997.910031349533;6237.438630523184;6288.213973253343;6313.368663133741;6328.873422531524;6334.049892516159;5766.279215098113;5839.678817664984;5891.086881533369;5946.07835785454;5994.460498555151;5563.527100196528;5579.107342825128;5579.6652200868075;5605.131204663284;5610.943890561042;6421.74313027483;6427.871442571216;6442.130312407765;6456.838579035374;6472.741090356333;6113.884129230887;6150.753969841336;6236.189295391751;6269.247690277196;6285.277193122849;5308.283381743415;6298.779542715983;6372.713227904848;6374.171357723466;6403.3276505966205;6224.9986300618775;6265.8746825062335;6275.435210831047;6292.8426789328805;6312.431881130537;5311.176767601028;6145.016797312409;6266.342034086355;6274.090256912813;6291.653959032297;6145.586515101462;6158.876822463436;6222.408871157233;6277.71750271248;6287.2140982377205;6221.9377806221355;6231.937680623136;6234.189555357774;6239.376062393724;6280.246370435577;5391.514763671288;5655.095184770417;5943.346231914573;6053.878922421615;6144.109104179958;6083.178770866053;6112.716259964451;6126.365000174519;6137.5302083125;6274.686265686789;6146.661933593696;6153.815385538441;6157.876842463215;6161;6161.383861613982 08:27:37 INFO - PROCESS | 1873 | |1;ext.html;484.80365451991935;510.7293134638641;517.9278892692129;545.5935328180503;571.4200086911784;41594.58405415945;43848.78075609622;43944.340834887465;45384.546154538446;45573.31640025394;45021.64935051954;45059.324110138376;45107.87230319244;45370.31944520835;45549;9600.471974041442;9802.558884850163;9810.117089461946;9846.55690493926;11505.079593632488;11545.365004924726;11654.359010254433;11714.472848721785;11753.471093800757;11775.941120294387;11634.592789252378;11688.941555292211;11697.239679420853;11705;11745.706357341092;11598.768024639503;11635.24370915892;11679.883201167964;11709.882901170964;11719.589814356457;11471;11537.942310288396;11605.535778568932;11636.534538618445;11654.475548600334;38563.42154867679;38942.80528597339;38949;39079.804600976815;39093.41359879604;3533.3463309287863;4052.4326594276827;4056.290149223874;4081.6938729595104;4144.7513149211145;27797.583036254473;28021.85989070062;28382.432351352858;30059.098227053004;30209.697903020908;3262.1029216965285;3494.968984149682;3532.3818331791836;3534.0634731796094;3534.1871369584883;515075;517008;517025;518268;518744.40627796995;58228.126578101364;58693;58833.70583147142;59026;59065.40934590599;51878.48121518812;61707.074393884126;63361.36638633647;63536;63653.40866478282;16830;16946.98318100918;16995.065271409945;17014.31942722302;17095.14524273806;12072.396380180955;12332.568360107352;13156.802647960289;13248;13497.325133743274;18146.818531814777;18343.17455714496;18417.907910460362;18516;18533.629327413375;7878.921210787933;7882.605869706492;7960.800979975547;8014.639341229658;8018.759437216893;852.5268475995758;861.3238607692931;933.5658918602883;938.2915898496727;945.4421891084294;30118.24704382364;30191.849040754656;30943.535846962313;31003.224919377197;31191;912.7946212102224;1071.4160782373624;1073.2111897755144;1095.9671209863716;1134.3477500437282;13896.305184740924;14433.783493247609;14706.926465367818;15792.336365587154;16030.75953860693;665190;669031;673674;674642;676159;40862;53866.46133538693;55080;55175;55182.72408637931;1440.5390275111863;1462.7659574468246;1463.5682473670292;1497.6630258192038;1500.7373709600777;25361.49277014486;27595;28328;29032;29687;48158;48683.75658121687;48798.512014879394;48850.75574622104;48891;532123;532538.337308311;533557;537154;638326;22693;25432.618510721983;25685.743142568335;25756.8712156438;26162;34213.80251691179;40694.64832184662;42230.94422639459;42978.35532466953;45324.32013519866;29389.853050734608;29837.55243671303;30107;30881.382372352877;31171.220719482193;25752.742472575035;29506;30682.693173067983;30751.38497229998;31537.36925261436;35723;36307.818460907525;36355.091122722144;36605.63394366129;36627.816860915525;30841.8457907709;31105.8444707775;31226;31271.84364078165;31570;21352.466188345417;23588.97486933363;24483.020679172634;24693.7653117347;25187.622185667595 08:27:37 INFO - PROCESS | 1873 | |2;jquery.html;367.68746565419394;400.23754747206544;400.5793916387792;403.3163787380391;433.50796845580277;15173.241337933114;17008.234629441675;20224.797752022474;21712.99999999998;21772;17198.39805606804;21620;21708.782912170875;21892.45268868279;21936;5753.819027648407;5902.645841249518;6025.186599809013;6086.421789929949;6090.908636370458;5814.156947242647;5870.88258234835;5985.311689155737;5993.730282137314;6002.279726432834;5968.4329988651225;5994.760209591611;6020.367861374559;6027.457528822405;6035.939640603604;5791.986402379588;5866.853328666774;5886.293644762613;5994.070919007539;6008.218931538894;5237;5252.6323157379;5254.65844720092;5265.973670131663;5284.128118860383;25120.49759004828;25313.36716582082;25386.857591408432;25730.842112105;26092.34769130787;2728.8499132547704;2742.506348857205;2761.62718033065;2793.7764978801647;2805.4809860175933;15885.92057039719;15980.520584382488;16037.278322475517;16224.675506489923;16494.92782969103;1871.8315351618282;1903.4194570655945;1903.847692184622;1921.2027008791335;1928.257620815982;131101;132011;133811;135196.32401838026;135921;11570.42147892602;11763.470643821049;11791.764164716658;11816.409179540988;11816.822747658793;14492.985491015528;14589.124652520883;14788.704225915422;14800.925995369955;14829.555113346616;8809.735707928772;8993.370464067584;9121.45271283712;9157.221636161;9186.586603602855;8898.11018898105;9006.59470323837;9012.107457434013;9158.67944621935;9193.49435781025;6159.753609855556;7234.891476627855;7291.635418229067;7330.413566914741;7398.297161769731;1990.8506861985409;2027.4221846773598;2029.3506078054882;2035.8676686015658;2043.2746375036945;414.376363572827;421.4373810962338;442.65694087082767;869.517417833096;951.1154626197564;11788.823167652321;11889.762204755856;12135.575254866037;12265.325407102695;12602.369881505889;410.417207565258;472.80378642862826;473.76785375165923;479.18777671846584;482.89859129582476;6063.605865618809;6172;6253.8117757625905;6297;6347.365263473615;805307;811475;811818;831347;844827;112061;116440.835591643;163832;168124;170947;453.7164272329794;893.3746377535751;1007.2294694558668;1089.4988305379457;1096.8574085368691;19739;19793.901030494755;20238.39284821458;20353.557573091322;20453.977301135183;44549;50496;64453.066408007835;67257;67560.3243967554;77590;93071;96321;97070;97156;8421.284190843848;8433.536155511505;8754.956225218833;8858.73423797287;8949.865752013595;10835.404052777172;11615.6515304541;13308;13384.263865487492;13598.660033499242;11252.212345135762;11625.825612615987;11748.647540573797;11821.82267266009;11894.702632433913;10574.524146413276;10646.520906559379;10778.784424311312;10805.135589153006;11138.721531961766;11611.535538578362;11675.124365672778;11702;11818.881811181778;11978.341191234565;8545.529995850287;8558.52928088961;8765.991910930325;8779.0781967895;8806.955965220133;10126.974605079162;10257.58969641206;11564.479598417924;11600;11699.181057325906 08:27:37 INFO - PROCESS | 1873 | |3;mootools.html;1401.5444980381376;1452.6077958951078;1468.6622076922308;1480.8741256993162;1484.2727063738764;7840.4511684182135;8066.5966701664975;8332.70835520757;8725.520096394695;8749.343799215052;8284.627191776384;8546.273566746833;8556.657733690645;8672.696455624055;8677.95661021694;2375.3111597636666;2397.5444665513583;2401.6997875265592;2401.807855371575;2417.8791060446906;2359.9646005309933;2366.3965688749345;2393.198278576679;2399.6280576510667;2407;2366.7278262999803;2371.9762802372015;2378;2405.122130422401;2410.963835542468;2065.2048961149917;2332.183735692494;2360.78752912237;2365.6451532270125;2392.844465109771;2382.654515095319;2388.0328466970905;2419.9637005444933;2420.0803694595943;2428.3929017745563;7833.725819596344;8208.891799607096;8634.00708918476;8679.218870301704;8686.391952563323;5212.348456442945;5292.867678308035;5358.223057656657;5361.329833770739;5361.544268737162;3358.7313014958745;3418.1283772637817;3623.0942264433893;3647.8905632831056;3656.7988760618155;1219.548766956232;1222.407132540717;1265.171312790124;1306.2423794199342;1323.444153455551;11025.062869656089;11036.337819730761;11070.557177712883;11098.611548595842;11114.05530529906;4645.071160394638;4732.574068333797;4803.735794531334;4863.975680121577;4875.585575226075;9776.657816976372;9779;9790.412575245507;9791.608335666493;9812.705618831447;4699.389079419653;5303.231031500411;5356.1697936819855;5411.161270003156;5421.078416669176;4483.663725220622;4548.317752337176;4936.136176169156;4946.08497427977;4981.850544483671;3980.542237642708;4060.959390406117;4100.528439229527;4131.462909821704;4134.503859536815;1299.9025073119553;1303.7131830997166;1310.344827586207;1320.3398300849576;1321.8215540902047;703.7642389799474;723.3670538279006;762.3291503476905;785.6307535458326;822.5229366967145;5701.4013528579735;5732.656040637492;5765.365809760923;5950.345461999176;5964.552658550626;483.97096174228955;541.0126519102654;555.1811078658945;562.6174201542908;563.7913971830449;3473.7394695397948;3576.7317451191266;3801.752886062397;3819.4461803038403;3984.521857377133;9066.093390661013;9870.950645246729;9873.25950553697;10067.194624430029;10214.08073273409;8544.2310192083;10128.34165779222;10251.179905607534;10290.897091029143;10291.176705863512;7861.2531809478005;7892.644830982619;8068.273855353046;8072.152423995513;8077.959610202029;5574.414686457863;5635.633683810539;5676.659400435987;5759.308882934075;5801.100829371286;9355.578998944928;9379.906200937903;9415.05849415053;9444;9498.525073746147;9168.724938251864;9491.525423728648;9511.57197926081;9528.380655257663;9567.712968610953;6854.10896583441;7033.542819716702;7165.964170179116;7298.2701729826595;7791.454598178299;7921.722739704082;8083.232092951161;8176.591170441573;8179.141190174946;8181.140980197231;6998.440124789903;7083.008378826866;7126.608036558039;7138.8215294615975;7145.714171433292;6884.759033433806;6949.131358580177;6958.721651133898;6963.338482844324;7080.4335653148655;5945.40545945402;6410.5833120848865;6465.870682586416;6557.803265902031;6565.540412171294;4965.329680493087;5110.233464980134;5130.769115389891;5176.9741151294;5180.222966555047;4945.381827271591;5073.44192138857;5142;5170.741462926914;5241.344831896013 08:27:37 INFO - PROCESS | 1873 | |4;prototype.html;364.80300637655665;366.4960679066284;374.12641482139196;378.1944458303816;378.2397381263661;4544.818207271714;5242.151010315452;5557.9166312505295;5613.69124698142;5624.10014397696;4554.8840534069195;4861.562459378655;5187.106706052292;5245.426372088377;5497.587680923927;4283.443152390193;4332.631726303268;4347.369631403445;4352.586504282088;4385.412606562128;4191.748495090289;4247.702660813744;4331.978340108295;4379.671524635649;4401.757903315316;4264.616184543391;4272.209641216385;4291.184674911772;4295.957040429603;4313.137372525492;3458.5849698036145;3470.7570470067108;4294.076773493698;4308.3968244445805;4387.135467426363;3252.4796032634663;3396.7112795412418;3848.2495913296916;3863.4011728182177;3867.3757022050804;5092.3125378073855;5235.502627250406;5272.973635131838;5328.520433161034;5362.51737343641;4646.465656449484;4742.083618574587;4775.665703400763;4987.775550100254;5011.924821127686;5580.359372346935;5854.385289544579;5864.191363082275;5904.380040095771;5909.09999400398;3737.4206997915367;4190.790460476995;4236.449261596003;4257.808398622069;4279.379489973936;5684.30520161898;5795.377888819487;6057.515398768132;6176.84557886052;6206.25524937006;4341.697490752825;4344.45694288214;4354.869353919387;4362.367456718758;4396.560343965578;3842.692584593226;3847.9230415392094;4083.612056854594;4316.805743741539;4381.5618438155925;4427.454213827063;4988.8752781180765;4992;5017;5021.6735912165595;5084.644074914794;5193.870153246199;5324.1099409708995;5894.32215295238;5931.228940237742;1226.5955480974235;1308.5485507500089;1357.7237396846933;1415.0943396226423;1421.5713208225668;955.5699935029264;975.8633791269228;1023.3603997501561;1027.5992362978445;1030.6289735695143;4929.901401971941;5029.723365214948;5209.817656382009;5291.365036195682;5308.442613525602;5761.711914404263;5769.88460230793;5777.826665200051;5783.826485205366;5841.532677385799;4296.119295544406;4784.779881130359;5000.049990501793;5052.38071595252;5117.692938423706;2821.915342539727;2932;2991.8952836650615;3008.909732708022;3009;5921.3190483094995;5939.524838012949;5955.404459554096;5985.491233245223;5994.730237139339;5020.397552293675;5139.8458046258675;5382.596305277041;5630;5886.35182148999;5159.9999999999245;5680.261565996394;5948.762049518057;5962.672053037038;6019;5092.759185958166;5457.454254574511;5730.656160630376;5780.190773291826;5824.097264923944;5537.584681148849;5609.186667933045;5615.803446879339;5617.522510586646;5637.746301416528;5552.583556233218;5749.540036796962;5949;5972.611780234271;5982.581219314607;3727.403615421628;3812.7331086823656;3978.940315895322;4023.879283621496;4155.771432571238;4958.553730164303;5672.369683402543;6028.608140470856;6030.733545955399;6044.304904935989;5613.887722245614;5823.82528524145;5854.9121763174435;5858.414158584107;5866.266716660418;4911;5097.337346144977;5735.655860648374;5819.82540523785;5869.853253668693;5670.886582268249;5702.258706368146;5703.771849125988;5723.3958548861;5770.567207459373;5544.97227513876;5837.591368604157;5839.941600584109;5849.766009359578;5852.473277405054;5011.095783602208;5258.763355648928;5690.520464679131;5697.829065128053;5730.799422020209 08:27:37 INFO - PROCESS | 1873 | |5;yui.html;718.5150023733978;786.8268980824216;793.3296364571938;864.2351518905768;866.3415803988967;15695.686086278281;16300.429484968028;16390.67218655626;16527.669446611075;16530;14838.777418338734;14981.026233294828;14999.775003374958;15020.774688379655;15062.096274223526;684.1550684904151;712.9287071292855;831.4927893984694;868.4702331577732;870.8519551676198;876.5529579914257;877.1666916429385;877.4998250996935;878.989162463166;880.2781718990431;933.2114363362947;935.2611436964789;936.929730270229;937.0114529171722;939.178219058324;915.8305713443001;927.420362273579;931.3108299858123;932.7201839448206;935.7847695030147;678.3352314731591;690.6615758278458;695.5409429776323;695.8817001109824;700.1877821726772;15291;15344.156071416068;15634.843651563566;15928;16298.185090745415;14715.55853324402;14908.478203262943;15134.318955647022;15185.620359490991;15251.61870953224;11655.358955257458;11744.236624619372;11751.764964700658;11842.513072254837;12006.819897701542;3799.9620003799882;3846.4614953906475;3900.3369427197445;3928.155446578999;4064.6138616831354;103996.48001760019;106616;107494.46252768765;108659;109561;11507.76984460306;11592.826107608393;11611.477483513263;12093.97201237887;12131.636050918487;23748;24341.756582434304;24366.878165609058;24851.875740621545;24964.0014399422;7686.884696729554;7771.067471903408;7804.453688241769;7842.843143137219;8051.758447246592;4369.890752731207;4445.244308467568;4462.732236065782;4560.429946256718;4606.055758569485;3581.534400527915;3757.830897609614;3799.2401519696173;3841.500604921398;3845.7115716321387;523.9921401178985;560.7336515155268;566.742132329789;567.8835838653066;708.8794904866185;562.8846086552247;567.6764244380663;593.2110293309887;593.257627068365;616.0512810272131;9344.925333586589;10022;10498.737531561772;11115.666530004113;11137.164712646583;675.3145557259386;688.7004153193277;716.5235118646146;719.4172720096739;737.8819388897858;4290.2921018031675;5116.923246151311;5189.028169295719;5210.76551555181;5221.6344855859725;105387.47306263419;108098;109059.45470272757;110011;110502;90423;93708.12583748288;94842;95674;99698.50452243222;61069;82082.58958705288;82926;83420;84697.57651211704;9915.55380007887;10243.897561024294;10257.025582569644;10286.382817031003;10306.536205870903;52457;54350.184747228035;55227.72386138044;56026.43973560212;56196;73261.63369183333;73995.63002184955;74190.51618967699;76341;76441.85337219835;10616.89383106159;11509.654710358702;11551.942240288745;11958.58144964922;12162.939185304016;13211.603651890458;13224.537141200397;13493.662658433617;13533.729325413633;13652.726945460836;11808.291502509877;12226.877731222574;12368.567100151451;12701.618951431472;12709.80935285953;12309;12430.689232769253;12460.750784984431;12580.496780129059;12734.426950787052;10693.411862347646;12081.395930203278;12553.246805191719;12610.454992241566;12745.681357966125;8920.055267497577;9270.631866488566;9357.906420935704;9400.15398614128;9442.716718498456;10178.491075446345;10262.230332725225;10265.486725663837;10279.691609251735;10301.278910476194 08:27:37 INFO - PROCESS | 1873 | __end_tp_report 08:27:37 INFO - PROCESS | 1873 | __start_cc_report 08:27:37 INFO - PROCESS | 1873 | _x_x_mozilla_cycle_collect,378 08:27:37 INFO - PROCESS | 1873 | __end_cc_report 08:27:37 INFO - PROCESS | 1873 | __startTimestamp1490714857726__endTimestamp 08:27:37 INFO - PROCESS | 1873 | 08:27:37 INFO - PROCESS | 1873 | ------- Summary: start ------- 08:27:37 INFO - PROCESS | 1873 | Number of tests: 6 08:27:37 INFO - PROCESS | 1873 | 08:27:37 INFO - PROCESS | 1873 | [#0] dojo.html Cycles:165 Average:5786.73 Median:6112.72 stddev:671.43 (11.0%) stddev-sans-first:638.44 08:27:37 INFO - PROCESS | 1873 | Values: 3057.5 3466.4 3524.3 3526.8 3527.8 4620.4 5751.9 5786.3 5794.1 5901.6 5057.9 5837.3 5837.8 5911.7 5924.8 4196.5 4818.7 5149.1 5196.9 5352.7 4432.8 5332.7 5366.4 5382.7 5404.9 5230.9 5342.9 5367.1 5379.7 5406.6 4549.3 5135.7 5201.3 5307.6 5391.7 5151.8 5271.5 5295.6 5306.9 5329.9 5473.7 5651.0 5848.8 5877.4 5974.2 6150.7 6202.5 6210.0 6220.1 6243.2 6272.8 6276.7 6289.7 6291.3 6309.5 5999.0 6013.8 6038.7 6053.9 6057.9 6191.7 6199.6 6251.0 6282.7 6393.5 6219.6 6253.7 6259.6 6271.8 6289.1 6237.8 6254.3 6274.2 6275.7 6280.2 6184.1 6193.2 6208.2 6216.7 6235.5 6201.9 6218.9 6252.4 6254.5 6272.2 5039.0 5039.8 5048.3 5050.8 5070.2 4389.3 4418.5 4516.3 4544.8 4556.0 5226.3 5943.9 5958.4 5980.6 5997.9 6237.4 6288.2 6313.4 6328.9 6334.0 5766.3 5839.7 5891.1 5946.1 5994.5 5563.5 5579.1 5579.7 5605.1 5610.9 6421.7 6427.9 6442.1 6456.8 6472.7 6113.9 6150.8 6236.2 6269.2 6285.3 5308.3 6298.8 6372.7 6374.2 6403.3 6225.0 6265.9 6275.4 6292.8 6312.4 5311.2 6145.0 6266.3 6274.1 6291.7 6145.6 6158.9 6222.4 6277.7 6287.2 6221.9 6231.9 6234.2 6239.4 6280.2 5391.5 5655.1 5943.3 6053.9 6144.1 6083.2 6112.7 6126.4 6137.5 6274.7 6146.7 6153.8 6157.9 6161.0 6161.4 08:27:37 INFO - PROCESS | 1873 | 08:27:37 INFO - PROCESS | 1873 | [#1] ext.html Cycles:180 Average:70462.84 Median:18737.95 stddev:156661.13 (836.1%) stddev-sans-first:157012.50 08:27:37 INFO - PROCESS | 1873 | Values: 484.8 510.7 517.9 545.6 571.4 41594.6 43848.8 43944.3 45384.5 45573.3 45021.6 45059.3 45107.9 45370.3 45549.0 9600.5 9802.6 9810.1 9846.6 11505.1 11545.4 11654.4 11714.5 11753.5 11775.9 11634.6 11688.9 11697.2 11705.0 11745.7 11598.8 11635.2 11679.9 11709.9 11719.6 11471.0 11537.9 11605.5 11636.5 11654.5 38563.4 38942.8 38949.0 39079.8 39093.4 3533.3 4052.4 4056.3 4081.7 4144.8 27797.6 28021.9 28382.4 30059.1 30209.7 3262.1 3495.0 3532.4 3534.1 3534.2 515075.0 517008.0 517025.0 518268.0 518744.4 58228.1 58693.0 58833.7 59026.0 59065.4 51878.5 61707.1 63361.4 63536.0 63653.4 16830.0 16947.0 16995.1 17014.3 17095.1 12072.4 12332.6 13156.8 13248.0 13497.3 18146.8 18343.2 18417.9 18516.0 18533.6 7878.9 7882.6 7960.8 8014.6 8018.8 852.5 861.3 933.6 938.3 945.4 30118.2 30191.8 30943.5 31003.2 31191.0 912.8 1071.4 1073.2 1096.0 1134.3 13896.3 14433.8 14706.9 15792.3 16030.8 665190.0 669031.0 673674.0 674642.0 676159.0 40862.0 53866.5 55080.0 55175.0 55182.7 1440.5 1462.8 1463.6 1497.7 1500.7 25361.5 27595.0 28328.0 29032.0 29687.0 48158.0 48683.8 48798.5 48850.8 48891.0 532123.0 532538.3 533557.0 537154.0 638326.0 22693.0 25432.6 25685.7 25756.9 26162.0 34213.8 40694.6 42230.9 42978.4 45324.3 29389.9 29837.6 30107.0 30881.4 31171.2 25752.7 29506.0 30682.7 30751.4 31537.4 35723.0 36307.8 36355.1 36605.6 36627.8 30841.8 31105.8 31226.0 31271.8 31570.0 21352.5 23589.0 24483.0 24693.8 25187.6 08:27:37 INFO - PROCESS | 1873 | 08:27:37 INFO - PROCESS | 1873 | [#2] jquery.html Cycles:180 Average:42698.15 Median:2777.70 stddev:136241.61 (4904.8%) stddev-sans-first:136586.72 08:27:37 INFO - PROCESS | 1873 | Values: 367.7 400.2 400.6 403.3 433.5 15173.2 17008.2 20224.8 21713.0 21772.0 17198.4 21620.0 21708.8 21892.5 21936.0 5753.8 5902.6 6025.2 6086.4 6090.9 5814.2 5870.9 5985.3 5993.7 6002.3 5968.4 5994.8 6020.4 6027.5 6035.9 5792.0 5866.9 5886.3 5994.1 6008.2 5237.0 5252.6 5254.7 5266.0 5284.1 25120.5 25313.4 25386.9 25730.8 26092.3 2728.8 2742.5 2761.6 2793.8 2805.5 15885.9 15980.5 16037.3 16224.7 16494.9 1871.8 1903.4 1903.8 1921.2 1928.3 131101.0 132011.0 133811.0 135196.3 135921.0 11570.4 11763.5 11791.8 11816.4 11816.8 14493.0 14589.1 14788.7 14800.9 14829.6 8809.7 8993.4 9121.5 9157.2 9186.6 8898.1 9006.6 9012.1 9158.7 9193.5 6159.8 7234.9 7291.6 7330.4 7398.3 1990.9 2027.4 2029.4 2035.9 2043.3 414.4 421.4 442.7 869.5 951.1 11788.8 11889.8 12135.6 12265.3 12602.4 410.4 472.8 473.8 479.2 482.9 6063.6 6172.0 6253.8 6297.0 6347.4 805307.0 811475.0 811818.0 831347.0 844827.0 112061.0 116440.8 163832.0 168124.0 170947.0 453.7 893.4 1007.2 1089.5 1096.9 19739.0 19793.9 20238.4 20353.6 20454.0 44549.0 50496.0 64453.1 67257.0 67560.3 77590.0 93071.0 96321.0 97070.0 97156.0 8421.3 8433.5 8755.0 8858.7 8949.9 10835.4 11615.7 13308.0 13384.3 13598.7 11252.2 11625.8 11748.6 11821.8 11894.7 10574.5 10646.5 10778.8 10805.1 11138.7 11611.5 11675.1 11702.0 11818.9 11978.3 8545.5 8558.5 8766.0 8779.1 8807.0 10127.0 10257.6 11564.5 11600.0 11699.2 08:27:37 INFO - PROCESS | 1873 | 08:27:37 INFO - PROCESS | 1873 | [#3] mootools.html Cycles:180 Average:5526.86 Median:5329.70 stddev:3014.28 (56.6%) stddev-sans-first:3006.79 08:27:37 INFO - PROCESS | 1873 | Values: 1401.5 1452.6 1468.7 1480.9 1484.3 7840.5 8066.6 8332.7 8725.5 8749.3 8284.6 8546.3 8556.7 8672.7 8678.0 2375.3 2397.5 2401.7 2401.8 2417.9 2360.0 2366.4 2393.2 2399.6 2407.0 2366.7 2372.0 2378.0 2405.1 2411.0 2065.2 2332.2 2360.8 2365.6 2392.8 2382.7 2388.0 2420.0 2420.1 2428.4 7833.7 8208.9 8634.0 8679.2 8686.4 5212.3 5292.9 5358.2 5361.3 5361.5 3358.7 3418.1 3623.1 3647.9 3656.8 1219.5 1222.4 1265.2 1306.2 1323.4 11025.1 11036.3 11070.6 11098.6 11114.1 4645.1 4732.6 4803.7 4864.0 4875.6 9776.7 9779.0 9790.4 9791.6 9812.7 4699.4 5303.2 5356.2 5411.2 5421.1 4483.7 4548.3 4936.1 4946.1 4981.9 3980.5 4061.0 4100.5 4131.5 4134.5 1299.9 1303.7 1310.3 1320.3 1321.8 703.8 723.4 762.3 785.6 822.5 5701.4 5732.7 5765.4 5950.3 5964.6 484.0 541.0 555.2 562.6 563.8 3473.7 3576.7 3801.8 3819.4 3984.5 9066.1 9871.0 9873.3 10067.2 10214.1 8544.2 10128.3 10251.2 10290.9 10291.2 7861.3 7892.6 8068.3 8072.2 8078.0 5574.4 5635.6 5676.7 5759.3 5801.1 9355.6 9379.9 9415.1 9444.0 9498.5 9168.7 9491.5 9511.6 9528.4 9567.7 6854.1 7033.5 7166.0 7298.3 7791.5 7921.7 8083.2 8176.6 8179.1 8181.1 6998.4 7083.0 7126.6 7138.8 7145.7 6884.8 6949.1 6958.7 6963.3 7080.4 5945.4 6410.6 6465.9 6557.8 6565.5 4965.3 5110.2 5130.8 5177.0 5180.2 4945.4 5073.4 5142.0 5170.7 5241.3 08:27:37 INFO - PROCESS | 1873 | 08:27:37 INFO - PROCESS | 1873 | [#4] prototype.html Cycles:180 Average:4705.19 Median:5128.77 stddev:1394.31 (27.2%) stddev-sans-first:1359.63 08:27:37 INFO - PROCESS | 1873 | Values: 364.8 366.5 374.1 378.2 378.2 4544.8 5242.2 5557.9 5613.7 5624.1 4554.9 4861.6 5187.1 5245.4 5497.6 4283.4 4332.6 4347.4 4352.6 4385.4 4191.7 4247.7 4332.0 4379.7 4401.8 4264.6 4272.2 4291.2 4296.0 4313.1 3458.6 3470.8 4294.1 4308.4 4387.1 3252.5 3396.7 3848.2 3863.4 3867.4 5092.3 5235.5 5273.0 5328.5 5362.5 4646.5 4742.1 4775.7 4987.8 5011.9 5580.4 5854.4 5864.2 5904.4 5909.1 3737.4 4190.8 4236.4 4257.8 4279.4 5684.3 5795.4 6057.5 6176.8 6206.3 4341.7 4344.5 4354.9 4362.4 4396.6 3842.7 3847.9 4083.6 4316.8 4381.6 4427.5 4988.9 4992.0 5017.0 5021.7 5084.6 5193.9 5324.1 5894.3 5931.2 1226.6 1308.5 1357.7 1415.1 1421.6 955.6 975.9 1023.4 1027.6 1030.6 4929.9 5029.7 5209.8 5291.4 5308.4 5761.7 5769.9 5777.8 5783.8 5841.5 4296.1 4784.8 5000.0 5052.4 5117.7 2821.9 2932.0 2991.9 3008.9 3009.0 5921.3 5939.5 5955.4 5985.5 5994.7 5020.4 5139.8 5382.6 5630.0 5886.4 5160.0 5680.3 5948.8 5962.7 6019.0 5092.8 5457.5 5730.7 5780.2 5824.1 5537.6 5609.2 5615.8 5617.5 5637.7 5552.6 5749.5 5949.0 5972.6 5982.6 3727.4 3812.7 3978.9 4023.9 4155.8 4958.6 5672.4 6028.6 6030.7 6044.3 5613.9 5823.8 5854.9 5858.4 5866.3 4911.0 5097.3 5735.7 5819.8 5869.9 5670.9 5702.3 5703.8 5723.4 5770.6 5545.0 5837.6 5839.9 5849.8 5852.5 5011.1 5258.8 5690.5 5697.8 5730.8 08:27:37 INFO - PROCESS | 1873 | 08:27:37 INFO - PROCESS | 1873 | [#5] yui.html Cycles:180 Average:21205.38 Median:3914.25 stddev:30906.46 (789.6%) stddev-sans-first:30954.88 08:27:37 INFO - PROCESS | 1873 | Values: 718.5 786.8 793.3 864.2 866.3 15695.7 16300.4 16390.7 16527.7 16530.0 14838.8 14981.0 14999.8 15020.8 15062.1 684.2 712.9 831.5 868.5 870.9 876.6 877.2 877.5 879.0 880.3 933.2 935.3 936.9 937.0 939.2 915.8 927.4 931.3 932.7 935.8 678.3 690.7 695.5 695.9 700.2 15291.0 15344.2 15634.8 15928.0 16298.2 14715.6 14908.5 15134.3 15185.6 15251.6 11655.4 11744.2 11751.8 11842.5 12006.8 3800.0 3846.5 3900.3 3928.2 4064.6 103996.5 106616.0 107494.5 108659.0 109561.0 11507.8 11592.8 11611.5 12094.0 12131.6 23748.0 24341.8 24366.9 24851.9 24964.0 7686.9 7771.1 7804.5 7842.8 8051.8 4369.9 4445.2 4462.7 4560.4 4606.1 3581.5 3757.8 3799.2 3841.5 3845.7 524.0 560.7 566.7 567.9 708.9 562.9 567.7 593.2 593.3 616.1 9344.9 10022.0 10498.7 11115.7 11137.2 675.3 688.7 716.5 719.4 737.9 4290.3 5116.9 5189.0 5210.8 5221.6 105387.5 108098.0 109059.5 110011.0 110502.0 90423.0 93708.1 94842.0 95674.0 99698.5 61069.0 82082.6 82926.0 83420.0 84697.6 9915.6 10243.9 10257.0 10286.4 10306.5 52457.0 54350.2 55227.7 56026.4 56196.0 73261.6 73995.6 74190.5 76341.0 76441.9 10616.9 11509.7 11551.9 11958.6 12162.9 13211.6 13224.5 13493.7 13533.7 13652.7 11808.3 12226.9 12368.6 12701.6 12709.8 12309.0 12430.7 12460.8 12580.5 12734.4 10693.4 12081.4 12553.2 12610.5 12745.7 8920.1 9270.6 9357.9 9400.2 9442.7 10178.5 10262.2 10265.5 10279.7 10301.3 08:27:37 INFO - PROCESS | 1873 | -------- Summary: end -------- 08:27:37 INFO - PROCESS | 1873 | 08:27:38 INFO - TEST-INFO | 1873: exit 0 08:27:38 INFO - TEST-OK | dromaeo_css | took 1114419ms 08:27:38 INFO - TEST-START | kraken 08:27:38 INFO - operating with platform_type : mac_ 08:27:38 INFO - Initialising browser for kraken test... 08:27:38 INFO - TEST-INFO | started process 1883 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/tmpCxNvWz/profile http://localhost:49254/getInfo.html) 08:27:40 INFO - PROCESS | 1883 | 2017-03-28 08:27:40.921 plugin-container[1885:10432] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 08:27:40 INFO - PROCESS | 1883 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:27:40 INFO - PROCESS | 1883 | 2017-03-28 08:27:40.922 plugin-container[1885:10432] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4523, name = 'com.apple.CFPasteboardClient' 08:27:40 INFO - PROCESS | 1883 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:27:40 INFO - PROCESS | 1883 | 2017-03-28 08:27:40.922 plugin-container[1885:10432] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:27:40 INFO - PROCESS | 1883 | __metrics Screen width/height:1600/1200 08:27:40 INFO - PROCESS | 1883 | colorDepth:24 08:27:40 INFO - PROCESS | 1883 | Browser inner width/height: 1024/689 08:27:40 INFO - PROCESS | 1883 | __metrics 08:27:41 ERROR - PROCESS | 1883 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 08:27:41 INFO - TEST-INFO | 1883: exit 0 08:27:41 INFO - Browser initialized. 08:27:41 INFO - Running cycle 1/1 for kraken test... 08:27:41 INFO - TEST-INFO | started process 1886 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/tmpCxNvWz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:27:43 INFO - PROCESS | 1886 | 2017-03-28 08:27:43.921 plugin-container[1887:10560] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 08:27:43 INFO - PROCESS | 1886 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:27:43 INFO - PROCESS | 1886 | 2017-03-28 08:27:43.922 plugin-container[1887:10560] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4613, name = 'com.apple.CFPasteboardClient' 08:27:43 INFO - PROCESS | 1886 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:27:43 INFO - PROCESS | 1886 | 2017-03-28 08:27:43.922 plugin-container[1887:10560] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:27:53 INFO - PROCESS | 1886 | RSS: Main: 173252608 08:27:53 INFO - PROCESS | 1886 | 08:27:56 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-beat-detection) 08:27:57 INFO - PROCESS | 1886 | RSS: Main: 186130432 08:27:57 INFO - PROCESS | 1886 | 08:28:00 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-dft) 08:28:00 INFO - PROCESS | 1886 | RSS: Main: 193851392 08:28:00 INFO - PROCESS | 1886 | 08:28:04 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-fft) 08:28:04 INFO - PROCESS | 1886 | RSS: Main: 197492736 08:28:04 INFO - PROCESS | 1886 | 08:28:06 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-oscillator) 08:28:07 INFO - PROCESS | 1886 | RSS: Main: 198979584 08:28:07 INFO - PROCESS | 1886 | 08:28:09 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49254/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:28:09 INFO - PROCESS | 1886 | RSS: Main: 199544832 08:28:09 INFO - PROCESS | 1886 | 08:28:12 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49254/tests/kraken/driver.html?testName=imaging-darkroom) 08:28:13 INFO - PROCESS | 1886 | RSS: Main: 203816960 08:28:13 INFO - PROCESS | 1886 | 08:28:16 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49254/tests/kraken/driver.html?testName=imaging-desaturate) 08:28:17 INFO - PROCESS | 1886 | RSS: Main: 202944512 08:28:17 INFO - PROCESS | 1886 | 08:28:20 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49254/tests/kraken/driver.html?testName=json-parse-financial) 08:28:20 INFO - PROCESS | 1886 | RSS: Main: 203333632 08:28:20 INFO - PROCESS | 1886 | 08:28:22 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49254/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:28:23 INFO - PROCESS | 1886 | RSS: Main: 201719808 08:28:23 INFO - PROCESS | 1886 | 08:28:25 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:28:25 INFO - PROCESS | 1886 | RSS: Main: 201666560 08:28:25 INFO - PROCESS | 1886 | 08:28:27 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:28:28 INFO - PROCESS | 1886 | RSS: Main: 201826304 08:28:28 INFO - PROCESS | 1886 | 08:28:31 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:28:32 INFO - PROCESS | 1886 | RSS: Main: 202887168 08:28:32 INFO - PROCESS | 1886 | 08:28:34 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:28:35 INFO - PROCESS | 1886 | RSS: Main: 202338304 08:28:35 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49254/tests/kraken/driver.html?testName=ai-astar) 08:28:37 INFO - PROCESS | 1886 | RSS: Main: 204009472 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | __start_tp_report 08:28:37 INFO - PROCESS | 1886 | _x_x_mozilla_page_load 08:28:37 INFO - PROCESS | 1886 | _x_x_mozilla_page_load_details 08:28:37 INFO - PROCESS | 1886 | |i|pagename|runs| 08:28:37 INFO - PROCESS | 1886 | |0;ai-astar;106;117;118;120;117;118;118;115;164;175 08:28:37 INFO - PROCESS | 1886 | |1;audio-beat-detection;140;111;111;170;111;113;111;126;164;118 08:28:37 INFO - PROCESS | 1886 | |2;audio-dft;206;197;197;195;193;198;200;194;199;190 08:28:37 INFO - PROCESS | 1886 | |3;audio-fft;84;59;59;87;58;59;63;70;98;60 08:28:37 INFO - PROCESS | 1886 | |4;audio-oscillator;88;74;113;101;100;87;79;70;69;84 08:28:37 INFO - PROCESS | 1886 | |5;imaging-gaussian-blur;92;120;90;86;90;91;104;88;89;87 08:28:37 INFO - PROCESS | 1886 | |6;imaging-darkroom;113;169;120;168;154;100;102;126;100;99 08:28:37 INFO - PROCESS | 1886 | |7;imaging-desaturate;93;100;76;146;148;93;91;92;93;91 08:28:37 INFO - PROCESS | 1886 | |8;json-parse-financial;58;57;57;60;79;86;88;81;59;59 08:28:37 INFO - PROCESS | 1886 | |9;json-stringify-tinderbox;66;74;78;70;75;75;67;67;73;65 08:28:37 INFO - PROCESS | 1886 | |10;stanford-crypto-aes;83;84;80;81;81;84;84;87;89;81 08:28:37 INFO - PROCESS | 1886 | |11;stanford-crypto-ccm;136;138;138;137;146;150;152;154;170;145 08:28:37 INFO - PROCESS | 1886 | |12;stanford-crypto-pbkdf2;213;165;165;168;161;164;161;164;160;168 08:28:37 INFO - PROCESS | 1886 | |13;stanford-crypto-sha256-iterative;65;66;66;83;82;80;84;70;65;65 08:28:37 INFO - PROCESS | 1886 | __end_tp_report 08:28:37 INFO - PROCESS | 1886 | __start_cc_report 08:28:37 INFO - PROCESS | 1886 | _x_x_mozilla_cycle_collect,947 08:28:37 INFO - PROCESS | 1886 | __end_cc_report 08:28:37 INFO - PROCESS | 1886 | __startTimestamp1490714917431__endTimestamp 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | ------- Summary: start ------- 08:28:37 INFO - PROCESS | 1886 | Number of tests: 14 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#0] ai-astar Cycles:10 Average:126.80 Median:118.00 stddev:22.97 (19.5%) stddev-sans-first:23.10 08:28:37 INFO - PROCESS | 1886 | Values: 106.0 117.0 118.0 120.0 117.0 118.0 118.0 115.0 164.0 175.0 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:122.00 stddev:22.82 (18.7%) stddev-sans-first:23.75 08:28:37 INFO - PROCESS | 1886 | Values: 140.0 111.0 111.0 170.0 111.0 113.0 111.0 126.0 164.0 118.0 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#2] audio-dft Cycles:10 Average:196.90 Median:197.50 stddev:4.38 (2.2%) stddev-sans-first:3.18 08:28:37 INFO - PROCESS | 1886 | Values: 206.0 197.0 197.0 195.0 193.0 198.0 200.0 194.0 199.0 190.0 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#3] audio-fft Cycles:10 Average:69.70 Median:66.50 stddev:14.62 (22.0%) stddev-sans-first:14.56 08:28:37 INFO - PROCESS | 1886 | Values: 84.0 59.0 59.0 87.0 58.0 59.0 63.0 70.0 98.0 60.0 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#4] audio-oscillator Cycles:10 Average:86.50 Median:76.50 stddev:14.51 (19.0%) stddev-sans-first:15.38 08:28:37 INFO - PROCESS | 1886 | Values: 88.0 74.0 113.0 101.0 100.0 87.0 79.0 70.0 69.0 84.0 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#5] imaging-gaussian-blur Cycles:10 Average:93.70 Median:89.50 stddev:10.51 (11.7%) stddev-sans-first:11.13 08:28:37 INFO - PROCESS | 1886 | Values: 92.0 120.0 90.0 86.0 90.0 91.0 104.0 88.0 89.0 87.0 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#6] imaging-darkroom Cycles:10 Average:125.10 Median:140.00 stddev:28.38 (20.3%) stddev-sans-first:29.77 08:28:37 INFO - PROCESS | 1886 | Values: 113.0 169.0 120.0 168.0 154.0 100.0 102.0 126.0 100.0 99.0 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#7] imaging-desaturate Cycles:10 Average:102.30 Median:91.50 stddev:24.30 (26.6%) stddev-sans-first:25.54 08:28:37 INFO - PROCESS | 1886 | Values: 93.0 100.0 76.0 146.0 148.0 93.0 91.0 92.0 93.0 91.0 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#8] json-parse-financial Cycles:10 Average:68.40 Median:69.50 stddev:13.25 (19.1%) stddev-sans-first:13.51 08:28:37 INFO - PROCESS | 1886 | Values: 58.0 57.0 57.0 60.0 79.0 86.0 88.0 81.0 59.0 59.0 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:73.50 stddev:4.57 (6.2%) stddev-sans-first:4.48 08:28:37 INFO - PROCESS | 1886 | Values: 66.0 74.0 78.0 70.0 75.0 75.0 67.0 67.0 73.0 65.0 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:84.00 stddev:2.88 (3.4%) stddev-sans-first:3.05 08:28:37 INFO - PROCESS | 1886 | Values: 83.0 84.0 80.0 81.0 81.0 84.0 84.0 87.0 89.0 81.0 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#11] stanford-crypto-ccm Cycles:10 Average:146.60 Median:148.00 stddev:10.53 (7.1%) stddev-sans-first:10.45 08:28:37 INFO - PROCESS | 1886 | Values: 136.0 138.0 138.0 137.0 146.0 150.0 152.0 154.0 170.0 145.0 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:165.00 stddev:15.74 (9.5%) stddev-sans-first:2.92 08:28:37 INFO - PROCESS | 1886 | Values: 213.0 165.0 165.0 168.0 161.0 164.0 161.0 164.0 160.0 168.0 08:28:37 INFO - PROCESS | 1886 | 08:28:37 INFO - PROCESS | 1886 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:75.00 stddev:8.49 (11.3%) stddev-sans-first:8.55 08:28:37 INFO - PROCESS | 1886 | Values: 65.0 66.0 66.0 83.0 82.0 80.0 84.0 70.0 65.0 65.0 08:28:37 INFO - PROCESS | 1886 | -------- Summary: end -------- 08:28:37 INFO - PROCESS | 1886 | 08:28:38 INFO - TEST-INFO | 1886: exit 0 08:28:38 INFO - TEST-OK | kraken | took 59814ms 08:28:38 INFO - SUITE-END | took 1174s 08:28:38 INFO - Completed test suite (00:19:34) 08:28:38 INFO - javascript benchmark 08:28:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7361.720951329526, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3057.5413687946807, 3466.445368741002, 3524.3479956208103, 3526.8412921418567, 3527.770694904831, 4620.445546534412, 5751.878383715177, 5786.33457152427, 5794.130880367941, 5901.5573831962665, 5057.924131138036, 5837.299524057119, 5837.795677151301, 5911.674857882815, 5924.822255332336, 4196.475440569929, 4818.734969576672, 5149.124648809692, 5196.896062078757, 5352.705601191933, 4432.778361081949, 5332.653377530448, 5366.356037275513, 5382.650127741704, 5404.945950540484, 5230.895382092356, 5342.919856202141, 5367.141257398817, 5379.677219366831, 5406.567474602022, 4549.31760235964, 5135.666181698215, 5201.349831271091, 5307.628466007381, 5391.7034563099405, 5151.8196863109615, 5271.472852714736, 5295.629305948585, 5306.946930530684, 5329.946700532983, 5473.726313684339, 5651.011073062197, 5848.824535263949, 5877.353491116017, 5974.163617093611, 6150.7232174552255, 6202.534809889276, 6209.975354066573, 6220.129181914535, 6243.157173781527, 6272.8118156455375, 6276.686165691697, 6289.748410063592, 6291.307956124822, 6309.4636955858805, 5998.970005149947, 6013.789517366871, 6038.667873266969, 6053.878922421527, 6057.909131363033, 6191.690415479298, 6199.597026193283, 6251.0, 6282.748690052438, 6393.488520918316, 6219.626822390671, 6253.749850005949, 6259.593126446766, 6271.843203919939, 6289.119523266746, 6237.750489980441, 6254.34329395416, 6274.215723034621, 6275.748970041147, 6280.152179455807, 6184.134221209034, 6193.225846769154, 6208.161898143693, 6216.658083805434, 6235.469985051231, 6201.875962480725, 6218.9378106218355, 6252.406021428048, 6254.468370188494, 6272.2159730033745, 5038.96701176258, 5039.79840806371, 5048.318477005557, 5050.848474545696, 5070.163423035232, 4389.297712366006, 4418.513963464016, 4516.322551617283, 4544.818207271738, 4556.0, 5226.320578324793, 5943.851403714855, 5958.374370691102, 5980.5514586405325, 5997.910031349533, 6237.438630523184, 6288.213973253343, 6313.368663133741, 6328.873422531524, 6334.049892516159, 5766.279215098113, 5839.678817664984, 5891.086881533369, 5946.07835785454, 5994.460498555151, 5563.527100196528, 5579.107342825128, 5579.6652200868075, 5605.131204663284, 5610.943890561042, 6421.74313027483, 6427.871442571216, 6442.130312407765, 6456.838579035374, 6472.741090356333, 6113.884129230887, 6150.753969841336, 6236.189295391751, 6269.247690277196, 6285.277193122849, 5308.283381743415, 6298.779542715983, 6372.713227904848, 6374.171357723466, 6403.3276505966205, 6224.9986300618775, 6265.8746825062335, 6275.435210831047, 6292.8426789328805, 6312.431881130537, 5311.176767601028, 6145.016797312409, 6266.342034086355, 6274.090256912813, 6291.653959032297, 6145.586515101462, 6158.876822463436, 6222.408871157233, 6277.71750271248, 6287.2140982377205, 6221.9377806221355, 6231.937680623136, 6234.189555357774, 6239.376062393724, 6280.246370435577, 5391.514763671288, 5655.095184770417, 5943.346231914573, 6053.878922421615, 6144.109104179958, 6083.178770866053, 6112.716259964451, 6126.365000174519, 6137.5302083125, 6274.686265686789, 6146.661933593696, 6153.815385538441, 6157.876842463215, 6161.0, 6161.383861613982], "value": 5744.909347384342, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.80365451991935, 510.7293134638641, 517.9278892692129, 545.5935328180503, 571.4200086911784, 41594.58405415945, 43848.78075609622, 43944.340834887465, 45384.546154538446, 45573.31640025394, 45021.64935051954, 45059.324110138376, 45107.87230319244, 45370.31944520835, 45549.0, 9600.471974041442, 9802.558884850163, 9810.117089461946, 9846.55690493926, 11505.079593632488, 11545.365004924726, 11654.359010254433, 11714.472848721785, 11753.471093800757, 11775.941120294387, 11634.592789252378, 11688.941555292211, 11697.239679420853, 11705.0, 11745.706357341092, 11598.768024639503, 11635.24370915892, 11679.883201167964, 11709.882901170964, 11719.589814356457, 11471.0, 11537.942310288396, 11605.535778568932, 11636.534538618445, 11654.475548600334, 38563.42154867679, 38942.80528597339, 38949.0, 39079.804600976815, 39093.41359879604, 3533.3463309287863, 4052.4326594276827, 4056.290149223874, 4081.6938729595104, 4144.7513149211145, 27797.583036254473, 28021.85989070062, 28382.432351352858, 30059.098227053004, 30209.697903020908, 3262.1029216965285, 3494.968984149682, 3532.3818331791836, 3534.0634731796094, 3534.1871369584883, 515075.0, 517008.0, 517025.0, 518268.0, 518744.40627796995, 58228.126578101364, 58693.0, 58833.70583147142, 59026.0, 59065.40934590599, 51878.48121518812, 61707.074393884126, 63361.36638633647, 63536.0, 63653.40866478282, 16830.0, 16946.98318100918, 16995.065271409945, 17014.31942722302, 17095.14524273806, 12072.396380180955, 12332.568360107352, 13156.802647960289, 13248.0, 13497.325133743274, 18146.818531814777, 18343.17455714496, 18417.907910460362, 18516.0, 18533.629327413375, 7878.921210787933, 7882.605869706492, 7960.800979975547, 8014.639341229658, 8018.759437216893, 852.5268475995758, 861.3238607692931, 933.5658918602883, 938.2915898496727, 945.4421891084294, 30118.24704382364, 30191.849040754656, 30943.535846962313, 31003.224919377197, 31191.0, 912.7946212102224, 1071.4160782373624, 1073.2111897755144, 1095.9671209863716, 1134.3477500437282, 13896.305184740924, 14433.783493247609, 14706.926465367818, 15792.336365587154, 16030.75953860693, 665190.0, 669031.0, 673674.0, 674642.0, 676159.0, 40862.0, 53866.46133538693, 55080.0, 55175.0, 55182.72408637931, 1440.5390275111863, 1462.7659574468246, 1463.5682473670292, 1497.6630258192038, 1500.7373709600777, 25361.49277014486, 27595.0, 28328.0, 29032.0, 29687.0, 48158.0, 48683.75658121687, 48798.512014879394, 48850.75574622104, 48891.0, 532123.0, 532538.337308311, 533557.0, 537154.0, 638326.0, 22693.0, 25432.618510721983, 25685.743142568335, 25756.8712156438, 26162.0, 34213.80251691179, 40694.64832184662, 42230.94422639459, 42978.35532466953, 45324.32013519866, 29389.853050734608, 29837.55243671303, 30107.0, 30881.382372352877, 31171.220719482193, 25752.742472575035, 29506.0, 30682.693173067983, 30751.38497229998, 31537.36925261436, 35723.0, 36307.818460907525, 36355.091122722144, 36605.63394366129, 36627.816860915525, 30841.8457907709, 31105.8444707775, 31226.0, 31271.84364078165, 31570.0, 21352.466188345417, 23588.97486933363, 24483.020679172634, 24693.7653117347, 25187.622185667595], "value": 20066.887421864485, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.68746565419394, 400.23754747206544, 400.5793916387792, 403.3163787380391, 433.50796845580277, 15173.241337933114, 17008.234629441675, 20224.797752022474, 21712.99999999998, 21772.0, 17198.39805606804, 21620.0, 21708.782912170875, 21892.45268868279, 21936.0, 5753.819027648407, 5902.645841249518, 6025.186599809013, 6086.421789929949, 6090.908636370458, 5814.156947242647, 5870.88258234835, 5985.311689155737, 5993.730282137314, 6002.279726432834, 5968.4329988651225, 5994.760209591611, 6020.367861374559, 6027.457528822405, 6035.939640603604, 5791.986402379588, 5866.853328666774, 5886.293644762613, 5994.070919007539, 6008.218931538894, 5237.0, 5252.6323157379, 5254.65844720092, 5265.973670131663, 5284.128118860383, 25120.49759004828, 25313.36716582082, 25386.857591408432, 25730.842112105, 26092.34769130787, 2728.8499132547704, 2742.506348857205, 2761.62718033065, 2793.7764978801647, 2805.4809860175933, 15885.92057039719, 15980.520584382488, 16037.278322475517, 16224.675506489923, 16494.92782969103, 1871.8315351618282, 1903.4194570655945, 1903.847692184622, 1921.2027008791335, 1928.257620815982, 131101.0, 132011.0, 133811.0, 135196.32401838026, 135921.0, 11570.42147892602, 11763.470643821049, 11791.764164716658, 11816.409179540988, 11816.822747658793, 14492.985491015528, 14589.124652520883, 14788.704225915422, 14800.925995369955, 14829.555113346616, 8809.735707928772, 8993.370464067584, 9121.45271283712, 9157.221636161, 9186.586603602855, 8898.11018898105, 9006.59470323837, 9012.107457434013, 9158.67944621935, 9193.49435781025, 6159.753609855556, 7234.891476627855, 7291.635418229067, 7330.413566914741, 7398.297161769731, 1990.8506861985409, 2027.4221846773598, 2029.3506078054882, 2035.8676686015658, 2043.2746375036945, 414.376363572827, 421.4373810962338, 442.65694087082767, 869.517417833096, 951.1154626197564, 11788.823167652321, 11889.762204755856, 12135.575254866037, 12265.325407102695, 12602.369881505889, 410.417207565258, 472.80378642862826, 473.76785375165923, 479.18777671846584, 482.89859129582476, 6063.605865618809, 6172.0, 6253.8117757625905, 6297.0, 6347.365263473615, 805307.0, 811475.0, 811818.0, 831347.0, 844827.0, 112061.0, 116440.835591643, 163832.0, 168124.0, 170947.0, 453.7164272329794, 893.3746377535751, 1007.2294694558668, 1089.4988305379457, 1096.8574085368691, 19739.0, 19793.901030494755, 20238.39284821458, 20353.557573091322, 20453.977301135183, 44549.0, 50496.0, 64453.066408007835, 67257.0, 67560.3243967554, 77590.0, 93071.0, 96321.0, 97070.0, 97156.0, 8421.284190843848, 8433.536155511505, 8754.956225218833, 8858.73423797287, 8949.865752013595, 10835.404052777172, 11615.6515304541, 13308.0, 13384.263865487492, 13598.660033499242, 11252.212345135762, 11625.825612615987, 11748.647540573797, 11821.82267266009, 11894.702632433913, 10574.524146413276, 10646.520906559379, 10778.784424311312, 10805.135589153006, 11138.721531961766, 11611.535538578362, 11675.124365672778, 11702.0, 11818.881811181778, 11978.341191234565, 8545.529995850287, 8558.52928088961, 8765.991910930325, 8779.0781967895, 8806.955965220133, 10126.974605079162, 10257.58969641206, 11564.479598417924, 11600.0, 11699.181057325906], "value": 9625.111775778918, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.5444980381376, 1452.6077958951078, 1468.6622076922308, 1480.8741256993162, 1484.2727063738764, 7840.4511684182135, 8066.5966701664975, 8332.70835520757, 8725.520096394695, 8749.343799215052, 8284.627191776384, 8546.273566746833, 8556.657733690645, 8672.696455624055, 8677.95661021694, 2375.3111597636666, 2397.5444665513583, 2401.6997875265592, 2401.807855371575, 2417.8791060446906, 2359.9646005309933, 2366.3965688749345, 2393.198278576679, 2399.6280576510667, 2407.0, 2366.7278262999803, 2371.9762802372015, 2378.0, 2405.122130422401, 2410.963835542468, 2065.2048961149917, 2332.183735692494, 2360.78752912237, 2365.6451532270125, 2392.844465109771, 2382.654515095319, 2388.0328466970905, 2419.9637005444933, 2420.0803694595943, 2428.3929017745563, 7833.725819596344, 8208.891799607096, 8634.00708918476, 8679.218870301704, 8686.391952563323, 5212.348456442945, 5292.867678308035, 5358.223057656657, 5361.329833770739, 5361.544268737162, 3358.7313014958745, 3418.1283772637817, 3623.0942264433893, 3647.8905632831056, 3656.7988760618155, 1219.548766956232, 1222.407132540717, 1265.171312790124, 1306.2423794199342, 1323.444153455551, 11025.062869656089, 11036.337819730761, 11070.557177712883, 11098.611548595842, 11114.05530529906, 4645.071160394638, 4732.574068333797, 4803.735794531334, 4863.975680121577, 4875.585575226075, 9776.657816976372, 9779.0, 9790.412575245507, 9791.608335666493, 9812.705618831447, 4699.389079419653, 5303.231031500411, 5356.1697936819855, 5411.161270003156, 5421.078416669176, 4483.663725220622, 4548.317752337176, 4936.136176169156, 4946.08497427977, 4981.850544483671, 3980.542237642708, 4060.959390406117, 4100.528439229527, 4131.462909821704, 4134.503859536815, 1299.9025073119553, 1303.7131830997166, 1310.344827586207, 1320.3398300849576, 1321.8215540902047, 703.7642389799474, 723.3670538279006, 762.3291503476905, 785.6307535458326, 822.5229366967145, 5701.4013528579735, 5732.656040637492, 5765.365809760923, 5950.345461999176, 5964.552658550626, 483.97096174228955, 541.0126519102654, 555.1811078658945, 562.6174201542908, 563.7913971830449, 3473.7394695397948, 3576.7317451191266, 3801.752886062397, 3819.4461803038403, 3984.521857377133, 9066.093390661013, 9870.950645246729, 9873.25950553697, 10067.194624430029, 10214.08073273409, 8544.2310192083, 10128.34165779222, 10251.179905607534, 10290.897091029143, 10291.176705863512, 7861.2531809478005, 7892.644830982619, 8068.273855353046, 8072.152423995513, 8077.959610202029, 5574.414686457863, 5635.633683810539, 5676.659400435987, 5759.308882934075, 5801.100829371286, 9355.578998944928, 9379.906200937903, 9415.05849415053, 9444.0, 9498.525073746147, 9168.724938251864, 9491.525423728648, 9511.57197926081, 9528.380655257663, 9567.712968610953, 6854.10896583441, 7033.542819716702, 7165.964170179116, 7298.2701729826595, 7791.454598178299, 7921.722739704082, 8083.232092951161, 8176.591170441573, 8179.141190174946, 8181.140980197231, 6998.440124789903, 7083.008378826866, 7126.608036558039, 7138.8215294615975, 7145.714171433292, 6884.759033433806, 6949.131358580177, 6958.721651133898, 6963.338482844324, 7080.4335653148655, 5945.40545945402, 6410.5833120848865, 6465.870682586416, 6557.803265902031, 6565.540412171294, 4965.329680493087, 5110.233464980134, 5130.769115389891, 5176.9741151294, 5180.222966555047, 4945.381827271591, 5073.44192138857, 5142.0, 5170.741462926914, 5241.344831896013], "value": 4430.71009253914, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.80300637655665, 366.4960679066284, 374.12641482139196, 378.1944458303816, 378.2397381263661, 4544.818207271714, 5242.151010315452, 5557.9166312505295, 5613.69124698142, 5624.10014397696, 4554.8840534069195, 4861.562459378655, 5187.106706052292, 5245.426372088377, 5497.587680923927, 4283.443152390193, 4332.631726303268, 4347.369631403445, 4352.586504282088, 4385.412606562128, 4191.748495090289, 4247.702660813744, 4331.978340108295, 4379.671524635649, 4401.757903315316, 4264.616184543391, 4272.209641216385, 4291.184674911772, 4295.957040429603, 4313.137372525492, 3458.5849698036145, 3470.7570470067108, 4294.076773493698, 4308.3968244445805, 4387.135467426363, 3252.4796032634663, 3396.7112795412418, 3848.2495913296916, 3863.4011728182177, 3867.3757022050804, 5092.3125378073855, 5235.502627250406, 5272.973635131838, 5328.520433161034, 5362.51737343641, 4646.465656449484, 4742.083618574587, 4775.665703400763, 4987.775550100254, 5011.924821127686, 5580.359372346935, 5854.385289544579, 5864.191363082275, 5904.380040095771, 5909.09999400398, 3737.4206997915367, 4190.790460476995, 4236.449261596003, 4257.808398622069, 4279.379489973936, 5684.30520161898, 5795.377888819487, 6057.515398768132, 6176.84557886052, 6206.25524937006, 4341.697490752825, 4344.45694288214, 4354.869353919387, 4362.367456718758, 4396.560343965578, 3842.692584593226, 3847.9230415392094, 4083.612056854594, 4316.805743741539, 4381.5618438155925, 4427.454213827063, 4988.8752781180765, 4992.0, 5017.0, 5021.6735912165595, 5084.644074914794, 5193.870153246199, 5324.1099409708995, 5894.32215295238, 5931.228940237742, 1226.5955480974235, 1308.5485507500089, 1357.7237396846933, 1415.0943396226423, 1421.5713208225668, 955.5699935029264, 975.8633791269228, 1023.3603997501561, 1027.5992362978445, 1030.6289735695143, 4929.901401971941, 5029.723365214948, 5209.817656382009, 5291.365036195682, 5308.442613525602, 5761.711914404263, 5769.88460230793, 5777.826665200051, 5783.826485205366, 5841.532677385799, 4296.119295544406, 4784.779881130359, 5000.049990501793, 5052.38071595252, 5117.692938423706, 2821.915342539727, 2932.0, 2991.8952836650615, 3008.909732708022, 3009.0, 5921.3190483094995, 5939.524838012949, 5955.404459554096, 5985.491233245223, 5994.730237139339, 5020.397552293675, 5139.8458046258675, 5382.596305277041, 5630.0, 5886.35182148999, 5159.9999999999245, 5680.261565996394, 5948.762049518057, 5962.672053037038, 6019.0, 5092.759185958166, 5457.454254574511, 5730.656160630376, 5780.190773291826, 5824.097264923944, 5537.584681148849, 5609.186667933045, 5615.803446879339, 5617.522510586646, 5637.746301416528, 5552.583556233218, 5749.540036796962, 5949.0, 5972.611780234271, 5982.581219314607, 3727.403615421628, 3812.7331086823656, 3978.940315895322, 4023.879283621496, 4155.771432571238, 4958.553730164303, 5672.369683402543, 6028.608140470856, 6030.733545955399, 6044.304904935989, 5613.887722245614, 5823.82528524145, 5854.9121763174435, 5858.414158584107, 5866.266716660418, 4911.0, 5097.337346144977, 5735.655860648374, 5819.82540523785, 5869.853253668693, 5670.886582268249, 5702.258706368146, 5703.771849125988, 5723.3958548861, 5770.567207459373, 5544.97227513876, 5837.591368604157, 5839.941600584109, 5849.766009359578, 5852.473277405054, 5011.095783602208, 5258.763355648928, 5690.520464679131, 5697.829065128053, 5730.799422020209], "value": 4276.666123853144, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.5150023733978, 786.8268980824216, 793.3296364571938, 864.2351518905768, 866.3415803988967, 15695.686086278281, 16300.429484968028, 16390.67218655626, 16527.669446611075, 16530.0, 14838.777418338734, 14981.026233294828, 14999.775003374958, 15020.774688379655, 15062.096274223526, 684.1550684904151, 712.9287071292855, 831.4927893984694, 868.4702331577732, 870.8519551676198, 876.5529579914257, 877.1666916429385, 877.4998250996935, 878.989162463166, 880.2781718990431, 933.2114363362947, 935.2611436964789, 936.929730270229, 937.0114529171722, 939.178219058324, 915.8305713443001, 927.420362273579, 931.3108299858123, 932.7201839448206, 935.7847695030147, 678.3352314731591, 690.6615758278458, 695.5409429776323, 695.8817001109824, 700.1877821726772, 15291.0, 15344.156071416068, 15634.843651563566, 15928.0, 16298.185090745415, 14715.55853324402, 14908.478203262943, 15134.318955647022, 15185.620359490991, 15251.61870953224, 11655.358955257458, 11744.236624619372, 11751.764964700658, 11842.513072254837, 12006.819897701542, 3799.9620003799882, 3846.4614953906475, 3900.3369427197445, 3928.155446578999, 4064.6138616831354, 103996.48001760019, 106616.0, 107494.46252768765, 108659.0, 109561.0, 11507.76984460306, 11592.826107608393, 11611.477483513263, 12093.97201237887, 12131.636050918487, 23748.0, 24341.756582434304, 24366.878165609058, 24851.875740621545, 24964.0014399422, 7686.884696729554, 7771.067471903408, 7804.453688241769, 7842.843143137219, 8051.758447246592, 4369.890752731207, 4445.244308467568, 4462.732236065782, 4560.429946256718, 4606.055758569485, 3581.534400527915, 3757.830897609614, 3799.2401519696173, 3841.500604921398, 3845.7115716321387, 523.9921401178985, 560.7336515155268, 566.742132329789, 567.8835838653066, 708.8794904866185, 562.8846086552247, 567.6764244380663, 593.2110293309887, 593.257627068365, 616.0512810272131, 9344.925333586589, 10022.0, 10498.737531561772, 11115.666530004113, 11137.164712646583, 675.3145557259386, 688.7004153193277, 716.5235118646146, 719.4172720096739, 737.8819388897858, 4290.2921018031675, 5116.923246151311, 5189.028169295719, 5210.76551555181, 5221.6344855859725, 105387.47306263419, 108098.0, 109059.45470272757, 110011.0, 110502.0, 90423.0, 93708.12583748288, 94842.0, 95674.0, 99698.50452243222, 61069.0, 82082.58958705288, 82926.0, 83420.0, 84697.57651211704, 9915.55380007887, 10243.897561024294, 10257.025582569644, 10286.382817031003, 10306.536205870903, 52457.0, 54350.184747228035, 55227.72386138044, 56026.43973560212, 56196.0, 73261.63369183333, 73995.63002184955, 74190.51618967699, 76341.0, 76441.85337219835, 10616.89383106159, 11509.654710358702, 11551.942240288745, 11958.58144964922, 12162.939185304016, 13211.603651890458, 13224.537141200397, 13493.662658433617, 13533.729325413633, 13652.726945460836, 11808.291502509877, 12226.877731222574, 12368.567100151451, 12701.618951431472, 12709.80935285953, 12309.0, 12430.689232769253, 12460.750784984431, 12580.496780129059, 12734.426950787052, 10693.411862347646, 12081.395930203278, 12553.246805191719, 12610.454992241566, 12745.681357966125, 8920.055267497577, 9270.631866488566, 9357.906420935704, 9400.15398614128, 9442.716718498456, 10178.491075446345, 10262.230332725225, 10265.486725663837, 10279.691609251735, 10301.278910476194], "value": 7569.77766829923, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1539.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 118.0, 120.0, 117.0, 118.0, 118.0, 115.0, 164.0, 175.0], "value": 126.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 111.0, 111.0, 170.0, 111.0, 113.0, 111.0, 126.0, 164.0, 118.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 197.0, 197.0, 195.0, 193.0, 198.0, 200.0, 194.0, 199.0, 190.0], "value": 196.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 59.0, 87.0, 58.0, 59.0, 63.0, 70.0, 98.0, 60.0], "value": 69.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 74.0, 113.0, 101.0, 100.0, 87.0, 79.0, 70.0, 69.0, 84.0], "value": 86.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 120.0, 90.0, 86.0, 90.0, 91.0, 104.0, 88.0, 89.0, 87.0], "value": 93.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 169.0, 120.0, 168.0, 154.0, 100.0, 102.0, 126.0, 100.0, 99.0], "value": 125.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 100.0, 76.0, 146.0, 148.0, 93.0, 91.0, 92.0, 93.0, 91.0], "value": 102.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 60.0, 79.0, 86.0, 88.0, 81.0, 59.0, 59.0], "value": 68.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 78.0, 70.0, 75.0, 75.0, 67.0, 67.0, 73.0, 65.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 80.0, 81.0, 81.0, 84.0, 84.0, 87.0, 89.0, 81.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 138.0, 138.0, 137.0, 146.0, 150.0, 152.0, 154.0, 170.0, 145.0], "value": 146.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [213.0, 165.0, 165.0, 168.0, 161.0, 164.0, 161.0, 164.0, 160.0, 168.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 83.0, 82.0, 80.0, 84.0, 70.0, 65.0, 65.0], "value": 72.6, "unit": "score"}]}]} 08:28:38 INFO - Return code: 0 08:28:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:28:38 ERROR - # TBPL SUCCESS # 08:28:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:28:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:28:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:28:38 INFO - 2017-03-28 08:28:38.751 screenresolution[1891:10945] starting screenresolution argv=screenresolution get 08:28:38 INFO - 2017-03-28 08:28:38.753 screenresolution[1891:10945] Display 0: 1600x1200x32@60 08:28:38 INFO - 2017-03-28 08:28:38.759 screenresolution[1892:10948] starting screenresolution argv=screenresolution list 08:28:38 INFO - Available Modes on Display 0 08:28:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:28:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:28:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:28:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:28:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:28:38 INFO - Intel Iris: 08:28:38 INFO - Chipset Model: Intel Iris 08:28:38 INFO - Type: GPU 08:28:38 INFO - Bus: Built-In 08:28:38 INFO - VRAM (Dynamic, Max): 1536 MB 08:28:38 INFO - Vendor: Intel (0x8086) 08:28:38 INFO - Device ID: 0x0a2e 08:28:38 INFO - Revision ID: 0x0009 08:28:38 INFO - Displays: 08:28:38 INFO - Display: 08:28:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:28:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:28:38 INFO - Display Serial Number: 1600x1200 60 08:28:38 INFO - Main Display: Yes 08:28:38 INFO - Mirror: Off 08:28:38 INFO - Online: Yes 08:28:38 INFO - Rotation: Supported 08:28:38 INFO - Return code: 0 08:28:38 INFO - Running post-action listener: _resource_record_post_action 08:28:38 INFO - [mozharness: 2017-03-28 15:28:38.994554Z] Finished run-tests step (success) 08:28:38 INFO - Running post-run listener: _resource_record_post_run 08:28:39 INFO - Total resource usage - Wall time: 1190s; CPU: 55.0%; Read bytes: 40101888; Write bytes: 428428288; Read time: 256; Write time: 1508 08:28:39 INFO - TinderboxPrint: CPU usage
54.6% 08:28:39 INFO - TinderboxPrint: I/O read bytes / time
40,101,888 / 256 08:28:39 INFO - TinderboxPrint: I/O write bytes / time
428,428,288 / 1,508 08:28:39 INFO - TinderboxPrint: CPU idle
2,161.7 (45.4%) 08:28:39 INFO - TinderboxPrint: CPU system
966.5 (20.3%) 08:28:39 INFO - TinderboxPrint: CPU user
1,633.6 (34.3%) 08:28:39 INFO - TinderboxPrint: Swap in / out
161,296,384 / 0 08:28:39 INFO - install - Wall time: 15s; CPU: 55.0%; Read bytes: 124770816; Write bytes: 112742400; Read time: 10626; Write time: 252 08:28:39 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 32612352; Write bytes: 304634880; Read time: 195; Write time: 1220 08:28:39 INFO - Running post-run listener: _upload_blobber_files 08:28:39 INFO - Blob upload gear active. 08:28:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:28:39 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 08:28:39 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'] 08:28:39 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 08:28:39 INFO - (blobuploader) - INFO - Open directory for files ... 08:28:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:28:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:28:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:40 INFO - (blobuploader) - INFO - Done attempting. 08:28:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:28:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:28:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:41 INFO - (blobuploader) - INFO - Done attempting. 08:28:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:28:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:28:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:42 INFO - (blobuploader) - INFO - Done attempting. 08:28:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:28:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:28:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:44 INFO - (blobuploader) - INFO - Done attempting. 08:28:44 INFO - (blobuploader) - INFO - Iteration through files over. 08:28:44 INFO - Return code: 0 08:28:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:28:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:28:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6926a2824b3d5b5ae6829aff5464b19e0d6f0252243adee53075772e8de61bda26cc1f3c93d29025fdf7d1465d94b88f1b1c3aaa03318165754478470195d402", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04b1ed34807fe2f49f41507cc6aa9b2a78c9ab7cf8b92fc253569e86db1600aef8ea16f8a4a582f80475ec4e707dc6cf7453cba8159a85359b4d270fd48c9b83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b930a9b9cea8154aab4a501f55e355ece1ddd9d508162c5a8c734435f5ab9f1d34948b1f82b27d59baa50f40800625b7250f59531a0a9dbf92a811f9e40b22ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db6d0acedbe55289444a72fe5dfde0e64ab1c7a963a03a7fde32bd15797b5c5804772388a0b487e4f394c9eb501930a2da1a005e42c56b5e89988d4adf533792"} 08:28:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:28:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:28:44 INFO - Contents: 08:28:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6926a2824b3d5b5ae6829aff5464b19e0d6f0252243adee53075772e8de61bda26cc1f3c93d29025fdf7d1465d94b88f1b1c3aaa03318165754478470195d402", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04b1ed34807fe2f49f41507cc6aa9b2a78c9ab7cf8b92fc253569e86db1600aef8ea16f8a4a582f80475ec4e707dc6cf7453cba8159a85359b4d270fd48c9b83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b930a9b9cea8154aab4a501f55e355ece1ddd9d508162c5a8c734435f5ab9f1d34948b1f82b27d59baa50f40800625b7250f59531a0a9dbf92a811f9e40b22ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db6d0acedbe55289444a72fe5dfde0e64ab1c7a963a03a7fde32bd15797b5c5804772388a0b487e4f394c9eb501930a2da1a005e42c56b5e89988d4adf533792"} 08:28:44 INFO - Running post-run listener: copy_logs_to_upload_dir 08:28:44 INFO - Copying logs to upload dir... 08:28:44 INFO - mkdir: /builds/slave/test/build/upload/logs 08:28:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.980371 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-03-28 08:28:44.593151) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 08:28:44.597136) ========= 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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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/6926a2824b3d5b5ae6829aff5464b19e0d6f0252243adee53075772e8de61bda26cc1f3c93d29025fdf7d1465d94b88f1b1c3aaa03318165754478470195d402", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04b1ed34807fe2f49f41507cc6aa9b2a78c9ab7cf8b92fc253569e86db1600aef8ea16f8a4a582f80475ec4e707dc6cf7453cba8159a85359b4d270fd48c9b83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b930a9b9cea8154aab4a501f55e355ece1ddd9d508162c5a8c734435f5ab9f1d34948b1f82b27d59baa50f40800625b7250f59531a0a9dbf92a811f9e40b22ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db6d0acedbe55289444a72fe5dfde0e64ab1c7a963a03a7fde32bd15797b5c5804772388a0b487e4f394c9eb501930a2da1a005e42c56b5e89988d4adf533792"} build_url:https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010891 build_url: 'https://queue.taskcluster.net/v1/task/IHBK_nzCRyCkKU2fEfhmpA/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/6926a2824b3d5b5ae6829aff5464b19e0d6f0252243adee53075772e8de61bda26cc1f3c93d29025fdf7d1465d94b88f1b1c3aaa03318165754478470195d402", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04b1ed34807fe2f49f41507cc6aa9b2a78c9ab7cf8b92fc253569e86db1600aef8ea16f8a4a582f80475ec4e707dc6cf7453cba8159a85359b4d270fd48c9b83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b930a9b9cea8154aab4a501f55e355ece1ddd9d508162c5a8c734435f5ab9f1d34948b1f82b27d59baa50f40800625b7250f59531a0a9dbf92a811f9e40b22ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db6d0acedbe55289444a72fe5dfde0e64ab1c7a963a03a7fde32bd15797b5c5804772388a0b487e4f394c9eb501930a2da1a005e42c56b5e89988d4adf533792"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 08:28:44.631006) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 08:28:44.631304) ========= 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.dBPOKqJUMK/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.vRYJJmQS2L/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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.005316 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 08:28:44.679350) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 08:28:44.679690) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 08:28:44.680017) ========= ========= Total master_lag: 2.45 =========