builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0236 starttime: 1493025761.9 results: success (0) buildid: 20170424010019 builduid: e2f70cd244f1433cb517e3e258b64f4d revision: bb3319dc41bf480dc159ebf7d64be25e3174abe9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 02:22:41.898187) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 02:22:41.898637) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 02:22:41.899040) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 02:22:42.000337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:22:42.000796) ========= 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.mOcsRWrw2r/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.llLgM9mga4/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-24 02:22:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 166M=0s 2017-04-24 02:22:42 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.174465 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:22:42.202942) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:22:42.203238) ========= 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.mOcsRWrw2r/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.llLgM9mga4/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.023973 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:22:42.264107) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-24 02:22:42.264456) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bb3319dc41bf480dc159ebf7d64be25e3174abe9 --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 bb3319dc41bf480dc159ebf7d64be25e3174abe9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mOcsRWrw2r/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.llLgM9mga4/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-24 02:22:42,356 truncating revision to first 12 chars 2017-04-24 02:22:42,356 Setting DEBUG logging. 2017-04-24 02:22:42,356 attempt 1/10 2017-04-24 02:22:42,356 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bb3319dc41bf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-24 02:22:43,313 unpacking tar archive at: mozilla-beta-bb3319dc41bf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.215025 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-24 02:22:43.506541) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 02:22:43.507059) ========= script_repo_revision: bb3319dc41bf480dc159ebf7d64be25e3174abe9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 02:22:43.507697) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 02:22:43.508214) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 02:22:43.538346) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-04-24 02:22:43.538804) ========= /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.mOcsRWrw2r/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.llLgM9mga4/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:22:43 INFO - MultiFileLogger online at 20170424 02:22:43 in /builds/slave/test 02:22:43 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 02:22:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:22:43 INFO - {'append_to_log': False, 02:22:43 INFO - 'base_work_dir': '/builds/slave/test', 02:22:43 INFO - 'blob_upload_branch': 'Mozilla-Beta', 02:22:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:22:43 INFO - 'branch': 'Mozilla-Beta', 02:22:43 INFO - 'buildbot_json_path': 'buildprops.json', 02:22:43 INFO - 'config_files': ('talos/mac_config.py',), 02:22:43 INFO - 'default_actions': ('clobber', 02:22:43 INFO - 'read-buildbot-config', 02:22:43 INFO - 'download-and-extract', 02:22:43 INFO - 'populate-webroot', 02:22:43 INFO - 'create-virtualenv', 02:22:43 INFO - 'install', 02:22:43 INFO - 'run-tests'), 02:22:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:22:43 INFO - 'download_minidump_stackwalk': True, 02:22:43 INFO - 'download_symbols': 'ondemand', 02:22:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:22:43 INFO - 'tooltool.py': '/tools/tooltool.py', 02:22:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:22:43 INFO - '/tools/misc-python/virtualenv.py')}, 02:22:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:22:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:22:43 INFO - 'gecko_profile': False, 02:22:43 INFO - 'gecko_profile_interval': 0, 02:22:43 INFO - 'installer_path': 'installer.exe', 02:22:43 INFO - 'log_level': 'info', 02:22:43 INFO - 'log_name': 'talos', 02:22:43 INFO - 'log_to_console': True, 02:22:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:22:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:22:43 INFO - 'opt_config_files': (), 02:22:43 INFO - 'pip_index': False, 02:22:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:22:43 INFO - 'cmd': ('bash', 02:22:43 INFO - '-c', 02:22:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:22:43 INFO - 'enabled': True, 02:22:43 INFO - 'halt_on_failure': False, 02:22:43 INFO - 'name': 'check_screen_resolution'},), 02:22:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:22:43 INFO - 'cmd': ('bash', 02:22:43 INFO - '-c', 02:22:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:22:43 INFO - 'enabled': True, 02:22:43 INFO - 'halt_on_failure': False, 02:22:43 INFO - 'name': 'check_screen_resolution'},), 02:22:43 INFO - 'run_cmd_checks_enabled': True, 02:22:43 INFO - 'suite': 'dromaeojs-e10s', 02:22:43 INFO - 'system_bits': '32', 02:22:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:22:43 INFO - 'title': 't-yosemite-r7-0236', 02:22:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:22:43 INFO - 'use_talos_json': True, 02:22:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:22:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:22:43 INFO - 'work_dir': 'build'} 02:22:43 INFO - [mozharness: 2017-04-24 09:22:43.750445Z] Running clobber step. 02:22:43 INFO - Running pre-action listener: _resource_record_pre_action 02:22:43 INFO - Running main action method: clobber 02:22:43 INFO - rmtree: /builds/slave/test/build 02:22:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:22:45 INFO - Running post-action listener: _resource_record_post_action 02:22:45 INFO - [mozharness: 2017-04-24 09:22:45.829185Z] Finished clobber step (success) 02:22:45 INFO - [mozharness: 2017-04-24 09:22:45.829301Z] Running read-buildbot-config step. 02:22:45 INFO - Running pre-action listener: _resource_record_pre_action 02:22:45 INFO - Running main action method: read_buildbot_config 02:22:45 INFO - Using buildbot properties: 02:22:45 INFO - { 02:22:45 INFO - "project": "", 02:22:45 INFO - "product": "firefox", 02:22:45 INFO - "script_repo_revision": "production", 02:22:45 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 02:22:45 INFO - "repository": "", 02:22:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 02:22:45 INFO - "buildid": "20170424010019", 02:22:45 INFO - "pgo_build": "False", 02:22:45 INFO - "basedir": "/builds/slave/test", 02:22:45 INFO - "buildnumber": 14, 02:22:45 INFO - "slavename": "t-yosemite-r7-0236", 02:22:45 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:22:45 INFO - "platform": "yosemite_r7", 02:22:45 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 02:22:45 INFO - "branch": "mozilla-beta", 02:22:45 INFO - "repo_path": "releases/mozilla-beta", 02:22:45 INFO - "revision": "bb3319dc41bf480dc159ebf7d64be25e3174abe9", 02:22:45 INFO - "stage_platform": "macosx64", 02:22:45 INFO - "builduid": "e2f70cd244f1433cb517e3e258b64f4d", 02:22:45 INFO - "slavebuilddir": "test" 02:22:45 INFO - } 02:22:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 02:22:45 INFO - Running post-action listener: _resource_record_post_action 02:22:45 INFO - [mozharness: 2017-04-24 09:22:45.832284Z] Finished read-buildbot-config step (success) 02:22:45 INFO - [mozharness: 2017-04-24 09:22:45.832399Z] Running download-and-extract step. 02:22:45 INFO - Running pre-action listener: _resource_record_pre_action 02:22:45 INFO - Running main action method: download_and_extract 02:22:45 INFO - mkdir: /builds/slave/test/build/tests 02:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:45 INFO - trying https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 02:22:45 INFO - Downloading https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 02:22:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 02:22:47 INFO - Downloaded 1165 bytes. 02:22:47 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 02:22:47 INFO - Using the following test package requirements: 02:22:47 INFO - {u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 02:22:47 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 02:22:47 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 02:22:47 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 02:22:47 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 02:22:47 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 02:22:47 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 02:22:47 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 02:22:47 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 02:22:47 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 02:22:47 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 02:22:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 02:22:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 02:22:47 INFO - Fetch https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 02:22:48 INFO - Expected file size: 51224368 02:22:48 INFO - Obtained file size: 51224368 02:23:04 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 02:23:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 02:23:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 02:23:04 INFO - Fetch https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 02:23:05 INFO - Expected file size: 51224368 02:23:05 INFO - Obtained file size: 51224368 02:23:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 02:23:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 02:23:20 INFO - Fetch https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 02:23:27 INFO - Expected file size: 14404449 02:23:27 INFO - Obtained file size: 14404449 02:23: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')]} 02:23:28 INFO - trying https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:23:28 INFO - Downloading https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 02:23:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 02:23:29 INFO - Downloaded 59544648 bytes. 02:23:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:23:29 INFO - mkdir: /builds/slave/test/properties 02:23:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:23:29 INFO - Writing to file /builds/slave/test/properties/build_url 02:23:29 INFO - Contents: 02:23:29 INFO - build_url:https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:23:30 INFO - Running post-action listener: _resource_record_post_action 02:23:30 INFO - Running post-action listener: set_extra_try_arguments 02:23:30 INFO - [mozharness: 2017-04-24 09:23:30.413189Z] Finished download-and-extract step (success) 02:23:30 INFO - [mozharness: 2017-04-24 09:23:30.413313Z] Running populate-webroot step. 02:23:30 INFO - Running pre-action listener: _resource_record_pre_action 02:23:30 INFO - Running main action method: populate_webroot 02:23:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:23:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:23:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:23:30 INFO - '--tppagecycles', 02:23:30 INFO - '7'], 02:23:30 INFO - 'tests': ['tsvgm']}}, 02:23:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:23:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:23:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:23:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:23:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:23:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:23:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:23:30 INFO - 'talos_options': ['--disable-e10s'], 02:23:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:23:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:23:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:23:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:23:30 INFO - 'talos_options': ['--disable-e10s'], 02:23:30 INFO - 'tests': ['damp', 'tps']}, 02:23:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:23:30 INFO - 'tests': ['damp', 'tps']}, 02:23:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:23:30 INFO - 'tests': ['dromaeo_dom']}, 02:23:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:23:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:23:30 INFO - 'tests': ['basic_compositor_video']}, 02:23:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:23:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:23:30 INFO - 'tests': ['a11yr', 02:23:30 INFO - 'ts_paint', 02:23:30 INFO - 'tpaint', 02:23:30 INFO - 'sessionrestore', 02:23:30 INFO - 'sessionrestore_no_auto_restore', 02:23:30 INFO - 'tabpaint']}, 02:23:30 INFO - 'other-e10s': {'tests': ['a11yr', 02:23:30 INFO - 'ts_paint', 02:23:30 INFO - 'tpaint', 02:23:30 INFO - 'sessionrestore', 02:23:30 INFO - 'sessionrestore_no_auto_restore', 02:23:30 INFO - 'tabpaint']}, 02:23:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:23:30 INFO - 'ts_paint', 02:23:30 INFO - 'tpaint', 02:23:30 INFO - 'sessionrestore', 02:23:30 INFO - 'sessionrestore_no_auto_restore', 02:23:30 INFO - 'tabpaint']}, 02:23:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:23:30 INFO - 'ts_paint', 02:23:30 INFO - 'tpaint', 02:23:30 INFO - 'sessionrestore', 02:23:30 INFO - 'sessionrestore_no_auto_restore', 02:23:30 INFO - 'tabpaint']}, 02:23:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:23:30 INFO - 'tests': ['a11yr', 02:23:30 INFO - 'ts_paint', 02:23:30 INFO - 'tpaint', 02:23:30 INFO - 'sessionrestore', 02:23:30 INFO - 'sessionrestore_no_auto_restore', 02:23:30 INFO - 'tabpaint']}, 02:23:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:23:30 INFO - 'tests': ['a11yr', 02:23:30 INFO - 'ts_paint', 02:23:30 INFO - 'tpaint', 02:23:30 INFO - 'sessionrestore', 02:23:30 INFO - 'sessionrestore_no_auto_restore', 02:23:30 INFO - 'tabpaint']}, 02:23:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:23:30 INFO - 'tests': ['tsvgx', 02:23:30 INFO - 'tsvgr_opacity', 02:23:30 INFO - 'tart', 02:23:30 INFO - 'tscrollx', 02:23:30 INFO - 'cart', 02:23:30 INFO - 'tsvg_static']}, 02:23:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:23:30 INFO - 'tsvgr_opacity', 02:23:30 INFO - 'tart', 02:23:30 INFO - 'tscrollx', 02:23:30 INFO - 'cart', 02:23:30 INFO - 'tsvg_static']}, 02:23:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:23:30 INFO - 'talos_options': ['--disable-e10s'], 02:23:30 INFO - 'tests': ['tp5o']}, 02:23:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:23:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:23:30 INFO - 'talos_options': ['--disable-e10s', 02:23:30 INFO - '--xperf_path', 02:23:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:23:30 INFO - 'tests': ['tp5n']}, 02:23:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:23:30 INFO - 'talos_options': ['--xperf_path', 02:23:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:23:30 INFO - 'tests': ['tp5n']}}, 02:23:30 INFO - 'talos.zip': {'path': '', 02:23:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:23:30 INFO - Running post-action listener: _resource_record_post_action 02:23:30 INFO - [mozharness: 2017-04-24 09:23:30.431253Z] Finished populate-webroot step (success) 02:23:30 INFO - [mozharness: 2017-04-24 09:23:30.431370Z] Running create-virtualenv step. 02:23:30 INFO - Running pre-action listener: _resource_record_pre_action 02:23:30 INFO - Running main action method: create_virtualenv 02:23:30 INFO - Creating virtualenv /builds/slave/test/build/venv 02:23:30 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 02:23:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:23:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:23:30 INFO - Using real prefix '/tools/python27' 02:23:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:23:31 INFO - Installing distribute.............................................................................................................................................................................................done. 02:23:34 INFO - Installing pip.................done. 02:23:34 INFO - Return code: 0 02:23:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:23:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:23:34 INFO - Reading from file tmpfile_stdout 02:23:34 INFO - Output received: 02:23:34 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) 02:23:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:23:34 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')]} 02:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:34 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')]} 02:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:34 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 0x107735b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107738768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb9b4753f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107513d30>, '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 0x10743adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107508be8>, '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.mOcsRWrw2r/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.llLgM9mga4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:23:34 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 02:23:34 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 02:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOcsRWrw2r/Render', 02:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:34 INFO - 'HOME': '/Users/cltbld', 02:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:34 INFO - 'LOGNAME': 'cltbld', 02:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:34 INFO - 'MOZ_NO_REMOTE': '1', 02:23:34 INFO - 'NO_EM_RESTART': '1', 02:23:34 INFO - 'PAGER': '/bin/cat', 02:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:34 INFO - 'PWD': '/builds/slave/test', 02:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:34 INFO - 'SHELL': '/bin/bash', 02:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llLgM9mga4/Listeners', 02:23:34 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:34 INFO - 'USER': 'cltbld', 02:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:34 INFO - 'XPC_FLAGS': '0x0', 02:23:34 INFO - 'XPC_SERVICE_NAME': '0', 02:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:34 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 02:23:34 INFO - Cleaning up... 02:23:34 INFO - Return code: 0 02:23:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:23:34 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')]} 02:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:34 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')]} 02:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:34 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 0x107735b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107738768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb9b4753f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107513d30>, '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 0x10743adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107508be8>, '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.mOcsRWrw2r/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.llLgM9mga4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:23:34 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 02:23:34 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 02:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOcsRWrw2r/Render', 02:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:34 INFO - 'HOME': '/Users/cltbld', 02:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:34 INFO - 'LOGNAME': 'cltbld', 02:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:34 INFO - 'MOZ_NO_REMOTE': '1', 02:23:34 INFO - 'NO_EM_RESTART': '1', 02:23:34 INFO - 'PAGER': '/bin/cat', 02:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:34 INFO - 'PWD': '/builds/slave/test', 02:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:34 INFO - 'SHELL': '/bin/bash', 02:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llLgM9mga4/Listeners', 02:23:34 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:34 INFO - 'USER': 'cltbld', 02:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:34 INFO - 'XPC_FLAGS': '0x0', 02:23:34 INFO - 'XPC_SERVICE_NAME': '0', 02:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:34 INFO - Downloading/unpacking psutil>=3.1.1 02:23: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 02:23: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 02:23: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 02:23: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 02:23: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 02:23: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 02:23:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:23:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:23:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:23:38 INFO - Installing collected packages: psutil 02:23:38 INFO - Running setup.py install for psutil 02:23:39 INFO - building 'psutil._psutil_osx' extension 02:23:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:23:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:23:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:23:39 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 02:23:39 INFO - building 'psutil._psutil_posix' extension 02:23:39 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 02:23:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:23:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:23:39 INFO - ^ 02:23:39 INFO - 1 warning generated. 02:23:39 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 02:23:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:23:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:23:39 INFO - Successfully installed psutil 02:23:39 INFO - Cleaning up... 02:23:39 INFO - Return code: 0 02:23:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:23:39 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')]} 02:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:39 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')]} 02:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:39 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 0x107735b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107738768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb9b4753f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107513d30>, '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 0x10743adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107508be8>, '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.mOcsRWrw2r/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.llLgM9mga4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:23:39 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 02:23:39 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 02:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOcsRWrw2r/Render', 02:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:39 INFO - 'HOME': '/Users/cltbld', 02:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:39 INFO - 'LOGNAME': 'cltbld', 02:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:39 INFO - 'MOZ_NO_REMOTE': '1', 02:23:39 INFO - 'NO_EM_RESTART': '1', 02:23:39 INFO - 'PAGER': '/bin/cat', 02:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:39 INFO - 'PWD': '/builds/slave/test', 02:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:39 INFO - 'SHELL': '/bin/bash', 02:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llLgM9mga4/Listeners', 02:23:39 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:39 INFO - 'USER': 'cltbld', 02:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:39 INFO - 'XPC_FLAGS': '0x0', 02:23:39 INFO - 'XPC_SERVICE_NAME': '0', 02:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:23: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 02:23: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 02:23: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 02:23: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 02:23: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 02:23: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 02:23:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:23:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:23:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:23:42 INFO - Installing collected packages: mozsystemmonitor 02:23:42 INFO - Running setup.py install for mozsystemmonitor 02:23:42 INFO - Successfully installed mozsystemmonitor 02:23:42 INFO - Cleaning up... 02:23:43 INFO - Return code: 0 02:23:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:23:43 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')]} 02:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:43 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')]} 02:23:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:43 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 0x107735b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107738768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb9b4753f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107513d30>, '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 0x10743adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107508be8>, '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.mOcsRWrw2r/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.llLgM9mga4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:23:43 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 02:23:43 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 02:23:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOcsRWrw2r/Render', 02:23:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:43 INFO - 'HOME': '/Users/cltbld', 02:23:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:43 INFO - 'LOGNAME': 'cltbld', 02:23:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:43 INFO - 'MOZ_NO_REMOTE': '1', 02:23:43 INFO - 'NO_EM_RESTART': '1', 02:23:43 INFO - 'PAGER': '/bin/cat', 02:23:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:43 INFO - 'PWD': '/builds/slave/test', 02:23:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:43 INFO - 'SHELL': '/bin/bash', 02:23:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llLgM9mga4/Listeners', 02:23:43 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:23:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:43 INFO - 'USER': 'cltbld', 02:23:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:43 INFO - 'XPC_FLAGS': '0x0', 02:23:43 INFO - 'XPC_SERVICE_NAME': '0', 02:23:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:43 INFO - Downloading/unpacking jsonschema==2.5.1 02:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:23:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:23:45 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 02:23:45 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 02:23:45 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 02:23:45 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 02:23:45 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 02:23:45 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 02:23:46 INFO - Downloading functools32-3.2.3-2.tar.gz 02:23:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:23:46 INFO - warning: no files found matching '*.txt' 02:23:46 INFO - no previously-included directories found matching 'build' 02:23:46 INFO - no previously-included directories found matching 'dist' 02:23:46 INFO - no previously-included directories found matching '.git*' 02:23:46 INFO - Installing collected packages: jsonschema, functools32 02:23:46 INFO - Running setup.py install for functools32 02:23:46 INFO - warning: no files found matching '*.txt' 02:23:46 INFO - no previously-included directories found matching 'build' 02:23:46 INFO - no previously-included directories found matching 'dist' 02:23:46 INFO - no previously-included directories found matching '.git*' 02:23:46 INFO - Successfully installed jsonschema functools32 02:23:46 INFO - Cleaning up... 02:23:46 INFO - Return code: 0 02:23:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:23: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')]} 02:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23: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')]} 02:23:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:46 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 0x107735b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107738768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb9b4753f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107513d30>, '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 0x10743adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107508be8>, '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.mOcsRWrw2r/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.llLgM9mga4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:23:46 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 02:23:46 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 02:23:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOcsRWrw2r/Render', 02:23:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:46 INFO - 'HOME': '/Users/cltbld', 02:23:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:46 INFO - 'LOGNAME': 'cltbld', 02:23:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:46 INFO - 'MOZ_NO_REMOTE': '1', 02:23:46 INFO - 'NO_EM_RESTART': '1', 02:23:46 INFO - 'PAGER': '/bin/cat', 02:23:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:46 INFO - 'PWD': '/builds/slave/test', 02:23:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:46 INFO - 'SHELL': '/bin/bash', 02:23:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llLgM9mga4/Listeners', 02:23:46 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:23:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:46 INFO - 'USER': 'cltbld', 02:23:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:46 INFO - 'XPC_FLAGS': '0x0', 02:23:46 INFO - 'XPC_SERVICE_NAME': '0', 02:23:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:23:47 INFO - Cleaning up... 02:23:47 INFO - Return code: 0 02:23:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107735b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107738768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb9b4753f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107513d30>, '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 0x10743adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107508be8>, '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.mOcsRWrw2r/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.llLgM9mga4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:23:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:23:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOcsRWrw2r/Render', 02:23:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:47 INFO - 'HOME': '/Users/cltbld', 02:23:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:47 INFO - 'LOGNAME': 'cltbld', 02:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:47 INFO - 'MOZ_NO_REMOTE': '1', 02:23:47 INFO - 'NO_EM_RESTART': '1', 02:23:47 INFO - 'PAGER': '/bin/cat', 02:23:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:47 INFO - 'PWD': '/builds/slave/test', 02:23:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:47 INFO - 'SHELL': '/bin/bash', 02:23:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llLgM9mga4/Listeners', 02:23:47 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:23:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:47 INFO - 'USER': 'cltbld', 02:23:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:47 INFO - 'XPC_FLAGS': '0x0', 02:23:47 INFO - 'XPC_SERVICE_NAME': '0', 02:23:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:47 INFO - Downloading/unpacking blobuploader==1.2.4 02:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:49 INFO - Downloading blobuploader-1.2.4.tar.gz 02:23:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:23:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:23:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:51 INFO - Downloading docopt-0.6.1.tar.gz 02:23:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:23:51 INFO - Installing collected packages: blobuploader, requests, docopt 02:23:51 INFO - Running setup.py install for blobuploader 02:23:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:23:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:23:51 INFO - Running setup.py install for requests 02:23:51 INFO - Running setup.py install for docopt 02:23:52 INFO - Successfully installed blobuploader requests docopt 02:23:52 INFO - Cleaning up... 02:23:52 INFO - Return code: 0 02:23:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:23: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')]} 02:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23: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')]} 02:23:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:52 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 0x107735b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107738768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb9b4753f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107513d30>, '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 0x10743adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107508be8>, '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.mOcsRWrw2r/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.llLgM9mga4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:23:52 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 02:23:52 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 02:23:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOcsRWrw2r/Render', 02:23:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:52 INFO - 'HOME': '/Users/cltbld', 02:23:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:52 INFO - 'LOGNAME': 'cltbld', 02:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:52 INFO - 'MOZ_NO_REMOTE': '1', 02:23:52 INFO - 'NO_EM_RESTART': '1', 02:23:52 INFO - 'PAGER': '/bin/cat', 02:23:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:52 INFO - 'PWD': '/builds/slave/test', 02:23:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:52 INFO - 'SHELL': '/bin/bash', 02:23:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llLgM9mga4/Listeners', 02:23:52 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:23:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:52 INFO - 'USER': 'cltbld', 02:23:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:52 INFO - 'XPC_FLAGS': '0x0', 02:23:52 INFO - 'XPC_SERVICE_NAME': '0', 02:23:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:23:52 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Jc13Id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:23:52 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-rjK9hg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:23:52 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-HM8Cd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:23:52 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-YCvGTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:23:52 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-1Ojr5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:23:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-BJXBFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:23:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-zGSJjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:23:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-G80mNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:23:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-c0hxSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:23:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-GUc0CD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:23:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-GACiB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:23:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-thqLsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:23:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-WgBRuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:23:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Y1wZTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-wkc71x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:23:54 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-6T9_3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:23:54 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-z3ueao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:23:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:23:54 INFO - Running setup.py install for manifestparser 02:23:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:23:54 INFO - Running setup.py install for mozcrash 02:23:54 INFO - Running setup.py install for mozdebug 02:23:54 INFO - Running setup.py install for mozdevice 02:23:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:23:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:23:54 INFO - Running setup.py install for mozfile 02:23:55 INFO - Running setup.py install for mozhttpd 02:23:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:23:55 INFO - Running setup.py install for mozinfo 02:23:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:23:55 INFO - Running setup.py install for mozInstall 02:23:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:23:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:23:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:23:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:23:55 INFO - Running setup.py install for mozleak 02:23:55 INFO - Running setup.py install for mozlog 02:23:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:23:55 INFO - Running setup.py install for moznetwork 02:23:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:23:55 INFO - Running setup.py install for mozprocess 02:23:56 INFO - Running setup.py install for mozprofile 02:23:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:23:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:23:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:23:56 INFO - Running setup.py install for mozrunner 02:23:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:23:56 INFO - Running setup.py install for mozscreenshot 02:23:56 INFO - Running setup.py install for moztest 02:23:56 INFO - Running setup.py install for mozversion 02:23:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:23:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:23:56 INFO - Cleaning up... 02:23:56 INFO - Return code: 0 02:23:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:23:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:56 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 0x107735b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107738768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb9b4753f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107513d30>, '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 0x10743adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107508be8>, '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.mOcsRWrw2r/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.llLgM9mga4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:23:56 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 02:23:56 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 02:23:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOcsRWrw2r/Render', 02:23:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:56 INFO - 'HOME': '/Users/cltbld', 02:23:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:56 INFO - 'LOGNAME': 'cltbld', 02:23:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:56 INFO - 'MOZ_NO_REMOTE': '1', 02:23:56 INFO - 'NO_EM_RESTART': '1', 02:23:56 INFO - 'PAGER': '/bin/cat', 02:23:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:56 INFO - 'PWD': '/builds/slave/test', 02:23:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:56 INFO - 'SHELL': '/bin/bash', 02:23:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llLgM9mga4/Listeners', 02:23:56 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:23:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:56 INFO - 'USER': 'cltbld', 02:23:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:56 INFO - 'XPC_FLAGS': '0x0', 02:23:56 INFO - 'XPC_SERVICE_NAME': '0', 02:23:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:23:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-VqR2mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:23:57 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)) 02:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:23:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-wglIoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:23:57 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)) 02:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:23:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-8W2wr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:23:57 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)) 02:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:23:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-i4xNnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:23:57 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)) 02:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:23:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Lw49yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:23:57 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)) 02:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:23:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-ZClNq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:23:57 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)) 02:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:23:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-uH780U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:23:57 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)) 02:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:23:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-k3KQnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:23:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:23:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-MYeu3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:23:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:23:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-pDfcfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:23:58 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)) 02:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:23:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-A289id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:23:58 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)) 02:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:23:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-fypTJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:23:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:23:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-mtEXV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:23:58 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)) 02:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:23:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-8_nej_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:23:58 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)) 02:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-oH_6g6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:58 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)) 02:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:23:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-ejCsoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:23:58 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)) 02:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:23:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-9QRMKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:23:58 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)) 02:23:58 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)) 02:23:58 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)) 02:23:58 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)) 02:23:58 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)) 02:23:58 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)) 02:23:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:01 INFO - Downloading blessings-1.6.tar.gz 02:24:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:24:01 INFO - Installing collected packages: blessings 02:24:01 INFO - Running setup.py install for blessings 02:24:01 INFO - Successfully installed blessings 02:24:01 INFO - Cleaning up... 02:24:02 INFO - Return code: 0 02:24:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:24:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:24:02 INFO - Reading from file tmpfile_stdout 02:24:02 INFO - Current package versions: 02:24:02 INFO - blessings == 1.6 02:24:02 INFO - blobuploader == 1.2.4 02:24:02 INFO - docopt == 0.6.1 02:24:02 INFO - functools32 == 3.2.3-2 02:24:02 INFO - jsonschema == 2.5.1 02:24:02 INFO - manifestparser == 1.1 02:24:02 INFO - mozInstall == 1.12 02:24:02 INFO - mozcrash == 1.0 02:24:02 INFO - mozdebug == 0.1 02:24:02 INFO - mozdevice == 0.49 02:24:02 INFO - mozfile == 1.2 02:24:02 INFO - mozhttpd == 0.7 02:24:02 INFO - mozinfo == 0.9 02:24:02 INFO - mozleak == 0.1 02:24:02 INFO - mozlog == 3.4 02:24:02 INFO - moznetwork == 0.27 02:24:02 INFO - mozprocess == 0.25 02:24:02 INFO - mozprofile == 0.28 02:24:02 INFO - mozrunner == 6.13 02:24:02 INFO - mozscreenshot == 0.1 02:24:02 INFO - mozsystemmonitor == 0.3 02:24:02 INFO - moztest == 0.8 02:24:02 INFO - mozversion == 1.4 02:24:02 INFO - psutil == 3.1.1 02:24:02 INFO - requests == 1.2.3 02:24:02 INFO - wsgiref == 0.1.2 02:24:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:24:02 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')]} 02:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:02 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')]} 02:24:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:02 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 0x107735b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107738768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb9b4753f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107513d30>, '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 0x10743adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107508be8>, '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.mOcsRWrw2r/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.llLgM9mga4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:24:02 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 02:24:02 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 02:24:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOcsRWrw2r/Render', 02:24:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:02 INFO - 'HOME': '/Users/cltbld', 02:24:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:02 INFO - 'LOGNAME': 'cltbld', 02:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:02 INFO - 'MOZ_NO_REMOTE': '1', 02:24:02 INFO - 'NO_EM_RESTART': '1', 02:24:02 INFO - 'PAGER': '/bin/cat', 02:24:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:02 INFO - 'PWD': '/builds/slave/test', 02:24:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:02 INFO - 'SHELL': '/bin/bash', 02:24:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llLgM9mga4/Listeners', 02:24:02 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:24:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:02 INFO - 'USER': 'cltbld', 02:24:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:02 INFO - 'XPC_FLAGS': '0x0', 02:24:02 INFO - 'XPC_SERVICE_NAME': '0', 02:24:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:02 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)) 02:24:02 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)) 02:24:02 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)) 02:24:02 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)) 02:24:02 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)) 02:24:02 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)) 02:24:02 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)) 02:24:02 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)) 02:24:02 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)) 02:24:02 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)) 02:24:02 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)) 02:24:02 INFO - Cleaning up... 02:24:02 INFO - Return code: 0 02:24:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:24:02 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')]} 02:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:02 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')]} 02:24:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107735b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107738768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb9b4753f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107513d30>, '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 0x10743adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107508be8>, '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.mOcsRWrw2r/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.llLgM9mga4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:24:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:24:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOcsRWrw2r/Render', 02:24:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:02 INFO - 'HOME': '/Users/cltbld', 02:24:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:02 INFO - 'LOGNAME': 'cltbld', 02:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:02 INFO - 'MOZ_NO_REMOTE': '1', 02:24:02 INFO - 'NO_EM_RESTART': '1', 02:24:02 INFO - 'PAGER': '/bin/cat', 02:24:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:02 INFO - 'PWD': '/builds/slave/test', 02:24:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:02 INFO - 'SHELL': '/bin/bash', 02:24:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llLgM9mga4/Listeners', 02:24:02 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:24:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:02 INFO - 'USER': 'cltbld', 02:24:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:02 INFO - 'XPC_FLAGS': '0x0', 02:24:02 INFO - 'XPC_SERVICE_NAME': '0', 02:24:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:24:02 INFO - Cleaning up... 02:24:02 INFO - Return code: 0 02:24:02 INFO - Running post-action listener: _resource_record_post_action 02:24:02 INFO - Running post-action listener: _start_resource_monitoring 02:24:02 INFO - Starting resource monitoring. 02:24:02 INFO - [mozharness: 2017-04-24 09:24:02.866160Z] Finished create-virtualenv step (success) 02:24:02 INFO - [mozharness: 2017-04-24 09:24:02.867206Z] Running install step. 02:24:02 INFO - Running pre-action listener: _resource_record_pre_action 02:24:02 INFO - Running main action method: install 02:24:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:24:03 INFO - Reading from file tmpfile_stdout 02:24:03 INFO - Detecting whether we're running mozinstall >=1.0... 02:24:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:24:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:24:03 INFO - Reading from file tmpfile_stdout 02:24:03 INFO - Output received: 02:24:03 INFO - Usage: mozinstall [options] installer 02:24:03 INFO - Options: 02:24:03 INFO - -h, --help show this help message and exit 02:24:03 INFO - -d DEST, --destination=DEST 02:24:03 INFO - Directory to install application into. [default: 02:24:03 INFO - "/builds/slave/test"] 02:24:03 INFO - --app=APP Application being installed. [default: firefox] 02:24:03 INFO - mkdir: /builds/slave/test/build/application 02:24:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:24:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 02:24:18 INFO - Reading from file tmpfile_stdout 02:24:18 INFO - Output received: 02:24:18 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 02:24:18 INFO - Running post-action listener: _resource_record_post_action 02:24:18 INFO - [mozharness: 2017-04-24 09:24:18.229106Z] Finished install step (success) 02:24:18 INFO - [mozharness: 2017-04-24 09:24:18.229222Z] Running run-tests step. 02:24:18 INFO - Running pre-action listener: _resource_record_pre_action 02:24:18 INFO - Running main action method: run_tests 02:24:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:24:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:24:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:24:18 INFO - 2017-04-24 02:24:18.244 screenresolution[4249:344592] starting screenresolution argv=screenresolution get 02:24:18 INFO - 2017-04-24 02:24:18.246 screenresolution[4249:344592] Display 0: 1600x1200x32@60 02:24:18 INFO - 2017-04-24 02:24:18.252 screenresolution[4250:344594] starting screenresolution argv=screenresolution list 02:24:18 INFO - Available Modes on Display 0 02:24:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:24:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:24:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:24:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:24:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:24:18 INFO - Intel Iris: 02:24:18 INFO - Chipset Model: Intel Iris 02:24:18 INFO - Type: GPU 02:24:18 INFO - Bus: Built-In 02:24:18 INFO - VRAM (Dynamic, Max): 1536 MB 02:24:18 INFO - Vendor: Intel (0x8086) 02:24:18 INFO - Device ID: 0x0a2e 02:24:18 INFO - Revision ID: 0x0009 02:24:18 INFO - Displays: 02:24:18 INFO - Display: 02:24:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:24:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:24:18 INFO - Display Serial Number: 1600x1200 60 02:24:18 INFO - Main Display: Yes 02:24:18 INFO - Mirror: Off 02:24:18 INFO - Online: Yes 02:24:18 INFO - Rotation: Supported 02:24:18 INFO - Return code: 0 02:24:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:24:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:24:18 INFO - Python 2.7.3 02:24:18 INFO - Return code: 0 02:24:18 INFO - grabbing minidump binary from tooltool 02:24:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:18 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 0x107513d30>, '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 0x10743adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107508be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:24:18 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 02:24:18 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 02:24:18 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 02:24:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:24:18 INFO - Return code: 0 02:24:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:24:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:24:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:24:18 INFO - ENV: RUST_BACKTRACE is now 1 02:24:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:24:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:24:18 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/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--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 02:24:18 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/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0236 --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 02:24:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOcsRWrw2r/Render', 02:24:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:18 INFO - 'HOME': '/Users/cltbld', 02:24:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:18 INFO - 'LOGNAME': 'cltbld', 02:24:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:24:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:24:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:18 INFO - 'MOZ_NO_REMOTE': '1', 02:24:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:24:18 INFO - 'NO_EM_RESTART': '1', 02:24:18 INFO - 'PAGER': '/bin/cat', 02:24:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:18 INFO - 'PWD': '/builds/slave/test', 02:24:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:24:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:18 INFO - 'RUST_BACKTRACE': '1', 02:24:18 INFO - 'SHELL': '/bin/bash', 02:24:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llLgM9mga4/Listeners', 02:24:18 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:24:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:18 INFO - 'USER': 'cltbld', 02:24:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:18 INFO - 'XPC_FLAGS': '0x0', 02:24:18 INFO - 'XPC_SERVICE_NAME': '0', 02:24:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:18 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/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--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 02:24:18 INFO - mozversion application_buildid: 20170424010019 02:24:18 INFO - mozversion application_changeset: bb3319dc41bf480dc159ebf7d64be25e3174abe9 02:24:18 INFO - mozversion application_display_name: Firefox 02:24:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:24:18 INFO - mozversion application_name: Firefox 02:24:18 INFO - mozversion application_remotingname: firefox 02:24:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 02:24:18 INFO - mozversion application_vendor: Mozilla 02:24:18 INFO - mozversion application_version: 54.0 02:24:18 INFO - mozversion platform_buildid: 20170424010019 02:24:18 INFO - mozversion platform_changeset: bb3319dc41bf480dc159ebf7d64be25e3174abe9 02:24:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 02:24:18 INFO - mozversion platform_version: 54.0 02:24:18 INFO - using testdate: 1493025858 02:24:18 INFO - actual date: 1493025858 02:24:18 INFO - starting webserver on 'localhost:57528' 02:24:18 INFO - SUITE-START | Running 2 tests 02:24:18 INFO - TEST-START | dromaeo_css 02:24:18 INFO - operating with platform_type : mac_ 02:24:18 INFO - Initialising browser for dromaeo_css test... 02:24:18 INFO - TEST-INFO | started process 4260 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpClMNw3/profile http://localhost:57528/getInfo.html) 02:24:21 INFO - PROCESS | 4260 | 2017-04-24 02:24:21.595 plugin-container[4263:344748] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 02:24:21 INFO - PROCESS | 4260 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:24:21 INFO - PROCESS | 4260 | 2017-04-24 02:24:21.595 plugin-container[4263:344748] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 02:24:21 INFO - PROCESS | 4260 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:24:21 INFO - PROCESS | 4260 | 2017-04-24 02:24:21.595 plugin-container[4263:344748] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:24:21 INFO - PROCESS | 4260 | __metrics Screen width/height:1600/1200 02:24:21 INFO - PROCESS | 4260 | colorDepth:24 02:24:21 INFO - PROCESS | 4260 | Browser inner width/height: 1024/689 02:24:21 INFO - PROCESS | 4260 | __metrics 02:24:21 ERROR - PROCESS | 4260 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 02:24:22 INFO - TEST-INFO | 4260: exit 0 02:24:22 INFO - Browser initialized. 02:24:22 INFO - Running cycle 1/1 for dromaeo_css test... 02:24:22 INFO - TEST-INFO | started process 4264 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpClMNw3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:24:24 INFO - PROCESS | 4264 | 2017-04-24 02:24:24.740 plugin-container[4265:344894] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854f, name = 'com.apple.tsm.portname' 02:24:24 INFO - PROCESS | 4264 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:24:24 INFO - PROCESS | 4264 | 2017-04-24 02:24:24.740 plugin-container[4265:344894] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b03, name = 'com.apple.CFPasteboardClient' 02:24:24 INFO - PROCESS | 4264 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:24:24 INFO - PROCESS | 4264 | 2017-04-24 02:24:24.740 plugin-container[4265:344894] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:24:34 INFO - PROCESS | 4264 | RSS: Main: 174641152 02:24:34 INFO - PROCESS | 4264 | 02:27:36 INFO - PROCESS | 4264 | Cycle 1(1): loaded http://localhost:57528/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57528/tests/dromaeo/cssquery-ext.html) 02:27:38 INFO - PROCESS | 4264 | RSS: Main: 189063168 02:27:38 INFO - PROCESS | 4264 | 02:30:40 INFO - PROCESS | 4264 | Cycle 1(1): loaded http://localhost:57528/tests/dromaeo/cssquery-ext.html (next: http://localhost:57528/tests/dromaeo/cssquery-jquery.html) 02:30:41 INFO - PROCESS | 4264 | RSS: Main: 199983104 02:30:41 INFO - PROCESS | 4264 | 02:33:43 INFO - PROCESS | 4264 | Cycle 1(1): loaded http://localhost:57528/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57528/tests/dromaeo/cssquery-mootools.html) 02:33:43 INFO - PROCESS | 4264 | RSS: Main: 204857344 02:33:43 INFO - PROCESS | 4264 | 02:36:45 INFO - PROCESS | 4264 | Cycle 1(1): loaded http://localhost:57528/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57528/tests/dromaeo/cssquery-prototype.html) 02:36:46 INFO - PROCESS | 4264 | RSS: Main: 207929344 02:36:46 INFO - PROCESS | 4264 | 02:39:49 INFO - PROCESS | 4264 | Cycle 1(1): loaded http://localhost:57528/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57528/tests/dromaeo/cssquery-yui.html) 02:39:50 INFO - PROCESS | 4264 | RSS: Main: 210452480 02:39:50 INFO - PROCESS | 4264 | 02:42:52 INFO - PROCESS | 4264 | Cycle 1(1): loaded http://localhost:57528/tests/dromaeo/cssquery-yui.html (next: http://localhost:57528/tests/dromaeo/cssquery-dojo.html) 02:42:53 INFO - PROCESS | 4264 | RSS: Main: 208433152 02:42:53 INFO - PROCESS | 4264 | 02:42:53 INFO - PROCESS | 4264 | __start_tp_report 02:42:53 INFO - PROCESS | 4264 | _x_x_mozilla_page_load 02:42:53 INFO - PROCESS | 4264 | _x_x_mozilla_page_load_details 02:42:53 INFO - PROCESS | 4264 | |i|pagename|runs| 02:42:53 INFO - PROCESS | 4264 | |0;dojo.html;2699.9470206619435;3281.78668386555;3542.681158695718;3570.268095040517;3583.6058033616305;5988.431099045594;6052.818415447544;6059.151718759377;6068.302145253298;6068.726907289171;5134.024535338283;5901.937651222789;6000.519958403328;6005.789797357093;6034.125051867477;5405.864853378667;5526.50261476467;5528.226048353234;5548.6393384430085;5550.639208451458;5566.332040155166;5574.804881829137;5581.553475721932;5585.218069470277;5590.608657393984;5361.571074314045;5393.164059570773;5574.303212098488;5574.554035677136;5578.888422231553;5521.254630624874;5531.695756733378;5599.4120617335;5631.211630371751;5658.43415658435;5392.137258038695;5499.890002199973;5512;5520.503154716095;5571.66570005801;4390.385346051556;5134.743262836843;5473.850491396812;5809.535237181015;6172.660503672296;6239.313675495692;6269.122322874801;6309.558330916838;6320.146780184662;6333.271673757485;6430.839229019265;6447.2907980122145;6454.128692626529;6461.095446637474;6461.450776683988;5051.87370315745;5958.19564358806;6143.1092491588915;6156.569040167235;6159.81520554384;4908.460069392364;6439.776442476008;6541.705623246965;6559.934400656028;6565.048068030109;6261.342559031327;6283.842903927439;6339.366063393716;6351.682415879188;6352.078948552434;5022.296878437021;6462.547621666532;6466.741330346827;6482.546221764431;6487.3836985486305;6199.628022318675;6226.688665566703;6239.688015599293;6242.656653883989;6279.340669229757;6338.968305158444;6383.39357761012;6392.808215753535;6394.8721025579225;6397.10440538325;5127.333446651986;5136;5138.845834624893;5154.329937108227;5190.766415511311;4433.202023635712;4487.259602165606;4533.297338912474;4569.428821397325;4588.7935042923145;5443.673379597237;6202.100695399142;6220.720067596879;6235.594686345373;6254.405831446005;6236.812895613139;6473.546851720334;6507.609543427409;6520.902186467207;6538.476921846241;5847.473727364558;6126.9452004266695;6147.815565533041;6190.1952746142715;6200.3799620037635;4374.562543745664;5694.4590263924865;5706.087026075808;5710.657360558297;5722.942770572325;6740.191177058687;6743.089682892747;6752.08846805675;6755.594664320157;6760.256371799;6204.3795620437595;6269.592476489013;6389.584676995981;6391.776287829904;6437.7103030362805;6655.334466553306;6728.293529179366;6738.898916516354;6743.089682892747;6759.797206083826;6363.459105976044;6365.1088847562305;6373.8725225496155;6386.361363863576;6410.967945160244;6231.034189700604;6267.686615669107;6367.076773867856;6473.385028422293;6503.349665033459;4564.589186973189;6268.934281172303;6277.2781130170615;6295.779647712308;6303.243610766737;6283.748650053946;6292.370762923671;6301.590396624205;6312.936870631235;6317.241930968313;4722.126406614787;6068.544859135671;6197.569931635648;6208.782692605737;6240.563160578716;6232.968835155795;6284.560080794484;6291.433770960635;6298.653574053471;6309.621422714652;6209.59637623553;6225.3774622539195;6269.12232287471;6277.811665650038;6284.088807123033 02:42:53 INFO - PROCESS | 4264 | |1;ext.html;791;823.7569916874521;895.4314010603267;966.0822218892055;973.532704301935;39731.404028939556;40227.79886100566;40252.9936751581;40253.396199057;40316.596834031654;40203;40328.39507407391;40360.79819600898;40411;40420.59579404205;7914.28771410573;8041.437099403015;8224.095349511565;11768.058555315554;11986.340751258676;11992.7601447971;12078.637640870787;12080.818787718192;12085.15403921726;12101.576444824434;9957.302988790787;12078.396080195955;12081;12111;12141.757164856697;12021.978131858803;12044.638660840188;12063.758724825455;12078.758424831542;12095.21381110225;9700.854487182698;11655.883441165564;11705.473253703518;11727.296362218294;11731;41164.1767164658;41279;41359.58640413587;41363.58636413657;41408.378874316906;3394.5247665326874;3954.6638535724496;3955.367141257414;4001.819918103663;4060.553339132693;29152.854235728897;29462.82148714049;29480.410391792044;30015.249618759488;31202.751889924377;3129.092563156682;3283.113559338965;3294.8517316720804;3395.898123056312;3413.0272872231544;347780;348885.5111448904;349133;349976;350112;44738;45471.54528454739;52973.47026529763;53929.73035134799;54135.72932135393;57004;57698.711506442196;57966;58338;58342.41657583455;15465.072095674295;15475.535733928;15531.611709707347;15536.378544858306;15565.455209067628;13514.459421623222;13532;13540.729185416236;13567.439744429517;13576.932115339558;12491.312977786309;12497.93751031239;12511.249325040526;12591.937040314922;12597.433115509824;5805.419458054245;5806.477417032487;5807.796727114615;5822.912656310159;5842.298924129132;1158.5192145259623;1165.8134698448207;1198.25708061001;1200.7752092865228;1205.879412058787;27807.30481737932;27857.72142278551;27979.860100699367;28089.43821123566;28267.57598636022;1263.33674820718;1282.58316047285;1289.548657969703;1340.4571148684797;1345.1727187779445;13059.542915998085;13866.098703584235;14437;14569.490067847575;14580;397488;397830;399236;399317;399551;55832.72083639637;56473.7176314124;56560.151597726064;56726.7163664179;56771.43228567744;1479.2973337664523;1483.4362942081939;1520.8327084020748;1534.2175490499926;1539.3765524962407;25646;26611.33471663224;27037.324066898484;27177.45645087127;27231;47052.7647361761;47126.82182945454;47160.05679886452;47220.291695623906;47267.290990635855;335098;375999;384640;385480;387372;29900;30382.392352153274;34979.82510087433;35137;35846.28307433784;46616.06767864556;46792.53207468018;46929;47856.80357991078;49111;31835.363292733553;32008.35983280368;32024.839875800473;32162.87429939941;32723.67276327206;31754.52368214525;32289.28189388805;32576.674233257363;32581.02256932296;32663;32619.34761304808;40586.39120413255;40772.59227407688;42451.150976980905;42869;40959.59040409558;41248;42319.3652095225;43456.91357715956;43458.56541434545;26910.865445672647;34360.82819585886;34443.827780860935;34471.138221544665;34884.47673284852 02:42:53 INFO - PROCESS | 4264 | |2;jquery.html;367.2269871919609;405.2523094889928;426.7418211981753;431.512390998172;477.2125992113013;20215.494612634673;21743.67384489232;22291.44271393216;22469.55060898785;22571.887140564293;22469;23358.41603959902;24196.03215871363;24299.51400971984;24347.878260608715;7144.821379465516;7295.283384900053;7364.079490063742;7405.8889116663295;7432.219616940224;6867.519273650846;7222.747203847866;7233.927660723378;7246.8188295292775;7251.70993160273;7052.753153639624;7184.856302873939;7188.676509557083;7216.747413840517;7318.7438439654625;7080.150381954147;7116.537425067406;7226.6025368604705;7271.200167981518;7296.817579560527;6981.546199497067;7009.964950175267;7028.613426261553;7042.683079261395;7045.260247673972;27638;27748.722512775017;28073.43853122926;28150;28677.426451470852;2958.363951750384;3097.396007778484;3233.7898036627544;3487.232808782064;3545.4859045438266;16147;17405.390811321544;17574;17752.644947101115;18075.54811129719;1666.96666066678;1839.4481655503296;1859.2297476759666;1866.8693191476602;1882.9246830126779;85439;131049.34475327563;136787;143273;146700.26649866896;23922.7607723924;24640.630390544156;24647.630285545732;24677.38306542351;24723;38256.617433825304;38838.22323553513;38884.8055759725;39113.02217444587;39228.803855980535;11271.774564508663;11411.087113031104;11757.941785239369;11795.115366347558;11953;9520.428774273565;9525.333226674065;9543.427394356222;9688.515574221261;9703.9029609703;11018.889811101946;11226.775464490664;11280.830787538194;11330.716732081764;11375.829362559569;3553.8400771965325;3604.945925811115;3925.74482658632;4015.9357770190923;4322.740635561876;841.1420351241699;857.691231156783;958.5351104714348;967.9854802177973;1008.324422636835;22485.775142248694;23895.641565376533;23978;24086.759132408453;24129.758702413103;989.1443901025705;996.1781530237583;1012.4684540616236;1031.2677998620914;1031.4791030529534;9529.90470095304;9530;12119.939400302943;12750.48998040068;12816.166949148275;799967;800365;802850;804265;812159;141986;168604;171853;174001;176921;1408.8661577150153;1413.8868890488736;1455.1850963460283;1474.0197768484052;1474.6460849396067;18143.639227058287;18873.528161796065;19991.264988485622;20930.0581473831;20961.475963101046;57535;57636.71181644233;57872.421275786706;57961;58094;62769;67179;79918.60040699759;81731.59134204291;82259.5887020561;10281.177505799667;11027.393493357942;11474.082073434272;11571.942140289244;11658.6502404928;11068.391238481961;13449.596512104652;13554.661133471742;13597.932010339884;13695.08268842343;9494.95252523733;11448.141389395994;11733.00269477104;11745.647630571097;11969.10231732641;10484.475776211006;10684.732881678021;10784.784304314027;10860.619878304222;10940;10143.594256229964;11543.134264930331;11801.822972655245;11847.348395838311;11975.401229938294;8905.55472226399;11149.72125696864;11607.89724976126;11864.703382415508;12003.339816310187;11587.942060289644;11772;11832.467538960595;11920.701982450508;12015.75968480643 02:42:53 INFO - PROCESS | 4264 | |3;mootools.html;1600.759886017098;1616.6524197297579;1631.812341580718;1640.3192675039859;1653.0908000599668;11490.597829075985;11711.824322635146;11993.640190794269;12111.939440302785;12141.57504487342;11599.956003959642;11709.414529273545;11827.34949577773;11836;11838.526458941653;2445.327534927891;2489.0914816092086;2494.4761600063916;2495.3262619092834;2500.4124030852736;2429.5626787178303;2467.481828815951;2476.7399423060588;2497.7377375375595;2509.2346834215555;2435.2207293666033;2443.7067551893797;2460.3357093584723;2484.043643197364;2485.4159272570932;2429.514097180553;2430.647556104364;2441.3530414440097;2458.9918133565243;2472.4560596668703;2442.9389265268333;2455.1284294075526;2455.2020593307248;2464.137551856854;2479.3305807431893;10967.780644387147;11033.282836615675;11064.17018723591;11072.723181920435;11086.556537738481;5929.080992446177;6112.327643959161;6121.877562448726;6127.050307202347;6138.938610613882;3414.078198886287;3511.7541772075965;3570.83931223093;3717.4609681596016;3761.360568703327;1133.18410744264;1155.249088092738;1161.268400907423;1276.546825876819;1285.5693342730196;14327.856721432756;14625;15298.08211507302;16396.75404868928;16617.41839035628;4826.203676393356;4937.530934561211;4944.826931057396;4956.008798240367;4984.37695288089;12378.566750163702;12407.069469789802;12428.192167509264;12429.254244745343;12450.190737602026;5330.413654498002;5488.533474654699;5986.73059712314;6072.848178795565;6080.969595151996;5419.647722897999;5441.537469315073;5459.1265397537;5475;5493.313335833021;3707.2029513654693;3958.6437220650278;4056.513218413809;4148.543660197376;4149.294619914622;1067.3755852826184;1070.0690399352613;1072.6460268111505;1082.718493191776;1103.5585765693627;989.9109080182818;993.1955116355775;994.4182653147844;1022.6625213679467;1036.2435422141878;5759.539236861041;6948.965255173793;6984.02223688684;7080;7152.392046676091;976.7790262172284;1017.3641474078701;1022.6676330192718;1043.1498328861953;1091.4379094766202;4154.958450415457;4399.076193999296;4412.69111162222;4503.392042074343;4626.814927402934;11993;13804;14080.577582672537;14385.6403589909;14422.567322980327;13775.311234438239;13809.654758631115;14150.787738183935;14152.575422737335;14158.929205354114;9094.545272736337;9413.38812977168;10302.278840481244;10312.175025997902;10550.261481696207;6592.307807680317;7048.365647091787;7097.787066388017;7149.356557909813;7273.163586187656;12565.371731413576;12868.806967895302;12942.482300707867;12987.805182922075;13128.671783205114;10693.304935179189;11593.130515211224;12178.391080445765;12283.324417157142;12492.562760303346;9352.625894964125;9705.5147242639;9780.217582593234;9811.166050885755;9827.066428689262;8174.509529428254;10632.84050739224;10654.200934929757;10767.676969690921;10791.676249712522;9028.593713282786;9152.725418237464;9189.540522973692;9348.205402540589;9363.531823408939;8901.777455563662;8939.150780675825;8954.955225223832;8981.506017169118;9124;8363.874541881998;8373.66505339804;8382.958085209535;8427.620757065824;8502.107278735644;5956.315023772148;5982.102684597346;6021.187139736079;6147.170132032297;6180.845478863064;6036.667983260787;6089.878202436015;6258.217722784652;6304.905426418516;6351.96824015877 02:42:53 INFO - PROCESS | 4264 | |4;prototype.html;350.3168820799441;385.70300868331367;389.5695256741301;400.50337581399066;404.1008755518969;4175.724230294493;4331.350297455383;4770.284457331402;5074.923876141861;5082.618803589732;4188.057687020419;4982.551570358667;5083.79664813407;5090.541851233388;5136.974315128428;4146.958530414688;4226.4852412814325;4227.175700738358;4265.594768497003;4296.806643701041;3570.321638888616;3904.707146963975;4235.512916014667;4334.328179132223;4357.586029327209;3474.3617270943323;3602.9279414411694;4109.815058322368;4282.850100246492;4307.6123148916595;3516.7889926604485;3956.3274243378405;4172.937405938914;4312.482502099752;4312.82748690052;3778.8677396291273;3940.9802950985063;3949.2891279569667;3965.8215380307956;3987.800609969519;4017.819198136091;4309.547497512779;4702.8118875244945;4993.302277225761;5000.674956127877;4260.677930527839;4607.930881036754;4961.330220420269;4974.5325129087005;5006.5494105530315;4338.913221735579;5034.572061374751;5203.1414816555225;5506.449355064502;5536.560494271518;3667.5965643779164;3999.8800035998966;4206.254404422161;4396.538363471821;4452.688311818174;5171.526629465523;5328.920066199049;5577.749001294951;5690.857728556819;5911.29064512257;3947.2894878921497;3974.364101743707;4277.26770657884;4291.570842915684;4327.6970612057485;4466.570697376808;4584.7936842842155;4610.515895830957;4619.376384188092;4640.327152562927;4155.58444155582;4384.671149663788;4432.800523976429;4644;4785.473597904228;4828;5137.486251374833;5497.780088796483;5508.504234618904;5549.528290095387;1348.5414958914016;1443.2783608195903;1522.893397462167;1524.6112241378378;1593.251171949182;1096.2928910852615;1107.3023994883172;1182.4028865422906;1187.2935603316014;1215.851020785367;4471.038726673716;4746.644001699887;4866.765913655143;4877.512248775094;5038.84883453497;4915.48387419323;5200.375954885362;5271.182966640177;5638.182463542763;5742.7415766290615;4484.439445069366;4632.189366860786;4986.531666824945;5052.418971818288;5097.46476619957;2713.416615427653;2763;2790.037437084203;2938.5151450010935;2986.925326866846;5078.314427552233;5478.342598888159;5532.5297349724915;5819.912701309484;5981.581289309794;4535.9773201133785;5442.510174084351;5567.944320556823;5595.804146854922;5825.446582574648;5213.627799351286;5292.338457692788;5301.469853014668;5315.84052478434;5583.748731307182;4938.481459446706;5232.241325007776;5687;5720.48515633595;5750.297360240847;4844.95155048445;5526.47498487643;5546.528545073714;5560.638558493685;5622.9156562652415;5057.760848592118;5663.631863928831;5733.684647344436;5744.655320680772;5758.510526605285;4087.118581400165;4203;4316.525182230015;4429.136318417877;4519.276915693474;4652.371929789435;4920.40955085392;5060.595152387874;5503.358387973715;5762.510186634183;4590.931136032896;4620;5266.2884562516565;5459.972700136474;5525.806596769094;4596.839110631112;5323.908598737327;5513.807016754395;5519.3652729936575;5554.833354999357;4881.707097574157;5084.500072478554;5110.565601923878;5367.570594352521;5410.618363522798;4399.934000989923;4499.752513611652;4884;5144;5454.563634909118;5163.793448262028;5363.222332761806;5392.919106213489;5414.485623865727;5437.374701909331 02:42:53 INFO - PROCESS | 4264 | |5;yui.html;742.1131747561666;788.0897563314373;799.6401619271328;819.8603940522675;876.7063033883646;16334.918325408371;17069.82930170698;17151.885127466707;17310.826891731078;17438;15836.84163158368;16054.59863503413;16057.518274451755;16060.919695401506;16083.356665733387;968.7190714692748;985.1035557642547;998.6704387552127;1033.9379637221753;1039.937603743774;1013.6756238003842;1018.7504061504942;1029.5984566019258;1030.2479190191127;1031.762694580247;1053.615430367918;1065.424670677837;1069.3476979042778;1071.3678929431633;1071.4214324264888;889.3374436045123;1050.4432650695144;1062.946852657364;1064.0423618743116;1066.8826429092794;787.1144961917843;790.8220650353682;792.5522080024767;792.774059393076;793.694427645355;17140.571485712957;17197.570060748458;17370.39203627867;17920.014399208038;18167.63664726698;12095.395230238453;14477;14908.850911490854;14914.254287285592;15278.465253716067;11500;11790.292582444996;12103.334316612583;12103.697407564794;12402.19385739924;4524.095180963787;4618.976905115486;4679.298105284234;4791.377120974286;4832.395950506187;84629.57685211535;101115.49442252815;108835;115915.42042289818;119345;11012.614558490415;11120.315384999283;11139.387333696724;11199.440027998568;11430.485628146753;22260.22089226869;22350.66474002891;22405.439864003205;22595.548089038126;22673.2064377746;7768.300852923264;7816.921830781733;7863.803404914923;7874.527528348317;7975.52146871178;3888.1446081861945;3997.860609726214;4453.354263631755;4525.117602067565;4657.6273898088075;3191.5212718092007;3229.273413481948;3234.3046245056953;3256.202230453554;3257.918552036218;491.6140829448937;599.3646734461485;608.4250383387745;611.0406661541338;632.5161251642497;692.5083190934392;693.2582137113341;737.6643627149634;742.5915746339492;751.7331347371713;10891.237613366988;11007.06439952597;11011.229213954946;11125.331200320017;11185;601.6781022153127;608.8995315772936;618.6071844378852;648.9059086432441;736.9461669812113;4082.510098788164;4269.807858646368;5116.872078198075;5298.867528311746;5316.521513063843;70567;76435.70692879222;88012;92762;93902;75294.8705769414;77563.2243677556;83038;84152.57923710342;86503.56748216345;71254;72486.63756681183;78676.60661696541;81332.78000829992;83244;10588.83268947404;11247.325160490396;11389.43052847371;11488.770224595293;11522.82715759281;47867.52132478631;48324;49681.50318496769;50560.74719626379;52278.738606308245;71757.28242717506;71925;74428.62785686037;74481;75931.62034189794;12995.740085198433;13056;13502.86497135016;13565.728685426038;13571.932140339235;15326.540203793904;15446.84553154454;15740.055596664237;15799.526014219591;16302.67394652124;14497.347619356944;14555.41778328855;14606;14610;14706.779398309249;13918.174626472402;14382.928085359506;14563.41746330135;14564.85435145635;14736;13442.529511467052;14099.788503172256;14206.289685515889;14223.431062757374;14355.856441435451;9675.403558412783;10142.087212150942;10155.289129760846;10181.89818101839;10687.572497100029;8702.651893924172;10342;11386.829197561878;11608.88391116078;11883.465244063866 02:42:53 INFO - PROCESS | 4264 | __end_tp_report 02:42:53 INFO - PROCESS | 4264 | __start_cc_report 02:42:53 INFO - PROCESS | 4264 | _x_x_mozilla_cycle_collect,391 02:42:53 INFO - PROCESS | 4264 | __end_cc_report 02:42:53 INFO - PROCESS | 4264 | __startTimestamp1493026973045__endTimestamp 02:42:53 INFO - PROCESS | 4264 | 02:42:53 INFO - PROCESS | 4264 | ------- Summary: start ------- 02:42:53 INFO - PROCESS | 4264 | Number of tests: 6 02:42:53 INFO - PROCESS | 4264 | 02:42:53 INFO - PROCESS | 4264 | [#0] dojo.html Cycles:165 Average:5915.35 Median:6220.72 stddev:720.62 (11.6%) stddev-sans-first:677.24 02:42:53 INFO - PROCESS | 4264 | Values: 2699.9 3281.8 3542.7 3570.3 3583.6 5988.4 6052.8 6059.2 6068.3 6068.7 5134.0 5901.9 6000.5 6005.8 6034.1 5405.9 5526.5 5528.2 5548.6 5550.6 5566.3 5574.8 5581.6 5585.2 5590.6 5361.6 5393.2 5574.3 5574.6 5578.9 5521.3 5531.7 5599.4 5631.2 5658.4 5392.1 5499.9 5512.0 5520.5 5571.7 4390.4 5134.7 5473.9 5809.5 6172.7 6239.3 6269.1 6309.6 6320.1 6333.3 6430.8 6447.3 6454.1 6461.1 6461.5 5051.9 5958.2 6143.1 6156.6 6159.8 4908.5 6439.8 6541.7 6559.9 6565.0 6261.3 6283.8 6339.4 6351.7 6352.1 5022.3 6462.5 6466.7 6482.5 6487.4 6199.6 6226.7 6239.7 6242.7 6279.3 6339.0 6383.4 6392.8 6394.9 6397.1 5127.3 5136.0 5138.8 5154.3 5190.8 4433.2 4487.3 4533.3 4569.4 4588.8 5443.7 6202.1 6220.7 6235.6 6254.4 6236.8 6473.5 6507.6 6520.9 6538.5 5847.5 6126.9 6147.8 6190.2 6200.4 4374.6 5694.5 5706.1 5710.7 5722.9 6740.2 6743.1 6752.1 6755.6 6760.3 6204.4 6269.6 6389.6 6391.8 6437.7 6655.3 6728.3 6738.9 6743.1 6759.8 6363.5 6365.1 6373.9 6386.4 6411.0 6231.0 6267.7 6367.1 6473.4 6503.3 4564.6 6268.9 6277.3 6295.8 6303.2 6283.7 6292.4 6301.6 6312.9 6317.2 4722.1 6068.5 6197.6 6208.8 6240.6 6233.0 6284.6 6291.4 6298.7 6309.6 6209.6 6225.4 6269.1 6277.8 6284.1 02:42:53 INFO - PROCESS | 4264 | 02:42:53 INFO - PROCESS | 4264 | [#1] ext.html Cycles:180 Average:53623.16 Median:18886.93 stddev:98521.89 (521.6%) stddev-sans-first:98718.42 02:42:53 INFO - PROCESS | 4264 | Values: 791.0 823.8 895.4 966.1 973.5 39731.4 40227.8 40253.0 40253.4 40316.6 40203.0 40328.4 40360.8 40411.0 40420.6 7914.3 8041.4 8224.1 11768.1 11986.3 11992.8 12078.6 12080.8 12085.2 12101.6 9957.3 12078.4 12081.0 12111.0 12141.8 12022.0 12044.6 12063.8 12078.8 12095.2 9700.9 11655.9 11705.5 11727.3 11731.0 41164.2 41279.0 41359.6 41363.6 41408.4 3394.5 3954.7 3955.4 4001.8 4060.6 29152.9 29462.8 29480.4 30015.2 31202.8 3129.1 3283.1 3294.9 3395.9 3413.0 347780.0 348885.5 349133.0 349976.0 350112.0 44738.0 45471.5 52973.5 53929.7 54135.7 57004.0 57698.7 57966.0 58338.0 58342.4 15465.1 15475.5 15531.6 15536.4 15565.5 13514.5 13532.0 13540.7 13567.4 13576.9 12491.3 12497.9 12511.2 12591.9 12597.4 5805.4 5806.5 5807.8 5822.9 5842.3 1158.5 1165.8 1198.3 1200.8 1205.9 27807.3 27857.7 27979.9 28089.4 28267.6 1263.3 1282.6 1289.5 1340.5 1345.2 13059.5 13866.1 14437.0 14569.5 14580.0 397488.0 397830.0 399236.0 399317.0 399551.0 55832.7 56473.7 56560.2 56726.7 56771.4 1479.3 1483.4 1520.8 1534.2 1539.4 25646.0 26611.3 27037.3 27177.5 27231.0 47052.8 47126.8 47160.1 47220.3 47267.3 335098.0 375999.0 384640.0 385480.0 387372.0 29900.0 30382.4 34979.8 35137.0 35846.3 46616.1 46792.5 46929.0 47856.8 49111.0 31835.4 32008.4 32024.8 32162.9 32723.7 31754.5 32289.3 32576.7 32581.0 32663.0 32619.3 40586.4 40772.6 42451.2 42869.0 40959.6 41248.0 42319.4 43456.9 43458.6 26910.9 34360.8 34443.8 34471.1 34884.5 02:42:53 INFO - PROCESS | 4264 | 02:42:53 INFO - PROCESS | 4264 | [#2] jquery.html Cycles:180 Average:44526.96 Median:24108.26 stddev:133269.38 (552.8%) stddev-sans-first:133601.99 02:42:53 INFO - PROCESS | 4264 | Values: 367.2 405.3 426.7 431.5 477.2 20215.5 21743.7 22291.4 22469.6 22571.9 22469.0 23358.4 24196.0 24299.5 24347.9 7144.8 7295.3 7364.1 7405.9 7432.2 6867.5 7222.7 7233.9 7246.8 7251.7 7052.8 7184.9 7188.7 7216.7 7318.7 7080.2 7116.5 7226.6 7271.2 7296.8 6981.5 7010.0 7028.6 7042.7 7045.3 27638.0 27748.7 28073.4 28150.0 28677.4 2958.4 3097.4 3233.8 3487.2 3545.5 16147.0 17405.4 17574.0 17752.6 18075.5 1667.0 1839.4 1859.2 1866.9 1882.9 85439.0 131049.3 136787.0 143273.0 146700.3 23922.8 24640.6 24647.6 24677.4 24723.0 38256.6 38838.2 38884.8 39113.0 39228.8 11271.8 11411.1 11757.9 11795.1 11953.0 9520.4 9525.3 9543.4 9688.5 9703.9 11018.9 11226.8 11280.8 11330.7 11375.8 3553.8 3604.9 3925.7 4015.9 4322.7 841.1 857.7 958.5 968.0 1008.3 22485.8 23895.6 23978.0 24086.8 24129.8 989.1 996.2 1012.5 1031.3 1031.5 9529.9 9530.0 12119.9 12750.5 12816.2 799967.0 800365.0 802850.0 804265.0 812159.0 141986.0 168604.0 171853.0 174001.0 176921.0 1408.9 1413.9 1455.2 1474.0 1474.6 18143.6 18873.5 19991.3 20930.1 20961.5 57535.0 57636.7 57872.4 57961.0 58094.0 62769.0 67179.0 79918.6 81731.6 82259.6 10281.2 11027.4 11474.1 11571.9 11658.7 11068.4 13449.6 13554.7 13597.9 13695.1 9495.0 11448.1 11733.0 11745.6 11969.1 10484.5 10684.7 10784.8 10860.6 10940.0 10143.6 11543.1 11801.8 11847.3 11975.4 8905.6 11149.7 11607.9 11864.7 12003.3 11587.9 11772.0 11832.5 11920.7 12015.8 02:42:53 INFO - PROCESS | 4264 | 02:42:53 INFO - PROCESS | 4264 | [#3] mootools.html Cycles:180 Average:6948.51 Median:2484.73 stddev:4317.18 (173.7%) stddev-sans-first:4310.59 02:42:53 INFO - PROCESS | 4264 | Values: 1600.8 1616.7 1631.8 1640.3 1653.1 11490.6 11711.8 11993.6 12111.9 12141.6 11600.0 11709.4 11827.3 11836.0 11838.5 2445.3 2489.1 2494.5 2495.3 2500.4 2429.6 2467.5 2476.7 2497.7 2509.2 2435.2 2443.7 2460.3 2484.0 2485.4 2429.5 2430.6 2441.4 2459.0 2472.5 2442.9 2455.1 2455.2 2464.1 2479.3 10967.8 11033.3 11064.2 11072.7 11086.6 5929.1 6112.3 6121.9 6127.1 6138.9 3414.1 3511.8 3570.8 3717.5 3761.4 1133.2 1155.2 1161.3 1276.5 1285.6 14327.9 14625.0 15298.1 16396.8 16617.4 4826.2 4937.5 4944.8 4956.0 4984.4 12378.6 12407.1 12428.2 12429.3 12450.2 5330.4 5488.5 5986.7 6072.8 6081.0 5419.6 5441.5 5459.1 5475.0 5493.3 3707.2 3958.6 4056.5 4148.5 4149.3 1067.4 1070.1 1072.6 1082.7 1103.6 989.9 993.2 994.4 1022.7 1036.2 5759.5 6949.0 6984.0 7080.0 7152.4 976.8 1017.4 1022.7 1043.1 1091.4 4155.0 4399.1 4412.7 4503.4 4626.8 11993.0 13804.0 14080.6 14385.6 14422.6 13775.3 13809.7 14150.8 14152.6 14158.9 9094.5 9413.4 10302.3 10312.2 10550.3 6592.3 7048.4 7097.8 7149.4 7273.2 12565.4 12868.8 12942.5 12987.8 13128.7 10693.3 11593.1 12178.4 12283.3 12492.6 9352.6 9705.5 9780.2 9811.2 9827.1 8174.5 10632.8 10654.2 10767.7 10791.7 9028.6 9152.7 9189.5 9348.2 9363.5 8901.8 8939.2 8955.0 8981.5 9124.0 8363.9 8373.7 8383.0 8427.6 8502.1 5956.3 5982.1 6021.2 6147.2 6180.8 6036.7 6089.9 6258.2 6304.9 6352.0 02:42:53 INFO - PROCESS | 4264 | 02:42:53 INFO - PROCESS | 4264 | [#4] prototype.html Cycles:180 Average:4485.72 Median:4806.74 stddev:1248.49 (26.0%) stddev-sans-first:1212.79 02:42:53 INFO - PROCESS | 4264 | Values: 350.3 385.7 389.6 400.5 404.1 4175.7 4331.4 4770.3 5074.9 5082.6 4188.1 4982.6 5083.8 5090.5 5137.0 4147.0 4226.5 4227.2 4265.6 4296.8 3570.3 3904.7 4235.5 4334.3 4357.6 3474.4 3602.9 4109.8 4282.9 4307.6 3516.8 3956.3 4172.9 4312.5 4312.8 3778.9 3941.0 3949.3 3965.8 3987.8 4017.8 4309.5 4702.8 4993.3 5000.7 4260.7 4607.9 4961.3 4974.5 5006.5 4338.9 5034.6 5203.1 5506.4 5536.6 3667.6 3999.9 4206.3 4396.5 4452.7 5171.5 5328.9 5577.7 5690.9 5911.3 3947.3 3974.4 4277.3 4291.6 4327.7 4466.6 4584.8 4610.5 4619.4 4640.3 4155.6 4384.7 4432.8 4644.0 4785.5 4828.0 5137.5 5497.8 5508.5 5549.5 1348.5 1443.3 1522.9 1524.6 1593.3 1096.3 1107.3 1182.4 1187.3 1215.9 4471.0 4746.6 4866.8 4877.5 5038.8 4915.5 5200.4 5271.2 5638.2 5742.7 4484.4 4632.2 4986.5 5052.4 5097.5 2713.4 2763.0 2790.0 2938.5 2986.9 5078.3 5478.3 5532.5 5819.9 5981.6 4536.0 5442.5 5567.9 5595.8 5825.4 5213.6 5292.3 5301.5 5315.8 5583.7 4938.5 5232.2 5687.0 5720.5 5750.3 4845.0 5526.5 5546.5 5560.6 5622.9 5057.8 5663.6 5733.7 5744.7 5758.5 4087.1 4203.0 4316.5 4429.1 4519.3 4652.4 4920.4 5060.6 5503.4 5762.5 4590.9 4620.0 5266.3 5460.0 5525.8 4596.8 5323.9 5513.8 5519.4 5554.8 4881.7 5084.5 5110.6 5367.6 5410.6 4399.9 4499.8 4884.0 5144.0 5454.6 5163.8 5363.2 5392.9 5414.5 5437.4 02:42:53 INFO - PROCESS | 4264 | 02:42:53 INFO - PROCESS | 4264 | [#5] yui.html Cycles:180 Average:20306.40 Median:17679.01 stddev:27858.16 (157.6%) stddev-sans-first:27897.58 02:42:53 INFO - PROCESS | 4264 | Values: 742.1 788.1 799.6 819.9 876.7 16334.9 17069.8 17151.9 17310.8 17438.0 15836.8 16054.6 16057.5 16060.9 16083.4 968.7 985.1 998.7 1033.9 1039.9 1013.7 1018.8 1029.6 1030.2 1031.8 1053.6 1065.4 1069.3 1071.4 1071.4 889.3 1050.4 1062.9 1064.0 1066.9 787.1 790.8 792.6 792.8 793.7 17140.6 17197.6 17370.4 17920.0 18167.6 12095.4 14477.0 14908.9 14914.3 15278.5 11500.0 11790.3 12103.3 12103.7 12402.2 4524.1 4619.0 4679.3 4791.4 4832.4 84629.6 101115.5 108835.0 115915.4 119345.0 11012.6 11120.3 11139.4 11199.4 11430.5 22260.2 22350.7 22405.4 22595.5 22673.2 7768.3 7816.9 7863.8 7874.5 7975.5 3888.1 3997.9 4453.4 4525.1 4657.6 3191.5 3229.3 3234.3 3256.2 3257.9 491.6 599.4 608.4 611.0 632.5 692.5 693.3 737.7 742.6 751.7 10891.2 11007.1 11011.2 11125.3 11185.0 601.7 608.9 618.6 648.9 736.9 4082.5 4269.8 5116.9 5298.9 5316.5 70567.0 76435.7 88012.0 92762.0 93902.0 75294.9 77563.2 83038.0 84152.6 86503.6 71254.0 72486.6 78676.6 81332.8 83244.0 10588.8 11247.3 11389.4 11488.8 11522.8 47867.5 48324.0 49681.5 50560.7 52278.7 71757.3 71925.0 74428.6 74481.0 75931.6 12995.7 13056.0 13502.9 13565.7 13571.9 15326.5 15446.8 15740.1 15799.5 16302.7 14497.3 14555.4 14606.0 14610.0 14706.8 13918.2 14382.9 14563.4 14564.9 14736.0 13442.5 14099.8 14206.3 14223.4 14355.9 9675.4 10142.1 10155.3 10181.9 10687.6 8702.7 10342.0 11386.8 11608.9 11883.5 02:42:53 INFO - PROCESS | 4264 | -------- Summary: end -------- 02:42:53 INFO - PROCESS | 4264 | 02:42:53 INFO - TEST-INFO | 4264: exit 0 02:42:53 INFO - TEST-OK | dromaeo_css | took 1114770ms 02:42:53 INFO - TEST-START | kraken 02:42:53 INFO - operating with platform_type : mac_ 02:42:53 INFO - Initialising browser for kraken test... 02:42:53 INFO - TEST-INFO | started process 4271 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpYn1OcP/profile http://localhost:57528/getInfo.html) 02:42:56 INFO - PROCESS | 4271 | 2017-04-24 02:42:56.091 plugin-container[4273:347148] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 02:42:56 INFO - PROCESS | 4271 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:42:56 INFO - PROCESS | 4271 | 2017-04-24 02:42:56.091 plugin-container[4273:347148] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 02:42:56 INFO - PROCESS | 4271 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:42:56 INFO - PROCESS | 4271 | 2017-04-24 02:42:56.091 plugin-container[4273:347148] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:42:56 INFO - PROCESS | 4271 | __metrics Screen width/height:1600/1200 02:42:56 INFO - PROCESS | 4271 | colorDepth:24 02:42:56 INFO - PROCESS | 4271 | Browser inner width/height: 1024/689 02:42:56 INFO - PROCESS | 4271 | __metrics 02:42:56 ERROR - PROCESS | 4271 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 02:42:56 INFO - TEST-INFO | 4271: exit 0 02:42:56 INFO - Browser initialized. 02:42:56 INFO - Running cycle 1/1 for kraken test... 02:42:56 INFO - TEST-INFO | started process 4274 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpYn1OcP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:42:59 INFO - PROCESS | 4274 | 2017-04-24 02:42:59.330 plugin-container[4275:347276] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x874b, name = 'com.apple.tsm.portname' 02:42:59 INFO - PROCESS | 4274 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:42:59 INFO - PROCESS | 4274 | 2017-04-24 02:42:59.331 plugin-container[4275:347276] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4613, name = 'com.apple.CFPasteboardClient' 02:42:59 INFO - PROCESS | 4274 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:42:59 INFO - PROCESS | 4274 | 2017-04-24 02:42:59.331 plugin-container[4275:347276] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:43:09 INFO - PROCESS | 4274 | RSS: Main: 174649344 02:43:09 INFO - PROCESS | 4274 | 02:43:12 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57528/tests/kraken/driver.html?testName=audio-beat-detection) 02:43:13 INFO - PROCESS | 4274 | RSS: Main: 188477440 02:43:13 INFO - PROCESS | 4274 | 02:43:15 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57528/tests/kraken/driver.html?testName=audio-dft) 02:43:16 INFO - PROCESS | 4274 | RSS: Main: 196358144 02:43:16 INFO - PROCESS | 4274 | 02:43:19 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57528/tests/kraken/driver.html?testName=audio-fft) 02:43:20 INFO - PROCESS | 4274 | RSS: Main: 200065024 02:43:20 INFO - PROCESS | 4274 | 02:43:22 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57528/tests/kraken/driver.html?testName=audio-oscillator) 02:43:23 INFO - PROCESS | 4274 | RSS: Main: 200548352 02:43:23 INFO - PROCESS | 4274 | 02:43:25 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57528/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:43:25 INFO - PROCESS | 4274 | RSS: Main: 200552448 02:43:25 INFO - PROCESS | 4274 | 02:43:28 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57528/tests/kraken/driver.html?testName=imaging-darkroom) 02:43:29 INFO - PROCESS | 4274 | RSS: Main: 204726272 02:43:29 INFO - PROCESS | 4274 | 02:43:32 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57528/tests/kraken/driver.html?testName=imaging-desaturate) 02:43:32 INFO - PROCESS | 4274 | RSS: Main: 204152832 02:43:32 INFO - PROCESS | 4274 | 02:43:36 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57528/tests/kraken/driver.html?testName=json-parse-financial) 02:43:36 INFO - PROCESS | 4274 | RSS: Main: 204279808 02:43:36 INFO - PROCESS | 4274 | 02:43:38 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57528/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:43:39 INFO - PROCESS | 4274 | RSS: Main: 205201408 02:43:39 INFO - PROCESS | 4274 | 02:43:40 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57528/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:43:41 INFO - PROCESS | 4274 | RSS: Main: 205201408 02:43:41 INFO - PROCESS | 4274 | 02:43:43 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57528/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:43:44 INFO - PROCESS | 4274 | RSS: Main: 205303808 02:43:44 INFO - PROCESS | 4274 | 02:43:47 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57528/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:43:48 INFO - PROCESS | 4274 | RSS: Main: 205713408 02:43:48 INFO - PROCESS | 4274 | 02:43:51 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57528/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:43:51 INFO - PROCESS | 4274 | RSS: Main: 205389824 02:43:51 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | Cycle 1(1): loaded http://localhost:57528/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57528/tests/kraken/driver.html?testName=ai-astar) 02:43:53 INFO - PROCESS | 4274 | RSS: Main: 207376384 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | __start_tp_report 02:43:53 INFO - PROCESS | 4274 | _x_x_mozilla_page_load 02:43:53 INFO - PROCESS | 4274 | _x_x_mozilla_page_load_details 02:43:53 INFO - PROCESS | 4274 | |i|pagename|runs| 02:43:53 INFO - PROCESS | 4274 | |0;ai-astar;162;181;145;121;121;118;104;181;181;168 02:43:53 INFO - PROCESS | 4274 | |1;audio-beat-detection;150;114;110;150;111;111;113;119;157;114 02:43:53 INFO - PROCESS | 4274 | |2;audio-dft;193;197;234;222;198;199;234;196;198;197 02:43:53 INFO - PROCESS | 4274 | |3;audio-fft;86;60;67;84;65;84;86;77;73;61 02:43:53 INFO - PROCESS | 4274 | |4;audio-oscillator;82;74;110;104;105;102;82;72;75;111 02:43:53 INFO - PROCESS | 4274 | |5;imaging-gaussian-blur;87;88;99;87;89;88;90;88;88;87 02:43:53 INFO - PROCESS | 4274 | |6;imaging-darkroom;96;107;95;95;95;96;96;97;96;97 02:43:53 INFO - PROCESS | 4274 | |7;imaging-desaturate;97;121;148;97;86;92;95;148;144;94 02:43:53 INFO - PROCESS | 4274 | |8;json-parse-financial;66;64;66;69;81;68;63;69;70;69 02:43:53 INFO - PROCESS | 4274 | |9;json-stringify-tinderbox;58;66;58;66;73;65;61;61;60;62 02:43:53 INFO - PROCESS | 4274 | |10;stanford-crypto-aes;86;83;83;87;86;84;82;84;72;90 02:43:53 INFO - PROCESS | 4274 | |11;stanford-crypto-ccm;145;155;135;139;143;125;144;154;196;141 02:43:53 INFO - PROCESS | 4274 | |12;stanford-crypto-pbkdf2;205;221;190;164;184;225;161;218;219;216 02:43:53 INFO - PROCESS | 4274 | |13;stanford-crypto-sha256-iterative;66;62;70;71;84;86;84;73;65;68 02:43:53 INFO - PROCESS | 4274 | __end_tp_report 02:43:53 INFO - PROCESS | 4274 | __start_cc_report 02:43:53 INFO - PROCESS | 4274 | _x_x_mozilla_cycle_collect,973 02:43:53 INFO - PROCESS | 4274 | __end_cc_report 02:43:53 INFO - PROCESS | 4274 | __startTimestamp1493027033925__endTimestamp 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | ------- Summary: start ------- 02:43:53 INFO - PROCESS | 4274 | Number of tests: 14 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#0] ai-astar Cycles:10 Average:148.20 Median:165.00 stddev:30.12 (18.3%) stddev-sans-first:31.53 02:43:53 INFO - PROCESS | 4274 | Values: 162.0 181.0 145.0 121.0 121.0 118.0 104.0 181.0 181.0 168.0 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:116.50 stddev:19.19 (16.5%) stddev-sans-first:18.07 02:43:53 INFO - PROCESS | 4274 | Values: 150.0 114.0 110.0 150.0 111.0 111.0 113.0 119.0 157.0 114.0 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#2] audio-dft Cycles:10 Average:206.80 Median:198.50 stddev:16.42 (8.3%) stddev-sans-first:16.64 02:43:53 INFO - PROCESS | 4274 | Values: 193.0 197.0 234.0 222.0 198.0 199.0 234.0 196.0 198.0 197.0 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#3] audio-fft Cycles:10 Average:74.30 Median:80.50 stddev:10.50 (13.0%) stddev-sans-first:10.25 02:43:53 INFO - PROCESS | 4274 | Values: 86.0 60.0 67.0 84.0 65.0 84.0 86.0 77.0 73.0 61.0 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:73.00 stddev:16.02 (21.9%) stddev-sans-first:16.60 02:43:53 INFO - PROCESS | 4274 | Values: 82.0 74.0 110.0 104.0 105.0 102.0 82.0 72.0 75.0 111.0 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#5] imaging-gaussian-blur Cycles:10 Average:89.10 Median:88.00 stddev:3.60 (4.1%) stddev-sans-first:3.74 02:43:53 INFO - PROCESS | 4274 | Values: 87.0 88.0 99.0 87.0 89.0 88.0 90.0 88.0 88.0 87.0 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:96.00 stddev:3.59 (3.7%) stddev-sans-first:3.79 02:43:53 INFO - PROCESS | 4274 | Values: 96.0 107.0 95.0 95.0 95.0 96.0 96.0 97.0 96.0 97.0 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#7] imaging-desaturate Cycles:10 Average:112.20 Median:93.00 stddev:25.46 (27.4%) stddev-sans-first:26.41 02:43:53 INFO - PROCESS | 4274 | Values: 97.0 121.0 148.0 97.0 86.0 92.0 95.0 148.0 144.0 94.0 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#8] json-parse-financial Cycles:10 Average:68.50 Median:69.00 stddev:4.97 (7.2%) stddev-sans-first:5.19 02:43:53 INFO - PROCESS | 4274 | Values: 66.0 64.0 66.0 69.0 81.0 68.0 63.0 69.0 70.0 69.0 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#9] json-stringify-tinderbox Cycles:10 Average:63.00 Median:63.50 stddev:4.59 (7.2%) stddev-sans-first:4.50 02:43:53 INFO - PROCESS | 4274 | Values: 58.0 66.0 58.0 66.0 73.0 65.0 61.0 61.0 60.0 62.0 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:85.00 stddev:4.74 (5.6%) stddev-sans-first:4.95 02:43:53 INFO - PROCESS | 4274 | Values: 86.0 83.0 83.0 87.0 86.0 84.0 82.0 84.0 72.0 90.0 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:144.50 stddev:19.05 (13.2%) stddev-sans-first:20.18 02:43:53 INFO - PROCESS | 4274 | Values: 145.0 155.0 135.0 139.0 143.0 125.0 144.0 154.0 196.0 141.0 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.30 Median:217.00 stddev:24.05 (11.1%) stddev-sans-first:25.44 02:43:53 INFO - PROCESS | 4274 | Values: 205.0 221.0 190.0 164.0 184.0 225.0 161.0 218.0 219.0 216.0 02:43:53 INFO - PROCESS | 4274 | 02:43:53 INFO - PROCESS | 4274 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:72.00 stddev:8.71 (12.1%) stddev-sans-first:8.87 02:43:53 INFO - PROCESS | 4274 | Values: 66.0 62.0 70.0 71.0 84.0 86.0 84.0 73.0 65.0 68.0 02:43:53 INFO - PROCESS | 4274 | -------- Summary: end -------- 02:43:53 INFO - PROCESS | 4274 | 02:43:54 INFO - TEST-INFO | 4274: exit 0 02:43:54 INFO - TEST-OK | kraken | took 61329ms 02:43:54 INFO - SUITE-END | took 1176s 02:43:55 INFO - Completed test suite (00:19:36) 02:43:55 INFO - javascript benchmark 02:43:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7900.046157140064, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2699.9470206619435, 3281.78668386555, 3542.681158695718, 3570.268095040517, 3583.6058033616305, 5988.431099045594, 6052.818415447544, 6059.151718759377, 6068.302145253298, 6068.726907289171, 5134.024535338283, 5901.937651222789, 6000.519958403328, 6005.789797357093, 6034.125051867477, 5405.864853378667, 5526.50261476467, 5528.226048353234, 5548.6393384430085, 5550.639208451458, 5566.332040155166, 5574.804881829137, 5581.553475721932, 5585.218069470277, 5590.608657393984, 5361.571074314045, 5393.164059570773, 5574.303212098488, 5574.554035677136, 5578.888422231553, 5521.254630624874, 5531.695756733378, 5599.4120617335, 5631.211630371751, 5658.43415658435, 5392.137258038695, 5499.890002199973, 5512.0, 5520.503154716095, 5571.66570005801, 4390.385346051556, 5134.743262836843, 5473.850491396812, 5809.535237181015, 6172.660503672296, 6239.313675495692, 6269.122322874801, 6309.558330916838, 6320.146780184662, 6333.271673757485, 6430.839229019265, 6447.2907980122145, 6454.128692626529, 6461.095446637474, 6461.450776683988, 5051.87370315745, 5958.19564358806, 6143.1092491588915, 6156.569040167235, 6159.81520554384, 4908.460069392364, 6439.776442476008, 6541.705623246965, 6559.934400656028, 6565.048068030109, 6261.342559031327, 6283.842903927439, 6339.366063393716, 6351.682415879188, 6352.078948552434, 5022.296878437021, 6462.547621666532, 6466.741330346827, 6482.546221764431, 6487.3836985486305, 6199.628022318675, 6226.688665566703, 6239.688015599293, 6242.656653883989, 6279.340669229757, 6338.968305158444, 6383.39357761012, 6392.808215753535, 6394.8721025579225, 6397.10440538325, 5127.333446651986, 5136.0, 5138.845834624893, 5154.329937108227, 5190.766415511311, 4433.202023635712, 4487.259602165606, 4533.297338912474, 4569.428821397325, 4588.7935042923145, 5443.673379597237, 6202.100695399142, 6220.720067596879, 6235.594686345373, 6254.405831446005, 6236.812895613139, 6473.546851720334, 6507.609543427409, 6520.902186467207, 6538.476921846241, 5847.473727364558, 6126.9452004266695, 6147.815565533041, 6190.1952746142715, 6200.3799620037635, 4374.562543745664, 5694.4590263924865, 5706.087026075808, 5710.657360558297, 5722.942770572325, 6740.191177058687, 6743.089682892747, 6752.08846805675, 6755.594664320157, 6760.256371799, 6204.3795620437595, 6269.592476489013, 6389.584676995981, 6391.776287829904, 6437.7103030362805, 6655.334466553306, 6728.293529179366, 6738.898916516354, 6743.089682892747, 6759.797206083826, 6363.459105976044, 6365.1088847562305, 6373.8725225496155, 6386.361363863576, 6410.967945160244, 6231.034189700604, 6267.686615669107, 6367.076773867856, 6473.385028422293, 6503.349665033459, 4564.589186973189, 6268.934281172303, 6277.2781130170615, 6295.779647712308, 6303.243610766737, 6283.748650053946, 6292.370762923671, 6301.590396624205, 6312.936870631235, 6317.241930968313, 4722.126406614787, 6068.544859135671, 6197.569931635648, 6208.782692605737, 6240.563160578716, 6232.968835155795, 6284.560080794484, 6291.433770960635, 6298.653574053471, 6309.621422714652, 6209.59637623553, 6225.3774622539195, 6269.12232287471, 6277.811665650038, 6284.088807123033], "value": 5870.984491548714, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.0, 823.7569916874521, 895.4314010603267, 966.0822218892055, 973.532704301935, 39731.404028939556, 40227.79886100566, 40252.9936751581, 40253.396199057, 40316.596834031654, 40203.0, 40328.39507407391, 40360.79819600898, 40411.0, 40420.59579404205, 7914.28771410573, 8041.437099403015, 8224.095349511565, 11768.058555315554, 11986.340751258676, 11992.7601447971, 12078.637640870787, 12080.818787718192, 12085.15403921726, 12101.576444824434, 9957.302988790787, 12078.396080195955, 12081.0, 12111.0, 12141.757164856697, 12021.978131858803, 12044.638660840188, 12063.758724825455, 12078.758424831542, 12095.21381110225, 9700.854487182698, 11655.883441165564, 11705.473253703518, 11727.296362218294, 11731.0, 41164.1767164658, 41279.0, 41359.58640413587, 41363.58636413657, 41408.378874316906, 3394.5247665326874, 3954.6638535724496, 3955.367141257414, 4001.819918103663, 4060.553339132693, 29152.854235728897, 29462.82148714049, 29480.410391792044, 30015.249618759488, 31202.751889924377, 3129.092563156682, 3283.113559338965, 3294.8517316720804, 3395.898123056312, 3413.0272872231544, 347780.0, 348885.5111448904, 349133.0, 349976.0, 350112.0, 44738.0, 45471.54528454739, 52973.47026529763, 53929.73035134799, 54135.72932135393, 57004.0, 57698.711506442196, 57966.0, 58338.0, 58342.41657583455, 15465.072095674295, 15475.535733928, 15531.611709707347, 15536.378544858306, 15565.455209067628, 13514.459421623222, 13532.0, 13540.729185416236, 13567.439744429517, 13576.932115339558, 12491.312977786309, 12497.93751031239, 12511.249325040526, 12591.937040314922, 12597.433115509824, 5805.419458054245, 5806.477417032487, 5807.796727114615, 5822.912656310159, 5842.298924129132, 1158.5192145259623, 1165.8134698448207, 1198.25708061001, 1200.7752092865228, 1205.879412058787, 27807.30481737932, 27857.72142278551, 27979.860100699367, 28089.43821123566, 28267.57598636022, 1263.33674820718, 1282.58316047285, 1289.548657969703, 1340.4571148684797, 1345.1727187779445, 13059.542915998085, 13866.098703584235, 14437.0, 14569.490067847575, 14580.0, 397488.0, 397830.0, 399236.0, 399317.0, 399551.0, 55832.72083639637, 56473.7176314124, 56560.151597726064, 56726.7163664179, 56771.43228567744, 1479.2973337664523, 1483.4362942081939, 1520.8327084020748, 1534.2175490499926, 1539.3765524962407, 25646.0, 26611.33471663224, 27037.324066898484, 27177.45645087127, 27231.0, 47052.7647361761, 47126.82182945454, 47160.05679886452, 47220.291695623906, 47267.290990635855, 335098.0, 375999.0, 384640.0, 385480.0, 387372.0, 29900.0, 30382.392352153274, 34979.82510087433, 35137.0, 35846.28307433784, 46616.06767864556, 46792.53207468018, 46929.0, 47856.80357991078, 49111.0, 31835.363292733553, 32008.35983280368, 32024.839875800473, 32162.87429939941, 32723.67276327206, 31754.52368214525, 32289.28189388805, 32576.674233257363, 32581.02256932296, 32663.0, 32619.34761304808, 40586.39120413255, 40772.59227407688, 42451.150976980905, 42869.0, 40959.59040409558, 41248.0, 42319.3652095225, 43456.91357715956, 43458.56541434545, 26910.865445672647, 34360.82819585886, 34443.827780860935, 34471.138221544665, 34884.47673284852], "value": 19911.756321575896, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.2269871919609, 405.2523094889928, 426.7418211981753, 431.512390998172, 477.2125992113013, 20215.494612634673, 21743.67384489232, 22291.44271393216, 22469.55060898785, 22571.887140564293, 22469.0, 23358.41603959902, 24196.03215871363, 24299.51400971984, 24347.878260608715, 7144.821379465516, 7295.283384900053, 7364.079490063742, 7405.8889116663295, 7432.219616940224, 6867.519273650846, 7222.747203847866, 7233.927660723378, 7246.8188295292775, 7251.70993160273, 7052.753153639624, 7184.856302873939, 7188.676509557083, 7216.747413840517, 7318.7438439654625, 7080.150381954147, 7116.537425067406, 7226.6025368604705, 7271.200167981518, 7296.817579560527, 6981.546199497067, 7009.964950175267, 7028.613426261553, 7042.683079261395, 7045.260247673972, 27638.0, 27748.722512775017, 28073.43853122926, 28150.0, 28677.426451470852, 2958.363951750384, 3097.396007778484, 3233.7898036627544, 3487.232808782064, 3545.4859045438266, 16147.0, 17405.390811321544, 17574.0, 17752.644947101115, 18075.54811129719, 1666.96666066678, 1839.4481655503296, 1859.2297476759666, 1866.8693191476602, 1882.9246830126779, 85439.0, 131049.34475327563, 136787.0, 143273.0, 146700.26649866896, 23922.7607723924, 24640.630390544156, 24647.630285545732, 24677.38306542351, 24723.0, 38256.617433825304, 38838.22323553513, 38884.8055759725, 39113.02217444587, 39228.803855980535, 11271.774564508663, 11411.087113031104, 11757.941785239369, 11795.115366347558, 11953.0, 9520.428774273565, 9525.333226674065, 9543.427394356222, 9688.515574221261, 9703.9029609703, 11018.889811101946, 11226.775464490664, 11280.830787538194, 11330.716732081764, 11375.829362559569, 3553.8400771965325, 3604.945925811115, 3925.74482658632, 4015.9357770190923, 4322.740635561876, 841.1420351241699, 857.691231156783, 958.5351104714348, 967.9854802177973, 1008.324422636835, 22485.775142248694, 23895.641565376533, 23978.0, 24086.759132408453, 24129.758702413103, 989.1443901025705, 996.1781530237583, 1012.4684540616236, 1031.2677998620914, 1031.4791030529534, 9529.90470095304, 9530.0, 12119.939400302943, 12750.48998040068, 12816.166949148275, 799967.0, 800365.0, 802850.0, 804265.0, 812159.0, 141986.0, 168604.0, 171853.0, 174001.0, 176921.0, 1408.8661577150153, 1413.8868890488736, 1455.1850963460283, 1474.0197768484052, 1474.6460849396067, 18143.639227058287, 18873.528161796065, 19991.264988485622, 20930.0581473831, 20961.475963101046, 57535.0, 57636.71181644233, 57872.421275786706, 57961.0, 58094.0, 62769.0, 67179.0, 79918.60040699759, 81731.59134204291, 82259.5887020561, 10281.177505799667, 11027.393493357942, 11474.082073434272, 11571.942140289244, 11658.6502404928, 11068.391238481961, 13449.596512104652, 13554.661133471742, 13597.932010339884, 13695.08268842343, 9494.95252523733, 11448.141389395994, 11733.00269477104, 11745.647630571097, 11969.10231732641, 10484.475776211006, 10684.732881678021, 10784.784304314027, 10860.619878304222, 10940.0, 10143.594256229964, 11543.134264930331, 11801.822972655245, 11847.348395838311, 11975.401229938294, 8905.55472226399, 11149.72125696864, 11607.89724976126, 11864.703382415508, 12003.339816310187, 11587.942060289644, 11772.0, 11832.467538960595, 11920.701982450508, 12015.75968480643], "value": 12046.579457934557, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1600.759886017098, 1616.6524197297579, 1631.812341580718, 1640.3192675039859, 1653.0908000599668, 11490.597829075985, 11711.824322635146, 11993.640190794269, 12111.939440302785, 12141.57504487342, 11599.956003959642, 11709.414529273545, 11827.34949577773, 11836.0, 11838.526458941653, 2445.327534927891, 2489.0914816092086, 2494.4761600063916, 2495.3262619092834, 2500.4124030852736, 2429.5626787178303, 2467.481828815951, 2476.7399423060588, 2497.7377375375595, 2509.2346834215555, 2435.2207293666033, 2443.7067551893797, 2460.3357093584723, 2484.043643197364, 2485.4159272570932, 2429.514097180553, 2430.647556104364, 2441.3530414440097, 2458.9918133565243, 2472.4560596668703, 2442.9389265268333, 2455.1284294075526, 2455.2020593307248, 2464.137551856854, 2479.3305807431893, 10967.780644387147, 11033.282836615675, 11064.17018723591, 11072.723181920435, 11086.556537738481, 5929.080992446177, 6112.327643959161, 6121.877562448726, 6127.050307202347, 6138.938610613882, 3414.078198886287, 3511.7541772075965, 3570.83931223093, 3717.4609681596016, 3761.360568703327, 1133.18410744264, 1155.249088092738, 1161.268400907423, 1276.546825876819, 1285.5693342730196, 14327.856721432756, 14625.0, 15298.08211507302, 16396.75404868928, 16617.41839035628, 4826.203676393356, 4937.530934561211, 4944.826931057396, 4956.008798240367, 4984.37695288089, 12378.566750163702, 12407.069469789802, 12428.192167509264, 12429.254244745343, 12450.190737602026, 5330.413654498002, 5488.533474654699, 5986.73059712314, 6072.848178795565, 6080.969595151996, 5419.647722897999, 5441.537469315073, 5459.1265397537, 5475.0, 5493.313335833021, 3707.2029513654693, 3958.6437220650278, 4056.513218413809, 4148.543660197376, 4149.294619914622, 1067.3755852826184, 1070.0690399352613, 1072.6460268111505, 1082.718493191776, 1103.5585765693627, 989.9109080182818, 993.1955116355775, 994.4182653147844, 1022.6625213679467, 1036.2435422141878, 5759.539236861041, 6948.965255173793, 6984.02223688684, 7080.0, 7152.392046676091, 976.7790262172284, 1017.3641474078701, 1022.6676330192718, 1043.1498328861953, 1091.4379094766202, 4154.958450415457, 4399.076193999296, 4412.69111162222, 4503.392042074343, 4626.814927402934, 11993.0, 13804.0, 14080.577582672537, 14385.6403589909, 14422.567322980327, 13775.311234438239, 13809.654758631115, 14150.787738183935, 14152.575422737335, 14158.929205354114, 9094.545272736337, 9413.38812977168, 10302.278840481244, 10312.175025997902, 10550.261481696207, 6592.307807680317, 7048.365647091787, 7097.787066388017, 7149.356557909813, 7273.163586187656, 12565.371731413576, 12868.806967895302, 12942.482300707867, 12987.805182922075, 13128.671783205114, 10693.304935179189, 11593.130515211224, 12178.391080445765, 12283.324417157142, 12492.562760303346, 9352.625894964125, 9705.5147242639, 9780.217582593234, 9811.166050885755, 9827.066428689262, 8174.509529428254, 10632.84050739224, 10654.200934929757, 10767.676969690921, 10791.676249712522, 9028.593713282786, 9152.725418237464, 9189.540522973692, 9348.205402540589, 9363.531823408939, 8901.777455563662, 8939.150780675825, 8954.955225223832, 8981.506017169118, 9124.0, 8363.874541881998, 8373.66505339804, 8382.958085209535, 8427.620757065824, 8502.107278735644, 5956.315023772148, 5982.102684597346, 6021.187139736079, 6147.170132032297, 6180.845478863064, 6036.667983260787, 6089.878202436015, 6258.217722784652, 6304.905426418516, 6351.96824015877], "value": 5312.96769579465, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.3168820799441, 385.70300868331367, 389.5695256741301, 400.50337581399066, 404.1008755518969, 4175.724230294493, 4331.350297455383, 4770.284457331402, 5074.923876141861, 5082.618803589732, 4188.057687020419, 4982.551570358667, 5083.79664813407, 5090.541851233388, 5136.974315128428, 4146.958530414688, 4226.4852412814325, 4227.175700738358, 4265.594768497003, 4296.806643701041, 3570.321638888616, 3904.707146963975, 4235.512916014667, 4334.328179132223, 4357.586029327209, 3474.3617270943323, 3602.9279414411694, 4109.815058322368, 4282.850100246492, 4307.6123148916595, 3516.7889926604485, 3956.3274243378405, 4172.937405938914, 4312.482502099752, 4312.82748690052, 3778.8677396291273, 3940.9802950985063, 3949.2891279569667, 3965.8215380307956, 3987.800609969519, 4017.819198136091, 4309.547497512779, 4702.8118875244945, 4993.302277225761, 5000.674956127877, 4260.677930527839, 4607.930881036754, 4961.330220420269, 4974.5325129087005, 5006.5494105530315, 4338.913221735579, 5034.572061374751, 5203.1414816555225, 5506.449355064502, 5536.560494271518, 3667.5965643779164, 3999.8800035998966, 4206.254404422161, 4396.538363471821, 4452.688311818174, 5171.526629465523, 5328.920066199049, 5577.749001294951, 5690.857728556819, 5911.29064512257, 3947.2894878921497, 3974.364101743707, 4277.26770657884, 4291.570842915684, 4327.6970612057485, 4466.570697376808, 4584.7936842842155, 4610.515895830957, 4619.376384188092, 4640.327152562927, 4155.58444155582, 4384.671149663788, 4432.800523976429, 4644.0, 4785.473597904228, 4828.0, 5137.486251374833, 5497.780088796483, 5508.504234618904, 5549.528290095387, 1348.5414958914016, 1443.2783608195903, 1522.893397462167, 1524.6112241378378, 1593.251171949182, 1096.2928910852615, 1107.3023994883172, 1182.4028865422906, 1187.2935603316014, 1215.851020785367, 4471.038726673716, 4746.644001699887, 4866.765913655143, 4877.512248775094, 5038.84883453497, 4915.48387419323, 5200.375954885362, 5271.182966640177, 5638.182463542763, 5742.7415766290615, 4484.439445069366, 4632.189366860786, 4986.531666824945, 5052.418971818288, 5097.46476619957, 2713.416615427653, 2763.0, 2790.037437084203, 2938.5151450010935, 2986.925326866846, 5078.314427552233, 5478.342598888159, 5532.5297349724915, 5819.912701309484, 5981.581289309794, 4535.9773201133785, 5442.510174084351, 5567.944320556823, 5595.804146854922, 5825.446582574648, 5213.627799351286, 5292.338457692788, 5301.469853014668, 5315.84052478434, 5583.748731307182, 4938.481459446706, 5232.241325007776, 5687.0, 5720.48515633595, 5750.297360240847, 4844.95155048445, 5526.47498487643, 5546.528545073714, 5560.638558493685, 5622.9156562652415, 5057.760848592118, 5663.631863928831, 5733.684647344436, 5744.655320680772, 5758.510526605285, 4087.118581400165, 4203.0, 4316.525182230015, 4429.136318417877, 4519.276915693474, 4652.371929789435, 4920.40955085392, 5060.595152387874, 5503.358387973715, 5762.510186634183, 4590.931136032896, 4620.0, 5266.2884562516565, 5459.972700136474, 5525.806596769094, 4596.839110631112, 5323.908598737327, 5513.807016754395, 5519.3652729936575, 5554.833354999357, 4881.707097574157, 5084.500072478554, 5110.565601923878, 5367.570594352521, 5410.618363522798, 4399.934000989923, 4499.752513611652, 4884.0, 5144.0, 5454.563634909118, 5163.793448262028, 5363.222332761806, 5392.919106213489, 5414.485623865727, 5437.374701909331], "value": 4129.7177106725285, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.1131747561666, 788.0897563314373, 799.6401619271328, 819.8603940522675, 876.7063033883646, 16334.918325408371, 17069.82930170698, 17151.885127466707, 17310.826891731078, 17438.0, 15836.84163158368, 16054.59863503413, 16057.518274451755, 16060.919695401506, 16083.356665733387, 968.7190714692748, 985.1035557642547, 998.6704387552127, 1033.9379637221753, 1039.937603743774, 1013.6756238003842, 1018.7504061504942, 1029.5984566019258, 1030.2479190191127, 1031.762694580247, 1053.615430367918, 1065.424670677837, 1069.3476979042778, 1071.3678929431633, 1071.4214324264888, 889.3374436045123, 1050.4432650695144, 1062.946852657364, 1064.0423618743116, 1066.8826429092794, 787.1144961917843, 790.8220650353682, 792.5522080024767, 792.774059393076, 793.694427645355, 17140.571485712957, 17197.570060748458, 17370.39203627867, 17920.014399208038, 18167.63664726698, 12095.395230238453, 14477.0, 14908.850911490854, 14914.254287285592, 15278.465253716067, 11500.0, 11790.292582444996, 12103.334316612583, 12103.697407564794, 12402.19385739924, 4524.095180963787, 4618.976905115486, 4679.298105284234, 4791.377120974286, 4832.395950506187, 84629.57685211535, 101115.49442252815, 108835.0, 115915.42042289818, 119345.0, 11012.614558490415, 11120.315384999283, 11139.387333696724, 11199.440027998568, 11430.485628146753, 22260.22089226869, 22350.66474002891, 22405.439864003205, 22595.548089038126, 22673.2064377746, 7768.300852923264, 7816.921830781733, 7863.803404914923, 7874.527528348317, 7975.52146871178, 3888.1446081861945, 3997.860609726214, 4453.354263631755, 4525.117602067565, 4657.6273898088075, 3191.5212718092007, 3229.273413481948, 3234.3046245056953, 3256.202230453554, 3257.918552036218, 491.6140829448937, 599.3646734461485, 608.4250383387745, 611.0406661541338, 632.5161251642497, 692.5083190934392, 693.2582137113341, 737.6643627149634, 742.5915746339492, 751.7331347371713, 10891.237613366988, 11007.06439952597, 11011.229213954946, 11125.331200320017, 11185.0, 601.6781022153127, 608.8995315772936, 618.6071844378852, 648.9059086432441, 736.9461669812113, 4082.510098788164, 4269.807858646368, 5116.872078198075, 5298.867528311746, 5316.521513063843, 70567.0, 76435.70692879222, 88012.0, 92762.0, 93902.0, 75294.8705769414, 77563.2243677556, 83038.0, 84152.57923710342, 86503.56748216345, 71254.0, 72486.63756681183, 78676.60661696541, 81332.78000829992, 83244.0, 10588.83268947404, 11247.325160490396, 11389.43052847371, 11488.770224595293, 11522.82715759281, 47867.52132478631, 48324.0, 49681.50318496769, 50560.74719626379, 52278.738606308245, 71757.28242717506, 71925.0, 74428.62785686037, 74481.0, 75931.62034189794, 12995.740085198433, 13056.0, 13502.86497135016, 13565.728685426038, 13571.932140339235, 15326.540203793904, 15446.84553154454, 15740.055596664237, 15799.526014219591, 16302.67394652124, 14497.347619356944, 14555.41778328855, 14606.0, 14610.0, 14706.779398309249, 13918.174626472402, 14382.928085359506, 14563.41746330135, 14564.85435145635, 14736.0, 13442.529511467052, 14099.788503172256, 14206.289685515889, 14223.431062757374, 14355.856441435451, 9675.403558412783, 10142.087212150942, 10155.289129760846, 10181.89818101839, 10687.572497100029, 8702.651893924172, 10342.0, 11386.829197561878, 11608.88391116078, 11883.465244063866], "value": 7866.687259662978, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1580.3000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 181.0, 145.0, 121.0, 121.0, 118.0, 104.0, 181.0, 181.0, 168.0], "value": 148.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 114.0, 110.0, 150.0, 111.0, 111.0, 113.0, 119.0, 157.0, 114.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 197.0, 234.0, 222.0, 198.0, 199.0, 234.0, 196.0, 198.0, 197.0], "value": 206.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 60.0, 67.0, 84.0, 65.0, 84.0, 86.0, 77.0, 73.0, 61.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 74.0, 110.0, 104.0, 105.0, 102.0, 82.0, 72.0, 75.0, 111.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 99.0, 87.0, 89.0, 88.0, 90.0, 88.0, 88.0, 87.0], "value": 89.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 107.0, 95.0, 95.0, 95.0, 96.0, 96.0, 97.0, 96.0, 97.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 121.0, 148.0, 97.0, 86.0, 92.0, 95.0, 148.0, 144.0, 94.0], "value": 112.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 69.0, 81.0, 68.0, 63.0, 69.0, 70.0, 69.0], "value": 68.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 66.0, 58.0, 66.0, 73.0, 65.0, 61.0, 61.0, 60.0, 62.0], "value": 63.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 83.0, 87.0, 86.0, 84.0, 82.0, 84.0, 72.0, 90.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 155.0, 135.0, 139.0, 143.0, 125.0, 144.0, 154.0, 196.0, 141.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 221.0, 190.0, 164.0, 184.0, 225.0, 161.0, 218.0, 219.0, 216.0], "value": 200.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 70.0, 71.0, 84.0, 86.0, 84.0, 73.0, 65.0, 68.0], "value": 72.9, "unit": "score"}]}]} 02:43:55 INFO - Return code: 0 02:43:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:43:55 ERROR - # TBPL SUCCESS # 02:43:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:43:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:43:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:43:55 INFO - 2017-04-24 02:43:55.258 screenresolution[4279:347657] starting screenresolution argv=screenresolution get 02:43:55 INFO - 2017-04-24 02:43:55.260 screenresolution[4279:347657] Display 0: 1600x1200x32@60 02:43:55 INFO - 2017-04-24 02:43:55.267 screenresolution[4280:347660] starting screenresolution argv=screenresolution list 02:43:55 INFO - Available Modes on Display 0 02:43:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:43:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:43:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:43:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:43:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:43:55 INFO - Intel Iris: 02:43:55 INFO - Chipset Model: Intel Iris 02:43:55 INFO - Type: GPU 02:43:55 INFO - Bus: Built-In 02:43:55 INFO - VRAM (Dynamic, Max): 1536 MB 02:43:55 INFO - Vendor: Intel (0x8086) 02:43:55 INFO - Device ID: 0x0a2e 02:43:55 INFO - Revision ID: 0x0009 02:43:55 INFO - Displays: 02:43:55 INFO - Display: 02:43:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:43:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:43:55 INFO - Display Serial Number: 1600x1200 60 02:43:55 INFO - Main Display: Yes 02:43:55 INFO - Mirror: Off 02:43:55 INFO - Online: Yes 02:43:55 INFO - Rotation: Supported 02:43:55 INFO - Return code: 0 02:43:55 INFO - Running post-action listener: _resource_record_post_action 02:43:55 INFO - [mozharness: 2017-04-24 09:43:55.505572Z] Finished run-tests step (success) 02:43:55 INFO - Running post-run listener: _resource_record_post_run 02:43:55 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 696320; Write bytes: 432502272; Read time: 5; Write time: 1470 02:43:55 INFO - TinderboxPrint: CPU usage
54.4% 02:43:55 INFO - TinderboxPrint: I/O read bytes / time
696,320 / 5 02:43:55 INFO - TinderboxPrint: I/O write bytes / time
432,502,272 / 1,470 02:43:55 INFO - TinderboxPrint: CPU idle
2,175.7 (45.6%) 02:43:55 INFO - TinderboxPrint: CPU system
956.3 (20.0%) 02:43:55 INFO - TinderboxPrint: CPU user
1,637.6 (34.3%) 02:43:55 INFO - TinderboxPrint: Swap in / out
122,056,704 / 0 02:43:55 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119298560; Write bytes: 112918528; Read time: 10972; Write time: 242 02:43:55 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 651264; Write bytes: 308516352; Read time: 4; Write time: 1188 02:43:55 INFO - Running post-run listener: _upload_blobber_files 02:43:55 INFO - Blob upload gear active. 02:43:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:43:55 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 02:43:55 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'] 02:43:55 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 02:43:56 INFO - (blobuploader) - INFO - Open directory for files ... 02:43:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:43:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:43:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:43:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:43:57 INFO - (blobuploader) - INFO - Done attempting. 02:43:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:43:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:43:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:43:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:43:58 INFO - (blobuploader) - INFO - Done attempting. 02:43:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:43:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:43:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:43:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:43:59 INFO - (blobuploader) - INFO - Done attempting. 02:43:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:43:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:44:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:44:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:44:02 INFO - (blobuploader) - INFO - Done attempting. 02:44:02 INFO - (blobuploader) - INFO - Iteration through files over. 02:44:02 INFO - Return code: 0 02:44:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:44:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:44:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/166fe052ce7fd89f383095965079d79c887cc9e79390f6dff1f10bfaddd3c80dcaa6844d231044b794eb6b2a9fa7d4b024a0aac0c6b6bd207878091adff7d54a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5d389fae5e79126331c348cf7db56b5c6f6b3eb4d1d89fd7878a535af055e22a5b77eaa44d74e32d1912d373c4c4667cf70131e4a345468edaf3034530179c37", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cd254834f41d3f85407a12a17f44cd83bf8ccad1dd16c7edfc52c2f8cba199d07e35c34c181f3e2f7a9529d9462493c5f6da007a86ff9d8deba21674add3f312", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/402dc1590bba448acb629878e22b3a015e7ffd4ba05391cff79621810406927da594a8ae92b6a8fd3de51cac1c04cb47242622d5298e6851c661728c41c01fb7"} 02:44:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:44:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:44:02 INFO - Contents: 02:44:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/166fe052ce7fd89f383095965079d79c887cc9e79390f6dff1f10bfaddd3c80dcaa6844d231044b794eb6b2a9fa7d4b024a0aac0c6b6bd207878091adff7d54a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5d389fae5e79126331c348cf7db56b5c6f6b3eb4d1d89fd7878a535af055e22a5b77eaa44d74e32d1912d373c4c4667cf70131e4a345468edaf3034530179c37", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cd254834f41d3f85407a12a17f44cd83bf8ccad1dd16c7edfc52c2f8cba199d07e35c34c181f3e2f7a9529d9462493c5f6da007a86ff9d8deba21674add3f312", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/402dc1590bba448acb629878e22b3a015e7ffd4ba05391cff79621810406927da594a8ae92b6a8fd3de51cac1c04cb47242622d5298e6851c661728c41c01fb7"} 02:44:02 INFO - Running post-run listener: copy_logs_to_upload_dir 02:44:02 INFO - Copying logs to upload dir... 02:44:02 INFO - mkdir: /builds/slave/test/build/upload/logs 02:44:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.842319 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-04-24 02:44:02.422333) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 02:44:02.426201) ========= 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.mOcsRWrw2r/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.llLgM9mga4/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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/166fe052ce7fd89f383095965079d79c887cc9e79390f6dff1f10bfaddd3c80dcaa6844d231044b794eb6b2a9fa7d4b024a0aac0c6b6bd207878091adff7d54a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5d389fae5e79126331c348cf7db56b5c6f6b3eb4d1d89fd7878a535af055e22a5b77eaa44d74e32d1912d373c4c4667cf70131e4a345468edaf3034530179c37", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cd254834f41d3f85407a12a17f44cd83bf8ccad1dd16c7edfc52c2f8cba199d07e35c34c181f3e2f7a9529d9462493c5f6da007a86ff9d8deba21674add3f312", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/402dc1590bba448acb629878e22b3a015e7ffd4ba05391cff79621810406927da594a8ae92b6a8fd3de51cac1c04cb47242622d5298e6851c661728c41c01fb7"} build_url:https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011607 build_url: 'https://queue.taskcluster.net/v1/task/eHwTIF3oT6S-v0OvqhjTIw/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/166fe052ce7fd89f383095965079d79c887cc9e79390f6dff1f10bfaddd3c80dcaa6844d231044b794eb6b2a9fa7d4b024a0aac0c6b6bd207878091adff7d54a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5d389fae5e79126331c348cf7db56b5c6f6b3eb4d1d89fd7878a535af055e22a5b77eaa44d74e32d1912d373c4c4667cf70131e4a345468edaf3034530179c37", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cd254834f41d3f85407a12a17f44cd83bf8ccad1dd16c7edfc52c2f8cba199d07e35c34c181f3e2f7a9529d9462493c5f6da007a86ff9d8deba21674add3f312", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/402dc1590bba448acb629878e22b3a015e7ffd4ba05391cff79621810406927da594a8ae92b6a8fd3de51cac1c04cb47242622d5298e6851c661728c41c01fb7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 02:44:02.461713) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:44:02.462050) ========= 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.mOcsRWrw2r/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.llLgM9mga4/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.005162 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:44:02.510847) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 02:44:02.511166) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 02:44:02.511503) ========= ========= Total master_lag: 0.20 =========