builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0291 starttime: 1492791471.91 results: success (0) buildid: 20170421075527 builduid: b67a917ded3a4c5fbfe42bb5b16d09bd revision: 7e0110d709641ac572bef90185052f37c9ea45c0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-21 09:17:51.912870) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-21 09:17:51.913294) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-21 09:17:51.913573) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-21 09:17:51.940696) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 09:17:51.941041) ========= 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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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-21 09:17:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 179M=0s 2017-04-21 09:17:52 (179 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.083956 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 09:17:52.049797) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 09:17:52.050119) ========= 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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.026934 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 09:17:52.099088) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-21 09:17:52.099422) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7e0110d709641ac572bef90185052f37c9ea45c0 --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 7e0110d709641ac572bef90185052f37c9ea45c0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ji5OiSiWyM/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.83vNMYxCIS/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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-21 09:17:52,210 truncating revision to first 12 chars 2017-04-21 09:17:52,210 Setting DEBUG logging. 2017-04-21 09:17:52,210 attempt 1/10 2017-04-21 09:17:52,210 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/7e0110d70964?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-21 09:17:53,418 unpacking tar archive at: mozilla-beta-7e0110d70964/testing/mozharness/ program finished with exit code 0 elapsedTime=1.514122 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-21 09:17:53.637889) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-21 09:17:53.638237) ========= script_repo_revision: 7e0110d709641ac572bef90185052f37c9ea45c0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-21 09:17:53.638613) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-21 09:17:53.638891) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-21 09:17:53.659362) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-04-21 09:17:53.659778) ========= /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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:17:53 INFO - MultiFileLogger online at 20170421 09:17:53 in /builds/slave/test 09:17:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:17:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:17:53 INFO - {'append_to_log': False, 09:17:53 INFO - 'base_work_dir': '/builds/slave/test', 09:17:53 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:17:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:17:53 INFO - 'branch': 'Mozilla-Beta', 09:17:53 INFO - 'buildbot_json_path': 'buildprops.json', 09:17:53 INFO - 'config_files': ('talos/mac_config.py',), 09:17:53 INFO - 'default_actions': ('clobber', 09:17:53 INFO - 'read-buildbot-config', 09:17:53 INFO - 'download-and-extract', 09:17:53 INFO - 'populate-webroot', 09:17:53 INFO - 'create-virtualenv', 09:17:53 INFO - 'install', 09:17:53 INFO - 'run-tests'), 09:17:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:17:53 INFO - 'download_minidump_stackwalk': True, 09:17:53 INFO - 'download_symbols': 'ondemand', 09:17:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:17:53 INFO - 'tooltool.py': '/tools/tooltool.py', 09:17:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:17:53 INFO - '/tools/misc-python/virtualenv.py')}, 09:17:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:17:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:17:53 INFO - 'gecko_profile': False, 09:17:53 INFO - 'gecko_profile_interval': 0, 09:17:53 INFO - 'installer_path': 'installer.exe', 09:17:53 INFO - 'log_level': 'info', 09:17:53 INFO - 'log_name': 'talos', 09:17:53 INFO - 'log_to_console': True, 09:17:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:17:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:17:53 INFO - 'opt_config_files': (), 09:17:53 INFO - 'pip_index': False, 09:17:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:53 INFO - 'cmd': ('bash', 09:17:53 INFO - '-c', 09:17:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:53 INFO - 'enabled': True, 09:17:53 INFO - 'halt_on_failure': False, 09:17:53 INFO - 'name': 'check_screen_resolution'},), 09:17:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:53 INFO - 'cmd': ('bash', 09:17:53 INFO - '-c', 09:17:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:53 INFO - 'enabled': True, 09:17:53 INFO - 'halt_on_failure': False, 09:17:53 INFO - 'name': 'check_screen_resolution'},), 09:17:53 INFO - 'run_cmd_checks_enabled': True, 09:17:53 INFO - 'suite': 'dromaeojs-e10s', 09:17:53 INFO - 'system_bits': '32', 09:17:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:17:53 INFO - 'title': 't-yosemite-r7-0291', 09:17:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:17:53 INFO - 'use_talos_json': True, 09:17:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:17:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:17:53 INFO - 'work_dir': 'build'} 09:17:53 INFO - [mozharness: 2017-04-21 16:17:53.878294Z] Running clobber step. 09:17:53 INFO - Running pre-action listener: _resource_record_pre_action 09:17:53 INFO - Running main action method: clobber 09:17:53 INFO - rmtree: /builds/slave/test/build 09:17:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:17:56 INFO - Running post-action listener: _resource_record_post_action 09:17:56 INFO - [mozharness: 2017-04-21 16:17:56.455418Z] Finished clobber step (success) 09:17:56 INFO - [mozharness: 2017-04-21 16:17:56.455584Z] Running read-buildbot-config step. 09:17:56 INFO - Running pre-action listener: _resource_record_pre_action 09:17:56 INFO - Running main action method: read_buildbot_config 09:17:56 INFO - Using buildbot properties: 09:17:56 INFO - { 09:17:56 INFO - "project": "", 09:17:56 INFO - "product": "firefox", 09:17:56 INFO - "script_repo_revision": "production", 09:17:56 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:17:56 INFO - "repository": "", 09:17:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:17:56 INFO - "buildid": "20170421075527", 09:17:56 INFO - "pgo_build": "False", 09:17:56 INFO - "basedir": "/builds/slave/test", 09:17:56 INFO - "buildnumber": 7, 09:17:56 INFO - "slavename": "t-yosemite-r7-0291", 09:17:56 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:17:56 INFO - "platform": "yosemite_r7", 09:17:56 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:17:56 INFO - "branch": "mozilla-beta", 09:17:56 INFO - "repo_path": "releases/mozilla-beta", 09:17:56 INFO - "revision": "7e0110d709641ac572bef90185052f37c9ea45c0", 09:17:56 INFO - "stage_platform": "macosx64", 09:17:56 INFO - "builduid": "b67a917ded3a4c5fbfe42bb5b16d09bd", 09:17:56 INFO - "slavebuilddir": "test" 09:17:56 INFO - } 09:17:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:17:56 INFO - Running post-action listener: _resource_record_post_action 09:17:56 INFO - [mozharness: 2017-04-21 16:17:56.458984Z] Finished read-buildbot-config step (success) 09:17:56 INFO - [mozharness: 2017-04-21 16:17:56.459081Z] Running download-and-extract step. 09:17:56 INFO - Running pre-action listener: _resource_record_pre_action 09:17:56 INFO - Running main action method: download_and_extract 09:17:56 INFO - mkdir: /builds/slave/test/build/tests 09:17: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')]} 09:17:56 INFO - trying https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:17:56 INFO - Downloading https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:17:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 09:17:57 INFO - Downloaded 1165 bytes. 09:17:57 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:17:57 INFO - Using the following test package requirements: 09:17:57 INFO - {u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:17:57 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:17:57 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:17:57 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:17:57 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:17:57 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:17:57 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:17:57 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:17:57 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:17:57 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:17:57 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:17:57 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:17:57 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:17:57 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:17:57 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:17:57 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:17:57 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:17:57 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:17:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:17:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:17:57 INFO - Fetch https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:17:59 INFO - Expected file size: 51220808 09:17:59 INFO - Obtained file size: 51220808 09:18:15 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:18:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:18:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:18:15 INFO - Fetch https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:18:16 INFO - Expected file size: 51220808 09:18:16 INFO - Obtained file size: 51220808 09:18:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:18:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:18:31 INFO - Fetch https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:18:32 INFO - Expected file size: 14404424 09:18:32 INFO - Obtained file size: 14404424 09:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:33 INFO - trying https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:18:33 INFO - Downloading https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:18:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 09:18:35 INFO - Downloaded 59545622 bytes. 09:18:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:18:35 INFO - mkdir: /builds/slave/test/properties 09:18:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:18:35 INFO - Writing to file /builds/slave/test/properties/build_url 09:18:35 INFO - Contents: 09:18:35 INFO - build_url:https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:18:35 INFO - Running post-action listener: _resource_record_post_action 09:18:35 INFO - Running post-action listener: set_extra_try_arguments 09:18:35 INFO - [mozharness: 2017-04-21 16:18:35.792698Z] Finished download-and-extract step (success) 09:18:35 INFO - [mozharness: 2017-04-21 16:18:35.792839Z] Running populate-webroot step. 09:18:35 INFO - Running pre-action listener: _resource_record_pre_action 09:18:35 INFO - Running main action method: populate_webroot 09:18:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:18:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:18:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:18:35 INFO - '--tppagecycles', 09:18:35 INFO - '7'], 09:18:35 INFO - 'tests': ['tsvgm']}}, 09:18:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:18:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:18:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:18:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:18:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['damp', 'tps']}, 09:18:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'tests': ['damp', 'tps']}, 09:18:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['dromaeo_dom']}, 09:18:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:18:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['basic_compositor_video']}, 09:18:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:18:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['a11yr', 09:18:35 INFO - 'ts_paint', 09:18:35 INFO - 'tpaint', 09:18:35 INFO - 'sessionrestore', 09:18:35 INFO - 'sessionrestore_no_auto_restore', 09:18:35 INFO - 'tabpaint']}, 09:18:35 INFO - 'other-e10s': {'tests': ['a11yr', 09:18:35 INFO - 'ts_paint', 09:18:35 INFO - 'tpaint', 09:18:35 INFO - 'sessionrestore', 09:18:35 INFO - 'sessionrestore_no_auto_restore', 09:18:35 INFO - 'tabpaint']}, 09:18:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:18:35 INFO - 'ts_paint', 09:18:35 INFO - 'tpaint', 09:18:35 INFO - 'sessionrestore', 09:18:35 INFO - 'sessionrestore_no_auto_restore', 09:18:35 INFO - 'tabpaint']}, 09:18:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:18:35 INFO - 'ts_paint', 09:18:35 INFO - 'tpaint', 09:18:35 INFO - 'sessionrestore', 09:18:35 INFO - 'sessionrestore_no_auto_restore', 09:18:35 INFO - 'tabpaint']}, 09:18:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['a11yr', 09:18:35 INFO - 'ts_paint', 09:18:35 INFO - 'tpaint', 09:18:35 INFO - 'sessionrestore', 09:18:35 INFO - 'sessionrestore_no_auto_restore', 09:18:35 INFO - 'tabpaint']}, 09:18:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['a11yr', 09:18:35 INFO - 'ts_paint', 09:18:35 INFO - 'tpaint', 09:18:35 INFO - 'sessionrestore', 09:18:35 INFO - 'sessionrestore_no_auto_restore', 09:18:35 INFO - 'tabpaint']}, 09:18:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['tsvgx', 09:18:35 INFO - 'tsvgr_opacity', 09:18:35 INFO - 'tart', 09:18:35 INFO - 'tscrollx', 09:18:35 INFO - 'cart', 09:18:35 INFO - 'tsvg_static']}, 09:18:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:18:35 INFO - 'tsvgr_opacity', 09:18:35 INFO - 'tart', 09:18:35 INFO - 'tscrollx', 09:18:35 INFO - 'cart', 09:18:35 INFO - 'tsvg_static']}, 09:18:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['tp5o']}, 09:18:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:18:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'talos_options': ['--disable-e10s', 09:18:35 INFO - '--xperf_path', 09:18:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:35 INFO - 'tests': ['tp5n']}, 09:18:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'talos_options': ['--xperf_path', 09:18:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:35 INFO - 'tests': ['tp5n']}}, 09:18:35 INFO - 'talos.zip': {'path': '', 09:18:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:18:35 INFO - Running post-action listener: _resource_record_post_action 09:18:35 INFO - [mozharness: 2017-04-21 16:18:35.811437Z] Finished populate-webroot step (success) 09:18:35 INFO - [mozharness: 2017-04-21 16:18:35.811578Z] Running create-virtualenv step. 09:18:35 INFO - Running pre-action listener: _resource_record_pre_action 09:18:35 INFO - Running main action method: create_virtualenv 09:18:35 INFO - Creating virtualenv /builds/slave/test/build/venv 09:18:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:18:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:18:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:18:35 INFO - Using real prefix '/tools/python27' 09:18:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:18:36 INFO - Installing distribute.............................................................................................................................................................................................done. 09:18:39 INFO - Installing pip.................done. 09:18:39 INFO - Return code: 0 09:18:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:18:39 INFO - Reading from file tmpfile_stdout 09:18:39 INFO - Output received: 09:18:39 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:18:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:18: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')]} 09:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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')]} 09:18:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3adb8bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0c3d30>, '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 0x10cfeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0b8be8>, '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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18: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', 'pip>=1.5'] in /builds/slave/test/build 09:18: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 pip>=1.5 09:18:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ji5OiSiWyM/Render', 09:18:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:39 INFO - 'HOME': '/Users/cltbld', 09:18:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:39 INFO - 'LOGNAME': 'cltbld', 09:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:39 INFO - 'MOZ_NO_REMOTE': '1', 09:18:39 INFO - 'NO_EM_RESTART': '1', 09:18:39 INFO - 'PAGER': '/bin/cat', 09:18:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:39 INFO - 'PWD': '/builds/slave/test', 09:18:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:39 INFO - 'SHELL': '/bin/bash', 09:18:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83vNMYxCIS/Listeners', 09:18:39 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 09:18:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:39 INFO - 'USER': 'cltbld', 09:18:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:39 INFO - 'XPC_FLAGS': '0x0', 09:18:39 INFO - 'XPC_SERVICE_NAME': '0', 09:18:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:40 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:18:40 INFO - Cleaning up... 09:18:40 INFO - Return code: 0 09:18:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:40 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 0x10d2e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3adb8bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0c3d30>, '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 0x10cfeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0b8be8>, '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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:18:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:18:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ji5OiSiWyM/Render', 09:18:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:40 INFO - 'HOME': '/Users/cltbld', 09:18:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:40 INFO - 'LOGNAME': 'cltbld', 09:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:40 INFO - 'MOZ_NO_REMOTE': '1', 09:18:40 INFO - 'NO_EM_RESTART': '1', 09:18:40 INFO - 'PAGER': '/bin/cat', 09:18:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:40 INFO - 'PWD': '/builds/slave/test', 09:18:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:40 INFO - 'SHELL': '/bin/bash', 09:18:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83vNMYxCIS/Listeners', 09:18:40 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 09:18:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:40 INFO - 'USER': 'cltbld', 09:18:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:40 INFO - 'XPC_FLAGS': '0x0', 09:18:40 INFO - 'XPC_SERVICE_NAME': '0', 09:18:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:40 INFO - Downloading/unpacking psutil>=3.1.1 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:18:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:43 INFO - Installing collected packages: psutil 09:18:43 INFO - Running setup.py install for psutil 09:18:43 INFO - building 'psutil._psutil_osx' extension 09:18:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:18:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:18:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:18:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:18:44 INFO - building 'psutil._psutil_posix' extension 09:18:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:18:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:18:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:18:44 INFO - ^ 09:18:44 INFO - 1 warning generated. 09:18:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:18:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:44 INFO - Successfully installed psutil 09:18:44 INFO - Cleaning up... 09:18:44 INFO - Return code: 0 09:18:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:44 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 0x10d2e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3adb8bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0c3d30>, '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 0x10cfeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0b8be8>, '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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:18:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:18:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ji5OiSiWyM/Render', 09:18:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:44 INFO - 'HOME': '/Users/cltbld', 09:18:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:44 INFO - 'LOGNAME': 'cltbld', 09:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:44 INFO - 'MOZ_NO_REMOTE': '1', 09:18:44 INFO - 'NO_EM_RESTART': '1', 09:18:44 INFO - 'PAGER': '/bin/cat', 09:18:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:44 INFO - 'PWD': '/builds/slave/test', 09:18:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:44 INFO - 'SHELL': '/bin/bash', 09:18:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83vNMYxCIS/Listeners', 09:18:44 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 09:18:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:44 INFO - 'USER': 'cltbld', 09:18:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:44 INFO - 'XPC_FLAGS': '0x0', 09:18:44 INFO - 'XPC_SERVICE_NAME': '0', 09:18:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:18:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:18:47 INFO - Installing collected packages: mozsystemmonitor 09:18:47 INFO - Running setup.py install for mozsystemmonitor 09:18:48 INFO - Successfully installed mozsystemmonitor 09:18:48 INFO - Cleaning up... 09:18:48 INFO - Return code: 0 09:18:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:48 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 0x10d2e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3adb8bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0c3d30>, '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 0x10cfeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0b8be8>, '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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ji5OiSiWyM/Render', 09:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:48 INFO - 'HOME': '/Users/cltbld', 09:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:48 INFO - 'LOGNAME': 'cltbld', 09:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:48 INFO - 'MOZ_NO_REMOTE': '1', 09:18:48 INFO - 'NO_EM_RESTART': '1', 09:18:48 INFO - 'PAGER': '/bin/cat', 09:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:48 INFO - 'PWD': '/builds/slave/test', 09:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:48 INFO - 'SHELL': '/bin/bash', 09:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83vNMYxCIS/Listeners', 09:18:48 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 09:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:48 INFO - 'USER': 'cltbld', 09:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:48 INFO - 'XPC_FLAGS': '0x0', 09:18:48 INFO - 'XPC_SERVICE_NAME': '0', 09:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:49 INFO - Downloading/unpacking jsonschema==2.5.1 09:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:18:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:52 INFO - Downloading functools32-3.2.3-2.tar.gz 09:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:18:52 INFO - warning: no files found matching '*.txt' 09:18:52 INFO - no previously-included directories found matching 'build' 09:18:52 INFO - no previously-included directories found matching 'dist' 09:18:52 INFO - no previously-included directories found matching '.git*' 09:18:52 INFO - Installing collected packages: jsonschema, functools32 09:18:52 INFO - Running setup.py install for functools32 09:18:52 INFO - warning: no files found matching '*.txt' 09:18:52 INFO - no previously-included directories found matching 'build' 09:18:52 INFO - no previously-included directories found matching 'dist' 09:18:52 INFO - no previously-included directories found matching '.git*' 09:18:52 INFO - Successfully installed jsonschema functools32 09:18:52 INFO - Cleaning up... 09:18:52 INFO - Return code: 0 09:18:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d2e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3adb8bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0c3d30>, '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 0x10cfeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0b8be8>, '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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ji5OiSiWyM/Render', 09:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:52 INFO - 'HOME': '/Users/cltbld', 09:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:52 INFO - 'LOGNAME': 'cltbld', 09:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:52 INFO - 'MOZ_NO_REMOTE': '1', 09:18:52 INFO - 'NO_EM_RESTART': '1', 09:18:52 INFO - 'PAGER': '/bin/cat', 09:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:52 INFO - 'PWD': '/builds/slave/test', 09:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:52 INFO - 'SHELL': '/bin/bash', 09:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83vNMYxCIS/Listeners', 09:18:52 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 09:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:52 INFO - 'USER': 'cltbld', 09:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:52 INFO - 'XPC_FLAGS': '0x0', 09:18:52 INFO - 'XPC_SERVICE_NAME': '0', 09:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:18:53 INFO - Cleaning up... 09:18:53 INFO - Return code: 0 09:18:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d2e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3adb8bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0c3d30>, '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 0x10cfeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0b8be8>, '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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:18:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ji5OiSiWyM/Render', 09:18:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:53 INFO - 'HOME': '/Users/cltbld', 09:18:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:53 INFO - 'LOGNAME': 'cltbld', 09:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:53 INFO - 'MOZ_NO_REMOTE': '1', 09:18:53 INFO - 'NO_EM_RESTART': '1', 09:18:53 INFO - 'PAGER': '/bin/cat', 09:18:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:53 INFO - 'PWD': '/builds/slave/test', 09:18:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:53 INFO - 'SHELL': '/bin/bash', 09:18:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83vNMYxCIS/Listeners', 09:18:53 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 09:18:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:53 INFO - 'USER': 'cltbld', 09:18:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:53 INFO - 'XPC_FLAGS': '0x0', 09:18:53 INFO - 'XPC_SERVICE_NAME': '0', 09:18:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:53 INFO - Downloading/unpacking blobuploader==1.2.4 09:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:55 INFO - Downloading blobuploader-1.2.4.tar.gz 09:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:18:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:18:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:57 INFO - Downloading docopt-0.6.1.tar.gz 09:18:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:18:57 INFO - Installing collected packages: blobuploader, requests, docopt 09:18:57 INFO - Running setup.py install for blobuploader 09:18:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:18:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:18:57 INFO - Running setup.py install for requests 09:18:57 INFO - Running setup.py install for docopt 09:18:58 INFO - Successfully installed blobuploader requests docopt 09:18:58 INFO - Cleaning up... 09:18:58 INFO - Return code: 0 09:18:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:58 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 0x10d2e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3adb8bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0c3d30>, '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 0x10cfeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0b8be8>, '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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:18:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:18:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ji5OiSiWyM/Render', 09:18:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:58 INFO - 'HOME': '/Users/cltbld', 09:18:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:58 INFO - 'LOGNAME': 'cltbld', 09:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:58 INFO - 'MOZ_NO_REMOTE': '1', 09:18:58 INFO - 'NO_EM_RESTART': '1', 09:18:58 INFO - 'PAGER': '/bin/cat', 09:18:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:58 INFO - 'PWD': '/builds/slave/test', 09:18:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:58 INFO - 'SHELL': '/bin/bash', 09:18:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83vNMYxCIS/Listeners', 09:18:58 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 09:18:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:58 INFO - 'USER': 'cltbld', 09:18:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:58 INFO - 'XPC_FLAGS': '0x0', 09:18:58 INFO - 'XPC_SERVICE_NAME': '0', 09:18:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:58 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-7M3BcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:58 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-dEYttf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:58 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-h8dE9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:58 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-AmvnKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:58 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-EKudqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:58 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-5dSnZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:59 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-GzIK_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:59 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-7ettGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:59 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-XRTdYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:59 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-E5K367-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:59 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-07Qwpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:59 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-uNwNDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:59 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-2MAwNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:59 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-nnBpy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:59 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-ezg3Jl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:19:00 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-NPMX8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:19:00 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-pGc_un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:19:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:19:00 INFO - Running setup.py install for manifestparser 09:19:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:19:00 INFO - Running setup.py install for mozcrash 09:19:00 INFO - Running setup.py install for mozdebug 09:19:00 INFO - Running setup.py install for mozdevice 09:19:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:19:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:19:00 INFO - Running setup.py install for mozfile 09:19:00 INFO - Running setup.py install for mozhttpd 09:19:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:19:01 INFO - Running setup.py install for mozinfo 09:19:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:19:01 INFO - Running setup.py install for mozInstall 09:19:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:19:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:19:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:19:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:19:01 INFO - Running setup.py install for mozleak 09:19:01 INFO - Running setup.py install for mozlog 09:19:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:19:01 INFO - Running setup.py install for moznetwork 09:19:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:19:01 INFO - Running setup.py install for mozprocess 09:19:02 INFO - Running setup.py install for mozprofile 09:19:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:19:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:19:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:19:02 INFO - Running setup.py install for mozrunner 09:19:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:19:02 INFO - Running setup.py install for mozscreenshot 09:19:02 INFO - Running setup.py install for moztest 09:19:02 INFO - Running setup.py install for mozversion 09:19:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:19:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:19:02 INFO - Cleaning up... 09:19:02 INFO - Return code: 0 09:19:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19: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')]} 09:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19: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')]} 09:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:02 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 0x10d2e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3adb8bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0c3d30>, '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 0x10cfeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0b8be8>, '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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:19:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:19:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ji5OiSiWyM/Render', 09:19:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:02 INFO - 'HOME': '/Users/cltbld', 09:19:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:02 INFO - 'LOGNAME': 'cltbld', 09:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:02 INFO - 'MOZ_NO_REMOTE': '1', 09:19:02 INFO - 'NO_EM_RESTART': '1', 09:19:02 INFO - 'PAGER': '/bin/cat', 09:19:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:02 INFO - 'PWD': '/builds/slave/test', 09:19:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:02 INFO - 'SHELL': '/bin/bash', 09:19:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83vNMYxCIS/Listeners', 09:19:02 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 09:19:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:02 INFO - 'USER': 'cltbld', 09:19:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:02 INFO - 'XPC_FLAGS': '0x0', 09:19:02 INFO - 'XPC_SERVICE_NAME': '0', 09:19:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:19:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-ju73rN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:19:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-uiMXhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:19:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-_IZJrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:19:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-TBhmAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:19:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-sIRVrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:19:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-f4cy0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:19:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-D2MpV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:19:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-L_P5Fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:19:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Xi3yKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:19:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-ma9s0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:19:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-M_kQqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:19:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-CbpAiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:19:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-5jLb83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:19:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-rGB7S0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-h7JvXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:19:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-A45Su9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:19:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-lrf_Hp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:19:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:07 INFO - Downloading blessings-1.6.tar.gz 09:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:19:07 INFO - Installing collected packages: blessings 09:19:07 INFO - Running setup.py install for blessings 09:19:07 INFO - Successfully installed blessings 09:19:07 INFO - Cleaning up... 09:19:07 INFO - Return code: 0 09:19:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:19:08 INFO - Reading from file tmpfile_stdout 09:19:08 INFO - Current package versions: 09:19:08 INFO - blessings == 1.6 09:19:08 INFO - blobuploader == 1.2.4 09:19:08 INFO - docopt == 0.6.1 09:19:08 INFO - functools32 == 3.2.3-2 09:19:08 INFO - jsonschema == 2.5.1 09:19:08 INFO - manifestparser == 1.1 09:19:08 INFO - mozInstall == 1.12 09:19:08 INFO - mozcrash == 1.0 09:19:08 INFO - mozdebug == 0.1 09:19:08 INFO - mozdevice == 0.49 09:19:08 INFO - mozfile == 1.2 09:19:08 INFO - mozhttpd == 0.7 09:19:08 INFO - mozinfo == 0.9 09:19:08 INFO - mozleak == 0.1 09:19:08 INFO - mozlog == 3.4 09:19:08 INFO - moznetwork == 0.27 09:19:08 INFO - mozprocess == 0.25 09:19:08 INFO - mozprofile == 0.28 09:19:08 INFO - mozrunner == 6.13 09:19:08 INFO - mozscreenshot == 0.1 09:19:08 INFO - mozsystemmonitor == 0.3 09:19:08 INFO - moztest == 0.8 09:19:08 INFO - mozversion == 1.4 09:19:08 INFO - psutil == 3.1.1 09:19:08 INFO - requests == 1.2.3 09:19:08 INFO - wsgiref == 0.1.2 09:19:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:08 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 0x10d2e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3adb8bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0c3d30>, '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 0x10cfeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0b8be8>, '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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ji5OiSiWyM/Render', 09:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:08 INFO - 'HOME': '/Users/cltbld', 09:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:08 INFO - 'LOGNAME': 'cltbld', 09:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:08 INFO - 'MOZ_NO_REMOTE': '1', 09:19:08 INFO - 'NO_EM_RESTART': '1', 09:19:08 INFO - 'PAGER': '/bin/cat', 09:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:08 INFO - 'PWD': '/builds/slave/test', 09:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:08 INFO - 'SHELL': '/bin/bash', 09:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83vNMYxCIS/Listeners', 09:19:08 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 09:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:08 INFO - 'USER': 'cltbld', 09:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:08 INFO - 'XPC_FLAGS': '0x0', 09:19:08 INFO - 'XPC_SERVICE_NAME': '0', 09:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:19:08 INFO - Cleaning up... 09:19:08 INFO - Return code: 0 09:19:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:08 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 0x10d2e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3adb8bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0c3d30>, '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 0x10cfeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0b8be8>, '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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ji5OiSiWyM/Render', 09:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:08 INFO - 'HOME': '/Users/cltbld', 09:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:08 INFO - 'LOGNAME': 'cltbld', 09:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:08 INFO - 'MOZ_NO_REMOTE': '1', 09:19:08 INFO - 'NO_EM_RESTART': '1', 09:19:08 INFO - 'PAGER': '/bin/cat', 09:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:08 INFO - 'PWD': '/builds/slave/test', 09:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:08 INFO - 'SHELL': '/bin/bash', 09:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83vNMYxCIS/Listeners', 09:19:08 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 09:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:08 INFO - 'USER': 'cltbld', 09:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:08 INFO - 'XPC_FLAGS': '0x0', 09:19:08 INFO - 'XPC_SERVICE_NAME': '0', 09:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:19:08 INFO - Cleaning up... 09:19:08 INFO - Return code: 0 09:19:08 INFO - Running post-action listener: _resource_record_post_action 09:19:08 INFO - Running post-action listener: _start_resource_monitoring 09:19:08 INFO - Starting resource monitoring. 09:19:08 INFO - [mozharness: 2017-04-21 16:19:08.806336Z] Finished create-virtualenv step (success) 09:19:08 INFO - [mozharness: 2017-04-21 16:19:08.807353Z] Running install step. 09:19:08 INFO - Running pre-action listener: _resource_record_pre_action 09:19:08 INFO - Running main action method: install 09:19:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:19:09 INFO - Reading from file tmpfile_stdout 09:19:09 INFO - Detecting whether we're running mozinstall >=1.0... 09:19:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:19:09 INFO - Reading from file tmpfile_stdout 09:19:09 INFO - Output received: 09:19:09 INFO - Usage: mozinstall [options] installer 09:19:09 INFO - Options: 09:19:09 INFO - -h, --help show this help message and exit 09:19:09 INFO - -d DEST, --destination=DEST 09:19:09 INFO - Directory to install application into. [default: 09:19:09 INFO - "/builds/slave/test"] 09:19:09 INFO - --app=APP Application being installed. [default: firefox] 09:19:09 INFO - mkdir: /builds/slave/test/build/application 09:19:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:19:23 INFO - Reading from file tmpfile_stdout 09:19:23 INFO - Output received: 09:19:23 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:19:23 INFO - Running post-action listener: _resource_record_post_action 09:19:23 INFO - [mozharness: 2017-04-21 16:19:23.867485Z] Finished install step (success) 09:19:23 INFO - [mozharness: 2017-04-21 16:19:23.867616Z] Running run-tests step. 09:19:23 INFO - Running pre-action listener: _resource_record_pre_action 09:19:23 INFO - Running main action method: run_tests 09:19:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:19:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:19:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:19:23 INFO - 2017-04-21 09:19:23.882 screenresolution[1623:7279] starting screenresolution argv=screenresolution get 09:19:23 INFO - 2017-04-21 09:19:23.885 screenresolution[1623:7279] Display 0: 1600x1200x32@60 09:19:23 INFO - 2017-04-21 09:19:23.891 screenresolution[1624:7282] starting screenresolution argv=screenresolution list 09:19:23 INFO - Available Modes on Display 0 09:19:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:19:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:19:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:19:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:19:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:19:24 INFO - Intel Iris: 09:19:24 INFO - Chipset Model: Intel Iris 09:19:24 INFO - Type: GPU 09:19:24 INFO - Bus: Built-In 09:19:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:19:24 INFO - Vendor: Intel (0x8086) 09:19:24 INFO - Device ID: 0x0a2e 09:19:24 INFO - Revision ID: 0x0009 09:19:24 INFO - Displays: 09:19:24 INFO - Display: 09:19:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:19:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:19:24 INFO - Display Serial Number: 1600x1200 60 09:19:24 INFO - Main Display: Yes 09:19:24 INFO - Mirror: Off 09:19:24 INFO - Online: Yes 09:19:24 INFO - Rotation: Supported 09:19:24 INFO - Return code: 0 09:19:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:19:24 INFO - Python 2.7.3 09:19:24 INFO - Return code: 0 09:19:24 INFO - grabbing minidump binary from tooltool 09:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:24 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 0x10d0c3d30>, '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 0x10cfeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0b8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:19:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:19:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:19:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:19:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:19:24 INFO - Return code: 0 09:19:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:19:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:19:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:19:24 INFO - ENV: RUST_BACKTRACE is now 1 09:19:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:19:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:19:24 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/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:19:24 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/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0291 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:19:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ji5OiSiWyM/Render', 09:19:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:24 INFO - 'HOME': '/Users/cltbld', 09:19:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:24 INFO - 'LOGNAME': 'cltbld', 09:19:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:19:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:24 INFO - 'MOZ_NO_REMOTE': '1', 09:19:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:19:24 INFO - 'NO_EM_RESTART': '1', 09:19:24 INFO - 'PAGER': '/bin/cat', 09:19:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:24 INFO - 'PWD': '/builds/slave/test', 09:19:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:19:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:24 INFO - 'RUST_BACKTRACE': '1', 09:19:24 INFO - 'SHELL': '/bin/bash', 09:19:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.83vNMYxCIS/Listeners', 09:19:24 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 09:19:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:24 INFO - 'USER': 'cltbld', 09:19:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:24 INFO - 'XPC_FLAGS': '0x0', 09:19:24 INFO - 'XPC_SERVICE_NAME': '0', 09:19:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:24 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/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:19:24 INFO - mozversion application_buildid: 20170421075527 09:19:24 INFO - mozversion application_changeset: 7e0110d709641ac572bef90185052f37c9ea45c0 09:19:24 INFO - mozversion application_display_name: Firefox 09:19:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:19:24 INFO - mozversion application_name: Firefox 09:19:24 INFO - mozversion application_remotingname: firefox 09:19:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:19:24 INFO - mozversion application_vendor: Mozilla 09:19:24 INFO - mozversion application_version: 54.0 09:19:24 INFO - mozversion platform_buildid: 20170421075527 09:19:24 INFO - mozversion platform_changeset: 7e0110d709641ac572bef90185052f37c9ea45c0 09:19:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:19:24 INFO - mozversion platform_version: 54.0 09:19:24 INFO - using testdate: 1492791564 09:19:24 INFO - actual date: 1492791564 09:19:24 INFO - starting webserver on 'localhost:49236' 09:19:24 INFO - SUITE-START | Running 2 tests 09:19:24 INFO - TEST-START | dromaeo_css 09:19:24 INFO - operating with platform_type : mac_ 09:19:24 INFO - Initialising browser for dromaeo_css test... 09:19:24 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpPxbSPY/profile http://localhost:49236/getInfo.html) 09:19:27 INFO - PROCESS | 1634 | 2017-04-21 09:19:27.602 plugin-container[1637:7442] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b43, name = 'com.apple.tsm.portname' 09:19:27 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:27 INFO - PROCESS | 1634 | 2017-04-21 09:19:27.602 plugin-container[1637:7442] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 09:19:27 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:27 INFO - PROCESS | 1634 | 2017-04-21 09:19:27.602 plugin-container[1637:7442] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:19:27 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 09:19:27 INFO - PROCESS | 1634 | colorDepth:24 09:19:27 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 09:19:27 INFO - PROCESS | 1634 | __metrics 09:19:27 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 09:19:28 INFO - TEST-INFO | 1634: exit 0 09:19:28 INFO - Browser initialized. 09:19:28 INFO - Running cycle 1/1 for dromaeo_css test... 09:19:28 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpPxbSPY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:30 INFO - PROCESS | 1638 | 2017-04-21 09:19:30.749 plugin-container[1639:7576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 09:19:30 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:30 INFO - PROCESS | 1638 | 2017-04-21 09:19:30.749 plugin-container[1639:7576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870b, name = 'com.apple.CFPasteboardClient' 09:19:30 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:30 INFO - PROCESS | 1638 | 2017-04-21 09:19:30.749 plugin-container[1639:7576] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:19:40 INFO - PROCESS | 1638 | RSS: Main: 174235648 09:19:40 INFO - PROCESS | 1638 | 09:22:43 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 09:22:45 INFO - PROCESS | 1638 | RSS: Main: 189345792 09:22:45 INFO - PROCESS | 1638 | 09:25:47 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 09:25:48 INFO - PROCESS | 1638 | RSS: Main: 201887744 09:25:48 INFO - PROCESS | 1638 | 09:28:50 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 09:28:50 INFO - PROCESS | 1638 | RSS: Main: 204029952 09:28:50 INFO - PROCESS | 1638 | 09:31:52 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 09:31:53 INFO - PROCESS | 1638 | RSS: Main: 207310848 09:31:53 INFO - PROCESS | 1638 | 09:34:56 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 09:34:57 INFO - PROCESS | 1638 | RSS: Main: 210239488 09:34:57 INFO - PROCESS | 1638 | 09:37:59 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 09:37:59 INFO - PROCESS | 1638 | RSS: Main: 208302080 09:38:00 INFO - PROCESS | 1638 | 09:38:00 INFO - PROCESS | 1638 | __start_tp_report 09:38:00 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 09:38:00 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 09:38:00 INFO - PROCESS | 1638 | |i|pagename|runs| 09:38:00 INFO - PROCESS | 1638 | |0;dojo.html;3003.5044217704076;3440.569928758905;3655.3603119454087;3669.2111196092833;3682.2819550187705;6097.115918191859;6172.629642221465;6178.536609754269;6201.503879689625;6254.7498100075945;5081.517255860696;6137.785177518787;6178.660173690445;6222.782202622909;6230.940740074187;5646.096624540074;5652.943470565303;5665.66006039639;5670.886582268352;5732;5687.2606561146995;5698.401667824881;5702.885942281152;5717.656940583578;5733.168690539869;5485.0949593317055;5694.658320500783;5695.401982791809;5706.087026075828;5713.514351280146;5526.364468086162;5562.833115006556;5566.832995010156;5572.58205634579;5572.693501857396;5444.700541470217;5581.944180558183;5602.663840169603;5611.859703507404;5672.234248376499;6194.628322300677;6227.065940108974;6251.187345645082;6273.466755325803;6286.088517165032;5448.37343705471;6256.812295631138;6370.203724534434;6602.1747281589805;6668.299828518033;6440.51696122788;6536.77121300757;6574;6597.7031033603125;6624.635645039521;6251;6275.843103922347;6281.309056003836;6282.120503129566;6300.747970081191;5594.216809646653;6710.932890671128;6850.760223392257;6853.109095817512;6871.415929646036;6266.968665156645;6295.181626388632;6391.360863913665;6405.19935008124;6464.741410343628;6668.499862510331;6671.532992690465;6683.598984060972;6692.397684208445;6709.261981182066;6530.542861999709;6532.902006469907;6544.509161812788;6547.639879806561;6578.177727784027;5948.167256584082;6303.432691057735;6318.905216421757;6445.258795238514;6455.935440645628;4557.498675145731;4653.627709783209;5073.833018405788;5361.463853614608;5388.434214407431;4665.206914824488;4686.156491831435;4702.388689470347;4734.360861283682;4763.118823017753;6180.721867515883;6301.960176570906;6318.62088274705;6352.269489008823;6358.1734374531015;6016.157737916695;6228.6574238416415;6339;6575.309592492815;6665.066890635315;6315.526335524854;6319.778807741707;6338.714757835908;6355.618662880242;6365.554411191265;5939.46544810969;5940.940590594038;5946.940530594725;5963.552733544915;5969.224000879858;6722.731090756511;6812.114425124702;6842.384185423336;6878.621675807879;7001.824954376181;6649.135612370361;6668.899966500603;6668.933310666831;6674.499412543982;6690;6916.308369163044;6949.270326615835;6953.548019378724;6969.233384327821;6997.300269972962;6314.873702526015;6351.777687780906;6427.6786160690845;6562.868742625216;6569.96715016441;5411.350637923474;6145.907811382744;6594.043863639649;6610.272869984394;6611.603303801788;6398.424141827258;6411.679416029273;6421.935780642134;6427.742890284523;6428.19647544057;6267.717952692231;6268.498520118471;6303.936960630518;6411.6153030818305;6468.2561505427475;6181.752729890934;6320.367963203643;6342.9682851587295;6353.14232578596;6358.936410635834;6457.418832305115;6488.091667166695;6490.967545162244;6506;6510.088587597835;6149.046897730859;6293.842653933688;6298.464630506275;6350.7459701611415;6392 09:38:00 INFO - PROCESS | 1638 | |1;ext.html;778.7858338956789;818.316705550865;845.3997661660221;992.6575331510626;1001.4391940513318;42772.57227427725;42942.7852860736;43042.92392690181;43092.78453607731;43170.35244471335;41141.177176456455;41627;41681.166376672525;41745.58254417455;41760.37359439611;8306.210909963544;8463.873041904377;11404.144689148305;11706.53173873044;11880.108991825604;11497.252678575907;11596.594119205829;11602.535898564047;11611;11642.592509262178;11607.071434285237;11621.47703353351;11655.825162622568;11682.007029402512;11689.649310520655;9726.465044422554;10838.891611083867;11575.710607234802;11587.942060289644;11593.536258549648;11361.602343917923;11421.371824549646;11429.771404571946;11431.314121152758;11431.885681143165;36383.636163638286;36481.63518364835;36546.81726591376;36567.35446904896;36599;3947.230290093404;3950.2889479893606;3980.7611543307494;4005.35914253718;4063.085805693725;26926.326841828915;27102.186934392;27181.864090679617;28511.429771404662;28754.856225718737;3222.96777032229;3303.157694787838;3310.35448087623;3356.9496457552914;3426.9657303426898;372473;375779;432399;520744.9999999962;522797;44972.77513612411;45134;45173.77413112913;45272.54727452749;45331.773341133085;47017.76491117591;47059.764701176275;47165.764171178926;47174.82062948454;47277.76361118173;14525.92737036308;14607.853921460863;14662.926685366505;14693.338799754038;14834.332455039548;12598.118131730691;12701.110922235539;12758;12785.680357991125;12893.226406415644;12022.218555794021;12059.397030148457;12233.69415764613;12308.26150430959;12332;5675.829725108253;5714.857128571819;5724.427557244242;5737.08206686928;5737.913931291034;1170.9531618735155;1221.7617564574823;1235.1539195650828;1239.20690757915;1253.4359538207661;23022.07911683514;24552.631710524358;28551.580062672805;28767.712322876923;28956.27609309784;1080.5515710980012;1277.7316763479773;1277.8594354620984;1299.8375203099613;1307.6861553227156;12513.937430312972;14745.926270368795;14900.254987250593;14974.700505989818;15602.29789659468;525342;527990;528522;528646;530174;52179;52240;52263.21605175926;52494.21258681123;52527.47472525226;1295.7667619828521;1333.973320533584;1471.448206922404;1517.5674932644145;1528.1136940574663;22558.77441225567;22707.205247816248;27442.62786860609;29351.93921636771;29842.55236171416;48960.02079958452;49126.01747965092;49402;49438.75280623574;49450.25824612562;490386;491637;495254;495447;496627;30740.846295768377;30764.538531922488;30788.846055769576;31138.377232455678;31158.532622010236;39992;40327.193456130124;40605.984850378976;40698.79650601728;41395;28269.858650706614;28996.565051524667;29145.271368215963;29865.5520167202;29913.551296730133;28727.281817954718;29206.853965730035;29562.260943476584;29567.852160739058;29794.553081703358;33101.33797324088;33554.49668255027;33833.66166338404;34063;34329.65670343264;29319.827206911486;29378.853105734335;29434.411311773216;29448.558271625516;30239.941602043822;17127;22033.889830550746;23154.1896033638;23647;23852.52294954126 09:38:00 INFO - PROCESS | 1638 | |2;jquery.html;405.56401867991906;419.63282128137877;441.4419159153694;442.4292662465419;444.19822220891297;20340.18639254428;21087.051082701277;21974.780252197474;22200.444988875286;22339.77660223397;22582.548349033008;22961.4259643509;22965;23187.304380868558;24272;6712.664366781666;6963.860722785541;6987;7009.929900701004;7348.963255183743;7607.581583012932;7719.305262526372;7734.651940662684;7737.535747855119;7740.993670822785;7482.850342993137;7678.347340476066;7803.29770320671;7803.3367163791145;7848.136704962478;7562.28158324961;7722.884156737653;7729.884051739228;7780.688772449095;7791.4935529190425;7269.963650181769;7481.177070522238;7491.175970643225;7521.360684341837;7543.434242431785;26822.865885670642;26855.92576296946;27345.726542734716;27412.862935685192;28025.01912433055;2926.678065412803;3037.6506701729363;3040.878364865425;3048.1008102609785;3231.8868839590737;15079.396824127021;16343.203303539918;16429.4986894002;16839.41062062822;16933.40733074349;2109.1141720477435;2143.153454385509;2170.6852506386485;2217.4456385903522;2390.306811024801;124560;128046;146942;178958.10520947442;184178.07910960494;18832.623347532972;20317.796822031887;20937.37187884366;21101.261455848973;21137.154513819583;31560.210994725316;31567.368652626817;31762;32003.67996320007;32237.51643725346;10739.516721747541;10948.507317170586;11084.61203857861;11160.553577856957;11271;9229.927098106462;9241.815163696689;9324.440533567871;9524.523773811421;9597.424154550748;9701;9883.30816842814;9901.950490247647;9978.550965206583;10006.399616023062;3583.713302935811;4227.767472788964;4244.745315281093;4274.230638485103;4345.217860785089;826.3471857232839;1031.3657100882904;1064.2869277583882;1101.6970333158285;1126.9492872820742;21379.25172618951;21826;21928.903554822515;21968.670469942965;21979.450513736963;1111.3276467737064;1112.8330750387345;1135.9432028398712;1136.2841409911703;1137.70988397958;11014.50434730439;11215.60745373908;11292.717682058013;11294.94352528232;11358.716032099099;388367;390516;410811;796458;804442;157191.42808571996;163611;165490.1725491389;170521;175448;1297.5977446991274;1309.214471317221;1351.5472316773992;1355.2275203134316;1356.2472827580787;19263.036848157255;19373.225070997003;19822.306219282254;19871.00644967775;19969.400917972485;53141;60738.4815379619;62462;67456;67909;88538.55730721305;95536.52231738796;96292;96557.0344296548;98648.5202721974;11536.25014374063;11571.768564628492;11662.650120496399;11669.649910502698;11981.520739170335;10132.290739648153;11181.944090279496;13232.801507977581;13365.797078262614;13520.59438216855;11368.94315528417;11396.487158078073;11442.42787860587;11593.362365070003;11768.76462470763;10230.335028223142;10801.729956750829;10832.566697332019;11148.609798657008;11259.268147570381;11843.644690659294;11853.466594003463;11902.226355287225;12122.878771212176;12192.87807121953;11846.703832404259;11851.585194518151;11881.524739010689;12004.879951200377;12163.2702037878;11540.422978851191;11565.942170289094;11644.301341919512;11719.296842189497;11903.821442678194 09:38:00 INFO - PROCESS | 1638 | |3;mootools.html;1444.6749481366687;1580.9367625294988;1644.671065786843;1664.9750253746192;1666.608382001029;9973.451460169688;10114.241431892618;11349.262297950638;11828.940855295723;12101.334426606532;9668.226541876651;11654.359010254453;11659.242149260292;11662.82505762412;11868.881311186886;2357.8231632627535;2388.844725092872;2390.282915125464;2390.8565486070806;2396.269137912936;2339.9532009359805;2350.083467447697;2360.7639236076425;2363.7163540375177;2364.172539611131;2116.597846409185;2333.6616190652344;2341.3210169050953;2445.608702607583;2521.735217802132;2470.9505809883703;2478.8884500197446;2485.540175067609;2491.9377015574573;2496.563101457247;2391.880405979694;2392.6650268962358;2402.7356990731005;2402.9639555406684;2405.0980882169188;11596.768064638743;11687.240329378563;11692.824607630891;11709.53161873524;11726.941365293203;5931.228940237742;6093.268807743099;6131.080337949299;6214.378562143795;6227.1282020517165;3728.272986767581;3815.275097731422;3861.092643228839;3890.3191941410423;3935.0359162005207;1274.7195616964252;1290.825738525306;1305.9412326445333;1326.9668258293523;1336.40529964166;15896.682066358608;15902.60243493922;15964.521064368088;16088.919555402264;16291.755623665655;4821.397325334333;4889;4973.502649734997;5016.046951079356;5030.974845125751;12234.265944043385;12269.325187114617;12269.631911042683;12281.508739650313;12284.754304914031;6030.306514750772;6115.4190351916495;6122.836661034389;6122.846928826726;6144.2319710036245;4712.363830882856;4766.404199475066;5494.642848214853;5579.721013949286;5608.635438696471;3379.983100084533;3953.347697629916;4065.4511640928686;4122.237386083584;4125.50493940723;1079.0342643334172;1082.8429877667695;1087.9564817407374;1089.3572792052728;1090.4220762995626;980.4117529482254;995.4176806568239;1002.9899701003043;1012.5747186181674;1017.9134773544183;6115.1744514490865;6489.5781774184525;6871.9656401717675;6932.403834183991;7117.217106118323;923.8774888510492;965.2663975378763;981.8232718110665;993.5578667492896;1029.6550655530332;3802.980985095057;4370.694051416434;4379.802908869108;4450.821967121344;4536.228841097021;13471.865281347062;13850.792238116437;14405.207713575854;14458.915581331443;14634.487792927197;13919.860801392058;14004.159750414803;14244.928775356057;14266.500672476413;14275.214863182417;10415.270931034755;10717.946410267743;10722.303050301705;10808.459577021118;10813.297135686154;6972.546784458994;7000.19497757744;7081.610511421921;7090.4327653788605;7101.431885449048;12685.175463594836;12851.971842252784;13167.407466663832;13186.736265274832;13243.404046817725;12820.679483013;13015.349232538525;13071.411786469442;13151.408186631816;13309.866901330863;8351.039630442578;8588.828223435621;9461.574229159842;9501.287403444907;9604.423734575668;11059.115270778479;11080.113590912546;11187.776244475228;11201.879812018733;11205.551777928791;7734.7679569610755;9222.907770922206;9224.446533208029;9238.260939124988;9303.069693030642;8701.303895688456;8791.472511649321;8835.24900383449;8909.1536304051;9011.369204155646;8341.332693384393;8357.37319701037;8368;8475.40672152968;8486.490810551386;6080.331163572092;6165.198524191826;6214.5960512566535;6292.056191571118;6329.082283068838;6160.10678451631;6178.7219575120125;6227.813165605039;6228.906566401417;6371.331010244043 09:38:00 INFO - PROCESS | 1638 | |4;prototype.html;343.31851275218696;365.6782031812007;388.273927755098;395.1385978566725;404.3994667918141;4536.682432229745;4812.566868981795;5313.362396512414;5372.75822587984;5488.396276409591;4659.922567753216;5184.8703782405455;5246.37043554773;5272.789088436468;5308.390294599671;3633.7819730816104;3755.023693839594;3792.886213413602;4450.688451808359;4465.789434230061;3696.4455331700324;3818.7517811342314;4467.475876129993;4504.414426124616;4505.526919673436;3837.846486140551;4474.485434175062;4489.349044388562;4494.8876278092985;4506;3754.812259387047;4193.245215861144;4375.6937014409;4493.281075027996;4499.370088187657;3520.700740437066;3795.3168429682646;4074.899777060207;4081.8979525511804;4118.629323360882;4322.222000039991;4683.484816670163;5076.847694569132;5126.743662816882;5226.895462090775;4417.955820441786;4537.818487260505;4800.783964721596;5143.3313669223135;5147.6911385317;5554.777808887639;5657.3494048184575;5664.847358003932;5672.574556908207;5864.237649105589;3966.5240171179644;4372.234858899712;4529.909401811979;4596.161535385839;4615.515370886043;5309.840704778863;5352.652077614982;5741.971290143564;6039.5772295939305;6190.288116866573;4060.7360521566;4675.18184317743;4737.336772851803;4747.738874361943;4761.40482439695;4160.148733813424;4164.7917604119675;4691.7888695008805;4811.350467686888;4890.706557606555;4086.137496379299;5020;5135.563477104488;5137.460566640449;5230;4618.607418369476;4665.836695715634;4878.707277563358;5663;5710.829279997591;1551.0306058713304;1568.3021055630136;1600.743880979061;1610.8711303095959;1622.9107399093164;1115.2806439846254;1207.852540086921;1210.975780484403;1213.969650758738;1241.1064033895582;5345.465453454701;5356.196570514454;5358.946410535923;5362.104463589618;5362.65142765719;5347.839564813062;5499.690130145348;5877.471027607536;5898.997170481029;5954.672493012839;4426.822927082881;4775.856724298276;4875.34182885313;5100.668456550312;5116.411612664591;2544.122277814151;2688.784897208219;2703.648175912044;2804.901828435995;2910.6652734935756;5222.97388513055;5362;5370.62405631602;5415.620906536584;6088.330283668793;4755.789841856733;5073.213651883964;5214.800595862973;5649.322081350264;5996.2504686914135;5298.841034768963;5410.836670115866;5416.647917885403;5742.827715168468;6210.633660594662;4812.027068901733;5131.79472821083;5838.649681019152;5880.47107751979;6005.819825405245;5035.949640503548;5201.271821944855;5307.787688492417;5671.391160066416;5753.9136912945505;4926.827561035347;5242.606804489755;6227.750889964532;6234.781782637586;6236.314005459305;4662.813487460464;4686.797205313497;4692.601128904082;4718.363020992259;4726.8109275628585;5328.254044433705;5579.53637090318;5897.557683173865;5904.527637789052;5955.940440595712;4909.091818013679;5694.373618902;5703.657780533182;5728.742206600797;5759.251297331488;5086.949130508796;5504.394516603251;5697.800576979786;5737.684427356368;5738.741756620879;5379.246905433164;5460.3993560707495;5475;5536.889262214814;5611.653203231113;5224.7648855802345;5286.947130528645;5664.745086471036;5698.544116470755;5765.8558536036935;4974.5771609413605;5154.974225128851;5278.76245568958;5384.5423139032055;5555.30558680165 09:38:00 INFO - PROCESS | 1638 | |5;yui.html;772.266346970378;798.9374132403904;885.9069797671244;889.5685592487646;897.3001059173845;15287.388504459823;15890.205489725497;15943.04341739495;16935.66128677426;18126.456206313833;15876.96799708018;16054.759178612301;16199.757003644954;16434.75347869783;16440.42458513952;994.1947022911429;1006.4162785584343;1007.9193664506822;1008.1430783833756;1008.5713571732022;972.3873959405564;1002.1882275356948;1011.4083261292116;1012.0739523336139;1012.964546240878;1066.109798318405;1066.3655125200494;1067.2902519824308;1068.102793653331;1071.3946620159625;865.9740207793744;1057.4818339013905;1057.740853490892;1057.793730222603;1059.7403636109202;787.3032366355759;787.52354825331;787.5629025890628;787.8857565653005;788.6687591211705;16848.57878553034;17120;17243.482695519153;18122.184501697455;18335.54161145969;14579.562613121623;14764.778528322084;14829.332680029425;14938.775918361232;15136.091834489966;10389.688309350731;11977.92198702121;11997.700057498545;12169.14815962883;12762.808557871547;3406.6593340665736;3830.291396091704;3853.2486165197797;3955.0705584187695;4048.6356227939627;115589;118348;120178.39910800477;120792;122543.16185257139;11387.19239262343;11459.541618335339;11608.129390295593;11732.413379331;11913.16607837443;23547.41131471727;23718;24343;24364.87817560901;24367.903444345047;7159.821004474929;8141.959290203511;8334.49993000422;8361.790955226168;8410.495370277802;4606.677532572755;4611.354410382549;4668.603168730628;4686.461056978423;4808.2547205183255;3670.981645091811;3727.2731817295366;3762.059485128718;3773.52830896138;3778.0554861284677;658.2430205263985;664.4252721396055;669.9564528305742;690.3131384272681;701.3757755597522;724.4566575068699;750.9586972716553;754.3135746470685;779.1701837542998;782.8121250899743;9028.8645670315;10931.945340273247;10981.402946156837;11024.779504409868;11053.557857685764;723.6743465440468;744.6537360127459;747.5514691185246;749.606456610284;752.4018405367746;3987.7208595398624;5143.614228932845;5195.272661827347;5255.08036093682;5301.893962120736;83450.58274708588;102251;106540;106924.93075069305;106928;57906.131408028916;72179;72407;73294.90057649139;87113.69329460063;61438.38561614326;74303.62848185725;74728.6573787213;77958.83061754078;79708.60145699234;10905.945470272598;11399.145064120059;11509.712257193638;11585.362805045466;11609.767804644029;44661.776691116334;49774.75112624414;52003.21995170151;52135.739321303154;53118.46881531135;53564.46435535595;67396;67854.66072669605;68160.31839681539;68741.65629171822;13164.40760165814;13202.207867527595;13268.668283292995;13464;13601.523946661819;14993.175375354023;15074.623134421727;15202.847971520143;15362;15401.84598154049;13932.30338483092;14372;14396.496122635657;14565.92717036408;14600.12399256048;13716.24560649134;14411.85588144147;14433.35049922777;14661.560153195422;14663.048349840437;13341.933290333487;13921.930390347983;13974.930125349309;14263.928680356532;14518.201498917238;10285.228607854591;10596.8940310599;10636.607261833866;10712.73218169552;11046.447677615926;9726.465044422624;9897.65358212488;10310.845337320095;11725.941370293094;11739.413029348669 09:38:00 INFO - PROCESS | 1638 | __end_tp_report 09:38:00 INFO - PROCESS | 1638 | __start_cc_report 09:38:00 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,384 09:38:00 INFO - PROCESS | 1638 | __end_cc_report 09:38:00 INFO - PROCESS | 1638 | __startTimestamp1492792679998__endTimestamp 09:38:00 INFO - PROCESS | 1638 | 09:38:00 INFO - PROCESS | 1638 | ------- Summary: start ------- 09:38:00 INFO - PROCESS | 1638 | Number of tests: 6 09:38:00 INFO - PROCESS | 1638 | 09:38:00 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:6102.28 Median:6301.96 stddev:693.54 (11.0%) stddev-sans-first:651.68 09:38:00 INFO - PROCESS | 1638 | Values: 3003.5 3440.6 3655.4 3669.2 3682.3 6097.1 6172.6 6178.5 6201.5 6254.7 5081.5 6137.8 6178.7 6222.8 6230.9 5646.1 5652.9 5665.7 5670.9 5732.0 5687.3 5698.4 5702.9 5717.7 5733.2 5485.1 5694.7 5695.4 5706.1 5713.5 5526.4 5562.8 5566.8 5572.6 5572.7 5444.7 5581.9 5602.7 5611.9 5672.2 6194.6 6227.1 6251.2 6273.5 6286.1 5448.4 6256.8 6370.2 6602.2 6668.3 6440.5 6536.8 6574.0 6597.7 6624.6 6251.0 6275.8 6281.3 6282.1 6300.7 5594.2 6710.9 6850.8 6853.1 6871.4 6267.0 6295.2 6391.4 6405.2 6464.7 6668.5 6671.5 6683.6 6692.4 6709.3 6530.5 6532.9 6544.5 6547.6 6578.2 5948.2 6303.4 6318.9 6445.3 6455.9 4557.5 4653.6 5073.8 5361.5 5388.4 4665.2 4686.2 4702.4 4734.4 4763.1 6180.7 6302.0 6318.6 6352.3 6358.2 6016.2 6228.7 6339.0 6575.3 6665.1 6315.5 6319.8 6338.7 6355.6 6365.6 5939.5 5940.9 5946.9 5963.6 5969.2 6722.7 6812.1 6842.4 6878.6 7001.8 6649.1 6668.9 6668.9 6674.5 6690.0 6916.3 6949.3 6953.5 6969.2 6997.3 6314.9 6351.8 6427.7 6562.9 6570.0 5411.4 6145.9 6594.0 6610.3 6611.6 6398.4 6411.7 6421.9 6427.7 6428.2 6267.7 6268.5 6303.9 6411.6 6468.3 6181.8 6320.4 6343.0 6353.1 6358.9 6457.4 6488.1 6491.0 6506.0 6510.1 6149.0 6293.8 6298.5 6350.7 6392.0 09:38:00 INFO - PROCESS | 1638 | 09:38:00 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:61283.70 Median:29889.55 stddev:131038.30 (438.4%) stddev-sans-first:131327.16 09:38:00 INFO - PROCESS | 1638 | Values: 778.8 818.3 845.4 992.7 1001.4 42772.6 42942.8 43042.9 43092.8 43170.4 41141.2 41627.0 41681.2 41745.6 41760.4 8306.2 8463.9 11404.1 11706.5 11880.1 11497.3 11596.6 11602.5 11611.0 11642.6 11607.1 11621.5 11655.8 11682.0 11689.6 9726.5 10838.9 11575.7 11587.9 11593.5 11361.6 11421.4 11429.8 11431.3 11431.9 36383.6 36481.6 36546.8 36567.4 36599.0 3947.2 3950.3 3980.8 4005.4 4063.1 26926.3 27102.2 27181.9 28511.4 28754.9 3223.0 3303.2 3310.4 3356.9 3427.0 372473.0 375779.0 432399.0 520745.0 522797.0 44972.8 45134.0 45173.8 45272.5 45331.8 47017.8 47059.8 47165.8 47174.8 47277.8 14525.9 14607.9 14662.9 14693.3 14834.3 12598.1 12701.1 12758.0 12785.7 12893.2 12022.2 12059.4 12233.7 12308.3 12332.0 5675.8 5714.9 5724.4 5737.1 5737.9 1171.0 1221.8 1235.2 1239.2 1253.4 23022.1 24552.6 28551.6 28767.7 28956.3 1080.6 1277.7 1277.9 1299.8 1307.7 12513.9 14745.9 14900.3 14974.7 15602.3 525342.0 527990.0 528522.0 528646.0 530174.0 52179.0 52240.0 52263.2 52494.2 52527.5 1295.8 1334.0 1471.4 1517.6 1528.1 22558.8 22707.2 27442.6 29351.9 29842.6 48960.0 49126.0 49402.0 49438.8 49450.3 490386.0 491637.0 495254.0 495447.0 496627.0 30740.8 30764.5 30788.8 31138.4 31158.5 39992.0 40327.2 40606.0 40698.8 41395.0 28269.9 28996.6 29145.3 29865.6 29913.6 28727.3 29206.9 29562.3 29567.9 29794.6 33101.3 33554.5 33833.7 34063.0 34329.7 29319.8 29378.9 29434.4 29448.6 30239.9 17127.0 22033.9 23154.2 23647.0 23852.5 09:38:00 INFO - PROCESS | 1638 | 09:38:00 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:38643.51 Median:11998.34 stddev:101330.72 (844.5%) stddev-sans-first:101574.30 09:38:00 INFO - PROCESS | 1638 | Values: 405.6 419.6 441.4 442.4 444.2 20340.2 21087.1 21974.8 22200.4 22339.8 22582.5 22961.4 22965.0 23187.3 24272.0 6712.7 6963.9 6987.0 7009.9 7349.0 7607.6 7719.3 7734.7 7737.5 7741.0 7482.9 7678.3 7803.3 7803.3 7848.1 7562.3 7722.9 7729.9 7780.7 7791.5 7270.0 7481.2 7491.2 7521.4 7543.4 26822.9 26855.9 27345.7 27412.9 28025.0 2926.7 3037.7 3040.9 3048.1 3231.9 15079.4 16343.2 16429.5 16839.4 16933.4 2109.1 2143.2 2170.7 2217.4 2390.3 124560.0 128046.0 146942.0 178958.1 184178.1 18832.6 20317.8 20937.4 21101.3 21137.2 31560.2 31567.4 31762.0 32003.7 32237.5 10739.5 10948.5 11084.6 11160.6 11271.0 9229.9 9241.8 9324.4 9524.5 9597.4 9701.0 9883.3 9902.0 9978.6 10006.4 3583.7 4227.8 4244.7 4274.2 4345.2 826.3 1031.4 1064.3 1101.7 1126.9 21379.3 21826.0 21928.9 21968.7 21979.5 1111.3 1112.8 1135.9 1136.3 1137.7 11014.5 11215.6 11292.7 11294.9 11358.7 388367.0 390516.0 410811.0 796458.0 804442.0 157191.4 163611.0 165490.2 170521.0 175448.0 1297.6 1309.2 1351.5 1355.2 1356.2 19263.0 19373.2 19822.3 19871.0 19969.4 53141.0 60738.5 62462.0 67456.0 67909.0 88538.6 95536.5 96292.0 96557.0 98648.5 11536.3 11571.8 11662.7 11669.6 11981.5 10132.3 11181.9 13232.8 13365.8 13520.6 11368.9 11396.5 11442.4 11593.4 11768.8 10230.3 10801.7 10832.6 11148.6 11259.3 11843.6 11853.5 11902.2 12122.9 12192.9 11846.7 11851.6 11881.5 12004.9 12163.3 11540.4 11565.9 11644.3 11719.3 11903.8 09:38:00 INFO - PROCESS | 1638 | 09:38:00 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:7015.26 Median:2458.28 stddev:4424.63 (180.0%) stddev-sans-first:4417.25 09:38:00 INFO - PROCESS | 1638 | Values: 1444.7 1580.9 1644.7 1665.0 1666.6 9973.5 10114.2 11349.3 11828.9 12101.3 9668.2 11654.4 11659.2 11662.8 11868.9 2357.8 2388.8 2390.3 2390.9 2396.3 2340.0 2350.1 2360.8 2363.7 2364.2 2116.6 2333.7 2341.3 2445.6 2521.7 2471.0 2478.9 2485.5 2491.9 2496.6 2391.9 2392.7 2402.7 2403.0 2405.1 11596.8 11687.2 11692.8 11709.5 11726.9 5931.2 6093.3 6131.1 6214.4 6227.1 3728.3 3815.3 3861.1 3890.3 3935.0 1274.7 1290.8 1305.9 1327.0 1336.4 15896.7 15902.6 15964.5 16088.9 16291.8 4821.4 4889.0 4973.5 5016.0 5031.0 12234.3 12269.3 12269.6 12281.5 12284.8 6030.3 6115.4 6122.8 6122.8 6144.2 4712.4 4766.4 5494.6 5579.7 5608.6 3380.0 3953.3 4065.5 4122.2 4125.5 1079.0 1082.8 1088.0 1089.4 1090.4 980.4 995.4 1003.0 1012.6 1017.9 6115.2 6489.6 6872.0 6932.4 7117.2 923.9 965.3 981.8 993.6 1029.7 3803.0 4370.7 4379.8 4450.8 4536.2 13471.9 13850.8 14405.2 14458.9 14634.5 13919.9 14004.2 14244.9 14266.5 14275.2 10415.3 10717.9 10722.3 10808.5 10813.3 6972.5 7000.2 7081.6 7090.4 7101.4 12685.2 12852.0 13167.4 13186.7 13243.4 12820.7 13015.3 13071.4 13151.4 13309.9 8351.0 8588.8 9461.6 9501.3 9604.4 11059.1 11080.1 11187.8 11201.9 11205.6 7734.8 9222.9 9224.4 9238.3 9303.1 8701.3 8791.5 8835.2 8909.2 9011.4 8341.3 8357.4 8368.0 8475.4 8486.5 6080.3 6165.2 6214.6 6292.1 6329.1 6160.1 6178.7 6227.8 6228.9 6371.3 09:38:00 INFO - PROCESS | 1638 | 09:38:00 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4680.61 Median:5108.54 stddev:1314.20 (25.7%) stddev-sans-first:1276.93 09:38:00 INFO - PROCESS | 1638 | Values: 343.3 365.7 388.3 395.1 404.4 4536.7 4812.6 5313.4 5372.8 5488.4 4659.9 5184.9 5246.4 5272.8 5308.4 3633.8 3755.0 3792.9 4450.7 4465.8 3696.4 3818.8 4467.5 4504.4 4505.5 3837.8 4474.5 4489.3 4494.9 4506.0 3754.8 4193.2 4375.7 4493.3 4499.4 3520.7 3795.3 4074.9 4081.9 4118.6 4322.2 4683.5 5076.8 5126.7 5226.9 4418.0 4537.8 4800.8 5143.3 5147.7 5554.8 5657.3 5664.8 5672.6 5864.2 3966.5 4372.2 4529.9 4596.2 4615.5 5309.8 5352.7 5742.0 6039.6 6190.3 4060.7 4675.2 4737.3 4747.7 4761.4 4160.1 4164.8 4691.8 4811.4 4890.7 4086.1 5020.0 5135.6 5137.5 5230.0 4618.6 4665.8 4878.7 5663.0 5710.8 1551.0 1568.3 1600.7 1610.9 1622.9 1115.3 1207.9 1211.0 1214.0 1241.1 5345.5 5356.2 5358.9 5362.1 5362.7 5347.8 5499.7 5877.5 5899.0 5954.7 4426.8 4775.9 4875.3 5100.7 5116.4 2544.1 2688.8 2703.6 2804.9 2910.7 5223.0 5362.0 5370.6 5415.6 6088.3 4755.8 5073.2 5214.8 5649.3 5996.3 5298.8 5410.8 5416.6 5742.8 6210.6 4812.0 5131.8 5838.6 5880.5 6005.8 5035.9 5201.3 5307.8 5671.4 5753.9 4926.8 5242.6 6227.8 6234.8 6236.3 4662.8 4686.8 4692.6 4718.4 4726.8 5328.3 5579.5 5897.6 5904.5 5955.9 4909.1 5694.4 5703.7 5728.7 5759.3 5086.9 5504.4 5697.8 5737.7 5738.7 5379.2 5460.4 5475.0 5536.9 5611.7 5224.8 5286.9 5664.7 5698.5 5765.9 4974.6 5155.0 5278.8 5384.5 5555.3 09:38:00 INFO - PROCESS | 1638 | 09:38:00 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20633.83 Median:16644.50 stddev:29176.95 (175.3%) stddev-sans-first:29220.68 09:38:00 INFO - PROCESS | 1638 | Values: 772.3 798.9 885.9 889.6 897.3 15287.4 15890.2 15943.0 16935.7 18126.5 15877.0 16054.8 16199.8 16434.8 16440.4 994.2 1006.4 1007.9 1008.1 1008.6 972.4 1002.2 1011.4 1012.1 1013.0 1066.1 1066.4 1067.3 1068.1 1071.4 866.0 1057.5 1057.7 1057.8 1059.7 787.3 787.5 787.6 787.9 788.7 16848.6 17120.0 17243.5 18122.2 18335.5 14579.6 14764.8 14829.3 14938.8 15136.1 10389.7 11977.9 11997.7 12169.1 12762.8 3406.7 3830.3 3853.2 3955.1 4048.6 115589.0 118348.0 120178.4 120792.0 122543.2 11387.2 11459.5 11608.1 11732.4 11913.2 23547.4 23718.0 24343.0 24364.9 24367.9 7159.8 8142.0 8334.5 8361.8 8410.5 4606.7 4611.4 4668.6 4686.5 4808.3 3671.0 3727.3 3762.1 3773.5 3778.1 658.2 664.4 670.0 690.3 701.4 724.5 751.0 754.3 779.2 782.8 9028.9 10931.9 10981.4 11024.8 11053.6 723.7 744.7 747.6 749.6 752.4 3987.7 5143.6 5195.3 5255.1 5301.9 83450.6 102251.0 106540.0 106924.9 106928.0 57906.1 72179.0 72407.0 73294.9 87113.7 61438.4 74303.6 74728.7 77958.8 79708.6 10905.9 11399.1 11509.7 11585.4 11609.8 44661.8 49774.8 52003.2 52135.7 53118.5 53564.5 67396.0 67854.7 68160.3 68741.7 13164.4 13202.2 13268.7 13464.0 13601.5 14993.2 15074.6 15202.8 15362.0 15401.8 13932.3 14372.0 14396.5 14565.9 14600.1 13716.2 14411.9 14433.4 14661.6 14663.0 13341.9 13921.9 13974.9 14263.9 14518.2 10285.2 10596.9 10636.6 10712.7 11046.4 9726.5 9897.7 10310.8 11725.9 11739.4 09:38:00 INFO - PROCESS | 1638 | -------- Summary: end -------- 09:38:00 INFO - PROCESS | 1638 | 09:38:00 INFO - TEST-INFO | 1638: exit 0 09:38:00 INFO - TEST-OK | dromaeo_css | took 1116070ms 09:38:00 INFO - TEST-START | kraken 09:38:00 INFO - operating with platform_type : mac_ 09:38:00 INFO - Initialising browser for kraken test... 09:38:00 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpP3D_gr/profile http://localhost:49236/getInfo.html) 09:38:02 INFO - PROCESS | 1650 | 2017-04-21 09:38:02.944 plugin-container[1652:9984] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 09:38:02 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:02 INFO - PROCESS | 1650 | 2017-04-21 09:38:02.945 plugin-container[1652:9984] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 09:38:02 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:02 INFO - PROCESS | 1650 | 2017-04-21 09:38:02.945 plugin-container[1652:9984] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:02 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 09:38:02 INFO - PROCESS | 1650 | colorDepth:24 09:38:02 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 09:38:02 INFO - PROCESS | 1650 | __metrics 09:38:03 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 09:38:03 INFO - TEST-INFO | 1650: exit 0 09:38:03 INFO - Browser initialized. 09:38:03 INFO - Running cycle 1/1 for kraken test... 09:38:03 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpP3D_gr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:05 INFO - PROCESS | 1653 | 2017-04-21 09:38:05.957 plugin-container[1654:10108] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x832f, name = 'com.apple.tsm.portname' 09:38:05 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:05 INFO - PROCESS | 1653 | 2017-04-21 09:38:05.957 plugin-container[1654:10108] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450f, name = 'com.apple.CFPasteboardClient' 09:38:05 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:05 INFO - PROCESS | 1653 | 2017-04-21 09:38:05.957 plugin-container[1654:10108] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:15 INFO - PROCESS | 1653 | RSS: Main: 174186496 09:38:15 INFO - PROCESS | 1653 | 09:38:18 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 09:38:19 INFO - PROCESS | 1653 | RSS: Main: 187846656 09:38:19 INFO - PROCESS | 1653 | 09:38:22 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 09:38:23 INFO - PROCESS | 1653 | RSS: Main: 195792896 09:38:23 INFO - PROCESS | 1653 | 09:38:26 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 09:38:27 INFO - PROCESS | 1653 | RSS: Main: 199520256 09:38:27 INFO - PROCESS | 1653 | 09:38:28 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 09:38:29 INFO - PROCESS | 1653 | RSS: Main: 199831552 09:38:29 INFO - PROCESS | 1653 | 09:38:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:38:32 INFO - PROCESS | 1653 | RSS: Main: 199876608 09:38:32 INFO - PROCESS | 1653 | 09:38:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 09:38:35 INFO - PROCESS | 1653 | RSS: Main: 204984320 09:38:35 INFO - PROCESS | 1653 | 09:38:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 09:38:39 INFO - PROCESS | 1653 | RSS: Main: 204587008 09:38:39 INFO - PROCESS | 1653 | 09:38:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 09:38:43 INFO - PROCESS | 1653 | RSS: Main: 204922880 09:38:43 INFO - PROCESS | 1653 | 09:38:44 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:38:45 INFO - PROCESS | 1653 | RSS: Main: 204120064 09:38:45 INFO - PROCESS | 1653 | 09:38:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:38:48 INFO - PROCESS | 1653 | RSS: Main: 204103680 09:38:48 INFO - PROCESS | 1653 | 09:38:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:38:50 INFO - PROCESS | 1653 | RSS: Main: 204124160 09:38:50 INFO - PROCESS | 1653 | 09:38:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:38:54 INFO - PROCESS | 1653 | RSS: Main: 204537856 09:38:54 INFO - PROCESS | 1653 | 09:38:57 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:38:58 INFO - PROCESS | 1653 | RSS: Main: 204292096 09:38:58 INFO - PROCESS | 1653 | 09:38:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 09:39:00 INFO - PROCESS | 1653 | RSS: Main: 206262272 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | __start_tp_report 09:39:00 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 09:39:00 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 09:39:00 INFO - PROCESS | 1653 | |i|pagename|runs| 09:39:00 INFO - PROCESS | 1653 | |0;ai-astar;112;121;150;120;121;124;121;131;116;109 09:39:00 INFO - PROCESS | 1653 | |1;audio-beat-detection;153;141;144;148;110;146;138;141;137;115 09:39:00 INFO - PROCESS | 1653 | |2;audio-dft;191;196;197;201;198;200;196;196;207;233 09:39:00 INFO - PROCESS | 1653 | |3;audio-fft;84;59;61;92;63;61;60;61;74;59 09:39:00 INFO - PROCESS | 1653 | |4;audio-oscillator;85;70;85;74;72;71;106;102;93;83 09:39:00 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;89;104;110;89;115;93;93;119;89;90 09:39:00 INFO - PROCESS | 1653 | |6;imaging-darkroom;96;97;97;96;98;95;96;121;104;105 09:39:00 INFO - PROCESS | 1653 | |7;imaging-desaturate;90;95;116;111;92;91;92;91;81;144 09:39:00 INFO - PROCESS | 1653 | |8;json-parse-financial;66;65;65;70;70;87;60;65;63;86 09:39:00 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;67;88;87;100;67;60;58;59;68;55 09:39:00 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;80;80;79;83;81;88;93;99;92;101 09:39:00 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;134;148;142;150;149;156;170;165;207;160 09:39:00 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;220;159;204;219;167;167;171;162;224;166 09:39:00 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;67;66;65;66;65;65;68;85;83;71 09:39:00 INFO - PROCESS | 1653 | __end_tp_report 09:39:00 INFO - PROCESS | 1653 | __start_cc_report 09:39:00 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,997 09:39:00 INFO - PROCESS | 1653 | __end_cc_report 09:39:00 INFO - PROCESS | 1653 | __startTimestamp1492792740239__endTimestamp 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | ------- Summary: start ------- 09:39:00 INFO - PROCESS | 1653 | Number of tests: 14 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:122.50 Median:121.00 stddev:11.44 (9.5%) stddev-sans-first:11.49 09:39:00 INFO - PROCESS | 1653 | Values: 112.0 121.0 150.0 120.0 121.0 124.0 121.0 131.0 116.0 109.0 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:137.30 Median:142.50 stddev:13.95 (9.8%) stddev-sans-first:13.59 09:39:00 INFO - PROCESS | 1653 | Values: 153.0 141.0 144.0 148.0 110.0 146.0 138.0 141.0 137.0 115.0 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:201.50 Median:199.00 stddev:11.83 (5.9%) stddev-sans-first:11.92 09:39:00 INFO - PROCESS | 1653 | Values: 191.0 196.0 197.0 201.0 198.0 200.0 196.0 196.0 207.0 233.0 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:67.40 Median:62.00 stddev:11.84 (19.1%) stddev-sans-first:10.93 09:39:00 INFO - PROCESS | 1653 | Values: 84.0 59.0 61.0 92.0 63.0 61.0 60.0 61.0 74.0 59.0 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:78.50 stddev:12.91 (16.5%) stddev-sans-first:13.69 09:39:00 INFO - PROCESS | 1653 | Values: 85.0 70.0 85.0 74.0 72.0 71.0 106.0 102.0 93.0 83.0 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:99.10 Median:89.00 stddev:11.81 (13.3%) stddev-sans-first:11.95 09:39:00 INFO - PROCESS | 1653 | Values: 89.0 104.0 110.0 89.0 115.0 93.0 93.0 119.0 89.0 90.0 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:96.00 stddev:7.99 (8.3%) stddev-sans-first:8.31 09:39:00 INFO - PROCESS | 1653 | Values: 96.0 97.0 97.0 96.0 98.0 95.0 96.0 121.0 104.0 105.0 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:100.30 Median:91.00 stddev:18.52 (20.4%) stddev-sans-first:19.27 09:39:00 INFO - PROCESS | 1653 | Values: 90.0 95.0 116.0 111.0 92.0 91.0 92.0 91.0 81.0 144.0 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:69.70 Median:68.00 stddev:9.33 (13.7%) stddev-sans-first:9.80 09:39:00 INFO - PROCESS | 1653 | Values: 66.0 65.0 65.0 70.0 70.0 87.0 60.0 65.0 63.0 86.0 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:67.00 stddev:15.34 (22.9%) stddev-sans-first:16.20 09:39:00 INFO - PROCESS | 1653 | Values: 67.0 88.0 87.0 100.0 67.0 60.0 58.0 59.0 68.0 55.0 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:85.50 stddev:8.25 (9.6%) stddev-sans-first:8.28 09:39:00 INFO - PROCESS | 1653 | Values: 80.0 80.0 79.0 83.0 81.0 88.0 93.0 99.0 92.0 101.0 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:158.00 stddev:20.22 (12.8%) stddev-sans-first:19.47 09:39:00 INFO - PROCESS | 1653 | Values: 134.0 148.0 142.0 150.0 149.0 156.0 170.0 165.0 207.0 160.0 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.90 Median:187.50 stddev:27.21 (14.5%) stddev-sans-first:25.92 09:39:00 INFO - PROCESS | 1653 | Values: 220.0 159.0 204.0 219.0 167.0 167.0 171.0 162.0 224.0 166.0 09:39:00 INFO - PROCESS | 1653 | 09:39:00 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:67.50 stddev:7.56 (11.2%) stddev-sans-first:7.94 09:39:00 INFO - PROCESS | 1653 | Values: 67.0 66.0 65.0 66.0 65.0 65.0 68.0 85.0 83.0 71.0 09:39:00 INFO - PROCESS | 1653 | -------- Summary: end -------- 09:39:00 INFO - PROCESS | 1653 | 09:39:01 INFO - TEST-INFO | 1653: exit 0 09:39:01 INFO - TEST-OK | kraken | took 60529ms 09:39:01 INFO - SUITE-END | took 1176s 09:39:01 INFO - Completed test suite (00:19:36) 09:39:01 INFO - javascript benchmark 09:39:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7971.379593770068, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3003.5044217704076, 3440.569928758905, 3655.3603119454087, 3669.2111196092833, 3682.2819550187705, 6097.115918191859, 6172.629642221465, 6178.536609754269, 6201.503879689625, 6254.7498100075945, 5081.517255860696, 6137.785177518787, 6178.660173690445, 6222.782202622909, 6230.940740074187, 5646.096624540074, 5652.943470565303, 5665.66006039639, 5670.886582268352, 5732.0, 5687.2606561146995, 5698.401667824881, 5702.885942281152, 5717.656940583578, 5733.168690539869, 5485.0949593317055, 5694.658320500783, 5695.401982791809, 5706.087026075828, 5713.514351280146, 5526.364468086162, 5562.833115006556, 5566.832995010156, 5572.58205634579, 5572.693501857396, 5444.700541470217, 5581.944180558183, 5602.663840169603, 5611.859703507404, 5672.234248376499, 6194.628322300677, 6227.065940108974, 6251.187345645082, 6273.466755325803, 6286.088517165032, 5448.37343705471, 6256.812295631138, 6370.203724534434, 6602.1747281589805, 6668.299828518033, 6440.51696122788, 6536.77121300757, 6574.0, 6597.7031033603125, 6624.635645039521, 6251.0, 6275.843103922347, 6281.309056003836, 6282.120503129566, 6300.747970081191, 5594.216809646653, 6710.932890671128, 6850.760223392257, 6853.109095817512, 6871.415929646036, 6266.968665156645, 6295.181626388632, 6391.360863913665, 6405.19935008124, 6464.741410343628, 6668.499862510331, 6671.532992690465, 6683.598984060972, 6692.397684208445, 6709.261981182066, 6530.542861999709, 6532.902006469907, 6544.509161812788, 6547.639879806561, 6578.177727784027, 5948.167256584082, 6303.432691057735, 6318.905216421757, 6445.258795238514, 6455.935440645628, 4557.498675145731, 4653.627709783209, 5073.833018405788, 5361.463853614608, 5388.434214407431, 4665.206914824488, 4686.156491831435, 4702.388689470347, 4734.360861283682, 4763.118823017753, 6180.721867515883, 6301.960176570906, 6318.62088274705, 6352.269489008823, 6358.1734374531015, 6016.157737916695, 6228.6574238416415, 6339.0, 6575.309592492815, 6665.066890635315, 6315.526335524854, 6319.778807741707, 6338.714757835908, 6355.618662880242, 6365.554411191265, 5939.46544810969, 5940.940590594038, 5946.940530594725, 5963.552733544915, 5969.224000879858, 6722.731090756511, 6812.114425124702, 6842.384185423336, 6878.621675807879, 7001.824954376181, 6649.135612370361, 6668.899966500603, 6668.933310666831, 6674.499412543982, 6690.0, 6916.308369163044, 6949.270326615835, 6953.548019378724, 6969.233384327821, 6997.300269972962, 6314.873702526015, 6351.777687780906, 6427.6786160690845, 6562.868742625216, 6569.96715016441, 5411.350637923474, 6145.907811382744, 6594.043863639649, 6610.272869984394, 6611.603303801788, 6398.424141827258, 6411.679416029273, 6421.935780642134, 6427.742890284523, 6428.19647544057, 6267.717952692231, 6268.498520118471, 6303.936960630518, 6411.6153030818305, 6468.2561505427475, 6181.752729890934, 6320.367963203643, 6342.9682851587295, 6353.14232578596, 6358.936410635834, 6457.418832305115, 6488.091667166695, 6490.967545162244, 6506.0, 6510.088587597835, 6149.046897730859, 6293.842653933688, 6298.464630506275, 6350.7459701611415, 6392.0], "value": 6058.077397558373, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.7858338956789, 818.316705550865, 845.3997661660221, 992.6575331510626, 1001.4391940513318, 42772.57227427725, 42942.7852860736, 43042.92392690181, 43092.78453607731, 43170.35244471335, 41141.177176456455, 41627.0, 41681.166376672525, 41745.58254417455, 41760.37359439611, 8306.210909963544, 8463.873041904377, 11404.144689148305, 11706.53173873044, 11880.108991825604, 11497.252678575907, 11596.594119205829, 11602.535898564047, 11611.0, 11642.592509262178, 11607.071434285237, 11621.47703353351, 11655.825162622568, 11682.007029402512, 11689.649310520655, 9726.465044422554, 10838.891611083867, 11575.710607234802, 11587.942060289644, 11593.536258549648, 11361.602343917923, 11421.371824549646, 11429.771404571946, 11431.314121152758, 11431.885681143165, 36383.636163638286, 36481.63518364835, 36546.81726591376, 36567.35446904896, 36599.0, 3947.230290093404, 3950.2889479893606, 3980.7611543307494, 4005.35914253718, 4063.085805693725, 26926.326841828915, 27102.186934392, 27181.864090679617, 28511.429771404662, 28754.856225718737, 3222.96777032229, 3303.157694787838, 3310.35448087623, 3356.9496457552914, 3426.9657303426898, 372473.0, 375779.0, 432399.0, 520744.9999999962, 522797.0, 44972.77513612411, 45134.0, 45173.77413112913, 45272.54727452749, 45331.773341133085, 47017.76491117591, 47059.764701176275, 47165.764171178926, 47174.82062948454, 47277.76361118173, 14525.92737036308, 14607.853921460863, 14662.926685366505, 14693.338799754038, 14834.332455039548, 12598.118131730691, 12701.110922235539, 12758.0, 12785.680357991125, 12893.226406415644, 12022.218555794021, 12059.397030148457, 12233.69415764613, 12308.26150430959, 12332.0, 5675.829725108253, 5714.857128571819, 5724.427557244242, 5737.08206686928, 5737.913931291034, 1170.9531618735155, 1221.7617564574823, 1235.1539195650828, 1239.20690757915, 1253.4359538207661, 23022.07911683514, 24552.631710524358, 28551.580062672805, 28767.712322876923, 28956.27609309784, 1080.5515710980012, 1277.7316763479773, 1277.8594354620984, 1299.8375203099613, 1307.6861553227156, 12513.937430312972, 14745.926270368795, 14900.254987250593, 14974.700505989818, 15602.29789659468, 525342.0, 527990.0, 528522.0, 528646.0, 530174.0, 52179.0, 52240.0, 52263.21605175926, 52494.21258681123, 52527.47472525226, 1295.7667619828521, 1333.973320533584, 1471.448206922404, 1517.5674932644145, 1528.1136940574663, 22558.77441225567, 22707.205247816248, 27442.62786860609, 29351.93921636771, 29842.55236171416, 48960.02079958452, 49126.01747965092, 49402.0, 49438.75280623574, 49450.25824612562, 490386.0, 491637.0, 495254.0, 495447.0, 496627.0, 30740.846295768377, 30764.538531922488, 30788.846055769576, 31138.377232455678, 31158.532622010236, 39992.0, 40327.193456130124, 40605.984850378976, 40698.79650601728, 41395.0, 28269.858650706614, 28996.565051524667, 29145.271368215963, 29865.5520167202, 29913.551296730133, 28727.281817954718, 29206.853965730035, 29562.260943476584, 29567.852160739058, 29794.553081703358, 33101.33797324088, 33554.49668255027, 33833.66166338404, 34063.0, 34329.65670343264, 29319.827206911486, 29378.853105734335, 29434.411311773216, 29448.558271625516, 30239.941602043822, 17127.0, 22033.889830550746, 23154.1896033638, 23647.0, 23852.52294954126], "value": 19226.850192108155, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.56401867991906, 419.63282128137877, 441.4419159153694, 442.4292662465419, 444.19822220891297, 20340.18639254428, 21087.051082701277, 21974.780252197474, 22200.444988875286, 22339.77660223397, 22582.548349033008, 22961.4259643509, 22965.0, 23187.304380868558, 24272.0, 6712.664366781666, 6963.860722785541, 6987.0, 7009.929900701004, 7348.963255183743, 7607.581583012932, 7719.305262526372, 7734.651940662684, 7737.535747855119, 7740.993670822785, 7482.850342993137, 7678.347340476066, 7803.29770320671, 7803.3367163791145, 7848.136704962478, 7562.28158324961, 7722.884156737653, 7729.884051739228, 7780.688772449095, 7791.4935529190425, 7269.963650181769, 7481.177070522238, 7491.175970643225, 7521.360684341837, 7543.434242431785, 26822.865885670642, 26855.92576296946, 27345.726542734716, 27412.862935685192, 28025.01912433055, 2926.678065412803, 3037.6506701729363, 3040.878364865425, 3048.1008102609785, 3231.8868839590737, 15079.396824127021, 16343.203303539918, 16429.4986894002, 16839.41062062822, 16933.40733074349, 2109.1141720477435, 2143.153454385509, 2170.6852506386485, 2217.4456385903522, 2390.306811024801, 124560.0, 128046.0, 146942.0, 178958.10520947442, 184178.07910960494, 18832.623347532972, 20317.796822031887, 20937.37187884366, 21101.261455848973, 21137.154513819583, 31560.210994725316, 31567.368652626817, 31762.0, 32003.67996320007, 32237.51643725346, 10739.516721747541, 10948.507317170586, 11084.61203857861, 11160.553577856957, 11271.0, 9229.927098106462, 9241.815163696689, 9324.440533567871, 9524.523773811421, 9597.424154550748, 9701.0, 9883.30816842814, 9901.950490247647, 9978.550965206583, 10006.399616023062, 3583.713302935811, 4227.767472788964, 4244.745315281093, 4274.230638485103, 4345.217860785089, 826.3471857232839, 1031.3657100882904, 1064.2869277583882, 1101.6970333158285, 1126.9492872820742, 21379.25172618951, 21826.0, 21928.903554822515, 21968.670469942965, 21979.450513736963, 1111.3276467737064, 1112.8330750387345, 1135.9432028398712, 1136.2841409911703, 1137.70988397958, 11014.50434730439, 11215.60745373908, 11292.717682058013, 11294.94352528232, 11358.716032099099, 388367.0, 390516.0, 410811.0, 796458.0, 804442.0, 157191.42808571996, 163611.0, 165490.1725491389, 170521.0, 175448.0, 1297.5977446991274, 1309.214471317221, 1351.5472316773992, 1355.2275203134316, 1356.2472827580787, 19263.036848157255, 19373.225070997003, 19822.306219282254, 19871.00644967775, 19969.400917972485, 53141.0, 60738.4815379619, 62462.0, 67456.0, 67909.0, 88538.55730721305, 95536.52231738796, 96292.0, 96557.0344296548, 98648.5202721974, 11536.25014374063, 11571.768564628492, 11662.650120496399, 11669.649910502698, 11981.520739170335, 10132.290739648153, 11181.944090279496, 13232.801507977581, 13365.797078262614, 13520.59438216855, 11368.94315528417, 11396.487158078073, 11442.42787860587, 11593.362365070003, 11768.76462470763, 10230.335028223142, 10801.729956750829, 10832.566697332019, 11148.609798657008, 11259.268147570381, 11843.644690659294, 11853.466594003463, 11902.226355287225, 12122.878771212176, 12192.87807121953, 11846.703832404259, 11851.585194518151, 11881.524739010689, 12004.879951200377, 12163.2702037878, 11540.422978851191, 11565.942170289094, 11644.301341919512, 11719.296842189497, 11903.821442678194], "value": 12045.33487488311, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1444.6749481366687, 1580.9367625294988, 1644.671065786843, 1664.9750253746192, 1666.608382001029, 9973.451460169688, 10114.241431892618, 11349.262297950638, 11828.940855295723, 12101.334426606532, 9668.226541876651, 11654.359010254453, 11659.242149260292, 11662.82505762412, 11868.881311186886, 2357.8231632627535, 2388.844725092872, 2390.282915125464, 2390.8565486070806, 2396.269137912936, 2339.9532009359805, 2350.083467447697, 2360.7639236076425, 2363.7163540375177, 2364.172539611131, 2116.597846409185, 2333.6616190652344, 2341.3210169050953, 2445.608702607583, 2521.735217802132, 2470.9505809883703, 2478.8884500197446, 2485.540175067609, 2491.9377015574573, 2496.563101457247, 2391.880405979694, 2392.6650268962358, 2402.7356990731005, 2402.9639555406684, 2405.0980882169188, 11596.768064638743, 11687.240329378563, 11692.824607630891, 11709.53161873524, 11726.941365293203, 5931.228940237742, 6093.268807743099, 6131.080337949299, 6214.378562143795, 6227.1282020517165, 3728.272986767581, 3815.275097731422, 3861.092643228839, 3890.3191941410423, 3935.0359162005207, 1274.7195616964252, 1290.825738525306, 1305.9412326445333, 1326.9668258293523, 1336.40529964166, 15896.682066358608, 15902.60243493922, 15964.521064368088, 16088.919555402264, 16291.755623665655, 4821.397325334333, 4889.0, 4973.502649734997, 5016.046951079356, 5030.974845125751, 12234.265944043385, 12269.325187114617, 12269.631911042683, 12281.508739650313, 12284.754304914031, 6030.306514750772, 6115.4190351916495, 6122.836661034389, 6122.846928826726, 6144.2319710036245, 4712.363830882856, 4766.404199475066, 5494.642848214853, 5579.721013949286, 5608.635438696471, 3379.983100084533, 3953.347697629916, 4065.4511640928686, 4122.237386083584, 4125.50493940723, 1079.0342643334172, 1082.8429877667695, 1087.9564817407374, 1089.3572792052728, 1090.4220762995626, 980.4117529482254, 995.4176806568239, 1002.9899701003043, 1012.5747186181674, 1017.9134773544183, 6115.1744514490865, 6489.5781774184525, 6871.9656401717675, 6932.403834183991, 7117.217106118323, 923.8774888510492, 965.2663975378763, 981.8232718110665, 993.5578667492896, 1029.6550655530332, 3802.980985095057, 4370.694051416434, 4379.802908869108, 4450.821967121344, 4536.228841097021, 13471.865281347062, 13850.792238116437, 14405.207713575854, 14458.915581331443, 14634.487792927197, 13919.860801392058, 14004.159750414803, 14244.928775356057, 14266.500672476413, 14275.214863182417, 10415.270931034755, 10717.946410267743, 10722.303050301705, 10808.459577021118, 10813.297135686154, 6972.546784458994, 7000.19497757744, 7081.610511421921, 7090.4327653788605, 7101.431885449048, 12685.175463594836, 12851.971842252784, 13167.407466663832, 13186.736265274832, 13243.404046817725, 12820.679483013, 13015.349232538525, 13071.411786469442, 13151.408186631816, 13309.866901330863, 8351.039630442578, 8588.828223435621, 9461.574229159842, 9501.287403444907, 9604.423734575668, 11059.115270778479, 11080.113590912546, 11187.776244475228, 11201.879812018733, 11205.551777928791, 7734.7679569610755, 9222.907770922206, 9224.446533208029, 9238.260939124988, 9303.069693030642, 8701.303895688456, 8791.472511649321, 8835.24900383449, 8909.1536304051, 9011.369204155646, 8341.332693384393, 8357.37319701037, 8368.0, 8475.40672152968, 8486.490810551386, 6080.331163572092, 6165.198524191826, 6214.5960512566535, 6292.056191571118, 6329.082283068838, 6160.10678451631, 6178.7219575120125, 6227.813165605039, 6228.906566401417, 6371.331010244043], "value": 5328.422209607527, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.31851275218696, 365.6782031812007, 388.273927755098, 395.1385978566725, 404.3994667918141, 4536.682432229745, 4812.566868981795, 5313.362396512414, 5372.75822587984, 5488.396276409591, 4659.922567753216, 5184.8703782405455, 5246.37043554773, 5272.789088436468, 5308.390294599671, 3633.7819730816104, 3755.023693839594, 3792.886213413602, 4450.688451808359, 4465.789434230061, 3696.4455331700324, 3818.7517811342314, 4467.475876129993, 4504.414426124616, 4505.526919673436, 3837.846486140551, 4474.485434175062, 4489.349044388562, 4494.8876278092985, 4506.0, 3754.812259387047, 4193.245215861144, 4375.6937014409, 4493.281075027996, 4499.370088187657, 3520.700740437066, 3795.3168429682646, 4074.899777060207, 4081.8979525511804, 4118.629323360882, 4322.222000039991, 4683.484816670163, 5076.847694569132, 5126.743662816882, 5226.895462090775, 4417.955820441786, 4537.818487260505, 4800.783964721596, 5143.3313669223135, 5147.6911385317, 5554.777808887639, 5657.3494048184575, 5664.847358003932, 5672.574556908207, 5864.237649105589, 3966.5240171179644, 4372.234858899712, 4529.909401811979, 4596.161535385839, 4615.515370886043, 5309.840704778863, 5352.652077614982, 5741.971290143564, 6039.5772295939305, 6190.288116866573, 4060.7360521566, 4675.18184317743, 4737.336772851803, 4747.738874361943, 4761.40482439695, 4160.148733813424, 4164.7917604119675, 4691.7888695008805, 4811.350467686888, 4890.706557606555, 4086.137496379299, 5020.0, 5135.563477104488, 5137.460566640449, 5230.0, 4618.607418369476, 4665.836695715634, 4878.707277563358, 5663.0, 5710.829279997591, 1551.0306058713304, 1568.3021055630136, 1600.743880979061, 1610.8711303095959, 1622.9107399093164, 1115.2806439846254, 1207.852540086921, 1210.975780484403, 1213.969650758738, 1241.1064033895582, 5345.465453454701, 5356.196570514454, 5358.946410535923, 5362.104463589618, 5362.65142765719, 5347.839564813062, 5499.690130145348, 5877.471027607536, 5898.997170481029, 5954.672493012839, 4426.822927082881, 4775.856724298276, 4875.34182885313, 5100.668456550312, 5116.411612664591, 2544.122277814151, 2688.784897208219, 2703.648175912044, 2804.901828435995, 2910.6652734935756, 5222.97388513055, 5362.0, 5370.62405631602, 5415.620906536584, 6088.330283668793, 4755.789841856733, 5073.213651883964, 5214.800595862973, 5649.322081350264, 5996.2504686914135, 5298.841034768963, 5410.836670115866, 5416.647917885403, 5742.827715168468, 6210.633660594662, 4812.027068901733, 5131.79472821083, 5838.649681019152, 5880.47107751979, 6005.819825405245, 5035.949640503548, 5201.271821944855, 5307.787688492417, 5671.391160066416, 5753.9136912945505, 4926.827561035347, 5242.606804489755, 6227.750889964532, 6234.781782637586, 6236.314005459305, 4662.813487460464, 4686.797205313497, 4692.601128904082, 4718.363020992259, 4726.8109275628585, 5328.254044433705, 5579.53637090318, 5897.557683173865, 5904.527637789052, 5955.940440595712, 4909.091818013679, 5694.373618902, 5703.657780533182, 5728.742206600797, 5759.251297331488, 5086.949130508796, 5504.394516603251, 5697.800576979786, 5737.684427356368, 5738.741756620879, 5379.246905433164, 5460.3993560707495, 5475.0, 5536.889262214814, 5611.653203231113, 5224.7648855802345, 5286.947130528645, 5664.745086471036, 5698.544116470755, 5765.8558536036935, 4974.5771609413605, 5154.974225128851, 5278.76245568958, 5384.5423139032055, 5555.30558680165], "value": 4299.410988800654, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.266346970378, 798.9374132403904, 885.9069797671244, 889.5685592487646, 897.3001059173845, 15287.388504459823, 15890.205489725497, 15943.04341739495, 16935.66128677426, 18126.456206313833, 15876.96799708018, 16054.759178612301, 16199.757003644954, 16434.75347869783, 16440.42458513952, 994.1947022911429, 1006.4162785584343, 1007.9193664506822, 1008.1430783833756, 1008.5713571732022, 972.3873959405564, 1002.1882275356948, 1011.4083261292116, 1012.0739523336139, 1012.964546240878, 1066.109798318405, 1066.3655125200494, 1067.2902519824308, 1068.102793653331, 1071.3946620159625, 865.9740207793744, 1057.4818339013905, 1057.740853490892, 1057.793730222603, 1059.7403636109202, 787.3032366355759, 787.52354825331, 787.5629025890628, 787.8857565653005, 788.6687591211705, 16848.57878553034, 17120.0, 17243.482695519153, 18122.184501697455, 18335.54161145969, 14579.562613121623, 14764.778528322084, 14829.332680029425, 14938.775918361232, 15136.091834489966, 10389.688309350731, 11977.92198702121, 11997.700057498545, 12169.14815962883, 12762.808557871547, 3406.6593340665736, 3830.291396091704, 3853.2486165197797, 3955.0705584187695, 4048.6356227939627, 115589.0, 118348.0, 120178.39910800477, 120792.0, 122543.16185257139, 11387.19239262343, 11459.541618335339, 11608.129390295593, 11732.413379331, 11913.16607837443, 23547.41131471727, 23718.0, 24343.0, 24364.87817560901, 24367.903444345047, 7159.821004474929, 8141.959290203511, 8334.49993000422, 8361.790955226168, 8410.495370277802, 4606.677532572755, 4611.354410382549, 4668.603168730628, 4686.461056978423, 4808.2547205183255, 3670.981645091811, 3727.2731817295366, 3762.059485128718, 3773.52830896138, 3778.0554861284677, 658.2430205263985, 664.4252721396055, 669.9564528305742, 690.3131384272681, 701.3757755597522, 724.4566575068699, 750.9586972716553, 754.3135746470685, 779.1701837542998, 782.8121250899743, 9028.8645670315, 10931.945340273247, 10981.402946156837, 11024.779504409868, 11053.557857685764, 723.6743465440468, 744.6537360127459, 747.5514691185246, 749.606456610284, 752.4018405367746, 3987.7208595398624, 5143.614228932845, 5195.272661827347, 5255.08036093682, 5301.893962120736, 83450.58274708588, 102251.0, 106540.0, 106924.93075069305, 106928.0, 57906.131408028916, 72179.0, 72407.0, 73294.90057649139, 87113.69329460063, 61438.38561614326, 74303.62848185725, 74728.6573787213, 77958.83061754078, 79708.60145699234, 10905.945470272598, 11399.145064120059, 11509.712257193638, 11585.362805045466, 11609.767804644029, 44661.776691116334, 49774.75112624414, 52003.21995170151, 52135.739321303154, 53118.46881531135, 53564.46435535595, 67396.0, 67854.66072669605, 68160.31839681539, 68741.65629171822, 13164.40760165814, 13202.207867527595, 13268.668283292995, 13464.0, 13601.523946661819, 14993.175375354023, 15074.623134421727, 15202.847971520143, 15362.0, 15401.84598154049, 13932.30338483092, 14372.0, 14396.496122635657, 14565.92717036408, 14600.12399256048, 13716.24560649134, 14411.85588144147, 14433.35049922777, 14661.560153195422, 14663.048349840437, 13341.933290333487, 13921.930390347983, 13974.930125349309, 14263.928680356532, 14518.201498917238, 10285.228607854591, 10596.8940310599, 10636.607261833866, 10712.73218169552, 11046.447677615926, 9726.465044422624, 9897.65358212488, 10310.845337320095, 11725.941370293094, 11739.413029348669], "value": 7981.621929941946, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 121.0, 150.0, 120.0, 121.0, 124.0, 121.0, 131.0, 116.0, 109.0], "value": 122.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 141.0, 144.0, 148.0, 110.0, 146.0, 138.0, 141.0, 137.0, 115.0], "value": 137.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 196.0, 197.0, 201.0, 198.0, 200.0, 196.0, 196.0, 207.0, 233.0], "value": 201.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 61.0, 92.0, 63.0, 61.0, 60.0, 61.0, 74.0, 59.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 85.0, 74.0, 72.0, 71.0, 106.0, 102.0, 93.0, 83.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 104.0, 110.0, 89.0, 115.0, 93.0, 93.0, 119.0, 89.0, 90.0], "value": 99.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 96.0, 98.0, 95.0, 96.0, 121.0, 104.0, 105.0], "value": 100.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 116.0, 111.0, 92.0, 91.0, 92.0, 91.0, 81.0, 144.0], "value": 100.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 70.0, 70.0, 87.0, 60.0, 65.0, 63.0, 86.0], "value": 69.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 88.0, 87.0, 100.0, 67.0, 60.0, 58.0, 59.0, 68.0, 55.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 83.0, 81.0, 88.0, 93.0, 99.0, 92.0, 101.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 148.0, 142.0, 150.0, 149.0, 156.0, 170.0, 165.0, 207.0, 160.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 159.0, 204.0, 219.0, 167.0, 167.0, 171.0, 162.0, 224.0, 166.0], "value": 185.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 65.0, 66.0, 65.0, 65.0, 68.0, 85.0, 83.0, 71.0], "value": 70.1, "unit": "score"}]}]} 09:39:01 INFO - Return code: 0 09:39:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:39:01 ERROR - # TBPL SUCCESS # 09:39:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:39:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:39:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:39:01 INFO - 2017-04-21 09:39:01.551 screenresolution[1658:10494] starting screenresolution argv=screenresolution get 09:39:01 INFO - 2017-04-21 09:39:01.554 screenresolution[1658:10494] Display 0: 1600x1200x32@60 09:39:01 INFO - 2017-04-21 09:39:01.560 screenresolution[1659:10497] starting screenresolution argv=screenresolution list 09:39:01 INFO - Available Modes on Display 0 09:39:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:39:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:39:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:39:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:39:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:39:01 INFO - Intel Iris: 09:39:01 INFO - Chipset Model: Intel Iris 09:39:01 INFO - Type: GPU 09:39:01 INFO - Bus: Built-In 09:39:01 INFO - VRAM (Dynamic, Max): 1536 MB 09:39:01 INFO - Vendor: Intel (0x8086) 09:39:01 INFO - Device ID: 0x0a2e 09:39:01 INFO - Revision ID: 0x0009 09:39:01 INFO - Displays: 09:39:01 INFO - Display: 09:39:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:39:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:39:01 INFO - Display Serial Number: 1600x1200 60 09:39:01 INFO - Main Display: Yes 09:39:01 INFO - Mirror: Off 09:39:01 INFO - Online: Yes 09:39:01 INFO - Rotation: Supported 09:39:01 INFO - Return code: 0 09:39:01 INFO - Running post-action listener: _resource_record_post_action 09:39:01 INFO - [mozharness: 2017-04-21 16:39:01.800820Z] Finished run-tests step (success) 09:39:01 INFO - Running post-run listener: _resource_record_post_run 09:39:01 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 39516160; Write bytes: 426142208; Read time: 253; Write time: 1425 09:39:01 INFO - TinderboxPrint: CPU usage
54.3% 09:39:01 INFO - TinderboxPrint: I/O read bytes / time
39,516,160 / 253 09:39:01 INFO - TinderboxPrint: I/O write bytes / time
426,142,208 / 1,425 09:39:01 INFO - TinderboxPrint: CPU idle
2,177.4 (45.7%) 09:39:01 INFO - TinderboxPrint: CPU system
954.9 (20.0%) 09:39:01 INFO - TinderboxPrint: CPU user
1,637.3 (34.3%) 09:39:01 INFO - TinderboxPrint: Swap in / out
161,808,384 / 0 09:39:01 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123107840; Write bytes: 125031936; Read time: 11159; Write time: 279 09:39:01 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35567616; Write bytes: 299725824; Read time: 226; Write time: 1141 09:39:02 INFO - Running post-run listener: _upload_blobber_files 09:39:02 INFO - Blob upload gear active. 09:39:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:39:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:39:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:39:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:39:02 INFO - (blobuploader) - INFO - Open directory for files ... 09:39:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:39:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:39:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:04 INFO - (blobuploader) - INFO - Done attempting. 09:39:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:39:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:39:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:06 INFO - (blobuploader) - INFO - Done attempting. 09:39:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:39:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:39:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:07 INFO - (blobuploader) - INFO - Done attempting. 09:39:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:39:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:39:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:10 INFO - (blobuploader) - INFO - Done attempting. 09:39:10 INFO - (blobuploader) - INFO - Iteration through files over. 09:39:10 INFO - Return code: 0 09:39:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:39:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:39:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/53eba90ee90fb119732f450d93f59ff121591147c498f95c947008e87a4667d13935c5ee69670af304f541d21939b4c8391764a3696a0b64cf146f5effe9861b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3f9e0bc4665bf5a692d2de97a116aaf0361190f4a48c9da9efe71eb0afaff583495c502c5327af0a3c49be23a7e95e42dbafbadba27c38c62af27a76a0d75aa7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5523db6600dc0bad5c1097219a2a9dd303e33939f4d0f6048adc664d0fa1e5ff1999c74e13a231414801a87bcc48c656993f3f633e962ee889ef88bfabaf59f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/41c80f19eb82306651f7851d82cd2469ccd32244a40922d688580a0cc015bc8d5d62b242723237cda8696ebf4f8ddba293f9ba7af92499f3bf5bbc530c0fc8b6"} 09:39:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:39:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:39:10 INFO - Contents: 09:39:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/53eba90ee90fb119732f450d93f59ff121591147c498f95c947008e87a4667d13935c5ee69670af304f541d21939b4c8391764a3696a0b64cf146f5effe9861b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3f9e0bc4665bf5a692d2de97a116aaf0361190f4a48c9da9efe71eb0afaff583495c502c5327af0a3c49be23a7e95e42dbafbadba27c38c62af27a76a0d75aa7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5523db6600dc0bad5c1097219a2a9dd303e33939f4d0f6048adc664d0fa1e5ff1999c74e13a231414801a87bcc48c656993f3f633e962ee889ef88bfabaf59f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/41c80f19eb82306651f7851d82cd2469ccd32244a40922d688580a0cc015bc8d5d62b242723237cda8696ebf4f8ddba293f9ba7af92499f3bf5bbc530c0fc8b6"} 09:39:10 INFO - Running post-run listener: copy_logs_to_upload_dir 09:39:10 INFO - Copying logs to upload dir... 09:39:10 INFO - mkdir: /builds/slave/test/build/upload/logs 09:39:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.650806 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-04-21 09:39:10.350865) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-21 09:39:10.354302) ========= 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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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/53eba90ee90fb119732f450d93f59ff121591147c498f95c947008e87a4667d13935c5ee69670af304f541d21939b4c8391764a3696a0b64cf146f5effe9861b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3f9e0bc4665bf5a692d2de97a116aaf0361190f4a48c9da9efe71eb0afaff583495c502c5327af0a3c49be23a7e95e42dbafbadba27c38c62af27a76a0d75aa7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5523db6600dc0bad5c1097219a2a9dd303e33939f4d0f6048adc664d0fa1e5ff1999c74e13a231414801a87bcc48c656993f3f633e962ee889ef88bfabaf59f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/41c80f19eb82306651f7851d82cd2469ccd32244a40922d688580a0cc015bc8d5d62b242723237cda8696ebf4f8ddba293f9ba7af92499f3bf5bbc530c0fc8b6"} build_url:https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011323 build_url: 'https://queue.taskcluster.net/v1/task/RYzOCbBlSPWbBOS9iMOw3w/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/53eba90ee90fb119732f450d93f59ff121591147c498f95c947008e87a4667d13935c5ee69670af304f541d21939b4c8391764a3696a0b64cf146f5effe9861b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3f9e0bc4665bf5a692d2de97a116aaf0361190f4a48c9da9efe71eb0afaff583495c502c5327af0a3c49be23a7e95e42dbafbadba27c38c62af27a76a0d75aa7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5523db6600dc0bad5c1097219a2a9dd303e33939f4d0f6048adc664d0fa1e5ff1999c74e13a231414801a87bcc48c656993f3f633e962ee889ef88bfabaf59f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/41c80f19eb82306651f7851d82cd2469ccd32244a40922d688580a0cc015bc8d5d62b242723237cda8696ebf4f8ddba293f9ba7af92499f3bf5bbc530c0fc8b6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-21 09:39:10.389699) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 09:39:10.390031) ========= 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.Ji5OiSiWyM/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.83vNMYxCIS/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.005059 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 09:39:10.439124) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-21 09:39:10.439430) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-21 09:39:10.439787) ========= ========= Total master_lag: 0.18 =========