builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0191 starttime: 1484636528.28 results: success (0) buildid: 20170116214334 builduid: df78fa7ba63b47dc9183d5bfa5b41c91 revision: 3e275d37a06236981bff399b7d7aa0646be3fee7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:08.282419) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:08.282786) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:08.283064) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:08.351544) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:08.351873) ========= 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.i7LCQHSaSm/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.E99NVySbMq/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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-01-16 23:02:08-- 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% 15.1M=0.001s 2017-01-16 23:02:09 (15.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.729751 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:09.107286) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:09.107600) ========= 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.i7LCQHSaSm/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.E99NVySbMq/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.026561 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:09.155467) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:09.155821) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3e275d37a06236981bff399b7d7aa0646be3fee7 --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 3e275d37a06236981bff399b7d7aa0646be3fee7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.i7LCQHSaSm/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.E99NVySbMq/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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-01-16 23:02:09,242 truncating revision to first 12 chars 2017-01-16 23:02:09,242 Setting DEBUG logging. 2017-01-16 23:02:09,242 attempt 1/10 2017-01-16 23:02:09,242 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3e275d37a062?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-16 23:02:09,931 unpacking tar archive at: mozilla-central-3e275d37a062/testing/mozharness/ program finished with exit code 0 elapsedTime=0.971431 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:10.149893) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:10.150163) ========= script_repo_revision: 3e275d37a06236981bff399b7d7aa0646be3fee7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:10.150531) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:10.150793) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-16 23:02:10.170553) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-16 23:02:10.170905) ========= /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.i7LCQHSaSm/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.E99NVySbMq/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 23:02:10 INFO - MultiFileLogger online at 20170116 23:02:10 in /builds/slave/test 23:02:10 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 23:02:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:02:10 INFO - {'append_to_log': False, 23:02:10 INFO - 'base_work_dir': '/builds/slave/test', 23:02:10 INFO - 'blob_upload_branch': 'Firefox', 23:02:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:02:10 INFO - 'branch': 'Firefox', 23:02:10 INFO - 'buildbot_json_path': 'buildprops.json', 23:02:10 INFO - 'config_files': ('talos/mac_config.py',), 23:02:10 INFO - 'default_actions': ('clobber', 23:02:10 INFO - 'read-buildbot-config', 23:02:10 INFO - 'download-and-extract', 23:02:10 INFO - 'populate-webroot', 23:02:10 INFO - 'create-virtualenv', 23:02:10 INFO - 'install', 23:02:10 INFO - 'run-tests'), 23:02:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:02:10 INFO - 'download_minidump_stackwalk': True, 23:02:10 INFO - 'download_symbols': 'ondemand', 23:02:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:02:10 INFO - 'tooltool.py': '/tools/tooltool.py', 23:02:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:02:10 INFO - '/tools/misc-python/virtualenv.py')}, 23:02:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:02:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:02:10 INFO - 'installer_path': 'installer.exe', 23:02:10 INFO - 'log_level': 'info', 23:02:10 INFO - 'log_name': 'talos', 23:02:10 INFO - 'log_to_console': True, 23:02:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:02:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:02:10 INFO - 'opt_config_files': (), 23:02:10 INFO - 'pip_index': False, 23:02:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:02:10 INFO - 'cmd': ('bash', 23:02:10 INFO - '-c', 23:02:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:02:10 INFO - 'enabled': True, 23:02:10 INFO - 'halt_on_failure': False, 23:02:10 INFO - 'name': 'check_screen_resolution'},), 23:02:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:02:10 INFO - 'cmd': ('bash', 23:02:10 INFO - '-c', 23:02:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:02:10 INFO - 'enabled': True, 23:02:10 INFO - 'halt_on_failure': False, 23:02:10 INFO - 'name': 'check_screen_resolution'},), 23:02:10 INFO - 'run_cmd_checks_enabled': True, 23:02:10 INFO - 'sps_profile': False, 23:02:10 INFO - 'sps_profile_interval': 0, 23:02:10 INFO - 'suite': 'dromaeojs-e10s', 23:02:10 INFO - 'system_bits': '32', 23:02:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:02:10 INFO - 'title': 't-yosemite-r7-0191', 23:02:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:02:10 INFO - 'use_talos_json': True, 23:02:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:02:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:02:10 INFO - 'work_dir': 'build'} 23:02:10 INFO - [mozharness: 2017-01-17 07:02:10.375758Z] Running clobber step. 23:02:10 INFO - Running pre-action listener: _resource_record_pre_action 23:02:10 INFO - Running main action method: clobber 23:02:10 INFO - rmtree: /builds/slave/test/build 23:02:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:02:12 INFO - Running post-action listener: _resource_record_post_action 23:02:12 INFO - [mozharness: 2017-01-17 07:02:12.766667Z] Finished clobber step (success) 23:02:12 INFO - [mozharness: 2017-01-17 07:02:12.766914Z] Running read-buildbot-config step. 23:02:12 INFO - Running pre-action listener: _resource_record_pre_action 23:02:12 INFO - Running main action method: read_buildbot_config 23:02:12 INFO - Using buildbot properties: 23:02:12 INFO - { 23:02:12 INFO - "project": "", 23:02:12 INFO - "product": "firefox", 23:02:12 INFO - "script_repo_revision": "production", 23:02:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 23:02:12 INFO - "repository": "", 23:02:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 23:02:12 INFO - "buildid": "20170116214334", 23:02:12 INFO - "pgo_build": "False", 23:02:12 INFO - "basedir": "/builds/slave/test", 23:02:12 INFO - "buildnumber": 60, 23:02:12 INFO - "slavename": "t-yosemite-r7-0191", 23:02:12 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 23:02:12 INFO - "platform": "yosemite_r7", 23:02:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 23:02:12 INFO - "branch": "mozilla-central", 23:02:12 INFO - "repo_path": "mozilla-central", 23:02:12 INFO - "revision": "3e275d37a06236981bff399b7d7aa0646be3fee7", 23:02:12 INFO - "stage_platform": "macosx64", 23:02:12 INFO - "builduid": "df78fa7ba63b47dc9183d5bfa5b41c91", 23:02:12 INFO - "slavebuilddir": "test" 23:02:12 INFO - } 23:02:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 23:02:12 INFO - Running post-action listener: _resource_record_post_action 23:02:12 INFO - [mozharness: 2017-01-17 07:02:12.772643Z] Finished read-buildbot-config step (success) 23:02:12 INFO - [mozharness: 2017-01-17 07:02:12.772837Z] Running download-and-extract step. 23:02:12 INFO - Running pre-action listener: _resource_record_pre_action 23:02:12 INFO - Running main action method: download_and_extract 23:02:12 INFO - mkdir: /builds/slave/test/build/tests 23:02:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:02:12 INFO - trying https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 23:02:12 INFO - Downloading https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 23:02:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 23:02:13 INFO - Downloaded 1199 bytes. 23:02:13 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 23:02:13 INFO - Using the following test package requirements: 23:02:13 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 23:02:13 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:02:13 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 23:02:13 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:02:13 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 23:02:13 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:02:13 INFO - u'jsshell-mac.zip'], 23:02:13 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:02:13 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 23:02:13 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 23:02:13 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:02:13 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 23:02:13 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:02:13 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 23:02:13 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:02:13 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 23:02:13 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:02:13 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 23:02:13 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:02:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 23:02:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:02:13 INFO - Fetch https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 23:02:14 INFO - Expected file size: 16326588 23:02:14 INFO - Obtained file size: 16326588 23:02:21 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:02:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 23:02:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:02:21 INFO - Fetch https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 23:02:22 INFO - Expected file size: 16326588 23:02:22 INFO - Obtained file size: 16326588 23:02:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 23:02:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:02:28 INFO - Fetch https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 23:02:30 INFO - Expected file size: 14403463 23:02:30 INFO - Obtained file size: 14403463 23:02:30 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')]} 23:02:30 INFO - trying https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:02:30 INFO - Downloading https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 23:02:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 23:02:32 INFO - Downloaded 70112883 bytes. 23:02:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:02:32 INFO - mkdir: /builds/slave/test/properties 23:02:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:02:32 INFO - Writing to file /builds/slave/test/properties/build_url 23:02:32 INFO - Contents: 23:02:32 INFO - build_url:https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:02:33 INFO - Running post-action listener: _resource_record_post_action 23:02:33 INFO - Running post-action listener: set_extra_try_arguments 23:02:33 INFO - [mozharness: 2017-01-17 07:02:33.142989Z] Finished download-and-extract step (success) 23:02:33 INFO - [mozharness: 2017-01-17 07:02:33.143117Z] Running populate-webroot step. 23:02:33 INFO - Running pre-action listener: _resource_record_pre_action 23:02:33 INFO - Running main action method: populate_webroot 23:02:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:02:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:02:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:02:33 INFO - '--tppagecycles', 23:02:33 INFO - '7'], 23:02:33 INFO - 'tests': ['tsvgm']}}, 23:02:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:02:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:02:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:02:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:02:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:02:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:02:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:02:33 INFO - 'talos_options': ['--disable-e10s'], 23:02:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:02:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:02:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:02:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:02:33 INFO - 'talos_options': ['--disable-e10s'], 23:02:33 INFO - 'tests': ['damp', 'tps']}, 23:02:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:02:33 INFO - 'tests': ['damp', 'tps']}, 23:02:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:02:33 INFO - 'tests': ['dromaeo_dom']}, 23:02:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:02:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:02:33 INFO - 'tests': ['basic_compositor_video']}, 23:02:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:02:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:02:33 INFO - 'tests': ['a11yr', 23:02:33 INFO - 'ts_paint', 23:02:33 INFO - 'tpaint', 23:02:33 INFO - 'sessionrestore', 23:02:33 INFO - 'sessionrestore_no_auto_restore', 23:02:33 INFO - 'tabpaint']}, 23:02:33 INFO - 'other-e10s': {'tests': ['a11yr', 23:02:33 INFO - 'ts_paint', 23:02:33 INFO - 'tpaint', 23:02:33 INFO - 'sessionrestore', 23:02:33 INFO - 'sessionrestore_no_auto_restore', 23:02:33 INFO - 'tabpaint']}, 23:02:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:02:33 INFO - 'ts_paint', 23:02:33 INFO - 'tpaint', 23:02:33 INFO - 'sessionrestore', 23:02:33 INFO - 'sessionrestore_no_auto_restore', 23:02:33 INFO - 'tabpaint']}, 23:02:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:02:33 INFO - 'ts_paint', 23:02:33 INFO - 'tpaint', 23:02:33 INFO - 'sessionrestore', 23:02:33 INFO - 'sessionrestore_no_auto_restore', 23:02:33 INFO - 'tabpaint']}, 23:02:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:02:33 INFO - 'tests': ['a11yr', 23:02:33 INFO - 'ts_paint', 23:02:33 INFO - 'tpaint', 23:02:33 INFO - 'sessionrestore', 23:02:33 INFO - 'sessionrestore_no_auto_restore', 23:02:33 INFO - 'tabpaint']}, 23:02:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:02:33 INFO - 'tests': ['a11yr', 23:02:33 INFO - 'ts_paint', 23:02:33 INFO - 'tpaint', 23:02:33 INFO - 'sessionrestore', 23:02:33 INFO - 'sessionrestore_no_auto_restore', 23:02:33 INFO - 'tabpaint']}, 23:02:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:02:33 INFO - 'tests': ['tsvgx', 23:02:33 INFO - 'tsvgr_opacity', 23:02:33 INFO - 'tart', 23:02:33 INFO - 'tscrollx', 23:02:33 INFO - 'cart', 23:02:33 INFO - 'tsvg_static']}, 23:02:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:02:33 INFO - 'tsvgr_opacity', 23:02:33 INFO - 'tart', 23:02:33 INFO - 'tscrollx', 23:02:33 INFO - 'cart', 23:02:33 INFO - 'tsvg_static']}, 23:02:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:02:33 INFO - 'talos_options': ['--disable-e10s'], 23:02:33 INFO - 'tests': ['tp5o']}, 23:02:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:02:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:02:33 INFO - 'talos_options': ['--disable-e10s', 23:02:33 INFO - '--xperf_path', 23:02:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:02:33 INFO - 'tests': ['tp5n']}, 23:02:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:02:33 INFO - 'talos_options': ['--xperf_path', 23:02:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:02:33 INFO - 'tests': ['tp5n']}}, 23:02:33 INFO - 'talos.zip': {'path': '', 23:02:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:02:33 INFO - Running post-action listener: _resource_record_post_action 23:02:33 INFO - [mozharness: 2017-01-17 07:02:33.161301Z] Finished populate-webroot step (success) 23:02:33 INFO - [mozharness: 2017-01-17 07:02:33.161434Z] Running create-virtualenv step. 23:02:33 INFO - Running pre-action listener: _resource_record_pre_action 23:02:33 INFO - Running main action method: create_virtualenv 23:02:33 INFO - Creating virtualenv /builds/slave/test/build/venv 23:02:33 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 23:02:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:02:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:02:33 INFO - Using real prefix '/tools/python27' 23:02:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:02:34 INFO - Installing distribute.............................................................................................................................................................................................done. 23:02:36 INFO - Installing pip.................done. 23:02:36 INFO - Return code: 0 23:02:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:02:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:02:37 INFO - Reading from file tmpfile_stdout 23:02:37 INFO - Output received: 23:02:37 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) 23:02:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:02:37 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')]} 23:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:37 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')]} 23:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:37 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 0x106924b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106927768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc919cb51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702d30>, '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 0x106629db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066f7be8>, '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.i7LCQHSaSm/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.E99NVySbMq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 23:02:37 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 23:02:37 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 23:02:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i7LCQHSaSm/Render', 23:02:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:37 INFO - 'HOME': '/Users/cltbld', 23:02:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:37 INFO - 'LOGNAME': 'cltbld', 23:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:37 INFO - 'MOZ_NO_REMOTE': '1', 23:02:37 INFO - 'NO_EM_RESTART': '1', 23:02:37 INFO - 'PAGER': '/bin/cat', 23:02:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:37 INFO - 'PWD': '/builds/slave/test', 23:02:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:37 INFO - 'SHELL': '/bin/bash', 23:02:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E99NVySbMq/Listeners', 23:02:37 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 23:02:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:37 INFO - 'USER': 'cltbld', 23:02:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:37 INFO - 'XPC_FLAGS': '0x0', 23:02:37 INFO - 'XPC_SERVICE_NAME': '0', 23:02:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:37 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 23:02:37 INFO - Cleaning up... 23:02:37 INFO - Return code: 0 23:02:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:02:37 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')]} 23:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:37 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')]} 23:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:37 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 0x106924b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106927768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc919cb51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702d30>, '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 0x106629db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066f7be8>, '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.i7LCQHSaSm/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.E99NVySbMq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 23:02:37 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 23:02:37 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 23:02:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i7LCQHSaSm/Render', 23:02:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:37 INFO - 'HOME': '/Users/cltbld', 23:02:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:37 INFO - 'LOGNAME': 'cltbld', 23:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:37 INFO - 'MOZ_NO_REMOTE': '1', 23:02:37 INFO - 'NO_EM_RESTART': '1', 23:02:37 INFO - 'PAGER': '/bin/cat', 23:02:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:37 INFO - 'PWD': '/builds/slave/test', 23:02:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:37 INFO - 'SHELL': '/bin/bash', 23:02:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E99NVySbMq/Listeners', 23:02:37 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 23:02:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:37 INFO - 'USER': 'cltbld', 23:02:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:37 INFO - 'XPC_FLAGS': '0x0', 23:02:37 INFO - 'XPC_SERVICE_NAME': '0', 23:02:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:37 INFO - Downloading/unpacking psutil>=3.1.1 23:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:02:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:02:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:02:41 INFO - Installing collected packages: psutil 23:02:41 INFO - Running setup.py install for psutil 23:02:41 INFO - building 'psutil._psutil_osx' extension 23:02:41 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 23:02:42 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 23:02:42 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 23:02:42 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 23:02:42 INFO - building 'psutil._psutil_posix' extension 23:02:42 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 23:02:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:02:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:02:42 INFO - ^ 23:02:42 INFO - 1 warning generated. 23:02:42 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 23:02:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:02:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:02:42 INFO - Successfully installed psutil 23:02:42 INFO - Cleaning up... 23:02:42 INFO - Return code: 0 23:02:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:02:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:02:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:42 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 0x106924b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106927768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc919cb51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702d30>, '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 0x106629db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066f7be8>, '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.i7LCQHSaSm/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.E99NVySbMq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 23:02:42 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 23:02:42 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 23:02:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i7LCQHSaSm/Render', 23:02:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:42 INFO - 'HOME': '/Users/cltbld', 23:02:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:42 INFO - 'LOGNAME': 'cltbld', 23:02:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:42 INFO - 'MOZ_NO_REMOTE': '1', 23:02:42 INFO - 'NO_EM_RESTART': '1', 23:02:42 INFO - 'PAGER': '/bin/cat', 23:02:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:42 INFO - 'PWD': '/builds/slave/test', 23:02:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:42 INFO - 'SHELL': '/bin/bash', 23:02:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E99NVySbMq/Listeners', 23:02:42 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 23:02:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:42 INFO - 'USER': 'cltbld', 23:02:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:42 INFO - 'XPC_FLAGS': '0x0', 23:02:42 INFO - 'XPC_SERVICE_NAME': '0', 23:02:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:02:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:02:46 INFO - Installing collected packages: mozsystemmonitor 23:02:46 INFO - Running setup.py install for mozsystemmonitor 23:02:46 INFO - Successfully installed mozsystemmonitor 23:02:46 INFO - Cleaning up... 23:02:46 INFO - Return code: 0 23:02:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:02:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:02:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106924b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106927768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc919cb51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702d30>, '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 0x106629db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066f7be8>, '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.i7LCQHSaSm/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.E99NVySbMq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 23:02:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:02:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:02:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i7LCQHSaSm/Render', 23:02:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:46 INFO - 'HOME': '/Users/cltbld', 23:02:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:46 INFO - 'LOGNAME': 'cltbld', 23:02:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:46 INFO - 'MOZ_NO_REMOTE': '1', 23:02:46 INFO - 'NO_EM_RESTART': '1', 23:02:46 INFO - 'PAGER': '/bin/cat', 23:02:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:46 INFO - 'PWD': '/builds/slave/test', 23:02:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:46 INFO - 'SHELL': '/bin/bash', 23:02:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E99NVySbMq/Listeners', 23:02:46 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 23:02:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:46 INFO - 'USER': 'cltbld', 23:02:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:46 INFO - 'XPC_FLAGS': '0x0', 23:02:46 INFO - 'XPC_SERVICE_NAME': '0', 23:02:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:47 INFO - Downloading/unpacking jsonschema==2.5.1 23:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:02:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:50 INFO - Downloading functools32-3.2.3-2.tar.gz 23:02:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:02:50 INFO - warning: no files found matching '*.txt' 23:02:50 INFO - no previously-included directories found matching 'build' 23:02:50 INFO - no previously-included directories found matching 'dist' 23:02:50 INFO - no previously-included directories found matching '.git*' 23:02:50 INFO - Installing collected packages: jsonschema, functools32 23:02:50 INFO - Running setup.py install for functools32 23:02:50 INFO - warning: no files found matching '*.txt' 23:02:50 INFO - no previously-included directories found matching 'build' 23:02:50 INFO - no previously-included directories found matching 'dist' 23:02:50 INFO - no previously-included directories found matching '.git*' 23:02:50 INFO - Successfully installed jsonschema functools32 23:02:50 INFO - Cleaning up... 23:02:50 INFO - Return code: 0 23:02:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:02:50 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')]} 23:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:50 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')]} 23:02:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:50 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 0x106924b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106927768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc919cb51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702d30>, '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 0x106629db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066f7be8>, '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.i7LCQHSaSm/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.E99NVySbMq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 23:02:50 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 23:02:50 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 23:02:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i7LCQHSaSm/Render', 23:02:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:50 INFO - 'HOME': '/Users/cltbld', 23:02:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:50 INFO - 'LOGNAME': 'cltbld', 23:02:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:50 INFO - 'MOZ_NO_REMOTE': '1', 23:02:50 INFO - 'NO_EM_RESTART': '1', 23:02:50 INFO - 'PAGER': '/bin/cat', 23:02:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:50 INFO - 'PWD': '/builds/slave/test', 23:02:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:50 INFO - 'SHELL': '/bin/bash', 23:02:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E99NVySbMq/Listeners', 23:02:50 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 23:02:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:50 INFO - 'USER': 'cltbld', 23:02:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:50 INFO - 'XPC_FLAGS': '0x0', 23:02:50 INFO - 'XPC_SERVICE_NAME': '0', 23:02:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:02:50 INFO - Cleaning up... 23:02:50 INFO - Return code: 0 23:02:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:02:50 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')]} 23:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:50 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')]} 23:02:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:50 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 0x106924b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106927768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc919cb51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702d30>, '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 0x106629db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066f7be8>, '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.i7LCQHSaSm/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.E99NVySbMq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 23:02:50 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 23:02:50 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 23:02:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i7LCQHSaSm/Render', 23:02:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:50 INFO - 'HOME': '/Users/cltbld', 23:02:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:50 INFO - 'LOGNAME': 'cltbld', 23:02:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:50 INFO - 'MOZ_NO_REMOTE': '1', 23:02:50 INFO - 'NO_EM_RESTART': '1', 23:02:50 INFO - 'PAGER': '/bin/cat', 23:02:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:50 INFO - 'PWD': '/builds/slave/test', 23:02:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:50 INFO - 'SHELL': '/bin/bash', 23:02:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E99NVySbMq/Listeners', 23:02:50 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 23:02:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:50 INFO - 'USER': 'cltbld', 23:02:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:50 INFO - 'XPC_FLAGS': '0x0', 23:02:50 INFO - 'XPC_SERVICE_NAME': '0', 23:02:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:51 INFO - Downloading/unpacking blobuploader==1.2.4 23:02: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 23:02: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 23:02: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 23:02: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 23:02: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 23:02: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 23:02:53 INFO - Downloading blobuploader-1.2.4.tar.gz 23:02:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:02:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:02:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:02:54 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 23:02:54 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 23:02:54 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 23:02:54 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 23:02:54 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 23:02:54 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 23:02:54 INFO - Downloading docopt-0.6.1.tar.gz 23:02:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:02:55 INFO - Installing collected packages: blobuploader, requests, docopt 23:02:55 INFO - Running setup.py install for blobuploader 23:02:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:02:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:02:55 INFO - Running setup.py install for requests 23:02:55 INFO - Running setup.py install for docopt 23:02:55 INFO - Successfully installed blobuploader requests docopt 23:02:55 INFO - Cleaning up... 23:02:55 INFO - Return code: 0 23:02:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:02:55 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')]} 23:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:55 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')]} 23:02:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:55 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 0x106924b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106927768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc919cb51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702d30>, '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 0x106629db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066f7be8>, '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.i7LCQHSaSm/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.E99NVySbMq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 23:02:55 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 23:02:55 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 23:02:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i7LCQHSaSm/Render', 23:02:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:55 INFO - 'HOME': '/Users/cltbld', 23:02:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:55 INFO - 'LOGNAME': 'cltbld', 23:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:55 INFO - 'MOZ_NO_REMOTE': '1', 23:02:55 INFO - 'NO_EM_RESTART': '1', 23:02:55 INFO - 'PAGER': '/bin/cat', 23:02:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:55 INFO - 'PWD': '/builds/slave/test', 23:02:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:55 INFO - 'SHELL': '/bin/bash', 23:02:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E99NVySbMq/Listeners', 23:02:55 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 23:02:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:55 INFO - 'USER': 'cltbld', 23:02:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:55 INFO - 'XPC_FLAGS': '0x0', 23:02:55 INFO - 'XPC_SERVICE_NAME': '0', 23:02:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:02:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-tCFiCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:02:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-fHiX1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:02:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-p8pvIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:02:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-k1FENW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:02:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-XOvohX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:02:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-AzpZV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:02:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-MvkNfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:02:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-AzAFmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:02:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-oc6rIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:02:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-qgrqZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:02:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-EEE924-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:02:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-XGeWJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:02:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-kCTGwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:02:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-AIvJ61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:02:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-eAJLiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:02:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-PPlmOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:02:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-abz13D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:02:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:02:57 INFO - Running setup.py install for manifestparser 23:02:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:02:58 INFO - Running setup.py install for mozcrash 23:02:58 INFO - Running setup.py install for mozdebug 23:02:58 INFO - Running setup.py install for mozdevice 23:02:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:02:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:02:58 INFO - Running setup.py install for mozfile 23:02:58 INFO - Running setup.py install for mozhttpd 23:02:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:02:58 INFO - Running setup.py install for mozinfo 23:02:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:02:59 INFO - Running setup.py install for mozInstall 23:02:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:02:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:02:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:02:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:02:59 INFO - Running setup.py install for mozleak 23:02:59 INFO - Running setup.py install for mozlog 23:02:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:02:59 INFO - Running setup.py install for moznetwork 23:02:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:02:59 INFO - Running setup.py install for mozprocess 23:02:59 INFO - Running setup.py install for mozprofile 23:02:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:02:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:02:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:02:59 INFO - Running setup.py install for mozrunner 23:03:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:03:00 INFO - Running setup.py install for mozscreenshot 23:03:00 INFO - Running setup.py install for moztest 23:03:00 INFO - Running setup.py install for mozversion 23:03:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:03:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:03:00 INFO - Cleaning up... 23:03:00 INFO - Return code: 0 23:03:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:03:00 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')]} 23:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:00 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')]} 23:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:00 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 0x106924b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106927768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc919cb51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702d30>, '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 0x106629db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066f7be8>, '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.i7LCQHSaSm/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.E99NVySbMq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 23:03:00 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 23:03:00 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 23:03:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i7LCQHSaSm/Render', 23:03:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:03:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:03:00 INFO - 'HOME': '/Users/cltbld', 23:03:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:03:00 INFO - 'LOGNAME': 'cltbld', 23:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:00 INFO - 'MOZ_NO_REMOTE': '1', 23:03:00 INFO - 'NO_EM_RESTART': '1', 23:03:00 INFO - 'PAGER': '/bin/cat', 23:03:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:00 INFO - 'PWD': '/builds/slave/test', 23:03:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:03:00 INFO - 'SHELL': '/bin/bash', 23:03:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E99NVySbMq/Listeners', 23:03:00 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 23:03:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:03:00 INFO - 'USER': 'cltbld', 23:03:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:03:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:00 INFO - 'XPC_FLAGS': '0x0', 23:03:00 INFO - 'XPC_SERVICE_NAME': '0', 23:03:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:03:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:03:00 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-ZSub2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:03:00 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)) 23:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:03:00 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Pmn5TB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:03:01 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)) 23:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:03:01 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-SUMW3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:03:01 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)) 23:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:03:01 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-NBWGJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:03:01 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)) 23:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:03:01 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-HYSanh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:03:01 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)) 23:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:03:01 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-ZeynTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:03:01 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)) 23:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:03:01 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-7IcvXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:03:01 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)) 23:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:03:01 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-FMQxzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:03:01 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)) 23:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:03:01 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-AyGklo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:03:01 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)) 23:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:03:01 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-adynAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:03:01 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)) 23:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:03:01 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-9VUlXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:03:02 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)) 23:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:03:02 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-l3pP7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:03:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:03:02 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-4dUIR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:03:02 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)) 23:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:03:02 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-nMCWXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:03:02 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)) 23:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:03:02 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-1BmcDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:03:02 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)) 23:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:03:02 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-lOLad6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:03:02 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)) 23:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:03:02 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-xvceBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:03:02 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)) 23:03:02 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)) 23:03:02 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)) 23:03:02 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)) 23:03:02 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)) 23:03:02 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)) 23:03:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:03:02 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 23:03:02 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 23:03:02 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 23:03:02 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 23:03:02 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 23:03:02 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 23:03:05 INFO - Downloading blessings-1.6.tar.gz 23:03:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:03:05 INFO - Installing collected packages: blessings 23:03:05 INFO - Running setup.py install for blessings 23:03:05 INFO - Successfully installed blessings 23:03:05 INFO - Cleaning up... 23:03:05 INFO - Return code: 0 23:03:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:03:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:03:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:03:05 INFO - Reading from file tmpfile_stdout 23:03:05 INFO - Current package versions: 23:03:05 INFO - blessings == 1.6 23:03:05 INFO - blobuploader == 1.2.4 23:03:05 INFO - docopt == 0.6.1 23:03:05 INFO - functools32 == 3.2.3-2 23:03:05 INFO - jsonschema == 2.5.1 23:03:05 INFO - manifestparser == 1.1 23:03:05 INFO - mozInstall == 1.12 23:03:05 INFO - mozcrash == 1.0 23:03:05 INFO - mozdebug == 0.1 23:03:05 INFO - mozdevice == 0.49 23:03:05 INFO - mozfile == 1.2 23:03:05 INFO - mozhttpd == 0.7 23:03:05 INFO - mozinfo == 0.9 23:03:05 INFO - mozleak == 0.1 23:03:05 INFO - mozlog == 3.4 23:03:05 INFO - moznetwork == 0.27 23:03:05 INFO - mozprocess == 0.24 23:03:05 INFO - mozprofile == 0.28 23:03:05 INFO - mozrunner == 6.13 23:03:05 INFO - mozscreenshot == 0.1 23:03:05 INFO - mozsystemmonitor == 0.3 23:03:05 INFO - moztest == 0.8 23:03:05 INFO - mozversion == 1.4 23:03:05 INFO - psutil == 3.1.1 23:03:05 INFO - requests == 1.2.3 23:03:05 INFO - wsgiref == 0.1.2 23:03:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:03:05 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')]} 23:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:05 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')]} 23:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:05 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 0x106924b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106927768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc919cb51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702d30>, '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 0x106629db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066f7be8>, '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.i7LCQHSaSm/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.E99NVySbMq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 23:03:05 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 23:03:05 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 23:03:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i7LCQHSaSm/Render', 23:03:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:03:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:03:05 INFO - 'HOME': '/Users/cltbld', 23:03:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:03:05 INFO - 'LOGNAME': 'cltbld', 23:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:05 INFO - 'MOZ_NO_REMOTE': '1', 23:03:05 INFO - 'NO_EM_RESTART': '1', 23:03:05 INFO - 'PAGER': '/bin/cat', 23:03:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:05 INFO - 'PWD': '/builds/slave/test', 23:03:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:03:05 INFO - 'SHELL': '/bin/bash', 23:03:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E99NVySbMq/Listeners', 23:03:05 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 23:03:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:03:05 INFO - 'USER': 'cltbld', 23:03:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:03:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:05 INFO - 'XPC_FLAGS': '0x0', 23:03:05 INFO - 'XPC_SERVICE_NAME': '0', 23:03:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:06 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)) 23:03:06 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)) 23:03:06 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)) 23:03:06 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)) 23:03:06 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)) 23:03:06 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)) 23:03:06 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)) 23:03:06 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)) 23:03:06 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)) 23:03:06 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)) 23:03:06 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)) 23:03:06 INFO - Cleaning up... 23:03:06 INFO - Return code: 0 23:03:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:03:06 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')]} 23:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:06 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')]} 23:03:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:06 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 0x106924b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106927768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc919cb51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702d30>, '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 0x106629db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066f7be8>, '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.i7LCQHSaSm/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.E99NVySbMq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 23:03:06 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 23:03:06 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 23:03:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i7LCQHSaSm/Render', 23:03:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:03:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:03:06 INFO - 'HOME': '/Users/cltbld', 23:03:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:03:06 INFO - 'LOGNAME': 'cltbld', 23:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:06 INFO - 'MOZ_NO_REMOTE': '1', 23:03:06 INFO - 'NO_EM_RESTART': '1', 23:03:06 INFO - 'PAGER': '/bin/cat', 23:03:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:03:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:06 INFO - 'PWD': '/builds/slave/test', 23:03:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:03:06 INFO - 'SHELL': '/bin/bash', 23:03:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E99NVySbMq/Listeners', 23:03:06 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 23:03:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:03:06 INFO - 'USER': 'cltbld', 23:03:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:03:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:06 INFO - 'XPC_FLAGS': '0x0', 23:03:06 INFO - 'XPC_SERVICE_NAME': '0', 23:03:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:03:06 INFO - Cleaning up... 23:03:06 INFO - Return code: 0 23:03:06 INFO - Running post-action listener: _resource_record_post_action 23:03:06 INFO - Running post-action listener: _start_resource_monitoring 23:03:06 INFO - Starting resource monitoring. 23:03:06 INFO - [mozharness: 2017-01-17 07:03:06.504709Z] Finished create-virtualenv step (success) 23:03:06 INFO - [mozharness: 2017-01-17 07:03:06.505667Z] Running install step. 23:03:06 INFO - Running pre-action listener: _resource_record_pre_action 23:03:06 INFO - Running main action method: install 23:03:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:03:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:03:06 INFO - Reading from file tmpfile_stdout 23:03:06 INFO - Detecting whether we're running mozinstall >=1.0... 23:03:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:03:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:03:06 INFO - Reading from file tmpfile_stdout 23:03:06 INFO - Output received: 23:03:06 INFO - Usage: mozinstall [options] installer 23:03:06 INFO - Options: 23:03:06 INFO - -h, --help show this help message and exit 23:03:06 INFO - -d DEST, --destination=DEST 23:03:06 INFO - Directory to install application into. [default: 23:03:06 INFO - "/builds/slave/test"] 23:03:06 INFO - --app=APP Application being installed. [default: firefox] 23:03:06 INFO - mkdir: /builds/slave/test/build/application 23:03:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:03:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:03:28 INFO - Reading from file tmpfile_stdout 23:03:28 INFO - Output received: 23:03:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:03:28 INFO - Running post-action listener: _resource_record_post_action 23:03:28 INFO - [mozharness: 2017-01-17 07:03:28.745622Z] Finished install step (success) 23:03:28 INFO - [mozharness: 2017-01-17 07:03:28.745763Z] Running run-tests step. 23:03:28 INFO - Running pre-action listener: _resource_record_pre_action 23:03:28 INFO - Running main action method: run_tests 23:03:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:03:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:03:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:03:28 INFO - 2017-01-16 23:03:28.760 screenresolution[1633:8433] starting screenresolution argv=screenresolution get 23:03:28 INFO - 2017-01-16 23:03:28.763 screenresolution[1633:8433] Display 0: 1600x1200x32@60 23:03:28 INFO - 2017-01-16 23:03:28.769 screenresolution[1634:8436] starting screenresolution argv=screenresolution list 23:03:28 INFO - Available Modes on Display 0 23:03:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:03:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:03:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:03:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:03:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:03:28 INFO - Intel Iris: 23:03:28 INFO - Chipset Model: Intel Iris 23:03:28 INFO - Type: GPU 23:03:28 INFO - Bus: Built-In 23:03:28 INFO - VRAM (Dynamic, Max): 1536 MB 23:03:28 INFO - Vendor: Intel (0x8086) 23:03:28 INFO - Device ID: 0x0a2e 23:03:28 INFO - Revision ID: 0x0009 23:03:28 INFO - Displays: 23:03:28 INFO - Display: 23:03:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:03:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:03:28 INFO - Display Serial Number: 1600x1200 60 23:03:28 INFO - Main Display: Yes 23:03:28 INFO - Mirror: Off 23:03:28 INFO - Online: Yes 23:03:28 INFO - Rotation: Supported 23:03:28 INFO - Return code: 0 23:03:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:03:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:03:29 INFO - Python 2.7.3 23:03:29 INFO - Return code: 0 23:03:29 INFO - grabbing minidump binary from tooltool 23:03: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')]} 23:03: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 0x106702d30>, '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 0x106629db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066f7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:03: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 23:03: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 23:03: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 23:03:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:03:29 INFO - Return code: 0 23:03:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:03:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:03:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:03:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:03:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:03: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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--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 23:03: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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0191 --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 23:03:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i7LCQHSaSm/Render', 23:03:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:03:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:03:29 INFO - 'HOME': '/Users/cltbld', 23:03:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:03:29 INFO - 'LOGNAME': 'cltbld', 23:03:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:03:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:29 INFO - 'MOZ_NO_REMOTE': '1', 23:03:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:03:29 INFO - 'NO_EM_RESTART': '1', 23:03:29 INFO - 'PAGER': '/bin/cat', 23:03:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:29 INFO - 'PWD': '/builds/slave/test', 23:03:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:03:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:03:29 INFO - 'SHELL': '/bin/bash', 23:03:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E99NVySbMq/Listeners', 23:03:29 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 23:03:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:03:29 INFO - 'USER': 'cltbld', 23:03:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:03:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:29 INFO - 'XPC_FLAGS': '0x0', 23:03:29 INFO - 'XPC_SERVICE_NAME': '0', 23:03:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:03: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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--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 23:03:29 INFO - mozversion application_buildid: 20170116214334 23:03:29 INFO - mozversion application_changeset: 3e275d37a06236981bff399b7d7aa0646be3fee7 23:03:29 INFO - mozversion application_display_name: Nightly 23:03:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:03:29 INFO - mozversion application_name: Firefox 23:03:29 INFO - mozversion application_remotingname: firefox 23:03:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:03:29 INFO - mozversion application_vendor: Mozilla 23:03:29 INFO - mozversion application_version: 53.0a1 23:03:29 INFO - mozversion platform_buildid: 20170116214334 23:03:29 INFO - mozversion platform_changeset: 3e275d37a06236981bff399b7d7aa0646be3fee7 23:03:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:03:29 INFO - mozversion platform_version: 53.0a1 23:03:29 INFO - using testdate: 1484636609 23:03:29 INFO - actual date: 1484636609 23:03:29 INFO - starting webserver on 'localhost:49241' 23:03:29 INFO - SUITE-START | Running 2 tests 23:03:29 INFO - TEST-START | dromaeo_css 23:03:29 INFO - operating with platform_type : mac_ 23:03:29 INFO - Initialising browser for dromaeo_css test... 23:03:29 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpEOmp3Z/profile http://localhost:49241/getInfo.html) 23:03:32 INFO - PROCESS | 1644 | 2017-01-16 23:03:32.393 plugin-container[1647:8601] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 23:03:32 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:03:32 INFO - PROCESS | 1644 | 2017-01-16 23:03:32.396 plugin-container[1647:8601] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 23:03:32 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:03:32 INFO - PROCESS | 1644 | 2017-01-16 23:03:32.396 plugin-container[1647:8601] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:03:32 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 23:03:32 INFO - PROCESS | 1644 | colorDepth:24 23:03:32 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 23:03:32 INFO - PROCESS | 1644 | __metrics 23:03:32 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3253: TypeError: this.tabs is undefined 23:03:33 INFO - TEST-INFO | 1644: exit 0 23:03:33 INFO - Browser initialized. 23:03:33 INFO - Running cycle 1/1 for dromaeo_css test... 23:03:33 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpEOmp3Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:03:35 INFO - PROCESS | 1648 | 2017-01-16 23:03:35.656 plugin-container[1649:8721] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 23:03:35 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:03:35 INFO - PROCESS | 1648 | 2017-01-16 23:03:35.656 plugin-container[1649:8721] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 23:03:35 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:03:35 INFO - PROCESS | 1648 | 2017-01-16 23:03:35.656 plugin-container[1649:8721] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:03:45 INFO - PROCESS | 1648 | RSS: Main: 233488384 23:03:45 INFO - PROCESS | 1648 | 23:06:49 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 23:06:50 INFO - PROCESS | 1648 | RSS: Main: 252489728 23:06:50 INFO - PROCESS | 1648 | 23:08:33 INFO - PROCESS | 1648 | ************************* 23:08:33 INFO - PROCESS | 1648 | A coding exception was thrown and uncaught in a Task. 23:08:33 INFO - PROCESS | 1648 | 23:08:33 ERROR - PROCESS | 1648 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:08:33 INFO - PROCESS | 1648 | Full stack: 23:08:33 INFO - PROCESS | 1648 | ************************* 23:09:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 23:09:53 INFO - PROCESS | 1648 | RSS: Main: 263315456 23:09:53 INFO - PROCESS | 1648 | 23:12:55 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 23:12:56 INFO - PROCESS | 1648 | RSS: Main: 271794176 23:12:56 INFO - PROCESS | 1648 | 23:15:58 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 23:15:58 INFO - PROCESS | 1648 | RSS: Main: 268083200 23:15:58 INFO - PROCESS | 1648 | 23:19:01 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 23:19:02 INFO - PROCESS | 1648 | RSS: Main: 272650240 23:19:02 INFO - PROCESS | 1648 | 23:22:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 23:22:05 INFO - PROCESS | 1648 | RSS: Main: 275804160 23:22:05 INFO - PROCESS | 1648 | 23:22:05 INFO - PROCESS | 1648 | __start_tp_report 23:22:05 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 23:22:05 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 23:22:05 INFO - PROCESS | 1648 | |i|pagename|runs| 23:22:05 INFO - PROCESS | 1648 | |0;dojo.html;3056.9082927512154;3342.6323104458506;3379.1214284286084;3406.012256445631;3421.7262618990485;5601.6358936669085;5657.377688454267;5693.800716974907;5700.657960522366;5782.739776710047;5502;5573.996680597491;5585.776568937237;5690.2887139107615;5712.914786103073;5134.691918484884;5167.844964651066;5227.320448341729;5254.290670759437;5254.710990895499;5170.819021334254;5307.028064284013;5310.0441920454305;5326.094563924123;5330.733463326837;5259.421463638997;5312;5312.548433383147;5375.354957405098;5376.78492860287;4804.687695299829;4985.152524070881;5034.3455350804525;5144.485551444863;5152.3044389007755;5132.255822905695;5141.974290128563;5270;5289.127293996486;5308.46915308466;4476.306172543228;5046.924296135561;5233.842984710464;5676.545876329884;5835.299764028301;5509.614326997112;6221.035739460391;6271.153394291758;6271.592346497463;6358.237011558643;5688.146777983295;6167.383261673796;6220.1602783624085;6245.843853903643;6277.937220627781;5842.678652674102;5858.853528661817;5866.032104702762;5869.413058694139;5886.852828679232;5376.623636345496;5919.496842768327;6247.250329960343;6248.156498872685;6277.905831412532;5839.941600583939;6015.39846015395;6077.301110372364;6121.605402471149;6121.877562448726;6110.649890384107;6170.167027451326;6210.968945155336;6254.030625253093;6277.403646653652;6092.253264242548;6253.937460625336;6265.342139075423;6272.435480806749;6281.309056003836;6125.571219981323;6147.784827531015;6193.194884664965;6236.469900058546;6294.685265736786;4923.310736496894;4972.055309491185;4981.850544483671;5017.147084995559;5021.8242361517905;4396.428464299621;4482.708623939434;4508.571685689854;4514.232580461329;4548.818047278071;4697.530246975343;5751.913721294184;5782.277215348082;5818.5636077294375;5862.179294898718;6135.417135372132;6150;6265.216847894013;6282.780102696383;6307.211598550181;5756.136579513107;5892.263467066617;5915.053591425351;5965.194698715618;6011.909821352683;5430;5431.945680543223;5439.918401223985;5441.292631957899;5441.972790136024;5711.143328500758;5863.2670916135485;6359.936400636119;6372.362763723683;6385;6125.784909797561;6128.075379514812;6128.136482154469;6155.1075094112;6179.2584889811715;6283.96858015707;6323.146375239284;6350.872982540416;6361.236651601837;6374.904376434264;6169.84575385619;6175.3824617539685;6176.969115154396;6191.876162476815;6202.596831205957;5907.616004959664;6105.732692890381;6106.45041946227;6150.753969841336;6151.753929842936;6052.546059045501;6118.040554665315;6155.815325540241;6157.4458298753325;6163.96918015425;5952.107183922446;6122.38776122402;6151.876962460815;6152.446279834837;6156.015037593964;5726.398728133652;5805.970970145122;5929.347771745019;5959;5963.9105413417965;5114.974425127851;6080.391960803884;6116.153172518083;6134.2332208473945;6176.907346389898;5094.363204599425;5666.518345940642;5715.9142612860005;6057.212562366864;6088.360722124113 23:22:05 INFO - PROCESS | 1648 | |1;ext.html;462.8865927847676;480.4546839337351;496.21102447109087;496.811211739539;505.4137200847018;36374;40073.1985360293;40098.39852402213;42000.94997625061;42085.789571052104;40811.38782918259;40944;41301.96745081375;41316;42077.57922420775;8267.379946504007;8464.873026904601;9220.8616870747;9382;9407.388519746195;10964.506597203104;11288.604898828542;11295.491702873349;11472.369019703912;11476.254043487188;11298.378589177591;11359.943200283988;11370.488328025218;11389.487473063691;11414.31514109152;10017.999999999964;10788.406637634927;11260.268082574647;11313.3212007279;11327.830082548768;11157.330560166416;11174.720631984184;11184.944075279653;11221.158413119048;11227.27022743519;32238.355232895447;39024.02439938996;39372.80313598413;39435.802820986;39506;3929.5088113985753;4029.717919745619;4032.193561287754;4079.0414252650603;4110.753354798721;27149.72850271492;27609;27651.58522622162;27691.307717307027;27692.446151077067;2633.7234590367925;3097.3650401667605;3188.1073299476184;3265.6407795142513;3287.424700677396;392258;464213;469162;469196;470266.99999999657;54267.45732542703;54602.45397546053;54613;54924.17613735797;54949.62625934304;55412;55959;56128.75484980637;56515.71742141345;56758.71620641871;15029.849701503064;15085;15326.770098448533;15336.923315383352;15346.079235245697;10707.785844283071;10924.781504370027;11826.822597661041;11835.763284734257;12001.339926304137;17131.22909469077;17239;17317.22072506715;17395;17448.825511744973;7332.670029848669;7427.7771666850085;7443.478956473104;7529.736459223901;7573.697052117964;740.8110931712487;873.9388242823069;874.6457684637732;884.7478468636408;905.6965916418073;27825;29318.26704332409;29591;30187.849060754994;30741.538876916864;1066.706655669697;1072.4423299884018;1103.376592225406;1108.9279196852178;1188.358286525284;14025.97660516755;15312.617184570476;15316.713695580014;15515;15786.526404207892;564313;624019.8799006067;630398;633305;634358;57243.42756572464;57261.71369143128;57700;57964.130538041965;58179.709101454224;1274.6112435707198;1396.5391420831102;1443.0662911698375;1457.774045023002;1464.61187785237;28989.565156522247;28999;29240.853795730884;29550;29724.851375742983;36320;48279;48625.513744863514;48676.5132348672;48820.5117948816;517307;517524;520086;520936;523136;20319.4920126998;20688.896555517127;22046.685098064474;23108.884455577616;24795.264331496623;30737.692623074377;42071.15857682768;42574;44547.33179002253;44613.330800038675;35420.291594168484;35563;35564;36616.63383366239;36811;28749;31279.06162815119;35108.824455877555;35704.64295357013;35765.82117089398;28786.7121328784;34534.13664658403;34655.480167796995;35207.47188792119;35349.82325088358;27796.722032779413;31144.844275779382;31237.531437028007;31622.683773162895;31676.208094797814;19045.904770476613;23174.42063948415;23397.41506462352;23823.285301440985;24036 23:22:05 INFO - PROCESS | 1648 | |2;jquery.html;387.49625486867086;388.8230854960993;400.08779981641857;402.428551456931;419.1156659448565;17523.299068037264;19739.21043158274;21163;21248.8937555312;21258.57482850342;20626.896865515653;20700.689489657667;21387.57224855502;21787.891060544676;22143.889280553616;5821.417858214166;5851.800380921901;6095.207623009002;6147.846303842395;6153.476954458854;5073.46728593496;5440.4287549807295;5843.8246852594275;6101.023836186211;6111.358307377728;5999;6019.909701354483;6021.638701677892;6030.216071910645;6050.485708714765;5951.434613711691;6018.097285407181;6719.832004199885;7547.735829245978;7834.647440865175;7560.962195189044;7715.421343399212;7761.913332133506;7870.252326029018;7920.287174154354;29634.85182574095;29764.404711905856;29911.850440747658;29946.700532994608;30130.698693013008;2875.07997440819;3023.667396586374;3041.2396900774806;3064.387122575494;3126.515390114536;16272.349106035745;17213.655726885394;17279.30882764688;17571.29714811406;18068.36760713369;1657.2873664324336;1943.494691380237;1996.710476980844;1998.1507859159944;2001.4095841726726;118692.40653796762;123101;147842;150647;150954.50792355931;23636.7636323638;23726.406839829142;23845.761542384353;23866.761332386453;24060.037598496216;12403.317817519943;13100.737985240241;41442.1711565773;41474.58525414768;41539.7923010383;7271.413297430042;7326.194118646945;7435.1449583297535;7500.212477689764;7502.324790768857;7137.4290056795335;7148.821279467951;7236.891446628305;7256;7275.126984761862;4021.2962731521866;4114.506259248909;4127.7936103194725;4172.165566886574;4184.079502509443;1881.9529511762316;1962.6653875364889;2037.4600730806349;2069.627467055944;2090.341542414135;1008.3344992305043;1008.8789345278614;1011.3830563356347;1014.8629934958834;1055.6832950114936;21078.683819742717;21377;21393.57212855734;21575.13699452036;21683.56632867334;927.2581934452411;1036.6060896859144;1061.6814955513314;1078.6763970808727;1079.5573814735922;9352.485613291334;10222.6422075227;10688.038076573146;10875.401852898167;11215.887841121648;707529;710072;712362;718596;734559;144486.55513444942;148498;149515;150267;154255;1046.9371837689764;1048.219076787798;1074.4735079810837;1074.6883403812963;1074.897884700952;14371.80994330463;18602.534936626693;18792.154353053873;18829.623407532046;18890.7166392507;58016;58285.417145827996;58668.413315866295;59655;59809;83141.16858831335;85335;86849;87145;87368.5631571838;5859.765609375577;7630;8041.9947506561675;8141.430099893188;8435.32517398619;12654.873451265737;12701.491940322654;12825.166364186298;12883.35583220854;13039.608811735663;10858.565657373616;10912.236143469881;11039.558417663204;11084.667459976214;11220.046296064926;10835.566577336818;10987.780244395228;11064.834027489433;11091.389973551532;11179.60871369498;10857.544587432329;10875.891241087487;11027.44862756875;11312.983890718677;11452.3701196435;6734.764283250259;8108.513489190667;8309;8318;8442.451240669337;9312.25501959828;11024.834627480433;11054.613088542184;11076.003159715665;11181.552737890719 23:22:05 INFO - PROCESS | 1648 | |3;mootools.html;1258.2073293824892;1420.9218492982882;1433.5484322438424;1441.336984986906;1458.4822388052244;6458.612483251009;7454.664540095695;7710;8392.706255281058;8406.117357677447;7786.610669466519;7905.565193914333;8053.315468185211;8191.754247372573;8209.794755131124;2280.783325584071;2332.6384410416413;2338.368640467073;2342.344143639775;2348.178137651825;2068.5449201175716;2137.038332750261;2304.9078036878504;2342.496363281894;2347.80043696286;2242.2600541821243;2310.2376215848813;2315.2475445480286;2331.067572970817;2331.650252462136;2323.291396124173;2327.197116994634;2330.8484948478463;2339.2982105368324;2349.7767712067325;2307.012418917506;2311.5376924615143;2331.7059032237103;2343.332150337146;2348.0373047050625;8361.874571881426;8388.119247478988;8389.119142490074;8426.662933482654;8428;4978.203487442028;5130.666506677054;5137.255098010804;5137.7944882204665;5140.820071297524;3302.752293577967;3478.8608455661742;3491.3366460372704;3540.5751309842985;3558.377283975294;941.9905800941972;1173.771114632647;1243.6269119264184;1247.5571172233817;1248.0140688855793;10604.204684648683;10688.78622427555;10704.625338113128;10707.839382409271;10739.087177589914;4344.4352234209355;4643.721376717408;4672.742999135012;4682.601978831837;4690.420268852117;9371.250299975985;9417.105374989365;9429;9441.480718560546;9443.433393996245;4398.8020539075815;5137.614678899097;5219.895602088013;5223.582113430916;5231.790728370898;4666.160091183551;4745.691530050535;4759.119562880878;4773.403324584427;4805.087033463631;3764.661180493769;3830.9081911655044;3847.769133851978;3902.9804850975565;3929.390944403622;1174.1663418972626;1214.5991822698488;1251.8059700746217;1270.987290127087;1275.8724127587168;890.1810334492283;910.6220918318955;912.3203213605774;936.6347124621403;938.596403546468;5555.250041244381;5634.887302253932;5661.7735290588735;5663.858403539944;5862.618929769551;980.127686359141;1009.7425156585024;1010.2221289607108;1033.8397548380012;1042.3745752548562;3540.716742660581;3639.253952939641;3738.6635202831876;3741.775493470346;3885.96114038856;9700.466474343837;9937.254705897087;10023.749406264757;10030.398176109456;10042.895281519028;9704;9933.751656208653;9949.303548751517;9959.55182016811;9985.251106167067;7534.208908064684;7839.804004899923;7848.686052557834;7852.136265010844;7852.528848269122;4928.728919909439;5477.4796394342475;5529.391766905717;5540.252065971043;5568.638038527482;8974.955125224333;9032.187103160748;9093.40892841963;9110.90889091127;9221.953890230774;9127.041660625538;9269.119433653792;9370.906290937277;9380.343375963617;9388.202002829836;6652;6762.053312536151;6775.220849602359;6791.2190098139345;6792.456603471809;7686.385089192963;7737.303642671961;7745;7801.29788319054;7804.609769511615;6369.777057802955;7032.12098487689;7066.222715501393;7129.144502659714;7133.429325654039;5848.56135789826;6779.661016949231;6975;7001.404880585003;7031.824204394931;6314.052892066043;6392.137061496825;6411.51913606472;6414.262359828679;6438.6136831790245;4919.286703428054;4958.652894297365;4982.4768399319;5027.824026159067;5173.81891633791;4967.40391153064;5003.074431230234;5007.29897814299;5019.422766381913;5100.209467532539 23:22:05 INFO - PROCESS | 1648 | |4;prototype.html;269.6157974885788;307.3376872839033;353.0291697830965;353.58453816765297;360.19496425489046;4451.443569553806;5366.812161574346;5395.973020134898;5500.972495137524;5502.202180683798;4192.916141677165;4298.978505107478;5210.55710264627;5274.892272622744;5360.839174824761;3234.9514757278544;4063.735857169282;4266.765327906964;4301.40847886281;4304.440422745039;3295.110320213541;3671.4676371926053;3900.874023657114;4094.4472496213075;4283.37891005804;3897.8635747748835;4015.8393664253394;4025.9597404025876;4043.6765058795227;4051.3315302974975;3345.8327083645963;3627.6372362763777;3999.360102383619;4043.6158564936286;4066.6136717011836;3234.9353012939846;3533.752637315389;3537.434010558324;3540.150363912668;3564.7861128332383;4925.857252095314;5218.556422704075;5278.9208161877605;5289.41317604717;5333.546648534879;4627.583517483443;4657.301404789275;4667.183242932507;4674.555917187861;4699.801550604574;5725.484706376405;5731.111677689923;5766.682832444214;5767.36558978512;5780.7687692492245;3200.631927328364;3768.2651882882847;3862.439946207812;3875.418687196943;3921.3921842114232;5253;5758.971205143989;5941.029485257372;5977.8804423911715;6064.756040265086;3913.178232571135;3950.20995800841;3953.347697629916;3958.7030972677167;3965.7422267552515;3838.3091043612417;3915.667168290727;3943.645071943539;4003;4012.558618551957;4062.695297852673;4148.958510414857;4239.554846741109;4636.91032607337;4706.599939005223;5357.455208775878;5398.703071331036;5422.177364957695;5543.592843689276;5780.6820624865195;1024.779672370444;1199.818179093592;1245.7819881520697;1381.4336122190055;1401.5725203812935;842.9968337678143;948.1940350701848;999.4253304350028;1009.4397609326894;1013.9239557033252;4325.6106950374615;4790.928136077961;4958.975205123951;5246.737663116829;5273.126146250303;5648.181013752983;5721.971390143106;5752.827415177551;5753.798617048467;5762.942370576325;4133.751974881517;4848.369711937426;4871.805127794849;5001.7499125043605;5113.130767769489;2443.68232129822;2960.259935016246;2965.747911427553;2972.9554056689167;2973.8959136430553;5096.923546146811;5236.869078272997;5426.674399535961;5813.738381772746;5840.561957853178;4976.6765160265195;5226.895462090737;5568.104585615457;5694.943050569441;6057.698074731662;5615.4665306796605;6146.385361463907;6157.951813852172;6179.938200617937;6182.505399568024;5543.667379957215;5916.136417028622;5918.496927761188;5963.552733544915;6045.697715114315;4833.492483289204;5810.883782324415;5836.614763437281;5871.530277577869;5880.117982302689;4887.997960418177;5111.05445492585;5128.051310507419;5731.083026715705;6128.448439640454;3285.704286614216;3697.3529632314776;3897.454356390051;3979.900502487461;3983.7211395203217;4856.9757151215435;4886.755662216804;5316.6544174628525;5474.315710536183;5839.211706419579;4817.566419022305;5193.597728613328;5201.843944681666;5660.603757736919;5676.77292908279;4960.751962401793;5633.6619802811965;5695.288088988877;5719;5785.27684039495;5259.316288882421;5476.890462190813;5486.533644640251;5578.573320605549;5589.692566908859;5669;5675.864827034692;5683.829485115453;5695.174199741097;5760.395158508464;5333.5465685784975;5513.972430137984;5577.2191893135805;5596;5649.887002260014 23:22:05 INFO - PROCESS | 1648 | |5;yui.html;705.10531952874;706.8268274272804;763.5495057915829;798.832245228502;808.4664121679691;12502.9997600192;13244;15529.301181446834;15658.84341156588;15791.210439478024;14231;14240.430382784676;14367.64080897978;14382.424703011868;14413.855861441383;673.7742856143198;693.8820400531922;843.2663582683074;854.6965827131372;877.7937184761607;856.2721686566431;863.952482613456;875.8686197070426;876.128252388874;877.403365711316;928.4058202750246;932.6735642525095;933.9299552533553;938.2728385501223;940.5626383731563;915.2677857713836;924.96300147994;931.3713243560569;932.2262522106669;933.1182032978837;689.8861687821503;691.9342662447059;692.3076923076923;692.8787462194095;696.3976160621077;15200.087994720352;15411.460598878986;15473.690526189526;15563.688726225526;15666.373345066184;14306.642333941734;14566.4901728439;14630.268486575735;14637.70724585513;14647;11708.238964467282;11767.529298828036;11790.587329443428;11839.052875769918;11909.583164589285;3673.7061035117126;3699.574548926854;3727.2918145552258;3778.7165962552644;3844.500214972036;106034.4094838578;108182;110098.44950775275;110223;110517;10244.846327305097;10261.384316941007;10274.280800344053;10330;10428.426436545911;20066.698999515018;20069.197232110844;20142.798572014384;20459.488512787;20803.06386212624;7473.140588832354;7603.809904752315;7604.771856844303;7774.144844067148;7836;4204.66362690984;4222.9577704223175;4248.893777655521;4272.252355837716;4364.301711726109;3475.652434756504;3563.501109844624;3563.750537462353;3565.625609310985;3570.6786389225094;620.6431302001367;640.4268179978893;650.7071817682062;656.4813797100237;659.8779225843234;627.5387590121258;638.4636904999822;641.4355367276767;644.1465058797113;650.4796163069525;9553.426794392359;9692.51537423126;9741.172000379906;9748.366356186967;9814.656487023063;622.9750809967641;645.4836131095105;665.7337065173872;680.7583307925714;696.7352406085756;3971.404289356562;4008.218397412535;4063.22798668258;4063.6952228582973;4186.811593478301;83150;94048;95268.52365738127;96104;97743.51128244313;85750.57124714337;86895.56552217198;87695;87902.56048719844;89375.10624893798;74960.62519687366;76879.23120768832;76969;77301.2269877294;78505.21494785094;6493.837654058686;7933.722319719013;7949.7217597386125;8166.387520935835;8192.877106843522;51567.74216128895;51947.48052519577;52067.73966130296;52293.7385313071;52487.73756131195;65320.34679653143;66507.66746166431;67240.65518689697;68143.65928170526;68366;10991.945040274748;11044.779104418027;11049.72375690582;11107.777844443228;11178.608748693756;11912;11926.522939082339;11982.340971246666;12123.818142728043;12215.75568488643;11541.422928853355;11636.36000020007;11716.179867409199;11942.984846288164;12168.939155304166;11481.311121332747;11583.710407239887;11652.592159274385;11774.94112529432;12018.459169337226;9399.718008459757;11252.943735281271;11264.831027534758;11465.36940468249;11840.993515551274;9289.117533834275;9493.810123797348;9572;9773.12041916231;9932.205423565953;8670.176333248332;9323.393979391318;9327.067293270618;9340.906590934004;9361.204297634777 23:22:05 INFO - PROCESS | 1648 | __end_tp_report 23:22:05 INFO - PROCESS | 1648 | __start_cc_report 23:22:05 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,377 23:22:05 INFO - PROCESS | 1648 | __end_cc_report 23:22:05 INFO - PROCESS | 1648 | __startTimestamp1484637725395__endTimestamp 23:22:05 INFO - PROCESS | 1648 | 23:22:05 INFO - PROCESS | 1648 | ------- Summary: start ------- 23:22:05 INFO - PROCESS | 1648 | Number of tests: 6 23:22:05 INFO - PROCESS | 1648 | 23:22:05 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5697.85 Median:5886.85 stddev:645.54 (11.0%) stddev-sans-first:613.37 23:22:05 INFO - PROCESS | 1648 | Values: 3056.9 3342.6 3379.1 3406.0 3421.7 5601.6 5657.4 5693.8 5700.7 5782.7 5502.0 5574.0 5585.8 5690.3 5712.9 5134.7 5167.8 5227.3 5254.3 5254.7 5170.8 5307.0 5310.0 5326.1 5330.7 5259.4 5312.0 5312.5 5375.4 5376.8 4804.7 4985.2 5034.3 5144.5 5152.3 5132.3 5142.0 5270.0 5289.1 5308.5 4476.3 5046.9 5233.8 5676.5 5835.3 5509.6 6221.0 6271.2 6271.6 6358.2 5688.1 6167.4 6220.2 6245.8 6277.9 5842.7 5858.9 5866.0 5869.4 5886.9 5376.6 5919.5 6247.3 6248.2 6277.9 5839.9 6015.4 6077.3 6121.6 6121.9 6110.6 6170.2 6211.0 6254.0 6277.4 6092.3 6253.9 6265.3 6272.4 6281.3 6125.6 6147.8 6193.2 6236.5 6294.7 4923.3 4972.1 4981.9 5017.1 5021.8 4396.4 4482.7 4508.6 4514.2 4548.8 4697.5 5751.9 5782.3 5818.6 5862.2 6135.4 6150.0 6265.2 6282.8 6307.2 5756.1 5892.3 5915.1 5965.2 6011.9 5430.0 5431.9 5439.9 5441.3 5442.0 5711.1 5863.3 6359.9 6372.4 6385.0 6125.8 6128.1 6128.1 6155.1 6179.3 6284.0 6323.1 6350.9 6361.2 6374.9 6169.8 6175.4 6177.0 6191.9 6202.6 5907.6 6105.7 6106.5 6150.8 6151.8 6052.5 6118.0 6155.8 6157.4 6164.0 5952.1 6122.4 6151.9 6152.4 6156.0 5726.4 5806.0 5929.3 5959.0 5963.9 5115.0 6080.4 6116.2 6134.2 6176.9 5094.4 5666.5 5715.9 6057.2 6088.4 23:22:05 INFO - PROCESS | 1648 | 23:22:05 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:65593.28 Median:34594.81 stddev:142881.59 (413.0%) stddev-sans-first:143198.73 23:22:05 INFO - PROCESS | 1648 | Values: 462.9 480.5 496.2 496.8 505.4 36374.0 40073.2 40098.4 42000.9 42085.8 40811.4 40944.0 41302.0 41316.0 42077.6 8267.4 8464.9 9220.9 9382.0 9407.4 10964.5 11288.6 11295.5 11472.4 11476.3 11298.4 11359.9 11370.5 11389.5 11414.3 10018.0 10788.4 11260.3 11313.3 11327.8 11157.3 11174.7 11184.9 11221.2 11227.3 32238.4 39024.0 39372.8 39435.8 39506.0 3929.5 4029.7 4032.2 4079.0 4110.8 27149.7 27609.0 27651.6 27691.3 27692.4 2633.7 3097.4 3188.1 3265.6 3287.4 392258.0 464213.0 469162.0 469196.0 470267.0 54267.5 54602.5 54613.0 54924.2 54949.6 55412.0 55959.0 56128.8 56515.7 56758.7 15029.8 15085.0 15326.8 15336.9 15346.1 10707.8 10924.8 11826.8 11835.8 12001.3 17131.2 17239.0 17317.2 17395.0 17448.8 7332.7 7427.8 7443.5 7529.7 7573.7 740.8 873.9 874.6 884.7 905.7 27825.0 29318.3 29591.0 30187.8 30741.5 1066.7 1072.4 1103.4 1108.9 1188.4 14026.0 15312.6 15316.7 15515.0 15786.5 564313.0 624019.9 630398.0 633305.0 634358.0 57243.4 57261.7 57700.0 57964.1 58179.7 1274.6 1396.5 1443.1 1457.8 1464.6 28989.6 28999.0 29240.9 29550.0 29724.9 36320.0 48279.0 48625.5 48676.5 48820.5 517307.0 517524.0 520086.0 520936.0 523136.0 20319.5 20688.9 22046.7 23108.9 24795.3 30737.7 42071.2 42574.0 44547.3 44613.3 35420.3 35563.0 35564.0 36616.6 36811.0 28749.0 31279.1 35108.8 35704.6 35765.8 28786.7 34534.1 34655.5 35207.5 35349.8 27796.7 31144.8 31237.5 31622.7 31676.2 19045.9 23174.4 23397.4 23823.3 24036.0 23:22:05 INFO - PROCESS | 1648 | 23:22:05 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:40979.91 Median:23786.08 stddev:119523.77 (502.5%) stddev-sans-first:119820.20 23:22:05 INFO - PROCESS | 1648 | Values: 387.5 388.8 400.1 402.4 419.1 17523.3 19739.2 21163.0 21248.9 21258.6 20626.9 20700.7 21387.6 21787.9 22143.9 5821.4 5851.8 6095.2 6147.8 6153.5 5073.5 5440.4 5843.8 6101.0 6111.4 5999.0 6019.9 6021.6 6030.2 6050.5 5951.4 6018.1 6719.8 7547.7 7834.6 7561.0 7715.4 7761.9 7870.3 7920.3 29634.9 29764.4 29911.9 29946.7 30130.7 2875.1 3023.7 3041.2 3064.4 3126.5 16272.3 17213.7 17279.3 17571.3 18068.4 1657.3 1943.5 1996.7 1998.2 2001.4 118692.4 123101.0 147842.0 150647.0 150954.5 23636.8 23726.4 23845.8 23866.8 24060.0 12403.3 13100.7 41442.2 41474.6 41539.8 7271.4 7326.2 7435.1 7500.2 7502.3 7137.4 7148.8 7236.9 7256.0 7275.1 4021.3 4114.5 4127.8 4172.2 4184.1 1882.0 1962.7 2037.5 2069.6 2090.3 1008.3 1008.9 1011.4 1014.9 1055.7 21078.7 21377.0 21393.6 21575.1 21683.6 927.3 1036.6 1061.7 1078.7 1079.6 9352.5 10222.6 10688.0 10875.4 11215.9 707529.0 710072.0 712362.0 718596.0 734559.0 144486.6 148498.0 149515.0 150267.0 154255.0 1046.9 1048.2 1074.5 1074.7 1074.9 14371.8 18602.5 18792.2 18829.6 18890.7 58016.0 58285.4 58668.4 59655.0 59809.0 83141.2 85335.0 86849.0 87145.0 87368.6 5859.8 7630.0 8042.0 8141.4 8435.3 12654.9 12701.5 12825.2 12883.4 13039.6 10858.6 10912.2 11039.6 11084.7 11220.0 10835.6 10987.8 11064.8 11091.4 11179.6 10857.5 10875.9 11027.4 11313.0 11452.4 6734.8 8108.5 8309.0 8318.0 8442.5 9312.3 11024.8 11054.6 11076.0 11181.6 23:22:05 INFO - PROCESS | 1648 | 23:22:05 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5382.55 Median:5137.70 stddev:2911.13 (56.7%) stddev-sans-first:2902.79 23:22:05 INFO - PROCESS | 1648 | Values: 1258.2 1420.9 1433.5 1441.3 1458.5 6458.6 7454.7 7710.0 8392.7 8406.1 7786.6 7905.6 8053.3 8191.8 8209.8 2280.8 2332.6 2338.4 2342.3 2348.2 2068.5 2137.0 2304.9 2342.5 2347.8 2242.3 2310.2 2315.2 2331.1 2331.7 2323.3 2327.2 2330.8 2339.3 2349.8 2307.0 2311.5 2331.7 2343.3 2348.0 8361.9 8388.1 8389.1 8426.7 8428.0 4978.2 5130.7 5137.3 5137.8 5140.8 3302.8 3478.9 3491.3 3540.6 3558.4 942.0 1173.8 1243.6 1247.6 1248.0 10604.2 10688.8 10704.6 10707.8 10739.1 4344.4 4643.7 4672.7 4682.6 4690.4 9371.3 9417.1 9429.0 9441.5 9443.4 4398.8 5137.6 5219.9 5223.6 5231.8 4666.2 4745.7 4759.1 4773.4 4805.1 3764.7 3830.9 3847.8 3903.0 3929.4 1174.2 1214.6 1251.8 1271.0 1275.9 890.2 910.6 912.3 936.6 938.6 5555.3 5634.9 5661.8 5663.9 5862.6 980.1 1009.7 1010.2 1033.8 1042.4 3540.7 3639.3 3738.7 3741.8 3886.0 9700.5 9937.3 10023.7 10030.4 10042.9 9704.0 9933.8 9949.3 9959.6 9985.3 7534.2 7839.8 7848.7 7852.1 7852.5 4928.7 5477.5 5529.4 5540.3 5568.6 8975.0 9032.2 9093.4 9110.9 9222.0 9127.0 9269.1 9370.9 9380.3 9388.2 6652.0 6762.1 6775.2 6791.2 6792.5 7686.4 7737.3 7745.0 7801.3 7804.6 6369.8 7032.1 7066.2 7129.1 7133.4 5848.6 6779.7 6975.0 7001.4 7031.8 6314.1 6392.1 6411.5 6414.3 6438.6 4919.3 4958.7 4982.5 5027.8 5173.8 4967.4 5003.1 5007.3 5019.4 5100.2 23:22:05 INFO - PROCESS | 1648 | 23:22:05 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4545.49 Median:5103.99 stddev:1410.64 (27.6%) stddev-sans-first:1377.60 23:22:05 INFO - PROCESS | 1648 | Values: 269.6 307.3 353.0 353.6 360.2 4451.4 5366.8 5396.0 5501.0 5502.2 4192.9 4299.0 5210.6 5274.9 5360.8 3235.0 4063.7 4266.8 4301.4 4304.4 3295.1 3671.5 3900.9 4094.4 4283.4 3897.9 4015.8 4026.0 4043.7 4051.3 3345.8 3627.6 3999.4 4043.6 4066.6 3234.9 3533.8 3537.4 3540.2 3564.8 4925.9 5218.6 5278.9 5289.4 5333.5 4627.6 4657.3 4667.2 4674.6 4699.8 5725.5 5731.1 5766.7 5767.4 5780.8 3200.6 3768.3 3862.4 3875.4 3921.4 5253.0 5759.0 5941.0 5977.9 6064.8 3913.2 3950.2 3953.3 3958.7 3965.7 3838.3 3915.7 3943.6 4003.0 4012.6 4062.7 4149.0 4239.6 4636.9 4706.6 5357.5 5398.7 5422.2 5543.6 5780.7 1024.8 1199.8 1245.8 1381.4 1401.6 843.0 948.2 999.4 1009.4 1013.9 4325.6 4790.9 4959.0 5246.7 5273.1 5648.2 5722.0 5752.8 5753.8 5762.9 4133.8 4848.4 4871.8 5001.7 5113.1 2443.7 2960.3 2965.7 2973.0 2973.9 5096.9 5236.9 5426.7 5813.7 5840.6 4976.7 5226.9 5568.1 5694.9 6057.7 5615.5 6146.4 6158.0 6179.9 6182.5 5543.7 5916.1 5918.5 5963.6 6045.7 4833.5 5810.9 5836.6 5871.5 5880.1 4888.0 5111.1 5128.1 5731.1 6128.4 3285.7 3697.4 3897.5 3979.9 3983.7 4857.0 4886.8 5316.7 5474.3 5839.2 4817.6 5193.6 5201.8 5660.6 5676.8 4960.8 5633.7 5695.3 5719.0 5785.3 5259.3 5476.9 5486.5 5578.6 5589.7 5669.0 5675.9 5683.8 5695.2 5760.4 5333.5 5514.0 5577.2 5596.0 5649.9 23:22:05 INFO - PROCESS | 1648 | 23:22:05 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:19759.46 Median:625.26 stddev:28950.43 (4630.2%) stddev-sans-first:28996.29 23:22:05 INFO - PROCESS | 1648 | Values: 705.1 706.8 763.5 798.8 808.5 12503.0 13244.0 15529.3 15658.8 15791.2 14231.0 14240.4 14367.6 14382.4 14413.9 673.8 693.9 843.3 854.7 877.8 856.3 864.0 875.9 876.1 877.4 928.4 932.7 933.9 938.3 940.6 915.3 925.0 931.4 932.2 933.1 689.9 691.9 692.3 692.9 696.4 15200.1 15411.5 15473.7 15563.7 15666.4 14306.6 14566.5 14630.3 14637.7 14647.0 11708.2 11767.5 11790.6 11839.1 11909.6 3673.7 3699.6 3727.3 3778.7 3844.5 106034.4 108182.0 110098.4 110223.0 110517.0 10244.8 10261.4 10274.3 10330.0 10428.4 20066.7 20069.2 20142.8 20459.5 20803.1 7473.1 7603.8 7604.8 7774.1 7836.0 4204.7 4223.0 4248.9 4272.3 4364.3 3475.7 3563.5 3563.8 3565.6 3570.7 620.6 640.4 650.7 656.5 659.9 627.5 638.5 641.4 644.1 650.5 9553.4 9692.5 9741.2 9748.4 9814.7 623.0 645.5 665.7 680.8 696.7 3971.4 4008.2 4063.2 4063.7 4186.8 83150.0 94048.0 95268.5 96104.0 97743.5 85750.6 86895.6 87695.0 87902.6 89375.1 74960.6 76879.2 76969.0 77301.2 78505.2 6493.8 7933.7 7949.7 8166.4 8192.9 51567.7 51947.5 52067.7 52293.7 52487.7 65320.3 66507.7 67240.7 68143.7 68366.0 10991.9 11044.8 11049.7 11107.8 11178.6 11912.0 11926.5 11982.3 12123.8 12215.8 11541.4 11636.4 11716.2 11943.0 12168.9 11481.3 11583.7 11652.6 11774.9 12018.5 9399.7 11252.9 11264.8 11465.4 11841.0 9289.1 9493.8 9572.0 9773.1 9932.2 8670.2 9323.4 9327.1 9340.9 9361.2 23:22:05 INFO - PROCESS | 1648 | -------- Summary: end -------- 23:22:05 INFO - PROCESS | 1648 | 23:22:06 INFO - TEST-INFO | 1648: exit 0 23:22:06 INFO - TEST-OK | dromaeo_css | took 1116716ms 23:22:06 INFO - TEST-START | kraken 23:22:06 INFO - operating with platform_type : mac_ 23:22:06 INFO - Initialising browser for kraken test... 23:22:06 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpY8IrzN/profile http://localhost:49241/getInfo.html) 23:22:08 INFO - PROCESS | 1655 | 2017-01-16 23:22:08.830 plugin-container[1656:10900] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 23:22:08 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:22:08 INFO - PROCESS | 1655 | 2017-01-16 23:22:08.833 plugin-container[1656:10900] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8947, name = 'com.apple.CFPasteboardClient' 23:22:08 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:22:08 INFO - PROCESS | 1655 | 2017-01-16 23:22:08.833 plugin-container[1656:10900] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:22:08 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 23:22:08 INFO - PROCESS | 1655 | colorDepth:24 23:22:08 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 23:22:08 INFO - PROCESS | 1655 | __metrics 23:22:08 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3253: TypeError: this.tabs is undefined 23:22:09 INFO - TEST-INFO | 1655: exit 0 23:22:09 INFO - Browser initialized. 23:22:09 INFO - Running cycle 1/1 for kraken test... 23:22:09 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpY8IrzN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:22:12 INFO - PROCESS | 1657 | 2017-01-16 23:22:12.202 plugin-container[1658:11013] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 23:22:12 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:22:12 INFO - PROCESS | 1657 | 2017-01-16 23:22:12.203 plugin-container[1658:11013] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 23:22:12 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:22:12 INFO - PROCESS | 1657 | 2017-01-16 23:22:12.203 plugin-container[1658:11013] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:22:21 INFO - PROCESS | 1657 | RSS: Main: 233889792 23:22:21 INFO - PROCESS | 1657 | 23:22:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 23:22:25 INFO - PROCESS | 1657 | RSS: Main: 246837248 23:22:25 INFO - PROCESS | 1657 | 23:22:28 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 23:22:29 INFO - PROCESS | 1657 | RSS: Main: 254464000 23:22:29 INFO - PROCESS | 1657 | 23:22:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 23:22:33 INFO - PROCESS | 1657 | RSS: Main: 257196032 23:22:33 INFO - PROCESS | 1657 | 23:22:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 23:22:35 INFO - PROCESS | 1657 | RSS: Main: 258326528 23:22:35 INFO - PROCESS | 1657 | 23:22:38 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:22:38 INFO - PROCESS | 1657 | RSS: Main: 258629632 23:22:38 INFO - PROCESS | 1657 | 23:22:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 23:22:42 INFO - PROCESS | 1657 | RSS: Main: 264097792 23:22:42 INFO - PROCESS | 1657 | 23:22:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 23:22:45 INFO - PROCESS | 1657 | RSS: Main: 263270400 23:22:45 INFO - PROCESS | 1657 | 23:22:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 23:22:49 INFO - PROCESS | 1657 | RSS: Main: 264691712 23:22:49 INFO - PROCESS | 1657 | 23:22:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:22:51 INFO - PROCESS | 1657 | RSS: Main: 264089600 23:22:51 INFO - PROCESS | 1657 | 23:22:53 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:22:54 INFO - PROCESS | 1657 | RSS: Main: 264114176 23:22:54 INFO - PROCESS | 1657 | 23:22:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:22:57 INFO - PROCESS | 1657 | RSS: Main: 264179712 23:22:57 INFO - PROCESS | 1657 | 23:23:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:23:01 INFO - PROCESS | 1657 | RSS: Main: 264540160 23:23:01 INFO - PROCESS | 1657 | 23:23:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:23:04 INFO - PROCESS | 1657 | RSS: Main: 264290304 23:23:04 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 23:23:06 INFO - PROCESS | 1657 | RSS: Main: 266375168 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | __start_tp_report 23:23:06 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 23:23:06 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 23:23:06 INFO - PROCESS | 1657 | |i|pagename|runs| 23:23:06 INFO - PROCESS | 1657 | |0;ai-astar;107;118;118;121;120;118;120;118;118;119 23:23:06 INFO - PROCESS | 1657 | |1;audio-beat-detection;149;114;113;146;112;126;142;98;157;126 23:23:06 INFO - PROCESS | 1657 | |2;audio-dft;203;210;241;278;273;195;198;212;210;175 23:23:06 INFO - PROCESS | 1657 | |3;audio-fft;98;56;62;83;61;67;69;72;93;83 23:23:06 INFO - PROCESS | 1657 | |4;audio-oscillator;81;102;116;58;77;103;115;100;87;83 23:23:06 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;86;89;75;139;84;90;86;124;129 23:23:06 INFO - PROCESS | 1657 | |6;imaging-darkroom;95;106;96;97;94;96;100;151;112;97 23:23:06 INFO - PROCESS | 1657 | |7;imaging-desaturate;149;124;92;94;91;94;95;92;93;91 23:23:06 INFO - PROCESS | 1657 | |8;json-parse-financial;65;62;61;63;63;62;61;63;63;62 23:23:06 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;70;73;96;115;112;112;73;71;70;72 23:23:06 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;90;88;90;88;86;86;87;89;87;80 23:23:06 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;142;151;153;159;177;164;158;160;229;192 23:23:06 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;180;178;230;175;244;209;176;179;178;175 23:23:06 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;70;67;69;68;68;65;66;67;66;68 23:23:06 INFO - PROCESS | 1657 | __end_tp_report 23:23:06 INFO - PROCESS | 1657 | __start_cc_report 23:23:06 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,921 23:23:06 INFO - PROCESS | 1657 | __end_cc_report 23:23:06 INFO - PROCESS | 1657 | __startTimestamp1484637786772__endTimestamp 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | ------- Summary: start ------- 23:23:06 INFO - PROCESS | 1657 | Number of tests: 14 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:117.70 Median:118.50 stddev:3.92 (3.3%) stddev-sans-first:1.17 23:23:06 INFO - PROCESS | 1657 | Values: 107.0 118.0 118.0 121.0 120.0 118.0 120.0 118.0 118.0 119.0 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:144.00 stddev:19.40 (13.5%) stddev-sans-first:19.07 23:23:06 INFO - PROCESS | 1657 | Values: 149.0 114.0 113.0 146.0 112.0 126.0 142.0 98.0 157.0 126.0 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:219.50 Median:211.00 stddev:33.83 (16.0%) stddev-sans-first:35.35 23:23:06 INFO - PROCESS | 1657 | Values: 203.0 210.0 241.0 278.0 273.0 195.0 198.0 212.0 210.0 175.0 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:74.40 Median:77.50 stddev:14.19 (18.3%) stddev-sans-first:12.21 23:23:06 INFO - PROCESS | 1657 | Values: 98.0 56.0 62.0 83.0 61.0 67.0 69.0 72.0 93.0 83.0 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:67.50 stddev:18.25 (27.0%) stddev-sans-first:18.90 23:23:06 INFO - PROCESS | 1657 | Values: 81.0 102.0 116.0 58.0 77.0 103.0 115.0 100.0 87.0 83.0 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:99.10 Median:86.00 stddev:22.47 (26.1%) stddev-sans-first:23.54 23:23:06 INFO - PROCESS | 1657 | Values: 89.0 86.0 89.0 75.0 139.0 84.0 90.0 86.0 124.0 129.0 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:104.40 Median:95.50 stddev:17.32 (18.1%) stddev-sans-first:18.03 23:23:06 INFO - PROCESS | 1657 | Values: 95.0 106.0 96.0 97.0 94.0 96.0 100.0 151.0 112.0 97.0 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:101.50 Median:92.50 stddev:19.41 (21.0%) stddev-sans-first:10.51 23:23:06 INFO - PROCESS | 1657 | Values: 149.0 124.0 92.0 94.0 91.0 94.0 95.0 92.0 93.0 91.0 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:1.18 (1.9%) stddev-sans-first:0.83 23:23:06 INFO - PROCESS | 1657 | Values: 65.0 62.0 61.0 63.0 63.0 62.0 61.0 63.0 63.0 62.0 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:86.40 Median:71.50 stddev:19.90 (27.8%) stddev-sans-first:20.20 23:23:06 INFO - PROCESS | 1657 | Values: 70.0 73.0 96.0 115.0 112.0 112.0 73.0 71.0 70.0 72.0 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:88.00 stddev:2.88 (3.3%) stddev-sans-first:2.86 23:23:06 INFO - PROCESS | 1657 | Values: 90.0 88.0 90.0 88.0 86.0 86.0 87.0 89.0 87.0 80.0 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:168.50 Median:162.00 stddev:25.44 (15.7%) stddev-sans-first:25.12 23:23:06 INFO - PROCESS | 1657 | Values: 142.0 151.0 153.0 159.0 177.0 164.0 158.0 160.0 229.0 192.0 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.40 Median:179.50 stddev:25.76 (14.4%) stddev-sans-first:26.93 23:23:06 INFO - PROCESS | 1657 | Values: 180.0 178.0 230.0 175.0 244.0 209.0 176.0 179.0 178.0 175.0 23:23:06 INFO - PROCESS | 1657 | 23:23:06 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.51 (2.2%) stddev-sans-first:1.27 23:23:06 INFO - PROCESS | 1657 | Values: 70.0 67.0 69.0 68.0 68.0 65.0 66.0 67.0 66.0 68.0 23:23:06 INFO - PROCESS | 1657 | -------- Summary: end -------- 23:23:06 INFO - PROCESS | 1657 | 23:23:07 INFO - TEST-INFO | 1657: exit 0 23:23:07 INFO - TEST-OK | kraken | took 61621ms 23:23:07 INFO - SUITE-END | took 1178s 23:23:07 INFO - Completed test suite (00:19:38) 23:23:07 INFO - javascript benchmark 23:23:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7276.883290801796, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3056.9082927512154, 3342.6323104458506, 3379.1214284286084, 3406.012256445631, 3421.7262618990485, 5601.6358936669085, 5657.377688454267, 5693.800716974907, 5700.657960522366, 5782.739776710047, 5502.0, 5573.996680597491, 5585.776568937237, 5690.2887139107615, 5712.914786103073, 5134.691918484884, 5167.844964651066, 5227.320448341729, 5254.290670759437, 5254.710990895499, 5170.819021334254, 5307.028064284013, 5310.0441920454305, 5326.094563924123, 5330.733463326837, 5259.421463638997, 5312.0, 5312.548433383147, 5375.354957405098, 5376.78492860287, 4804.687695299829, 4985.152524070881, 5034.3455350804525, 5144.485551444863, 5152.3044389007755, 5132.255822905695, 5141.974290128563, 5270.0, 5289.127293996486, 5308.46915308466, 4476.306172543228, 5046.924296135561, 5233.842984710464, 5676.545876329884, 5835.299764028301, 5509.614326997112, 6221.035739460391, 6271.153394291758, 6271.592346497463, 6358.237011558643, 5688.146777983295, 6167.383261673796, 6220.1602783624085, 6245.843853903643, 6277.937220627781, 5842.678652674102, 5858.853528661817, 5866.032104702762, 5869.413058694139, 5886.852828679232, 5376.623636345496, 5919.496842768327, 6247.250329960343, 6248.156498872685, 6277.905831412532, 5839.941600583939, 6015.39846015395, 6077.301110372364, 6121.605402471149, 6121.877562448726, 6110.649890384107, 6170.167027451326, 6210.968945155336, 6254.030625253093, 6277.403646653652, 6092.253264242548, 6253.937460625336, 6265.342139075423, 6272.435480806749, 6281.309056003836, 6125.571219981323, 6147.784827531015, 6193.194884664965, 6236.469900058546, 6294.685265736786, 4923.310736496894, 4972.055309491185, 4981.850544483671, 5017.147084995559, 5021.8242361517905, 4396.428464299621, 4482.708623939434, 4508.571685689854, 4514.232580461329, 4548.818047278071, 4697.530246975343, 5751.913721294184, 5782.277215348082, 5818.5636077294375, 5862.179294898718, 6135.417135372132, 6150.0, 6265.216847894013, 6282.780102696383, 6307.211598550181, 5756.136579513107, 5892.263467066617, 5915.053591425351, 5965.194698715618, 6011.909821352683, 5430.0, 5431.945680543223, 5439.918401223985, 5441.292631957899, 5441.972790136024, 5711.143328500758, 5863.2670916135485, 6359.936400636119, 6372.362763723683, 6385.0, 6125.784909797561, 6128.075379514812, 6128.136482154469, 6155.1075094112, 6179.2584889811715, 6283.96858015707, 6323.146375239284, 6350.872982540416, 6361.236651601837, 6374.904376434264, 6169.84575385619, 6175.3824617539685, 6176.969115154396, 6191.876162476815, 6202.596831205957, 5907.616004959664, 6105.732692890381, 6106.45041946227, 6150.753969841336, 6151.753929842936, 6052.546059045501, 6118.040554665315, 6155.815325540241, 6157.4458298753325, 6163.96918015425, 5952.107183922446, 6122.38776122402, 6151.876962460815, 6152.446279834837, 6156.015037593964, 5726.398728133652, 5805.970970145122, 5929.347771745019, 5959.0, 5963.9105413417965, 5114.974425127851, 6080.391960803884, 6116.153172518083, 6134.2332208473945, 6176.907346389898, 5094.363204599425, 5666.518345940642, 5715.9142612860005, 6057.212562366864, 6088.360722124113], "value": 5658.103264827997, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.8865927847676, 480.4546839337351, 496.21102447109087, 496.811211739539, 505.4137200847018, 36374.0, 40073.1985360293, 40098.39852402213, 42000.94997625061, 42085.789571052104, 40811.38782918259, 40944.0, 41301.96745081375, 41316.0, 42077.57922420775, 8267.379946504007, 8464.873026904601, 9220.8616870747, 9382.0, 9407.388519746195, 10964.506597203104, 11288.604898828542, 11295.491702873349, 11472.369019703912, 11476.254043487188, 11298.378589177591, 11359.943200283988, 11370.488328025218, 11389.487473063691, 11414.31514109152, 10017.999999999964, 10788.406637634927, 11260.268082574647, 11313.3212007279, 11327.830082548768, 11157.330560166416, 11174.720631984184, 11184.944075279653, 11221.158413119048, 11227.27022743519, 32238.355232895447, 39024.02439938996, 39372.80313598413, 39435.802820986, 39506.0, 3929.5088113985753, 4029.717919745619, 4032.193561287754, 4079.0414252650603, 4110.753354798721, 27149.72850271492, 27609.0, 27651.58522622162, 27691.307717307027, 27692.446151077067, 2633.7234590367925, 3097.3650401667605, 3188.1073299476184, 3265.6407795142513, 3287.424700677396, 392258.0, 464213.0, 469162.0, 469196.0, 470266.99999999657, 54267.45732542703, 54602.45397546053, 54613.0, 54924.17613735797, 54949.62625934304, 55412.0, 55959.0, 56128.75484980637, 56515.71742141345, 56758.71620641871, 15029.849701503064, 15085.0, 15326.770098448533, 15336.923315383352, 15346.079235245697, 10707.785844283071, 10924.781504370027, 11826.822597661041, 11835.763284734257, 12001.339926304137, 17131.22909469077, 17239.0, 17317.22072506715, 17395.0, 17448.825511744973, 7332.670029848669, 7427.7771666850085, 7443.478956473104, 7529.736459223901, 7573.697052117964, 740.8110931712487, 873.9388242823069, 874.6457684637732, 884.7478468636408, 905.6965916418073, 27825.0, 29318.26704332409, 29591.0, 30187.849060754994, 30741.538876916864, 1066.706655669697, 1072.4423299884018, 1103.376592225406, 1108.9279196852178, 1188.358286525284, 14025.97660516755, 15312.617184570476, 15316.713695580014, 15515.0, 15786.526404207892, 564313.0, 624019.8799006067, 630398.0, 633305.0, 634358.0, 57243.42756572464, 57261.71369143128, 57700.0, 57964.130538041965, 58179.709101454224, 1274.6112435707198, 1396.5391420831102, 1443.0662911698375, 1457.774045023002, 1464.61187785237, 28989.565156522247, 28999.0, 29240.853795730884, 29550.0, 29724.851375742983, 36320.0, 48279.0, 48625.513744863514, 48676.5132348672, 48820.5117948816, 517307.0, 517524.0, 520086.0, 520936.0, 523136.0, 20319.4920126998, 20688.896555517127, 22046.685098064474, 23108.884455577616, 24795.264331496623, 30737.692623074377, 42071.15857682768, 42574.0, 44547.33179002253, 44613.330800038675, 35420.291594168484, 35563.0, 35564.0, 36616.63383366239, 36811.0, 28749.0, 31279.06162815119, 35108.824455877555, 35704.64295357013, 35765.82117089398, 28786.7121328784, 34534.13664658403, 34655.480167796995, 35207.47188792119, 35349.82325088358, 27796.722032779413, 31144.844275779382, 31237.531437028007, 31622.683773162895, 31676.208094797814, 19045.904770476613, 23174.42063948415, 23397.41506462352, 23823.285301440985, 24036.0], "value": 19267.2285114122, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.49625486867086, 388.8230854960993, 400.08779981641857, 402.428551456931, 419.1156659448565, 17523.299068037264, 19739.21043158274, 21163.0, 21248.8937555312, 21258.57482850342, 20626.896865515653, 20700.689489657667, 21387.57224855502, 21787.891060544676, 22143.889280553616, 5821.417858214166, 5851.800380921901, 6095.207623009002, 6147.846303842395, 6153.476954458854, 5073.46728593496, 5440.4287549807295, 5843.8246852594275, 6101.023836186211, 6111.358307377728, 5999.0, 6019.909701354483, 6021.638701677892, 6030.216071910645, 6050.485708714765, 5951.434613711691, 6018.097285407181, 6719.832004199885, 7547.735829245978, 7834.647440865175, 7560.962195189044, 7715.421343399212, 7761.913332133506, 7870.252326029018, 7920.287174154354, 29634.85182574095, 29764.404711905856, 29911.850440747658, 29946.700532994608, 30130.698693013008, 2875.07997440819, 3023.667396586374, 3041.2396900774806, 3064.387122575494, 3126.515390114536, 16272.349106035745, 17213.655726885394, 17279.30882764688, 17571.29714811406, 18068.36760713369, 1657.2873664324336, 1943.494691380237, 1996.710476980844, 1998.1507859159944, 2001.4095841726726, 118692.40653796762, 123101.0, 147842.0, 150647.0, 150954.50792355931, 23636.7636323638, 23726.406839829142, 23845.761542384353, 23866.761332386453, 24060.037598496216, 12403.317817519943, 13100.737985240241, 41442.1711565773, 41474.58525414768, 41539.7923010383, 7271.413297430042, 7326.194118646945, 7435.1449583297535, 7500.212477689764, 7502.324790768857, 7137.4290056795335, 7148.821279467951, 7236.891446628305, 7256.0, 7275.126984761862, 4021.2962731521866, 4114.506259248909, 4127.7936103194725, 4172.165566886574, 4184.079502509443, 1881.9529511762316, 1962.6653875364889, 2037.4600730806349, 2069.627467055944, 2090.341542414135, 1008.3344992305043, 1008.8789345278614, 1011.3830563356347, 1014.8629934958834, 1055.6832950114936, 21078.683819742717, 21377.0, 21393.57212855734, 21575.13699452036, 21683.56632867334, 927.2581934452411, 1036.6060896859144, 1061.6814955513314, 1078.6763970808727, 1079.5573814735922, 9352.485613291334, 10222.6422075227, 10688.038076573146, 10875.401852898167, 11215.887841121648, 707529.0, 710072.0, 712362.0, 718596.0, 734559.0, 144486.55513444942, 148498.0, 149515.0, 150267.0, 154255.0, 1046.9371837689764, 1048.219076787798, 1074.4735079810837, 1074.6883403812963, 1074.897884700952, 14371.80994330463, 18602.534936626693, 18792.154353053873, 18829.623407532046, 18890.7166392507, 58016.0, 58285.417145827996, 58668.413315866295, 59655.0, 59809.0, 83141.16858831335, 85335.0, 86849.0, 87145.0, 87368.5631571838, 5859.765609375577, 7630.0, 8041.9947506561675, 8141.430099893188, 8435.32517398619, 12654.873451265737, 12701.491940322654, 12825.166364186298, 12883.35583220854, 13039.608811735663, 10858.565657373616, 10912.236143469881, 11039.558417663204, 11084.667459976214, 11220.046296064926, 10835.566577336818, 10987.780244395228, 11064.834027489433, 11091.389973551532, 11179.60871369498, 10857.544587432329, 10875.891241087487, 11027.44862756875, 11312.983890718677, 11452.3701196435, 6734.764283250259, 8108.513489190667, 8309.0, 8318.0, 8442.451240669337, 9312.25501959828, 11024.834627480433, 11054.613088542184, 11076.003159715665, 11181.552737890719], "value": 10550.08093324835, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1258.2073293824892, 1420.9218492982882, 1433.5484322438424, 1441.336984986906, 1458.4822388052244, 6458.612483251009, 7454.664540095695, 7710.0, 8392.706255281058, 8406.117357677447, 7786.610669466519, 7905.565193914333, 8053.315468185211, 8191.754247372573, 8209.794755131124, 2280.783325584071, 2332.6384410416413, 2338.368640467073, 2342.344143639775, 2348.178137651825, 2068.5449201175716, 2137.038332750261, 2304.9078036878504, 2342.496363281894, 2347.80043696286, 2242.2600541821243, 2310.2376215848813, 2315.2475445480286, 2331.067572970817, 2331.650252462136, 2323.291396124173, 2327.197116994634, 2330.8484948478463, 2339.2982105368324, 2349.7767712067325, 2307.012418917506, 2311.5376924615143, 2331.7059032237103, 2343.332150337146, 2348.0373047050625, 8361.874571881426, 8388.119247478988, 8389.119142490074, 8426.662933482654, 8428.0, 4978.203487442028, 5130.666506677054, 5137.255098010804, 5137.7944882204665, 5140.820071297524, 3302.752293577967, 3478.8608455661742, 3491.3366460372704, 3540.5751309842985, 3558.377283975294, 941.9905800941972, 1173.771114632647, 1243.6269119264184, 1247.5571172233817, 1248.0140688855793, 10604.204684648683, 10688.78622427555, 10704.625338113128, 10707.839382409271, 10739.087177589914, 4344.4352234209355, 4643.721376717408, 4672.742999135012, 4682.601978831837, 4690.420268852117, 9371.250299975985, 9417.105374989365, 9429.0, 9441.480718560546, 9443.433393996245, 4398.8020539075815, 5137.614678899097, 5219.895602088013, 5223.582113430916, 5231.790728370898, 4666.160091183551, 4745.691530050535, 4759.119562880878, 4773.403324584427, 4805.087033463631, 3764.661180493769, 3830.9081911655044, 3847.769133851978, 3902.9804850975565, 3929.390944403622, 1174.1663418972626, 1214.5991822698488, 1251.8059700746217, 1270.987290127087, 1275.8724127587168, 890.1810334492283, 910.6220918318955, 912.3203213605774, 936.6347124621403, 938.596403546468, 5555.250041244381, 5634.887302253932, 5661.7735290588735, 5663.858403539944, 5862.618929769551, 980.127686359141, 1009.7425156585024, 1010.2221289607108, 1033.8397548380012, 1042.3745752548562, 3540.716742660581, 3639.253952939641, 3738.6635202831876, 3741.775493470346, 3885.96114038856, 9700.466474343837, 9937.254705897087, 10023.749406264757, 10030.398176109456, 10042.895281519028, 9704.0, 9933.751656208653, 9949.303548751517, 9959.55182016811, 9985.251106167067, 7534.208908064684, 7839.804004899923, 7848.686052557834, 7852.136265010844, 7852.528848269122, 4928.728919909439, 5477.4796394342475, 5529.391766905717, 5540.252065971043, 5568.638038527482, 8974.955125224333, 9032.187103160748, 9093.40892841963, 9110.90889091127, 9221.953890230774, 9127.041660625538, 9269.119433653792, 9370.906290937277, 9380.343375963617, 9388.202002829836, 6652.0, 6762.053312536151, 6775.220849602359, 6791.2190098139345, 6792.456603471809, 7686.385089192963, 7737.303642671961, 7745.0, 7801.29788319054, 7804.609769511615, 6369.777057802955, 7032.12098487689, 7066.222715501393, 7129.144502659714, 7133.429325654039, 5848.56135789826, 6779.661016949231, 6975.0, 7001.404880585003, 7031.824204394931, 6314.052892066043, 6392.137061496825, 6411.51913606472, 6414.262359828679, 6438.6136831790245, 4919.286703428054, 4958.652894297365, 4982.4768399319, 5027.824026159067, 5173.81891633791, 4967.40391153064, 5003.074431230234, 5007.29897814299, 5019.422766381913, 5100.209467532539], "value": 4396.742250559856, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.6157974885788, 307.3376872839033, 353.0291697830965, 353.58453816765297, 360.19496425489046, 4451.443569553806, 5366.812161574346, 5395.973020134898, 5500.972495137524, 5502.202180683798, 4192.916141677165, 4298.978505107478, 5210.55710264627, 5274.892272622744, 5360.839174824761, 3234.9514757278544, 4063.735857169282, 4266.765327906964, 4301.40847886281, 4304.440422745039, 3295.110320213541, 3671.4676371926053, 3900.874023657114, 4094.4472496213075, 4283.37891005804, 3897.8635747748835, 4015.8393664253394, 4025.9597404025876, 4043.6765058795227, 4051.3315302974975, 3345.8327083645963, 3627.6372362763777, 3999.360102383619, 4043.6158564936286, 4066.6136717011836, 3234.9353012939846, 3533.752637315389, 3537.434010558324, 3540.150363912668, 3564.7861128332383, 4925.857252095314, 5218.556422704075, 5278.9208161877605, 5289.41317604717, 5333.546648534879, 4627.583517483443, 4657.301404789275, 4667.183242932507, 4674.555917187861, 4699.801550604574, 5725.484706376405, 5731.111677689923, 5766.682832444214, 5767.36558978512, 5780.7687692492245, 3200.631927328364, 3768.2651882882847, 3862.439946207812, 3875.418687196943, 3921.3921842114232, 5253.0, 5758.971205143989, 5941.029485257372, 5977.8804423911715, 6064.756040265086, 3913.178232571135, 3950.20995800841, 3953.347697629916, 3958.7030972677167, 3965.7422267552515, 3838.3091043612417, 3915.667168290727, 3943.645071943539, 4003.0, 4012.558618551957, 4062.695297852673, 4148.958510414857, 4239.554846741109, 4636.91032607337, 4706.599939005223, 5357.455208775878, 5398.703071331036, 5422.177364957695, 5543.592843689276, 5780.6820624865195, 1024.779672370444, 1199.818179093592, 1245.7819881520697, 1381.4336122190055, 1401.5725203812935, 842.9968337678143, 948.1940350701848, 999.4253304350028, 1009.4397609326894, 1013.9239557033252, 4325.6106950374615, 4790.928136077961, 4958.975205123951, 5246.737663116829, 5273.126146250303, 5648.181013752983, 5721.971390143106, 5752.827415177551, 5753.798617048467, 5762.942370576325, 4133.751974881517, 4848.369711937426, 4871.805127794849, 5001.7499125043605, 5113.130767769489, 2443.68232129822, 2960.259935016246, 2965.747911427553, 2972.9554056689167, 2973.8959136430553, 5096.923546146811, 5236.869078272997, 5426.674399535961, 5813.738381772746, 5840.561957853178, 4976.6765160265195, 5226.895462090737, 5568.104585615457, 5694.943050569441, 6057.698074731662, 5615.4665306796605, 6146.385361463907, 6157.951813852172, 6179.938200617937, 6182.505399568024, 5543.667379957215, 5916.136417028622, 5918.496927761188, 5963.552733544915, 6045.697715114315, 4833.492483289204, 5810.883782324415, 5836.614763437281, 5871.530277577869, 5880.117982302689, 4887.997960418177, 5111.05445492585, 5128.051310507419, 5731.083026715705, 6128.448439640454, 3285.704286614216, 3697.3529632314776, 3897.454356390051, 3979.900502487461, 3983.7211395203217, 4856.9757151215435, 4886.755662216804, 5316.6544174628525, 5474.315710536183, 5839.211706419579, 4817.566419022305, 5193.597728613328, 5201.843944681666, 5660.603757736919, 5676.77292908279, 4960.751962401793, 5633.6619802811965, 5695.288088988877, 5719.0, 5785.27684039495, 5259.316288882421, 5476.890462190813, 5486.533644640251, 5578.573320605549, 5589.692566908859, 5669.0, 5675.864827034692, 5683.829485115453, 5695.174199741097, 5760.395158508464, 5333.5465685784975, 5513.972430137984, 5577.2191893135805, 5596.0, 5649.887002260014], "value": 4106.203346741647, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.10531952874, 706.8268274272804, 763.5495057915829, 798.832245228502, 808.4664121679691, 12502.9997600192, 13244.0, 15529.301181446834, 15658.84341156588, 15791.210439478024, 14231.0, 14240.430382784676, 14367.64080897978, 14382.424703011868, 14413.855861441383, 673.7742856143198, 693.8820400531922, 843.2663582683074, 854.6965827131372, 877.7937184761607, 856.2721686566431, 863.952482613456, 875.8686197070426, 876.128252388874, 877.403365711316, 928.4058202750246, 932.6735642525095, 933.9299552533553, 938.2728385501223, 940.5626383731563, 915.2677857713836, 924.96300147994, 931.3713243560569, 932.2262522106669, 933.1182032978837, 689.8861687821503, 691.9342662447059, 692.3076923076923, 692.8787462194095, 696.3976160621077, 15200.087994720352, 15411.460598878986, 15473.690526189526, 15563.688726225526, 15666.373345066184, 14306.642333941734, 14566.4901728439, 14630.268486575735, 14637.70724585513, 14647.0, 11708.238964467282, 11767.529298828036, 11790.587329443428, 11839.052875769918, 11909.583164589285, 3673.7061035117126, 3699.574548926854, 3727.2918145552258, 3778.7165962552644, 3844.500214972036, 106034.4094838578, 108182.0, 110098.44950775275, 110223.0, 110517.0, 10244.846327305097, 10261.384316941007, 10274.280800344053, 10330.0, 10428.426436545911, 20066.698999515018, 20069.197232110844, 20142.798572014384, 20459.488512787, 20803.06386212624, 7473.140588832354, 7603.809904752315, 7604.771856844303, 7774.144844067148, 7836.0, 4204.66362690984, 4222.9577704223175, 4248.893777655521, 4272.252355837716, 4364.301711726109, 3475.652434756504, 3563.501109844624, 3563.750537462353, 3565.625609310985, 3570.6786389225094, 620.6431302001367, 640.4268179978893, 650.7071817682062, 656.4813797100237, 659.8779225843234, 627.5387590121258, 638.4636904999822, 641.4355367276767, 644.1465058797113, 650.4796163069525, 9553.426794392359, 9692.51537423126, 9741.172000379906, 9748.366356186967, 9814.656487023063, 622.9750809967641, 645.4836131095105, 665.7337065173872, 680.7583307925714, 696.7352406085756, 3971.404289356562, 4008.218397412535, 4063.22798668258, 4063.6952228582973, 4186.811593478301, 83150.0, 94048.0, 95268.52365738127, 96104.0, 97743.51128244313, 85750.57124714337, 86895.56552217198, 87695.0, 87902.56048719844, 89375.10624893798, 74960.62519687366, 76879.23120768832, 76969.0, 77301.2269877294, 78505.21494785094, 6493.837654058686, 7933.722319719013, 7949.7217597386125, 8166.387520935835, 8192.877106843522, 51567.74216128895, 51947.48052519577, 52067.73966130296, 52293.7385313071, 52487.73756131195, 65320.34679653143, 66507.66746166431, 67240.65518689697, 68143.65928170526, 68366.0, 10991.945040274748, 11044.779104418027, 11049.72375690582, 11107.777844443228, 11178.608748693756, 11912.0, 11926.522939082339, 11982.340971246666, 12123.818142728043, 12215.75568488643, 11541.422928853355, 11636.36000020007, 11716.179867409199, 11942.984846288164, 12168.939155304166, 11481.311121332747, 11583.710407239887, 11652.592159274385, 11774.94112529432, 12018.459169337226, 9399.718008459757, 11252.943735281271, 11264.831027534758, 11465.36940468249, 11840.993515551274, 9289.117533834275, 9493.810123797348, 9572.0, 9773.12041916231, 9932.205423565953, 8670.176333248332, 9323.393979391318, 9327.067293270618, 9340.906590934004, 9361.204297634777], "value": 7150.023130737411, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1601.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 118.0, 121.0, 120.0, 118.0, 120.0, 118.0, 118.0, 119.0], "value": 117.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 114.0, 113.0, 146.0, 112.0, 126.0, 142.0, 98.0, 157.0, 126.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 210.0, 241.0, 278.0, 273.0, 195.0, 198.0, 212.0, 210.0, 175.0], "value": 219.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 56.0, 62.0, 83.0, 61.0, 67.0, 69.0, 72.0, 93.0, 83.0], "value": 74.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 102.0, 116.0, 58.0, 77.0, 103.0, 115.0, 100.0, 87.0, 83.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 75.0, 139.0, 84.0, 90.0, 86.0, 124.0, 129.0], "value": 99.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 106.0, 96.0, 97.0, 94.0, 96.0, 100.0, 151.0, 112.0, 97.0], "value": 104.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 124.0, 92.0, 94.0, 91.0, 94.0, 95.0, 92.0, 93.0, 91.0], "value": 101.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 61.0, 63.0, 63.0, 62.0, 61.0, 63.0, 63.0, 62.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 96.0, 115.0, 112.0, 112.0, 73.0, 71.0, 70.0, 72.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 88.0, 86.0, 86.0, 87.0, 89.0, 87.0, 80.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 151.0, 153.0, 159.0, 177.0, 164.0, 158.0, 160.0, 229.0, 192.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 178.0, 230.0, 175.0, 244.0, 209.0, 176.0, 179.0, 178.0, 175.0], "value": 192.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 69.0, 68.0, 68.0, 65.0, 66.0, 67.0, 66.0, 68.0], "value": 67.4, "unit": "score"}]}]} 23:23:08 INFO - Return code: 0 23:23:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:23:08 ERROR - # TBPL SUCCESS # 23:23:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:23:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:23:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:23:08 INFO - 2017-01-16 23:23:08.107 screenresolution[1662:11407] starting screenresolution argv=screenresolution get 23:23:08 INFO - 2017-01-16 23:23:08.110 screenresolution[1662:11407] Display 0: 1600x1200x32@60 23:23:08 INFO - 2017-01-16 23:23:08.117 screenresolution[1663:11410] starting screenresolution argv=screenresolution list 23:23:08 INFO - Available Modes on Display 0 23:23:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:23:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:23:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:23:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:23:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:23:08 INFO - Intel Iris: 23:23:08 INFO - Chipset Model: Intel Iris 23:23:08 INFO - Type: GPU 23:23:08 INFO - Bus: Built-In 23:23:08 INFO - VRAM (Dynamic, Max): 1536 MB 23:23:08 INFO - Vendor: Intel (0x8086) 23:23:08 INFO - Device ID: 0x0a2e 23:23:08 INFO - Revision ID: 0x0009 23:23:08 INFO - Displays: 23:23:08 INFO - Display: 23:23:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:23:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:23:08 INFO - Display Serial Number: 1600x1200 60 23:23:08 INFO - Main Display: Yes 23:23:08 INFO - Mirror: Off 23:23:08 INFO - Online: Yes 23:23:08 INFO - Rotation: Supported 23:23:08 INFO - Return code: 0 23:23:08 INFO - Running post-action listener: _resource_record_post_action 23:23:08 INFO - [mozharness: 2017-01-17 07:23:08.357807Z] Finished run-tests step (success) 23:23:08 INFO - Running post-run listener: _resource_record_post_run 23:23:08 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 33726976; Write bytes: 556070400; Read time: 317; Write time: 1926 23:23:08 INFO - TinderboxPrint: CPU usage
54.3% 23:23:08 INFO - TinderboxPrint: I/O read bytes / time
33,726,976 / 317 23:23:08 INFO - TinderboxPrint: I/O write bytes / time
556,070,400 / 1,926 23:23:08 INFO - TinderboxPrint: CPU idle
2,197.7 (45.7%) 23:23:08 INFO - TinderboxPrint: CPU system
961.8 (20.0%) 23:23:08 INFO - TinderboxPrint: CPU user
1,646.3 (34.3%) 23:23:08 INFO - TinderboxPrint: Swap in / out
281,645,056 / 0 23:23:08 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 249883136; Write bytes: 243983872; Read time: 18201; Write time: 470 23:23:08 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29712896; Write bytes: 300412928; Read time: 282; Write time: 1426 23:23:08 INFO - Running post-run listener: _upload_blobber_files 23:23:08 INFO - Blob upload gear active. 23:23:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:23:08 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 23:23:08 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'] 23:23:08 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 23:23:09 INFO - (blobuploader) - INFO - Open directory for files ... 23:23:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:23:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:23:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:23:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:23:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:23:09 INFO - (blobuploader) - INFO - Done attempting. 23:23:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:23:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:23:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:23:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:23:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:23:11 INFO - (blobuploader) - INFO - Done attempting. 23:23:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:23:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:23:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:23:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:23:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:23:12 INFO - (blobuploader) - INFO - Done attempting. 23:23:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:23:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:23:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:23:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:23:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:23:14 INFO - (blobuploader) - INFO - Done attempting. 23:23:14 INFO - (blobuploader) - INFO - Iteration through files over. 23:23:14 INFO - Return code: 0 23:23:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:23:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:23:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d67f66c3fdf55ce3b754dbf2bca25dccd5319383180be6f66cc5657da895071e65a5cf730bbc040364f109c44709021d5b4a03c2ae266bb70479abb2459f1c9c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d29600016c1670898345ce45f389773b3e93c69a9563f4774b62351e97049e2f5ea8e452386cec18e26cb27fcfbc169a702c4097793ce64e8144142c89c639ef", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/467a460b4c59a8b220e85dbc375fd5fe6673c8332f508758fecd62b9c2aefbb855924bf7bb8204048e589349736261a23a724770c112d6df8e6d05762e0f41d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/547bc2a4895872e0fae17fdd7f883a0d4d4b46b098f6d660e672496fb62cda1dab5875588f6c72a40a960e8cf0ec2738102ff20145b4d5dab162f8a336486ec6"} 23:23:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:23:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:23:14 INFO - Contents: 23:23:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d67f66c3fdf55ce3b754dbf2bca25dccd5319383180be6f66cc5657da895071e65a5cf730bbc040364f109c44709021d5b4a03c2ae266bb70479abb2459f1c9c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d29600016c1670898345ce45f389773b3e93c69a9563f4774b62351e97049e2f5ea8e452386cec18e26cb27fcfbc169a702c4097793ce64e8144142c89c639ef", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/467a460b4c59a8b220e85dbc375fd5fe6673c8332f508758fecd62b9c2aefbb855924bf7bb8204048e589349736261a23a724770c112d6df8e6d05762e0f41d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/547bc2a4895872e0fae17fdd7f883a0d4d4b46b098f6d660e672496fb62cda1dab5875588f6c72a40a960e8cf0ec2738102ff20145b4d5dab162f8a336486ec6"} 23:23:14 INFO - Running post-run listener: copy_logs_to_upload_dir 23:23:14 INFO - Copying logs to upload dir... 23:23:14 INFO - mkdir: /builds/slave/test/build/upload/logs 23:23:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.092889 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-16 23:23:14.301215) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-16 23:23:14.304193) ========= 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.i7LCQHSaSm/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.E99NVySbMq/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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/d67f66c3fdf55ce3b754dbf2bca25dccd5319383180be6f66cc5657da895071e65a5cf730bbc040364f109c44709021d5b4a03c2ae266bb70479abb2459f1c9c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d29600016c1670898345ce45f389773b3e93c69a9563f4774b62351e97049e2f5ea8e452386cec18e26cb27fcfbc169a702c4097793ce64e8144142c89c639ef", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/467a460b4c59a8b220e85dbc375fd5fe6673c8332f508758fecd62b9c2aefbb855924bf7bb8204048e589349736261a23a724770c112d6df8e6d05762e0f41d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/547bc2a4895872e0fae17fdd7f883a0d4d4b46b098f6d660e672496fb62cda1dab5875588f6c72a40a960e8cf0ec2738102ff20145b4d5dab162f8a336486ec6"} build_url:https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011039 build_url: 'https://queue.taskcluster.net/v1/task/P3k2CdPjQPaQgllhleUBMA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d67f66c3fdf55ce3b754dbf2bca25dccd5319383180be6f66cc5657da895071e65a5cf730bbc040364f109c44709021d5b4a03c2ae266bb70479abb2459f1c9c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d29600016c1670898345ce45f389773b3e93c69a9563f4774b62351e97049e2f5ea8e452386cec18e26cb27fcfbc169a702c4097793ce64e8144142c89c639ef", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/467a460b4c59a8b220e85dbc375fd5fe6673c8332f508758fecd62b9c2aefbb855924bf7bb8204048e589349736261a23a724770c112d6df8e6d05762e0f41d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/547bc2a4895872e0fae17fdd7f883a0d4d4b46b098f6d660e672496fb62cda1dab5875588f6c72a40a960e8cf0ec2738102ff20145b4d5dab162f8a336486ec6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-16 23:23:14.340789) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 23:23:14.341125) ========= 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.i7LCQHSaSm/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.E99NVySbMq/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.005243 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 23:23:14.389852) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-16 23:23:14.390174) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-16 23:23:14.390520) ========= ========= Total master_lag: 0.18 =========