builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0233 starttime: 1472156521.46 results: success (0) buildid: 20160825112817 builduid: c5d1e6f639174e498865aa0d36479e0e revision: 4dd3a3052ef07b1618eb7653e5be89644b2bc134 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 13:22:01.462253) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 13:22:01.462718) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 13:22:01.462999) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 13:22:01.486688) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:22:01.487086) ========= 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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 --2016-08-25 13:22:01-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.22M=0.001s 2016-08-25 13:22:01 (8.22 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088923 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:22:01.597691) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:22:01.598005) ========= 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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.020988 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:22:01.646315) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 13:22:01.646679) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 4dd3a3052ef07b1618eb7653e5be89644b2bc134 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 4dd3a3052ef07b1618eb7653e5be89644b2bc134 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0RuhVS8AGl/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.xAmmhlLdsT/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 2016-08-25 13:22:01,727 truncating revision to first 12 chars 2016-08-25 13:22:01,727 Setting DEBUG logging. 2016-08-25 13:22:01,727 attempt 1/10 2016-08-25 13:22:01,727 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/4dd3a3052ef0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 13:22:02,810 unpacking tar archive at: autoland-4dd3a3052ef0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.341687 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 13:22:03.010672) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 13:22:03.011049) ========= script_repo_revision: 4dd3a3052ef07b1618eb7653e5be89644b2bc134 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 13:22:03.011613) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 13:22:03.011958) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 13:22:03.030595) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-25 13:22:03.030974) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0RuhVS8AGl/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.xAmmhlLdsT/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 13:22:03 INFO - MultiFileLogger online at 20160825 13:22:03 in /builds/slave/test 13:22:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 13:22:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:22:03 INFO - {'append_to_log': False, 13:22:03 INFO - 'base_work_dir': '/builds/slave/test', 13:22:03 INFO - 'blob_upload_branch': 'Autoland', 13:22:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:22:03 INFO - 'branch': 'Autoland', 13:22:03 INFO - 'buildbot_json_path': 'buildprops.json', 13:22:03 INFO - 'config_files': ('talos/mac_config.py',), 13:22:03 INFO - 'default_actions': ('clobber', 13:22:03 INFO - 'read-buildbot-config', 13:22:03 INFO - 'download-and-extract', 13:22:03 INFO - 'populate-webroot', 13:22:03 INFO - 'create-virtualenv', 13:22:03 INFO - 'install', 13:22:03 INFO - 'run-tests'), 13:22:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:22:03 INFO - 'download_minidump_stackwalk': True, 13:22:03 INFO - 'download_symbols': 'ondemand', 13:22:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:22:03 INFO - 'tooltool.py': '/tools/tooltool.py', 13:22:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:22:03 INFO - '/tools/misc-python/virtualenv.py')}, 13:22:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:22:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:22:03 INFO - 'installer_path': 'installer.exe', 13:22:03 INFO - 'log_level': 'info', 13:22:03 INFO - 'log_name': 'talos', 13:22:03 INFO - 'log_to_console': True, 13:22:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:22:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:22:03 INFO - 'opt_config_files': (), 13:22:03 INFO - 'pip_index': False, 13:22:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:22:03 INFO - 'cmd': ('bash', 13:22:03 INFO - '-c', 13:22:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:22:03 INFO - 'enabled': True, 13:22:03 INFO - 'halt_on_failure': False, 13:22:03 INFO - 'name': 'check_screen_resolution'},), 13:22:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:22:03 INFO - 'cmd': ('bash', 13:22:03 INFO - '-c', 13:22:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:22:03 INFO - 'enabled': True, 13:22:03 INFO - 'halt_on_failure': False, 13:22:03 INFO - 'name': 'check_screen_resolution'},), 13:22:03 INFO - 'run_cmd_checks_enabled': True, 13:22:03 INFO - 'sps_profile': False, 13:22:03 INFO - 'sps_profile_interval': 0, 13:22:03 INFO - 'suite': 'dromaeojs', 13:22:03 INFO - 'system_bits': '32', 13:22:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:22:03 INFO - 'title': 't-yosemite-r7-0233', 13:22:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:22:03 INFO - 'use_talos_json': True, 13:22:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:22:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:22:03 INFO - 'work_dir': 'build'} 13:22:03 INFO - [mozharness: 2016-08-25 20:22:03.222781Z] Running clobber step. 13:22:03 INFO - Running pre-action listener: _resource_record_pre_action 13:22:03 INFO - Running main action method: clobber 13:22:03 INFO - rmtree: /builds/slave/test/build 13:22:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:22:04 INFO - Running post-action listener: _resource_record_post_action 13:22:04 INFO - [mozharness: 2016-08-25 20:22:04.816889Z] Finished clobber step (success) 13:22:04 INFO - [mozharness: 2016-08-25 20:22:04.817053Z] Running read-buildbot-config step. 13:22:04 INFO - Running pre-action listener: _resource_record_pre_action 13:22:04 INFO - Running main action method: read_buildbot_config 13:22:04 INFO - Using buildbot properties: 13:22:04 INFO - { 13:22:04 INFO - "project": "", 13:22:04 INFO - "product": "firefox", 13:22:04 INFO - "script_repo_revision": "production", 13:22:04 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 13:22:04 INFO - "repository": "", 13:22:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 13:22:04 INFO - "buildid": "20160825112817", 13:22:04 INFO - "pgo_build": "False", 13:22:04 INFO - "basedir": "/builds/slave/test", 13:22:04 INFO - "buildnumber": 146, 13:22:04 INFO - "slavename": "t-yosemite-r7-0233", 13:22:04 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:22:04 INFO - "platform": "yosemite_r7", 13:22:04 INFO - "scheduler": "tests-autoland-macosx64-talos", 13:22:04 INFO - "branch": "autoland", 13:22:04 INFO - "repo_path": "integration/autoland", 13:22:04 INFO - "revision": "4dd3a3052ef07b1618eb7653e5be89644b2bc134", 13:22:04 INFO - "stage_platform": "macosx64", 13:22:04 INFO - "builduid": "c5d1e6f639174e498865aa0d36479e0e", 13:22:04 INFO - "slavebuilddir": "test" 13:22:04 INFO - } 13:22:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 13:22:04 INFO - Running post-action listener: _resource_record_post_action 13:22:04 INFO - [mozharness: 2016-08-25 20:22:04.820389Z] Finished read-buildbot-config step (success) 13:22:04 INFO - [mozharness: 2016-08-25 20:22:04.820514Z] Running download-and-extract step. 13:22:04 INFO - Running pre-action listener: _resource_record_pre_action 13:22:04 INFO - Running main action method: download_and_extract 13:22:04 INFO - mkdir: /builds/slave/test/build/tests 13:22:04 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')]} 13:22:04 INFO - trying https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 13:22:04 INFO - Downloading https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 13:22:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 13:22:06 INFO - Downloaded 1199 bytes. 13:22:06 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 13:22:06 INFO - Using the following test package requirements: 13:22:06 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 13:22:06 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:22:06 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 13:22:06 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:22:06 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 13:22:06 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:22:06 INFO - u'jsshell-mac.zip'], 13:22:06 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:22:06 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 13:22:06 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 13:22:06 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:22:06 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 13:22:06 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:22:06 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 13:22:06 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:22:06 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 13:22:06 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:22:06 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 13:22:06 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:22: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')]} 13:22:06 INFO - trying https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:22:06 INFO - Downloading https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:22:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:22:07 INFO - Downloaded 16346805 bytes. 13:22:07 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 13:22:13 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:22:13 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')]} 13:22:13 INFO - trying https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:22:13 INFO - Downloading https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:22:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:22:14 INFO - Downloaded 16346805 bytes. 13:22:14 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 13:22:21 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')]} 13:22:21 INFO - trying https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 13:22:21 INFO - Downloading https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 13:22:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:22:22 INFO - Downloaded 14413998 bytes. 13:22:22 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 13:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:22 INFO - trying https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:22:22 INFO - Downloading https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 13:22:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 13:22:25 INFO - Downloaded 111514526 bytes. 13:22:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:22:25 INFO - mkdir: /builds/slave/test/properties 13:22:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:22:25 INFO - Writing to file /builds/slave/test/properties/build_url 13:22:25 INFO - Contents: 13:22:25 INFO - build_url:https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:22:25 INFO - Running post-action listener: _resource_record_post_action 13:22:25 INFO - Running post-action listener: set_extra_try_arguments 13:22:25 INFO - [mozharness: 2016-08-25 20:22:25.833071Z] Finished download-and-extract step (success) 13:22:25 INFO - [mozharness: 2016-08-25 20:22:25.833222Z] Running populate-webroot step. 13:22:25 INFO - Running pre-action listener: _resource_record_pre_action 13:22:25 INFO - Running main action method: populate_webroot 13:22:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:22:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:22:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:22:25 INFO - '--tppagecycles', 13:22:25 INFO - '7'], 13:22:25 INFO - 'tests': ['tsvgm']}}, 13:22:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:22:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:22:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:22:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:22:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:22:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:22:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:22:25 INFO - 'talos_options': ['--disable-e10s'], 13:22:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:22:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:22:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:22:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:22:25 INFO - 'talos_options': ['--disable-e10s'], 13:22:25 INFO - 'tests': ['damp', 'tps']}, 13:22:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:22:25 INFO - 'tests': ['damp', 'tps']}, 13:22:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:22:25 INFO - 'tests': ['dromaeo_dom']}, 13:22:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:22:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:22:25 INFO - 'tests': ['basic_compositor_video']}, 13:22:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:22:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:22:25 INFO - 'tests': ['a11yr', 13:22:25 INFO - 'ts_paint', 13:22:25 INFO - 'tpaint', 13:22:25 INFO - 'sessionrestore', 13:22:25 INFO - 'sessionrestore_no_auto_restore', 13:22:25 INFO - 'tabpaint']}, 13:22:25 INFO - 'other-e10s': {'tests': ['a11yr', 13:22:25 INFO - 'ts_paint', 13:22:25 INFO - 'tpaint', 13:22:25 INFO - 'sessionrestore', 13:22:25 INFO - 'sessionrestore_no_auto_restore', 13:22:25 INFO - 'tabpaint']}, 13:22:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:22:25 INFO - 'ts_paint', 13:22:25 INFO - 'tpaint', 13:22:25 INFO - 'sessionrestore', 13:22:25 INFO - 'sessionrestore_no_auto_restore', 13:22:25 INFO - 'tabpaint']}, 13:22:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:22:25 INFO - 'ts_paint', 13:22:25 INFO - 'tpaint', 13:22:25 INFO - 'sessionrestore', 13:22:25 INFO - 'sessionrestore_no_auto_restore', 13:22:25 INFO - 'tabpaint']}, 13:22:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:22:25 INFO - 'tests': ['a11yr', 13:22:25 INFO - 'ts_paint', 13:22:25 INFO - 'tpaint', 13:22:25 INFO - 'sessionrestore', 13:22:25 INFO - 'sessionrestore_no_auto_restore', 13:22:25 INFO - 'tabpaint']}, 13:22:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:22:25 INFO - 'tests': ['a11yr', 13:22:25 INFO - 'ts_paint', 13:22:25 INFO - 'tpaint', 13:22:25 INFO - 'sessionrestore', 13:22:25 INFO - 'sessionrestore_no_auto_restore', 13:22:25 INFO - 'tabpaint']}, 13:22:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:22:25 INFO - 'tests': ['tsvgx', 13:22:25 INFO - 'tsvgr_opacity', 13:22:25 INFO - 'tart', 13:22:25 INFO - 'tscrollx', 13:22:25 INFO - 'cart']}, 13:22:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:22:25 INFO - 'tsvgr_opacity', 13:22:25 INFO - 'tart', 13:22:25 INFO - 'tscrollx', 13:22:25 INFO - 'cart']}, 13:22:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:22:25 INFO - 'talos_options': ['--disable-e10s'], 13:22:25 INFO - 'tests': ['tp5o']}, 13:22:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:22:25 INFO - 'tests': ['tp5o']}, 13:22:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:22:25 INFO - 'talos_options': ['--disable-e10s', 13:22:25 INFO - '--xperf_path', 13:22:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:22:25 INFO - 'tests': ['tp5n']}, 13:22:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:22:25 INFO - 'talos_options': ['--xperf_path', 13:22:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:22:25 INFO - 'tests': ['tp5n']}}, 13:22:25 INFO - 'talos.zip': {'path': '', 13:22:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:22:25 INFO - Running post-action listener: _resource_record_post_action 13:22:25 INFO - [mozharness: 2016-08-25 20:22:25.851741Z] Finished populate-webroot step (success) 13:22:25 INFO - [mozharness: 2016-08-25 20:22:25.851873Z] Running create-virtualenv step. 13:22:25 INFO - Running pre-action listener: _resource_record_pre_action 13:22:25 INFO - Running main action method: create_virtualenv 13:22:25 INFO - Creating virtualenv /builds/slave/test/build/venv 13:22:25 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 13:22:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:22:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:22:25 INFO - Using real prefix '/tools/python27' 13:22:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:22:26 INFO - Installing distribute.............................................................................................................................................................................................done. 13:22:29 INFO - Installing pip.................done. 13:22:29 INFO - Return code: 0 13:22:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:22: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')]} 13:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22: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')]} 13:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:29 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 0x1063f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106488618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5084bd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106357d30>, '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 0x1060bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634fb20>, '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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 13:22:29 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 13:22:29 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 13:22:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0RuhVS8AGl/Render', 13:22:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:29 INFO - 'HOME': '/Users/cltbld', 13:22:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:29 INFO - 'LOGNAME': 'cltbld', 13:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:29 INFO - 'MOZ_NO_REMOTE': '1', 13:22:29 INFO - 'NO_EM_RESTART': '1', 13:22:29 INFO - 'PAGER': '/bin/cat', 13:22:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:29 INFO - 'PWD': '/builds/slave/test', 13:22:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:29 INFO - 'SHELL': '/bin/bash', 13:22:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAmmhlLdsT/Listeners', 13:22:29 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 13:22:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:29 INFO - 'USER': 'cltbld', 13:22:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:29 INFO - 'XPC_FLAGS': '0x0', 13:22:29 INFO - 'XPC_SERVICE_NAME': '0', 13:22:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:29 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 13:22:29 INFO - Cleaning up... 13:22:29 INFO - Return code: 0 13:22:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:22: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')]} 13:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22: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')]} 13:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:29 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 0x1063f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106488618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5084bd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106357d30>, '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 0x1060bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634fb20>, '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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 13:22:29 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 13:22:29 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 13:22:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0RuhVS8AGl/Render', 13:22:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:29 INFO - 'HOME': '/Users/cltbld', 13:22:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:29 INFO - 'LOGNAME': 'cltbld', 13:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:29 INFO - 'MOZ_NO_REMOTE': '1', 13:22:29 INFO - 'NO_EM_RESTART': '1', 13:22:29 INFO - 'PAGER': '/bin/cat', 13:22:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:29 INFO - 'PWD': '/builds/slave/test', 13:22:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:29 INFO - 'SHELL': '/bin/bash', 13:22:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAmmhlLdsT/Listeners', 13:22:29 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 13:22:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:29 INFO - 'USER': 'cltbld', 13:22:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:29 INFO - 'XPC_FLAGS': '0x0', 13:22:29 INFO - 'XPC_SERVICE_NAME': '0', 13:22:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:30 INFO - Downloading/unpacking psutil>=3.1.1 13:22:30 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 13:22:30 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 13:22:30 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 13:22:30 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 13:22:30 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 13:22:30 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 13:22:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:22:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:22:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:22:33 INFO - Installing collected packages: psutil 13:22:33 INFO - Running setup.py install for psutil 13:22:33 INFO - building 'psutil._psutil_osx' extension 13:22:33 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 13:22:33 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 13:22:33 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 13:22:34 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 13:22:34 INFO - building 'psutil._psutil_posix' extension 13:22:34 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 13:22:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:22:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:22:34 INFO - ^ 13:22:34 INFO - 1 warning generated. 13:22:34 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 13:22:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:22:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:22:34 INFO - Successfully installed psutil 13:22:34 INFO - Cleaning up... 13:22:34 INFO - Return code: 0 13:22:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:22:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1063f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106488618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5084bd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106357d30>, '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 0x1060bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634fb20>, '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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 13:22:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:22:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:22:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0RuhVS8AGl/Render', 13:22:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:34 INFO - 'HOME': '/Users/cltbld', 13:22:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:34 INFO - 'LOGNAME': 'cltbld', 13:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:34 INFO - 'MOZ_NO_REMOTE': '1', 13:22:34 INFO - 'NO_EM_RESTART': '1', 13:22:34 INFO - 'PAGER': '/bin/cat', 13:22:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:34 INFO - 'PWD': '/builds/slave/test', 13:22:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:34 INFO - 'SHELL': '/bin/bash', 13:22:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAmmhlLdsT/Listeners', 13:22:34 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 13:22:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:34 INFO - 'USER': 'cltbld', 13:22:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:34 INFO - 'XPC_FLAGS': '0x0', 13:22:34 INFO - 'XPC_SERVICE_NAME': '0', 13:22:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:22:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:22:37 INFO - Installing collected packages: mozsystemmonitor 13:22:37 INFO - Running setup.py install for mozsystemmonitor 13:22:37 INFO - Successfully installed mozsystemmonitor 13:22:37 INFO - Cleaning up... 13:22:37 INFO - Return code: 0 13:22:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:22: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')]} 13:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22: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')]} 13:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22: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', '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 0x1063f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106488618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5084bd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106357d30>, '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 0x1060bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634fb20>, '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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 13:22: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:22: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 jsonschema==2.5.1 13:22:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0RuhVS8AGl/Render', 13:22:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:37 INFO - 'HOME': '/Users/cltbld', 13:22:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:37 INFO - 'LOGNAME': 'cltbld', 13:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:37 INFO - 'MOZ_NO_REMOTE': '1', 13:22:37 INFO - 'NO_EM_RESTART': '1', 13:22:37 INFO - 'PAGER': '/bin/cat', 13:22:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:37 INFO - 'PWD': '/builds/slave/test', 13:22:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:37 INFO - 'SHELL': '/bin/bash', 13:22:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAmmhlLdsT/Listeners', 13:22:37 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 13:22:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:37 INFO - 'USER': 'cltbld', 13:22:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:37 INFO - 'XPC_FLAGS': '0x0', 13:22:37 INFO - 'XPC_SERVICE_NAME': '0', 13:22:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:37 INFO - Downloading/unpacking jsonschema==2.5.1 13:22: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 13:22: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 13:22: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 13:22: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 13:22: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 13:22: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 13:22:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:22:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:40 INFO - Downloading functools32-3.2.3-2.tar.gz 13:22:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:22:40 INFO - warning: no files found matching '*.txt' 13:22:40 INFO - no previously-included directories found matching 'build' 13:22:40 INFO - no previously-included directories found matching 'dist' 13:22:40 INFO - no previously-included directories found matching '.git*' 13:22:40 INFO - Installing collected packages: jsonschema, functools32 13:22:40 INFO - Running setup.py install for functools32 13:22:40 INFO - warning: no files found matching '*.txt' 13:22:40 INFO - no previously-included directories found matching 'build' 13:22:40 INFO - no previously-included directories found matching 'dist' 13:22:40 INFO - no previously-included directories found matching '.git*' 13:22:40 INFO - Successfully installed jsonschema functools32 13:22:40 INFO - Cleaning up... 13:22:40 INFO - Return code: 0 13:22:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1063f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106488618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5084bd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106357d30>, '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 0x1060bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634fb20>, '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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 13:22:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:22:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:22:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0RuhVS8AGl/Render', 13:22:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:40 INFO - 'HOME': '/Users/cltbld', 13:22:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:40 INFO - 'LOGNAME': 'cltbld', 13:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:40 INFO - 'MOZ_NO_REMOTE': '1', 13:22:40 INFO - 'NO_EM_RESTART': '1', 13:22:40 INFO - 'PAGER': '/bin/cat', 13:22:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:40 INFO - 'PWD': '/builds/slave/test', 13:22:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:40 INFO - 'SHELL': '/bin/bash', 13:22:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAmmhlLdsT/Listeners', 13:22:40 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 13:22:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:40 INFO - 'USER': 'cltbld', 13:22:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:40 INFO - 'XPC_FLAGS': '0x0', 13:22:40 INFO - 'XPC_SERVICE_NAME': '0', 13:22:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:22:41 INFO - Cleaning up... 13:22:41 INFO - Return code: 0 13:22:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:22:41 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')]} 13:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:41 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')]} 13:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:41 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 0x1063f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106488618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5084bd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106357d30>, '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 0x1060bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634fb20>, '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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 13:22:41 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 13:22:41 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 13:22:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0RuhVS8AGl/Render', 13:22:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:41 INFO - 'HOME': '/Users/cltbld', 13:22:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:41 INFO - 'LOGNAME': 'cltbld', 13:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:41 INFO - 'MOZ_NO_REMOTE': '1', 13:22:41 INFO - 'NO_EM_RESTART': '1', 13:22:41 INFO - 'PAGER': '/bin/cat', 13:22:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:41 INFO - 'PWD': '/builds/slave/test', 13:22:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:41 INFO - 'SHELL': '/bin/bash', 13:22:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAmmhlLdsT/Listeners', 13:22:41 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 13:22:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:41 INFO - 'USER': 'cltbld', 13:22:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:41 INFO - 'XPC_FLAGS': '0x0', 13:22:41 INFO - 'XPC_SERVICE_NAME': '0', 13:22:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:41 INFO - Downloading/unpacking blobuploader==1.2.4 13:22:41 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 13:22:41 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 13:22:41 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 13:22:41 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 13:22:41 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 13:22:41 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 13:22:43 INFO - Downloading blobuploader-1.2.4.tar.gz 13:22:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:22:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:22: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 13:22: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 13:22: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 13:22: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 13:22: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 13:22: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 13:22:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:22:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:22:44 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 13:22:44 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 13:22:44 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 13:22:44 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 13:22:44 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 13:22:44 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 13:22:45 INFO - Downloading docopt-0.6.1.tar.gz 13:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:22:45 INFO - Installing collected packages: blobuploader, requests, docopt 13:22:45 INFO - Running setup.py install for blobuploader 13:22:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:22:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:22:45 INFO - Running setup.py install for requests 13:22:45 INFO - Running setup.py install for docopt 13:22:45 INFO - Successfully installed blobuploader requests docopt 13:22:45 INFO - Cleaning up... 13:22:46 INFO - Return code: 0 13:22:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:22: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')]} 13:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22: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')]} 13:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:46 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 0x1063f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106488618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5084bd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106357d30>, '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 0x1060bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634fb20>, '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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 13:22:46 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 13:22:46 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 13:22:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0RuhVS8AGl/Render', 13:22:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:46 INFO - 'HOME': '/Users/cltbld', 13:22:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:46 INFO - 'LOGNAME': 'cltbld', 13:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:46 INFO - 'MOZ_NO_REMOTE': '1', 13:22:46 INFO - 'NO_EM_RESTART': '1', 13:22:46 INFO - 'PAGER': '/bin/cat', 13:22:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:46 INFO - 'PWD': '/builds/slave/test', 13:22:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:46 INFO - 'SHELL': '/bin/bash', 13:22:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAmmhlLdsT/Listeners', 13:22:46 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 13:22:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:46 INFO - 'USER': 'cltbld', 13:22:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:46 INFO - 'XPC_FLAGS': '0x0', 13:22:46 INFO - 'XPC_SERVICE_NAME': '0', 13:22:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:22:46 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-rMFQN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:22:46 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-HwTUV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:22:46 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-PXO6Li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:22:46 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-wO3Xtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:22:46 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-4iM_WK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:22:46 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-QNNLeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:22:46 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-x8rRi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:22:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-jLVyJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:22:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-3oEUlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:22:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-S270nL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:22:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-ZbGzuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:22:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-5jWrs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:22:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-HabSFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:22:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-k_YFD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:22:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Kivyyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:22:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-w5iUH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:22:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-al5jbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:22:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:22:48 INFO - Running setup.py install for manifestparser 13:22:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:22:48 INFO - Running setup.py install for mozcrash 13:22:48 INFO - Running setup.py install for mozdebug 13:22:48 INFO - Running setup.py install for mozdevice 13:22:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:22:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:22:48 INFO - Running setup.py install for mozfile 13:22:48 INFO - Running setup.py install for mozhttpd 13:22:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:22:48 INFO - Running setup.py install for mozinfo 13:22:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:22:49 INFO - Running setup.py install for mozInstall 13:22:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:22:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:22:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:22:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:22:49 INFO - Running setup.py install for mozleak 13:22:49 INFO - Running setup.py install for mozlog 13:22:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:22:49 INFO - Running setup.py install for moznetwork 13:22:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:22:49 INFO - Running setup.py install for mozprocess 13:22:49 INFO - Running setup.py install for mozprofile 13:22:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:22:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:22:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:22:50 INFO - Running setup.py install for mozrunner 13:22:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:22:50 INFO - Running setup.py install for mozscreenshot 13:22:50 INFO - Running setup.py install for moztest 13:22:50 INFO - Running setup.py install for mozversion 13:22:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:22:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:22:50 INFO - Cleaning up... 13:22:50 INFO - Return code: 0 13:22:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:22: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')]} 13:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22: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')]} 13:22:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:50 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 0x1063f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106488618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5084bd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106357d30>, '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 0x1060bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634fb20>, '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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 13:22:50 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 13:22:50 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 13:22:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0RuhVS8AGl/Render', 13:22:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:50 INFO - 'HOME': '/Users/cltbld', 13:22:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:50 INFO - 'LOGNAME': 'cltbld', 13:22:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:50 INFO - 'MOZ_NO_REMOTE': '1', 13:22:50 INFO - 'NO_EM_RESTART': '1', 13:22:50 INFO - 'PAGER': '/bin/cat', 13:22:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:50 INFO - 'PWD': '/builds/slave/test', 13:22:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:50 INFO - 'SHELL': '/bin/bash', 13:22:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAmmhlLdsT/Listeners', 13:22:50 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 13:22:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:50 INFO - 'USER': 'cltbld', 13:22:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:50 INFO - 'XPC_FLAGS': '0x0', 13:22:50 INFO - 'XPC_SERVICE_NAME': '0', 13:22:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:22:50 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-6y4wPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:22:51 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)) 13:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:22:51 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-5LlKDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:22:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 13:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:22:51 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-3dC2Ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:22:51 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)) 13:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:22:51 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-K9FWxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:22:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 13:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:22:51 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-MbnUoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:22:51 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)) 13:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:22:51 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-jJuhqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:22:51 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)) 13:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:22:51 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-JfVsEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:22:51 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)) 13:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:22:51 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-HUBWab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:22:51 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)) 13:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:22:51 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-su6EwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:22:51 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)) 13:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:22:51 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-VfvR7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:22:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 13:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:22:51 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Fbc3Mo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:22:52 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)) 13:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:22:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-YOwDWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:22:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 13:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:22:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-yfvrem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:22:52 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)) 13:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:22:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-HBOJbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:22:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 13:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:22:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-__HgmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:22:52 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)) 13:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:22:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-d8vXu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:22:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 13:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:22:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-U3TMoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:22:52 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)) 13:22:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:22:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:22:52 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)) 13:22:52 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:22:52 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:22:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:55 INFO - Downloading blessings-1.6.tar.gz 13:22:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:22:55 INFO - Installing collected packages: blessings 13:22:55 INFO - Running setup.py install for blessings 13:22:55 INFO - Successfully installed blessings 13:22:55 INFO - Cleaning up... 13:22:55 INFO - Return code: 0 13:22:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:22:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:22:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:22:55 INFO - Reading from file tmpfile_stdout 13:22:55 INFO - Current package versions: 13:22:55 INFO - blessings == 1.6 13:22:55 INFO - blobuploader == 1.2.4 13:22:55 INFO - docopt == 0.6.1 13:22:55 INFO - functools32 == 3.2.3-2 13:22:55 INFO - jsonschema == 2.5.1 13:22:55 INFO - manifestparser == 1.1 13:22:55 INFO - mozInstall == 1.12 13:22:55 INFO - mozcrash == 0.17 13:22:55 INFO - mozdebug == 0.1 13:22:55 INFO - mozdevice == 0.48 13:22:55 INFO - mozfile == 1.2 13:22:55 INFO - mozhttpd == 0.7 13:22:55 INFO - mozinfo == 0.9 13:22:55 INFO - mozleak == 0.1 13:22:55 INFO - mozlog == 3.3 13:22:55 INFO - moznetwork == 0.27 13:22:55 INFO - mozprocess == 0.23 13:22:55 INFO - mozprofile == 0.28 13:22:55 INFO - mozrunner == 6.12 13:22:55 INFO - mozscreenshot == 0.1 13:22:55 INFO - mozsystemmonitor == 0.3 13:22:55 INFO - moztest == 0.7 13:22:55 INFO - mozversion == 1.4 13:22:55 INFO - psutil == 3.1.1 13:22:55 INFO - requests == 1.2.3 13:22:55 INFO - wsgiref == 0.1.2 13:22:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:22: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')]} 13:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22: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')]} 13:22:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:55 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 0x1063f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106488618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5084bd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106357d30>, '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 0x1060bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634fb20>, '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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 13:22:55 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 13:22:55 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 13:22:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0RuhVS8AGl/Render', 13:22:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:55 INFO - 'HOME': '/Users/cltbld', 13:22:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:55 INFO - 'LOGNAME': 'cltbld', 13:22:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:55 INFO - 'MOZ_NO_REMOTE': '1', 13:22:55 INFO - 'NO_EM_RESTART': '1', 13:22:55 INFO - 'PAGER': '/bin/cat', 13:22:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:55 INFO - 'PWD': '/builds/slave/test', 13:22:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:55 INFO - 'SHELL': '/bin/bash', 13:22:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAmmhlLdsT/Listeners', 13:22:55 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 13:22:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:55 INFO - 'USER': 'cltbld', 13:22:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:55 INFO - 'XPC_FLAGS': '0x0', 13:22:55 INFO - 'XPC_SERVICE_NAME': '0', 13:22:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:56 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)) 13:22:56 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)) 13:22:56 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)) 13:22:56 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)) 13:22:56 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)) 13:22:56 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)) 13:22:56 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)) 13:22:56 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)) 13:22:56 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)) 13:22:56 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)) 13:22:56 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)) 13:22:56 INFO - Cleaning up... 13:22:56 INFO - Return code: 0 13:22:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:56 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 0x1063f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106488618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5084bd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106357d30>, '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 0x1060bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634fb20>, '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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 13:22:56 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 13:22:56 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 13:22:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0RuhVS8AGl/Render', 13:22:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:56 INFO - 'HOME': '/Users/cltbld', 13:22:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:56 INFO - 'LOGNAME': 'cltbld', 13:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:56 INFO - 'MOZ_NO_REMOTE': '1', 13:22:56 INFO - 'NO_EM_RESTART': '1', 13:22:56 INFO - 'PAGER': '/bin/cat', 13:22:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:56 INFO - 'PWD': '/builds/slave/test', 13:22:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:56 INFO - 'SHELL': '/bin/bash', 13:22:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAmmhlLdsT/Listeners', 13:22:56 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 13:22:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:56 INFO - 'USER': 'cltbld', 13:22:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:56 INFO - 'XPC_FLAGS': '0x0', 13:22:56 INFO - 'XPC_SERVICE_NAME': '0', 13:22:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:22:56 INFO - Cleaning up... 13:22:56 INFO - Return code: 0 13:22:56 INFO - Running post-action listener: _resource_record_post_action 13:22:56 INFO - Running post-action listener: _start_resource_monitoring 13:22:56 INFO - Starting resource monitoring. 13:22:56 INFO - [mozharness: 2016-08-25 20:22:56.388941Z] Finished create-virtualenv step (success) 13:22:56 INFO - [mozharness: 2016-08-25 20:22:56.389990Z] Running install step. 13:22:56 INFO - Running pre-action listener: _resource_record_pre_action 13:22:56 INFO - Running main action method: install 13:22:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:22:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:22:56 INFO - Reading from file tmpfile_stdout 13:22:56 INFO - Detecting whether we're running mozinstall >=1.0... 13:22:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:22:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:22:56 INFO - Reading from file tmpfile_stdout 13:22:56 INFO - Output received: 13:22:56 INFO - Usage: mozinstall [options] installer 13:22:56 INFO - Options: 13:22:56 INFO - -h, --help show this help message and exit 13:22:56 INFO - -d DEST, --destination=DEST 13:22:56 INFO - Directory to install application into. [default: 13:22:56 INFO - "/builds/slave/test"] 13:22:56 INFO - --app=APP Application being installed. [default: firefox] 13:22:56 INFO - mkdir: /builds/slave/test/build/application 13:22:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:22:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:23:30 INFO - Reading from file tmpfile_stdout 13:23:30 INFO - Output received: 13:23:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:23:30 INFO - Running post-action listener: _resource_record_post_action 13:23:30 INFO - [mozharness: 2016-08-25 20:23:30.866179Z] Finished install step (success) 13:23:30 INFO - [mozharness: 2016-08-25 20:23:30.866304Z] Running run-tests step. 13:23:30 INFO - Running pre-action listener: _resource_record_pre_action 13:23:30 INFO - Running main action method: run_tests 13:23:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:23:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:23:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:23:30 INFO - 2016-08-25 13:23:30.881 screenresolution[2185:18908] starting screenresolution argv=screenresolution get 13:23:30 INFO - 2016-08-25 13:23:30.883 screenresolution[2185:18908] Display 0: 1600x1200x32@60 13:23:30 INFO - 2016-08-25 13:23:30.889 screenresolution[2186:18911] starting screenresolution argv=screenresolution list 13:23:30 INFO - Available Modes on Display 0 13:23:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:23:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:23:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:23:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:23:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:23:31 INFO - Intel Iris: 13:23:31 INFO - Chipset Model: Intel Iris 13:23:31 INFO - Type: GPU 13:23:31 INFO - Bus: Built-In 13:23:31 INFO - VRAM (Dynamic, Max): 1536 MB 13:23:31 INFO - Vendor: Intel (0x8086) 13:23:31 INFO - Device ID: 0x0a2e 13:23:31 INFO - Revision ID: 0x0009 13:23:31 INFO - Displays: 13:23:31 INFO - Display: 13:23:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:23:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:23:31 INFO - Display Serial Number: 1600x1200 60 13:23:31 INFO - Main Display: Yes 13:23:31 INFO - Mirror: Off 13:23:31 INFO - Online: Yes 13:23:31 INFO - Rotation: Supported 13:23:31 INFO - Return code: 0 13:23:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:23:31 INFO - Python 2.7.3 13:23:31 INFO - Return code: 0 13:23:31 INFO - grabbing minidump binary from tooltool 13:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:23:31 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 0x106357d30>, '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 0x1060bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:23:31 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 13:23:31 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 13:23:31 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 13:23:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:23:31 INFO - Return code: 0 13:23:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:23:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:23:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:23:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:23:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:23:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0233 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:23:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0RuhVS8AGl/Render', 13:23:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:23:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:23:31 INFO - 'HOME': '/Users/cltbld', 13:23:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:23:31 INFO - 'LOGNAME': 'cltbld', 13:23:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:23:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:31 INFO - 'MOZ_NO_REMOTE': '1', 13:23:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:23:31 INFO - 'NO_EM_RESTART': '1', 13:23:31 INFO - 'PAGER': '/bin/cat', 13:23:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:31 INFO - 'PWD': '/builds/slave/test', 13:23:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:23:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:23:31 INFO - 'SHELL': '/bin/bash', 13:23:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAmmhlLdsT/Listeners', 13:23:31 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 13:23:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:23:31 INFO - 'USER': 'cltbld', 13:23:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:23:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:31 INFO - 'XPC_FLAGS': '0x0', 13:23:31 INFO - 'XPC_SERVICE_NAME': '0', 13:23:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:23:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:23:31 INFO - mozversion application_buildid: 20160825112817 13:23:31 INFO - mozversion application_changeset: 4dd3a3052ef07b1618eb7653e5be89644b2bc134 13:23:31 INFO - mozversion application_display_name: Nightly 13:23:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:23:31 INFO - mozversion application_name: Firefox 13:23:31 INFO - mozversion application_remotingname: firefox 13:23:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 13:23:31 INFO - mozversion application_vendor: Mozilla 13:23:31 INFO - mozversion application_version: 51.0a1 13:23:31 INFO - mozversion platform_buildid: 20160825112817 13:23:31 INFO - mozversion platform_changeset: 4dd3a3052ef07b1618eb7653e5be89644b2bc134 13:23:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 13:23:31 INFO - mozversion platform_version: 51.0a1 13:23:31 INFO - using testdate: 1472156611 13:23:31 INFO - actual date: 1472156611 13:23:31 INFO - starting webserver on 'localhost:53174' 13:23:31 INFO - SUITE-START | Running 2 tests 13:23:31 INFO - TEST-START | dromaeo_css 13:23:31 INFO - operating with platform_type : mac_ 13:23:31 INFO - Initialising browser for dromaeo_css test... 13:23:31 INFO - TEST-INFO | started process 2196 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmptFa7iV/profile http://localhost:53174/getInfo.html) 13:23:33 INFO - PROCESS | 2196 | __metrics Screen width/height:1600/1200 13:23:33 INFO - PROCESS | 2196 | colorDepth:24 13:23:33 INFO - PROCESS | 2196 | Browser inner width/height: 1024/689 13:23:33 INFO - PROCESS | 2196 | __metrics 13:23:33 INFO - PROCESS | 2196 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:23:33 INFO - PROCESS | 2196 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:23:34 INFO - TEST-INFO | 2196: exit 0 13:23:34 INFO - Browser initialized. 13:23:34 INFO - Running cycle 1/1 for dromaeo_css test... 13:23:34 INFO - TEST-INFO | started process 2198 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmptFa7iV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:23:46 INFO - PROCESS | 2198 | RSS: Main: 232345600 13:23:46 INFO - PROCESS | 2198 | 13:24:38 INFO - PROCESS | 2198 | 1472156678348 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:26:49 INFO - PROCESS | 2198 | Cycle 1(1): loaded http://localhost:53174/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53174/tests/dromaeo/cssquery-ext.html) 13:26:51 INFO - PROCESS | 2198 | RSS: Main: 341647360 13:26:51 INFO - PROCESS | 2198 | 13:29:54 INFO - PROCESS | 2198 | Cycle 1(1): loaded http://localhost:53174/tests/dromaeo/cssquery-ext.html (next: http://localhost:53174/tests/dromaeo/cssquery-jquery.html) 13:29:54 INFO - PROCESS | 2198 | RSS: Main: 367443968 13:29:54 INFO - PROCESS | 2198 | 13:32:57 INFO - PROCESS | 2198 | Cycle 1(1): loaded http://localhost:53174/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53174/tests/dromaeo/cssquery-mootools.html) 13:32:57 INFO - PROCESS | 2198 | RSS: Main: 332447744 13:32:57 INFO - PROCESS | 2198 | 13:36:00 INFO - PROCESS | 2198 | Cycle 1(1): loaded http://localhost:53174/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53174/tests/dromaeo/cssquery-prototype.html) 13:36:00 INFO - PROCESS | 2198 | RSS: Main: 356417536 13:36:00 INFO - PROCESS | 2198 | 13:39:04 INFO - PROCESS | 2198 | Cycle 1(1): loaded http://localhost:53174/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53174/tests/dromaeo/cssquery-yui.html) 13:39:04 INFO - PROCESS | 2198 | RSS: Main: 323829760 13:39:04 INFO - PROCESS | 2198 | 13:42:08 INFO - PROCESS | 2198 | Cycle 1(1): loaded http://localhost:53174/tests/dromaeo/cssquery-yui.html (next: http://localhost:53174/tests/dromaeo/cssquery-dojo.html) 13:42:08 INFO - PROCESS | 2198 | RSS: Main: 440233984 13:42:08 INFO - PROCESS | 2198 | 13:42:08 INFO - PROCESS | 2198 | __start_tp_report 13:42:08 INFO - PROCESS | 2198 | _x_x_mozilla_page_load 13:42:08 INFO - PROCESS | 2198 | _x_x_mozilla_page_load_details 13:42:08 INFO - PROCESS | 2198 | |i|pagename|runs| 13:42:08 INFO - PROCESS | 2198 | |0;dojo.html;3318.269980604266;3328.5839270091237;3337.2824842658824;3361.764676472648;3415.5901291844984;5554.805581804638;5648.915266271004;5691.317041954961;5702.914456283159;5761.020626493496;4798;5668.291463567054;5775.797847075353;5794.043982742853;5833.095870140124;5125.2312153176945;5144.151215049513;5194.065068287707;5217.765200565965;5282.286891269687;5261.947380526203;5262.158054711247;5268.104422248208;5298.576113910878;5324.946750532503;5150.587952963791;5227.32044834171;5234.879873651177;5276.604254680895;5298.655587386807;5229.1633338666015;5239.790408383698;5244.265802787613;5244.659097158673;5253.106971814801;5144.125498665236;5177.741112944338;5177.964407118591;5211.400688920785;5251.658642188246;5661.377248502661;5685.459881311269;5737.254156959611;5749.942500574983;5795.217645617872;6125.723854043132;6143.539234557426;6165.106059621375;6237.688115594247;6258.06129080637;6084.847878803021;6303.5902666327;6315.652639104847;6324.841878953017;6341.936580634181;5635.1828984796975;5695.914561281584;5704.771809127671;5789.9867523183275;5799.2750906136735;6186.81439556814;6272;6278.277998030194;6291.496680265568;6291.937080629226;6162.506999440034;6177.444030037318;6178.351273116348;6184.690765461799;6186.628802271878;6103.450689437972;6286.591371560834;6287.1198032275515;6294.024426213944;6302.842428939313;6065.272167339859;6101.03536932808;6104.694765261719;6107.075063853431;6108.22065524731;6179.3202747697715;6183.505319574423;6249.156363890907;6262.46769024638;6270.529710271748;4842.903141937141;4844.176489996709;4850.563449289511;4857.392825896763;4888.169011268093;4419.712718673276;4435.556444355603;4466.977665111719;4481.126180394792;4531.660125490602;5706.429357064343;5745.88508229833;5748.338941021836;5758.798442054508;5799.710014499258;6280.152179455715;6298.433141017331;6312.495000399957;6320.494360451153;6325.019621958604;5569.944300557024;5722.284714410699;5773.942260577424;5790.855228619318;5809.3609702932645;5336.386315573759;5350.652207606493;5364.6512976656395;5379.193121031876;5444.210589464506;5313.256144139824;6256.718447669775;6272.968635156795;6275.623462592259;6281.842953926097;6112.746658179666;6272.341404152681;6275.560710750204;6278.623282603058;6289.811305660837;6181.81454556364;6206.348333424925;6240.438360547572;6286.968565157145;6293.276273228637;5065.7720402581235;5253.422123566481;6172.907406388818;6179.196704428396;6225.25296964349;6022.066579680157;6063.636181829104;6250;6256.812295631138;6299.433051025429;6065;6109.938900610937;6119.4798442132915;6147.200863887845;6160.137580738789;6119.289302195972;6119.807225076679;6121.378883290332;6132.04958718918;6138.204480310071;5091.974540127276;5750.424957504383;5964.7912323068485;5982.982892908303;5987.191729116514;6049.274087109575;6074.210352654303;6081.756729730761;6139.6454669416935;6141.762184092961;5937.495312898279;5979.342272350125;5983;6003.099535069599;6006.879862402815 13:42:08 INFO - PROCESS | 2198 | |1;ext.html;459.68281885499005;470.966229127066;523.1838332201763;524.9999999999999;527.5357685236993;27034.729652703467;31694.049178524623;32793.67206327936;32925.99999999997;40226.59773402265;40560.79719601398;43536.12927741443;43589.12821743563;43629.7818510907;43792.78103609486;9686.03139686031;9892.010798920122;9895.802083958317;9901.900980990205;9909.671227261588;9685.757856053584;9770.364926279804;9796.265280103986;9796.804063918717;9833.16418104462;9763.609455621767;9778.902210977905;9826.263030272758;9828.164606008499;9828.262880283972;9773;9803.607855685765;9804.950975245149;9810.411375317432;9817.558209880572;9680.806383872354;9781.41311521311;9781.755456113655;9786.119249267529;9808.264380171515;37688.81155594232;40001;41132.971675708046;41496;41586.37620435696;3412.176028721624;3542.982285088584;3974.205158968218;4150.626443620058;4311.655067594585;25797;32251;32321;32355.337815882125;32560.348793024008;3182.9363412731614;3354.429746943001;3390.643982381839;3463.7748546344405;3468.271662950783;379640;380305.9999999972;380815;381318;387612;59414.70292648509;59691.70154149202;59767.701161494784;59826.700866496256;59946.70026649926;63443.6827815858;63613.68193159004;63641;63791.36208637947;63797;16365.34538618442;16584;16609.169541522875;16617;16632.417865374653;12827.422765975554;12892.419841107172;12908.031897607718;12912.935435322763;12925.418356173996;13088.672783180496;16526.338946442007;16542.66914661724;16546.42087526955;16563.585910352096;6102.481289090389;6106.603070800473;6185.474234690012;6187.752489900354;6196.597221180609;891.6166048599035;891.9910800891907;1004.6333088422673;1009.7677534167183;1015.857160694219;29831.552526712116;29841.403171936872;30156.246093847392;30674.53988190179;30683.539746903814;1058.666520046183;1080.36258607422;1128.3230061962756;1148.328227986637;1170.3913964738458;15363.607367833523;15556.988795728468;15701.83737489428;15873.12697801632;15935.601609959844;595828;653445;657383;659858;660984;58740.412595873495;59128;59129.704351477965;59246;59322;1225.6384366611871;1352.4590163934504;1372.066994443768;1382.0947279531922;1394.5955672854961;30692.232694182825;31847.68152318447;32429.837850810596;32659.673403265664;32952.505712413855;49882;56360;57564.42435575591;57787.13319300298;61387.07919381124;608681;612019;612785;612855;613394;23900.28299151028;24367.878160609085;26250.606240906785;26284.21147365582;26835.463290734468;40742.79628601838;45515;47476;48138.79653008703;49243.50756492533;39648.80175599104;39652.2069558613;39966.80016599898;41177.588224118575;41363.379549306184;38710.8386748398;39637;39675;41047.56333528312;41174.38238426366;33047;37889.62110378861;38443;38850;39384.40923386094;34041.82979085089;34689.30621387609;35878;35898.8205058973;36046;21505.44057183267;25795.742042580085;25896.741032589434;26527.204183873742;26794.196174114808 13:42:08 INFO - PROCESS | 2198 | |2;jquery.html;353.1348196917552;354.5674277381594;375.7970695824253;384.722999440403;406.3295562322168;13195.538156164535;14859.257037148154;20196.495087622818;21023.84368859712;21073.47316317093;17598.120093995312;20523.384298471068;20768.48078798031;20824.68762968553;20834.854083025428;3529.3470707918987;5658.773649054043;5907.527397808165;5911.763529458816;5913.438223368773;4150.916981660365;6449.903251451232;6495.12315837363;6589.242237142718;6613.272540020618;6216.8445788855415;6428.389303016229;6499.44754695849;6521.184851893513;6531.575447595915;6499.967500162493;6502.463054187173;6555.180602424697;6559.9672001640165;6569.67151642416;5517.862053448655;5607.859803504904;5678;5683.9715801420725;5687.317521897398;24580.385490362703;26164.869175654192;26223.73776262251;26369;26558.73441265582;2073.2743539761113;2258.6386178211405;2592.9481410371686;2895.570720465678;3004.5192769157047;10399.168066554657;11325;12026.917577417988;15724.528264152093;15895.761563576556;1637.9262933168036;1784.357631252748;1833.6791061564172;1859.014722197224;1914.9425517234506;122726.38636806759;130586;136419.31790341085;137256;139322;11336.319820810777;11367.886321136848;11526.827097593543;11567.074634029257;11682.70793230176;14504.709905802036;14551.27243637835;14553.92723036378;14573.489927852474;14578.198199098939;8188.303994160444;8508.872366914502;8521.701740439055;8590.613422396007;8604.8709269361;8471.915280847235;8478.915210847812;8490.490570565786;8667.826643467222;8679.305655547541;4451.933221001687;4506.414166158444;4511.436070491189;4525.818967241339;4608.677392582487;1869.7849747279238;1925.643755905162;1925.8748181368167;1926.9325573604856;1928.797476264972;375.35439044842826;383.2201470008519;387.96508314251855;815.404754529197;903.9050899655441;11433.428328583537;11538.826917596243;11655.30068195911;11751.88248117525;11961.760764784656;415.2711990456729;427.5083653798157;429.6551792888302;443.07704562200206;456.0673422850279;5982.910256346158;5998.49012833914;6024.096385542204;6167.136600875881;6196.8760624787255;620749;622739;622879;627603.5405966557;638789.8060509667;122224;123912.76087239193;127818;137593.3120334392;141587.5841241595;964.4261664309724;1000.2248257600418;1032.055669062799;1032.210359075308;1032.4063663393429;16640.25118869678;19208.327708530695;19303.613927721646;19520.414387568395;19609.82341059541;52684.73657631687;56612.43387566072;56636.43363566312;57046;58492.415075848694;85080.57459712662;86411;86765;87042;89303.55348223218;6894.965525172342;7960.164182760958;8099.8380032397845;8210.63052162642;8261.38039647017;8885.600147993226;12447;12595.496180152692;12862.678433038875;13024;10960.945195273973;11009.174311926798;11097.278676885977;11259.493322800658;11366;10926.180536459957;10965.396903170084;11059.944700276446;11202.103831693608;11326.32042077478;8244.752657420288;11311.660650180507;11320.26418282809;11577.131715121232;11593.304401735923;6953.721851126101;8067.959660201662;8131.715389961323;8157.75526734199;8191.139930307232;9466.432014079177;9514.383544525312;11160.497777599865;11228.438578071227;11229 13:42:08 INFO - PROCESS | 2198 | |3;mootools.html;1348.7841945288756;1498.940042398304;1539.9528320741895;1704.8209937956515;1877.5963167918903;7772.572508512029;8580.613872375743;8786.6924657637;8820.955895220515;8866.778330541723;8648.091950345217;8718.258947989412;8724.869126963085;8733;8750.168733970262;2393.9760602394012;2417.4198192433864;2421.757824217582;2423.006567307404;2423.8060955123547;2375.5486457573093;2400.9639855402183;2419.4919066995953;2420.6127019676856;2430.2587710748217;2232.2633530934754;2324.883755812211;2408.217329367954;2425.4785221177444;2428.2229686500327;2170.121100954116;2366.2309749331534;2367.6922000140044;2396.209250947183;2409.132712223598;2341.800946919514;2356.422676444265;2388.235764555343;2393.521295740859;2394.8084153267696;8598.87101693475;8653.091425400306;8765.167309105625;8769.517676527788;8773.210411062972;5244.659097158673;5421.376541697716;5421.484958928895;5422.810201642963;5431.646942948696;3263.233140212048;3680.9815950920074;3685.7051435885064;3713.9071523211865;3759.8417329443128;1086.5327908999127;1093.436880006797;1115.81031224692;1124.1849658997244;1138.8633363996373;11366.545338186463;11425.657740163246;11498.367589782558;11512.481938312712;11512.942435287938;4109.3425051991535;4391.736495810262;4473.619742321939;4666.300054991779;4677.18149323867;9096.135867092615;9098.226650734628;9099.863502047474;9101.135392137736;9115.635374585076;5265.289185959847;5283.550898173621;5286.392064912584;5293.708846013506;5299.6025298102795;4428.13651337986;4430.534793846664;4596.4714057883775;4947.356843610308;4968.527989841032;3790.6209379061875;3795.221979494252;3892.4745159403683;3892.9416058759143;3893.377059670439;1171.0572988744143;1189.5539172810197;1192.689900625826;1198.7842188405996;1206.6319772469305;922.3128769067088;927.332320729074;929.7494869400646;936.4381371177375;973.6952483671772;4879.829205977774;5198.584113270929;5828.388019257917;5967.76128954837;5993.160957465958;921.7373048681228;951.7246889168547;966.6809952715587;972.2756546372998;977.3158788847693;3276.9344613107605;3984.561698213194;3992.740471869319;4103.9179216415505;4127.814248358831;10387.065164135263;10653.147748180127;10758.56965721378;10770.461476926122;10791.78416431667;10265.74335641615;10722.624708135178;10731.785364292826;10733.121703701821;10749.78500429987;8010.639521221559;8340.958295208486;8352.373571982003;8352.373571982125;8354.039285482126;5875.941240587539;5907.084401917709;6000.189974353407;6027.21646185993;6089.117078023751;8587.699430520152;10044.49777511107;10072.899271007196;10076.294659373774;10089.293749437762;9271.397359171633;9634.795650543681;9645.180159686504;10118;10127.544260508443;7155.821104472429;7329.340359367469;7395.7041718330665;7567.697292108254;7776.339011184113;8166.224208700164;8423.620937057725;8473.83052338962;8579.313654907468;8598;6615.933840661724;7563.886541701769;7599.924000760143;7624.085109786861;7655.885161722467;7452.888206677013;7459.552426854406;7489.400847932044;7499.550026998398;7531.849363012819;6764.661766911733;6826.829329266808;6884.277150899284;6907.171139463297;6922.307769223037;4873.317735516959;5109.233614957786;5114.360704911886;5142.8971420572125;5229.555487783581;4999.725015124058;5056.74716264178;5074.416442109205;5296.470352964673;5309.787608495772 13:42:08 INFO - PROCESS | 2198 | |4;prototype.html;299.8695567428169;300.2119436479242;309.5696981196138;315.22297536572376;316.8669158953239;4867.174809446457;4883.902321953558;5045.318881950936;5057.822976195829;5094.414142373628;5011.799528018875;5013.849584512462;5015.598752099832;5017.347744793181;5031.899362012758;3232.3696879108643;3252.9186770330693;3571.285742851427;3607.278544291139;3748.7188460865545;3184.856681449329;3863.0298547740713;3909.2376986487648;3917.5103112110987;3929.469521614574;3646.9635303647024;3716.8477771890803;3857.8263978121054;3917.6082391760883;3930.9017274568223;3652.7625704329266;3726.459663348799;3853.6724378427866;3877.495925529663;3928.607139286077;3411.8911353809863;3416.8633254669785;3426.794392336443;3428.297199074209;3448.758586898918;3789.981050094732;4545.340925565807;4693.037927224911;4991.425986011583;4992;3773.962260377416;4262.190183865056;4440.844570440019;4446.221911165533;4484.260097083978;5184.844454666366;5194.948050519484;5231.555317797992;5237.16205407133;5238.5023422774775;3288.095773662238;3404.961486746541;3740.17716102457;3753.9113657039425;3758.624137586247;5405.945940540623;5420.629698311079;5548.588871846891;5560.4161563036105;5561.221428999943;3184.092533627905;3533.2756784859557;3589.6230895756094;3804.885853424402;3813.618638136164;3535.222251104753;3639.526861507987;3837.654611085016;3845.423186522044;3866.478025466582;3884.9223015540097;4327.112941846914;4340.327249276367;4409.382686423904;4424.6902716810155;4578.496365399803;5047.116754568009;5124.285942314288;5129.153689641167;5360.303160589098;1196.8264601632713;1199.5261871560685;1294.6828027133413;1294.9417276222405;1306.1314226039767;731.6963460164079;864.9394542382099;904.8009437923646;911.7036962987056;920.2331523474944;4230.069384735353;4407.493138289138;4448.065906159743;4987.326710894055;4988.650794444354;5181.4559471255725;5191.454897235812;5324.281222035053;5361.758720857571;5363.302770639792;4236.639885609696;4270.466191726035;4892.143874821892;4985.426675932242;5052.671576347526;2514.472954680571;2516.8925672704318;2529.4435224250637;2777.8333300002064;2823.2377258140186;4770.403699537558;5307.4692530746615;5558.555315574744;5561.247182644617;5565.053940830068;4929.679570827885;5549.001179787677;5574.554035677136;5580.302462192226;5605.5515558755205;5645.786155976402;5674.517665998436;5692.800751973661;5696.287964004499;5697.6296540724725;5175.4565770593545;5485.835424937259;5489.557147069516;5504.862378440571;5547.528460080938;4759.3336932830125;5341.172118321666;5382.757775900016;5409.350877894678;5416.21464887597;5356.517913387814;5527;5666.263385759825;5717.42825717425;5789.247397838422;3663.87176448823;3721.5348081489815;3724.925501490009;3728.8694895678523;3751.1372384351207;5414.3232095988005;5418.403975562764;5437.374701909331;5499.4225606310765;5516.9448305518035;4354.586314300136;5361.839144825662;5371.919421208607;5387.865303367447;5405.432429594836;4668.78990445424;5199.584033277253;5261.527082271326;5276.419593844751;5331.200319952039;4129.504459464883;5077.34986129502;5102.336696229467;5188.273641690242;5208.479152084761;5298.920516192183;5328.841819063286;5337.226102215234;5397.649152805055;5440.089552089613;5061.31672224245;5239.318888544465;5302.992431438014;5304.363476382704;5318.282031925641 13:42:08 INFO - PROCESS | 2198 | |5;yui.html;537.5860587347743;661.8577005943721;740.1155619035255;799.4523751230406;799.8402316640872;13581.932090339547;16356;16665.41671041514;16666.500004999838;16694.749578756328;14417.279136043207;14944.10335379878;15083.547493575183;15262.08427494351;15326;847.2417186617973;873.7291439653696;874.5539774714878;876.0538618292261;877.0965905117707;684.5858255755271;711.1821405383798;713.0801266366382;716.1585137463486;716.6774951271923;895.7536677413698;935.1023017902787;935.7426707655416;936.6815282803846;938.3712912348743;921.3136213520903;921.4885738415177;922.8108237811266;922.9353945223837;923.2383283790899;540.8215288954635;681.8534015186709;682.0996284903929;683.1631251716657;686.457698418249;12648.68378290541;12780.936095319465;12782;12812.359382030954;14735.778963315559;14206.218657973808;15039.774403383957;15238.466653667068;15286.388544458208;15432.3827046918;12235.571754988621;12238.81641775374;12250.387480626023;12308;12646.430910609044;3923.234969181011;3957.56466788653;3987;4053.7770422626745;4139.089400331922;115778;118975.4051229747;119276;120249;122072;11501.884981150082;11528.76942461146;11596.768064638829;11600.825987610193;11976.46105925218;23918.64122038171;23986.04055837747;23991;24040.15859444911;24050.27849164528;7984.281414672707;8173.223543763333;8371.079181290053;8374.120717324715;8454.915450845412;4542.545745425497;4587.403637527167;4680.227762419231;4721.268203428474;4818.084563932842;3665.486831843544;3753.7560058596105;3793.4257283227703;3793.975626580808;3799.6200379962333;575.2234483447396;638.2022471910112;645.3030726815028;651.8761435327367;680.0615151091462;543.8721900353414;551.401729123905;553.4437889920604;563.4309347558913;667.4593579200863;7833.451658383972;8128.75613731589;9648;9670.468124253233;9710.368826026286;533.3706226652587;567.5601408908129;576.4379729763447;586.7150939442607;694.3611877073004;4456.876918506744;4686.250199967989;4725.385699859065;4754.524547545287;4796.208625576741;129259;149050.25474872772;152143;153487;156569;142303.2884835569;142422;144768;145886;149758.25120874326;94473.52763236414;95293.99999999862;97651.55871103136;117040.29597040398;126121;7817.648205830637;9427.387219830689;9530.95234523823;9630.614775409185;9894;50241;57981.13028304494;61457;61999;62870.68564657147;90419;90757;91532.54233728789;92600;94510;13049.804252936023;13079.738405232032;13257.469701211843;13431.395587198404;13684.931575342458;14646.853531464549;14798.77801832995;14822.62943426423;14957.252137392868;15013.099214047192;13778.586642400744;13789.793153102912;13838.792418113937;14036;14098.859011409755;13717.519886803913;13949.58151255464;13967.860321397062;14029.877609791396;14459.349329280418;13457.259850707913;13493.932530337286;13906.956978226472;14153;14278.286085695467;9393.859092113487;10909.942172923267;11127.554897803997;11425.942870285595;11530.423478825858;11354.432278386214;11383.829242561535;11615.709607259887;11643.068554515788;11655.18413711032 13:42:08 INFO - PROCESS | 2198 | __end_tp_report 13:42:08 INFO - PROCESS | 2198 | __start_cc_report 13:42:08 INFO - PROCESS | 2198 | _x_x_mozilla_cycle_collect,1317 13:42:08 INFO - PROCESS | 2198 | __end_cc_report 13:42:08 INFO - PROCESS | 2198 | __startTimestamp1472157728446__endTimestamp 13:42:08 INFO - PROCESS | 2198 | 13:42:08 INFO - PROCESS | 2198 | ------- Summary: start ------- 13:42:08 INFO - PROCESS | 2198 | Number of tests: 6 13:42:08 INFO - PROCESS | 2198 | 13:42:08 INFO - PROCESS | 2198 | [#0] dojo.html Cycles:165 Average:5720.55 Median:5979.34 stddev:641.99 (10.7%) stddev-sans-first:615.68 13:42:08 INFO - PROCESS | 2198 | Values: 3318.3 3328.6 3337.3 3361.8 3415.6 5554.8 5648.9 5691.3 5702.9 5761.0 4798.0 5668.3 5775.8 5794.0 5833.1 5125.2 5144.2 5194.1 5217.8 5282.3 5261.9 5262.2 5268.1 5298.6 5324.9 5150.6 5227.3 5234.9 5276.6 5298.7 5229.2 5239.8 5244.3 5244.7 5253.1 5144.1 5177.7 5178.0 5211.4 5251.7 5661.4 5685.5 5737.3 5749.9 5795.2 6125.7 6143.5 6165.1 6237.7 6258.1 6084.8 6303.6 6315.7 6324.8 6341.9 5635.2 5695.9 5704.8 5790.0 5799.3 6186.8 6272.0 6278.3 6291.5 6291.9 6162.5 6177.4 6178.4 6184.7 6186.6 6103.5 6286.6 6287.1 6294.0 6302.8 6065.3 6101.0 6104.7 6107.1 6108.2 6179.3 6183.5 6249.2 6262.5 6270.5 4842.9 4844.2 4850.6 4857.4 4888.2 4419.7 4435.6 4467.0 4481.1 4531.7 5706.4 5745.9 5748.3 5758.8 5799.7 6280.2 6298.4 6312.5 6320.5 6325.0 5569.9 5722.3 5773.9 5790.9 5809.4 5336.4 5350.7 5364.7 5379.2 5444.2 5313.3 6256.7 6273.0 6275.6 6281.8 6112.7 6272.3 6275.6 6278.6 6289.8 6181.8 6206.3 6240.4 6287.0 6293.3 5065.8 5253.4 6172.9 6179.2 6225.3 6022.1 6063.6 6250.0 6256.8 6299.4 6065.0 6109.9 6119.5 6147.2 6160.1 6119.3 6119.8 6121.4 6132.0 6138.2 5092.0 5750.4 5964.8 5983.0 5987.2 6049.3 6074.2 6081.8 6139.6 6141.8 5937.5 5979.3 5983.0 6003.1 6006.9 13:42:08 INFO - PROCESS | 2198 | 13:42:08 INFO - PROCESS | 2198 | [#1] ext.html Cycles:180 Average:68496.22 Median:21970.50 stddev:149619.80 (681.0%) stddev-sans-first:149952.32 13:42:08 INFO - PROCESS | 2198 | Values: 459.7 471.0 523.2 525.0 527.5 27034.7 31694.0 32793.7 32926.0 40226.6 40560.8 43536.1 43589.1 43629.8 43792.8 9686.0 9892.0 9895.8 9901.9 9909.7 9685.8 9770.4 9796.3 9796.8 9833.2 9763.6 9778.9 9826.3 9828.2 9828.3 9773.0 9803.6 9805.0 9810.4 9817.6 9680.8 9781.4 9781.8 9786.1 9808.3 37688.8 40001.0 41133.0 41496.0 41586.4 3412.2 3543.0 3974.2 4150.6 4311.7 25797.0 32251.0 32321.0 32355.3 32560.3 3182.9 3354.4 3390.6 3463.8 3468.3 379640.0 380306.0 380815.0 381318.0 387612.0 59414.7 59691.7 59767.7 59826.7 59946.7 63443.7 63613.7 63641.0 63791.4 63797.0 16365.3 16584.0 16609.2 16617.0 16632.4 12827.4 12892.4 12908.0 12912.9 12925.4 13088.7 16526.3 16542.7 16546.4 16563.6 6102.5 6106.6 6185.5 6187.8 6196.6 891.6 892.0 1004.6 1009.8 1015.9 29831.6 29841.4 30156.2 30674.5 30683.5 1058.7 1080.4 1128.3 1148.3 1170.4 15363.6 15557.0 15701.8 15873.1 15935.6 595828.0 653445.0 657383.0 659858.0 660984.0 58740.4 59128.0 59129.7 59246.0 59322.0 1225.6 1352.5 1372.1 1382.1 1394.6 30692.2 31847.7 32429.8 32659.7 32952.5 49882.0 56360.0 57564.4 57787.1 61387.1 608681.0 612019.0 612785.0 612855.0 613394.0 23900.3 24367.9 26250.6 26284.2 26835.5 40742.8 45515.0 47476.0 48138.8 49243.5 39648.8 39652.2 39966.8 41177.6 41363.4 38710.8 39637.0 39675.0 41047.6 41174.4 33047.0 37889.6 38443.0 38850.0 39384.4 34041.8 34689.3 35878.0 35898.8 36046.0 21505.4 25795.7 25896.7 26527.2 26794.2 13:42:08 INFO - PROCESS | 2198 | 13:42:08 INFO - PROCESS | 2198 | [#2] jquery.html Cycles:180 Average:36254.09 Median:1941.24 stddev:104976.88 (5407.7%) stddev-sans-first:105236.76 13:42:08 INFO - PROCESS | 2198 | Values: 353.1 354.6 375.8 384.7 406.3 13195.5 14859.3 20196.5 21023.8 21073.5 17598.1 20523.4 20768.5 20824.7 20834.9 3529.3 5658.8 5907.5 5911.8 5913.4 4150.9 6449.9 6495.1 6589.2 6613.3 6216.8 6428.4 6499.4 6521.2 6531.6 6500.0 6502.5 6555.2 6560.0 6569.7 5517.9 5607.9 5678.0 5684.0 5687.3 24580.4 26164.9 26223.7 26369.0 26558.7 2073.3 2258.6 2592.9 2895.6 3004.5 10399.2 11325.0 12026.9 15724.5 15895.8 1637.9 1784.4 1833.7 1859.0 1914.9 122726.4 130586.0 136419.3 137256.0 139322.0 11336.3 11367.9 11526.8 11567.1 11682.7 14504.7 14551.3 14553.9 14573.5 14578.2 8188.3 8508.9 8521.7 8590.6 8604.9 8471.9 8478.9 8490.5 8667.8 8679.3 4451.9 4506.4 4511.4 4525.8 4608.7 1869.8 1925.6 1925.9 1926.9 1928.8 375.4 383.2 388.0 815.4 903.9 11433.4 11538.8 11655.3 11751.9 11961.8 415.3 427.5 429.7 443.1 456.1 5982.9 5998.5 6024.1 6167.1 6196.9 620749.0 622739.0 622879.0 627603.5 638789.8 122224.0 123912.8 127818.0 137593.3 141587.6 964.4 1000.2 1032.1 1032.2 1032.4 16640.3 19208.3 19303.6 19520.4 19609.8 52684.7 56612.4 56636.4 57046.0 58492.4 85080.6 86411.0 86765.0 87042.0 89303.6 6895.0 7960.2 8099.8 8210.6 8261.4 8885.6 12447.0 12595.5 12862.7 13024.0 10960.9 11009.2 11097.3 11259.5 11366.0 10926.2 10965.4 11059.9 11202.1 11326.3 8244.8 11311.7 11320.3 11577.1 11593.3 6953.7 8068.0 8131.7 8157.8 8191.1 9466.4 9514.4 11160.5 11228.4 11229.0 13:42:08 INFO - PROCESS | 2198 | 13:42:08 INFO - PROCESS | 2198 | [#3] mootools.html Cycles:180 Average:5667.11 Median:5091.83 stddev:3138.43 (61.6%) stddev-sans-first:3130.45 13:42:08 INFO - PROCESS | 2198 | Values: 1348.8 1498.9 1540.0 1704.8 1877.6 7772.6 8580.6 8786.7 8821.0 8866.8 8648.1 8718.3 8724.9 8733.0 8750.2 2394.0 2417.4 2421.8 2423.0 2423.8 2375.5 2401.0 2419.5 2420.6 2430.3 2232.3 2324.9 2408.2 2425.5 2428.2 2170.1 2366.2 2367.7 2396.2 2409.1 2341.8 2356.4 2388.2 2393.5 2394.8 8598.9 8653.1 8765.2 8769.5 8773.2 5244.7 5421.4 5421.5 5422.8 5431.6 3263.2 3681.0 3685.7 3713.9 3759.8 1086.5 1093.4 1115.8 1124.2 1138.9 11366.5 11425.7 11498.4 11512.5 11512.9 4109.3 4391.7 4473.6 4666.3 4677.2 9096.1 9098.2 9099.9 9101.1 9115.6 5265.3 5283.6 5286.4 5293.7 5299.6 4428.1 4430.5 4596.5 4947.4 4968.5 3790.6 3795.2 3892.5 3892.9 3893.4 1171.1 1189.6 1192.7 1198.8 1206.6 922.3 927.3 929.7 936.4 973.7 4879.8 5198.6 5828.4 5967.8 5993.2 921.7 951.7 966.7 972.3 977.3 3276.9 3984.6 3992.7 4103.9 4127.8 10387.1 10653.1 10758.6 10770.5 10791.8 10265.7 10722.6 10731.8 10733.1 10749.8 8010.6 8341.0 8352.4 8352.4 8354.0 5875.9 5907.1 6000.2 6027.2 6089.1 8587.7 10044.5 10072.9 10076.3 10089.3 9271.4 9634.8 9645.2 10118.0 10127.5 7155.8 7329.3 7395.7 7567.7 7776.3 8166.2 8423.6 8473.8 8579.3 8598.0 6615.9 7563.9 7599.9 7624.1 7655.9 7452.9 7459.6 7489.4 7499.6 7531.8 6764.7 6826.8 6884.3 6907.2 6922.3 4873.3 5109.2 5114.4 5142.9 5229.6 4999.7 5056.7 5074.4 5296.5 5309.8 13:42:08 INFO - PROCESS | 2198 | 13:42:08 INFO - PROCESS | 2198 | [#4] prototype.html Cycles:180 Average:4323.23 Median:4990.04 stddev:1335.54 (26.8%) stddev-sans-first:1304.70 13:42:08 INFO - PROCESS | 2198 | Values: 299.9 300.2 309.6 315.2 316.9 4867.2 4883.9 5045.3 5057.8 5094.4 5011.8 5013.8 5015.6 5017.3 5031.9 3232.4 3252.9 3571.3 3607.3 3748.7 3184.9 3863.0 3909.2 3917.5 3929.5 3647.0 3716.8 3857.8 3917.6 3930.9 3652.8 3726.5 3853.7 3877.5 3928.6 3411.9 3416.9 3426.8 3428.3 3448.8 3790.0 4545.3 4693.0 4991.4 4992.0 3774.0 4262.2 4440.8 4446.2 4484.3 5184.8 5194.9 5231.6 5237.2 5238.5 3288.1 3405.0 3740.2 3753.9 3758.6 5405.9 5420.6 5548.6 5560.4 5561.2 3184.1 3533.3 3589.6 3804.9 3813.6 3535.2 3639.5 3837.7 3845.4 3866.5 3884.9 4327.1 4340.3 4409.4 4424.7 4578.5 5047.1 5124.3 5129.2 5360.3 1196.8 1199.5 1294.7 1294.9 1306.1 731.7 864.9 904.8 911.7 920.2 4230.1 4407.5 4448.1 4987.3 4988.7 5181.5 5191.5 5324.3 5361.8 5363.3 4236.6 4270.5 4892.1 4985.4 5052.7 2514.5 2516.9 2529.4 2777.8 2823.2 4770.4 5307.5 5558.6 5561.2 5565.1 4929.7 5549.0 5574.6 5580.3 5605.6 5645.8 5674.5 5692.8 5696.3 5697.6 5175.5 5485.8 5489.6 5504.9 5547.5 4759.3 5341.2 5382.8 5409.4 5416.2 5356.5 5527.0 5666.3 5717.4 5789.2 3663.9 3721.5 3724.9 3728.9 3751.1 5414.3 5418.4 5437.4 5499.4 5516.9 4354.6 5361.8 5371.9 5387.9 5405.4 4668.8 5199.6 5261.5 5276.4 5331.2 4129.5 5077.3 5102.3 5188.3 5208.5 5298.9 5328.8 5337.2 5397.6 5440.1 5061.3 5239.3 5303.0 5304.4 5318.3 13:42:08 INFO - PROCESS | 2198 | 13:42:08 INFO - PROCESS | 2198 | [#5] yui.html Cycles:180 Average:25532.13 Median:3861.43 stddev:41110.56 (1064.6%) stddev-sans-first:41183.05 13:42:08 INFO - PROCESS | 2198 | Values: 537.6 661.9 740.1 799.5 799.8 13581.9 16356.0 16665.4 16666.5 16694.7 14417.3 14944.1 15083.5 15262.1 15326.0 847.2 873.7 874.6 876.1 877.1 684.6 711.2 713.1 716.2 716.7 895.8 935.1 935.7 936.7 938.4 921.3 921.5 922.8 922.9 923.2 540.8 681.9 682.1 683.2 686.5 12648.7 12780.9 12782.0 12812.4 14735.8 14206.2 15039.8 15238.5 15286.4 15432.4 12235.6 12238.8 12250.4 12308.0 12646.4 3923.2 3957.6 3987.0 4053.8 4139.1 115778.0 118975.4 119276.0 120249.0 122072.0 11501.9 11528.8 11596.8 11600.8 11976.5 23918.6 23986.0 23991.0 24040.2 24050.3 7984.3 8173.2 8371.1 8374.1 8454.9 4542.5 4587.4 4680.2 4721.3 4818.1 3665.5 3753.8 3793.4 3794.0 3799.6 575.2 638.2 645.3 651.9 680.1 543.9 551.4 553.4 563.4 667.5 7833.5 8128.8 9648.0 9670.5 9710.4 533.4 567.6 576.4 586.7 694.4 4456.9 4686.3 4725.4 4754.5 4796.2 129259.0 149050.3 152143.0 153487.0 156569.0 142303.3 142422.0 144768.0 145886.0 149758.3 94473.5 95294.0 97651.6 117040.3 126121.0 7817.6 9427.4 9531.0 9630.6 9894.0 50241.0 57981.1 61457.0 61999.0 62870.7 90419.0 90757.0 91532.5 92600.0 94510.0 13049.8 13079.7 13257.5 13431.4 13684.9 14646.9 14798.8 14822.6 14957.3 15013.1 13778.6 13789.8 13838.8 14036.0 14098.9 13717.5 13949.6 13967.9 14029.9 14459.3 13457.3 13493.9 13907.0 14153.0 14278.3 9393.9 10909.9 11127.6 11425.9 11530.4 11354.4 11383.8 11615.7 11643.1 11655.2 13:42:08 INFO - PROCESS | 2198 | -------- Summary: end -------- 13:42:08 INFO - PROCESS | 2198 | 13:42:09 INFO - TEST-INFO | 2198: exit 0 13:42:09 INFO - TEST-OK | dromaeo_css | took 1117636ms 13:42:09 INFO - TEST-START | kraken 13:42:09 INFO - operating with platform_type : mac_ 13:42:09 INFO - Initialising browser for kraken test... 13:42:09 INFO - TEST-INFO | started process 2202 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpksSNDr/profile http://localhost:53174/getInfo.html) 13:42:11 INFO - PROCESS | 2202 | __metrics Screen width/height:1600/1200 13:42:11 INFO - PROCESS | 2202 | colorDepth:24 13:42:11 INFO - PROCESS | 2202 | Browser inner width/height: 1024/689 13:42:11 INFO - PROCESS | 2202 | __metrics 13:42:11 INFO - PROCESS | 2202 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:42:11 INFO - PROCESS | 2202 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:42:12 INFO - TEST-INFO | 2202: exit 0 13:42:12 INFO - Browser initialized. 13:42:12 INFO - Running cycle 1/1 for kraken test... 13:42:12 INFO - TEST-INFO | started process 2203 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpksSNDr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:42:24 INFO - PROCESS | 2203 | RSS: Main: 232542208 13:42:24 INFO - PROCESS | 2203 | 13:42:26 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53174/tests/kraken/driver.html?testName=audio-beat-detection) 13:42:27 INFO - PROCESS | 2203 | RSS: Main: 291102720 13:42:27 INFO - PROCESS | 2203 | 13:42:30 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53174/tests/kraken/driver.html?testName=audio-dft) 13:42:30 INFO - PROCESS | 2203 | RSS: Main: 285863936 13:42:30 INFO - PROCESS | 2203 | 13:42:34 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53174/tests/kraken/driver.html?testName=audio-fft) 13:42:35 INFO - PROCESS | 2203 | RSS: Main: 374394880 13:42:35 INFO - PROCESS | 2203 | 13:42:37 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53174/tests/kraken/driver.html?testName=audio-oscillator) 13:42:37 INFO - PROCESS | 2203 | RSS: Main: 276574208 13:42:37 INFO - PROCESS | 2203 | 13:42:39 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53174/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:42:40 INFO - PROCESS | 2203 | RSS: Main: 280100864 13:42:40 INFO - PROCESS | 2203 | 13:42:43 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53174/tests/kraken/driver.html?testName=imaging-darkroom) 13:42:43 INFO - PROCESS | 2203 | RSS: Main: 319037440 13:42:43 INFO - PROCESS | 2203 | 13:42:46 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53174/tests/kraken/driver.html?testName=imaging-desaturate) 13:42:47 INFO - PROCESS | 2203 | RSS: Main: 321298432 13:42:47 INFO - PROCESS | 2203 | 13:42:50 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53174/tests/kraken/driver.html?testName=json-parse-financial) 13:42:50 INFO - PROCESS | 2203 | RSS: Main: 317931520 13:42:50 INFO - PROCESS | 2203 | 13:42:52 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53174/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:42:52 INFO - PROCESS | 2203 | RSS: Main: 296587264 13:42:52 INFO - PROCESS | 2203 | 13:42:54 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53174/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:42:55 INFO - PROCESS | 2203 | RSS: Main: 300888064 13:42:55 INFO - PROCESS | 2203 | 13:42:57 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53174/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:42:58 INFO - PROCESS | 2203 | RSS: Main: 296656896 13:42:58 INFO - PROCESS | 2203 | 13:43:01 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53174/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:43:02 INFO - PROCESS | 2203 | RSS: Main: 317382656 13:43:02 INFO - PROCESS | 2203 | 13:43:05 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53174/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:43:05 INFO - PROCESS | 2203 | RSS: Main: 313569280 13:43:05 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53174/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53174/tests/kraken/driver.html?testName=ai-astar) 13:43:07 INFO - PROCESS | 2203 | RSS: Main: 357105664 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | __start_tp_report 13:43:07 INFO - PROCESS | 2203 | _x_x_mozilla_page_load 13:43:07 INFO - PROCESS | 2203 | _x_x_mozilla_page_load_details 13:43:07 INFO - PROCESS | 2203 | |i|pagename|runs| 13:43:07 INFO - PROCESS | 2203 | |0;ai-astar;111;111;125;112;111;110;110;110;111;112 13:43:07 INFO - PROCESS | 2203 | |1;audio-beat-detection;149;108;130;159;108;129;130;105;161;107 13:43:07 INFO - PROCESS | 2203 | |2;audio-dft;183;185;184;184;184;183;178;182;184;183 13:43:07 INFO - PROCESS | 2203 | |3;audio-fft;79;75;78;154;57;56;57;70;140;58 13:43:07 INFO - PROCESS | 2203 | |4;audio-oscillator;84;85;133;70;75;92;119;70;70;121 13:43:07 INFO - PROCESS | 2203 | |5;imaging-gaussian-blur;86;84;86;84;87;83;86;84;86;84 13:43:07 INFO - PROCESS | 2203 | |6;imaging-darkroom;130;122;94;94;93;93;93;93;94;97 13:43:07 INFO - PROCESS | 2203 | |7;imaging-desaturate;86;86;84;85;85;86;85;87;86;84 13:43:07 INFO - PROCESS | 2203 | |8;json-parse-financial;56;54;55;55;55;55;55;55;54;54 13:43:07 INFO - PROCESS | 2203 | |9;json-stringify-tinderbox;67;65;63;72;71;74;71;69;70;70 13:43:07 INFO - PROCESS | 2203 | |10;stanford-crypto-aes;84;85;84;85;83;101;97;94;97;74 13:43:07 INFO - PROCESS | 2203 | |11;stanford-crypto-ccm;153;152;136;145;138;139;141;144;183;150 13:43:07 INFO - PROCESS | 2203 | |12;stanford-crypto-pbkdf2;171;221;205;172;175;174;171;171;173;175 13:43:07 INFO - PROCESS | 2203 | |13;stanford-crypto-sha256-iterative;67;67;69;67;68;67;67;68;66;67 13:43:07 INFO - PROCESS | 2203 | __end_tp_report 13:43:07 INFO - PROCESS | 2203 | __start_cc_report 13:43:07 INFO - PROCESS | 2203 | _x_x_mozilla_cycle_collect,2125 13:43:07 INFO - PROCESS | 2203 | __end_cc_report 13:43:07 INFO - PROCESS | 2203 | __startTimestamp1472157787703__endTimestamp 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | ------- Summary: start ------- 13:43:07 INFO - PROCESS | 2203 | Number of tests: 14 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#0] ai-astar Cycles:10 Average:112.30 Median:111.00 stddev:4.52 (4.1%) stddev-sans-first:4.77 13:43:07 INFO - PROCESS | 2203 | Values: 111.0 111.0 125.0 112.0 111.0 110.0 110.0 110.0 111.0 112.0 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:130.00 stddev:21.77 (16.7%) stddev-sans-first:21.81 13:43:07 INFO - PROCESS | 2203 | Values: 149.0 108.0 130.0 159.0 108.0 129.0 130.0 105.0 161.0 107.0 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#2] audio-dft Cycles:10 Average:183.00 Median:184.00 stddev:1.94 (1.1%) stddev-sans-first:2.06 13:43:07 INFO - PROCESS | 2203 | Values: 183.0 185.0 184.0 184.0 184.0 183.0 178.0 182.0 184.0 183.0 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#3] audio-fft Cycles:10 Average:82.40 Median:64.00 stddev:35.38 (55.3%) stddev-sans-first:37.51 13:43:07 INFO - PROCESS | 2203 | Values: 79.0 75.0 78.0 154.0 57.0 56.0 57.0 70.0 140.0 58.0 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:72.50 stddev:23.82 (32.8%) stddev-sans-first:25.09 13:43:07 INFO - PROCESS | 2203 | Values: 84.0 85.0 133.0 70.0 75.0 92.0 119.0 70.0 70.0 121.0 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#5] imaging-gaussian-blur Cycles:10 Average:85.00 Median:86.00 stddev:1.33 (1.6%) stddev-sans-first:1.36 13:43:07 INFO - PROCESS | 2203 | Values: 86.0 84.0 86.0 84.0 87.0 83.0 86.0 84.0 86.0 84.0 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#6] imaging-darkroom Cycles:10 Average:100.30 Median:93.50 stddev:13.73 (14.7%) stddev-sans-first:9.46 13:43:07 INFO - PROCESS | 2203 | Values: 130.0 122.0 94.0 94.0 93.0 93.0 93.0 93.0 94.0 97.0 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:0.97 (1.1%) stddev-sans-first:1.00 13:43:07 INFO - PROCESS | 2203 | Values: 86.0 86.0 84.0 85.0 85.0 86.0 85.0 87.0 86.0 84.0 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:0.63 (1.1%) stddev-sans-first:0.50 13:43:07 INFO - PROCESS | 2203 | Values: 56.0 54.0 55.0 55.0 55.0 55.0 55.0 55.0 54.0 54.0 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:70.50 stddev:3.33 (4.7%) stddev-sans-first:3.43 13:43:07 INFO - PROCESS | 2203 | Values: 67.0 65.0 63.0 72.0 71.0 74.0 71.0 69.0 70.0 70.0 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:85.00 stddev:8.41 (9.9%) stddev-sans-first:8.77 13:43:07 INFO - PROCESS | 2203 | Values: 84.0 85.0 84.0 85.0 83.0 101.0 97.0 94.0 97.0 74.0 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:147.50 stddev:13.62 (9.2%) stddev-sans-first:14.33 13:43:07 INFO - PROCESS | 2203 | Values: 153.0 152.0 136.0 145.0 138.0 139.0 141.0 144.0 183.0 150.0 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.80 Median:174.50 stddev:17.45 (10.0%) stddev-sans-first:18.15 13:43:07 INFO - PROCESS | 2203 | Values: 171.0 221.0 205.0 172.0 175.0 174.0 171.0 171.0 173.0 175.0 13:43:07 INFO - PROCESS | 2203 | 13:43:07 INFO - PROCESS | 2203 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 13:43:07 INFO - PROCESS | 2203 | Values: 67.0 67.0 69.0 67.0 68.0 67.0 67.0 68.0 66.0 67.0 13:43:07 INFO - PROCESS | 2203 | -------- Summary: end -------- 13:43:07 INFO - PROCESS | 2203 | 13:43:08 INFO - TEST-INFO | 2203: exit 0 13:43:08 INFO - TEST-OK | kraken | took 59434ms 13:43:08 INFO - SUITE-END | took 1177s 13:43:08 INFO - Completed test suite (00:19:37) 13:43:08 INFO - javascript benchmark 13:43:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.269980604266, 3328.5839270091237, 3337.2824842658824, 3361.764676472648, 3415.5901291844984, 5554.805581804638, 5648.915266271004, 5691.317041954961, 5702.914456283159, 5761.020626493496, 4798.0, 5668.291463567054, 5775.797847075353, 5794.043982742853, 5833.095870140124, 5125.2312153176945, 5144.151215049513, 5194.065068287707, 5217.765200565965, 5282.286891269687, 5261.947380526203, 5262.158054711247, 5268.104422248208, 5298.576113910878, 5324.946750532503, 5150.587952963791, 5227.32044834171, 5234.879873651177, 5276.604254680895, 5298.655587386807, 5229.1633338666015, 5239.790408383698, 5244.265802787613, 5244.659097158673, 5253.106971814801, 5144.125498665236, 5177.741112944338, 5177.964407118591, 5211.400688920785, 5251.658642188246, 5661.377248502661, 5685.459881311269, 5737.254156959611, 5749.942500574983, 5795.217645617872, 6125.723854043132, 6143.539234557426, 6165.106059621375, 6237.688115594247, 6258.06129080637, 6084.847878803021, 6303.5902666327, 6315.652639104847, 6324.841878953017, 6341.936580634181, 5635.1828984796975, 5695.914561281584, 5704.771809127671, 5789.9867523183275, 5799.2750906136735, 6186.81439556814, 6272.0, 6278.277998030194, 6291.496680265568, 6291.937080629226, 6162.506999440034, 6177.444030037318, 6178.351273116348, 6184.690765461799, 6186.628802271878, 6103.450689437972, 6286.591371560834, 6287.1198032275515, 6294.024426213944, 6302.842428939313, 6065.272167339859, 6101.03536932808, 6104.694765261719, 6107.075063853431, 6108.22065524731, 6179.3202747697715, 6183.505319574423, 6249.156363890907, 6262.46769024638, 6270.529710271748, 4842.903141937141, 4844.176489996709, 4850.563449289511, 4857.392825896763, 4888.169011268093, 4419.712718673276, 4435.556444355603, 4466.977665111719, 4481.126180394792, 4531.660125490602, 5706.429357064343, 5745.88508229833, 5748.338941021836, 5758.798442054508, 5799.710014499258, 6280.152179455715, 6298.433141017331, 6312.495000399957, 6320.494360451153, 6325.019621958604, 5569.944300557024, 5722.284714410699, 5773.942260577424, 5790.855228619318, 5809.3609702932645, 5336.386315573759, 5350.652207606493, 5364.6512976656395, 5379.193121031876, 5444.210589464506, 5313.256144139824, 6256.718447669775, 6272.968635156795, 6275.623462592259, 6281.842953926097, 6112.746658179666, 6272.341404152681, 6275.560710750204, 6278.623282603058, 6289.811305660837, 6181.81454556364, 6206.348333424925, 6240.438360547572, 6286.968565157145, 6293.276273228637, 5065.7720402581235, 5253.422123566481, 6172.907406388818, 6179.196704428396, 6225.25296964349, 6022.066579680157, 6063.636181829104, 6250.0, 6256.812295631138, 6299.433051025429, 6065.0, 6109.938900610937, 6119.4798442132915, 6147.200863887845, 6160.137580738789, 6119.289302195972, 6119.807225076679, 6121.378883290332, 6132.04958718918, 6138.204480310071, 5091.974540127276, 5750.424957504383, 5964.7912323068485, 5982.982892908303, 5987.191729116514, 6049.274087109575, 6074.210352654303, 6081.756729730761, 6139.6454669416935, 6141.762184092961, 5937.495312898279, 5979.342272350125, 5983.0, 6003.099535069599, 6006.879862402815], "value": 5680.192835868505, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.68281885499005, 470.966229127066, 523.1838332201763, 524.9999999999999, 527.5357685236993, 27034.729652703467, 31694.049178524623, 32793.67206327936, 32925.99999999997, 40226.59773402265, 40560.79719601398, 43536.12927741443, 43589.12821743563, 43629.7818510907, 43792.78103609486, 9686.03139686031, 9892.010798920122, 9895.802083958317, 9901.900980990205, 9909.671227261588, 9685.757856053584, 9770.364926279804, 9796.265280103986, 9796.804063918717, 9833.16418104462, 9763.609455621767, 9778.902210977905, 9826.263030272758, 9828.164606008499, 9828.262880283972, 9773.0, 9803.607855685765, 9804.950975245149, 9810.411375317432, 9817.558209880572, 9680.806383872354, 9781.41311521311, 9781.755456113655, 9786.119249267529, 9808.264380171515, 37688.81155594232, 40001.0, 41132.971675708046, 41496.0, 41586.37620435696, 3412.176028721624, 3542.982285088584, 3974.205158968218, 4150.626443620058, 4311.655067594585, 25797.0, 32251.0, 32321.0, 32355.337815882125, 32560.348793024008, 3182.9363412731614, 3354.429746943001, 3390.643982381839, 3463.7748546344405, 3468.271662950783, 379640.0, 380305.9999999972, 380815.0, 381318.0, 387612.0, 59414.70292648509, 59691.70154149202, 59767.701161494784, 59826.700866496256, 59946.70026649926, 63443.6827815858, 63613.68193159004, 63641.0, 63791.36208637947, 63797.0, 16365.34538618442, 16584.0, 16609.169541522875, 16617.0, 16632.417865374653, 12827.422765975554, 12892.419841107172, 12908.031897607718, 12912.935435322763, 12925.418356173996, 13088.672783180496, 16526.338946442007, 16542.66914661724, 16546.42087526955, 16563.585910352096, 6102.481289090389, 6106.603070800473, 6185.474234690012, 6187.752489900354, 6196.597221180609, 891.6166048599035, 891.9910800891907, 1004.6333088422673, 1009.7677534167183, 1015.857160694219, 29831.552526712116, 29841.403171936872, 30156.246093847392, 30674.53988190179, 30683.539746903814, 1058.666520046183, 1080.36258607422, 1128.3230061962756, 1148.328227986637, 1170.3913964738458, 15363.607367833523, 15556.988795728468, 15701.83737489428, 15873.12697801632, 15935.601609959844, 595828.0, 653445.0, 657383.0, 659858.0, 660984.0, 58740.412595873495, 59128.0, 59129.704351477965, 59246.0, 59322.0, 1225.6384366611871, 1352.4590163934504, 1372.066994443768, 1382.0947279531922, 1394.5955672854961, 30692.232694182825, 31847.68152318447, 32429.837850810596, 32659.673403265664, 32952.505712413855, 49882.0, 56360.0, 57564.42435575591, 57787.13319300298, 61387.07919381124, 608681.0, 612019.0, 612785.0, 612855.0, 613394.0, 23900.28299151028, 24367.878160609085, 26250.606240906785, 26284.21147365582, 26835.463290734468, 40742.79628601838, 45515.0, 47476.0, 48138.79653008703, 49243.50756492533, 39648.80175599104, 39652.2069558613, 39966.80016599898, 41177.588224118575, 41363.379549306184, 38710.8386748398, 39637.0, 39675.0, 41047.56333528312, 41174.38238426366, 33047.0, 37889.62110378861, 38443.0, 38850.0, 39384.40923386094, 34041.82979085089, 34689.30621387609, 35878.0, 35898.8205058973, 36046.0, 21505.44057183267, 25795.742042580085, 25896.741032589434, 26527.204183873742, 26794.196174114808], "value": 19921.9669712159, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.1348196917552, 354.5674277381594, 375.7970695824253, 384.722999440403, 406.3295562322168, 13195.538156164535, 14859.257037148154, 20196.495087622818, 21023.84368859712, 21073.47316317093, 17598.120093995312, 20523.384298471068, 20768.48078798031, 20824.68762968553, 20834.854083025428, 3529.3470707918987, 5658.773649054043, 5907.527397808165, 5911.763529458816, 5913.438223368773, 4150.916981660365, 6449.903251451232, 6495.12315837363, 6589.242237142718, 6613.272540020618, 6216.8445788855415, 6428.389303016229, 6499.44754695849, 6521.184851893513, 6531.575447595915, 6499.967500162493, 6502.463054187173, 6555.180602424697, 6559.9672001640165, 6569.67151642416, 5517.862053448655, 5607.859803504904, 5678.0, 5683.9715801420725, 5687.317521897398, 24580.385490362703, 26164.869175654192, 26223.73776262251, 26369.0, 26558.73441265582, 2073.2743539761113, 2258.6386178211405, 2592.9481410371686, 2895.570720465678, 3004.5192769157047, 10399.168066554657, 11325.0, 12026.917577417988, 15724.528264152093, 15895.761563576556, 1637.9262933168036, 1784.357631252748, 1833.6791061564172, 1859.014722197224, 1914.9425517234506, 122726.38636806759, 130586.0, 136419.31790341085, 137256.0, 139322.0, 11336.319820810777, 11367.886321136848, 11526.827097593543, 11567.074634029257, 11682.70793230176, 14504.709905802036, 14551.27243637835, 14553.92723036378, 14573.489927852474, 14578.198199098939, 8188.303994160444, 8508.872366914502, 8521.701740439055, 8590.613422396007, 8604.8709269361, 8471.915280847235, 8478.915210847812, 8490.490570565786, 8667.826643467222, 8679.305655547541, 4451.933221001687, 4506.414166158444, 4511.436070491189, 4525.818967241339, 4608.677392582487, 1869.7849747279238, 1925.643755905162, 1925.8748181368167, 1926.9325573604856, 1928.797476264972, 375.35439044842826, 383.2201470008519, 387.96508314251855, 815.404754529197, 903.9050899655441, 11433.428328583537, 11538.826917596243, 11655.30068195911, 11751.88248117525, 11961.760764784656, 415.2711990456729, 427.5083653798157, 429.6551792888302, 443.07704562200206, 456.0673422850279, 5982.910256346158, 5998.49012833914, 6024.096385542204, 6167.136600875881, 6196.8760624787255, 620749.0, 622739.0, 622879.0, 627603.5405966557, 638789.8060509667, 122224.0, 123912.76087239193, 127818.0, 137593.3120334392, 141587.5841241595, 964.4261664309724, 1000.2248257600418, 1032.055669062799, 1032.210359075308, 1032.4063663393429, 16640.25118869678, 19208.327708530695, 19303.613927721646, 19520.414387568395, 19609.82341059541, 52684.73657631687, 56612.43387566072, 56636.43363566312, 57046.0, 58492.415075848694, 85080.57459712662, 86411.0, 86765.0, 87042.0, 89303.55348223218, 6894.965525172342, 7960.164182760958, 8099.8380032397845, 8210.63052162642, 8261.38039647017, 8885.600147993226, 12447.0, 12595.496180152692, 12862.678433038875, 13024.0, 10960.945195273973, 11009.174311926798, 11097.278676885977, 11259.493322800658, 11366.0, 10926.180536459957, 10965.396903170084, 11059.944700276446, 11202.103831693608, 11326.32042077478, 8244.752657420288, 11311.660650180507, 11320.26418282809, 11577.131715121232, 11593.304401735923, 6953.721851126101, 8067.959660201662, 8131.715389961323, 8157.75526734199, 8191.139930307232, 9466.432014079177, 9514.383544525312, 11160.497777599865, 11228.438578071227, 11229.0], "value": 9096.737549500605, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.7841945288756, 1498.940042398304, 1539.9528320741895, 1704.8209937956515, 1877.5963167918903, 7772.572508512029, 8580.613872375743, 8786.6924657637, 8820.955895220515, 8866.778330541723, 8648.091950345217, 8718.258947989412, 8724.869126963085, 8733.0, 8750.168733970262, 2393.9760602394012, 2417.4198192433864, 2421.757824217582, 2423.006567307404, 2423.8060955123547, 2375.5486457573093, 2400.9639855402183, 2419.4919066995953, 2420.6127019676856, 2430.2587710748217, 2232.2633530934754, 2324.883755812211, 2408.217329367954, 2425.4785221177444, 2428.2229686500327, 2170.121100954116, 2366.2309749331534, 2367.6922000140044, 2396.209250947183, 2409.132712223598, 2341.800946919514, 2356.422676444265, 2388.235764555343, 2393.521295740859, 2394.8084153267696, 8598.87101693475, 8653.091425400306, 8765.167309105625, 8769.517676527788, 8773.210411062972, 5244.659097158673, 5421.376541697716, 5421.484958928895, 5422.810201642963, 5431.646942948696, 3263.233140212048, 3680.9815950920074, 3685.7051435885064, 3713.9071523211865, 3759.8417329443128, 1086.5327908999127, 1093.436880006797, 1115.81031224692, 1124.1849658997244, 1138.8633363996373, 11366.545338186463, 11425.657740163246, 11498.367589782558, 11512.481938312712, 11512.942435287938, 4109.3425051991535, 4391.736495810262, 4473.619742321939, 4666.300054991779, 4677.18149323867, 9096.135867092615, 9098.226650734628, 9099.863502047474, 9101.135392137736, 9115.635374585076, 5265.289185959847, 5283.550898173621, 5286.392064912584, 5293.708846013506, 5299.6025298102795, 4428.13651337986, 4430.534793846664, 4596.4714057883775, 4947.356843610308, 4968.527989841032, 3790.6209379061875, 3795.221979494252, 3892.4745159403683, 3892.9416058759143, 3893.377059670439, 1171.0572988744143, 1189.5539172810197, 1192.689900625826, 1198.7842188405996, 1206.6319772469305, 922.3128769067088, 927.332320729074, 929.7494869400646, 936.4381371177375, 973.6952483671772, 4879.829205977774, 5198.584113270929, 5828.388019257917, 5967.76128954837, 5993.160957465958, 921.7373048681228, 951.7246889168547, 966.6809952715587, 972.2756546372998, 977.3158788847693, 3276.9344613107605, 3984.561698213194, 3992.740471869319, 4103.9179216415505, 4127.814248358831, 10387.065164135263, 10653.147748180127, 10758.56965721378, 10770.461476926122, 10791.78416431667, 10265.74335641615, 10722.624708135178, 10731.785364292826, 10733.121703701821, 10749.78500429987, 8010.639521221559, 8340.958295208486, 8352.373571982003, 8352.373571982125, 8354.039285482126, 5875.941240587539, 5907.084401917709, 6000.189974353407, 6027.21646185993, 6089.117078023751, 8587.699430520152, 10044.49777511107, 10072.899271007196, 10076.294659373774, 10089.293749437762, 9271.397359171633, 9634.795650543681, 9645.180159686504, 10118.0, 10127.544260508443, 7155.821104472429, 7329.340359367469, 7395.7041718330665, 7567.697292108254, 7776.339011184113, 8166.224208700164, 8423.620937057725, 8473.83052338962, 8579.313654907468, 8598.0, 6615.933840661724, 7563.886541701769, 7599.924000760143, 7624.085109786861, 7655.885161722467, 7452.888206677013, 7459.552426854406, 7489.400847932044, 7499.550026998398, 7531.849363012819, 6764.661766911733, 6826.829329266808, 6884.277150899284, 6907.171139463297, 6922.307769223037, 4873.317735516959, 5109.233614957786, 5114.360704911886, 5142.8971420572125, 5229.555487783581, 4999.725015124058, 5056.74716264178, 5074.416442109205, 5296.470352964673, 5309.787608495772], "value": 4576.051711828927, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.8695567428169, 300.2119436479242, 309.5696981196138, 315.22297536572376, 316.8669158953239, 4867.174809446457, 4883.902321953558, 5045.318881950936, 5057.822976195829, 5094.414142373628, 5011.799528018875, 5013.849584512462, 5015.598752099832, 5017.347744793181, 5031.899362012758, 3232.3696879108643, 3252.9186770330693, 3571.285742851427, 3607.278544291139, 3748.7188460865545, 3184.856681449329, 3863.0298547740713, 3909.2376986487648, 3917.5103112110987, 3929.469521614574, 3646.9635303647024, 3716.8477771890803, 3857.8263978121054, 3917.6082391760883, 3930.9017274568223, 3652.7625704329266, 3726.459663348799, 3853.6724378427866, 3877.495925529663, 3928.607139286077, 3411.8911353809863, 3416.8633254669785, 3426.794392336443, 3428.297199074209, 3448.758586898918, 3789.981050094732, 4545.340925565807, 4693.037927224911, 4991.425986011583, 4992.0, 3773.962260377416, 4262.190183865056, 4440.844570440019, 4446.221911165533, 4484.260097083978, 5184.844454666366, 5194.948050519484, 5231.555317797992, 5237.16205407133, 5238.5023422774775, 3288.095773662238, 3404.961486746541, 3740.17716102457, 3753.9113657039425, 3758.624137586247, 5405.945940540623, 5420.629698311079, 5548.588871846891, 5560.4161563036105, 5561.221428999943, 3184.092533627905, 3533.2756784859557, 3589.6230895756094, 3804.885853424402, 3813.618638136164, 3535.222251104753, 3639.526861507987, 3837.654611085016, 3845.423186522044, 3866.478025466582, 3884.9223015540097, 4327.112941846914, 4340.327249276367, 4409.382686423904, 4424.6902716810155, 4578.496365399803, 5047.116754568009, 5124.285942314288, 5129.153689641167, 5360.303160589098, 1196.8264601632713, 1199.5261871560685, 1294.6828027133413, 1294.9417276222405, 1306.1314226039767, 731.6963460164079, 864.9394542382099, 904.8009437923646, 911.7036962987056, 920.2331523474944, 4230.069384735353, 4407.493138289138, 4448.065906159743, 4987.326710894055, 4988.650794444354, 5181.4559471255725, 5191.454897235812, 5324.281222035053, 5361.758720857571, 5363.302770639792, 4236.639885609696, 4270.466191726035, 4892.143874821892, 4985.426675932242, 5052.671576347526, 2514.472954680571, 2516.8925672704318, 2529.4435224250637, 2777.8333300002064, 2823.2377258140186, 4770.403699537558, 5307.4692530746615, 5558.555315574744, 5561.247182644617, 5565.053940830068, 4929.679570827885, 5549.001179787677, 5574.554035677136, 5580.302462192226, 5605.5515558755205, 5645.786155976402, 5674.517665998436, 5692.800751973661, 5696.287964004499, 5697.6296540724725, 5175.4565770593545, 5485.835424937259, 5489.557147069516, 5504.862378440571, 5547.528460080938, 4759.3336932830125, 5341.172118321666, 5382.757775900016, 5409.350877894678, 5416.21464887597, 5356.517913387814, 5527.0, 5666.263385759825, 5717.42825717425, 5789.247397838422, 3663.87176448823, 3721.5348081489815, 3724.925501490009, 3728.8694895678523, 3751.1372384351207, 5414.3232095988005, 5418.403975562764, 5437.374701909331, 5499.4225606310765, 5516.9448305518035, 4354.586314300136, 5361.839144825662, 5371.919421208607, 5387.865303367447, 5405.432429594836, 4668.78990445424, 5199.584033277253, 5261.527082271326, 5276.419593844751, 5331.200319952039, 4129.504459464883, 5077.34986129502, 5102.336696229467, 5188.273641690242, 5208.479152084761, 5298.920516192183, 5328.841819063286, 5337.226102215234, 5397.649152805055, 5440.089552089613, 5061.31672224245, 5239.318888544465, 5302.992431438014, 5304.363476382704, 5318.282031925641], "value": 3902.770885840848, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.5860587347743, 661.8577005943721, 740.1155619035255, 799.4523751230406, 799.8402316640872, 13581.932090339547, 16356.0, 16665.41671041514, 16666.500004999838, 16694.749578756328, 14417.279136043207, 14944.10335379878, 15083.547493575183, 15262.08427494351, 15326.0, 847.2417186617973, 873.7291439653696, 874.5539774714878, 876.0538618292261, 877.0965905117707, 684.5858255755271, 711.1821405383798, 713.0801266366382, 716.1585137463486, 716.6774951271923, 895.7536677413698, 935.1023017902787, 935.7426707655416, 936.6815282803846, 938.3712912348743, 921.3136213520903, 921.4885738415177, 922.8108237811266, 922.9353945223837, 923.2383283790899, 540.8215288954635, 681.8534015186709, 682.0996284903929, 683.1631251716657, 686.457698418249, 12648.68378290541, 12780.936095319465, 12782.0, 12812.359382030954, 14735.778963315559, 14206.218657973808, 15039.774403383957, 15238.466653667068, 15286.388544458208, 15432.3827046918, 12235.571754988621, 12238.81641775374, 12250.387480626023, 12308.0, 12646.430910609044, 3923.234969181011, 3957.56466788653, 3987.0, 4053.7770422626745, 4139.089400331922, 115778.0, 118975.4051229747, 119276.0, 120249.0, 122072.0, 11501.884981150082, 11528.76942461146, 11596.768064638829, 11600.825987610193, 11976.46105925218, 23918.64122038171, 23986.04055837747, 23991.0, 24040.15859444911, 24050.27849164528, 7984.281414672707, 8173.223543763333, 8371.079181290053, 8374.120717324715, 8454.915450845412, 4542.545745425497, 4587.403637527167, 4680.227762419231, 4721.268203428474, 4818.084563932842, 3665.486831843544, 3753.7560058596105, 3793.4257283227703, 3793.975626580808, 3799.6200379962333, 575.2234483447396, 638.2022471910112, 645.3030726815028, 651.8761435327367, 680.0615151091462, 543.8721900353414, 551.401729123905, 553.4437889920604, 563.4309347558913, 667.4593579200863, 7833.451658383972, 8128.75613731589, 9648.0, 9670.468124253233, 9710.368826026286, 533.3706226652587, 567.5601408908129, 576.4379729763447, 586.7150939442607, 694.3611877073004, 4456.876918506744, 4686.250199967989, 4725.385699859065, 4754.524547545287, 4796.208625576741, 129259.0, 149050.25474872772, 152143.0, 153487.0, 156569.0, 142303.2884835569, 142422.0, 144768.0, 145886.0, 149758.25120874326, 94473.52763236414, 95293.99999999862, 97651.55871103136, 117040.29597040398, 126121.0, 7817.648205830637, 9427.387219830689, 9530.95234523823, 9630.614775409185, 9894.0, 50241.0, 57981.13028304494, 61457.0, 61999.0, 62870.68564657147, 90419.0, 90757.0, 91532.54233728789, 92600.0, 94510.0, 13049.804252936023, 13079.738405232032, 13257.469701211843, 13431.395587198404, 13684.931575342458, 14646.853531464549, 14798.77801832995, 14822.62943426423, 14957.252137392868, 15013.099214047192, 13778.586642400744, 13789.793153102912, 13838.792418113937, 14036.0, 14098.859011409755, 13717.519886803913, 13949.58151255464, 13967.860321397062, 14029.877609791396, 14459.349329280418, 13457.259850707913, 13493.932530337286, 13906.956978226472, 14153.0, 14278.286085695467, 9393.859092113487, 10909.942172923267, 11127.554897803997, 11425.942870285595, 11530.423478825858, 11354.432278386214, 11383.829242561535, 11615.709607259887, 11643.068554515788, 11655.18413711032], "value": 7890.042810050153, "unit": "score"}], "name": "dromaeo_css", "value": 7248.731374569699, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 125.0, 112.0, 111.0, 110.0, 110.0, 110.0, 111.0, 112.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 108.0, 130.0, 159.0, 108.0, 129.0, 130.0, 105.0, 161.0, 107.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 184.0, 184.0, 184.0, 183.0, 178.0, 182.0, 184.0, 183.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 78.0, 154.0, 57.0, 56.0, 57.0, 70.0, 140.0, 58.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 133.0, 70.0, 75.0, 92.0, 119.0, 70.0, 70.0, 121.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 87.0, 83.0, 86.0, 84.0, 86.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 122.0, 94.0, 94.0, 93.0, 93.0, 93.0, 93.0, 94.0, 97.0], "value": 100.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 85.0, 85.0, 86.0, 85.0, 87.0, 86.0, 84.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 54.0, 54.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 63.0, 72.0, 71.0, 74.0, 71.0, 69.0, 70.0, 70.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 85.0, 83.0, 101.0, 97.0, 94.0, 97.0, 74.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 136.0, 145.0, 138.0, 139.0, 141.0, 144.0, 183.0, 150.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 221.0, 205.0, 172.0, 175.0, 174.0, 171.0, 171.0, 173.0, 175.0], "value": 180.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 67.0, 68.0, 67.0, 67.0, 68.0, 66.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1477.4999999999998, "alertThreshold": 2.0}]} 13:43:08 INFO - Return code: 0 13:43:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:43:09 INFO - # TBPL SUCCESS # 13:43:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:43:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:43:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:43:09 INFO - 2016-08-25 13:43:09.033 screenresolution[2208:20915] starting screenresolution argv=screenresolution get 13:43:09 INFO - 2016-08-25 13:43:09.036 screenresolution[2208:20915] Display 0: 1600x1200x32@60 13:43:09 INFO - 2016-08-25 13:43:09.042 screenresolution[2209:20917] starting screenresolution argv=screenresolution list 13:43:09 INFO - Available Modes on Display 0 13:43:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:43:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:43:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:43:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:43:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:43:09 INFO - Intel Iris: 13:43:09 INFO - Chipset Model: Intel Iris 13:43:09 INFO - Type: GPU 13:43:09 INFO - Bus: Built-In 13:43:09 INFO - VRAM (Dynamic, Max): 1536 MB 13:43:09 INFO - Vendor: Intel (0x8086) 13:43:09 INFO - Device ID: 0x0a2e 13:43:09 INFO - Revision ID: 0x0009 13:43:09 INFO - Displays: 13:43:09 INFO - Display: 13:43:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:43:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:43:09 INFO - Display Serial Number: 1600x1200 60 13:43:09 INFO - Main Display: Yes 13:43:09 INFO - Mirror: Off 13:43:09 INFO - Online: Yes 13:43:09 INFO - Rotation: Supported 13:43:09 INFO - Return code: 0 13:43:09 INFO - Running post-action listener: _resource_record_post_action 13:43:09 INFO - [mozharness: 2016-08-25 20:43:09.280126Z] Finished run-tests step (success) 13:43:09 INFO - Running post-run listener: _resource_record_post_run 13:43:09 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 466944; Write bytes: 704646656; Read time: 6; Write time: 1935 13:43:09 INFO - TinderboxPrint: CPU usage
53.5% 13:43:09 INFO - TinderboxPrint: I/O read bytes / time
466,944 / 6 13:43:09 INFO - TinderboxPrint: I/O write bytes / time
704,646,656 / 1,935 13:43:09 INFO - TinderboxPrint: CPU idle
2,254.3 (46.5%) 13:43:09 INFO - TinderboxPrint: CPU system
967.6 (20.0%) 13:43:09 INFO - TinderboxPrint: CPU user
1,627.7 (33.6%) 13:43:09 INFO - TinderboxPrint: Swap in / out
405,803,008 / 0 13:43:09 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397109760; Write bytes: 392099328; Read time: 29630; Write time: 794 13:43:09 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 458752; Write bytes: 294680576; Read time: 5; Write time: 1093 13:43:09 INFO - Running post-run listener: _upload_blobber_files 13:43:09 INFO - Blob upload gear active. 13:43:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:43:09 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 13:43:09 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:43:09 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:43:10 INFO - (blobuploader) - INFO - Open directory for files ... 13:43:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:43:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:43:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:43:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:43:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:43:10 INFO - (blobuploader) - INFO - Done attempting. 13:43:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:43:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:43:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:43:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:43:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:43:11 INFO - (blobuploader) - INFO - Done attempting. 13:43:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:43:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:43:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:43:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:43:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:43:15 INFO - (blobuploader) - INFO - Done attempting. 13:43:15 INFO - (blobuploader) - INFO - Iteration through files over. 13:43:15 INFO - Return code: 0 13:43:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:43:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:43:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93c998ca0485c51e07b7ef1d10a6226d96504aa85bddb3c550f97074ecf2a56a7f3688725e7c4682cbf39493cdef8d3390174f93c90ab6ee8b8e94c0da3c1869", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/77e218e1d8cf38d69569e4640f7ff00d506b4bf6bcd7b2b71542fe13980312be0139676394453c0a36e70b9bdc6538e9e87e55d9085113f6ae959b0dca11e0e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1cfdd74292ab10e419daf64b87b4f03cd0d82c56e9c7f9929452fd136e8d30f13e3f41f02bf13e47243404e12ca0d4b20921d9ce6215185aaad618376f802470"} 13:43:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:43:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:43:15 INFO - Contents: 13:43:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93c998ca0485c51e07b7ef1d10a6226d96504aa85bddb3c550f97074ecf2a56a7f3688725e7c4682cbf39493cdef8d3390174f93c90ab6ee8b8e94c0da3c1869", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/77e218e1d8cf38d69569e4640f7ff00d506b4bf6bcd7b2b71542fe13980312be0139676394453c0a36e70b9bdc6538e9e87e55d9085113f6ae959b0dca11e0e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1cfdd74292ab10e419daf64b87b4f03cd0d82c56e9c7f9929452fd136e8d30f13e3f41f02bf13e47243404e12ca0d4b20921d9ce6215185aaad618376f802470"} 13:43:15 INFO - Running post-run listener: copy_logs_to_upload_dir 13:43:15 INFO - Copying logs to upload dir... 13:43:15 INFO - mkdir: /builds/slave/test/build/upload/logs 13:43:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.162641 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-25 13:43:15.229371) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 13:43:15.233076) ========= 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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93c998ca0485c51e07b7ef1d10a6226d96504aa85bddb3c550f97074ecf2a56a7f3688725e7c4682cbf39493cdef8d3390174f93c90ab6ee8b8e94c0da3c1869", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/77e218e1d8cf38d69569e4640f7ff00d506b4bf6bcd7b2b71542fe13980312be0139676394453c0a36e70b9bdc6538e9e87e55d9085113f6ae959b0dca11e0e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1cfdd74292ab10e419daf64b87b4f03cd0d82c56e9c7f9929452fd136e8d30f13e3f41f02bf13e47243404e12ca0d4b20921d9ce6215185aaad618376f802470"} build_url:https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010873 build_url: 'https://queue.taskcluster.net/v1/task/J59xMh58QligG_yMYmMVLw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93c998ca0485c51e07b7ef1d10a6226d96504aa85bddb3c550f97074ecf2a56a7f3688725e7c4682cbf39493cdef8d3390174f93c90ab6ee8b8e94c0da3c1869", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/77e218e1d8cf38d69569e4640f7ff00d506b4bf6bcd7b2b71542fe13980312be0139676394453c0a36e70b9bdc6538e9e87e55d9085113f6ae959b0dca11e0e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1cfdd74292ab10e419daf64b87b4f03cd0d82c56e9c7f9929452fd136e8d30f13e3f41f02bf13e47243404e12ca0d4b20921d9ce6215185aaad618376f802470"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 13:43:15.265462) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:43:15.265789) ========= 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.0RuhVS8AGl/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.xAmmhlLdsT/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.005287 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:43:15.314460) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 13:43:15.314793) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 13:43:15.315148) ========= ========= Total master_lag: 0.17 =========