builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0261 starttime: 1491220128.08 results: success (0) buildid: 20170403030207 builduid: 0a78a9416f614dc2bd8538e21f3f728d revision: 38894655c89e68bcd8f45d31a0d3005f2c2b53db ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:48.078329) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:48.078946) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:48.079272) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:48.107829) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:48.108314) ========= 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.MwYa7un3Wu/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.LIk796cxLS/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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-03 04:48:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.7M=0s 2017-04-03 04:48:48 (28.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113812 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:48.246922) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:48.247297) ========= 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.MwYa7un3Wu/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.LIk796cxLS/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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.027320 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:48.297945) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-03 04:48:48.298352) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 38894655c89e68bcd8f45d31a0d3005f2c2b53db --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 38894655c89e68bcd8f45d31a0d3005f2c2b53db --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MwYa7un3Wu/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.LIk796cxLS/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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-03 04:48:48,737 truncating revision to first 12 chars 2017-04-03 04:48:48,737 Setting DEBUG logging. 2017-04-03 04:48:48,738 attempt 1/10 2017-04-03 04:48:48,738 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/38894655c89e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-03 04:48:50,395 unpacking tar archive at: mozilla-central-38894655c89e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.940714 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-03 04:48:50.262322) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:50.262698) ========= script_repo_revision: 38894655c89e68bcd8f45d31a0d3005f2c2b53db ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:50.263163) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:50.263470) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:50.283690) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-03 04:48:50.284159) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MwYa7un3Wu/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.LIk796cxLS/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 04:48:50 INFO - MultiFileLogger online at 20170403 04:48:50 in /builds/slave/test 04:48:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:48:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:48:50 INFO - {'append_to_log': False, 04:48:50 INFO - 'base_work_dir': '/builds/slave/test', 04:48:50 INFO - 'blob_upload_branch': 'Firefox', 04:48:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:48:50 INFO - 'branch': 'Firefox', 04:48:50 INFO - 'buildbot_json_path': 'buildprops.json', 04:48:50 INFO - 'config_files': ('talos/mac_config.py',), 04:48:50 INFO - 'default_actions': ('clobber', 04:48:50 INFO - 'read-buildbot-config', 04:48:50 INFO - 'download-and-extract', 04:48:50 INFO - 'populate-webroot', 04:48:50 INFO - 'create-virtualenv', 04:48:50 INFO - 'install', 04:48:50 INFO - 'run-tests'), 04:48:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:48:50 INFO - 'download_minidump_stackwalk': True, 04:48:50 INFO - 'download_symbols': 'ondemand', 04:48:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:48:50 INFO - 'tooltool.py': '/tools/tooltool.py', 04:48:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:48:50 INFO - '/tools/misc-python/virtualenv.py')}, 04:48:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:48:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:48:50 INFO - 'gecko_profile': False, 04:48:50 INFO - 'gecko_profile_interval': 0, 04:48:50 INFO - 'installer_path': 'installer.exe', 04:48:50 INFO - 'log_level': 'info', 04:48:50 INFO - 'log_name': 'talos', 04:48:50 INFO - 'log_to_console': True, 04:48:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:48:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:48:50 INFO - 'opt_config_files': (), 04:48:50 INFO - 'pip_index': False, 04:48:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:48:50 INFO - 'cmd': ('bash', 04:48:50 INFO - '-c', 04:48:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:48:50 INFO - 'enabled': True, 04:48:50 INFO - 'halt_on_failure': False, 04:48:50 INFO - 'name': 'check_screen_resolution'},), 04:48:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:48:50 INFO - 'cmd': ('bash', 04:48:50 INFO - '-c', 04:48:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:48:50 INFO - 'enabled': True, 04:48:50 INFO - 'halt_on_failure': False, 04:48:50 INFO - 'name': 'check_screen_resolution'},), 04:48:50 INFO - 'run_cmd_checks_enabled': True, 04:48:50 INFO - 'suite': 'dromaeojs-e10s', 04:48:50 INFO - 'system_bits': '32', 04:48:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:48:50 INFO - 'title': 't-yosemite-r7-0261', 04:48:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:48:50 INFO - 'use_talos_json': True, 04:48:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:48:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:48:50 INFO - 'work_dir': 'build'} 04:48:50 INFO - [mozharness: 2017-04-03 11:48:50.843745Z] Running clobber step. 04:48:50 INFO - Running pre-action listener: _resource_record_pre_action 04:48:50 INFO - Running main action method: clobber 04:48:50 INFO - rmtree: /builds/slave/test/build 04:48:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:48:53 INFO - Running post-action listener: _resource_record_post_action 04:48:53 INFO - [mozharness: 2017-04-03 11:48:53.215792Z] Finished clobber step (success) 04:48:53 INFO - [mozharness: 2017-04-03 11:48:53.215923Z] Running read-buildbot-config step. 04:48:53 INFO - Running pre-action listener: _resource_record_pre_action 04:48:53 INFO - Running main action method: read_buildbot_config 04:48:53 INFO - Using buildbot properties: 04:48:53 INFO - { 04:48:53 INFO - "project": "", 04:48:53 INFO - "product": "firefox", 04:48:53 INFO - "script_repo_revision": "production", 04:48:53 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:48:53 INFO - "repository": "", 04:48:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:48:53 INFO - "buildid": "20170403030207", 04:48:53 INFO - "stage_platform": "macosx64", 04:48:53 INFO - "basedir": "/builds/slave/test", 04:48:53 INFO - "nightly_build": "True", 04:48:53 INFO - "buildnumber": 17, 04:48:53 INFO - "slavename": "t-yosemite-r7-0261", 04:48:53 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:48:53 INFO - "platform": "yosemite_r7", 04:48:53 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:48:53 INFO - "branch": "mozilla-central", 04:48:53 INFO - "repo_path": "mozilla-central", 04:48:53 INFO - "revision": "38894655c89e68bcd8f45d31a0d3005f2c2b53db", 04:48:53 INFO - "pgo_build": "False", 04:48:53 INFO - "builduid": "0a78a9416f614dc2bd8538e21f3f728d", 04:48:53 INFO - "slavebuilddir": "test" 04:48:53 INFO - } 04:48:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:48:53 INFO - Running post-action listener: _resource_record_post_action 04:48:53 INFO - [mozharness: 2017-04-03 11:48:53.219070Z] Finished read-buildbot-config step (success) 04:48:53 INFO - [mozharness: 2017-04-03 11:48:53.219213Z] Running download-and-extract step. 04:48:53 INFO - Running pre-action listener: _resource_record_pre_action 04:48:53 INFO - Running main action method: download_and_extract 04:48:53 INFO - mkdir: /builds/slave/test/build/tests 04:48: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')]} 04:48:53 INFO - trying https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:48:53 INFO - Downloading https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:48:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 04:48:54 INFO - Downloaded 1325 bytes. 04:48:54 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:48:54 INFO - Using the following test package requirements: 04:48:54 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:54 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:48:54 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:48:54 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:54 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:48:54 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:54 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:48:54 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:54 INFO - u'jsshell-mac.zip'], 04:48:54 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:54 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:48:54 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:48:54 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:54 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:48:54 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:54 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:48:54 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:54 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:48:54 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:54 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:48:54 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:48:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:48:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:48:54 INFO - Fetch https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:48:56 INFO - Expected file size: 52516287 04:48:56 INFO - Obtained file size: 52516287 04:49:12 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:49:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:49:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:49:12 INFO - Fetch https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:49:14 INFO - Expected file size: 52516287 04:49:14 INFO - Obtained file size: 52516287 04:49:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:49:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:49:30 INFO - Fetch https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:49:31 INFO - Expected file size: 14404152 04:49:31 INFO - Obtained file size: 14404152 04:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:32 INFO - trying https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:49:32 INFO - Downloading https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:49:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 04:49:34 INFO - Downloaded 75641450 bytes. 04:49:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:49:34 INFO - mkdir: /builds/slave/test/properties 04:49:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:49:34 INFO - Writing to file /builds/slave/test/properties/build_url 04:49:34 INFO - Contents: 04:49:34 INFO - build_url:https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:49:34 INFO - Running post-action listener: _resource_record_post_action 04:49:34 INFO - Running post-action listener: set_extra_try_arguments 04:49:34 INFO - [mozharness: 2017-04-03 11:49:34.855955Z] Finished download-and-extract step (success) 04:49:34 INFO - [mozharness: 2017-04-03 11:49:34.856104Z] Running populate-webroot step. 04:49:34 INFO - Running pre-action listener: _resource_record_pre_action 04:49:34 INFO - Running main action method: populate_webroot 04:49:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:49:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:49:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:49:34 INFO - '--tppagecycles', 04:49:34 INFO - '7'], 04:49:34 INFO - 'tests': ['tsvgm']}}, 04:49:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:49:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:49:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:49:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:49:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:49:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:49:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:49:34 INFO - 'talos_options': ['--disable-e10s'], 04:49:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:49:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:49:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:49:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:49:34 INFO - 'talos_options': ['--disable-e10s'], 04:49:34 INFO - 'tests': ['damp', 'tps']}, 04:49:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:49:34 INFO - 'tests': ['damp', 'tps']}, 04:49:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:49:34 INFO - 'tests': ['dromaeo_dom']}, 04:49:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:49:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:49:34 INFO - 'tests': ['basic_compositor_video']}, 04:49:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:49:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:49:34 INFO - 'tests': ['a11yr', 04:49:34 INFO - 'ts_paint', 04:49:34 INFO - 'tpaint', 04:49:34 INFO - 'sessionrestore', 04:49:34 INFO - 'sessionrestore_no_auto_restore', 04:49:34 INFO - 'tabpaint']}, 04:49:34 INFO - 'other-e10s': {'tests': ['a11yr', 04:49:34 INFO - 'ts_paint', 04:49:34 INFO - 'tpaint', 04:49:34 INFO - 'sessionrestore', 04:49:34 INFO - 'sessionrestore_no_auto_restore', 04:49:34 INFO - 'tabpaint']}, 04:49:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:49:34 INFO - 'ts_paint', 04:49:34 INFO - 'tpaint', 04:49:34 INFO - 'sessionrestore', 04:49:34 INFO - 'sessionrestore_no_auto_restore', 04:49:34 INFO - 'tabpaint']}, 04:49:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:49:34 INFO - 'ts_paint', 04:49:34 INFO - 'tpaint', 04:49:34 INFO - 'sessionrestore', 04:49:34 INFO - 'sessionrestore_no_auto_restore', 04:49:34 INFO - 'tabpaint']}, 04:49:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:49:34 INFO - 'tests': ['a11yr', 04:49:34 INFO - 'ts_paint', 04:49:34 INFO - 'tpaint', 04:49:34 INFO - 'sessionrestore', 04:49:34 INFO - 'sessionrestore_no_auto_restore', 04:49:34 INFO - 'tabpaint']}, 04:49:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:49:34 INFO - 'tests': ['a11yr', 04:49:34 INFO - 'ts_paint', 04:49:34 INFO - 'tpaint', 04:49:34 INFO - 'sessionrestore', 04:49:34 INFO - 'sessionrestore_no_auto_restore', 04:49:34 INFO - 'tabpaint']}, 04:49:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:49:34 INFO - 'tests': ['tsvgx', 04:49:34 INFO - 'tsvgr_opacity', 04:49:34 INFO - 'tart', 04:49:34 INFO - 'tscrollx', 04:49:34 INFO - 'cart', 04:49:34 INFO - 'tsvg_static']}, 04:49:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:49:34 INFO - 'tsvgr_opacity', 04:49:34 INFO - 'tart', 04:49:34 INFO - 'tscrollx', 04:49:34 INFO - 'cart', 04:49:34 INFO - 'tsvg_static']}, 04:49:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:49:34 INFO - 'talos_options': ['--disable-e10s'], 04:49:34 INFO - 'tests': ['tp5o']}, 04:49:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:49:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:49:34 INFO - 'talos_options': ['--disable-e10s', 04:49:34 INFO - '--xperf_path', 04:49:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:49:34 INFO - 'tests': ['tp5n']}, 04:49:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:49:34 INFO - 'talos_options': ['--xperf_path', 04:49:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:49:34 INFO - 'tests': ['tp5n']}}, 04:49:34 INFO - 'talos.zip': {'path': '', 04:49:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:49:34 INFO - Running post-action listener: _resource_record_post_action 04:49:34 INFO - [mozharness: 2017-04-03 11:49:34.874751Z] Finished populate-webroot step (success) 04:49:34 INFO - [mozharness: 2017-04-03 11:49:34.874896Z] Running create-virtualenv step. 04:49:34 INFO - Running pre-action listener: _resource_record_pre_action 04:49:34 INFO - Running main action method: create_virtualenv 04:49:34 INFO - Creating virtualenv /builds/slave/test/build/venv 04:49:34 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 04:49:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:49:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:49:35 INFO - Using real prefix '/tools/python27' 04:49:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:49:35 INFO - Installing distribute.............................................................................................................................................................................................done. 04:49:38 INFO - Installing pip.................done. 04:49:38 INFO - Return code: 0 04:49:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:49:38 INFO - Reading from file tmpfile_stdout 04:49:38 INFO - Output received: 04:49:38 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) 04:49:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7055c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b48d30>, '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 0x106a6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b3dbe8>, '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.MwYa7un3Wu/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.LIk796cxLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 04:49:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MwYa7un3Wu/Render', 04:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:38 INFO - 'HOME': '/Users/cltbld', 04:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:38 INFO - 'LOGNAME': 'cltbld', 04:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:38 INFO - 'MOZ_NO_REMOTE': '1', 04:49:38 INFO - 'NO_EM_RESTART': '1', 04:49:38 INFO - 'PAGER': '/bin/cat', 04:49:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:38 INFO - 'PWD': '/builds/slave/test', 04:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:38 INFO - 'SHELL': '/bin/bash', 04:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LIk796cxLS/Listeners', 04:49:38 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 04:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:38 INFO - 'USER': 'cltbld', 04:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:38 INFO - 'XPC_FLAGS': '0x0', 04:49:38 INFO - 'XPC_SERVICE_NAME': '0', 04:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:39 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 04:49:39 INFO - Cleaning up... 04:49:39 INFO - Return code: 0 04:49:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:49: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')]} 04:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49: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')]} 04:49:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49: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', '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 0x106d6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7055c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b48d30>, '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 0x106a6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b3dbe8>, '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.MwYa7un3Wu/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.LIk796cxLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 04:49: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', 'psutil>=3.1.1'] in /builds/slave/test/build 04:49: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 psutil>=3.1.1 04:49:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MwYa7un3Wu/Render', 04:49:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:39 INFO - 'HOME': '/Users/cltbld', 04:49:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:39 INFO - 'LOGNAME': 'cltbld', 04:49:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:39 INFO - 'MOZ_NO_REMOTE': '1', 04:49:39 INFO - 'NO_EM_RESTART': '1', 04:49:39 INFO - 'PAGER': '/bin/cat', 04:49:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:39 INFO - 'PWD': '/builds/slave/test', 04:49:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:39 INFO - 'SHELL': '/bin/bash', 04:49:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LIk796cxLS/Listeners', 04:49:39 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 04:49:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:39 INFO - 'USER': 'cltbld', 04:49:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:39 INFO - 'XPC_FLAGS': '0x0', 04:49:39 INFO - 'XPC_SERVICE_NAME': '0', 04:49:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:39 INFO - Downloading/unpacking psutil>=3.1.1 04:49:39 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 04:49:39 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 04:49:39 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 04:49:39 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 04:49:39 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 04:49:39 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 04:49:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:49:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:49:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:49:42 INFO - Installing collected packages: psutil 04:49:42 INFO - Running setup.py install for psutil 04:49:43 INFO - building 'psutil._psutil_osx' extension 04:49: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 04:49: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:49: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:49:43 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 04:49:43 INFO - building 'psutil._psutil_posix' extension 04:49:43 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 04:49:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:49:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:49:43 INFO - ^ 04:49:43 INFO - 1 warning generated. 04:49:43 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 04:49:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:49:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:49:44 INFO - Successfully installed psutil 04:49:44 INFO - Cleaning up... 04:49:44 INFO - Return code: 0 04:49:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:49: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')]} 04:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49: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')]} 04:49:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49: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 0x106d6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7055c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b48d30>, '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 0x106a6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b3dbe8>, '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.MwYa7un3Wu/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.LIk796cxLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 04:49: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 04:49: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 04:49:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MwYa7un3Wu/Render', 04:49:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:44 INFO - 'HOME': '/Users/cltbld', 04:49:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:44 INFO - 'LOGNAME': 'cltbld', 04:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:44 INFO - 'MOZ_NO_REMOTE': '1', 04:49:44 INFO - 'NO_EM_RESTART': '1', 04:49:44 INFO - 'PAGER': '/bin/cat', 04:49:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:44 INFO - 'PWD': '/builds/slave/test', 04:49:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:44 INFO - 'SHELL': '/bin/bash', 04:49:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LIk796cxLS/Listeners', 04:49:44 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 04:49:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:44 INFO - 'USER': 'cltbld', 04:49:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:44 INFO - 'XPC_FLAGS': '0x0', 04:49:44 INFO - 'XPC_SERVICE_NAME': '0', 04:49:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:49:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:49:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:49:47 INFO - Installing collected packages: mozsystemmonitor 04:49:47 INFO - Running setup.py install for mozsystemmonitor 04:49:48 INFO - Successfully installed mozsystemmonitor 04:49:48 INFO - Cleaning up... 04:49:48 INFO - Return code: 0 04:49:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:49: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')]} 04:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49: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')]} 04:49:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49: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 0x106d6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7055c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b48d30>, '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 0x106a6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b3dbe8>, '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.MwYa7un3Wu/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.LIk796cxLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 04:49: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 04:49: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 04:49:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MwYa7un3Wu/Render', 04:49:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:48 INFO - 'HOME': '/Users/cltbld', 04:49:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:48 INFO - 'LOGNAME': 'cltbld', 04:49:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:48 INFO - 'MOZ_NO_REMOTE': '1', 04:49:48 INFO - 'NO_EM_RESTART': '1', 04:49:48 INFO - 'PAGER': '/bin/cat', 04:49:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:48 INFO - 'PWD': '/builds/slave/test', 04:49:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:48 INFO - 'SHELL': '/bin/bash', 04:49:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LIk796cxLS/Listeners', 04:49:48 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 04:49:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:48 INFO - 'USER': 'cltbld', 04:49:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:48 INFO - 'XPC_FLAGS': '0x0', 04:49:48 INFO - 'XPC_SERVICE_NAME': '0', 04:49:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:48 INFO - Downloading/unpacking jsonschema==2.5.1 04:49:48 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 04:49:48 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 04:49:48 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 04:49:48 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 04:49:48 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 04:49:48 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 04:49:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:49:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:49:51 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 04:49:51 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 04:49:51 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 04:49:51 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 04:49:51 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 04:49:51 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 04:49:51 INFO - Downloading functools32-3.2.3-2.tar.gz 04:49:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:49:51 INFO - warning: no files found matching '*.txt' 04:49:51 INFO - no previously-included directories found matching 'build' 04:49:51 INFO - no previously-included directories found matching 'dist' 04:49:51 INFO - no previously-included directories found matching '.git*' 04:49:51 INFO - Installing collected packages: jsonschema, functools32 04:49:52 INFO - Running setup.py install for functools32 04:49:52 INFO - warning: no files found matching '*.txt' 04:49:52 INFO - no previously-included directories found matching 'build' 04:49:52 INFO - no previously-included directories found matching 'dist' 04:49:52 INFO - no previously-included directories found matching '.git*' 04:49:52 INFO - Successfully installed jsonschema functools32 04:49:52 INFO - Cleaning up... 04:49:52 INFO - Return code: 0 04:49:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:49: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')]} 04:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49: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')]} 04:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49: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 0x106d6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7055c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b48d30>, '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 0x106a6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b3dbe8>, '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.MwYa7un3Wu/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.LIk796cxLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 04:49: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 04:49: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 04:49:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MwYa7un3Wu/Render', 04:49:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:52 INFO - 'HOME': '/Users/cltbld', 04:49:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:52 INFO - 'LOGNAME': 'cltbld', 04:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:52 INFO - 'MOZ_NO_REMOTE': '1', 04:49:52 INFO - 'NO_EM_RESTART': '1', 04:49:52 INFO - 'PAGER': '/bin/cat', 04:49:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:52 INFO - 'PWD': '/builds/slave/test', 04:49:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:52 INFO - 'SHELL': '/bin/bash', 04:49:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LIk796cxLS/Listeners', 04:49:52 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 04:49:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:52 INFO - 'USER': 'cltbld', 04:49:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:52 INFO - 'XPC_FLAGS': '0x0', 04:49:52 INFO - 'XPC_SERVICE_NAME': '0', 04:49:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:49:52 INFO - Cleaning up... 04:49:52 INFO - Return code: 0 04:49:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:49: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')]} 04:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49: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')]} 04:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49: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', '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 0x106d6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7055c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b48d30>, '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 0x106a6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b3dbe8>, '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.MwYa7un3Wu/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.LIk796cxLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 04:49: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:49: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 blobuploader==1.2.4 04:49:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MwYa7un3Wu/Render', 04:49:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:52 INFO - 'HOME': '/Users/cltbld', 04:49:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:52 INFO - 'LOGNAME': 'cltbld', 04:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:52 INFO - 'MOZ_NO_REMOTE': '1', 04:49:52 INFO - 'NO_EM_RESTART': '1', 04:49:52 INFO - 'PAGER': '/bin/cat', 04:49:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:52 INFO - 'PWD': '/builds/slave/test', 04:49:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:52 INFO - 'SHELL': '/bin/bash', 04:49:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LIk796cxLS/Listeners', 04:49:52 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 04:49:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:52 INFO - 'USER': 'cltbld', 04:49:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:52 INFO - 'XPC_FLAGS': '0x0', 04:49:52 INFO - 'XPC_SERVICE_NAME': '0', 04:49:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:52 INFO - Downloading/unpacking blobuploader==1.2.4 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49:55 INFO - Downloading blobuploader-1.2.4.tar.gz 04:49:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:49:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:49:55 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 04:49:55 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 04:49:55 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 04:49:55 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 04:49:55 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 04:49:55 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 04:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:49:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49:56 INFO - Downloading docopt-0.6.1.tar.gz 04:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:49:56 INFO - Installing collected packages: blobuploader, requests, docopt 04:49:56 INFO - Running setup.py install for blobuploader 04:49:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:49:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:49:57 INFO - Running setup.py install for requests 04:49:57 INFO - Running setup.py install for docopt 04:49:57 INFO - Successfully installed blobuploader requests docopt 04:49:57 INFO - Cleaning up... 04:49:57 INFO - Return code: 0 04:49:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:49:57 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')]} 04:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:57 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')]} 04:49:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:57 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 0x106d6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7055c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b48d30>, '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 0x106a6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b3dbe8>, '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.MwYa7un3Wu/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.LIk796cxLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 04:49:57 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 04:49:57 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 04:49:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MwYa7un3Wu/Render', 04:49:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:57 INFO - 'HOME': '/Users/cltbld', 04:49:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:57 INFO - 'LOGNAME': 'cltbld', 04:49:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:57 INFO - 'MOZ_NO_REMOTE': '1', 04:49:57 INFO - 'NO_EM_RESTART': '1', 04:49:57 INFO - 'PAGER': '/bin/cat', 04:49:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:57 INFO - 'PWD': '/builds/slave/test', 04:49:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:57 INFO - 'SHELL': '/bin/bash', 04:49:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LIk796cxLS/Listeners', 04:49:57 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 04:49:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:57 INFO - 'USER': 'cltbld', 04:49:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:57 INFO - 'XPC_FLAGS': '0x0', 04:49:57 INFO - 'XPC_SERVICE_NAME': '0', 04:49:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:49:58 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-flo7Cd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:49:58 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Yki3FV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:49:58 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-CSnzRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:49:58 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-PDXlM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:49:58 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Ys1ddg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:49:58 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-vIQlom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:49:58 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-N1N_dH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:49:58 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-VWcNZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:49:58 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-0OjTRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:49:58 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-l5b571-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:49:59 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-smn4Zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:49:59 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-dn93el-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:49:59 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-B3l4J4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:49:59 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-pHUTfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:59 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-oDbdAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:49:59 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-x1GBOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:49:59 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-yRsp1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:49:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:49:59 INFO - Running setup.py install for manifestparser 04:49:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:49:59 INFO - Running setup.py install for mozcrash 04:50:00 INFO - Running setup.py install for mozdebug 04:50:00 INFO - Running setup.py install for mozdevice 04:50:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:50:00 INFO - Running setup.py install for mozfile 04:50:00 INFO - Running setup.py install for mozhttpd 04:50:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:50:00 INFO - Running setup.py install for mozinfo 04:50:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:50:00 INFO - Running setup.py install for mozInstall 04:50:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:50:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:50:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:50:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:50:00 INFO - Running setup.py install for mozleak 04:50:01 INFO - Running setup.py install for mozlog 04:50:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:50:01 INFO - Running setup.py install for moznetwork 04:50:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:50:01 INFO - Running setup.py install for mozprocess 04:50:01 INFO - Running setup.py install for mozprofile 04:50:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:50:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:50:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:50:01 INFO - Running setup.py install for mozrunner 04:50:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:50:01 INFO - Running setup.py install for mozscreenshot 04:50:02 INFO - Running setup.py install for moztest 04:50:02 INFO - Running setup.py install for mozversion 04:50:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:50:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:50:02 INFO - Cleaning up... 04:50:02 INFO - Return code: 0 04:50:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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')]} 04:50:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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 0x106d6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7055c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b48d30>, '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 0x106a6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b3dbe8>, '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.MwYa7un3Wu/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.LIk796cxLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 04:50: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 04:50: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 04:50:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MwYa7un3Wu/Render', 04:50:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:02 INFO - 'HOME': '/Users/cltbld', 04:50:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:02 INFO - 'LOGNAME': 'cltbld', 04:50:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:02 INFO - 'MOZ_NO_REMOTE': '1', 04:50:02 INFO - 'NO_EM_RESTART': '1', 04:50:02 INFO - 'PAGER': '/bin/cat', 04:50:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:02 INFO - 'PWD': '/builds/slave/test', 04:50:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:02 INFO - 'SHELL': '/bin/bash', 04:50:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LIk796cxLS/Listeners', 04:50:02 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 04:50:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:02 INFO - 'USER': 'cltbld', 04:50:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:02 INFO - 'XPC_FLAGS': '0x0', 04:50:02 INFO - 'XPC_SERVICE_NAME': '0', 04:50:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:50:02 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-mxaiNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:50:02 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)) 04:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:50:02 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-2CtrP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:50:02 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)) 04:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:50:02 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-lVI875-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:50:02 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)) 04:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:50:02 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-W4SslQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:50: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)) 04:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:50:03 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-OcTsS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:50: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)) 04:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:50:03 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-vOnB9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:50: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)) 04:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:50:03 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-VgUGnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:50: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)) 04:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:50:03 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-DcZykI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:50: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)) 04:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:50:03 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-N44APL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:50:03 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)) 04:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:50:03 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-qdSYV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:50:03 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)) 04:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:50:03 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Ab9DsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:50:03 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)) 04:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:50:03 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-lmYRRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:50:03 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)) 04:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:50:03 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-8TUmcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:50: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)) 04:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:50:04 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Hbapiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:50: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)) 04:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:04 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-3nRYin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:50: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)) 04:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:50:04 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-q8APmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:50: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)) 04:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:50:04 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-TzWS44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:50: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)) 04:50: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)) 04:50: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)) 04:50: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)) 04:50: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)) 04:50: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)) 04:50:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:50: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 04:50: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 04:50: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 04:50: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 04:50: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 04:50: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 04:50:07 INFO - Downloading blessings-1.6.tar.gz 04:50:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:50:07 INFO - Installing collected packages: blessings 04:50:07 INFO - Running setup.py install for blessings 04:50:07 INFO - Successfully installed blessings 04:50:07 INFO - Cleaning up... 04:50:07 INFO - Return code: 0 04:50:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:50:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:50:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:50:07 INFO - Reading from file tmpfile_stdout 04:50:07 INFO - Current package versions: 04:50:07 INFO - blessings == 1.6 04:50:07 INFO - blobuploader == 1.2.4 04:50:07 INFO - docopt == 0.6.1 04:50:07 INFO - functools32 == 3.2.3-2 04:50:07 INFO - jsonschema == 2.5.1 04:50:07 INFO - manifestparser == 1.1 04:50:07 INFO - mozInstall == 1.12 04:50:07 INFO - mozcrash == 1.0 04:50:07 INFO - mozdebug == 0.1 04:50:07 INFO - mozdevice == 0.49 04:50:07 INFO - mozfile == 1.2 04:50:07 INFO - mozhttpd == 0.7 04:50:07 INFO - mozinfo == 0.9 04:50:07 INFO - mozleak == 0.1 04:50:07 INFO - mozlog == 3.4 04:50:07 INFO - moznetwork == 0.27 04:50:07 INFO - mozprocess == 0.25 04:50:07 INFO - mozprofile == 0.28 04:50:07 INFO - mozrunner == 6.13 04:50:07 INFO - mozscreenshot == 0.1 04:50:07 INFO - mozsystemmonitor == 0.3 04:50:07 INFO - moztest == 0.8 04:50:07 INFO - mozversion == 1.4 04:50:07 INFO - psutil == 3.1.1 04:50:07 INFO - requests == 1.2.3 04:50:07 INFO - wsgiref == 0.1.2 04:50:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:07 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 0x106d6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7055c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b48d30>, '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 0x106a6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b3dbe8>, '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.MwYa7un3Wu/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.LIk796cxLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 04:50:07 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 04:50:07 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 04:50:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MwYa7un3Wu/Render', 04:50:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:07 INFO - 'HOME': '/Users/cltbld', 04:50:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:07 INFO - 'LOGNAME': 'cltbld', 04:50:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:07 INFO - 'MOZ_NO_REMOTE': '1', 04:50:07 INFO - 'NO_EM_RESTART': '1', 04:50:07 INFO - 'PAGER': '/bin/cat', 04:50:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:07 INFO - 'PWD': '/builds/slave/test', 04:50:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:07 INFO - 'SHELL': '/bin/bash', 04:50:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LIk796cxLS/Listeners', 04:50:07 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 04:50:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:07 INFO - 'USER': 'cltbld', 04:50:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:07 INFO - 'XPC_FLAGS': '0x0', 04:50:07 INFO - 'XPC_SERVICE_NAME': '0', 04:50:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50: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)) 04:50: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)) 04:50: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)) 04:50: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)) 04:50: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)) 04:50: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)) 04:50: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)) 04:50: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)) 04:50: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)) 04:50: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)) 04:50: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)) 04:50:08 INFO - Cleaning up... 04:50:08 INFO - Return code: 0 04:50:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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')]} 04:50:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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 0x106d6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7055c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b48d30>, '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 0x106a6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b3dbe8>, '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.MwYa7un3Wu/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.LIk796cxLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 04:50: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 04:50: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 04:50:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MwYa7un3Wu/Render', 04:50:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:08 INFO - 'HOME': '/Users/cltbld', 04:50:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:08 INFO - 'LOGNAME': 'cltbld', 04:50:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:08 INFO - 'MOZ_NO_REMOTE': '1', 04:50:08 INFO - 'NO_EM_RESTART': '1', 04:50:08 INFO - 'PAGER': '/bin/cat', 04:50:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:08 INFO - 'PWD': '/builds/slave/test', 04:50:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:08 INFO - 'SHELL': '/bin/bash', 04:50:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LIk796cxLS/Listeners', 04:50:08 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 04:50:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:08 INFO - 'USER': 'cltbld', 04:50:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:08 INFO - 'XPC_FLAGS': '0x0', 04:50:08 INFO - 'XPC_SERVICE_NAME': '0', 04:50:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:50:08 INFO - Cleaning up... 04:50:08 INFO - Return code: 0 04:50:08 INFO - Running post-action listener: _resource_record_post_action 04:50:08 INFO - Running post-action listener: _start_resource_monitoring 04:50:08 INFO - Starting resource monitoring. 04:50:08 INFO - [mozharness: 2017-04-03 11:50:08.327928Z] Finished create-virtualenv step (success) 04:50:08 INFO - [mozharness: 2017-04-03 11:50:08.328964Z] Running install step. 04:50:08 INFO - Running pre-action listener: _resource_record_pre_action 04:50:08 INFO - Running main action method: install 04:50:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:50:08 INFO - Reading from file tmpfile_stdout 04:50:08 INFO - Detecting whether we're running mozinstall >=1.0... 04:50:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:50:08 INFO - Reading from file tmpfile_stdout 04:50:08 INFO - Output received: 04:50:08 INFO - Usage: mozinstall [options] installer 04:50:08 INFO - Options: 04:50:08 INFO - -h, --help show this help message and exit 04:50:08 INFO - -d DEST, --destination=DEST 04:50:08 INFO - Directory to install application into. [default: 04:50:08 INFO - "/builds/slave/test"] 04:50:08 INFO - --app=APP Application being installed. [default: firefox] 04:50:08 INFO - mkdir: /builds/slave/test/build/application 04:50:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:50:28 INFO - Reading from file tmpfile_stdout 04:50:28 INFO - Output received: 04:50:28 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:50:28 INFO - Running post-action listener: _resource_record_post_action 04:50:28 INFO - [mozharness: 2017-04-03 11:50:28.941682Z] Finished install step (success) 04:50:28 INFO - [mozharness: 2017-04-03 11:50:28.941814Z] Running run-tests step. 04:50:28 INFO - Running pre-action listener: _resource_record_pre_action 04:50:28 INFO - Running main action method: run_tests 04:50:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:50:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:50:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:50:28 INFO - 2017-04-03 04:50:28.956 screenresolution[1615:7275] starting screenresolution argv=screenresolution get 04:50:28 INFO - 2017-04-03 04:50:28.959 screenresolution[1615:7275] Display 0: 1600x1200x32@60 04:50:28 INFO - 2017-04-03 04:50:28.964 screenresolution[1616:7278] starting screenresolution argv=screenresolution list 04:50:28 INFO - Available Modes on Display 0 04:50:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:50:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:50:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:50:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:50:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:50:29 INFO - Intel Iris: 04:50:29 INFO - Chipset Model: Intel Iris 04:50:29 INFO - Type: GPU 04:50:29 INFO - Bus: Built-In 04:50:29 INFO - VRAM (Dynamic, Max): 1536 MB 04:50:29 INFO - Vendor: Intel (0x8086) 04:50:29 INFO - Device ID: 0x0a2e 04:50:29 INFO - Revision ID: 0x0009 04:50:29 INFO - Displays: 04:50:29 INFO - Display: 04:50:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:50:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:50:29 INFO - Display Serial Number: 1600x1200 60 04:50:29 INFO - Main Display: Yes 04:50:29 INFO - Mirror: Off 04:50:29 INFO - Online: Yes 04:50:29 INFO - Rotation: Supported 04:50:29 INFO - Return code: 0 04:50:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:50:29 INFO - Python 2.7.3 04:50:29 INFO - Return code: 0 04:50:29 INFO - grabbing minidump binary from tooltool 04:50:29 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')]} 04:50:29 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 0x106b48d30>, '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 0x106a6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b3dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:50:29 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 04:50:29 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 04:50:29 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 04:50:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:50:29 INFO - Return code: 0 04:50:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:50:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:50:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:50:29 INFO - ENV: RUST_BACKTRACE is now 1 04:50:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:50:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:50:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0261', '--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 04:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0261 --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 04:50:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MwYa7un3Wu/Render', 04:50:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:29 INFO - 'HOME': '/Users/cltbld', 04:50:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:29 INFO - 'LOGNAME': 'cltbld', 04:50:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:50:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:29 INFO - 'MOZ_NO_REMOTE': '1', 04:50:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:50:29 INFO - 'NO_EM_RESTART': '1', 04:50:29 INFO - 'PAGER': '/bin/cat', 04:50:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:29 INFO - 'PWD': '/builds/slave/test', 04:50:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:50:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:29 INFO - 'RUST_BACKTRACE': '1', 04:50:29 INFO - 'SHELL': '/bin/bash', 04:50:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LIk796cxLS/Listeners', 04:50:29 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 04:50:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:29 INFO - 'USER': 'cltbld', 04:50:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:29 INFO - 'XPC_FLAGS': '0x0', 04:50:29 INFO - 'XPC_SERVICE_NAME': '0', 04:50:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0261', '--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 04:50:29 INFO - mozversion application_buildid: 20170403030207 04:50:29 INFO - mozversion application_changeset: 38894655c89e68bcd8f45d31a0d3005f2c2b53db 04:50:29 INFO - mozversion application_display_name: Nightly 04:50:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:50:29 INFO - mozversion application_name: Firefox 04:50:29 INFO - mozversion application_remotingname: firefox 04:50:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:50:29 INFO - mozversion application_vendor: Mozilla 04:50:29 INFO - mozversion application_version: 55.0a1 04:50:29 INFO - mozversion platform_buildid: 20170403030207 04:50:29 INFO - mozversion platform_changeset: 38894655c89e68bcd8f45d31a0d3005f2c2b53db 04:50:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:50:29 INFO - mozversion platform_version: 55.0a1 04:50:29 INFO - using testdate: 1491220229 04:50:29 INFO - actual date: 1491220229 04:50:29 INFO - starting webserver on 'localhost:49243' 04:50:29 INFO - SUITE-START | Running 2 tests 04:50:29 INFO - TEST-START | dromaeo_css 04:50:29 INFO - operating with platform_type : mac_ 04:50:29 INFO - Initialising browser for dromaeo_css test... 04:50:29 INFO - TEST-INFO | started process 1626 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpYLnG9M/profile http://localhost:49243/getInfo.html) 04:50:32 INFO - PID 1626 | 2017-04-03 04:50:32.665 plugin-container[1628:7457] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 04:50:32 INFO - PID 1626 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:32 INFO - PID 1626 | 2017-04-03 04:50:32.666 plugin-container[1628:7457] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 04:50:32 INFO - PID 1626 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:32 INFO - PID 1626 | 2017-04-03 04:50:32.666 plugin-container[1628:7457] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:50:32 INFO - PID 1626 | __metrics Screen width/height:1600/1200 04:50:32 INFO - PID 1626 | colorDepth:24 04:50:32 INFO - PID 1626 | Browser inner width/height: 1024/689 04:50:32 INFO - PID 1626 | __metrics 04:50:32 ERROR - PID 1626 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 04:50:33 INFO - TEST-INFO | 1626: exit 0 04:50:33 INFO - Browser initialized. 04:50:33 INFO - Running cycle 1/1 for dromaeo_css test... 04:50:33 INFO - TEST-INFO | started process 1630 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpYLnG9M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:50:36 INFO - PID 1630 | 2017-04-03 04:50:36.007 plugin-container[1631:7602] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a47, name = 'com.apple.tsm.portname' 04:50:36 INFO - PID 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:36 INFO - PID 1630 | 2017-04-03 04:50:36.007 plugin-container[1631:7602] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 04:50:36 INFO - PID 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:50:36 INFO - PID 1630 | 2017-04-03 04:50:36.007 plugin-container[1631:7602] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:50:45 INFO - PID 1630 | RSS: Main: 229003264 04:50:45 INFO - PID 1630 | 04:53:50 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 04:53:51 INFO - PID 1630 | RSS: Main: 253566976 04:53:51 INFO - PID 1630 | 04:55:34 INFO - PID 1630 | ************************* 04:55:34 INFO - PID 1630 | A coding exception was thrown and uncaught in a Task. 04:55:34 INFO - PID 1630 | 04:55:34 ERROR - PID 1630 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:55:34 INFO - PID 1630 | Full stack: 04:55:34 INFO - PID 1630 | ************************* 04:55:34 INFO - PID 1630 | ************************* 04:55:34 INFO - PID 1630 | A coding exception was thrown and uncaught in a Task. 04:55:34 INFO - PID 1630 | 04:55:34 ERROR - PID 1630 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:55:34 INFO - PID 1630 | Full stack: 04:55:34 INFO - PID 1630 | ************************* 04:55:34 INFO - PID 1630 | ************************* 04:55:34 INFO - PID 1630 | A coding exception was thrown and uncaught in a Task. 04:55:34 INFO - PID 1630 | 04:55:34 ERROR - PID 1630 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:55:34 INFO - PID 1630 | Full stack: 04:55:34 INFO - PID 1630 | ************************* 04:55:34 INFO - PID 1630 | ************************* 04:55:34 INFO - PID 1630 | A coding exception was thrown and uncaught in a Task. 04:55:34 INFO - PID 1630 | 04:55:34 ERROR - PID 1630 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:55:34 INFO - PID 1630 | Full stack: 04:55:34 INFO - PID 1630 | ************************* 04:56:53 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 04:56:54 INFO - PID 1630 | RSS: Main: 262365184 04:56:54 INFO - PID 1630 | 04:59:56 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 04:59:57 INFO - PID 1630 | RSS: Main: 277782528 04:59:57 INFO - PID 1630 | 05:02:59 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 05:02:59 INFO - PID 1630 | RSS: Main: 270757888 05:02:59 INFO - PID 1630 | 05:06:02 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 05:06:03 INFO - PID 1630 | RSS: Main: 280989696 05:06:03 INFO - PID 1630 | 05:09:05 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 05:09:06 INFO - PID 1630 | RSS: Main: 274677760 05:09:06 INFO - PID 1630 | 05:09:06 INFO - PID 1630 | __start_tp_report 05:09:06 INFO - PID 1630 | _x_x_mozilla_page_load 05:09:06 INFO - PID 1630 | _x_x_mozilla_page_load_details 05:09:06 INFO - PID 1630 | |i|pagename|runs| 05:09:06 INFO - PID 1630 | |0;dojo.html;3330.600327960645;3427.8800241991535;3589.2821435712863;3626.34725749365;3647.5805282392535;6110.437255174884;6232.470240029597;6234.002559590466;6242.531810114237;6319.462845658125;6132.508481341045;6171.352008039158;6182.845428864281;6238.407351301619;6274.184356033709;5482.068048431776;5489.39616642169;5490.478404551571;5518.392976772552;5587.916181257285;5406.810761623325;5464.12573988162;5579.358373787026;5582.748776305056;5590.580706447013;5387.434319396485;5421.810236641738;5493.340799104093;5519.061759500894;5557.221988921534;5446.809361672362;5472.179173124024;5479.698616576086;5485.670859748428;5532.059549876531;5433.4566543345745;5457.890842183174;5469.972650136764;5474.616776825584;5480.725963701799;5506.972465137689;6002.909956350658;6129.542179248687;6152.661603611799;6185.969070154665;5659.943400565983;6655.833604159886;6719;6731.158605174353;6752;6796.082528858639;6799.082123913307;6805.353491418307;6808.6936087876165;6816.352446517573;6136.886723216021;6152.446279834837;6302.590331628476;6319.368063193644;6322.525810564226;6870.656467176621;6892.310768923067;6934.687939042755;6935.3411425914455;6949.583025018515;6300;6353.491720662336;6471.611703297817;6546.541742078104;6563.737450501926;6698;6773.017912402674;6777.017332486762;6777.898331525031;6805.659717014229;6349;6379;6683.732650694015;6688.498362622823;6693.631850248283;6594.472442204612;6608.768693095719;6656.234533028667;6689;6707.094542236833;4190.916181676349;5373.301470808769;5378.381486129067;5384.730763461811;5387.838364849061;4700.506446823103;4753.667243292936;4786;4789.281607758794;4805.783739731721;5388.757505912244;6313.305536390993;6313.9368606314265;6314.336994615499;6346.270178929482;6733.360330768569;6748.797536073926;6749.392554670103;6753.797386078426;6767.526273160832;6274.811755647338;6281.968590157111;6309.211348581427;6313.779017734358;6332.778352757631;5787.479126878601;5902.1146827976145;5904.4095590441475;5906.793262235801;5958.702064896738;6235.532335074797;6976.999999999898;7059;7080.362767350963;7083.256258092826;6734.259231484435;6812.625305608239;6825.317468253135;6827.692753825991;6836.555623884432;6716.6977486012265;6797.1843378794865;6970.930290697231;7012;7026.578405295699;6634.369734875179;6640.103586015826;6694.765683201064;6705.061291419108;6710.731570737116;5710.400407957105;6399.136116624196;6430.517711171775;6773.356531129535;6776.390124888783;6294.087357333253;6464.353564643498;6473.190851143607;6550.017497375241;6551.148350714377;5227.2943152675425;6509.251436084722;6511.34886511345;6550;6557.606543607399;6120.233815636221;6251.9687401562705;6267.5299352549655;6286.748530058747;6351.110844481868;5549.944500555104;6573.014047892663;6574;6574.638394888136;6582.605043697394;6398.520110991787;6410.230772307353;6416.615003099829;6420.486361091195;6428 05:09:06 INFO - PID 1630 | |1;ext.html;716.9103862017248;805.299389531108;831.1771346367096;1093.9343639381632;1236.8021116621337;37387.81306093469;38437.61562384372;38531;38587.22825543487;38597.64908228213;38433.807830960875;38653.226935461345;38814.611853881455;38872;38875;8026.879596806052;8194.22154895284;8342.582870856464;8459.788505287355;8711.128887111301;7605.277498637621;7732.381409487228;8728.563571821414;8766;8777.7805554861;8070.394720395964;8702.651893924236;8769.991450983154;8779.824403511926;8785.7803554911;7590.506617069873;8707.651693932236;8737.257333126692;8768.517731524795;8823.029466758715;8520.19058189471;8585.098564650747;8604.0965698602;8616;8626.353023523261;31864.840675796706;38080.04799879997;38266.234675306616;38400.42399364012;38411.23177536461;3005.1285127313054;3051.992842362037;3059.479888418974;3059.4951832947536;3073.3085055862475;27567.862160689067;27665.723342766516;27818.443631127466;27951;28697.28256793597;2745.643066401375;2769.1138835572624;2776.6112744215825;2810.7329803668617;2818.8449635270053;335404.322978386;338087;341392;342297;342454;49146.75426622844;49161.26258106131;49375.25937110946;49417.76455588639;49604.759881002545;50534.74732626313;50951;51048.744756275984;51205;51350.22974655383;14523.564293071226;14539.418423263163;14602.488912887999;14629.634259143606;14639;11817.231879927776;11820.70448238801;11831.408429578487;11832.290062596272;11858.466369013415;12689.936550317374;13432;13535.984801139954;13552.119112257671;13619.114757540729;5926.496247818984;5975.492083172892;6001.3098493672915;6002.159697642333;6003.279606447254;833.7748807821854;971.4802580619335;999.8700168977987;1010.4594042187395;1025.1388833379997;23680.526389472114;23718.407039823796;23927.880360598432;23952.161674341663;23987.400314992265;837.539353355652;1076.5478499030426;1109.944502774858;1117.1956191542076;1125.3810404277615;10496.055355018085;10803.243772935975;11329.43352832355;12188.354572132648;12188.512459501699;463856;466986.6650666725;467596;468375;468563;53111.46888531143;53372.19941700878;53455.73272133692;53840.19239711407;55329.61675958133;1442.1419255542935;1475.6753514226853;1489.8137732783402;1492.79847220626;1495.8952873298983;27454.588181177696;27622.171334859988;27986.58020129659;28263.717362826108;28309.433811323248;36021.27957440889;36189.63810361863;36199;36209;36298.455523166645;376226;377954;379158;379834;380264;30655.693443065284;30738;31365.215869603442;32812.67187328191;32995.67004329926;42835;43437;44453.555464444944;44491;45006.324905127105;27034.594481083193;29807;30008;30662.846685766428;31417.8429107853;29630.703692962794;30588.08235752931;30664.233394165323;30670.693293066783;31237.375252495276;35403.36967294351;35559.64440355667;35860;36057.639423605426;36319.27361452809;22119.77880221177;30418.239544010692;30752.538711918893;30769.846150769103;30947;22418.327450175868;23310.76689233086;24099.51800963936;24143;24607.892644830667 05:09:06 INFO - PID 1630 | |2;jquery.html;409.3266576481686;415.55328022375954;421.87765547991086;437.8620734468642;442.4336848833493;19620.803791962095;21292.57414851704;22026.669599956014;22162.77837221627;22169.891505424726;19422.70865937012;21581.3525594232;22995.310140695805;23194;23211.732832135312;6044.667543285118;6300.338464461234;6321.715522801462;6327.968360158216;6345.111684364193;5967.060705270783;6193.1948846649875;6235.563510554263;6279.843003924915;6308.810735677914;5443.618946673734;6002.699865006754;6232.127502149703;6245.375462453741;6287.559870809046;6136.969315153418;6248.687565621746;6260.686965651699;6260.906086408708;6282.591631543958;5308.283381743454;5450;5544.972275138599;5559.471850174267;5570.526505247059;27036.59445108325;27269.590956135675;27401.725982740118;28493.002744903828;28576.857115714287;2032.7763945965933;2059.680749483832;2062.391594479617;2077.387170784622;2101.6217080925426;14739.705205895929;17213.22540485681;17749.467515974542;18125.909370453064;18363.810592481826;1958.5103724068983;2045.263705066175;2050.0672194151625;2060.8660437071694;2076.273304343483;143850;152534;157000;158036.09819508978;158094.20952895278;20038.899805501173;21189.576208475744;24629;24749.752502475105;24985.37536561564;40879.59120408758;40954.59045409508;40986.38520422196;41146.794266028475;41237.587624123975;8770.210681038738;8847.46915185091;8922.241609463268;9153.725388238363;9168.541572921327;8637.309015278763;8656.26421754158;8659.870101948476;8828;8855.070217627057;7978.6409611567615;8125.309348705308;8153.225443582969;8160.306373958161;8163.673453061811;2207.5364173523417;2211.823054155696;2214.4131805071347;2226.7216597925258;2372.6678265042906;900.3697411811758;920.6639576554643;920.8710780490736;953.9601834000972;957.5260246178048;17074.019222932402;20586.27948021842;21266;21316.573668526544;21662;937.460959948019;957.2389949989774;970.2965350121106;986.068165583531;1004.1815920025302;8579.571021448903;10991.560337586567;11116.721577018585;11140.777184456427;11145.832812507819;693714;706245.4687726632;714611;728750;731789;152839;154178.22910885373;156530;156684;157731;1159.8327501681329;1177.4407156600716;1208.2448469706433;1212;1217.2939694976892;18123.45629631113;18149.092545372943;18170.90914545419;18172.90913545424;18209.362672306404;57847.4215257842;58443.70778146082;58494;58719.48567457609;59776;81329;82116;82684;83042.9239268999;84076;8452.577371131541;8640.833487479362;8643.567821608769;8717.651293948426;8988.056254093226;11127.833082503606;14034.649133771738;14106.684929041872;14185.858141418454;14364.425422982964;11161.386123763272;11804;12048.518059277882;12055.51777928908;12209.694757630776;10994.505247263733;11006.174536909604;11028.724281893017;11310.4910279036;11355.54577816878;11931.284122952651;11932.582359617372;12247.938760306142;12272.263664180178;12304.323262220305;7093.290670932865;8624.18070283322;8721.389502734748;8867.955660221916;8919.955400222958;11813.40932953366;11907.642770716893;11943.701407464883;11963.880361196625;12139.575114870937 05:09:06 INFO - PID 1630 | |3;mootools.html;1383.3843939446947;1453.8078775404174;1456.5484699743074;1465.6042868425525;1486.0340778493978;10399.064084232417;10726.7318317042;10777.245592808507;11121.944390278057;11348.829767553494;10759.462026898664;10953;10959.178061645369;10960.28758130721;11020.50407731652;2423.684920960273;2479.814013948952;2490.476999830038;2491.9626205606933;2526.2295000024983;2082.2816128435666;2139.9786002140013;2280.441291883483;2384.821138414617;2461.79074778644;2402.3873912152376;2450.4119011437215;2452.0191923230673;2459.274514018369;2468.395243165427;2367.7513861044686;2403.495265994152;2405.8195635327334;2410.9397265068515;2475.8266921315517;2054.9725137431283;2407.2537513370735;2410.1925854838473;2419.14120487228;2419.334682962182;10731.302465339806;11041.944790276077;11127.888721112766;11148.498317575728;11170.497327620276;5521.834344969658;6208.565400421972;6377.904331435032;6454.806355809334;6454.903176452357;3304.933901321984;3890.3775395936796;3963.246983073207;4095.3037983542868;4101.733387329844;1032.6282538286212;1192.9821052684217;1198.8801119888028;1216.3127832774453;1226.3316492511512;13898.93050534751;14191.503297384543;14979.400823967137;15076;15185.088894666245;5217.713025783542;5239.947600524022;5407;5444.1017232156255;5512.972435137879;11575.88424115748;11722.88277117235;11724.00345970585;11767.529298828122;11787.882121178678;6385.968070159713;6392.296847346788;6396.456301214449;6399.840003999937;6431.134970858398;5628;5685.857853553612;5696.3733989261145;5734.455226753452;5947.226860508106;4161.791910404468;4256.36154576816;4257.893552661147;4329.1125319310095;4340.283853164193;1249.843769528809;1271.5041133957764;1286.601153642374;1290.4451086033096;1302.602706174607;710.4174576847039;752.5935994562984;795.741384050186;796.211750367144;815.8449894520022;5513.421090785409;5960.254968128984;6028.788992385245;6216.658083805343;6232.968835155795;490.5560467776668;552.2379116818764;582.3884920833108;593.7090825495476;594.1622312539299;3076.923076923095;3930.33184358654;3942.842286308573;4253.702240843173;4302.139572085595;11991.340476273714;13882.722345553033;13953.3720982554;14064.08583442073;14077;13552.661183470294;13668.863311366958;13947.790783138262;13960.58118256454;13975;10005.14956228714;10336.121429678562;10389.013043760982;10408.687739367831;10457.477126143815;5501.614886957875;5714.599978001666;6781.084553585203;6939.236683964659;6976.720931162899;12462.638076673093;12682.175658582162;12743.87256127427;12775.488980440678;12926.935365323114;12860.742785144432;12877.935610322264;12932.741345173232;12934;13074.477020919057;8818.38271321027;9057.04900985387;9073.31950103758;9203.86194207074;9251.907480925105;10325;10354.585816567253;10366.585336586451;10478;10483.790324193626;8623.439476434012;8745.212930836255;8779.780505487413;8798.12018798115;8821.2060914518;7124.5725256484775;8576.44253123545;8652.870206947027;8708.34687398435;8711.39020268575;6770.729170833111;7858.489198202098;7862.2923936845955;7929.207079292025;7947;5842.2405087338375;5871.735771890189;6005.669688167192;6043.848903777441;6073.544484163794;5937.139114828239;6055.273367195965;6158.815235542762;6163.722632481459;6304.65324407162 05:09:06 INFO - PID 1630 | |4;prototype.html;326.37336314276615;379.660204117315;403.7920470957457;406.93895915612654;407.12265068776776;5599.832005039855;6051.878962420749;6285.9685701571425;6327.462165715914;6359.968200158998;6061.93916064688;6199.574097957472;6224.502039836813;6231.283402408724;6247;4346.065595896882;4350.325699516564;4760.952390476103;4774.355462012636;4784.282357646346;4142.482189726285;4540.568645978643;4702.929456058162;4770.856874293777;4776.235802271637;4114.958850411503;4688.648351373644;4692.788824502905;4710.034442939189;4719.882002949937;4694.319323698045;4705.764711764416;4744.312074749159;4766.332713420124;4774.546418090275;4067.776272305022;4125.968507873032;4145.54399016108;4198.109202778759;4223.049813791903;5976.462118409364;6046.848828779272;6062.545309101835;6078.270607527125;6111.144439778434;5609.467100625434;5615.241942337773;5616.803411880564;5640.435956404368;5646;6356.682165891733;6527.281998980108;6669.666516674195;6692.431143352821;6695.263521012685;4768.189407800683;4781.713097214179;4785.976070119662;4788.7605619719225;4791.95208047922;6710.3289671033;6961.23426423093;7019.700740651885;7093.787186384365;7138.46522997558;4346.239408103569;4898.191798353303;4905.950940490621;4926.926096108561;4930.359053323045;4999.675021123615;5002.648755085104;5022.146235140035;5039.622028347889;5043.3191519145175;5362.785488580413;5434.266374039533;5545.195946587723;5615.820677657656;5624.6625202487985;5664.631798933056;6130.509559235251;6674.499412544079;6728;6737.3599508046655;1481.8073650425592;1596.433266190509;1676.5976165720383;1708.4618345221215;1711.1700825099817;978.7014960436993;999.9500024998721;1175.2302242031483;1176.3647630279554;1177.270092542629;6161.907571386433;6222.906656400158;6228.22147231596;6256.623542820572;6290.339514351019;5900.409959004065;6748.865022699519;6821.829454263683;6830.93169068303;6877.4841886858685;5311.760970756325;5944.673042982591;6264.404881536247;6328.6835658216905;6335.23977122739;2940.55891616255;3530.9646903531147;3583.2475180212446;3588.4079126944284;3602.531670882821;6031.155638210654;6702.932970670328;7097.21930587635;7098.396636285974;7115.039469671631;7031.191412987469;7052.894206586802;7086.39765619928;7092.397146242524;7125.42996560274;6835.726570937206;6925.75759848413;7148.821279467951;7196.388306993859;7196.568205907663;6383.0531687835655;6875.593542260169;6890.173179218526;6890.655467226718;6890.965545172242;6118.9807363569025;6188.783392581238;6219.284782250099;6431.742730290736;6613.239477459962;6381.712822923072;6998.950157476419;7097.787066388017;7142.5000249982;7142.714291428285;4245.235857545671;4420.359047937966;4430.911381772411;4440.156370289635;4478.462584489882;6840.726370945106;6860.931390686229;6861.099454469252;6880.139982502187;6883.690233939385;5397.541208997279;6625.768098116543;6648.304682305981;6678.599284042973;6691;6130.969345153424;6265.530085243534;6581;6609.380701728107;6628.83427914306;5323.653962492425;5791.131330300489;6487.3836985486305;6496.8630489665065;6502.837429064311;5643.984082865124;5671.517920976763;6417.93582064192;6617.205935287796;6656.30108838565;6445.935540644721;6451.4516266116025;6466.612003279818;6472.514561407819;6498.415142637185 05:09:06 INFO - PID 1630 | |5;yui.html;716.1155972374117;731.5976213082803;747.3946103656037;749.6950645074919;750.5121670913906;14286;15479;15931.44239951602;16368.508944731662;16486.010839349634;14762.557123286291;15038.022528535665;15079.773803392958;15330.46343377982;15373.07761534306;816.8162163513207;978.1636700620974;991.3506653142204;999.7900440907418;1004.397361583048;985.1970643925179;1008.6822650864991;1014.7158795537262;1026.686860507545;1028.9691309260734;1071.9869723111663;1077.854489643896;1078.2075174746556;1079.0773888325448;1085.4952447112091;1063.8776540697802;1066.62668066177;1067.146282973618;1069.9893001070045;1079.2013909706839;673.9696713647897;785.3128512551517;796.5658715999793;797.0117054851942;799.8040480082359;16539.50381488557;16852.915735421368;16885.577860553585;17187.742183867253;17387.913060434617;12089.939550302191;14878.925605371904;14926.40294388234;14999.850001499954;15164;10053.949730251376;11753.706157346049;11834.349110798903;11873.931346178799;12082.093842961725;2639.577667573198;2929.6283611057174;2979.7616190704907;2994.191568276575;3113.5641010258582;98789.72390107781;102463.97536024619;117138.41430792876;117582;118509;11173.944130279297;11625.418729063513;11763.411829408495;11984.221025633306;12016;24869;25200.873995629903;25205;25239.62140567893;25246.368840779127;7654.770356889302;7748.845023099507;7892.881606775903;7950.403719721044;8039.638216280281;4051.4125451809914;4183.581641835792;4185.518665353462;4191.622753952159;4197;4084.36692312692;4124.608162224584;4152.854650087293;4178.206140833232;4222.19778242133;698.7868700046535;800.2597597222546;811.6063709101081;812.305478815616;814.9103598604149;559.720139930035;563.2818156850049;574.8131857146444;575.3728436004775;601.3295175878864;8726.86909696355;9203.585838637277;9608.570988436542;10252.282340236112;10256.743581410525;559.3846768554639;577.9855503612359;583.1951906369184;584.6842704939285;601.5518438763062;4465.427265174509;4904.4605093439695;4912.490650710546;4929.211326187793;4935.161022626162;88378.11621883827;94990;98255.01744982602;100023;100751;75045;85646;88205.67691484634;89876;91744.54127729319;74660.88008679874;75321;77261.61369193118;79324.60337698275;79398.6030069846;10695.526352302037;10854;10942.890571094506;11008.889911100787;11114.277571957797;36619.63380366269;37277;37412.43881341837;37590.43614345732;37913.81043094767;44072;44480.55519444764;44638;45019.77490112528;45472.09055818931;13451.529196478075;13614.727705446034;13697.726045478836;14030.228337441538;14259.714805704036;14356.784648230494;14447.566573002827;14451;14501.854981450051;14717.337719802848;13218.479874814519;13431.50756442092;13624.93187534056;13691.794623080463;13900;13394.732105358033;13409.195448273134;13431.865681343452;13594.524191653245;13614;13131;13223.537176198786;13309.46762129504;13618.319084045559;13711.794323084961;8635.525046122524;9453.95273023658;9491;9727.416355018722;9880.40717556949;8484.872726909225;9626.9037309626;9960.302778805704;10236;11223.55105795759 05:09:06 INFO - PID 1630 | __end_tp_report 05:09:06 INFO - PID 1630 | __start_cc_report 05:09:06 INFO - PID 1630 | _x_x_mozilla_cycle_collect,430 05:09:06 INFO - PID 1630 | __end_cc_report 05:09:06 INFO - PID 1630 | __startTimestamp1491221346195__endTimestamp 05:09:06 INFO - PID 1630 | 05:09:06 INFO - PID 1630 | ------- Summary: start ------- 05:09:06 INFO - PID 1630 | Number of tests: 6 05:09:06 INFO - PID 1630 | 05:09:06 INFO - PID 1630 | [#0] dojo.html Cycles:165 Average:6144.45 Median:6319.46 stddev:742.32 (11.7%) stddev-sans-first:711.02 05:09:06 INFO - PID 1630 | Values: 3330.6 3427.9 3589.3 3626.3 3647.6 6110.4 6232.5 6234.0 6242.5 6319.5 6132.5 6171.4 6182.8 6238.4 6274.2 5482.1 5489.4 5490.5 5518.4 5587.9 5406.8 5464.1 5579.4 5582.7 5590.6 5387.4 5421.8 5493.3 5519.1 5557.2 5446.8 5472.2 5479.7 5485.7 5532.1 5433.5 5457.9 5470.0 5474.6 5480.7 5507.0 6002.9 6129.5 6152.7 6186.0 5659.9 6655.8 6719.0 6731.2 6752.0 6796.1 6799.1 6805.4 6808.7 6816.4 6136.9 6152.4 6302.6 6319.4 6322.5 6870.7 6892.3 6934.7 6935.3 6949.6 6300.0 6353.5 6471.6 6546.5 6563.7 6698.0 6773.0 6777.0 6777.9 6805.7 6349.0 6379.0 6683.7 6688.5 6693.6 6594.5 6608.8 6656.2 6689.0 6707.1 4190.9 5373.3 5378.4 5384.7 5387.8 4700.5 4753.7 4786.0 4789.3 4805.8 5388.8 6313.3 6313.9 6314.3 6346.3 6733.4 6748.8 6749.4 6753.8 6767.5 6274.8 6282.0 6309.2 6313.8 6332.8 5787.5 5902.1 5904.4 5906.8 5958.7 6235.5 6977.0 7059.0 7080.4 7083.3 6734.3 6812.6 6825.3 6827.7 6836.6 6716.7 6797.2 6970.9 7012.0 7026.6 6634.4 6640.1 6694.8 6705.1 6710.7 5710.4 6399.1 6430.5 6773.4 6776.4 6294.1 6464.4 6473.2 6550.0 6551.1 5227.3 6509.3 6511.3 6550.0 6557.6 6120.2 6252.0 6267.5 6286.7 6351.1 5549.9 6573.0 6574.0 6574.6 6582.6 6398.5 6410.2 6416.6 6420.5 6428.0 05:09:06 INFO - PID 1630 | 05:09:06 INFO - PID 1630 | [#1] ext.html Cycles:180 Average:52897.84 Median:35709.82 stddev:105790.62 (296.3%) stddev-sans-first:106014.84 05:09:06 INFO - PID 1630 | Values: 716.9 805.3 831.2 1093.9 1236.8 37387.8 38437.6 38531.0 38587.2 38597.6 38433.8 38653.2 38814.6 38872.0 38875.0 8026.9 8194.2 8342.6 8459.8 8711.1 7605.3 7732.4 8728.6 8766.0 8777.8 8070.4 8702.7 8770.0 8779.8 8785.8 7590.5 8707.7 8737.3 8768.5 8823.0 8520.2 8585.1 8604.1 8616.0 8626.4 31864.8 38080.0 38266.2 38400.4 38411.2 3005.1 3052.0 3059.5 3059.5 3073.3 27567.9 27665.7 27818.4 27951.0 28697.3 2745.6 2769.1 2776.6 2810.7 2818.8 335404.3 338087.0 341392.0 342297.0 342454.0 49146.8 49161.3 49375.3 49417.8 49604.8 50534.7 50951.0 51048.7 51205.0 51350.2 14523.6 14539.4 14602.5 14629.6 14639.0 11817.2 11820.7 11831.4 11832.3 11858.5 12689.9 13432.0 13536.0 13552.1 13619.1 5926.5 5975.5 6001.3 6002.2 6003.3 833.8 971.5 999.9 1010.5 1025.1 23680.5 23718.4 23927.9 23952.2 23987.4 837.5 1076.5 1109.9 1117.2 1125.4 10496.1 10803.2 11329.4 12188.4 12188.5 463856.0 466986.7 467596.0 468375.0 468563.0 53111.5 53372.2 53455.7 53840.2 55329.6 1442.1 1475.7 1489.8 1492.8 1495.9 27454.6 27622.2 27986.6 28263.7 28309.4 36021.3 36189.6 36199.0 36209.0 36298.5 376226.0 377954.0 379158.0 379834.0 380264.0 30655.7 30738.0 31365.2 32812.7 32995.7 42835.0 43437.0 44453.6 44491.0 45006.3 27034.6 29807.0 30008.0 30662.8 31417.8 29630.7 30588.1 30664.2 30670.7 31237.4 35403.4 35559.6 35860.0 36057.6 36319.3 22119.8 30418.2 30752.5 30769.8 30947.0 22418.3 23310.8 24099.5 24143.0 24607.9 05:09:06 INFO - PID 1630 | 05:09:06 INFO - PID 1630 | [#2] jquery.html Cycles:180 Average:41982.18 Median:27153.09 stddev:119662.33 (440.7%) stddev-sans-first:119957.30 05:09:06 INFO - PID 1630 | Values: 409.3 415.6 421.9 437.9 442.4 19620.8 21292.6 22026.7 22162.8 22169.9 19422.7 21581.4 22995.3 23194.0 23211.7 6044.7 6300.3 6321.7 6328.0 6345.1 5967.1 6193.2 6235.6 6279.8 6308.8 5443.6 6002.7 6232.1 6245.4 6287.6 6137.0 6248.7 6260.7 6260.9 6282.6 5308.3 5450.0 5545.0 5559.5 5570.5 27036.6 27269.6 27401.7 28493.0 28576.9 2032.8 2059.7 2062.4 2077.4 2101.6 14739.7 17213.2 17749.5 18125.9 18363.8 1958.5 2045.3 2050.1 2060.9 2076.3 143850.0 152534.0 157000.0 158036.1 158094.2 20038.9 21189.6 24629.0 24749.8 24985.4 40879.6 40954.6 40986.4 41146.8 41237.6 8770.2 8847.5 8922.2 9153.7 9168.5 8637.3 8656.3 8659.9 8828.0 8855.1 7978.6 8125.3 8153.2 8160.3 8163.7 2207.5 2211.8 2214.4 2226.7 2372.7 900.4 920.7 920.9 954.0 957.5 17074.0 20586.3 21266.0 21316.6 21662.0 937.5 957.2 970.3 986.1 1004.2 8579.6 10991.6 11116.7 11140.8 11145.8 693714.0 706245.5 714611.0 728750.0 731789.0 152839.0 154178.2 156530.0 156684.0 157731.0 1159.8 1177.4 1208.2 1212.0 1217.3 18123.5 18149.1 18170.9 18172.9 18209.4 57847.4 58443.7 58494.0 58719.5 59776.0 81329.0 82116.0 82684.0 83042.9 84076.0 8452.6 8640.8 8643.6 8717.7 8988.1 11127.8 14034.6 14106.7 14185.9 14364.4 11161.4 11804.0 12048.5 12055.5 12209.7 10994.5 11006.2 11028.7 11310.5 11355.5 11931.3 11932.6 12247.9 12272.3 12304.3 7093.3 8624.2 8721.4 8868.0 8920.0 11813.4 11907.6 11943.7 11963.9 12139.6 05:09:06 INFO - PID 1630 | 05:09:06 INFO - PID 1630 | [#3] mootools.html Cycles:180 Average:6785.84 Median:2477.82 stddev:4222.93 (170.4%) stddev-sans-first:4215.27 05:09:06 INFO - PID 1630 | Values: 1383.4 1453.8 1456.5 1465.6 1486.0 10399.1 10726.7 10777.2 11121.9 11348.8 10759.5 10953.0 10959.2 10960.3 11020.5 2423.7 2479.8 2490.5 2492.0 2526.2 2082.3 2140.0 2280.4 2384.8 2461.8 2402.4 2450.4 2452.0 2459.3 2468.4 2367.8 2403.5 2405.8 2410.9 2475.8 2055.0 2407.3 2410.2 2419.1 2419.3 10731.3 11041.9 11127.9 11148.5 11170.5 5521.8 6208.6 6377.9 6454.8 6454.9 3304.9 3890.4 3963.2 4095.3 4101.7 1032.6 1193.0 1198.9 1216.3 1226.3 13898.9 14191.5 14979.4 15076.0 15185.1 5217.7 5239.9 5407.0 5444.1 5513.0 11575.9 11722.9 11724.0 11767.5 11787.9 6386.0 6392.3 6396.5 6399.8 6431.1 5628.0 5685.9 5696.4 5734.5 5947.2 4161.8 4256.4 4257.9 4329.1 4340.3 1249.8 1271.5 1286.6 1290.4 1302.6 710.4 752.6 795.7 796.2 815.8 5513.4 5960.3 6028.8 6216.7 6233.0 490.6 552.2 582.4 593.7 594.2 3076.9 3930.3 3942.8 4253.7 4302.1 11991.3 13882.7 13953.4 14064.1 14077.0 13552.7 13668.9 13947.8 13960.6 13975.0 10005.1 10336.1 10389.0 10408.7 10457.5 5501.6 5714.6 6781.1 6939.2 6976.7 12462.6 12682.2 12743.9 12775.5 12926.9 12860.7 12877.9 12932.7 12934.0 13074.5 8818.4 9057.0 9073.3 9203.9 9251.9 10325.0 10354.6 10366.6 10478.0 10483.8 8623.4 8745.2 8779.8 8798.1 8821.2 7124.6 8576.4 8652.9 8708.3 8711.4 6770.7 7858.5 7862.3 7929.2 7947.0 5842.2 5871.7 6005.7 6043.8 6073.5 5937.1 6055.3 6158.8 6163.7 6304.7 05:09:06 INFO - PID 1630 | 05:09:06 INFO - PID 1630 | [#4] prototype.html Cycles:180 Average:5448.05 Median:6115.06 stddev:1652.94 (27.0%) stddev-sans-first:1612.25 05:09:06 INFO - PID 1630 | Values: 326.4 379.7 403.8 406.9 407.1 5599.8 6051.9 6286.0 6327.5 6360.0 6061.9 6199.6 6224.5 6231.3 6247.0 4346.1 4350.3 4761.0 4774.4 4784.3 4142.5 4540.6 4702.9 4770.9 4776.2 4115.0 4688.6 4692.8 4710.0 4719.9 4694.3 4705.8 4744.3 4766.3 4774.5 4067.8 4126.0 4145.5 4198.1 4223.0 5976.5 6046.8 6062.5 6078.3 6111.1 5609.5 5615.2 5616.8 5640.4 5646.0 6356.7 6527.3 6669.7 6692.4 6695.3 4768.2 4781.7 4786.0 4788.8 4792.0 6710.3 6961.2 7019.7 7093.8 7138.5 4346.2 4898.2 4906.0 4926.9 4930.4 4999.7 5002.6 5022.1 5039.6 5043.3 5362.8 5434.3 5545.2 5615.8 5624.7 5664.6 6130.5 6674.5 6728.0 6737.4 1481.8 1596.4 1676.6 1708.5 1711.2 978.7 1000.0 1175.2 1176.4 1177.3 6161.9 6222.9 6228.2 6256.6 6290.3 5900.4 6748.9 6821.8 6830.9 6877.5 5311.8 5944.7 6264.4 6328.7 6335.2 2940.6 3531.0 3583.2 3588.4 3602.5 6031.2 6702.9 7097.2 7098.4 7115.0 7031.2 7052.9 7086.4 7092.4 7125.4 6835.7 6925.8 7148.8 7196.4 7196.6 6383.1 6875.6 6890.2 6890.7 6891.0 6119.0 6188.8 6219.3 6431.7 6613.2 6381.7 6999.0 7097.8 7142.5 7142.7 4245.2 4420.4 4430.9 4440.2 4478.5 6840.7 6860.9 6861.1 6880.1 6883.7 5397.5 6625.8 6648.3 6678.6 6691.0 6131.0 6265.5 6581.0 6609.4 6628.8 5323.7 5791.1 6487.4 6496.9 6502.8 5644.0 5671.5 6417.9 6617.2 6656.3 6445.9 6451.5 6466.6 6472.5 6498.4 05:09:06 INFO - PID 1630 | 05:09:06 INFO - PID 1630 | [#5] yui.html Cycles:180 Average:19542.05 Median:2784.60 stddev:28025.11 (1006.4%) stddev-sans-first:28068.08 05:09:06 INFO - PID 1630 | Values: 716.1 731.6 747.4 749.7 750.5 14286.0 15479.0 15931.4 16368.5 16486.0 14762.6 15038.0 15079.8 15330.5 15373.1 816.8 978.2 991.4 999.8 1004.4 985.2 1008.7 1014.7 1026.7 1029.0 1072.0 1077.9 1078.2 1079.1 1085.5 1063.9 1066.6 1067.1 1070.0 1079.2 674.0 785.3 796.6 797.0 799.8 16539.5 16852.9 16885.6 17187.7 17387.9 12089.9 14878.9 14926.4 14999.9 15164.0 10053.9 11753.7 11834.3 11873.9 12082.1 2639.6 2929.6 2979.8 2994.2 3113.6 98789.7 102464.0 117138.4 117582.0 118509.0 11173.9 11625.4 11763.4 11984.2 12016.0 24869.0 25200.9 25205.0 25239.6 25246.4 7654.8 7748.8 7892.9 7950.4 8039.6 4051.4 4183.6 4185.5 4191.6 4197.0 4084.4 4124.6 4152.9 4178.2 4222.2 698.8 800.3 811.6 812.3 814.9 559.7 563.3 574.8 575.4 601.3 8726.9 9203.6 9608.6 10252.3 10256.7 559.4 578.0 583.2 584.7 601.6 4465.4 4904.5 4912.5 4929.2 4935.2 88378.1 94990.0 98255.0 100023.0 100751.0 75045.0 85646.0 88205.7 89876.0 91744.5 74660.9 75321.0 77261.6 79324.6 79398.6 10695.5 10854.0 10942.9 11008.9 11114.3 36619.6 37277.0 37412.4 37590.4 37913.8 44072.0 44480.6 44638.0 45019.8 45472.1 13451.5 13614.7 13697.7 14030.2 14259.7 14356.8 14447.6 14451.0 14501.9 14717.3 13218.5 13431.5 13624.9 13691.8 13900.0 13394.7 13409.2 13431.9 13594.5 13614.0 13131.0 13223.5 13309.5 13618.3 13711.8 8635.5 9454.0 9491.0 9727.4 9880.4 8484.9 9626.9 9960.3 10236.0 11223.6 05:09:06 INFO - PID 1630 | -------- Summary: end -------- 05:09:06 INFO - PID 1630 | 05:09:06 INFO - TEST-INFO | 1630: exit 0 05:09:06 INFO - TEST-OK | dromaeo_css | took 1117306ms 05:09:06 INFO - TEST-START | kraken 05:09:06 INFO - operating with platform_type : mac_ 05:09:06 INFO - Initialising browser for kraken test... 05:09:06 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpvjoKTT/profile http://localhost:49243/getInfo.html) 05:09:09 INFO - PID 1643 | 2017-04-03 05:09:09.516 plugin-container[1645:10096] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 05:09:09 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:09:09 INFO - PID 1643 | 2017-04-03 05:09:09.516 plugin-container[1645:10096] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 05:09:09 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:09:09 INFO - PID 1643 | 2017-04-03 05:09:09.516 plugin-container[1645:10096] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:09:09 INFO - PID 1643 | __metrics Screen width/height:1600/1200 05:09:09 INFO - PID 1643 | colorDepth:24 05:09:09 INFO - PID 1643 | Browser inner width/height: 1024/689 05:09:09 INFO - PID 1643 | __metrics 05:09:09 ERROR - PID 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 05:09:10 INFO - TEST-INFO | 1643: exit 0 05:09:10 INFO - Browser initialized. 05:09:10 INFO - Running cycle 1/1 for kraken test... 05:09:10 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpvjoKTT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:09:12 INFO - PID 1646 | 2017-04-03 05:09:12.899 plugin-container[1647:10227] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 05:09:12 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:09:12 INFO - PID 1646 | 2017-04-03 05:09:12.900 plugin-container[1647:10227] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 05:09:12 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:09:12 INFO - PID 1646 | 2017-04-03 05:09:12.900 plugin-container[1647:10227] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:09:22 INFO - PID 1646 | RSS: Main: 228601856 05:09:22 INFO - PID 1646 | 05:09:25 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 05:09:26 INFO - PID 1646 | RSS: Main: 244170752 05:09:26 INFO - PID 1646 | 05:09:29 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 05:09:30 INFO - PID 1646 | RSS: Main: 252215296 05:09:30 INFO - PID 1646 | 05:09:33 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 05:09:34 INFO - PID 1646 | RSS: Main: 254586880 05:09:34 INFO - PID 1646 | 05:09:36 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 05:09:37 INFO - PID 1646 | RSS: Main: 255504384 05:09:37 INFO - PID 1646 | 05:09:39 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:09:39 INFO - PID 1646 | RSS: Main: 257077248 05:09:39 INFO - PID 1646 | 05:09:42 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 05:09:43 INFO - PID 1646 | RSS: Main: 262512640 05:09:43 INFO - PID 1646 | 05:09:46 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 05:09:47 INFO - PID 1646 | RSS: Main: 260571136 05:09:47 INFO - PID 1646 | 05:09:50 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 05:09:51 INFO - PID 1646 | RSS: Main: 261603328 05:09:51 INFO - PID 1646 | 05:09:53 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:09:53 INFO - PID 1646 | RSS: Main: 262021120 05:09:53 INFO - PID 1646 | 05:09:55 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:09:56 INFO - PID 1646 | RSS: Main: 262037504 05:09:56 INFO - PID 1646 | 05:09:58 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:09:59 INFO - PID 1646 | RSS: Main: 262119424 05:09:59 INFO - PID 1646 | 05:10:02 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:10:03 INFO - PID 1646 | RSS: Main: 262860800 05:10:03 INFO - PID 1646 | 05:10:06 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:10:07 INFO - PID 1646 | RSS: Main: 260624384 05:10:07 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 05:10:09 INFO - PID 1646 | RSS: Main: 263815168 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | __start_tp_report 05:10:09 INFO - PID 1646 | _x_x_mozilla_page_load 05:10:09 INFO - PID 1646 | _x_x_mozilla_page_load_details 05:10:09 INFO - PID 1646 | |i|pagename|runs| 05:10:09 INFO - PID 1646 | |0;ai-astar;113;152;119;116;125;135;112;178;180;105 05:10:09 INFO - PID 1646 | |1;audio-beat-detection;157;119;122;154;145;118;159;153;156;118 05:10:09 INFO - PID 1646 | |2;audio-dft;227;225;220;230;216;302;253;211;223;215 05:10:09 INFO - PID 1646 | |3;audio-fft;97;89;66;90;62;66;64;60;77;63 05:10:09 INFO - PID 1646 | |4;audio-oscillator;101;73;88;76;72;74;89;74;71;87 05:10:09 INFO - PID 1646 | |5;imaging-gaussian-blur;91;100;128;139;132;93;93;92;94;93 05:10:09 INFO - PID 1646 | |6;imaging-darkroom;100;112;104;102;132;101;122;160;164;159 05:10:09 INFO - PID 1646 | |7;imaging-desaturate;101;108;102;157;102;136;109;164;138;105 05:10:09 INFO - PID 1646 | |8;json-parse-financial;46;81;83;80;79;69;55;54;54;56 05:10:09 INFO - PID 1646 | |9;json-stringify-tinderbox;61;59;78;68;61;61;62;62;63;65 05:10:09 INFO - PID 1646 | |10;stanford-crypto-aes;88;89;80;110;112;109;107;87;86;88 05:10:09 INFO - PID 1646 | |11;stanford-crypto-ccm;150;164;167;166;165;162;168;190;199;180 05:10:09 INFO - PID 1646 | |12;stanford-crypto-pbkdf2;201;192;208;193;260;227;196;195;194;196 05:10:09 INFO - PID 1646 | |13;stanford-crypto-sha256-iterative;71;72;87;74;73;73;72;72;73;71 05:10:09 INFO - PID 1646 | __end_tp_report 05:10:09 INFO - PID 1646 | __start_cc_report 05:10:09 INFO - PID 1646 | _x_x_mozilla_cycle_collect,1023 05:10:09 INFO - PID 1646 | __end_cc_report 05:10:09 INFO - PID 1646 | __startTimestamp1491221409374__endTimestamp 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | ------- Summary: start ------- 05:10:09 INFO - PID 1646 | Number of tests: 14 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#0] ai-astar Cycles:10 Average:133.50 Median:130.00 stddev:27.43 (21.1%) stddev-sans-first:28.07 05:10:09 INFO - PID 1646 | Values: 113.0 152.0 119.0 116.0 125.0 135.0 112.0 178.0 180.0 105.0 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:153.50 stddev:18.35 (12.0%) stddev-sans-first:18.41 05:10:09 INFO - PID 1646 | Values: 157.0 119.0 122.0 154.0 145.0 118.0 159.0 153.0 156.0 118.0 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#2] audio-dft Cycles:10 Average:232.20 Median:226.00 stddev:27.14 (12.0%) stddev-sans-first:28.72 05:10:09 INFO - PID 1646 | Values: 227.0 225.0 220.0 230.0 216.0 302.0 253.0 211.0 223.0 215.0 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#3] audio-fft Cycles:10 Average:73.40 Median:71.50 stddev:13.76 (19.2%) stddev-sans-first:11.65 05:10:09 INFO - PID 1646 | Values: 97.0 89.0 66.0 90.0 62.0 66.0 64.0 60.0 77.0 63.0 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:75.00 stddev:10.08 (13.4%) stddev-sans-first:7.48 05:10:09 INFO - PID 1646 | Values: 101.0 73.0 88.0 76.0 72.0 74.0 89.0 74.0 71.0 87.0 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:105.50 Median:92.50 stddev:19.31 (20.9%) stddev-sans-first:19.75 05:10:09 INFO - PID 1646 | Values: 91.0 100.0 128.0 139.0 132.0 93.0 93.0 92.0 94.0 93.0 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#6] imaging-darkroom Cycles:10 Average:125.60 Median:127.00 stddev:26.45 (20.8%) stddev-sans-first:26.38 05:10:09 INFO - PID 1646 | Values: 100.0 112.0 104.0 102.0 132.0 101.0 122.0 160.0 164.0 159.0 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#7] imaging-desaturate Cycles:10 Average:122.20 Median:122.50 stddev:24.35 (19.9%) stddev-sans-first:24.59 05:10:09 INFO - PID 1646 | Values: 101.0 108.0 102.0 157.0 102.0 136.0 109.0 164.0 138.0 105.0 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#8] json-parse-financial Cycles:10 Average:65.70 Median:74.00 stddev:14.13 (19.1%) stddev-sans-first:13.06 05:10:09 INFO - PID 1646 | Values: 46.0 81.0 83.0 80.0 79.0 69.0 55.0 54.0 54.0 56.0 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:64.00 Median:62.50 stddev:5.52 (8.8%) stddev-sans-first:5.74 05:10:09 INFO - PID 1646 | Values: 61.0 59.0 78.0 68.0 61.0 61.0 62.0 62.0 63.0 65.0 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#10] stanford-crypto-aes Cycles:10 Average:95.60 Median:86.50 stddev:12.27 (14.2%) stddev-sans-first:12.70 05:10:09 INFO - PID 1646 | Values: 88.0 89.0 80.0 110.0 112.0 109.0 107.0 87.0 86.0 88.0 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:171.10 Median:167.50 stddev:14.46 (8.6%) stddev-sans-first:13.17 05:10:09 INFO - PID 1646 | Values: 150.0 164.0 167.0 166.0 165.0 162.0 168.0 190.0 199.0 180.0 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:206.20 Median:198.50 stddev:21.64 (10.9%) stddev-sans-first:22.87 05:10:09 INFO - PID 1646 | Values: 201.0 192.0 208.0 193.0 260.0 227.0 196.0 195.0 194.0 196.0 05:10:09 INFO - PID 1646 | 05:10:09 INFO - PID 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.80 Median:73.00 stddev:4.73 (6.5%) stddev-sans-first:4.91 05:10:09 INFO - PID 1646 | Values: 71.0 72.0 87.0 74.0 73.0 73.0 72.0 72.0 73.0 71.0 05:10:09 INFO - PID 1646 | -------- Summary: end -------- 05:10:09 INFO - PID 1646 | 05:10:10 INFO - TEST-INFO | 1646: exit 0 05:10:10 INFO - TEST-OK | kraken | took 63428ms 05:10:10 INFO - SUITE-END | took 1180s 05:10:10 INFO - Completed test suite (00:19:40) 05:10:10 INFO - javascript benchmark 05:10:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7794.075962137995, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.600327960645, 3427.8800241991535, 3589.2821435712863, 3626.34725749365, 3647.5805282392535, 6110.437255174884, 6232.470240029597, 6234.002559590466, 6242.531810114237, 6319.462845658125, 6132.508481341045, 6171.352008039158, 6182.845428864281, 6238.407351301619, 6274.184356033709, 5482.068048431776, 5489.39616642169, 5490.478404551571, 5518.392976772552, 5587.916181257285, 5406.810761623325, 5464.12573988162, 5579.358373787026, 5582.748776305056, 5590.580706447013, 5387.434319396485, 5421.810236641738, 5493.340799104093, 5519.061759500894, 5557.221988921534, 5446.809361672362, 5472.179173124024, 5479.698616576086, 5485.670859748428, 5532.059549876531, 5433.4566543345745, 5457.890842183174, 5469.972650136764, 5474.616776825584, 5480.725963701799, 5506.972465137689, 6002.909956350658, 6129.542179248687, 6152.661603611799, 6185.969070154665, 5659.943400565983, 6655.833604159886, 6719.0, 6731.158605174353, 6752.0, 6796.082528858639, 6799.082123913307, 6805.353491418307, 6808.6936087876165, 6816.352446517573, 6136.886723216021, 6152.446279834837, 6302.590331628476, 6319.368063193644, 6322.525810564226, 6870.656467176621, 6892.310768923067, 6934.687939042755, 6935.3411425914455, 6949.583025018515, 6300.0, 6353.491720662336, 6471.611703297817, 6546.541742078104, 6563.737450501926, 6698.0, 6773.017912402674, 6777.017332486762, 6777.898331525031, 6805.659717014229, 6349.0, 6379.0, 6683.732650694015, 6688.498362622823, 6693.631850248283, 6594.472442204612, 6608.768693095719, 6656.234533028667, 6689.0, 6707.094542236833, 4190.916181676349, 5373.301470808769, 5378.381486129067, 5384.730763461811, 5387.838364849061, 4700.506446823103, 4753.667243292936, 4786.0, 4789.281607758794, 4805.783739731721, 5388.757505912244, 6313.305536390993, 6313.9368606314265, 6314.336994615499, 6346.270178929482, 6733.360330768569, 6748.797536073926, 6749.392554670103, 6753.797386078426, 6767.526273160832, 6274.811755647338, 6281.968590157111, 6309.211348581427, 6313.779017734358, 6332.778352757631, 5787.479126878601, 5902.1146827976145, 5904.4095590441475, 5906.793262235801, 5958.702064896738, 6235.532335074797, 6976.999999999898, 7059.0, 7080.362767350963, 7083.256258092826, 6734.259231484435, 6812.625305608239, 6825.317468253135, 6827.692753825991, 6836.555623884432, 6716.6977486012265, 6797.1843378794865, 6970.930290697231, 7012.0, 7026.578405295699, 6634.369734875179, 6640.103586015826, 6694.765683201064, 6705.061291419108, 6710.731570737116, 5710.400407957105, 6399.136116624196, 6430.517711171775, 6773.356531129535, 6776.390124888783, 6294.087357333253, 6464.353564643498, 6473.190851143607, 6550.017497375241, 6551.148350714377, 5227.2943152675425, 6509.251436084722, 6511.34886511345, 6550.0, 6557.606543607399, 6120.233815636221, 6251.9687401562705, 6267.5299352549655, 6286.748530058747, 6351.110844481868, 5549.944500555104, 6573.014047892663, 6574.0, 6574.638394888136, 6582.605043697394, 6398.520110991787, 6410.230772307353, 6416.615003099829, 6420.486361091195, 6428.0], "value": 6095.585230366453, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.9103862017248, 805.299389531108, 831.1771346367096, 1093.9343639381632, 1236.8021116621337, 37387.81306093469, 38437.61562384372, 38531.0, 38587.22825543487, 38597.64908228213, 38433.807830960875, 38653.226935461345, 38814.611853881455, 38872.0, 38875.0, 8026.879596806052, 8194.22154895284, 8342.582870856464, 8459.788505287355, 8711.128887111301, 7605.277498637621, 7732.381409487228, 8728.563571821414, 8766.0, 8777.7805554861, 8070.394720395964, 8702.651893924236, 8769.991450983154, 8779.824403511926, 8785.7803554911, 7590.506617069873, 8707.651693932236, 8737.257333126692, 8768.517731524795, 8823.029466758715, 8520.19058189471, 8585.098564650747, 8604.0965698602, 8616.0, 8626.353023523261, 31864.840675796706, 38080.04799879997, 38266.234675306616, 38400.42399364012, 38411.23177536461, 3005.1285127313054, 3051.992842362037, 3059.479888418974, 3059.4951832947536, 3073.3085055862475, 27567.862160689067, 27665.723342766516, 27818.443631127466, 27951.0, 28697.28256793597, 2745.643066401375, 2769.1138835572624, 2776.6112744215825, 2810.7329803668617, 2818.8449635270053, 335404.322978386, 338087.0, 341392.0, 342297.0, 342454.0, 49146.75426622844, 49161.26258106131, 49375.25937110946, 49417.76455588639, 49604.759881002545, 50534.74732626313, 50951.0, 51048.744756275984, 51205.0, 51350.22974655383, 14523.564293071226, 14539.418423263163, 14602.488912887999, 14629.634259143606, 14639.0, 11817.231879927776, 11820.70448238801, 11831.408429578487, 11832.290062596272, 11858.466369013415, 12689.936550317374, 13432.0, 13535.984801139954, 13552.119112257671, 13619.114757540729, 5926.496247818984, 5975.492083172892, 6001.3098493672915, 6002.159697642333, 6003.279606447254, 833.7748807821854, 971.4802580619335, 999.8700168977987, 1010.4594042187395, 1025.1388833379997, 23680.526389472114, 23718.407039823796, 23927.880360598432, 23952.161674341663, 23987.400314992265, 837.539353355652, 1076.5478499030426, 1109.944502774858, 1117.1956191542076, 1125.3810404277615, 10496.055355018085, 10803.243772935975, 11329.43352832355, 12188.354572132648, 12188.512459501699, 463856.0, 466986.6650666725, 467596.0, 468375.0, 468563.0, 53111.46888531143, 53372.19941700878, 53455.73272133692, 53840.19239711407, 55329.61675958133, 1442.1419255542935, 1475.6753514226853, 1489.8137732783402, 1492.79847220626, 1495.8952873298983, 27454.588181177696, 27622.171334859988, 27986.58020129659, 28263.717362826108, 28309.433811323248, 36021.27957440889, 36189.63810361863, 36199.0, 36209.0, 36298.455523166645, 376226.0, 377954.0, 379158.0, 379834.0, 380264.0, 30655.693443065284, 30738.0, 31365.215869603442, 32812.67187328191, 32995.67004329926, 42835.0, 43437.0, 44453.555464444944, 44491.0, 45006.324905127105, 27034.594481083193, 29807.0, 30008.0, 30662.846685766428, 31417.8429107853, 29630.703692962794, 30588.08235752931, 30664.233394165323, 30670.693293066783, 31237.375252495276, 35403.36967294351, 35559.64440355667, 35860.0, 36057.639423605426, 36319.27361452809, 22119.77880221177, 30418.239544010692, 30752.538711918893, 30769.846150769103, 30947.0, 22418.327450175868, 23310.76689233086, 24099.51800963936, 24143.0, 24607.892644830667], "value": 17663.45691347535, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.3266576481686, 415.55328022375954, 421.87765547991086, 437.8620734468642, 442.4336848833493, 19620.803791962095, 21292.57414851704, 22026.669599956014, 22162.77837221627, 22169.891505424726, 19422.70865937012, 21581.3525594232, 22995.310140695805, 23194.0, 23211.732832135312, 6044.667543285118, 6300.338464461234, 6321.715522801462, 6327.968360158216, 6345.111684364193, 5967.060705270783, 6193.1948846649875, 6235.563510554263, 6279.843003924915, 6308.810735677914, 5443.618946673734, 6002.699865006754, 6232.127502149703, 6245.375462453741, 6287.559870809046, 6136.969315153418, 6248.687565621746, 6260.686965651699, 6260.906086408708, 6282.591631543958, 5308.283381743454, 5450.0, 5544.972275138599, 5559.471850174267, 5570.526505247059, 27036.59445108325, 27269.590956135675, 27401.725982740118, 28493.002744903828, 28576.857115714287, 2032.7763945965933, 2059.680749483832, 2062.391594479617, 2077.387170784622, 2101.6217080925426, 14739.705205895929, 17213.22540485681, 17749.467515974542, 18125.909370453064, 18363.810592481826, 1958.5103724068983, 2045.263705066175, 2050.0672194151625, 2060.8660437071694, 2076.273304343483, 143850.0, 152534.0, 157000.0, 158036.09819508978, 158094.20952895278, 20038.899805501173, 21189.576208475744, 24629.0, 24749.752502475105, 24985.37536561564, 40879.59120408758, 40954.59045409508, 40986.38520422196, 41146.794266028475, 41237.587624123975, 8770.210681038738, 8847.46915185091, 8922.241609463268, 9153.725388238363, 9168.541572921327, 8637.309015278763, 8656.26421754158, 8659.870101948476, 8828.0, 8855.070217627057, 7978.6409611567615, 8125.309348705308, 8153.225443582969, 8160.306373958161, 8163.673453061811, 2207.5364173523417, 2211.823054155696, 2214.4131805071347, 2226.7216597925258, 2372.6678265042906, 900.3697411811758, 920.6639576554643, 920.8710780490736, 953.9601834000972, 957.5260246178048, 17074.019222932402, 20586.27948021842, 21266.0, 21316.573668526544, 21662.0, 937.460959948019, 957.2389949989774, 970.2965350121106, 986.068165583531, 1004.1815920025302, 8579.571021448903, 10991.560337586567, 11116.721577018585, 11140.777184456427, 11145.832812507819, 693714.0, 706245.4687726632, 714611.0, 728750.0, 731789.0, 152839.0, 154178.22910885373, 156530.0, 156684.0, 157731.0, 1159.8327501681329, 1177.4407156600716, 1208.2448469706433, 1212.0, 1217.2939694976892, 18123.45629631113, 18149.092545372943, 18170.90914545419, 18172.90913545424, 18209.362672306404, 57847.4215257842, 58443.70778146082, 58494.0, 58719.48567457609, 59776.0, 81329.0, 82116.0, 82684.0, 83042.9239268999, 84076.0, 8452.577371131541, 8640.833487479362, 8643.567821608769, 8717.651293948426, 8988.056254093226, 11127.833082503606, 14034.649133771738, 14106.684929041872, 14185.858141418454, 14364.425422982964, 11161.386123763272, 11804.0, 12048.518059277882, 12055.51777928908, 12209.694757630776, 10994.505247263733, 11006.174536909604, 11028.724281893017, 11310.4910279036, 11355.54577816878, 11931.284122952651, 11932.582359617372, 12247.938760306142, 12272.263664180178, 12304.323262220305, 7093.290670932865, 8624.18070283322, 8721.389502734748, 8867.955660221916, 8919.955400222958, 11813.40932953366, 11907.642770716893, 11943.701407464883, 11963.880361196625, 12139.575114870937], "value": 10988.910577955729, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.3843939446947, 1453.8078775404174, 1456.5484699743074, 1465.6042868425525, 1486.0340778493978, 10399.064084232417, 10726.7318317042, 10777.245592808507, 11121.944390278057, 11348.829767553494, 10759.462026898664, 10953.0, 10959.178061645369, 10960.28758130721, 11020.50407731652, 2423.684920960273, 2479.814013948952, 2490.476999830038, 2491.9626205606933, 2526.2295000024983, 2082.2816128435666, 2139.9786002140013, 2280.441291883483, 2384.821138414617, 2461.79074778644, 2402.3873912152376, 2450.4119011437215, 2452.0191923230673, 2459.274514018369, 2468.395243165427, 2367.7513861044686, 2403.495265994152, 2405.8195635327334, 2410.9397265068515, 2475.8266921315517, 2054.9725137431283, 2407.2537513370735, 2410.1925854838473, 2419.14120487228, 2419.334682962182, 10731.302465339806, 11041.944790276077, 11127.888721112766, 11148.498317575728, 11170.497327620276, 5521.834344969658, 6208.565400421972, 6377.904331435032, 6454.806355809334, 6454.903176452357, 3304.933901321984, 3890.3775395936796, 3963.246983073207, 4095.3037983542868, 4101.733387329844, 1032.6282538286212, 1192.9821052684217, 1198.8801119888028, 1216.3127832774453, 1226.3316492511512, 13898.93050534751, 14191.503297384543, 14979.400823967137, 15076.0, 15185.088894666245, 5217.713025783542, 5239.947600524022, 5407.0, 5444.1017232156255, 5512.972435137879, 11575.88424115748, 11722.88277117235, 11724.00345970585, 11767.529298828122, 11787.882121178678, 6385.968070159713, 6392.296847346788, 6396.456301214449, 6399.840003999937, 6431.134970858398, 5628.0, 5685.857853553612, 5696.3733989261145, 5734.455226753452, 5947.226860508106, 4161.791910404468, 4256.36154576816, 4257.893552661147, 4329.1125319310095, 4340.283853164193, 1249.843769528809, 1271.5041133957764, 1286.601153642374, 1290.4451086033096, 1302.602706174607, 710.4174576847039, 752.5935994562984, 795.741384050186, 796.211750367144, 815.8449894520022, 5513.421090785409, 5960.254968128984, 6028.788992385245, 6216.658083805343, 6232.968835155795, 490.5560467776668, 552.2379116818764, 582.3884920833108, 593.7090825495476, 594.1622312539299, 3076.923076923095, 3930.33184358654, 3942.842286308573, 4253.702240843173, 4302.139572085595, 11991.340476273714, 13882.722345553033, 13953.3720982554, 14064.08583442073, 14077.0, 13552.661183470294, 13668.863311366958, 13947.790783138262, 13960.58118256454, 13975.0, 10005.14956228714, 10336.121429678562, 10389.013043760982, 10408.687739367831, 10457.477126143815, 5501.614886957875, 5714.599978001666, 6781.084553585203, 6939.236683964659, 6976.720931162899, 12462.638076673093, 12682.175658582162, 12743.87256127427, 12775.488980440678, 12926.935365323114, 12860.742785144432, 12877.935610322264, 12932.741345173232, 12934.0, 13074.477020919057, 8818.38271321027, 9057.04900985387, 9073.31950103758, 9203.86194207074, 9251.907480925105, 10325.0, 10354.585816567253, 10366.585336586451, 10478.0, 10483.790324193626, 8623.439476434012, 8745.212930836255, 8779.780505487413, 8798.12018798115, 8821.2060914518, 7124.5725256484775, 8576.44253123545, 8652.870206947027, 8708.34687398435, 8711.39020268575, 6770.729170833111, 7858.489198202098, 7862.2923936845955, 7929.207079292025, 7947.0, 5842.2405087338375, 5871.735771890189, 6005.669688167192, 6043.848903777441, 6073.544484163794, 5937.139114828239, 6055.273367195965, 6158.815235542762, 6163.722632481459, 6304.65324407162], "value": 5106.79189066466, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.37336314276615, 379.660204117315, 403.7920470957457, 406.93895915612654, 407.12265068776776, 5599.832005039855, 6051.878962420749, 6285.9685701571425, 6327.462165715914, 6359.968200158998, 6061.93916064688, 6199.574097957472, 6224.502039836813, 6231.283402408724, 6247.0, 4346.065595896882, 4350.325699516564, 4760.952390476103, 4774.355462012636, 4784.282357646346, 4142.482189726285, 4540.568645978643, 4702.929456058162, 4770.856874293777, 4776.235802271637, 4114.958850411503, 4688.648351373644, 4692.788824502905, 4710.034442939189, 4719.882002949937, 4694.319323698045, 4705.764711764416, 4744.312074749159, 4766.332713420124, 4774.546418090275, 4067.776272305022, 4125.968507873032, 4145.54399016108, 4198.109202778759, 4223.049813791903, 5976.462118409364, 6046.848828779272, 6062.545309101835, 6078.270607527125, 6111.144439778434, 5609.467100625434, 5615.241942337773, 5616.803411880564, 5640.435956404368, 5646.0, 6356.682165891733, 6527.281998980108, 6669.666516674195, 6692.431143352821, 6695.263521012685, 4768.189407800683, 4781.713097214179, 4785.976070119662, 4788.7605619719225, 4791.95208047922, 6710.3289671033, 6961.23426423093, 7019.700740651885, 7093.787186384365, 7138.46522997558, 4346.239408103569, 4898.191798353303, 4905.950940490621, 4926.926096108561, 4930.359053323045, 4999.675021123615, 5002.648755085104, 5022.146235140035, 5039.622028347889, 5043.3191519145175, 5362.785488580413, 5434.266374039533, 5545.195946587723, 5615.820677657656, 5624.6625202487985, 5664.631798933056, 6130.509559235251, 6674.499412544079, 6728.0, 6737.3599508046655, 1481.8073650425592, 1596.433266190509, 1676.5976165720383, 1708.4618345221215, 1711.1700825099817, 978.7014960436993, 999.9500024998721, 1175.2302242031483, 1176.3647630279554, 1177.270092542629, 6161.907571386433, 6222.906656400158, 6228.22147231596, 6256.623542820572, 6290.339514351019, 5900.409959004065, 6748.865022699519, 6821.829454263683, 6830.93169068303, 6877.4841886858685, 5311.760970756325, 5944.673042982591, 6264.404881536247, 6328.6835658216905, 6335.23977122739, 2940.55891616255, 3530.9646903531147, 3583.2475180212446, 3588.4079126944284, 3602.531670882821, 6031.155638210654, 6702.932970670328, 7097.21930587635, 7098.396636285974, 7115.039469671631, 7031.191412987469, 7052.894206586802, 7086.39765619928, 7092.397146242524, 7125.42996560274, 6835.726570937206, 6925.75759848413, 7148.821279467951, 7196.388306993859, 7196.568205907663, 6383.0531687835655, 6875.593542260169, 6890.173179218526, 6890.655467226718, 6890.965545172242, 6118.9807363569025, 6188.783392581238, 6219.284782250099, 6431.742730290736, 6613.239477459962, 6381.712822923072, 6998.950157476419, 7097.787066388017, 7142.5000249982, 7142.714291428285, 4245.235857545671, 4420.359047937966, 4430.911381772411, 4440.156370289635, 4478.462584489882, 6840.726370945106, 6860.931390686229, 6861.099454469252, 6880.139982502187, 6883.690233939385, 5397.541208997279, 6625.768098116543, 6648.304682305981, 6678.599284042973, 6691.0, 6130.969345153424, 6265.530085243534, 6581.0, 6609.380701728107, 6628.83427914306, 5323.653962492425, 5791.131330300489, 6487.3836985486305, 6496.8630489665065, 6502.837429064311, 5643.984082865124, 5671.517920976763, 6417.93582064192, 6617.205935287796, 6656.30108838565, 6445.935540644721, 6451.4516266116025, 6466.612003279818, 6472.514561407819, 6498.415142637185], "value": 4926.6392908709795, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.1155972374117, 731.5976213082803, 747.3946103656037, 749.6950645074919, 750.5121670913906, 14286.0, 15479.0, 15931.44239951602, 16368.508944731662, 16486.010839349634, 14762.557123286291, 15038.022528535665, 15079.773803392958, 15330.46343377982, 15373.07761534306, 816.8162163513207, 978.1636700620974, 991.3506653142204, 999.7900440907418, 1004.397361583048, 985.1970643925179, 1008.6822650864991, 1014.7158795537262, 1026.686860507545, 1028.9691309260734, 1071.9869723111663, 1077.854489643896, 1078.2075174746556, 1079.0773888325448, 1085.4952447112091, 1063.8776540697802, 1066.62668066177, 1067.146282973618, 1069.9893001070045, 1079.2013909706839, 673.9696713647897, 785.3128512551517, 796.5658715999793, 797.0117054851942, 799.8040480082359, 16539.50381488557, 16852.915735421368, 16885.577860553585, 17187.742183867253, 17387.913060434617, 12089.939550302191, 14878.925605371904, 14926.40294388234, 14999.850001499954, 15164.0, 10053.949730251376, 11753.706157346049, 11834.349110798903, 11873.931346178799, 12082.093842961725, 2639.577667573198, 2929.6283611057174, 2979.7616190704907, 2994.191568276575, 3113.5641010258582, 98789.72390107781, 102463.97536024619, 117138.41430792876, 117582.0, 118509.0, 11173.944130279297, 11625.418729063513, 11763.411829408495, 11984.221025633306, 12016.0, 24869.0, 25200.873995629903, 25205.0, 25239.62140567893, 25246.368840779127, 7654.770356889302, 7748.845023099507, 7892.881606775903, 7950.403719721044, 8039.638216280281, 4051.4125451809914, 4183.581641835792, 4185.518665353462, 4191.622753952159, 4197.0, 4084.36692312692, 4124.608162224584, 4152.854650087293, 4178.206140833232, 4222.19778242133, 698.7868700046535, 800.2597597222546, 811.6063709101081, 812.305478815616, 814.9103598604149, 559.720139930035, 563.2818156850049, 574.8131857146444, 575.3728436004775, 601.3295175878864, 8726.86909696355, 9203.585838637277, 9608.570988436542, 10252.282340236112, 10256.743581410525, 559.3846768554639, 577.9855503612359, 583.1951906369184, 584.6842704939285, 601.5518438763062, 4465.427265174509, 4904.4605093439695, 4912.490650710546, 4929.211326187793, 4935.161022626162, 88378.11621883827, 94990.0, 98255.01744982602, 100023.0, 100751.0, 75045.0, 85646.0, 88205.67691484634, 89876.0, 91744.54127729319, 74660.88008679874, 75321.0, 77261.61369193118, 79324.60337698275, 79398.6030069846, 10695.526352302037, 10854.0, 10942.890571094506, 11008.889911100787, 11114.277571957797, 36619.63380366269, 37277.0, 37412.43881341837, 37590.43614345732, 37913.81043094767, 44072.0, 44480.55519444764, 44638.0, 45019.77490112528, 45472.09055818931, 13451.529196478075, 13614.727705446034, 13697.726045478836, 14030.228337441538, 14259.714805704036, 14356.784648230494, 14447.566573002827, 14451.0, 14501.854981450051, 14717.337719802848, 13218.479874814519, 13431.50756442092, 13624.93187534056, 13691.794623080463, 13900.0, 13394.732105358033, 13409.195448273134, 13431.865681343452, 13594.524191653245, 13614.0, 13131.0, 13223.537176198786, 13309.46762129504, 13618.319084045559, 13711.794323084961, 8635.525046122524, 9453.95273023658, 9491.0, 9727.416355018722, 9880.40717556949, 8484.872726909225, 9626.9037309626, 9960.302778805704, 10236.0, 11223.55105795759], "value": 7530.2819795666865, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1689.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 152.0, 119.0, 116.0, 125.0, 135.0, 112.0, 178.0, 180.0, 105.0], "value": 133.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 119.0, 122.0, 154.0, 145.0, 118.0, 159.0, 153.0, 156.0, 118.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [227.0, 225.0, 220.0, 230.0, 216.0, 302.0, 253.0, 211.0, 223.0, 215.0], "value": 232.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 89.0, 66.0, 90.0, 62.0, 66.0, 64.0, 60.0, 77.0, 63.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 73.0, 88.0, 76.0, 72.0, 74.0, 89.0, 74.0, 71.0, 87.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 100.0, 128.0, 139.0, 132.0, 93.0, 93.0, 92.0, 94.0, 93.0], "value": 105.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 104.0, 102.0, 132.0, 101.0, 122.0, 160.0, 164.0, 159.0], "value": 125.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 108.0, 102.0, 157.0, 102.0, 136.0, 109.0, 164.0, 138.0, 105.0], "value": 122.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 81.0, 83.0, 80.0, 79.0, 69.0, 55.0, 54.0, 54.0, 56.0], "value": 65.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 78.0, 68.0, 61.0, 61.0, 62.0, 62.0, 63.0, 65.0], "value": 64.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 80.0, 110.0, 112.0, 109.0, 107.0, 87.0, 86.0, 88.0], "value": 95.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 164.0, 167.0, 166.0, 165.0, 162.0, 168.0, 190.0, 199.0, 180.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 192.0, 208.0, 193.0, 260.0, 227.0, 196.0, 195.0, 194.0, 196.0], "value": 206.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 87.0, 74.0, 73.0, 73.0, 72.0, 72.0, 73.0, 71.0], "value": 73.8, "unit": "score"}]}]} 05:10:10 INFO - Return code: 0 05:10:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:10:10 ERROR - # TBPL SUCCESS # 05:10:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:10:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:10:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:10:10 INFO - 2017-04-03 05:10:10.723 screenresolution[1651:10618] starting screenresolution argv=screenresolution get 05:10:10 INFO - 2017-04-03 05:10:10.726 screenresolution[1651:10618] Display 0: 1600x1200x32@60 05:10:10 INFO - 2017-04-03 05:10:10.733 screenresolution[1652:10621] starting screenresolution argv=screenresolution list 05:10:10 INFO - Available Modes on Display 0 05:10:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:10:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:10:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:10:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:10:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:10:10 INFO - Intel Iris: 05:10:10 INFO - Chipset Model: Intel Iris 05:10:10 INFO - Type: GPU 05:10:10 INFO - Bus: Built-In 05:10:10 INFO - VRAM (Dynamic, Max): 1536 MB 05:10:10 INFO - Vendor: Intel (0x8086) 05:10:10 INFO - Device ID: 0x0a2e 05:10:10 INFO - Revision ID: 0x0009 05:10:10 INFO - Displays: 05:10:10 INFO - Display: 05:10:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:10:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:10:10 INFO - Display Serial Number: 1600x1200 60 05:10:10 INFO - Main Display: Yes 05:10:10 INFO - Mirror: Off 05:10:10 INFO - Online: Yes 05:10:10 INFO - Rotation: Supported 05:10:10 INFO - Return code: 0 05:10:10 INFO - Running post-action listener: _resource_record_post_action 05:10:10 INFO - [mozharness: 2017-04-03 12:10:10.971342Z] Finished run-tests step (success) 05:10:10 INFO - Running post-run listener: _resource_record_post_run 05:10:11 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 38049792; Write bytes: 516282368; Read time: 248; Write time: 1593 05:10:11 INFO - TinderboxPrint: CPU usage
54.5% 05:10:11 INFO - TinderboxPrint: I/O read bytes / time
38,049,792 / 248 05:10:11 INFO - TinderboxPrint: I/O write bytes / time
516,282,368 / 1,593 05:10:11 INFO - TinderboxPrint: CPU idle
2,186.4 (45.5%) 05:10:11 INFO - TinderboxPrint: CPU system
972.4 (20.2%) 05:10:11 INFO - TinderboxPrint: CPU user
1,650.8 (34.3%) 05:10:11 INFO - TinderboxPrint: Swap in / out
249,806,848 / 0 05:10:11 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205173248; Write bytes: 204630016; Read time: 15661; Write time: 418 05:10:11 INFO - run-tests - Wall time: 1182s; CPU: 55.0%; Read bytes: 36206592; Write bytes: 300843008; Read time: 230; Write time: 1140 05:10:11 INFO - Running post-run listener: _upload_blobber_files 05:10:11 INFO - Blob upload gear active. 05:10:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:10:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:10:11 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:10:11 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:10:11 INFO - (blobuploader) - INFO - Open directory for files ... 05:10:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:10:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:10:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:13 INFO - (blobuploader) - INFO - Done attempting. 05:10:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:10:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:10:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:14 INFO - (blobuploader) - INFO - Done attempting. 05:10:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:10:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:10:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:14 INFO - (blobuploader) - INFO - Done attempting. 05:10:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:10:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:10:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:17 INFO - (blobuploader) - INFO - Done attempting. 05:10:17 INFO - (blobuploader) - INFO - Iteration through files over. 05:10:17 INFO - Return code: 0 05:10:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:10:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:10:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5757fcf4ff56568e695dcefcdc954bdd711cc23df9fd39d5ea093f7fc48daaaff868eb15365f55a1c0b9e9124f2b75f9ce942cfc4a4f9e1c97c72425011357c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9601cd7a86205d5a47e88242de254acf72653f1433e8b2a4ae419921a6f3e6be667936c68180b783ea1f57612293849c0829af27461d2f75c4f398ee53c87070", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bda816be01dc0d448c021c00ee86f018dac958043311d51bd30246fef8c204d59e44cc4df1f8b165955773740546026b6190f7a5eb528ac0fd6fd252a5733bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/224579f196dc47197fd55becf2f9aef7cc2e3ca243839801c38585a1349fa776016efda43572464cd2d33d68883425bd46cacbb58d481bad52e2c7bb8c890586"} 05:10:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:10:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:10:17 INFO - Contents: 05:10:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5757fcf4ff56568e695dcefcdc954bdd711cc23df9fd39d5ea093f7fc48daaaff868eb15365f55a1c0b9e9124f2b75f9ce942cfc4a4f9e1c97c72425011357c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9601cd7a86205d5a47e88242de254acf72653f1433e8b2a4ae419921a6f3e6be667936c68180b783ea1f57612293849c0829af27461d2f75c4f398ee53c87070", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bda816be01dc0d448c021c00ee86f018dac958043311d51bd30246fef8c204d59e44cc4df1f8b165955773740546026b6190f7a5eb528ac0fd6fd252a5733bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/224579f196dc47197fd55becf2f9aef7cc2e3ca243839801c38585a1349fa776016efda43572464cd2d33d68883425bd46cacbb58d481bad52e2c7bb8c890586"} 05:10:17 INFO - Running post-run listener: copy_logs_to_upload_dir 05:10:17 INFO - Copying logs to upload dir... 05:10:17 INFO - mkdir: /builds/slave/test/build/upload/logs 05:10:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.753825 ========= master_lag: 0.44 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-03 05:10:17.474365) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-03 05:10:17.478038) ========= 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.MwYa7un3Wu/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.LIk796cxLS/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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/Firefox/sha512/5757fcf4ff56568e695dcefcdc954bdd711cc23df9fd39d5ea093f7fc48daaaff868eb15365f55a1c0b9e9124f2b75f9ce942cfc4a4f9e1c97c72425011357c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9601cd7a86205d5a47e88242de254acf72653f1433e8b2a4ae419921a6f3e6be667936c68180b783ea1f57612293849c0829af27461d2f75c4f398ee53c87070", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bda816be01dc0d448c021c00ee86f018dac958043311d51bd30246fef8c204d59e44cc4df1f8b165955773740546026b6190f7a5eb528ac0fd6fd252a5733bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/224579f196dc47197fd55becf2f9aef7cc2e3ca243839801c38585a1349fa776016efda43572464cd2d33d68883425bd46cacbb58d481bad52e2c7bb8c890586"} build_url:https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5757fcf4ff56568e695dcefcdc954bdd711cc23df9fd39d5ea093f7fc48daaaff868eb15365f55a1c0b9e9124f2b75f9ce942cfc4a4f9e1c97c72425011357c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9601cd7a86205d5a47e88242de254acf72653f1433e8b2a4ae419921a6f3e6be667936c68180b783ea1f57612293849c0829af27461d2f75c4f398ee53c87070", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bda816be01dc0d448c021c00ee86f018dac958043311d51bd30246fef8c204d59e44cc4df1f8b165955773740546026b6190f7a5eb528ac0fd6fd252a5733bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/224579f196dc47197fd55becf2f9aef7cc2e3ca243839801c38585a1349fa776016efda43572464cd2d33d68883425bd46cacbb58d481bad52e2c7bb8c890586"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-03 05:10:17.517367) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 05:10:17.517718) ========= 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.MwYa7un3Wu/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.LIk796cxLS/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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.004957 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 05:10:17.566137) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-03 05:10:17.566463) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-03 05:10:17.566768) ========= ========= Total master_lag: 0.58 =========