builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0175 starttime: 1490097826.13 results: success (0) buildid: 20170321032428 builduid: e630dafc6bb3448fa81b0543c0d71dac revision: a0b80d99c2fe2d007c95820cbdbb8568ce8ee437 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 05:03:46.127484) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 05:03:46.127976) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2017-03-21 05:03:46.128304) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2017-03-21 05:03:48.672314) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 05:03:48.672676) ========= 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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-21 05:03:48-- 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% 39.8M=0s 2017-03-21 05:03:48 (39.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.099060 ========= master_lag: 1.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 05:03:49.799791) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:03:49.800182) ========= 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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.025998 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:03:49.853879) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-03-21 05:03:49.854385) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a0b80d99c2fe2d007c95820cbdbb8568ce8ee437 --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 a0b80d99c2fe2d007c95820cbdbb8568ce8ee437 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZrsgR9RySp/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.NgB6I0vLYP/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-21 05:03:49,949 truncating revision to first 12 chars 2017-03-21 05:03:49,949 Setting DEBUG logging. 2017-03-21 05:03:49,949 attempt 1/10 2017-03-21 05:03:49,949 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a0b80d99c2fe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-21 05:03:51,404 unpacking tar archive at: mozilla-beta-a0b80d99c2fe/testing/mozharness/ program finished with exit code 0 elapsedTime=1.715538 ========= master_lag: 3.26 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-03-21 05:03:54.832184) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 05:03:54.832567) ========= script_repo_revision: a0b80d99c2fe2d007c95820cbdbb8568ce8ee437 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 05:03:54.833047) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 05:03:54.833365) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 05:03:55.765795) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-21 05:03:55.766188) ========= /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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:03:55 INFO - MultiFileLogger online at 20170321 05:03:55 in /builds/slave/test 05:03:55 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 05:03:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:03:55 INFO - {'append_to_log': False, 05:03:55 INFO - 'base_work_dir': '/builds/slave/test', 05:03:55 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:03:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:03:55 INFO - 'branch': 'Mozilla-Beta', 05:03:55 INFO - 'buildbot_json_path': 'buildprops.json', 05:03:55 INFO - 'config_files': ('talos/mac_config.py',), 05:03:55 INFO - 'default_actions': ('clobber', 05:03:55 INFO - 'read-buildbot-config', 05:03:55 INFO - 'download-and-extract', 05:03:55 INFO - 'populate-webroot', 05:03:55 INFO - 'create-virtualenv', 05:03:55 INFO - 'install', 05:03:55 INFO - 'run-tests'), 05:03:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:03:55 INFO - 'download_minidump_stackwalk': True, 05:03:55 INFO - 'download_symbols': 'ondemand', 05:03:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:03:55 INFO - 'tooltool.py': '/tools/tooltool.py', 05:03:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:03:55 INFO - '/tools/misc-python/virtualenv.py')}, 05:03:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:03:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:03:55 INFO - 'installer_path': 'installer.exe', 05:03:55 INFO - 'log_level': 'info', 05:03:55 INFO - 'log_name': 'talos', 05:03:55 INFO - 'log_to_console': True, 05:03:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:03:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:03:55 INFO - 'opt_config_files': (), 05:03:55 INFO - 'pip_index': False, 05:03:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:03:55 INFO - 'cmd': ('bash', 05:03:55 INFO - '-c', 05:03:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:03:55 INFO - 'enabled': True, 05:03:55 INFO - 'halt_on_failure': False, 05:03:55 INFO - 'name': 'check_screen_resolution'},), 05:03:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:03:55 INFO - 'cmd': ('bash', 05:03:55 INFO - '-c', 05:03:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:03:55 INFO - 'enabled': True, 05:03:55 INFO - 'halt_on_failure': False, 05:03:55 INFO - 'name': 'check_screen_resolution'},), 05:03:55 INFO - 'run_cmd_checks_enabled': True, 05:03:55 INFO - 'sps_profile': False, 05:03:55 INFO - 'sps_profile_interval': 0, 05:03:55 INFO - 'suite': 'dromaeojs-e10s', 05:03:55 INFO - 'system_bits': '32', 05:03:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:03:55 INFO - 'title': 't-yosemite-r7-0175', 05:03:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:03:55 INFO - 'use_talos_json': True, 05:03:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:03:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:03:55 INFO - 'work_dir': 'build'} 05:03:55 INFO - [mozharness: 2017-03-21 12:03:55.977234Z] Running clobber step. 05:03:55 INFO - Running pre-action listener: _resource_record_pre_action 05:03:55 INFO - Running main action method: clobber 05:03:55 INFO - rmtree: /builds/slave/test/build 05:03:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:03:58 INFO - Running post-action listener: _resource_record_post_action 05:03:58 INFO - [mozharness: 2017-03-21 12:03:58.561691Z] Finished clobber step (success) 05:03:58 INFO - [mozharness: 2017-03-21 12:03:58.561909Z] Running read-buildbot-config step. 05:03:58 INFO - Running pre-action listener: _resource_record_pre_action 05:03:58 INFO - Running main action method: read_buildbot_config 05:03:58 INFO - Using buildbot properties: 05:03:58 INFO - { 05:03:58 INFO - "project": "", 05:03:58 INFO - "product": "firefox", 05:03:58 INFO - "script_repo_revision": "production", 05:03:58 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 05:03:58 INFO - "repository": "", 05:03:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 05:03:58 INFO - "buildid": "20170321032428", 05:03:58 INFO - "pgo_build": "False", 05:03:58 INFO - "basedir": "/builds/slave/test", 05:03:58 INFO - "buildnumber": 31, 05:03:58 INFO - "slavename": "t-yosemite-r7-0175", 05:03:58 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:03:58 INFO - "platform": "yosemite_r7", 05:03:58 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:03:58 INFO - "branch": "mozilla-beta", 05:03:58 INFO - "repo_path": "releases/mozilla-beta", 05:03:58 INFO - "revision": "a0b80d99c2fe2d007c95820cbdbb8568ce8ee437", 05:03:58 INFO - "stage_platform": "macosx64", 05:03:58 INFO - "builduid": "e630dafc6bb3448fa81b0543c0d71dac", 05:03:58 INFO - "slavebuilddir": "test" 05:03:58 INFO - } 05:03:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 05:03:58 INFO - Running post-action listener: _resource_record_post_action 05:03:58 INFO - [mozharness: 2017-03-21 12:03:58.567653Z] Finished read-buildbot-config step (success) 05:03:58 INFO - [mozharness: 2017-03-21 12:03:58.567811Z] Running download-and-extract step. 05:03:58 INFO - Running pre-action listener: _resource_record_pre_action 05:03:58 INFO - Running main action method: download_and_extract 05:03:58 INFO - mkdir: /builds/slave/test/build/tests 05:03:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:58 INFO - trying https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 05:03:58 INFO - Downloading https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/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 05:03:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/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 05:04:00 INFO - Downloaded 1165 bytes. 05:04:00 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 05:04:00 INFO - Using the following test package requirements: 05:04:00 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 05:04:00 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:04:00 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 05:04:00 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:04:00 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 05:04:00 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:04:00 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:04:00 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 05:04:00 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 05:04:00 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:04:00 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 05:04:00 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:04:00 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 05:04:00 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:04:00 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 05:04:00 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:04:00 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 05:04:00 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 05:04:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 05:04:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 05:04:00 INFO - Fetch https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 05:04:01 INFO - Expected file size: 16108180 05:04:01 INFO - Obtained file size: 16108180 05:04:07 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 05:04:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 05:04:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 05:04:07 INFO - Fetch https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 05:04:08 INFO - Expected file size: 16108180 05:04:08 INFO - Obtained file size: 16108180 05:04:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 05:04:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 05:04:15 INFO - Fetch https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 05:04:16 INFO - Expected file size: 14403663 05:04:16 INFO - Obtained file size: 14403663 05:04:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:16 INFO - trying https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:04:16 INFO - Downloading https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 05:04:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/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 05:04:18 INFO - Downloaded 58734723 bytes. 05:04:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:04:18 INFO - mkdir: /builds/slave/test/properties 05:04:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:04:18 INFO - Writing to file /builds/slave/test/properties/build_url 05:04:18 INFO - Contents: 05:04:18 INFO - build_url:https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:04:18 INFO - Running post-action listener: _resource_record_post_action 05:04:18 INFO - Running post-action listener: set_extra_try_arguments 05:04:18 INFO - [mozharness: 2017-03-21 12:04:18.981542Z] Finished download-and-extract step (success) 05:04:18 INFO - [mozharness: 2017-03-21 12:04:18.981676Z] Running populate-webroot step. 05:04:18 INFO - Running pre-action listener: _resource_record_pre_action 05:04:18 INFO - Running main action method: populate_webroot 05:04:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:04:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:04:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:04:18 INFO - '--tppagecycles', 05:04:18 INFO - '7'], 05:04:18 INFO - 'tests': ['tsvgm']}}, 05:04:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:04:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:04:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:04:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:04:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:04:18 INFO - 'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:04:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:04:18 INFO - 'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['damp', 'tps']}, 05:04:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:04:18 INFO - 'tests': ['damp', 'tps']}, 05:04:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['dromaeo_dom']}, 05:04:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:04:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['basic_compositor_video']}, 05:04:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:04:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['a11yr', 05:04:18 INFO - 'ts_paint', 05:04:18 INFO - 'tpaint', 05:04:18 INFO - 'sessionrestore', 05:04:18 INFO - 'sessionrestore_no_auto_restore', 05:04:18 INFO - 'tabpaint']}, 05:04:18 INFO - 'other-e10s': {'tests': ['a11yr', 05:04:18 INFO - 'ts_paint', 05:04:18 INFO - 'tpaint', 05:04:18 INFO - 'sessionrestore', 05:04:18 INFO - 'sessionrestore_no_auto_restore', 05:04:18 INFO - 'tabpaint']}, 05:04:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:04:18 INFO - 'ts_paint', 05:04:18 INFO - 'tpaint', 05:04:18 INFO - 'sessionrestore', 05:04:18 INFO - 'sessionrestore_no_auto_restore', 05:04:18 INFO - 'tabpaint']}, 05:04:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:04:18 INFO - 'ts_paint', 05:04:18 INFO - 'tpaint', 05:04:18 INFO - 'sessionrestore', 05:04:18 INFO - 'sessionrestore_no_auto_restore', 05:04:18 INFO - 'tabpaint']}, 05:04:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['a11yr', 05:04:18 INFO - 'ts_paint', 05:04:18 INFO - 'tpaint', 05:04:18 INFO - 'sessionrestore', 05:04:18 INFO - 'sessionrestore_no_auto_restore', 05:04:18 INFO - 'tabpaint']}, 05:04:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['a11yr', 05:04:18 INFO - 'ts_paint', 05:04:18 INFO - 'tpaint', 05:04:18 INFO - 'sessionrestore', 05:04:18 INFO - 'sessionrestore_no_auto_restore', 05:04:18 INFO - 'tabpaint']}, 05:04:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['tsvgx', 05:04:18 INFO - 'tsvgr_opacity', 05:04:18 INFO - 'tart', 05:04:18 INFO - 'tscrollx', 05:04:18 INFO - 'cart', 05:04:18 INFO - 'tsvg_static']}, 05:04:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:04:18 INFO - 'tsvgr_opacity', 05:04:18 INFO - 'tart', 05:04:18 INFO - 'tscrollx', 05:04:18 INFO - 'cart', 05:04:18 INFO - 'tsvg_static']}, 05:04:19 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:04:19 INFO - 'talos_options': ['--disable-e10s'], 05:04:19 INFO - 'tests': ['tp5o']}, 05:04:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:04:19 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:04:19 INFO - 'talos_options': ['--disable-e10s', 05:04:19 INFO - '--xperf_path', 05:04:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:19 INFO - 'tests': ['tp5n']}, 05:04:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:04:19 INFO - 'talos_options': ['--xperf_path', 05:04:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:19 INFO - 'tests': ['tp5n']}}, 05:04:19 INFO - 'talos.zip': {'path': '', 05:04:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:04:19 INFO - Running post-action listener: _resource_record_post_action 05:04:19 INFO - [mozharness: 2017-03-21 12:04:19.001789Z] Finished populate-webroot step (success) 05:04:19 INFO - [mozharness: 2017-03-21 12:04:19.001931Z] Running create-virtualenv step. 05:04:19 INFO - Running pre-action listener: _resource_record_pre_action 05:04:19 INFO - Running main action method: create_virtualenv 05:04:19 INFO - Creating virtualenv /builds/slave/test/build/venv 05:04:19 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 05:04:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:04:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:04:19 INFO - Using real prefix '/tools/python27' 05:04:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:04:19 INFO - Installing distribute.............................................................................................................................................................................................done. 05:04:22 INFO - Installing pip.................done. 05:04:22 INFO - Return code: 0 05:04:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:04:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:04:22 INFO - Reading from file tmpfile_stdout 05:04:22 INFO - Output received: 05:04:22 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) 05:04:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a6c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6934d65b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a1d30>, '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 0x10a3c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a496be8>, '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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:04:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:04:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:04:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZrsgR9RySp/Render', 05:04:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:22 INFO - 'HOME': '/Users/cltbld', 05:04:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:22 INFO - 'LOGNAME': 'cltbld', 05:04:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:22 INFO - 'MOZ_NO_REMOTE': '1', 05:04:22 INFO - 'NO_EM_RESTART': '1', 05:04:22 INFO - 'PAGER': '/bin/cat', 05:04:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:22 INFO - 'PWD': '/builds/slave/test', 05:04:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:22 INFO - 'SHELL': '/bin/bash', 05:04:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NgB6I0vLYP/Listeners', 05:04:22 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:04:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:22 INFO - 'USER': 'cltbld', 05:04:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:22 INFO - 'XPC_FLAGS': '0x0', 05:04:22 INFO - 'XPC_SERVICE_NAME': '0', 05:04:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:23 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 05:04:23 INFO - Cleaning up... 05:04:23 INFO - Return code: 0 05:04:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:04:23 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')]} 05:04:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:23 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')]} 05:04:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:23 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 0x10a6c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6934d65b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a1d30>, '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 0x10a3c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a496be8>, '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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:04:23 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 05:04:23 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 05:04:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZrsgR9RySp/Render', 05:04:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:23 INFO - 'HOME': '/Users/cltbld', 05:04:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:23 INFO - 'LOGNAME': 'cltbld', 05:04:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:23 INFO - 'MOZ_NO_REMOTE': '1', 05:04:23 INFO - 'NO_EM_RESTART': '1', 05:04:23 INFO - 'PAGER': '/bin/cat', 05:04:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:23 INFO - 'PWD': '/builds/slave/test', 05:04:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:23 INFO - 'SHELL': '/bin/bash', 05:04:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NgB6I0vLYP/Listeners', 05:04:23 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:04:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:23 INFO - 'USER': 'cltbld', 05:04:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:23 INFO - 'XPC_FLAGS': '0x0', 05:04:23 INFO - 'XPC_SERVICE_NAME': '0', 05:04:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:23 INFO - Downloading/unpacking psutil>=3.1.1 05:04:23 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 05:04:23 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 05:04:23 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 05:04:23 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 05:04:23 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 05:04:23 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 05:04:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:04:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:26 INFO - Installing collected packages: psutil 05:04:26 INFO - Running setup.py install for psutil 05:04:27 INFO - building 'psutil._psutil_osx' extension 05:04:27 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 05:04:27 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 05:04:27 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 05:04:27 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 05:04:27 INFO - building 'psutil._psutil_posix' extension 05:04:27 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 05:04:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:04:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:04:27 INFO - ^ 05:04:27 INFO - 1 warning generated. 05:04:27 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 05:04:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:28 INFO - Successfully installed psutil 05:04:28 INFO - Cleaning up... 05:04:28 INFO - Return code: 0 05:04:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:04:28 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')]} 05:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:28 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')]} 05:04:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:28 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 0x10a6c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6934d65b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a1d30>, '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 0x10a3c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a496be8>, '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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:04:28 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 05:04:28 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 05:04:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZrsgR9RySp/Render', 05:04:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:28 INFO - 'HOME': '/Users/cltbld', 05:04:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:28 INFO - 'LOGNAME': 'cltbld', 05:04:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:28 INFO - 'MOZ_NO_REMOTE': '1', 05:04:28 INFO - 'NO_EM_RESTART': '1', 05:04:28 INFO - 'PAGER': '/bin/cat', 05:04:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:28 INFO - 'PWD': '/builds/slave/test', 05:04:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:28 INFO - 'SHELL': '/bin/bash', 05:04:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NgB6I0vLYP/Listeners', 05:04:28 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:04:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:28 INFO - 'USER': 'cltbld', 05:04:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:28 INFO - 'XPC_FLAGS': '0x0', 05:04:28 INFO - 'XPC_SERVICE_NAME': '0', 05:04:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:04:28 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 05:04:28 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 05:04:28 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 05:04:28 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 05:04:28 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 05:04:28 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 05:04:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:04:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:04:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:04:31 INFO - Installing collected packages: mozsystemmonitor 05:04:31 INFO - Running setup.py install for mozsystemmonitor 05:04:31 INFO - Successfully installed mozsystemmonitor 05:04:31 INFO - Cleaning up... 05:04:31 INFO - Return code: 0 05:04:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:04: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')]} 05:04:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04: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')]} 05:04:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04: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', '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 0x10a6c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6934d65b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a1d30>, '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 0x10a3c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a496be8>, '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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:04: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:04: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 jsonschema==2.5.1 05:04:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZrsgR9RySp/Render', 05:04:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:31 INFO - 'HOME': '/Users/cltbld', 05:04:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:31 INFO - 'LOGNAME': 'cltbld', 05:04:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:31 INFO - 'MOZ_NO_REMOTE': '1', 05:04:31 INFO - 'NO_EM_RESTART': '1', 05:04:31 INFO - 'PAGER': '/bin/cat', 05:04:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:31 INFO - 'PWD': '/builds/slave/test', 05:04:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:31 INFO - 'SHELL': '/bin/bash', 05:04:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NgB6I0vLYP/Listeners', 05:04:31 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:04:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:31 INFO - 'USER': 'cltbld', 05:04:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:31 INFO - 'XPC_FLAGS': '0x0', 05:04:31 INFO - 'XPC_SERVICE_NAME': '0', 05:04:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:31 INFO - Downloading/unpacking jsonschema==2.5.1 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:04:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04:34 INFO - Downloading functools32-3.2.3-2.tar.gz 05:04:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:04:34 INFO - warning: no files found matching '*.txt' 05:04:34 INFO - no previously-included directories found matching 'build' 05:04:34 INFO - no previously-included directories found matching 'dist' 05:04:34 INFO - no previously-included directories found matching '.git*' 05:04:34 INFO - Installing collected packages: jsonschema, functools32 05:04:34 INFO - Running setup.py install for functools32 05:04:34 INFO - warning: no files found matching '*.txt' 05:04:34 INFO - no previously-included directories found matching 'build' 05:04:34 INFO - no previously-included directories found matching 'dist' 05:04:34 INFO - no previously-included directories found matching '.git*' 05:04:34 INFO - Successfully installed jsonschema functools32 05:04:34 INFO - Cleaning up... 05:04:35 INFO - Return code: 0 05:04:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a6c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6934d65b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a1d30>, '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 0x10a3c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a496be8>, '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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:04:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:04:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:04:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZrsgR9RySp/Render', 05:04:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:35 INFO - 'HOME': '/Users/cltbld', 05:04:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:35 INFO - 'LOGNAME': 'cltbld', 05:04:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:35 INFO - 'MOZ_NO_REMOTE': '1', 05:04:35 INFO - 'NO_EM_RESTART': '1', 05:04:35 INFO - 'PAGER': '/bin/cat', 05:04:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:35 INFO - 'PWD': '/builds/slave/test', 05:04:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:35 INFO - 'SHELL': '/bin/bash', 05:04:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NgB6I0vLYP/Listeners', 05:04:35 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:04:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:35 INFO - 'USER': 'cltbld', 05:04:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:35 INFO - 'XPC_FLAGS': '0x0', 05:04:35 INFO - 'XPC_SERVICE_NAME': '0', 05:04:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:04:36 INFO - Cleaning up... 05:04:36 INFO - Return code: 0 05:04:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:04: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')]} 05:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04: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')]} 05:04:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:36 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 0x10a6c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6934d65b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a1d30>, '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 0x10a3c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a496be8>, '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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:04:36 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 05:04:36 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 05:04:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZrsgR9RySp/Render', 05:04:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:36 INFO - 'HOME': '/Users/cltbld', 05:04:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:36 INFO - 'LOGNAME': 'cltbld', 05:04:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:36 INFO - 'MOZ_NO_REMOTE': '1', 05:04:36 INFO - 'NO_EM_RESTART': '1', 05:04:36 INFO - 'PAGER': '/bin/cat', 05:04:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:36 INFO - 'PWD': '/builds/slave/test', 05:04:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:36 INFO - 'SHELL': '/bin/bash', 05:04:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NgB6I0vLYP/Listeners', 05:04:36 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:04:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:36 INFO - 'USER': 'cltbld', 05:04:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:36 INFO - 'XPC_FLAGS': '0x0', 05:04:36 INFO - 'XPC_SERVICE_NAME': '0', 05:04:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:37 INFO - Downloading/unpacking blobuploader==1.2.4 05:04:37 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 05:04:37 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 05:04:37 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 05:04:37 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 05:04:37 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 05:04:37 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 05:04:40 INFO - Downloading blobuploader-1.2.4.tar.gz 05:04:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:04:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:04:40 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 05:04:40 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 05:04:40 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 05:04:40 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 05:04:40 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 05:04:40 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 05:04:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:04:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:04:40 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 05:04:40 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 05:04:40 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 05:04:40 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 05:04:40 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 05:04:40 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 05:04:41 INFO - Downloading docopt-0.6.1.tar.gz 05:04:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:04:41 INFO - Installing collected packages: blobuploader, requests, docopt 05:04:41 INFO - Running setup.py install for blobuploader 05:04:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:04:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:04:41 INFO - Running setup.py install for requests 05:04:42 INFO - Running setup.py install for docopt 05:04:42 INFO - Successfully installed blobuploader requests docopt 05:04:42 INFO - Cleaning up... 05:04:42 INFO - Return code: 0 05:04:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:42 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 0x10a6c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6934d65b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a1d30>, '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 0x10a3c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a496be8>, '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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:04:42 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 05:04:42 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 05:04:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZrsgR9RySp/Render', 05:04:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:42 INFO - 'HOME': '/Users/cltbld', 05:04:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:42 INFO - 'LOGNAME': 'cltbld', 05:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:42 INFO - 'MOZ_NO_REMOTE': '1', 05:04:42 INFO - 'NO_EM_RESTART': '1', 05:04:42 INFO - 'PAGER': '/bin/cat', 05:04:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:42 INFO - 'PWD': '/builds/slave/test', 05:04:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:42 INFO - 'SHELL': '/bin/bash', 05:04:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NgB6I0vLYP/Listeners', 05:04:42 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:04:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:42 INFO - 'USER': 'cltbld', 05:04:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:42 INFO - 'XPC_FLAGS': '0x0', 05:04:42 INFO - 'XPC_SERVICE_NAME': '0', 05:04:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:42 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-BWnmck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:42 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-mQnfJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:42 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-C7fiOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:42 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-aiCVnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:43 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-kZj6rT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:43 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-h2lCqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:43 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-0NdnP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:43 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-QZSwpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:43 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Xahbsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:43 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-LVrvR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:43 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-sVNZ9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:43 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-IaptQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:43 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-lYWM9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:43 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-hJFiAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:44 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-_QPy5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:44 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-V1DJd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:44 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-_NbX_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:04:44 INFO - Running setup.py install for manifestparser 05:04:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:04:44 INFO - Running setup.py install for mozcrash 05:04:44 INFO - Running setup.py install for mozdebug 05:04:44 INFO - Running setup.py install for mozdevice 05:04:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:04:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:04:44 INFO - Running setup.py install for mozfile 05:04:45 INFO - Running setup.py install for mozhttpd 05:04:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:04:45 INFO - Running setup.py install for mozinfo 05:04:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:04:45 INFO - Running setup.py install for mozInstall 05:04:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:04:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:04:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:04:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:04:45 INFO - Running setup.py install for mozleak 05:04:45 INFO - Running setup.py install for mozlog 05:04:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:04:45 INFO - Running setup.py install for moznetwork 05:04:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:04:45 INFO - Running setup.py install for mozprocess 05:04:46 INFO - Running setup.py install for mozprofile 05:04:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:04:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:04:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:04:46 INFO - Running setup.py install for mozrunner 05:04:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:04:46 INFO - Running setup.py install for mozscreenshot 05:04:46 INFO - Running setup.py install for moztest 05:04:46 INFO - Running setup.py install for mozversion 05:04:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:04:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:04:46 INFO - Cleaning up... 05:04:46 INFO - Return code: 0 05:04:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:46 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 0x10a6c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6934d65b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a1d30>, '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 0x10a3c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a496be8>, '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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:04:46 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 05:04:46 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 05:04:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZrsgR9RySp/Render', 05:04:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:46 INFO - 'HOME': '/Users/cltbld', 05:04:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:46 INFO - 'LOGNAME': 'cltbld', 05:04:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:46 INFO - 'MOZ_NO_REMOTE': '1', 05:04:46 INFO - 'NO_EM_RESTART': '1', 05:04:46 INFO - 'PAGER': '/bin/cat', 05:04:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:46 INFO - 'PWD': '/builds/slave/test', 05:04:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:46 INFO - 'SHELL': '/bin/bash', 05:04:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NgB6I0vLYP/Listeners', 05:04:46 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:04:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:46 INFO - 'USER': 'cltbld', 05:04:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:46 INFO - 'XPC_FLAGS': '0x0', 05:04:46 INFO - 'XPC_SERVICE_NAME': '0', 05:04:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:47 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-R1OAjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:47 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)) 05:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:47 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-ovVcH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:47 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)) 05:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:47 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-NLmLq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:47 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)) 05:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:47 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-YZCpZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:47 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)) 05:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:47 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-JKTIBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:47 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)) 05:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:47 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-rKZ7xR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:47 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)) 05:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:47 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-7wbipP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:47 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)) 05:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:47 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Wh6Uy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:48 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)) 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:48 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-51JXMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:48 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)) 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:48 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-93xXtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:48 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)) 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:48 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-BxRS8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:48 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)) 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:48 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-uRhbHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:48 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)) 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:48 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-FqbtpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:48 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)) 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:48 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-jiGZV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:48 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)) 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:48 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-zwUTUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:48 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)) 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:48 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-KFW1o9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:48 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)) 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:48 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-ZfcPvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:49 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)) 05:04:49 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)) 05:04:49 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)) 05:04:49 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)) 05:04:49 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)) 05:04:49 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)) 05:04:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:04:49 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 05:04:49 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 05:04:49 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 05:04:49 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 05:04:49 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 05:04:49 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 05:04:51 INFO - Downloading blessings-1.6.tar.gz 05:04:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:04:51 INFO - Installing collected packages: blessings 05:04:51 INFO - Running setup.py install for blessings 05:04:51 INFO - Successfully installed blessings 05:04:51 INFO - Cleaning up... 05:04:51 INFO - Return code: 0 05:04:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:04:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:52 INFO - Reading from file tmpfile_stdout 05:04:52 INFO - Current package versions: 05:04:52 INFO - blessings == 1.6 05:04:52 INFO - blobuploader == 1.2.4 05:04:52 INFO - docopt == 0.6.1 05:04:52 INFO - functools32 == 3.2.3-2 05:04:52 INFO - jsonschema == 2.5.1 05:04:52 INFO - manifestparser == 1.1 05:04:52 INFO - mozInstall == 1.12 05:04:52 INFO - mozcrash == 1.0 05:04:52 INFO - mozdebug == 0.1 05:04:52 INFO - mozdevice == 0.49 05:04:52 INFO - mozfile == 1.2 05:04:52 INFO - mozhttpd == 0.7 05:04:52 INFO - mozinfo == 0.9 05:04:52 INFO - mozleak == 0.1 05:04:52 INFO - mozlog == 3.4 05:04:52 INFO - moznetwork == 0.27 05:04:52 INFO - mozprocess == 0.24 05:04:52 INFO - mozprofile == 0.28 05:04:52 INFO - mozrunner == 6.13 05:04:52 INFO - mozscreenshot == 0.1 05:04:52 INFO - mozsystemmonitor == 0.3 05:04:52 INFO - moztest == 0.8 05:04:52 INFO - mozversion == 1.4 05:04:52 INFO - psutil == 3.1.1 05:04:52 INFO - requests == 1.2.3 05:04:52 INFO - wsgiref == 0.1.2 05:04:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:52 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 0x10a6c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6934d65b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a1d30>, '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 0x10a3c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a496be8>, '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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:04:52 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 05:04:52 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 05:04:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZrsgR9RySp/Render', 05:04:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:52 INFO - 'HOME': '/Users/cltbld', 05:04:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:52 INFO - 'LOGNAME': 'cltbld', 05:04:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:52 INFO - 'MOZ_NO_REMOTE': '1', 05:04:52 INFO - 'NO_EM_RESTART': '1', 05:04:52 INFO - 'PAGER': '/bin/cat', 05:04:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:52 INFO - 'PWD': '/builds/slave/test', 05:04:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:52 INFO - 'SHELL': '/bin/bash', 05:04:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NgB6I0vLYP/Listeners', 05:04:52 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:04:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:52 INFO - 'USER': 'cltbld', 05:04:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:52 INFO - 'XPC_FLAGS': '0x0', 05:04:52 INFO - 'XPC_SERVICE_NAME': '0', 05:04:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:52 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)) 05:04:52 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)) 05:04:52 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)) 05:04:52 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)) 05:04:52 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)) 05:04:52 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)) 05:04:52 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)) 05:04:52 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)) 05:04:52 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)) 05:04:52 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)) 05:04:52 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)) 05:04:52 INFO - Cleaning up... 05:04:52 INFO - Return code: 0 05:04:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:52 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 0x10a6c3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6c6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6934d65b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a1d30>, '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 0x10a3c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a496be8>, '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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:04:52 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 05:04:52 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 05:04:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZrsgR9RySp/Render', 05:04:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:52 INFO - 'HOME': '/Users/cltbld', 05:04:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:52 INFO - 'LOGNAME': 'cltbld', 05:04:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:52 INFO - 'MOZ_NO_REMOTE': '1', 05:04:52 INFO - 'NO_EM_RESTART': '1', 05:04:52 INFO - 'PAGER': '/bin/cat', 05:04:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:52 INFO - 'PWD': '/builds/slave/test', 05:04:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:52 INFO - 'SHELL': '/bin/bash', 05:04:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NgB6I0vLYP/Listeners', 05:04:52 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:04:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:52 INFO - 'USER': 'cltbld', 05:04:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:52 INFO - 'XPC_FLAGS': '0x0', 05:04:52 INFO - 'XPC_SERVICE_NAME': '0', 05:04:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:04:52 INFO - Cleaning up... 05:04:52 INFO - Return code: 0 05:04:52 INFO - Running post-action listener: _resource_record_post_action 05:04:52 INFO - Running post-action listener: _start_resource_monitoring 05:04:52 INFO - Starting resource monitoring. 05:04:52 INFO - [mozharness: 2017-03-21 12:04:52.809877Z] Finished create-virtualenv step (success) 05:04:52 INFO - [mozharness: 2017-03-21 12:04:52.810885Z] Running install step. 05:04:52 INFO - Running pre-action listener: _resource_record_pre_action 05:04:52 INFO - Running main action method: install 05:04:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:53 INFO - Reading from file tmpfile_stdout 05:04:53 INFO - Detecting whether we're running mozinstall >=1.0... 05:04:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:04:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:04:53 INFO - Reading from file tmpfile_stdout 05:04:53 INFO - Output received: 05:04:53 INFO - Usage: mozinstall [options] installer 05:04:53 INFO - Options: 05:04:53 INFO - -h, --help show this help message and exit 05:04:53 INFO - -d DEST, --destination=DEST 05:04:53 INFO - Directory to install application into. [default: 05:04:53 INFO - "/builds/slave/test"] 05:04:53 INFO - --app=APP Application being installed. [default: firefox] 05:04:53 INFO - mkdir: /builds/slave/test/build/application 05:04:53 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'] 05:04:53 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 05:05:07 INFO - Reading from file tmpfile_stdout 05:05:07 INFO - Output received: 05:05:07 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:05:07 INFO - Running post-action listener: _resource_record_post_action 05:05:07 INFO - [mozharness: 2017-03-21 12:05:07.708355Z] Finished install step (success) 05:05:07 INFO - [mozharness: 2017-03-21 12:05:07.708512Z] Running run-tests step. 05:05:07 INFO - Running pre-action listener: _resource_record_pre_action 05:05:07 INFO - Running main action method: run_tests 05:05:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:07 INFO - 2017-03-21 05:05:07.723 screenresolution[1623:7323] starting screenresolution argv=screenresolution get 05:05:07 INFO - 2017-03-21 05:05:07.725 screenresolution[1623:7323] Display 0: 1600x1200x32@60 05:05:07 INFO - 2017-03-21 05:05:07.731 screenresolution[1624:7325] starting screenresolution argv=screenresolution list 05:05:07 INFO - Available Modes on Display 0 05:05:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:05:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:05:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:05:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:05:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:05:07 INFO - Intel Iris: 05:05:07 INFO - Chipset Model: Intel Iris 05:05:07 INFO - Type: GPU 05:05:07 INFO - Bus: Built-In 05:05:07 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:07 INFO - Vendor: Intel (0x8086) 05:05:07 INFO - Device ID: 0x0a2e 05:05:07 INFO - Revision ID: 0x0009 05:05:07 INFO - Displays: 05:05:07 INFO - Display: 05:05:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:05:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:07 INFO - Display Serial Number: 1600x1200 60 05:05:07 INFO - Main Display: Yes 05:05:07 INFO - Mirror: Off 05:05:07 INFO - Online: Yes 05:05:07 INFO - Rotation: Supported 05:05:07 INFO - Return code: 0 05:05:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:05:07 INFO - Python 2.7.3 05:05:07 INFO - Return code: 0 05:05:07 INFO - grabbing minidump binary from tooltool 05:05:07 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')]} 05:05:07 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 0x10a4a1d30>, '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 0x10a3c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a496be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:05:07 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 05:05:07 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 05:05:07 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 05:05:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:05:08 INFO - Return code: 0 05:05:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:05:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:05:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:05:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:05:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:05:08 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/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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 05:05:08 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/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0175 --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 05:05:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZrsgR9RySp/Render', 05:05:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:08 INFO - 'HOME': '/Users/cltbld', 05:05:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:08 INFO - 'LOGNAME': 'cltbld', 05:05:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:05:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:08 INFO - 'MOZ_NO_REMOTE': '1', 05:05:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:05:08 INFO - 'NO_EM_RESTART': '1', 05:05:08 INFO - 'PAGER': '/bin/cat', 05:05:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:08 INFO - 'PWD': '/builds/slave/test', 05:05:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:05:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:08 INFO - 'SHELL': '/bin/bash', 05:05:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NgB6I0vLYP/Listeners', 05:05:08 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:05:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:08 INFO - 'USER': 'cltbld', 05:05:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:08 INFO - 'XPC_FLAGS': '0x0', 05:05:08 INFO - 'XPC_SERVICE_NAME': '0', 05:05:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:08 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/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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 05:05:08 INFO - mozversion application_buildid: 20170321032428 05:05:08 INFO - mozversion application_changeset: a0b80d99c2fe2d007c95820cbdbb8568ce8ee437 05:05:08 INFO - mozversion application_display_name: Firefox 05:05:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:05:08 INFO - mozversion application_name: Firefox 05:05:08 INFO - mozversion application_remotingname: firefox 05:05:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:05:08 INFO - mozversion application_vendor: Mozilla 05:05:08 INFO - mozversion application_version: 53.0 05:05:08 INFO - mozversion platform_buildid: 20170321032428 05:05:08 INFO - mozversion platform_changeset: a0b80d99c2fe2d007c95820cbdbb8568ce8ee437 05:05:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:05:08 INFO - mozversion platform_version: 53.0 05:05:08 INFO - using testdate: 1490097908 05:05:08 INFO - actual date: 1490097908 05:05:08 INFO - starting webserver on 'localhost:49241' 05:05:08 INFO - SUITE-START | Running 2 tests 05:05:08 INFO - TEST-START | dromaeo_css 05:05:08 INFO - operating with platform_type : mac_ 05:05:08 INFO - Initialising browser for dromaeo_css test... 05:05:08 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpjKOuGM/profile http://localhost:49241/getInfo.html) 05:05:11 INFO - PROCESS | 1634 | 2017-03-21 05:05:11.294 plugin-container[1637:7495] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 05:05:11 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:05:11 INFO - PROCESS | 1634 | 2017-03-21 05:05:11.295 plugin-container[1637:7495] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 05:05:11 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:05:11 INFO - PROCESS | 1634 | 2017-03-21 05:05:11.295 plugin-container[1637:7495] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:05:11 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 05:05:11 INFO - PROCESS | 1634 | colorDepth:24 05:05:11 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 05:05:11 INFO - PROCESS | 1634 | __metrics 05:05:11 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:05:12 INFO - TEST-INFO | 1634: exit 0 05:05:12 INFO - Browser initialized. 05:05:12 INFO - Running cycle 1/1 for dromaeo_css test... 05:05:12 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpjKOuGM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:05:14 INFO - PROCESS | 1638 | 2017-03-21 05:05:14.444 plugin-container[1639:7632] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 05:05:14 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:05:14 INFO - PROCESS | 1638 | 2017-03-21 05:05:14.444 plugin-container[1639:7632] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880f, name = 'com.apple.CFPasteboardClient' 05:05:14 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:05:14 INFO - PROCESS | 1638 | 2017-03-21 05:05:14.444 plugin-container[1639:7632] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:05:24 INFO - PROCESS | 1638 | RSS: Main: 172621824 05:05:24 INFO - PROCESS | 1638 | 05:08:25 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 05:08:27 INFO - PROCESS | 1638 | RSS: Main: 192471040 05:08:27 INFO - PROCESS | 1638 | 05:11:29 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 05:11:30 INFO - PROCESS | 1638 | RSS: Main: 204058624 05:11:30 INFO - PROCESS | 1638 | 05:14:32 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 05:14:33 INFO - PROCESS | 1638 | RSS: Main: 209850368 05:14:33 INFO - PROCESS | 1638 | 05:17:34 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 05:17:35 INFO - PROCESS | 1638 | RSS: Main: 212992000 05:17:35 INFO - PROCESS | 1638 | 05:20:38 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 05:20:38 INFO - PROCESS | 1638 | RSS: Main: 214114304 05:20:38 INFO - PROCESS | 1638 | 05:23:41 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 05:23:41 INFO - PROCESS | 1638 | RSS: Main: 214609920 05:23:41 INFO - PROCESS | 1638 | 05:23:41 INFO - PROCESS | 1638 | __start_tp_report 05:23:41 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 05:23:41 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 05:23:41 INFO - PROCESS | 1638 | |i|pagename|runs| 05:23:41 INFO - PROCESS | 1638 | |0;dojo.html;3520.0495866116134;3562.857485700572;3582.301451217014;3587.833953964962;3589.192431702867;4355.803988820503;5573.470520300575;5964.910526342108;5974.163617093605;5996.430339117782;5911.674857882815;5969.432903874125;5980.88038239235;6058.424449677284;6064.151018857364;5315.787368505265;5359.651622644516;5398.2982212312345;5411.485908838654;5425.593080518976;5426.782928682848;5448.891022179554;5462.3445186577665;5463;5464;5354;5356.571474282048;5436.374816896068;5436.565074794007;5509.752061157237;5394.541463975567;5397.568194544447;5433.782648694048;5449.945500544984;5477.945220547784;4894;5374.865628359323;5394.02907476654;5394.91907621386;5417.702026388547;5839.124131380285;5986.970065149646;6047.274327080734;6069.119977603223;6113.633182009094;6232.189815323979;6258.499320054385;6259.749610015594;6308.589941653778;6320;6356.872862542723;6367.044943258502;6371.553991220617;6372.044193370985;6396.872062558769;6121.693915304306;6131.754729810757;6146.1395404642935;6147.139400483936;6154.569180157391;5351.464853514695;6277.1525844010475;6277.780277690259;6389.968050159812;6393.264774550986;6286.748530058838;6317.936820631827;6319.24169099711;6334.556581039283;6335.714892829834;6360.936390636127;6396.2964073951825;6408.743650253938;6410.935890641127;6420.293767685551;6231.9688401557705;6235.282942461584;6279.843003924939;6282.4974002079725;6338.239411270677;6250.531210159256;6299.716512756937;6322.083297921776;6329.177206963065;6336.809895703136;5132.8460146195675;5140.485951404904;5159.17453207485;5162.6902385856965;5179.922301165486;4447.710898791568;4576.748278844696;4618.122556714214;4622.260438329851;4668.579827815445;6065.878682426327;6083.787067452707;6121.816345509642;6133.558613725771;6156.6921653917125;6394.904076438764;6399.7440102395385;6411.0383442484;6414.743410263631;6421.8073457796345;5971.701414929236;6031.728572214261;6067.969660151759;6073.5748497604745;6102.084687296852;5690.118031705092;5691.288588926384;5697.401772813878;5714.171445140431;5724.79963201286;6521.739130434825;6528.673566321665;6532.379423954717;6533.771318003847;6576.506761992774;6219.315875253809;6254.8123556293385;6267.96866015667;6319.715612797527;6339.017452294902;6480.805575832733;6497.285298617243;6501.544891857714;6519.282878883224;6558;6322.209723784527;6325.588836725598;6332.271788744353;6337.683115844097;6348.587341822767;6153.969230153821;6200.875982480416;6266.68666566679;6354.936450635434;6367.61794292344;5354.223637572608;6195.411435913581;6239.344868788712;6287.937120628735;6317.5893566918;6247.918271979355;6282.403171698681;6282.842928926813;6288.968555157195;6309.621422714652;6037.667928263988;6121.209103132385;6160.784372547118;6228.688565571794;6235.688215589294;6178;6193.566450348432;6212.285587157354;6281.120643109877;6281.246250449975;6204.503639708722;6207.068939659087;6214.161088253209;6224.128621992834;6227.968860155852 05:23:41 INFO - PROCESS | 1638 | |1;ext.html;494.1574615280946;499.00946620957416;513.3598153898295;516.50157597918;550.1527647422969;40403.59596404035;43150.48973285936;43371.99999999992;43406.34890476641;43440.13119737611;44411.333829992494;44685;44888.551114488844;44969.10061798762;44997.550024499746;9462.479563624016;9763.218942484584;9781.217502599775;11331.37677427741;11735.941320293387;11634.185607007514;11731.120165987584;11739.647810565653;11740.119491038206;11804.881951180465;9806.803863922718;10037.899621003806;11615.767684646302;11691;11762.117841161904;11579.131565132564;11603.013743831785;11664.125190610695;11688;11706.356150411724;11669.70825729355;11673;11712.824307635392;11732.35472049037;11750.29498230109;34474.482882756776;35647.82176089129;36617.63382366169;38922.22155556901;39268;3972.5828787977425;4111.301078816578;4140.37894315855;4184.849166479212;4195.664346852244;28730.712692873014;28921.85539072291;29135.125946221437;29179.562306565418;30824.84587577048;3033.211365045082;3085.213270616001;3208.6630903755004;3257.03917344384;3258.902232933016;513887.4305628448;513963;514781;517372;517793;60432.79134417287;60560.697196514615;60788.69605651943;60818.99999999911;60819.391806081374;62945.68527157335;63012.684936575024;63119.684401577695;63230.36769632337;63273.68363158247;16607.837451378527;17164.313427462763;17171.399001034904;17187.742183867253;17208.827911720724;10690.305130166515;12408.379581020912;13786.034977551475;14006.649833754034;14034.929825350808;21752.891235544037;22256.55486890253;22503.987320570286;22533.774662253167;22591;9038.18656320921;9206.631734730536;9221.81556368869;9234;9259.53702314895;899.6941040046285;906.746111088883;906.895706993691;931.8648795924688;948.8339540580371;31073.067807965796;31568.368632627677;31644.525332120036;31958.041258762274;32414.35171296561;940.6331530703031;957.6600306891092;1139.6865861888048;1150.7813515432042;1156.4217891054473;15353.385864565293;16251.918740406223;16423;16473.83526164747;16694.91652541754;656403;670570;671031;672230;672726;49515.51453456402;49732.50267497351;50016.749916250184;50018;50125.49874501281;1467.9266036698123;1476.7194233095463;1500.2348802111003;1522.3986525322437;1525.9542213733605;30121.09636710902;31201.53197701991;31290.21774455657;31460.595726009986;31876;54473.72763136159;54655;54762;54867;54871.72564137154;565011;566295;567586;568030;568547;37755.867323980325;38499;43698.56301436945;44481.33278000768;45275;47956.28065579089;48137.277940830216;48829.5117048825;50119.749401254216;50425.49574504208;39897.40153897636;41081;41403;41888.371674425514;42550.78724606357;39973.40039899346;39977.40033899552;40729.796351018056;40975.59024409718;41763.58236417597;38336.42495362516;38523.614763852005;39092.02269943274;39161.804190978866;39217.21565568616;25861.712903321095;35204.647953520136;35740;35959;35964;22036.669449957943;26019.089331873292;26032.609510856975;26075.348116297246;26472 05:23:41 INFO - PROCESS | 1638 | |2;jquery.html;389.5014381591564;403.30430008235777;403.5238418665973;414.4446441768031;414.58748545197517;16332.918335408322;17089.316427342892;18105.637887242265;21355.67966480504;21823.890880545594;21951.0122044508;22117;22181.00185491653;22269.109235630556;22516.662250066263;5666.57500687448;6058.1518587397795;6071.150038994544;6115.663638499881;6117.113018612298;5826.329972053205;6018.699065046752;6040.758369665208;6058.121572372003;6081.756729730805;5014.398272207322;5897.64614123154;5918.852028699274;6000.2199714037115;6011.759529618832;5738.741756620962;5889.087191485327;5970.552208584373;6032.366601506845;6037.034074548095;5238.266642670029;5322.0154271459905;5330.200469929541;5366.141417373241;5386.434424385461;25871.70641467919;26906.461870762672;27044;27256.59115113275;27344.726552734617;2848.700886406918;2961.185673939662;3130.9530357044664;3195.760317976161;3236.935261294761;15640.374385024585;15774.211289435598;15986.920065399714;16177.59556011109;16458.83541164585;1923.1826473748602;1932.8357089647398;1949.4638974282045;1971.6155349706812;2011.3664195778283;136866.31566842203;137585.31207343898;140110;140942;141216.9827823171;11888.465019074163;11961.46173422198;12003;12009.879901200877;12079.698007549881;12941.741165176833;13452.730945381038;15370.616644501824;15402.921795474433;15426.151561663992;7932.682692692228;9240.815183696423;9333.673321433716;9359.017303183204;9533.04669533055;9481.431114133175;9506.334556580972;9543.570539325747;9577;9694.951525242328;6968.95465680152;7299.489035767445;7645.350145237606;7691.923080769233;7773.222677732181;1813.5466133466634;1908.7136929460692;2033.7051127586417;2077.2625717869937;2085.91656333748;916.463868636855;1107.545906178463;1113.3486910157487;1127.7349822791637;1164.9359285239393;17881.910590446965;23011.424714382276;23130.496517726293;23147.76852231456;23359.41601459977;1028.1569113327;1129.8418221449003;1134.6709454258337;1148.5750272399098;1181.4919584578713;10716.517756700967;11231.876812318866;12052.397380130958;12224.266544007389;12238.877611223774;803411;804317;807223;817985.8201418029;825002.8749856212;135614;140265.5973440273;140629;140701;142604;1051.9789604207872;1062.18211976779;1075.9946200268948;1081.3944191252924;1082.71849319176;19312.7446715963;20023.416879998273;20253.898730506255;20265.189392424138;20339.491512712302;43437.348439772795;49540.50459495359;57184.142237867294;58556.70721646365;60010.69994649999;64722.67638661965;68596;77065.68802935921;81797;84838;8511.74464766058;8580.099089595753;8595.624700047962;8694;8775.561221939006;13501.662458438224;13595.728085438039;13641.317934103454;13788.172709637454;13964.581062568139;11571.19001669875;11767.470463828977;11899.345535995604;11935.582254621047;12089.51641934348;11563;11630.069594432256;11638.767224655629;11804.350760708243;12161.39193040362;11334.263272887565;11931.284122952651;12108.455119519467;12155.81766273489;12228.082893782825;7403.629818509161;7770.961145194238;7899.289063984269;8056.637451314822;8828.735137945872;10059.29584929048;11566.421678915853;11697.766044679229;11937.701557461132;12013.879861201276 05:23:41 INFO - PROCESS | 1638 | |3;mootools.html;1432.31964816712;1485.925703714814;1489.9627509312265;1492.910425374477;1501.654619437529;6850.794476165723;7741.806454838632;8269.751907442778;8683.478991260528;8697.086805885378;8101.756947291576;8464.026636936755;8467.788305292372;8479.19447652472;8518.87221691675;2364.2788949370433;2426.0659646036315;2429.574824405731;2430.1251549442186;2432.914847980321;2025.8581899267058;2399.2802159352213;2432.756724327562;2437.390652336916;2449.106076282162;2296.6555016747457;2298.7586303438147;2315.9536809263805;2325.232673217834;2333.649952507129;2351.5061837014246;2356.905723771047;2358.41039740065;2360.3391050505884;2383.5590415772963;2374.620060790265;2410.890470838017;2438.1222759806456;2438.512297540499;2443.767842055002;7266.45501587383;8351.039630442516;8375.701766226244;8396.790080247982;8489;5163.096458119851;5257.7108259045735;5288.2596436498925;5308.814191503279;5311.867203319909;3434.004138799745;3472.3055388922317;3480.860765569374;3529.4529347951107;3542.5394698689265;1181.5687274144962;1193.1468999665194;1220.6765207220094;1240.3922078181627;1241.0381953985643;10647.14822814173;10881.347119172875;10905.127589792797;10933.343999360064;10942.671719848417;4644.774748045129;4720.150372932836;4766.856994290177;4839.274108883696;4849.539293767087;9618.855717164248;9632;9632.95183524092;9668;9669.74293341875;5347.2781174541715;5359.5712343012465;5387.6228663993925;5391.2182733504205;5419.268398766116;4403.405540252089;4515.571020753023;4769.904601907942;4897.4857639948;4910.435299940481;3974.5627980922077;3999.64003239704;4028.657564107025;4090.304648209752;4110.034141976633;1136.9545218191345;1309.5285697149018;1332.8134061231435;1339.77223871942;1344.6167842165132;941.1811723800338;963.1139351796364;972.6206779356057;974.1378879691524;1003.0972125087334;4988.90022199554;5497.47773961473;5727.828165155052;5745.971270143623;5796.88406231873;588.9705514724246;597.560792817279;600.2347007565389;604.9395060493915;605.5488660947623;3799.1072098056934;3807.057492819014;3821.5796262411113;3862.111714305669;3866.7873266970587;9770.75573110678;10114;10139.188864890792;10146.036126567964;10156.187504999582;10042.146417554444;10042.949785251027;10073.043060909202;10096.293259471913;10112.191024718006;7952.443328967032;8015;8031.879521807061;8033.638486268132;8034.558099304594;5493.340799104133;5537.501624853782;5550.639208451437;5757.884842303214;5772.740226689725;6878.415334696607;9145.77135571616;9210.447373157633;9481.146696797321;9495.240380769385;9179.770505737142;9345.906540934504;9407.435553866517;9426.575804088696;9575.23398128162;6495.675216239264;7214.242504536948;7215;7219.736546104516;7308.96345518269;7926.365890728613;8012.3590112792;8024.197580241929;8033.598320084089;8043.718469853527;6335.891219036743;6481.287058423664;7060.858782824417;7206.423486121203;7226.494145409981;6940.375366217065;7034.261402552863;7085.539439936594;7104.289571042855;7133.6076515790055;6456.322086181071;6460.224773027267;6522.934770652233;6533.901991470037;6551.115599394021;5053.545180933734;5063.215201643751;5112.948870511247;5137.383513978347;5145.331106956071;5087.974560127176;5165.405978312542;5185.662931909414;5207.557357624644;5219.19102539107 05:23:41 INFO - PROCESS | 1638 | |4;prototype.html;366.7561071887195;372.1737742212289;380.36479079936504;384.6077001458511;387.14827379764523;4857.951420485799;5022.949770502294;5310.88471421002;5618.129189975555;5667.829965101048;4328.610425061744;5092.083424983502;5441.619086663935;5613.354464236614;5650.576206784487;3835.0987517933263;4318.827246910136;4363.410939523155;4377.802998865042;4384.5834645708765;3703.3889408247605;3901.8294511646536;4035.244668569167;4173.436586060889;4391.231534481469;3920.8825484736853;4224.929784405635;4287.335463003239;4302.354646802974;4317.330813723862;3950.861719839806;4295.871123866289;4299.570042995691;4303.440552728125;4316.935245971313;3064.3258483133677;3420.435628121357;3864.652181303696;3871.8644847430487;3872.1094148345755;5256.763445644917;5350.438203988603;5378.731063446851;5432.538234250093;5461.848994764008;4133.256013917494;4307.741535507879;4375.8249670013165;5041.6722913010535;5044.772985215674;5609.045622835891;5667.178259152441;5920.822375328747;5920.911186332209;5962.642241465526;3494.2662040971427;4231.936520952188;4276.374977508527;4290.485141782975;4293.801118166985;6067;6141.877162456726;6172.598781079216;6178.6943984968275;6264.373562643699;3550.8814723362057;3743.9251214975548;4351.888399794162;4376.912461750747;4390.692651514427;3901.8244179012013;4382.715123516961;4408.98593323531;4441.267190913528;4453.51011388747;4270.44484217056;5040.168372218616;5048.394192696826;5063.936573319645;5064.265681476165;5013.974930125326;5544.11705912923;5684.953416769959;5992.940070599326;6072.785442911435;1246.3830403950103;1307.235267368581;1445.2484707951805;1455.0542147604142;1458.8832893368506;846.4582667093065;984.414273507262;1016.8983101689772;1023.3677284730842;1026.6560702164709;4395.000274874924;4936.210206367035;5086.3133476980875;5089.389273287229;5270.367555893241;5384.219288203189;5613.989481893217;5809.82570522885;5879.500242479352;5907.940920590825;4722.976385118052;4974.676646017997;5087.26234695967;5270.183121616155;5290.851943093853;2918.6643535993207;3065.26433655921;3066.739327157172;3071.5699802027734;3074.200707815939;4966.627502937295;5284.101702710472;6023.247094113236;6072.453479186895;6091.725872335755;5121.436641969381;5437.293151890229;5492.80775172867;5899.646021238739;5998.980173370537;4986.825461108844;5131.870988382475;5432.402435732066;5517.806876759455;5840.8247752567495;5563.638363506359;5594.664320140804;5724.599278050497;5799.449052340021;5800.8259752207505;4936.3829521309835;5268.920966185587;5660.009498337857;5700.916825803084;5727;5179.6015075930045;5515.4760297771645;5682.318121825408;6037.245344331958;6078.939210608014;3546.680798728127;3885.9028524285986;4049.716519843583;4103.220388126247;4173.853915113078;6103.772123175098;6119.632822030692;6132.509399247961;6142.740738148579;6193.194884664965;5219.686818790885;5948.315943666361;5954.213735879132;5954.821355359346;5958.076498142795;5549.306336707911;5869.383714710065;5943.316518600243;5980.073088671263;5981;5360.490753378423;5838.970805145947;5859.882802344015;5864.621813873651;5913.91129133072;5171.224316352577;5443.319585051869;5500.909654331328;5852.561057920758;5926.481245470572;4731.9763401184155;4840.75796210181;5775.8556036099435;5780.682062486603;5822.796202132905 05:23:41 INFO - PROCESS | 1638 | |5;yui.html;724.974625888094;733.7982054934893;784.9960750196252;868.6829307302835;871.577285016767;15486.380544778209;16119.113448760314;16440;16476.58808529786;16526;14875.330610122543;15034;15200.467983620576;15226.162561059138;15331.77002344963;698.7624207769358;722.1406526233791;878.7363790862731;898.9235914947204;903.5075883643398;864.7492227254089;887.6360692116234;890.2566357091836;892.3708785306344;899.7210864631985;927.6706769096975;945.1776954049986;946.5835032585641;947.5972711597543;948.9383190092657;930.878985731854;934.6261495401826;935.8409070458005;937.2548823685187;937.4375374775149;673.6934694713942;687.6767919078026;702.8488874891872;703.3845385287873;705.2031204738613;14871.405143794234;15422.922885385613;15441.382344706199;16300.755488667679;16565.840391172624;14778.113313201242;14858;14925.955183137185;14943.701125977528;15139.697206055816;10553.960606136225;11395.803440638696;11879.524819007229;11910.225835320764;11910.34493102879;3622.257437225389;3759.642833930772;3821.254855303217;3919.2945269851416;4042.3734321180264;98069.50965245128;106830.46584767026;107923.46038269758;108173;110723.44638276838;10946;11380.886191138148;11454.713632159262;11615.477303521362;11712.297262164297;23690.881545592507;23916.52166956651;23939.880300598386;24008.63987040196;24046.87976560106;7722.459427839997;7724.691012359555;7830.921690783133;7969.425786777698;8245.505269683717;4317.222899878052;4345.351043643314;4351.412559304454;4424.557544245549;4476.7090139140855;3657.140571965586;3704.5739739930254;3707.721920855947;3713.034611001159;3973.106051138471;758.9468737188454;762.2111114995953;766.4290103872539;783.702193166593;784.752802867095;691.6438034412282;702.1118285369013;703.7923812475332;728.3663213004614;740.3780824107776;10820.350778953289;11053.337388018546;11478.139139564566;11615.593454229062;11806;589.2339958054512;591.8461200088008;618.264265524025;658.7200439813156;755.8148253677884;5006.549410553068;5147.455763271004;5212.466848790626;5332.986732520809;5334.91997620036;102628.48685756524;104496;105700.9429905691;105908;106171.9382806162;83056.67773289;88193.55903220571;91236;93727;94083.05916940879;82867.75698364529;83570;85180.14819851723;85187;85404.14595853962;9998.900010999796;10200.38797672142;10261.794764104825;10266.691999240034;10276.794464110526;43239.135217296105;51166.74416628042;51602.48397515977;51688.96622067613;53850.653733656975;61047.389526105944;68436;68789.93630191102;68861.2784680387;68985.65507172432;12000.099992500773;12025.098117641386;12169;12278.201916875374;12766.914812241064;13091.214527128403;13263.86736132665;13569.321533923066;14046.706169397085;14200.360983755962;12231.633051008484;12457.688557786129;12582.30797306157;12700.936495317465;13100.475980960655;11830.11274154459;12209;12341.938290308492;12601;12792.104552681223;10275.486225688535;10365.274430789772;10520.368777873353;11299.548018079186;12138.393080346124;9489.193418559224;9923.603055877684;10134.847977280495;10552.261341706007;10641.062015619584;8059.002342153738;9762.951185244028;10140.188784897338;10174.186065114922;10252.231082668979 05:23:41 INFO - PROCESS | 1638 | __end_tp_report 05:23:41 INFO - PROCESS | 1638 | __start_cc_report 05:23:41 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,371 05:23:41 INFO - PROCESS | 1638 | __end_cc_report 05:23:41 INFO - PROCESS | 1638 | __startTimestamp1490099021620__endTimestamp 05:23:41 INFO - PROCESS | 1638 | 05:23:41 INFO - PROCESS | 1638 | ------- Summary: start ------- 05:23:41 INFO - PROCESS | 1638 | Number of tests: 6 05:23:41 INFO - PROCESS | 1638 | 05:23:41 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5917.16 Median:6193.57 stddev:633.75 (10.2%) stddev-sans-first:607.15 05:23:41 INFO - PROCESS | 1638 | Values: 3520.0 3562.9 3582.3 3587.8 3589.2 4355.8 5573.5 5964.9 5974.2 5996.4 5911.7 5969.4 5980.9 6058.4 6064.2 5315.8 5359.7 5398.3 5411.5 5425.6 5426.8 5448.9 5462.3 5463.0 5464.0 5354.0 5356.6 5436.4 5436.6 5509.8 5394.5 5397.6 5433.8 5449.9 5477.9 4894.0 5374.9 5394.0 5394.9 5417.7 5839.1 5987.0 6047.3 6069.1 6113.6 6232.2 6258.5 6259.7 6308.6 6320.0 6356.9 6367.0 6371.6 6372.0 6396.9 6121.7 6131.8 6146.1 6147.1 6154.6 5351.5 6277.2 6277.8 6390.0 6393.3 6286.7 6317.9 6319.2 6334.6 6335.7 6360.9 6396.3 6408.7 6410.9 6420.3 6232.0 6235.3 6279.8 6282.5 6338.2 6250.5 6299.7 6322.1 6329.2 6336.8 5132.8 5140.5 5159.2 5162.7 5179.9 4447.7 4576.7 4618.1 4622.3 4668.6 6065.9 6083.8 6121.8 6133.6 6156.7 6394.9 6399.7 6411.0 6414.7 6421.8 5971.7 6031.7 6068.0 6073.6 6102.1 5690.1 5691.3 5697.4 5714.2 5724.8 6521.7 6528.7 6532.4 6533.8 6576.5 6219.3 6254.8 6268.0 6319.7 6339.0 6480.8 6497.3 6501.5 6519.3 6558.0 6322.2 6325.6 6332.3 6337.7 6348.6 6154.0 6200.9 6266.7 6354.9 6367.6 5354.2 6195.4 6239.3 6287.9 6317.6 6247.9 6282.4 6282.8 6289.0 6309.6 6037.7 6121.2 6160.8 6228.7 6235.7 6178.0 6193.6 6212.3 6281.1 6281.2 6204.5 6207.1 6214.2 6224.1 6228.0 05:23:41 INFO - PROCESS | 1638 | 05:23:41 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:72585.37 Median:21620.29 stddev:156678.24 (724.7%) stddev-sans-first:157024.26 05:23:41 INFO - PROCESS | 1638 | Values: 494.2 499.0 513.4 516.5 550.2 40403.6 43150.5 43372.0 43406.3 43440.1 44411.3 44685.0 44888.6 44969.1 44997.6 9462.5 9763.2 9781.2 11331.4 11735.9 11634.2 11731.1 11739.6 11740.1 11804.9 9806.8 10037.9 11615.8 11691.0 11762.1 11579.1 11603.0 11664.1 11688.0 11706.4 11669.7 11673.0 11712.8 11732.4 11750.3 34474.5 35647.8 36617.6 38922.2 39268.0 3972.6 4111.3 4140.4 4184.8 4195.7 28730.7 28921.9 29135.1 29179.6 30824.8 3033.2 3085.2 3208.7 3257.0 3258.9 513887.4 513963.0 514781.0 517372.0 517793.0 60432.8 60560.7 60788.7 60819.0 60819.4 62945.7 63012.7 63119.7 63230.4 63273.7 16607.8 17164.3 17171.4 17187.7 17208.8 10690.3 12408.4 13786.0 14006.6 14034.9 21752.9 22256.6 22504.0 22533.8 22591.0 9038.2 9206.6 9221.8 9234.0 9259.5 899.7 906.7 906.9 931.9 948.8 31073.1 31568.4 31644.5 31958.0 32414.4 940.6 957.7 1139.7 1150.8 1156.4 15353.4 16251.9 16423.0 16473.8 16694.9 656403.0 670570.0 671031.0 672230.0 672726.0 49515.5 49732.5 50016.7 50018.0 50125.5 1467.9 1476.7 1500.2 1522.4 1526.0 30121.1 31201.5 31290.2 31460.6 31876.0 54473.7 54655.0 54762.0 54867.0 54871.7 565011.0 566295.0 567586.0 568030.0 568547.0 37755.9 38499.0 43698.6 44481.3 45275.0 47956.3 48137.3 48829.5 50119.7 50425.5 39897.4 41081.0 41403.0 41888.4 42550.8 39973.4 39977.4 40729.8 40975.6 41763.6 38336.4 38523.6 39092.0 39161.8 39217.2 25861.7 35204.6 35740.0 35959.0 35964.0 22036.7 26019.1 26032.6 26075.3 26472.0 05:23:41 INFO - PROCESS | 1638 | 05:23:41 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:42434.21 Median:22034.01 stddev:134344.56 (609.7%) stddev-sans-first:134684.33 05:23:41 INFO - PROCESS | 1638 | Values: 389.5 403.3 403.5 414.4 414.6 16332.9 17089.3 18105.6 21355.7 21823.9 21951.0 22117.0 22181.0 22269.1 22516.7 5666.6 6058.2 6071.2 6115.7 6117.1 5826.3 6018.7 6040.8 6058.1 6081.8 5014.4 5897.6 5918.9 6000.2 6011.8 5738.7 5889.1 5970.6 6032.4 6037.0 5238.3 5322.0 5330.2 5366.1 5386.4 25871.7 26906.5 27044.0 27256.6 27344.7 2848.7 2961.2 3131.0 3195.8 3236.9 15640.4 15774.2 15986.9 16177.6 16458.8 1923.2 1932.8 1949.5 1971.6 2011.4 136866.3 137585.3 140110.0 140942.0 141217.0 11888.5 11961.5 12003.0 12009.9 12079.7 12941.7 13452.7 15370.6 15402.9 15426.2 7932.7 9240.8 9333.7 9359.0 9533.0 9481.4 9506.3 9543.6 9577.0 9695.0 6969.0 7299.5 7645.4 7691.9 7773.2 1813.5 1908.7 2033.7 2077.3 2085.9 916.5 1107.5 1113.3 1127.7 1164.9 17881.9 23011.4 23130.5 23147.8 23359.4 1028.2 1129.8 1134.7 1148.6 1181.5 10716.5 11231.9 12052.4 12224.3 12238.9 803411.0 804317.0 807223.0 817985.8 825002.9 135614.0 140265.6 140629.0 140701.0 142604.0 1052.0 1062.2 1076.0 1081.4 1082.7 19312.7 20023.4 20253.9 20265.2 20339.5 43437.3 49540.5 57184.1 58556.7 60010.7 64722.7 68596.0 77065.7 81797.0 84838.0 8511.7 8580.1 8595.6 8694.0 8775.6 13501.7 13595.7 13641.3 13788.2 13964.6 11571.2 11767.5 11899.3 11935.6 12089.5 11563.0 11630.1 11638.8 11804.4 12161.4 11334.3 11931.3 12108.5 12155.8 12228.1 7403.6 7771.0 7899.3 8056.6 8828.7 10059.3 11566.4 11697.8 11937.7 12013.9 05:23:41 INFO - PROCESS | 1638 | 05:23:41 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5483.03 Median:5213.37 stddev:2965.42 (56.9%) stddev-sans-first:2958.11 05:23:41 INFO - PROCESS | 1638 | Values: 1432.3 1485.9 1490.0 1492.9 1501.7 6850.8 7741.8 8269.8 8683.5 8697.1 8101.8 8464.0 8467.8 8479.2 8518.9 2364.3 2426.1 2429.6 2430.1 2432.9 2025.9 2399.3 2432.8 2437.4 2449.1 2296.7 2298.8 2316.0 2325.2 2333.6 2351.5 2356.9 2358.4 2360.3 2383.6 2374.6 2410.9 2438.1 2438.5 2443.8 7266.5 8351.0 8375.7 8396.8 8489.0 5163.1 5257.7 5288.3 5308.8 5311.9 3434.0 3472.3 3480.9 3529.5 3542.5 1181.6 1193.1 1220.7 1240.4 1241.0 10647.1 10881.3 10905.1 10933.3 10942.7 4644.8 4720.2 4766.9 4839.3 4849.5 9618.9 9632.0 9633.0 9668.0 9669.7 5347.3 5359.6 5387.6 5391.2 5419.3 4403.4 4515.6 4769.9 4897.5 4910.4 3974.6 3999.6 4028.7 4090.3 4110.0 1137.0 1309.5 1332.8 1339.8 1344.6 941.2 963.1 972.6 974.1 1003.1 4988.9 5497.5 5727.8 5746.0 5796.9 589.0 597.6 600.2 604.9 605.5 3799.1 3807.1 3821.6 3862.1 3866.8 9770.8 10114.0 10139.2 10146.0 10156.2 10042.1 10042.9 10073.0 10096.3 10112.2 7952.4 8015.0 8031.9 8033.6 8034.6 5493.3 5537.5 5550.6 5757.9 5772.7 6878.4 9145.8 9210.4 9481.1 9495.2 9179.8 9345.9 9407.4 9426.6 9575.2 6495.7 7214.2 7215.0 7219.7 7309.0 7926.4 8012.4 8024.2 8033.6 8043.7 6335.9 6481.3 7060.9 7206.4 7226.5 6940.4 7034.3 7085.5 7104.3 7133.6 6456.3 6460.2 6522.9 6533.9 6551.1 5053.5 5063.2 5112.9 5137.4 5145.3 5088.0 5165.4 5185.7 5207.6 5219.2 05:23:41 INFO - PROCESS | 1638 | 05:23:41 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4706.41 Median:5126.65 stddev:1409.60 (27.5%) stddev-sans-first:1375.40 05:23:41 INFO - PROCESS | 1638 | Values: 366.8 372.2 380.4 384.6 387.1 4858.0 5022.9 5310.9 5618.1 5667.8 4328.6 5092.1 5441.6 5613.4 5650.6 3835.1 4318.8 4363.4 4377.8 4384.6 3703.4 3901.8 4035.2 4173.4 4391.2 3920.9 4224.9 4287.3 4302.4 4317.3 3950.9 4295.9 4299.6 4303.4 4316.9 3064.3 3420.4 3864.7 3871.9 3872.1 5256.8 5350.4 5378.7 5432.5 5461.8 4133.3 4307.7 4375.8 5041.7 5044.8 5609.0 5667.2 5920.8 5920.9 5962.6 3494.3 4231.9 4276.4 4290.5 4293.8 6067.0 6141.9 6172.6 6178.7 6264.4 3550.9 3743.9 4351.9 4376.9 4390.7 3901.8 4382.7 4409.0 4441.3 4453.5 4270.4 5040.2 5048.4 5063.9 5064.3 5014.0 5544.1 5685.0 5992.9 6072.8 1246.4 1307.2 1445.2 1455.1 1458.9 846.5 984.4 1016.9 1023.4 1026.7 4395.0 4936.2 5086.3 5089.4 5270.4 5384.2 5614.0 5809.8 5879.5 5907.9 4723.0 4974.7 5087.3 5270.2 5290.9 2918.7 3065.3 3066.7 3071.6 3074.2 4966.6 5284.1 6023.2 6072.5 6091.7 5121.4 5437.3 5492.8 5899.6 5999.0 4986.8 5131.9 5432.4 5517.8 5840.8 5563.6 5594.7 5724.6 5799.4 5800.8 4936.4 5268.9 5660.0 5700.9 5727.0 5179.6 5515.5 5682.3 6037.2 6078.9 3546.7 3885.9 4049.7 4103.2 4173.9 6103.8 6119.6 6132.5 6142.7 6193.2 5219.7 5948.3 5954.2 5954.8 5958.1 5549.3 5869.4 5943.3 5980.1 5981.0 5360.5 5839.0 5859.9 5864.6 5913.9 5171.2 5443.3 5500.9 5852.6 5926.5 4732.0 4840.8 5775.9 5780.7 5822.8 05:23:41 INFO - PROCESS | 1638 | 05:23:41 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20768.19 Median:3870.27 stddev:30035.03 (776.0%) stddev-sans-first:30081.58 05:23:41 INFO - PROCESS | 1638 | Values: 725.0 733.8 785.0 868.7 871.6 15486.4 16119.1 16440.0 16476.6 16526.0 14875.3 15034.0 15200.5 15226.2 15331.8 698.8 722.1 878.7 898.9 903.5 864.7 887.6 890.3 892.4 899.7 927.7 945.2 946.6 947.6 948.9 930.9 934.6 935.8 937.3 937.4 673.7 687.7 702.8 703.4 705.2 14871.4 15422.9 15441.4 16300.8 16565.8 14778.1 14858.0 14926.0 14943.7 15139.7 10554.0 11395.8 11879.5 11910.2 11910.3 3622.3 3759.6 3821.3 3919.3 4042.4 98069.5 106830.5 107923.5 108173.0 110723.4 10946.0 11380.9 11454.7 11615.5 11712.3 23690.9 23916.5 23939.9 24008.6 24046.9 7722.5 7724.7 7830.9 7969.4 8245.5 4317.2 4345.4 4351.4 4424.6 4476.7 3657.1 3704.6 3707.7 3713.0 3973.1 758.9 762.2 766.4 783.7 784.8 691.6 702.1 703.8 728.4 740.4 10820.4 11053.3 11478.1 11615.6 11806.0 589.2 591.8 618.3 658.7 755.8 5006.5 5147.5 5212.5 5333.0 5334.9 102628.5 104496.0 105700.9 105908.0 106171.9 83056.7 88193.6 91236.0 93727.0 94083.1 82867.8 83570.0 85180.1 85187.0 85404.1 9998.9 10200.4 10261.8 10266.7 10276.8 43239.1 51166.7 51602.5 51689.0 53850.7 61047.4 68436.0 68789.9 68861.3 68985.7 12000.1 12025.1 12169.0 12278.2 12766.9 13091.2 13263.9 13569.3 14046.7 14200.4 12231.6 12457.7 12582.3 12700.9 13100.5 11830.1 12209.0 12341.9 12601.0 12792.1 10275.5 10365.3 10520.4 11299.5 12138.4 9489.2 9923.6 10134.8 10552.3 10641.1 8059.0 9763.0 10140.2 10174.2 10252.2 05:23:41 INFO - PROCESS | 1638 | -------- Summary: end -------- 05:23:41 INFO - PROCESS | 1638 | 05:23:42 INFO - TEST-INFO | 1638: exit 0 05:23:42 INFO - TEST-OK | dromaeo_css | took 1113885ms 05:23:42 INFO - TEST-START | kraken 05:23:42 INFO - operating with platform_type : mac_ 05:23:42 INFO - Initialising browser for kraken test... 05:23:42 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpPM_gfR/profile http://localhost:49241/getInfo.html) 05:23:44 INFO - PROCESS | 1650 | 2017-03-21 05:23:44.905 plugin-container[1652:9881] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 05:23:44 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:44 INFO - PROCESS | 1650 | 2017-03-21 05:23:44.906 plugin-container[1652:9881] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8813, name = 'com.apple.CFPasteboardClient' 05:23:44 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:44 INFO - PROCESS | 1650 | 2017-03-21 05:23:44.907 plugin-container[1652:9881] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:23:44 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 05:23:44 INFO - PROCESS | 1650 | colorDepth:24 05:23:44 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 05:23:44 INFO - PROCESS | 1650 | __metrics 05:23:45 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:23:45 INFO - TEST-INFO | 1650: exit 0 05:23:45 INFO - Browser initialized. 05:23:45 INFO - Running cycle 1/1 for kraken test... 05:23:45 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpPM_gfR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:23:47 INFO - PROCESS | 1653 | 2017-03-21 05:23:47.894 plugin-container[1654:10006] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854b, name = 'com.apple.tsm.portname' 05:23:47 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:47 INFO - PROCESS | 1653 | 2017-03-21 05:23:47.894 plugin-container[1654:10006] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 05:23:47 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:47 INFO - PROCESS | 1653 | 2017-03-21 05:23:47.894 plugin-container[1654:10006] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:23:57 INFO - PROCESS | 1653 | RSS: Main: 172630016 05:23:57 INFO - PROCESS | 1653 | 05:24:00 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:01 INFO - PROCESS | 1653 | RSS: Main: 185716736 05:24:01 INFO - PROCESS | 1653 | 05:24:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 05:24:04 INFO - PROCESS | 1653 | RSS: Main: 193744896 05:24:04 INFO - PROCESS | 1653 | 05:24:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 05:24:08 INFO - PROCESS | 1653 | RSS: Main: 195313664 05:24:08 INFO - PROCESS | 1653 | 05:24:10 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 05:24:11 INFO - PROCESS | 1653 | RSS: Main: 197124096 05:24:11 INFO - PROCESS | 1653 | 05:24:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:24:13 INFO - PROCESS | 1653 | RSS: Main: 197464064 05:24:13 INFO - PROCESS | 1653 | 05:24:16 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 05:24:17 INFO - PROCESS | 1653 | RSS: Main: 203276288 05:24:17 INFO - PROCESS | 1653 | 05:24:20 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 05:24:21 INFO - PROCESS | 1653 | RSS: Main: 203165696 05:24:21 INFO - PROCESS | 1653 | 05:24:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 05:24:24 INFO - PROCESS | 1653 | RSS: Main: 202395648 05:24:24 INFO - PROCESS | 1653 | 05:24:26 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:24:27 INFO - PROCESS | 1653 | RSS: Main: 201609216 05:24:27 INFO - PROCESS | 1653 | 05:24:29 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:24:29 INFO - PROCESS | 1653 | RSS: Main: 201670656 05:24:29 INFO - PROCESS | 1653 | 05:24:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:24:32 INFO - PROCESS | 1653 | RSS: Main: 201572352 05:24:32 INFO - PROCESS | 1653 | 05:24:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:24:35 INFO - PROCESS | 1653 | RSS: Main: 202051584 05:24:35 INFO - PROCESS | 1653 | 05:24:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:24:39 INFO - PROCESS | 1653 | RSS: Main: 202059776 05:24:39 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 05:24:41 INFO - PROCESS | 1653 | RSS: Main: 203857920 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | __start_tp_report 05:24:41 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 05:24:41 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 05:24:41 INFO - PROCESS | 1653 | |i|pagename|runs| 05:24:41 INFO - PROCESS | 1653 | |0;ai-astar;106;119;106;119;118;119;120;120;119;117 05:24:41 INFO - PROCESS | 1653 | |1;audio-beat-detection;141;111;113;174;113;114;110;110;129;113 05:24:41 INFO - PROCESS | 1653 | |2;audio-dft;198;280;278;196;199;240;280;203;194;195 05:24:41 INFO - PROCESS | 1653 | |3;audio-fft;81;60;58;86;59;59;56;58;71;60 05:24:41 INFO - PROCESS | 1653 | |4;audio-oscillator;80;71;82;69;69;70;81;68;70;81 05:24:41 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;108;130;90;87;95;86;90;99;90;122 05:24:41 INFO - PROCESS | 1653 | |6;imaging-darkroom;153;170;100;100;125;126;99;100;99;98 05:24:41 INFO - PROCESS | 1653 | |7;imaging-desaturate;108;122;91;92;93;127;148;141;93;93 05:24:41 INFO - PROCESS | 1653 | |8;json-parse-financial;58;58;57;62;62;61;63;62;63;62 05:24:41 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;67;68;76;84;74;98;58;64;94;98 05:24:41 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;84;81;79;85;81;82;88;80;83;80 05:24:41 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;137;136;133;134;132;139;142;152;205;165 05:24:41 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;158;200;222;168;164;161;164;162;163;161 05:24:41 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;66;67;68;76;88;81;85;64;65;77 05:24:41 INFO - PROCESS | 1653 | __end_tp_report 05:24:41 INFO - PROCESS | 1653 | __start_cc_report 05:24:41 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,943 05:24:41 INFO - PROCESS | 1653 | __end_cc_report 05:24:41 INFO - PROCESS | 1653 | __startTimestamp1490099081469__endTimestamp 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | ------- Summary: start ------- 05:24:41 INFO - PROCESS | 1653 | Number of tests: 14 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:116.30 Median:119.00 stddev:5.50 (4.6%) stddev-sans-first:4.39 05:24:41 INFO - PROCESS | 1653 | Values: 106.0 119.0 106.0 119.0 118.0 119.0 120.0 120.0 119.0 117.0 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:122.80 Median:113.50 stddev:20.61 (18.2%) stddev-sans-first:20.78 05:24:41 INFO - PROCESS | 1653 | Values: 141.0 111.0 113.0 174.0 113.0 114.0 110.0 110.0 129.0 113.0 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:226.30 Median:221.50 stddev:38.96 (17.6%) stddev-sans-first:39.95 05:24:41 INFO - PROCESS | 1653 | Values: 198.0 280.0 278.0 196.0 199.0 240.0 280.0 203.0 194.0 195.0 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:64.80 Median:60.00 stddev:10.72 (17.9%) stddev-sans-first:9.63 05:24:41 INFO - PROCESS | 1653 | Values: 81.0 60.0 58.0 86.0 59.0 59.0 56.0 58.0 71.0 60.0 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:74.10 Median:75.50 stddev:6.01 (8.0%) stddev-sans-first:5.98 05:24:41 INFO - PROCESS | 1653 | Values: 80.0 71.0 82.0 69.0 69.0 70.0 81.0 68.0 70.0 81.0 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:99.70 Median:90.00 stddev:15.41 (17.1%) stddev-sans-first:16.05 05:24:41 INFO - PROCESS | 1653 | Values: 108.0 130.0 90.0 87.0 95.0 86.0 90.0 99.0 90.0 122.0 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:117.00 Median:161.50 stddev:26.09 (16.2%) stddev-sans-first:24.20 05:24:41 INFO - PROCESS | 1653 | Values: 153.0 170.0 100.0 100.0 125.0 126.0 99.0 100.0 99.0 98.0 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:110.80 Median:91.50 stddev:22.08 (24.1%) stddev-sans-first:23.40 05:24:41 INFO - PROCESS | 1653 | Values: 108.0 122.0 91.0 92.0 93.0 127.0 148.0 141.0 93.0 93.0 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:62.00 stddev:2.25 (3.6%) stddev-sans-first:2.15 05:24:41 INFO - PROCESS | 1653 | Values: 58.0 58.0 57.0 62.0 62.0 61.0 63.0 62.0 63.0 62.0 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:78.10 Median:80.00 stddev:14.64 (18.3%) stddev-sans-first:14.97 05:24:41 INFO - PROCESS | 1653 | Values: 67.0 68.0 76.0 84.0 74.0 98.0 58.0 64.0 94.0 98.0 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:82.50 stddev:2.75 (3.3%) stddev-sans-first:2.85 05:24:41 INFO - PROCESS | 1653 | Values: 84.0 81.0 79.0 85.0 81.0 82.0 88.0 80.0 83.0 80.0 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:147.50 Median:140.50 stddev:22.63 (16.1%) stddev-sans-first:23.69 05:24:41 INFO - PROCESS | 1653 | Values: 137.0 136.0 133.0 134.0 132.0 139.0 142.0 152.0 205.0 165.0 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:164.00 stddev:21.20 (12.9%) stddev-sans-first:21.85 05:24:41 INFO - PROCESS | 1653 | Values: 158.0 200.0 222.0 168.0 164.0 161.0 164.0 162.0 163.0 161.0 05:24:41 INFO - PROCESS | 1653 | 05:24:41 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:76.50 stddev:8.87 (11.6%) stddev-sans-first:8.96 05:24:41 INFO - PROCESS | 1653 | Values: 66.0 67.0 68.0 76.0 88.0 81.0 85.0 64.0 65.0 77.0 05:24:41 INFO - PROCESS | 1653 | -------- Summary: end -------- 05:24:41 INFO - PROCESS | 1653 | 05:24:42 INFO - TEST-INFO | 1653: exit 0 05:24:42 INFO - TEST-OK | kraken | took 60074ms 05:24:42 INFO - SUITE-END | took 1173s 05:24:42 INFO - Completed test suite (00:19:34) 05:24:42 INFO - javascript benchmark 05:24:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7578.7516254791635, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3520.0495866116134, 3562.857485700572, 3582.301451217014, 3587.833953964962, 3589.192431702867, 4355.803988820503, 5573.470520300575, 5964.910526342108, 5974.163617093605, 5996.430339117782, 5911.674857882815, 5969.432903874125, 5980.88038239235, 6058.424449677284, 6064.151018857364, 5315.787368505265, 5359.651622644516, 5398.2982212312345, 5411.485908838654, 5425.593080518976, 5426.782928682848, 5448.891022179554, 5462.3445186577665, 5463.0, 5464.0, 5354.0, 5356.571474282048, 5436.374816896068, 5436.565074794007, 5509.752061157237, 5394.541463975567, 5397.568194544447, 5433.782648694048, 5449.945500544984, 5477.945220547784, 4894.0, 5374.865628359323, 5394.02907476654, 5394.91907621386, 5417.702026388547, 5839.124131380285, 5986.970065149646, 6047.274327080734, 6069.119977603223, 6113.633182009094, 6232.189815323979, 6258.499320054385, 6259.749610015594, 6308.589941653778, 6320.0, 6356.872862542723, 6367.044943258502, 6371.553991220617, 6372.044193370985, 6396.872062558769, 6121.693915304306, 6131.754729810757, 6146.1395404642935, 6147.139400483936, 6154.569180157391, 5351.464853514695, 6277.1525844010475, 6277.780277690259, 6389.968050159812, 6393.264774550986, 6286.748530058838, 6317.936820631827, 6319.24169099711, 6334.556581039283, 6335.714892829834, 6360.936390636127, 6396.2964073951825, 6408.743650253938, 6410.935890641127, 6420.293767685551, 6231.9688401557705, 6235.282942461584, 6279.843003924939, 6282.4974002079725, 6338.239411270677, 6250.531210159256, 6299.716512756937, 6322.083297921776, 6329.177206963065, 6336.809895703136, 5132.8460146195675, 5140.485951404904, 5159.17453207485, 5162.6902385856965, 5179.922301165486, 4447.710898791568, 4576.748278844696, 4618.122556714214, 4622.260438329851, 4668.579827815445, 6065.878682426327, 6083.787067452707, 6121.816345509642, 6133.558613725771, 6156.6921653917125, 6394.904076438764, 6399.7440102395385, 6411.0383442484, 6414.743410263631, 6421.8073457796345, 5971.701414929236, 6031.728572214261, 6067.969660151759, 6073.5748497604745, 6102.084687296852, 5690.118031705092, 5691.288588926384, 5697.401772813878, 5714.171445140431, 5724.79963201286, 6521.739130434825, 6528.673566321665, 6532.379423954717, 6533.771318003847, 6576.506761992774, 6219.315875253809, 6254.8123556293385, 6267.96866015667, 6319.715612797527, 6339.017452294902, 6480.805575832733, 6497.285298617243, 6501.544891857714, 6519.282878883224, 6558.0, 6322.209723784527, 6325.588836725598, 6332.271788744353, 6337.683115844097, 6348.587341822767, 6153.969230153821, 6200.875982480416, 6266.68666566679, 6354.936450635434, 6367.61794292344, 5354.223637572608, 6195.411435913581, 6239.344868788712, 6287.937120628735, 6317.5893566918, 6247.918271979355, 6282.403171698681, 6282.842928926813, 6288.968555157195, 6309.621422714652, 6037.667928263988, 6121.209103132385, 6160.784372547118, 6228.688565571794, 6235.688215589294, 6178.0, 6193.566450348432, 6212.285587157354, 6281.120643109877, 6281.246250449975, 6204.503639708722, 6207.068939659087, 6214.161088253209, 6224.128621992834, 6227.968860155852], "value": 5879.314525713203, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.1574615280946, 499.00946620957416, 513.3598153898295, 516.50157597918, 550.1527647422969, 40403.59596404035, 43150.48973285936, 43371.99999999992, 43406.34890476641, 43440.13119737611, 44411.333829992494, 44685.0, 44888.551114488844, 44969.10061798762, 44997.550024499746, 9462.479563624016, 9763.218942484584, 9781.217502599775, 11331.37677427741, 11735.941320293387, 11634.185607007514, 11731.120165987584, 11739.647810565653, 11740.119491038206, 11804.881951180465, 9806.803863922718, 10037.899621003806, 11615.767684646302, 11691.0, 11762.117841161904, 11579.131565132564, 11603.013743831785, 11664.125190610695, 11688.0, 11706.356150411724, 11669.70825729355, 11673.0, 11712.824307635392, 11732.35472049037, 11750.29498230109, 34474.482882756776, 35647.82176089129, 36617.63382366169, 38922.22155556901, 39268.0, 3972.5828787977425, 4111.301078816578, 4140.37894315855, 4184.849166479212, 4195.664346852244, 28730.712692873014, 28921.85539072291, 29135.125946221437, 29179.562306565418, 30824.84587577048, 3033.211365045082, 3085.213270616001, 3208.6630903755004, 3257.03917344384, 3258.902232933016, 513887.4305628448, 513963.0, 514781.0, 517372.0, 517793.0, 60432.79134417287, 60560.697196514615, 60788.69605651943, 60818.99999999911, 60819.391806081374, 62945.68527157335, 63012.684936575024, 63119.684401577695, 63230.36769632337, 63273.68363158247, 16607.837451378527, 17164.313427462763, 17171.399001034904, 17187.742183867253, 17208.827911720724, 10690.305130166515, 12408.379581020912, 13786.034977551475, 14006.649833754034, 14034.929825350808, 21752.891235544037, 22256.55486890253, 22503.987320570286, 22533.774662253167, 22591.0, 9038.18656320921, 9206.631734730536, 9221.81556368869, 9234.0, 9259.53702314895, 899.6941040046285, 906.746111088883, 906.895706993691, 931.8648795924688, 948.8339540580371, 31073.067807965796, 31568.368632627677, 31644.525332120036, 31958.041258762274, 32414.35171296561, 940.6331530703031, 957.6600306891092, 1139.6865861888048, 1150.7813515432042, 1156.4217891054473, 15353.385864565293, 16251.918740406223, 16423.0, 16473.83526164747, 16694.91652541754, 656403.0, 670570.0, 671031.0, 672230.0, 672726.0, 49515.51453456402, 49732.50267497351, 50016.749916250184, 50018.0, 50125.49874501281, 1467.9266036698123, 1476.7194233095463, 1500.2348802111003, 1522.3986525322437, 1525.9542213733605, 30121.09636710902, 31201.53197701991, 31290.21774455657, 31460.595726009986, 31876.0, 54473.72763136159, 54655.0, 54762.0, 54867.0, 54871.72564137154, 565011.0, 566295.0, 567586.0, 568030.0, 568547.0, 37755.867323980325, 38499.0, 43698.56301436945, 44481.33278000768, 45275.0, 47956.28065579089, 48137.277940830216, 48829.5117048825, 50119.749401254216, 50425.49574504208, 39897.40153897636, 41081.0, 41403.0, 41888.371674425514, 42550.78724606357, 39973.40039899346, 39977.40033899552, 40729.796351018056, 40975.59024409718, 41763.58236417597, 38336.42495362516, 38523.614763852005, 39092.02269943274, 39161.804190978866, 39217.21565568616, 25861.712903321095, 35204.647953520136, 35740.0, 35959.0, 35964.0, 22036.669449957943, 26019.089331873292, 26032.609510856975, 26075.348116297246, 26472.0], "value": 21288.42701519135, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.5014381591564, 403.30430008235777, 403.5238418665973, 414.4446441768031, 414.58748545197517, 16332.918335408322, 17089.316427342892, 18105.637887242265, 21355.67966480504, 21823.890880545594, 21951.0122044508, 22117.0, 22181.00185491653, 22269.109235630556, 22516.662250066263, 5666.57500687448, 6058.1518587397795, 6071.150038994544, 6115.663638499881, 6117.113018612298, 5826.329972053205, 6018.699065046752, 6040.758369665208, 6058.121572372003, 6081.756729730805, 5014.398272207322, 5897.64614123154, 5918.852028699274, 6000.2199714037115, 6011.759529618832, 5738.741756620962, 5889.087191485327, 5970.552208584373, 6032.366601506845, 6037.034074548095, 5238.266642670029, 5322.0154271459905, 5330.200469929541, 5366.141417373241, 5386.434424385461, 25871.70641467919, 26906.461870762672, 27044.0, 27256.59115113275, 27344.726552734617, 2848.700886406918, 2961.185673939662, 3130.9530357044664, 3195.760317976161, 3236.935261294761, 15640.374385024585, 15774.211289435598, 15986.920065399714, 16177.59556011109, 16458.83541164585, 1923.1826473748602, 1932.8357089647398, 1949.4638974282045, 1971.6155349706812, 2011.3664195778283, 136866.31566842203, 137585.31207343898, 140110.0, 140942.0, 141216.9827823171, 11888.465019074163, 11961.46173422198, 12003.0, 12009.879901200877, 12079.698007549881, 12941.741165176833, 13452.730945381038, 15370.616644501824, 15402.921795474433, 15426.151561663992, 7932.682692692228, 9240.815183696423, 9333.673321433716, 9359.017303183204, 9533.04669533055, 9481.431114133175, 9506.334556580972, 9543.570539325747, 9577.0, 9694.951525242328, 6968.95465680152, 7299.489035767445, 7645.350145237606, 7691.923080769233, 7773.222677732181, 1813.5466133466634, 1908.7136929460692, 2033.7051127586417, 2077.2625717869937, 2085.91656333748, 916.463868636855, 1107.545906178463, 1113.3486910157487, 1127.7349822791637, 1164.9359285239393, 17881.910590446965, 23011.424714382276, 23130.496517726293, 23147.76852231456, 23359.41601459977, 1028.1569113327, 1129.8418221449003, 1134.6709454258337, 1148.5750272399098, 1181.4919584578713, 10716.517756700967, 11231.876812318866, 12052.397380130958, 12224.266544007389, 12238.877611223774, 803411.0, 804317.0, 807223.0, 817985.8201418029, 825002.8749856212, 135614.0, 140265.5973440273, 140629.0, 140701.0, 142604.0, 1051.9789604207872, 1062.18211976779, 1075.9946200268948, 1081.3944191252924, 1082.71849319176, 19312.7446715963, 20023.416879998273, 20253.898730506255, 20265.189392424138, 20339.491512712302, 43437.348439772795, 49540.50459495359, 57184.142237867294, 58556.70721646365, 60010.69994649999, 64722.67638661965, 68596.0, 77065.68802935921, 81797.0, 84838.0, 8511.74464766058, 8580.099089595753, 8595.624700047962, 8694.0, 8775.561221939006, 13501.662458438224, 13595.728085438039, 13641.317934103454, 13788.172709637454, 13964.581062568139, 11571.19001669875, 11767.470463828977, 11899.345535995604, 11935.582254621047, 12089.51641934348, 11563.0, 11630.069594432256, 11638.767224655629, 11804.350760708243, 12161.39193040362, 11334.263272887565, 11931.284122952651, 12108.455119519467, 12155.81766273489, 12228.082893782825, 7403.629818509161, 7770.961145194238, 7899.289063984269, 8056.637451314822, 8828.735137945872, 10059.29584929048, 11566.421678915853, 11697.766044679229, 11937.701557461132, 12013.879861201276], "value": 10464.442142784806, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.31964816712, 1485.925703714814, 1489.9627509312265, 1492.910425374477, 1501.654619437529, 6850.794476165723, 7741.806454838632, 8269.751907442778, 8683.478991260528, 8697.086805885378, 8101.756947291576, 8464.026636936755, 8467.788305292372, 8479.19447652472, 8518.87221691675, 2364.2788949370433, 2426.0659646036315, 2429.574824405731, 2430.1251549442186, 2432.914847980321, 2025.8581899267058, 2399.2802159352213, 2432.756724327562, 2437.390652336916, 2449.106076282162, 2296.6555016747457, 2298.7586303438147, 2315.9536809263805, 2325.232673217834, 2333.649952507129, 2351.5061837014246, 2356.905723771047, 2358.41039740065, 2360.3391050505884, 2383.5590415772963, 2374.620060790265, 2410.890470838017, 2438.1222759806456, 2438.512297540499, 2443.767842055002, 7266.45501587383, 8351.039630442516, 8375.701766226244, 8396.790080247982, 8489.0, 5163.096458119851, 5257.7108259045735, 5288.2596436498925, 5308.814191503279, 5311.867203319909, 3434.004138799745, 3472.3055388922317, 3480.860765569374, 3529.4529347951107, 3542.5394698689265, 1181.5687274144962, 1193.1468999665194, 1220.6765207220094, 1240.3922078181627, 1241.0381953985643, 10647.14822814173, 10881.347119172875, 10905.127589792797, 10933.343999360064, 10942.671719848417, 4644.774748045129, 4720.150372932836, 4766.856994290177, 4839.274108883696, 4849.539293767087, 9618.855717164248, 9632.0, 9632.95183524092, 9668.0, 9669.74293341875, 5347.2781174541715, 5359.5712343012465, 5387.6228663993925, 5391.2182733504205, 5419.268398766116, 4403.405540252089, 4515.571020753023, 4769.904601907942, 4897.4857639948, 4910.435299940481, 3974.5627980922077, 3999.64003239704, 4028.657564107025, 4090.304648209752, 4110.034141976633, 1136.9545218191345, 1309.5285697149018, 1332.8134061231435, 1339.77223871942, 1344.6167842165132, 941.1811723800338, 963.1139351796364, 972.6206779356057, 974.1378879691524, 1003.0972125087334, 4988.90022199554, 5497.47773961473, 5727.828165155052, 5745.971270143623, 5796.88406231873, 588.9705514724246, 597.560792817279, 600.2347007565389, 604.9395060493915, 605.5488660947623, 3799.1072098056934, 3807.057492819014, 3821.5796262411113, 3862.111714305669, 3866.7873266970587, 9770.75573110678, 10114.0, 10139.188864890792, 10146.036126567964, 10156.187504999582, 10042.146417554444, 10042.949785251027, 10073.043060909202, 10096.293259471913, 10112.191024718006, 7952.443328967032, 8015.0, 8031.879521807061, 8033.638486268132, 8034.558099304594, 5493.340799104133, 5537.501624853782, 5550.639208451437, 5757.884842303214, 5772.740226689725, 6878.415334696607, 9145.77135571616, 9210.447373157633, 9481.146696797321, 9495.240380769385, 9179.770505737142, 9345.906540934504, 9407.435553866517, 9426.575804088696, 9575.23398128162, 6495.675216239264, 7214.242504536948, 7215.0, 7219.736546104516, 7308.96345518269, 7926.365890728613, 8012.3590112792, 8024.197580241929, 8033.598320084089, 8043.718469853527, 6335.891219036743, 6481.287058423664, 7060.858782824417, 7206.423486121203, 7226.494145409981, 6940.375366217065, 7034.261402552863, 7085.539439936594, 7104.289571042855, 7133.6076515790055, 6456.322086181071, 6460.224773027267, 6522.934770652233, 6533.901991470037, 6551.115599394021, 5053.545180933734, 5063.215201643751, 5112.948870511247, 5137.383513978347, 5145.331106956071, 5087.974560127176, 5165.405978312542, 5185.662931909414, 5207.557357624644, 5219.19102539107], "value": 4439.601051790834, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.7561071887195, 372.1737742212289, 380.36479079936504, 384.6077001458511, 387.14827379764523, 4857.951420485799, 5022.949770502294, 5310.88471421002, 5618.129189975555, 5667.829965101048, 4328.610425061744, 5092.083424983502, 5441.619086663935, 5613.354464236614, 5650.576206784487, 3835.0987517933263, 4318.827246910136, 4363.410939523155, 4377.802998865042, 4384.5834645708765, 3703.3889408247605, 3901.8294511646536, 4035.244668569167, 4173.436586060889, 4391.231534481469, 3920.8825484736853, 4224.929784405635, 4287.335463003239, 4302.354646802974, 4317.330813723862, 3950.861719839806, 4295.871123866289, 4299.570042995691, 4303.440552728125, 4316.935245971313, 3064.3258483133677, 3420.435628121357, 3864.652181303696, 3871.8644847430487, 3872.1094148345755, 5256.763445644917, 5350.438203988603, 5378.731063446851, 5432.538234250093, 5461.848994764008, 4133.256013917494, 4307.741535507879, 4375.8249670013165, 5041.6722913010535, 5044.772985215674, 5609.045622835891, 5667.178259152441, 5920.822375328747, 5920.911186332209, 5962.642241465526, 3494.2662040971427, 4231.936520952188, 4276.374977508527, 4290.485141782975, 4293.801118166985, 6067.0, 6141.877162456726, 6172.598781079216, 6178.6943984968275, 6264.373562643699, 3550.8814723362057, 3743.9251214975548, 4351.888399794162, 4376.912461750747, 4390.692651514427, 3901.8244179012013, 4382.715123516961, 4408.98593323531, 4441.267190913528, 4453.51011388747, 4270.44484217056, 5040.168372218616, 5048.394192696826, 5063.936573319645, 5064.265681476165, 5013.974930125326, 5544.11705912923, 5684.953416769959, 5992.940070599326, 6072.785442911435, 1246.3830403950103, 1307.235267368581, 1445.2484707951805, 1455.0542147604142, 1458.8832893368506, 846.4582667093065, 984.414273507262, 1016.8983101689772, 1023.3677284730842, 1026.6560702164709, 4395.000274874924, 4936.210206367035, 5086.3133476980875, 5089.389273287229, 5270.367555893241, 5384.219288203189, 5613.989481893217, 5809.82570522885, 5879.500242479352, 5907.940920590825, 4722.976385118052, 4974.676646017997, 5087.26234695967, 5270.183121616155, 5290.851943093853, 2918.6643535993207, 3065.26433655921, 3066.739327157172, 3071.5699802027734, 3074.200707815939, 4966.627502937295, 5284.101702710472, 6023.247094113236, 6072.453479186895, 6091.725872335755, 5121.436641969381, 5437.293151890229, 5492.80775172867, 5899.646021238739, 5998.980173370537, 4986.825461108844, 5131.870988382475, 5432.402435732066, 5517.806876759455, 5840.8247752567495, 5563.638363506359, 5594.664320140804, 5724.599278050497, 5799.449052340021, 5800.8259752207505, 4936.3829521309835, 5268.920966185587, 5660.009498337857, 5700.916825803084, 5727.0, 5179.6015075930045, 5515.4760297771645, 5682.318121825408, 6037.245344331958, 6078.939210608014, 3546.680798728127, 3885.9028524285986, 4049.716519843583, 4103.220388126247, 4173.853915113078, 6103.772123175098, 6119.632822030692, 6132.509399247961, 6142.740738148579, 6193.194884664965, 5219.686818790885, 5948.315943666361, 5954.213735879132, 5954.821355359346, 5958.076498142795, 5549.306336707911, 5869.383714710065, 5943.316518600243, 5980.073088671263, 5981.0, 5360.490753378423, 5838.970805145947, 5859.882802344015, 5864.621813873651, 5913.91129133072, 5171.224316352577, 5443.319585051869, 5500.909654331328, 5852.561057920758, 5926.481245470572, 4731.9763401184155, 4840.75796210181, 5775.8556036099435, 5780.682062486603, 5822.796202132905], "value": 4278.228398821032, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.974625888094, 733.7982054934893, 784.9960750196252, 868.6829307302835, 871.577285016767, 15486.380544778209, 16119.113448760314, 16440.0, 16476.58808529786, 16526.0, 14875.330610122543, 15034.0, 15200.467983620576, 15226.162561059138, 15331.77002344963, 698.7624207769358, 722.1406526233791, 878.7363790862731, 898.9235914947204, 903.5075883643398, 864.7492227254089, 887.6360692116234, 890.2566357091836, 892.3708785306344, 899.7210864631985, 927.6706769096975, 945.1776954049986, 946.5835032585641, 947.5972711597543, 948.9383190092657, 930.878985731854, 934.6261495401826, 935.8409070458005, 937.2548823685187, 937.4375374775149, 673.6934694713942, 687.6767919078026, 702.8488874891872, 703.3845385287873, 705.2031204738613, 14871.405143794234, 15422.922885385613, 15441.382344706199, 16300.755488667679, 16565.840391172624, 14778.113313201242, 14858.0, 14925.955183137185, 14943.701125977528, 15139.697206055816, 10553.960606136225, 11395.803440638696, 11879.524819007229, 11910.225835320764, 11910.34493102879, 3622.257437225389, 3759.642833930772, 3821.254855303217, 3919.2945269851416, 4042.3734321180264, 98069.50965245128, 106830.46584767026, 107923.46038269758, 108173.0, 110723.44638276838, 10946.0, 11380.886191138148, 11454.713632159262, 11615.477303521362, 11712.297262164297, 23690.881545592507, 23916.52166956651, 23939.880300598386, 24008.63987040196, 24046.87976560106, 7722.459427839997, 7724.691012359555, 7830.921690783133, 7969.425786777698, 8245.505269683717, 4317.222899878052, 4345.351043643314, 4351.412559304454, 4424.557544245549, 4476.7090139140855, 3657.140571965586, 3704.5739739930254, 3707.721920855947, 3713.034611001159, 3973.106051138471, 758.9468737188454, 762.2111114995953, 766.4290103872539, 783.702193166593, 784.752802867095, 691.6438034412282, 702.1118285369013, 703.7923812475332, 728.3663213004614, 740.3780824107776, 10820.350778953289, 11053.337388018546, 11478.139139564566, 11615.593454229062, 11806.0, 589.2339958054512, 591.8461200088008, 618.264265524025, 658.7200439813156, 755.8148253677884, 5006.549410553068, 5147.455763271004, 5212.466848790626, 5332.986732520809, 5334.91997620036, 102628.48685756524, 104496.0, 105700.9429905691, 105908.0, 106171.9382806162, 83056.67773289, 88193.55903220571, 91236.0, 93727.0, 94083.05916940879, 82867.75698364529, 83570.0, 85180.14819851723, 85187.0, 85404.14595853962, 9998.900010999796, 10200.38797672142, 10261.794764104825, 10266.691999240034, 10276.794464110526, 43239.135217296105, 51166.74416628042, 51602.48397515977, 51688.96622067613, 53850.653733656975, 61047.389526105944, 68436.0, 68789.93630191102, 68861.2784680387, 68985.65507172432, 12000.099992500773, 12025.098117641386, 12169.0, 12278.201916875374, 12766.914812241064, 13091.214527128403, 13263.86736132665, 13569.321533923066, 14046.706169397085, 14200.360983755962, 12231.633051008484, 12457.688557786129, 12582.30797306157, 12700.936495317465, 13100.475980960655, 11830.11274154459, 12209.0, 12341.938290308492, 12601.0, 12792.104552681223, 10275.486225688535, 10365.274430789772, 10520.368777873353, 11299.548018079186, 12138.393080346124, 9489.193418559224, 9923.603055877684, 10134.847977280495, 10552.261341706007, 10641.062015619584, 8059.002342153738, 9762.951185244028, 10140.188784897338, 10174.186065114922, 10252.231082668979], "value": 7616.290423902373, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1546.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 106.0, 119.0, 118.0, 119.0, 120.0, 120.0, 119.0, 117.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 111.0, 113.0, 174.0, 113.0, 114.0, 110.0, 110.0, 129.0, 113.0], "value": 122.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 280.0, 278.0, 196.0, 199.0, 240.0, 280.0, 203.0, 194.0, 195.0], "value": 226.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 58.0, 86.0, 59.0, 59.0, 56.0, 58.0, 71.0, 60.0], "value": 64.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 71.0, 82.0, 69.0, 69.0, 70.0, 81.0, 68.0, 70.0, 81.0], "value": 74.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 130.0, 90.0, 87.0, 95.0, 86.0, 90.0, 99.0, 90.0, 122.0], "value": 99.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 170.0, 100.0, 100.0, 125.0, 126.0, 99.0, 100.0, 99.0, 98.0], "value": 117.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 122.0, 91.0, 92.0, 93.0, 127.0, 148.0, 141.0, 93.0, 93.0], "value": 110.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 62.0, 62.0, 61.0, 63.0, 62.0, 63.0, 62.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 76.0, 84.0, 74.0, 98.0, 58.0, 64.0, 94.0, 98.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 79.0, 85.0, 81.0, 82.0, 88.0, 80.0, 83.0, 80.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 133.0, 134.0, 132.0, 139.0, 142.0, 152.0, 205.0, 165.0], "value": 147.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 200.0, 222.0, 168.0, 164.0, 161.0, 164.0, 162.0, 163.0, 161.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 76.0, 88.0, 81.0, 85.0, 64.0, 65.0, 77.0], "value": 73.7, "unit": "score"}]}]} 05:24:42 INFO - Return code: 0 05:24:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:24:42 ERROR - # TBPL SUCCESS # 05:24:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:24:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:24:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:24:42 INFO - 2017-03-21 05:24:42.756 screenresolution[1658:10392] starting screenresolution argv=screenresolution get 05:24:42 INFO - 2017-03-21 05:24:42.758 screenresolution[1658:10392] Display 0: 1600x1200x32@60 05:24:42 INFO - 2017-03-21 05:24:42.764 screenresolution[1659:10395] starting screenresolution argv=screenresolution list 05:24:42 INFO - Available Modes on Display 0 05:24:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:24:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:24:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:24:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:24:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:24:42 INFO - Intel Iris: 05:24:42 INFO - Chipset Model: Intel Iris 05:24:42 INFO - Type: GPU 05:24:42 INFO - Bus: Built-In 05:24:42 INFO - VRAM (Dynamic, Max): 1536 MB 05:24:42 INFO - Vendor: Intel (0x8086) 05:24:42 INFO - Device ID: 0x0a2e 05:24:42 INFO - Revision ID: 0x0009 05:24:42 INFO - Displays: 05:24:42 INFO - Display: 05:24:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:24:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:24:42 INFO - Display Serial Number: 1600x1200 60 05:24:42 INFO - Main Display: Yes 05:24:42 INFO - Mirror: Off 05:24:42 INFO - Online: Yes 05:24:42 INFO - Rotation: Supported 05:24:42 INFO - Return code: 0 05:24:42 INFO - Running post-action listener: _resource_record_post_action 05:24:42 INFO - [mozharness: 2017-03-21 12:24:42.999417Z] Finished run-tests step (success) 05:24:42 INFO - Running post-run listener: _resource_record_post_run 05:24:43 INFO - Total resource usage - Wall time: 1189s; CPU: 55.0%; Read bytes: 40720384; Write bytes: 427424256; Read time: 400; Write time: 1494 05:24:43 INFO - TinderboxPrint: CPU usage
54.6% 05:24:43 INFO - TinderboxPrint: I/O read bytes / time
40,720,384 / 400 05:24:43 INFO - TinderboxPrint: I/O write bytes / time
427,424,256 / 1,494 05:24:43 INFO - TinderboxPrint: CPU idle
2,162.1 (45.4%) 05:24:43 INFO - TinderboxPrint: CPU system
960.1 (20.2%) 05:24:43 INFO - TinderboxPrint: CPU user
1,635.4 (34.4%) 05:24:43 INFO - TinderboxPrint: Swap in / out
162,951,168 / 0 05:24:43 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 109996544; Write bytes: 102313984; Read time: 9909; Write time: 202 05:24:43 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 39757824; Write bytes: 301470208; Read time: 385; Write time: 1169 05:24:43 INFO - Running post-run listener: _upload_blobber_files 05:24:43 INFO - Blob upload gear active. 05:24:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:24:43 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 05:24:43 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'] 05:24:43 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 05:24:43 INFO - (blobuploader) - INFO - Open directory for files ... 05:24:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:24:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:24:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:45 INFO - (blobuploader) - INFO - Done attempting. 05:24:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:24:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:24:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:46 INFO - (blobuploader) - INFO - Done attempting. 05:24:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:24:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:24:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:47 INFO - (blobuploader) - INFO - Done attempting. 05:24:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:24:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:24:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:49 INFO - (blobuploader) - INFO - Done attempting. 05:24:49 INFO - (blobuploader) - INFO - Iteration through files over. 05:24:49 INFO - Return code: 0 05:24:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:24:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:24:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b1beaea6b93e985006b09dbe07089350b003e49f332fc0db6d2184976571b38fae3594e516f78031f58c1cf2fd3e307d53bd90c82e5b1e39e81f43d5ef716f5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b80de09fe7bebc5d06e37edeeaf5e8849aabb3099665c741cc6e164c206d4cde3731035841a23a07c791c1e6790ea6d76d71b01083f6a395860ff6ae02f4d29f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43fc9d1f7e9489136ebc89c88dda9559e412d876d74e9b18016f1e0e05162cc8583dc0fd9ed0337b1af5701251fc21dfacf8bf3ac069da9fccfa9d14460355f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5cb4f758d879fcf6039565ea050ba22d54afdddba53403125a357bb4c026cce063a6a9bee7d4b450bcab58265f90bb116dfd4b835e1543fae21713f62775e351"} 05:24:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:24:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:24:49 INFO - Contents: 05:24:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b1beaea6b93e985006b09dbe07089350b003e49f332fc0db6d2184976571b38fae3594e516f78031f58c1cf2fd3e307d53bd90c82e5b1e39e81f43d5ef716f5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b80de09fe7bebc5d06e37edeeaf5e8849aabb3099665c741cc6e164c206d4cde3731035841a23a07c791c1e6790ea6d76d71b01083f6a395860ff6ae02f4d29f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43fc9d1f7e9489136ebc89c88dda9559e412d876d74e9b18016f1e0e05162cc8583dc0fd9ed0337b1af5701251fc21dfacf8bf3ac069da9fccfa9d14460355f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5cb4f758d879fcf6039565ea050ba22d54afdddba53403125a357bb4c026cce063a6a9bee7d4b450bcab58265f90bb116dfd4b835e1543fae21713f62775e351"} 05:24:49 INFO - Running post-run listener: copy_logs_to_upload_dir 05:24:49 INFO - Copying logs to upload dir... 05:24:49 INFO - mkdir: /builds/slave/test/build/upload/logs 05:24:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.964190 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-21 05:24:49.766584) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 05:24:49.770691) ========= 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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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/7b1beaea6b93e985006b09dbe07089350b003e49f332fc0db6d2184976571b38fae3594e516f78031f58c1cf2fd3e307d53bd90c82e5b1e39e81f43d5ef716f5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b80de09fe7bebc5d06e37edeeaf5e8849aabb3099665c741cc6e164c206d4cde3731035841a23a07c791c1e6790ea6d76d71b01083f6a395860ff6ae02f4d29f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43fc9d1f7e9489136ebc89c88dda9559e412d876d74e9b18016f1e0e05162cc8583dc0fd9ed0337b1af5701251fc21dfacf8bf3ac069da9fccfa9d14460355f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5cb4f758d879fcf6039565ea050ba22d54afdddba53403125a357bb4c026cce063a6a9bee7d4b450bcab58265f90bb116dfd4b835e1543fae21713f62775e351"} build_url:https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011161 build_url: 'https://queue.taskcluster.net/v1/task/O5rYFTJjQgGptzwiFqx9OQ/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/7b1beaea6b93e985006b09dbe07089350b003e49f332fc0db6d2184976571b38fae3594e516f78031f58c1cf2fd3e307d53bd90c82e5b1e39e81f43d5ef716f5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b80de09fe7bebc5d06e37edeeaf5e8849aabb3099665c741cc6e164c206d4cde3731035841a23a07c791c1e6790ea6d76d71b01083f6a395860ff6ae02f4d29f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43fc9d1f7e9489136ebc89c88dda9559e412d876d74e9b18016f1e0e05162cc8583dc0fd9ed0337b1af5701251fc21dfacf8bf3ac069da9fccfa9d14460355f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5cb4f758d879fcf6039565ea050ba22d54afdddba53403125a357bb4c026cce063a6a9bee7d4b450bcab58265f90bb116dfd4b835e1543fae21713f62775e351"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 05:24:49.805232) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:24:49.805634) ========= 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.ZrsgR9RySp/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.NgB6I0vLYP/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.005167 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:24:49.855499) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 05:24:49.855945) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 05:24:49.856364) ========= ========= Total master_lag: 4.42 =========