builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0118 starttime: 1471566644.62 results: success (0) buildid: 20160818151256 builduid: 17c2596109c1484ca7d93a01b8052a2b revision: f8f422cda9d58189d100489111128f450c0bf581 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 17:30:44.616799) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 17:30:44.617285) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 17:30:44.617640) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 17:30:44.641591) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 17:30:44.642008) ========= 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.5L068MTf0N/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.vrjmfKqux0/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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-18 17:30:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 145M=0s 2016-08-18 17:30:44 (145 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098905 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 17:30:44.763577) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 17:30:44.763940) ========= 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.5L068MTf0N/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.vrjmfKqux0/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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.021384 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 17:30:44.812813) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 17:30:44.816256) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f8f422cda9d58189d100489111128f450c0bf581 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f8f422cda9d58189d100489111128f450c0bf581 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5L068MTf0N/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.vrjmfKqux0/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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-18 17:30:44,892 truncating revision to first 12 chars 2016-08-18 17:30:44,892 Setting DEBUG logging. 2016-08-18 17:30:44,892 attempt 1/10 2016-08-18 17:30:44,892 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f8f422cda9d5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 17:30:46,105 unpacking tar archive at: mozilla-central-f8f422cda9d5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.489884 ========= master_lag: 0.42 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 17:30:46.730757) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 17:30:46.731095) ========= script_repo_revision: f8f422cda9d58189d100489111128f450c0bf581 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 17:30:46.731845) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 17:30:46.732157) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 17:30:46.752130) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-18 17:30:46.752445) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5L068MTf0N/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.vrjmfKqux0/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 17:30:46 INFO - MultiFileLogger online at 20160818 17:30:46 in /builds/slave/test 17:30:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:30:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:30:46 INFO - {'append_to_log': False, 17:30:46 INFO - 'base_work_dir': '/builds/slave/test', 17:30:46 INFO - 'blob_upload_branch': 'Firefox', 17:30:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:30:46 INFO - 'branch': 'Firefox', 17:30:46 INFO - 'buildbot_json_path': 'buildprops.json', 17:30:46 INFO - 'config_files': ('talos/mac_config.py',), 17:30:46 INFO - 'default_actions': ('clobber', 17:30:46 INFO - 'read-buildbot-config', 17:30:46 INFO - 'download-and-extract', 17:30:46 INFO - 'populate-webroot', 17:30:46 INFO - 'create-virtualenv', 17:30:46 INFO - 'install', 17:30:46 INFO - 'run-tests'), 17:30:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:30:46 INFO - 'download_minidump_stackwalk': True, 17:30:46 INFO - 'download_symbols': 'ondemand', 17:30:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:30:46 INFO - 'tooltool.py': '/tools/tooltool.py', 17:30:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:30:46 INFO - '/tools/misc-python/virtualenv.py')}, 17:30:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:30:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:30:46 INFO - 'installer_path': 'installer.exe', 17:30:46 INFO - 'log_level': 'info', 17:30:46 INFO - 'log_name': 'talos', 17:30:46 INFO - 'log_to_console': True, 17:30:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:30:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:30:46 INFO - 'opt_config_files': (), 17:30:46 INFO - 'pip_index': False, 17:30:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:30:46 INFO - 'cmd': ('bash', 17:30:46 INFO - '-c', 17:30:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:30:46 INFO - 'enabled': True, 17:30:46 INFO - 'halt_on_failure': False, 17:30:46 INFO - 'name': 'check_screen_resolution'},), 17:30:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:30:46 INFO - 'cmd': ('bash', 17:30:46 INFO - '-c', 17:30:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:30:46 INFO - 'enabled': True, 17:30:46 INFO - 'halt_on_failure': False, 17:30:46 INFO - 'name': 'check_screen_resolution'},), 17:30:46 INFO - 'run_cmd_checks_enabled': True, 17:30:46 INFO - 'sps_profile': False, 17:30:46 INFO - 'sps_profile_interval': 0, 17:30:46 INFO - 'suite': 'dromaeojs', 17:30:46 INFO - 'system_bits': '32', 17:30:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:30:46 INFO - 'title': 't-yosemite-r7-0118', 17:30:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:30:46 INFO - 'use_talos_json': True, 17:30:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:30:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:30:46 INFO - 'work_dir': 'build'} 17:30:46 INFO - [mozharness: 2016-08-19 00:30:46.946772Z] Running clobber step. 17:30:46 INFO - Running pre-action listener: _resource_record_pre_action 17:30:46 INFO - Running main action method: clobber 17:30:46 INFO - rmtree: /builds/slave/test/build 17:30:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:30:47 INFO - Running post-action listener: _resource_record_post_action 17:30:47 INFO - [mozharness: 2016-08-19 00:30:47.897785Z] Finished clobber step (success) 17:30:47 INFO - [mozharness: 2016-08-19 00:30:47.897956Z] Running read-buildbot-config step. 17:30:47 INFO - Running pre-action listener: _resource_record_pre_action 17:30:47 INFO - Running main action method: read_buildbot_config 17:30:47 INFO - Using buildbot properties: 17:30:47 INFO - { 17:30:47 INFO - "project": "", 17:30:47 INFO - "product": "firefox", 17:30:47 INFO - "script_repo_revision": "production", 17:30:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 17:30:47 INFO - "repository": "", 17:30:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 17:30:47 INFO - "buildid": "20160818151256", 17:30:47 INFO - "pgo_build": "False", 17:30:47 INFO - "basedir": "/builds/slave/test", 17:30:47 INFO - "buildnumber": 65, 17:30:47 INFO - "slavename": "t-yosemite-r7-0118", 17:30:47 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 17:30:47 INFO - "platform": "yosemite_r7", 17:30:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:30:47 INFO - "branch": "mozilla-central", 17:30:47 INFO - "repo_path": "mozilla-central", 17:30:47 INFO - "revision": "f8f422cda9d58189d100489111128f450c0bf581", 17:30:47 INFO - "stage_platform": "macosx64", 17:30:47 INFO - "builduid": "17c2596109c1484ca7d93a01b8052a2b", 17:30:47 INFO - "slavebuilddir": "test" 17:30:47 INFO - } 17:30:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 17:30:47 INFO - Running post-action listener: _resource_record_post_action 17:30:47 INFO - [mozharness: 2016-08-19 00:30:47.901348Z] Finished read-buildbot-config step (success) 17:30:47 INFO - [mozharness: 2016-08-19 00:30:47.901450Z] Running download-and-extract step. 17:30:47 INFO - Running pre-action listener: _resource_record_pre_action 17:30:47 INFO - Running main action method: download_and_extract 17:30:47 INFO - mkdir: /builds/slave/test/build/tests 17:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:47 INFO - trying https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 17:30:47 INFO - Downloading https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/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 17:30:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/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 17:30:48 INFO - Downloaded 1199 bytes. 17:30:48 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 17:30:48 INFO - Using the following test package requirements: 17:30:48 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:30:48 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:30:48 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 17:30:48 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:30:48 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 17:30:48 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:30:48 INFO - u'jsshell-mac.zip'], 17:30:48 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:30:48 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 17:30:48 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:30:48 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:30:48 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 17:30:48 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:30:48 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 17:30:48 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:30:48 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 17:30:48 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:30:48 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 17:30:48 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:48 INFO - trying https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:30:48 INFO - Downloading https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/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 17:30:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/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 17:30:49 INFO - Downloaded 16339504 bytes. 17:30:49 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 17:30:56 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 17:30: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')]} 17:30:56 INFO - trying https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:30:56 INFO - Downloading https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/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 17:30:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/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 17:30:57 INFO - Downloaded 16339504 bytes. 17:30:57 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 17:31:03 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')]} 17:31:03 INFO - trying https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 17:31:03 INFO - Downloading https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/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 17:31:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/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 17:31:05 INFO - Downloaded 14413981 bytes. 17:31:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 17:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:05 INFO - trying https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:31:05 INFO - Downloading https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 17:31:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/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 17:31:08 INFO - Downloaded 111253260 bytes. 17:31:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:31:08 INFO - mkdir: /builds/slave/test/properties 17:31:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:31:08 INFO - Writing to file /builds/slave/test/properties/build_url 17:31:08 INFO - Contents: 17:31:08 INFO - build_url:https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:31:09 INFO - Running post-action listener: _resource_record_post_action 17:31:09 INFO - Running post-action listener: set_extra_try_arguments 17:31:09 INFO - [mozharness: 2016-08-19 00:31:09.026513Z] Finished download-and-extract step (success) 17:31:09 INFO - [mozharness: 2016-08-19 00:31:09.026642Z] Running populate-webroot step. 17:31:09 INFO - Running pre-action listener: _resource_record_pre_action 17:31:09 INFO - Running main action method: populate_webroot 17:31:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:31:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:31:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:31:09 INFO - '--tppagecycles', 17:31:09 INFO - '7'], 17:31:09 INFO - 'tests': ['tsvgm']}}, 17:31:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:31:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:31:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:31:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:31:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:31:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:31:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:31:09 INFO - 'talos_options': ['--disable-e10s'], 17:31:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:31:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:31:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:31:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:31:09 INFO - 'talos_options': ['--disable-e10s'], 17:31:09 INFO - 'tests': ['damp', 'tps']}, 17:31:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:31:09 INFO - 'tests': ['damp', 'tps']}, 17:31:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:31:09 INFO - 'tests': ['dromaeo_dom']}, 17:31:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:31:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:31:09 INFO - 'tests': ['basic_compositor_video']}, 17:31:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:31:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:31:09 INFO - 'tests': ['a11yr', 17:31:09 INFO - 'ts_paint', 17:31:09 INFO - 'tpaint', 17:31:09 INFO - 'sessionrestore', 17:31:09 INFO - 'sessionrestore_no_auto_restore', 17:31:09 INFO - 'tabpaint']}, 17:31:09 INFO - 'other-e10s': {'tests': ['a11yr', 17:31:09 INFO - 'ts_paint', 17:31:09 INFO - 'tpaint', 17:31:09 INFO - 'sessionrestore', 17:31:09 INFO - 'sessionrestore_no_auto_restore', 17:31:09 INFO - 'tabpaint']}, 17:31:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:31:09 INFO - 'ts_paint', 17:31:09 INFO - 'tpaint', 17:31:09 INFO - 'sessionrestore', 17:31:09 INFO - 'sessionrestore_no_auto_restore', 17:31:09 INFO - 'tabpaint']}, 17:31:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:31:09 INFO - 'ts_paint', 17:31:09 INFO - 'tpaint', 17:31:09 INFO - 'sessionrestore', 17:31:09 INFO - 'sessionrestore_no_auto_restore', 17:31:09 INFO - 'tabpaint']}, 17:31:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:31:09 INFO - 'tests': ['a11yr', 17:31:09 INFO - 'ts_paint', 17:31:09 INFO - 'tpaint', 17:31:09 INFO - 'sessionrestore', 17:31:09 INFO - 'sessionrestore_no_auto_restore', 17:31:09 INFO - 'tabpaint']}, 17:31:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:31:09 INFO - 'tests': ['a11yr', 17:31:09 INFO - 'ts_paint', 17:31:09 INFO - 'tpaint', 17:31:09 INFO - 'sessionrestore', 17:31:09 INFO - 'sessionrestore_no_auto_restore', 17:31:09 INFO - 'tabpaint']}, 17:31:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:31:09 INFO - 'tests': ['tsvgx', 17:31:09 INFO - 'tsvgr_opacity', 17:31:09 INFO - 'tart', 17:31:09 INFO - 'tscrollx', 17:31:09 INFO - 'cart']}, 17:31:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:31:09 INFO - 'tsvgr_opacity', 17:31:09 INFO - 'tart', 17:31:09 INFO - 'tscrollx', 17:31:09 INFO - 'cart']}, 17:31:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:31:09 INFO - 'talos_options': ['--disable-e10s'], 17:31:09 INFO - 'tests': ['tp5o']}, 17:31:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:31:09 INFO - 'tests': ['tp5o']}, 17:31:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:31:09 INFO - 'talos_options': ['--disable-e10s', 17:31:09 INFO - '--xperf_path', 17:31:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:31:09 INFO - 'tests': ['tp5n']}, 17:31:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:31:09 INFO - 'talos_options': ['--xperf_path', 17:31:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:31:09 INFO - 'tests': ['tp5n']}}, 17:31:09 INFO - 'talos.zip': {'path': '', 17:31:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:31:09 INFO - Running post-action listener: _resource_record_post_action 17:31:09 INFO - [mozharness: 2016-08-19 00:31:09.045853Z] Finished populate-webroot step (success) 17:31:09 INFO - [mozharness: 2016-08-19 00:31:09.045959Z] Running create-virtualenv step. 17:31:09 INFO - Running pre-action listener: _resource_record_pre_action 17:31:09 INFO - Running main action method: create_virtualenv 17:31:09 INFO - Creating virtualenv /builds/slave/test/build/venv 17:31:09 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 17:31:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:31:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:31:09 INFO - Using real prefix '/tools/python27' 17:31:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:31:09 INFO - Installing distribute.............................................................................................................................................................................................done. 17:31:12 INFO - Installing pip.................done. 17:31:12 INFO - Return code: 0 17:31:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:31:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:12 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 0x10961aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096af618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87985c0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10957ed30>, '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 0x1092e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109576b20>, '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.5L068MTf0N/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.vrjmfKqux0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 17:31:12 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 17:31:12 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 17:31:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5L068MTf0N/Render', 17:31:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:31:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:31:12 INFO - 'HOME': '/Users/cltbld', 17:31:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:31:12 INFO - 'LOGNAME': 'cltbld', 17:31:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:12 INFO - 'MOZ_NO_REMOTE': '1', 17:31:12 INFO - 'NO_EM_RESTART': '1', 17:31:12 INFO - 'PAGER': '/bin/cat', 17:31:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:31:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:12 INFO - 'PWD': '/builds/slave/test', 17:31:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:31:12 INFO - 'SHELL': '/bin/bash', 17:31:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrjmfKqux0/Listeners', 17:31:12 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 17:31:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:31:12 INFO - 'USER': 'cltbld', 17:31:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:31:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:31:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:12 INFO - 'XPC_FLAGS': '0x0', 17:31:12 INFO - 'XPC_SERVICE_NAME': '0', 17:31:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:13 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 17:31:13 INFO - Cleaning up... 17:31:13 INFO - Return code: 0 17:31:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:31: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')]} 17:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31: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')]} 17:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:13 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 0x10961aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096af618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87985c0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10957ed30>, '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 0x1092e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109576b20>, '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.5L068MTf0N/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.vrjmfKqux0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 17:31:13 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 17:31:13 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 17:31:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5L068MTf0N/Render', 17:31:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:31:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:31:13 INFO - 'HOME': '/Users/cltbld', 17:31:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:31:13 INFO - 'LOGNAME': 'cltbld', 17:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:13 INFO - 'MOZ_NO_REMOTE': '1', 17:31:13 INFO - 'NO_EM_RESTART': '1', 17:31:13 INFO - 'PAGER': '/bin/cat', 17:31:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:13 INFO - 'PWD': '/builds/slave/test', 17:31:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:31:13 INFO - 'SHELL': '/bin/bash', 17:31:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrjmfKqux0/Listeners', 17:31:13 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 17:31:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:31:13 INFO - 'USER': 'cltbld', 17:31:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:31:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:13 INFO - 'XPC_FLAGS': '0x0', 17:31:13 INFO - 'XPC_SERVICE_NAME': '0', 17:31:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:13 INFO - Downloading/unpacking psutil>=3.1.1 17:31:13 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 17:31:13 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 17:31:13 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 17:31:13 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 17:31:13 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 17:31:13 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 17:31:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:31:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:31:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:31:16 INFO - Installing collected packages: psutil 17:31:16 INFO - Running setup.py install for psutil 17:31:16 INFO - building 'psutil._psutil_osx' extension 17:31:16 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 17:31:16 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 17:31:16 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 17:31:16 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 17:31:16 INFO - building 'psutil._psutil_posix' extension 17:31:16 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 17:31:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:31:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:31:16 INFO - ^ 17:31:16 INFO - 1 warning generated. 17:31:16 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 17:31:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:31:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:31:16 INFO - Successfully installed psutil 17:31:16 INFO - Cleaning up... 17:31:16 INFO - Return code: 0 17:31:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:31:16 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')]} 17:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:16 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')]} 17:31:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:16 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 0x10961aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096af618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87985c0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10957ed30>, '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 0x1092e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109576b20>, '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.5L068MTf0N/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.vrjmfKqux0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 17:31:16 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 17:31:16 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 17:31:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5L068MTf0N/Render', 17:31:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:31:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:31:16 INFO - 'HOME': '/Users/cltbld', 17:31:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:31:16 INFO - 'LOGNAME': 'cltbld', 17:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:16 INFO - 'MOZ_NO_REMOTE': '1', 17:31:16 INFO - 'NO_EM_RESTART': '1', 17:31:16 INFO - 'PAGER': '/bin/cat', 17:31:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:31:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:16 INFO - 'PWD': '/builds/slave/test', 17:31:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:31:16 INFO - 'SHELL': '/bin/bash', 17:31:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrjmfKqux0/Listeners', 17:31:16 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 17:31:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:31:16 INFO - 'USER': 'cltbld', 17:31:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:31:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:16 INFO - 'XPC_FLAGS': '0x0', 17:31:16 INFO - 'XPC_SERVICE_NAME': '0', 17:31:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:31:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:31:17 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 17:31:17 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 17:31:17 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 17:31:17 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 17:31:17 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 17:31:17 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 17:31:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:31:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:31:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:31:19 INFO - Installing collected packages: mozsystemmonitor 17:31:19 INFO - Running setup.py install for mozsystemmonitor 17:31:19 INFO - Successfully installed mozsystemmonitor 17:31:19 INFO - Cleaning up... 17:31:19 INFO - Return code: 0 17:31:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:31:19 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')]} 17:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:19 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')]} 17:31:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:19 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 0x10961aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096af618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87985c0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10957ed30>, '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 0x1092e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109576b20>, '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.5L068MTf0N/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.vrjmfKqux0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 17:31:19 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 17:31:19 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 17:31:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5L068MTf0N/Render', 17:31:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:31:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:31:19 INFO - 'HOME': '/Users/cltbld', 17:31:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:31:19 INFO - 'LOGNAME': 'cltbld', 17:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:19 INFO - 'MOZ_NO_REMOTE': '1', 17:31:19 INFO - 'NO_EM_RESTART': '1', 17:31:19 INFO - 'PAGER': '/bin/cat', 17:31:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:19 INFO - 'PWD': '/builds/slave/test', 17:31:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:31:19 INFO - 'SHELL': '/bin/bash', 17:31:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrjmfKqux0/Listeners', 17:31:19 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 17:31:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:31:19 INFO - 'USER': 'cltbld', 17:31:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:31:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:19 INFO - 'XPC_FLAGS': '0x0', 17:31:19 INFO - 'XPC_SERVICE_NAME': '0', 17:31:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:20 INFO - Downloading/unpacking jsonschema==2.5.1 17:31:20 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 17:31:20 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 17:31:20 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 17:31:20 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 17:31:20 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 17:31:20 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 17:31:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:31:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:31:22 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 17:31:22 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 17:31:22 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 17:31:22 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 17:31:22 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 17:31:22 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 17:31:23 INFO - Downloading functools32-3.2.3-2.tar.gz 17:31:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:31:23 INFO - warning: no files found matching '*.txt' 17:31:23 INFO - no previously-included directories found matching 'build' 17:31:23 INFO - no previously-included directories found matching 'dist' 17:31:23 INFO - no previously-included directories found matching '.git*' 17:31:23 INFO - Installing collected packages: jsonschema, functools32 17:31:23 INFO - Running setup.py install for functools32 17:31:23 INFO - warning: no files found matching '*.txt' 17:31:23 INFO - no previously-included directories found matching 'build' 17:31:23 INFO - no previously-included directories found matching 'dist' 17:31:23 INFO - no previously-included directories found matching '.git*' 17:31:23 INFO - Successfully installed jsonschema functools32 17:31:23 INFO - Cleaning up... 17:31:23 INFO - Return code: 0 17:31:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:31:23 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')]} 17:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:23 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')]} 17:31:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:23 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 0x10961aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096af618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87985c0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10957ed30>, '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 0x1092e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109576b20>, '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.5L068MTf0N/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.vrjmfKqux0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 17:31:23 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 17:31:23 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 17:31:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5L068MTf0N/Render', 17:31:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:31:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:31:23 INFO - 'HOME': '/Users/cltbld', 17:31:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:31:23 INFO - 'LOGNAME': 'cltbld', 17:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:23 INFO - 'MOZ_NO_REMOTE': '1', 17:31:23 INFO - 'NO_EM_RESTART': '1', 17:31:23 INFO - 'PAGER': '/bin/cat', 17:31:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:23 INFO - 'PWD': '/builds/slave/test', 17:31:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:31:23 INFO - 'SHELL': '/bin/bash', 17:31:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrjmfKqux0/Listeners', 17:31:23 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 17:31:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:31:23 INFO - 'USER': 'cltbld', 17:31:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:31:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:23 INFO - 'XPC_FLAGS': '0x0', 17:31:23 INFO - 'XPC_SERVICE_NAME': '0', 17:31:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:31:23 INFO - Cleaning up... 17:31:23 INFO - Return code: 0 17:31:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:31:23 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')]} 17:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:23 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')]} 17:31:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:23 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 0x10961aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096af618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87985c0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10957ed30>, '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 0x1092e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109576b20>, '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.5L068MTf0N/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.vrjmfKqux0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 17:31:23 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 17:31:23 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 17:31:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5L068MTf0N/Render', 17:31:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:31:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:31:23 INFO - 'HOME': '/Users/cltbld', 17:31:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:31:23 INFO - 'LOGNAME': 'cltbld', 17:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:23 INFO - 'MOZ_NO_REMOTE': '1', 17:31:23 INFO - 'NO_EM_RESTART': '1', 17:31:23 INFO - 'PAGER': '/bin/cat', 17:31:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:23 INFO - 'PWD': '/builds/slave/test', 17:31:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:31:23 INFO - 'SHELL': '/bin/bash', 17:31:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrjmfKqux0/Listeners', 17:31:23 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 17:31:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:31:23 INFO - 'USER': 'cltbld', 17:31:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:31:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:23 INFO - 'XPC_FLAGS': '0x0', 17:31:23 INFO - 'XPC_SERVICE_NAME': '0', 17:31:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:23 INFO - Downloading/unpacking blobuploader==1.2.4 17:31:23 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 17:31:23 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 17:31:23 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 17:31:23 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 17:31:23 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 17:31:23 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 17:31:26 INFO - Downloading blobuploader-1.2.4.tar.gz 17:31:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:31:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:31:26 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 17:31:26 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 17:31:26 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 17:31:26 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 17:31:26 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 17:31:26 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 17:31:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:31:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:31:27 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 17:31:27 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 17:31:27 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 17:31:27 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 17:31:27 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 17:31:27 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 17:31:27 INFO - Downloading docopt-0.6.1.tar.gz 17:31:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:31:27 INFO - Installing collected packages: blobuploader, requests, docopt 17:31:27 INFO - Running setup.py install for blobuploader 17:31:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:31:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:31:27 INFO - Running setup.py install for requests 17:31:28 INFO - Running setup.py install for docopt 17:31:28 INFO - Successfully installed blobuploader requests docopt 17:31:28 INFO - Cleaning up... 17:31:28 INFO - Return code: 0 17:31:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:28 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 0x10961aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096af618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87985c0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10957ed30>, '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 0x1092e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109576b20>, '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.5L068MTf0N/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.vrjmfKqux0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 17:31:28 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 17:31:28 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 17:31:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5L068MTf0N/Render', 17:31:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:31:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:31:28 INFO - 'HOME': '/Users/cltbld', 17:31:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:31:28 INFO - 'LOGNAME': 'cltbld', 17:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:28 INFO - 'MOZ_NO_REMOTE': '1', 17:31:28 INFO - 'NO_EM_RESTART': '1', 17:31:28 INFO - 'PAGER': '/bin/cat', 17:31:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:28 INFO - 'PWD': '/builds/slave/test', 17:31:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:31:28 INFO - 'SHELL': '/bin/bash', 17:31:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrjmfKqux0/Listeners', 17:31:28 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 17:31:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:31:28 INFO - 'USER': 'cltbld', 17:31:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:31:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:28 INFO - 'XPC_FLAGS': '0x0', 17:31:28 INFO - 'XPC_SERVICE_NAME': '0', 17:31:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:31:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-oG5AId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:31:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-5gGVuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:31:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-xeZyqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:31:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-0MxBp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:31:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-vBnOM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:31:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-EwCYTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:31:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-DCIpb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:31:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-vqtZae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:31:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-VXrQWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:31:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-A8nMUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:31:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-1yTQI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:31:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-F0zsxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:31:30 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-tvHo4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:31:30 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-HprXqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:31:30 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-pHGcPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:31:30 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-_FPtN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:31:30 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-hgRunU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:31:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:31:30 INFO - Running setup.py install for manifestparser 17:31:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:31:30 INFO - Running setup.py install for mozcrash 17:31:30 INFO - Running setup.py install for mozdebug 17:31:31 INFO - Running setup.py install for mozdevice 17:31:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:31:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:31:31 INFO - Running setup.py install for mozfile 17:31:31 INFO - Running setup.py install for mozhttpd 17:31:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:31:31 INFO - Running setup.py install for mozinfo 17:31:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:31:31 INFO - Running setup.py install for mozInstall 17:31:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:31:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:31:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:31:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:31:31 INFO - Running setup.py install for mozleak 17:31:31 INFO - Running setup.py install for mozlog 17:31:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:31:32 INFO - Running setup.py install for moznetwork 17:31:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:31:32 INFO - Running setup.py install for mozprocess 17:31:32 INFO - Running setup.py install for mozprofile 17:31:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:31:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:31:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:31:32 INFO - Running setup.py install for mozrunner 17:31:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:31:32 INFO - Running setup.py install for mozscreenshot 17:31:32 INFO - Running setup.py install for moztest 17:31:33 INFO - Running setup.py install for mozversion 17:31:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:31:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:31:33 INFO - Cleaning up... 17:31:33 INFO - Return code: 0 17:31:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:33 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 0x10961aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096af618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87985c0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10957ed30>, '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 0x1092e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109576b20>, '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.5L068MTf0N/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.vrjmfKqux0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 17:31:33 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 17:31:33 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 17:31:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5L068MTf0N/Render', 17:31:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:31:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:31:33 INFO - 'HOME': '/Users/cltbld', 17:31:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:31:33 INFO - 'LOGNAME': 'cltbld', 17:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:33 INFO - 'MOZ_NO_REMOTE': '1', 17:31:33 INFO - 'NO_EM_RESTART': '1', 17:31:33 INFO - 'PAGER': '/bin/cat', 17:31:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:33 INFO - 'PWD': '/builds/slave/test', 17:31:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:31:33 INFO - 'SHELL': '/bin/bash', 17:31:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrjmfKqux0/Listeners', 17:31:33 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 17:31:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:31:33 INFO - 'USER': 'cltbld', 17:31:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:31:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:33 INFO - 'XPC_FLAGS': '0x0', 17:31:33 INFO - 'XPC_SERVICE_NAME': '0', 17:31:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:31:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-rZOUU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:31:33 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)) 17:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:31:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-17uvbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:31:33 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)) 17:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:31:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-2JFiLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:31:33 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)) 17:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:31:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-B1RYUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:31:33 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)) 17:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:31:33 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-kla799-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:31:34 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)) 17:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:31:34 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-tTDeHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:31:34 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)) 17:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:31:34 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-OpfwFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:31:34 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)) 17:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:31:34 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-78bXIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:31:34 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)) 17:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:31:34 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-hIMAl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:31:34 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)) 17:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:31:34 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-3BneEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:31:34 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)) 17:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:31:34 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-PnKY13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:31:34 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)) 17:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:31:34 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-2BmXYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:31:34 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)) 17:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:31:34 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-skM0kS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:31:34 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)) 17:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:31:34 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-qWF96Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:31:35 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)) 17:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:31:35 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-Xf7Kjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:31:35 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)) 17:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:31:35 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-iuk_nC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:31:35 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)) 17:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:31:35 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-qFzitc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:31:35 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)) 17:31:35 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)) 17:31:35 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)) 17:31:35 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)) 17:31:35 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)) 17:31:35 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)) 17:31:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:31:35 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 17:31:35 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 17:31:35 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 17:31:35 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 17:31:35 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 17:31:35 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 17:31:37 INFO - Downloading blessings-1.6.tar.gz 17:31:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:31:37 INFO - Installing collected packages: blessings 17:31:37 INFO - Running setup.py install for blessings 17:31:38 INFO - Successfully installed blessings 17:31:38 INFO - Cleaning up... 17:31:38 INFO - Return code: 0 17:31:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:31:38 INFO - Reading from file tmpfile_stdout 17:31:38 INFO - Current package versions: 17:31:38 INFO - blessings == 1.6 17:31:38 INFO - blobuploader == 1.2.4 17:31:38 INFO - docopt == 0.6.1 17:31:38 INFO - functools32 == 3.2.3-2 17:31:38 INFO - jsonschema == 2.5.1 17:31:38 INFO - manifestparser == 1.1 17:31:38 INFO - mozInstall == 1.12 17:31:38 INFO - mozcrash == 0.17 17:31:38 INFO - mozdebug == 0.1 17:31:38 INFO - mozdevice == 0.48 17:31:38 INFO - mozfile == 1.2 17:31:38 INFO - mozhttpd == 0.7 17:31:38 INFO - mozinfo == 0.9 17:31:38 INFO - mozleak == 0.1 17:31:38 INFO - mozlog == 3.3 17:31:38 INFO - moznetwork == 0.27 17:31:38 INFO - mozprocess == 0.23 17:31:38 INFO - mozprofile == 0.28 17:31:38 INFO - mozrunner == 6.12 17:31:38 INFO - mozscreenshot == 0.1 17:31:38 INFO - mozsystemmonitor == 0.3 17:31:38 INFO - moztest == 0.7 17:31:38 INFO - mozversion == 1.4 17:31:38 INFO - psutil == 3.1.1 17:31:38 INFO - requests == 1.2.3 17:31:38 INFO - wsgiref == 0.1.2 17:31:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:39 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 0x10961aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096af618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87985c0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10957ed30>, '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 0x1092e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109576b20>, '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.5L068MTf0N/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.vrjmfKqux0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 17:31:39 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 17:31:39 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 17:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5L068MTf0N/Render', 17:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:31:39 INFO - 'HOME': '/Users/cltbld', 17:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:31:39 INFO - 'LOGNAME': 'cltbld', 17:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:39 INFO - 'MOZ_NO_REMOTE': '1', 17:31:39 INFO - 'NO_EM_RESTART': '1', 17:31:39 INFO - 'PAGER': '/bin/cat', 17:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:39 INFO - 'PWD': '/builds/slave/test', 17:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:31:39 INFO - 'SHELL': '/bin/bash', 17:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrjmfKqux0/Listeners', 17:31:39 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 17:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:31:39 INFO - 'USER': 'cltbld', 17:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:39 INFO - 'XPC_FLAGS': '0x0', 17:31:39 INFO - 'XPC_SERVICE_NAME': '0', 17:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:39 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)) 17:31:39 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)) 17:31:39 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)) 17:31:39 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)) 17:31:39 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)) 17:31:39 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)) 17:31:39 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)) 17:31:39 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)) 17:31:39 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)) 17:31:39 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)) 17:31:39 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)) 17:31:39 INFO - Cleaning up... 17:31:39 INFO - Return code: 0 17:31:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:31:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10961aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096af618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87985c0910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10957ed30>, '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 0x1092e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109576b20>, '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.5L068MTf0N/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.vrjmfKqux0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 17:31:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5L068MTf0N/Render', 17:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:31:39 INFO - 'HOME': '/Users/cltbld', 17:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:31:39 INFO - 'LOGNAME': 'cltbld', 17:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:39 INFO - 'MOZ_NO_REMOTE': '1', 17:31:39 INFO - 'NO_EM_RESTART': '1', 17:31:39 INFO - 'PAGER': '/bin/cat', 17:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:39 INFO - 'PWD': '/builds/slave/test', 17:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:31:39 INFO - 'SHELL': '/bin/bash', 17:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrjmfKqux0/Listeners', 17:31:39 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 17:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:31:39 INFO - 'USER': 'cltbld', 17:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:39 INFO - 'XPC_FLAGS': '0x0', 17:31:39 INFO - 'XPC_SERVICE_NAME': '0', 17:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:31:39 INFO - Cleaning up... 17:31:39 INFO - Return code: 0 17:31:39 INFO - Running post-action listener: _resource_record_post_action 17:31:39 INFO - Running post-action listener: _start_resource_monitoring 17:31:39 INFO - Starting resource monitoring. 17:31:39 INFO - [mozharness: 2016-08-19 00:31:39.767134Z] Finished create-virtualenv step (success) 17:31:39 INFO - [mozharness: 2016-08-19 00:31:39.768166Z] Running install step. 17:31:39 INFO - Running pre-action listener: _resource_record_pre_action 17:31:39 INFO - Running main action method: install 17:31:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:31:40 INFO - Reading from file tmpfile_stdout 17:31:40 INFO - Detecting whether we're running mozinstall >=1.0... 17:31:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:31:40 INFO - Reading from file tmpfile_stdout 17:31:40 INFO - Output received: 17:31:40 INFO - Usage: mozinstall [options] installer 17:31:40 INFO - Options: 17:31:40 INFO - -h, --help show this help message and exit 17:31:40 INFO - -d DEST, --destination=DEST 17:31:40 INFO - Directory to install application into. [default: 17:31:40 INFO - "/builds/slave/test"] 17:31:40 INFO - --app=APP Application being installed. [default: firefox] 17:31:40 INFO - mkdir: /builds/slave/test/build/application 17:31:40 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'] 17:31:40 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 17:32:14 INFO - Reading from file tmpfile_stdout 17:32:14 INFO - Output received: 17:32:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:32:14 INFO - Running post-action listener: _resource_record_post_action 17:32:14 INFO - [mozharness: 2016-08-19 00:32:14.358467Z] Finished install step (success) 17:32:14 INFO - [mozharness: 2016-08-19 00:32:14.358577Z] Running run-tests step. 17:32:14 INFO - Running pre-action listener: _resource_record_pre_action 17:32:14 INFO - Running main action method: run_tests 17:32:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:32:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:32:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:32:14 INFO - 2016-08-18 17:32:14.373 screenresolution[1912:10560] starting screenresolution argv=screenresolution get 17:32:14 INFO - 2016-08-18 17:32:14.376 screenresolution[1912:10560] Display 0: 1600x1200x32@60 17:32:14 INFO - 2016-08-18 17:32:14.382 screenresolution[1913:10562] starting screenresolution argv=screenresolution list 17:32:14 INFO - Available Modes on Display 0 17:32:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:32:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:32:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:32:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:32:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:32:14 INFO - Intel Iris: 17:32:14 INFO - Chipset Model: Intel Iris 17:32:14 INFO - Type: GPU 17:32:14 INFO - Bus: Built-In 17:32:14 INFO - VRAM (Dynamic, Max): 1536 MB 17:32:14 INFO - Vendor: Intel (0x8086) 17:32:14 INFO - Device ID: 0x0a2e 17:32:14 INFO - Revision ID: 0x0009 17:32:14 INFO - Displays: 17:32:14 INFO - Display: 17:32:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:32:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:32:14 INFO - Display Serial Number: 1600x1200 60 17:32:14 INFO - Main Display: Yes 17:32:14 INFO - Mirror: Off 17:32:14 INFO - Online: Yes 17:32:14 INFO - Rotation: Supported 17:32:14 INFO - Return code: 0 17:32:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:32:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:32:14 INFO - Python 2.7.3 17:32:14 INFO - Return code: 0 17:32:14 INFO - grabbing minidump binary from tooltool 17:32:14 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')]} 17:32:14 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 0x10957ed30>, '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 0x1092e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109576b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:32:14 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 17:32:14 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 17:32:14 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 17:32:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:32:14 INFO - Return code: 0 17:32:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:32:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:32:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:32:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:32:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:32:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0118', '--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 17:32:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0118 --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 17:32:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5L068MTf0N/Render', 17:32:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:14 INFO - 'HOME': '/Users/cltbld', 17:32:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:14 INFO - 'LOGNAME': 'cltbld', 17:32:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:32:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:14 INFO - 'MOZ_NO_REMOTE': '1', 17:32:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:32:14 INFO - 'NO_EM_RESTART': '1', 17:32:14 INFO - 'PAGER': '/bin/cat', 17:32:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:14 INFO - 'PWD': '/builds/slave/test', 17:32:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:32:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:14 INFO - 'SHELL': '/bin/bash', 17:32:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrjmfKqux0/Listeners', 17:32:14 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 17:32:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:14 INFO - 'USER': 'cltbld', 17:32:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:14 INFO - 'XPC_FLAGS': '0x0', 17:32:14 INFO - 'XPC_SERVICE_NAME': '0', 17:32:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0118', '--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 17:32:14 INFO - mozversion application_buildid: 20160818151256 17:32:14 INFO - mozversion application_changeset: f8f422cda9d58189d100489111128f450c0bf581 17:32:14 INFO - mozversion application_display_name: Nightly 17:32:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:32:14 INFO - mozversion application_name: Firefox 17:32:14 INFO - mozversion application_remotingname: firefox 17:32:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:32:14 INFO - mozversion application_vendor: Mozilla 17:32:14 INFO - mozversion application_version: 51.0a1 17:32:14 INFO - mozversion platform_buildid: 20160818151256 17:32:14 INFO - mozversion platform_changeset: f8f422cda9d58189d100489111128f450c0bf581 17:32:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:32:14 INFO - mozversion platform_version: 51.0a1 17:32:14 INFO - using testdate: 1471566734 17:32:14 INFO - actual date: 1471566734 17:32:14 INFO - starting webserver on 'localhost:49766' 17:32:14 INFO - SUITE-START | Running 2 tests 17:32:14 INFO - TEST-START | dromaeo_css 17:32:14 INFO - operating with platform_type : mac_ 17:32:14 INFO - Initialising browser for dromaeo_css test... 17:32:14 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmp0Ym3A1/profile http://localhost:49766/getInfo.html) 17:32:17 INFO - PROCESS | 1923 | __metrics Screen width/height:1600/1200 17:32:17 INFO - PROCESS | 1923 | colorDepth:24 17:32:17 INFO - PROCESS | 1923 | Browser inner width/height: 1024/689 17:32:17 INFO - PROCESS | 1923 | __metrics 17:32:17 INFO - PROCESS | 1923 | 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" 17:32:17 INFO - PROCESS | 1923 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:32:18 INFO - TEST-INFO | 1923: exit 0 17:32:18 INFO - Browser initialized. 17:32:18 INFO - Running cycle 1/1 for dromaeo_css test... 17:32:18 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmp0Ym3A1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:32:30 INFO - PROCESS | 1925 | RSS: Main: 228327424 17:32:30 INFO - PROCESS | 1925 | 17:33:22 INFO - PROCESS | 1925 | 1471566802219 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] 17:35:33 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:49766/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49766/tests/dromaeo/cssquery-ext.html) 17:35:35 INFO - PROCESS | 1925 | RSS: Main: 340357120 17:35:35 INFO - PROCESS | 1925 | 17:38:38 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:49766/tests/dromaeo/cssquery-ext.html (next: http://localhost:49766/tests/dromaeo/cssquery-jquery.html) 17:38:38 INFO - PROCESS | 1925 | RSS: Main: 372367360 17:38:38 INFO - PROCESS | 1925 | 17:41:41 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:49766/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49766/tests/dromaeo/cssquery-mootools.html) 17:41:42 INFO - PROCESS | 1925 | RSS: Main: 349700096 17:41:42 INFO - PROCESS | 1925 | 17:44:44 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:49766/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49766/tests/dromaeo/cssquery-prototype.html) 17:44:45 INFO - PROCESS | 1925 | RSS: Main: 351715328 17:44:45 INFO - PROCESS | 1925 | 17:47:48 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:49766/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49766/tests/dromaeo/cssquery-yui.html) 17:47:49 INFO - PROCESS | 1925 | RSS: Main: 335564800 17:47:49 INFO - PROCESS | 1925 | 17:50:52 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:49766/tests/dromaeo/cssquery-yui.html (next: http://localhost:49766/tests/dromaeo/cssquery-dojo.html) 17:50:53 INFO - PROCESS | 1925 | RSS: Main: 447930368 17:50:53 INFO - PROCESS | 1925 | 17:50:53 INFO - PROCESS | 1925 | __start_tp_report 17:50:53 INFO - PROCESS | 1925 | _x_x_mozilla_page_load 17:50:53 INFO - PROCESS | 1925 | _x_x_mozilla_page_load_details 17:50:53 INFO - PROCESS | 1925 | |i|pagename|runs| 17:50:53 INFO - PROCESS | 1925 | |0;dojo.html;3153.7634677399196;3361.2773253750456;3380.712639425649;3391.609964854041;3405.233822389961;5677.233573467581;5732.541396688265;5812.070068788995;5842.64944103353;5861.472467477926;5795.15970184323;5807;5861.501772349345;5901;5923.111533270012;5174.172132458807;5231.5814734821315;5285.101532739424;5298.046351656701;5395.7032363220005;5364.356277246736;5416.593755468336;5428.511433970962;5430.212619170218;5459.099248623972;5201.063808514466;5243.082460569385;5278.129108697099;5323.520883120537;5326.573874090082;5316.388615309212;5319.893602127974;5321.122014867581;5327.520523152896;5345.118055520835;5177.275181474596;5216.113260745645;5244.81643142492;5265.262863199156;5287.682739035632;4853.805847766121;5743.425657434265;5803.303603567557;5849.093390524476;5890.352061273256;6314.968425157891;6316.873662526769;6326.68366581669;6328.430441260309;6340.746370145189;6163;6219.036049412348;6227.813165605039;6279.654618995954;6288.088227207029;5811.88376232473;5874.5887787854435;5882.382349853289;5900.940990590125;5945.613535120204;6395.360463953567;6402.103705481236;6404.135441715309;6406.839829004219;6420.7431702731365;6082.300535438393;6121.959266924633;6246.875062498815;6251.0623406488485;6258.968705156445;6201.441870231611;6278.183836101278;6300.401461861115;6305.905411418832;6319.747210111544;6280.937190628127;6289.654069026156;6291.307956124822;6311.779087731999;6314.242290925118;6155.169052177988;6216.937830621727;6303.3066362700065;6305.14880491137;6331.746730130743;4932.778024988812;4964.429090654548;5011.924821127686;5015.523525265094;5017.498250175026;4437.955620443754;4482.170798402306;4515.345274935116;4547.499775024745;4551.590356867898;5816.185733997244;5845.503132233723;5874.236349274567;5883.970580147157;5903.321118071391;6213.689315534205;6266.592671476339;6285.937140628626;6287.779927702509;6292.685365731695;4792.329073929653;5659.6321239119325;5694.943050569441;5827.970860145757;5840.766369345179;5477.643953143033;5490.835274941758;5496.972515137399;5531.25328080712;5544.889102218014;6378.45783108441;6414.743410263631;6452.677366131768;6464.806055818333;6470;6187.566870319034;6201.96899015502;6272.655003974643;6283.71723272463;6300.117983482224;6415.518836087405;6452.7418903245225;6464.676766161767;6498.577592456664;6504.089427480062;6276.905846412216;6278.623282603058;6288.24541055058;6297.96851015742;6301.212348456443;6174.567780255339;6184.938150618436;6276.623402595859;6282.403171698681;6292.653904035139;6125.815438131279;6162.414570615784;6221.47117495018;6232.937670623236;6234.251889773256;6161.84595385119;6190.690465476618;6205.968970155121;6209;6223.37766223374;5723.513501352431;5952.940470595238;6093.238345206849;6093.512518998381;6102.908456373069;6133.539984501091;6191.628502289877;6197.96901015492;6199.969000154971;6202.255729312331;5175.404828444777;5845.590808643354;6000.97983342824;6066.029435290332;6084.90872636902 17:50:53 INFO - PROCESS | 1925 | |1;ext.html;453.4581175495283;475.8405934012106;477.08399872245286;484.9684770489916;513.6173550704724;31155;33398.998030059105;40097.195626196815;40527.39208911861;43042.92392690176;41784.791076044654;42197.789011054905;42201.57798422015;42305.365419518734;42364.940876478024;8818.73543793684;9516.90483095169;9941.90058099417;9943.95028024859;9946.403215807039;8755.693550725726;11842.644720658394;12130.818037729441;12138.271703697763;12367;12105.515779368814;12159.331236781973;12188.81716774249;12197.45111469986;12237.388130593436;10317.79364412715;12077.818832717472;12180.939095304555;12182.756344873142;12191.939040304742;11974.940125299405;12053.517859285706;12087.456064477119;12087.93956030223;12089.274643521418;39729;39958.40062399067;40015.7999210005;40026.399604005965;40095;3338.2488939988307;3379.560657114584;3388.9830550847087;3391.7286617070577;3508.596511401196;31895.36209275825;32236.83881580577;33114.50328245078;33531.6646833531;34876.604935802534;3267.8202698851555;3282.441984862579;3312.38720836646;3327.88352407667;3422.6235114137426;489622;490451;497580;498915;506900.99999999633;65303.020454693215;66618;66626.0006099909;66666.99999500011;66734.99897501542;35517.112072198404;36240.81879590638;70064.29935700679;70655;70665.64667176631;16619.584510387336;17125.828741712674;17143.74284385735;17154.571135721457;17174.656506869793;11756.706082347839;12068;12733.80899286493;12952.158109722837;13086.803697944539;19968.90015549913;20830;20844.166233350497;20892.686609700868;20893.791062089487;7179.605121718356;8545.563912764626;8592.484450932963;8635.481871087755;8661.437006594551;866.9523176225242;977.2621670638622;1048.4548035021899;1073.978520429587;1076.1928553584812;29357.559636605467;30187.698123018927;30255.394892102035;30660;31109.377812443625;1025.8820235672845;1073.5598404654054;1100.1913593508764;1113.3932007056173;1120.7702421003676;15053.774193387108;15648.92175539115;15660;15806.20968951548;15880.602984925239;582074;587659;588076;589239;590729;57031.14453283204;57577;57664.13503797446;57679.423205768246;57723.71138144367;1116.7375666718315;1152.291340825403;1234.0682784497646;1389.9930500347434;1415.2287003583072;30306.696933030387;30968.535471967487;31342.686573134888;32031.519527206645;32059;48327;48867.26699099445;48877;48883.511164887896;48950;534077;536034;538067;538557;538993;27100.72899270982;27606.447871042867;27923;28144.29639259035;28499.572506412835;46704.53295467138;46767.532324676315;47151.76424117857;47374.763126184145;47908.05333226593;32275.838620806746;38306.0423489415;38527.03682407962;38784.6121538781;39525.80237098796;32078.358432830744;37725.81137094297;38904.2219155621;39837.80081099576;40310.992225194605;37550.24899502049;37862;38460.230795384494;38648.613513864504;38707.80646096864;32646.020619381456;34377.484337735455;34489.65510344865;34630.480542791374;35008.824955875054;20144.597108057464;23742.762572374748;24785.380365491008;25062.498750024533;25416.237512874643 17:50:53 INFO - PROCESS | 1925 | |2;jquery.html;332.9334133173366;350.29590523048665;354.61878480633317;371.32604323153475;402.45266437644796;15642.765358519617;17330.306787728492;20253.987300634984;20800.167993280287;20812.687809682848;20773.96130193492;20924.953752312398;20977.216936560373;21181.576368472623;21784;5860.384659610743;5896.5282777377815;6059.515238780887;6161.445469907708;6174.598651087687;4169.874903752892;6044.486218671418;6143.385661433843;6155.292141403751;6155.8153255402185;6478.445173158431;6774.322567743211;6794.422474089708;6845.24702282751;6892.655367231619;6554.737810487575;6754.121964144679;6806.489513286499;6822;6825;5733.541316694655;5969.283685957713;5972.432618901197;5976.5218782497295;5982.7906023289415;26403;26644.333891652863;26660.200193994213;26956.326091847666;27033.459330813275;2205.900734466953;2213.4908970936613;2283.7373702024147;2444.804415646744;2494.5634513959985;15192.620184495365;15889.841101589067;16003.759943600855;16273.737082956957;16669.83330166695;1902.5053486093577;1961.3037371732971;1977.8022197780251;1991.930282440122;1997.3608445297511;117081.8291817088;117477.41261293639;117843;118354.40822795831;120805.39597301958;10418.739531511621;10683.73290667724;10684;10975.615853445091;11186.44067796607;13943;13968.930155349159;13969.301534923214;13977.510787122605;13991.580252592235;8401;8684.6526138955;8692.174243446863;8861.911380886237;8864.468131912106;8711;8731.738047858575;8744.125587441205;8903.955480222558;8979.191872731486;6656.567323123982;6778.627175505296;6797.592144471348;6797.830054248584;6804.897926531106;1804.2873065139197;1836.9265229390676;1842.5577861313457;1842.6591080650123;1843.4285371534868;932.9860052099224;945.5603144537823;998.3760149906309;1002.7793885345212;1092.4865313302735;19454.221831126597;22564.871756412114;22797.88601056984;23018.769812301663;23037.424064398525;918.4718786697675;990.277097718655;1007.9143272821746;1011.6307547745167;1025.779457416659;11570.42147892602;11864.08485199882;12248.693782655326;12252.958498527725;12297.385130743427;596981;603792;603842;625501;628031;121044;124257.75742242462;126416;128320.07519887209;129628;987.481572174614;1009.1271050541376;1018.923580731448;1029.9330543514648;1035.8290882004535;19185.32851350196;19315.710264346326;20053.197063681822;20272.493187669836;20276.79723202749;41672.79163604163;55210.171847421516;55988.720056399456;57138.88350792604;57555;80460;81440.18559814326;83386;83729.58135209285;85274.58901644112;7770.611469426619;7804.292509684657;7925.960370198112;8101.392395570238;8154.75535733929;8838.196076098682;12676.302803345905;12719.236845789283;12864.421101050288;13139;10943.507542160463;11170.776584468427;11227.494762735532;11239.325640461599;11367.772644547229;8997.820043599222;10895.564177432814;11163.944180279046;11360.204785664924;11417.429128543707;11144.665660030212;11426.486173041918;11451.541938322374;11467.598634047768;11471.4264286787;7685.11621163551;7917.960410197912;7936.365090792839;8180.550069746221;8328.250457458858;10207.591696332063;11055.778884422427;11093.33439993603;11195.832062518906;11535.884641153816 17:50:53 INFO - PROCESS | 1925 | |3;mootools.html;1290.1871820752924;1387.7432674955135;1500.8349081601025;1669.157075676783;1872.728454374115;7609.695612175513;8337.24964753173;8568.400211985156;8571.185737354941;8700.173483519071;8463.788405289872;8547.188017138378;8554.44396114252;8563.91436085639;8564.74305770828;2341.625339945605;2375.798057165143;2378.5718570657273;2378.63131214662;2382.9046838126455;1932.9033548322527;2364.9239595983786;2387.7373488916205;2390.414348484615;2394.0782798622567;2336.7780060894274;2366.9644955325684;2373.32360277321;2381.30942026812;2383.7497062808416;2320.6867072945106;2350.083467447697;2353.95292094158;2362.208660098869;2364.207990323244;2344.296710986714;2347.1550241912896;2349.565330413879;2352.45893444507;2355.658429527726;7357.668904899292;8437.83124337516;8633.309335253165;8639.481631102153;8651.740447786577;5099.36257967754;5301.072312345363;5302.390225124083;5356.11624082026;5363.222332761767;3067.4631939410738;3585.4084076127406;3673.742838001341;3687.907802304937;3688.3360995020885;1091.879893211746;1092.748667806409;1097.462243500687;1103.3104309806372;1113.8663360396804;11259.943700281528;11275.379854108021;11317.943410282978;11358.545658173745;11369.886301136965;4346.391505189276;4387.232234358974;4567.725936443758;4570.840020599263;4570.9314360284625;8901.287896968228;8906.332025098143;8907.465552066897;8914.242289405343;8919.643214271486;5163.4062082860955;5172.405173405032;5176.456472070378;5185.274061631375;5185.740712964337;4331.0904710011255;4495.43807024122;4649.767511624405;4772.475027746945;4841.15279826029;3214.5499630051813;3533.258015816656;3601.94597081044;3677.5770786359376;3680.5399325084363;1162.1980833225048;1169.8947094761343;1171.94453784837;1176.264834478451;1177.5113327968797;768.0667988394127;792.397777688949;829.402829962426;875.2821893936338;893.6693423433371;5220.799216180224;5222.634415590948;5655.406182350876;5916.26046744157;6029.276486821609;442.4579889635235;456.62100456620925;458.0998338265258;992.7071513903416;1020.1940467031111;3869.3228684980018;3997.600239975979;4035.21313343901;4058.492688413948;4247.299195632748;10488.528016239288;10544.894551054545;10693.465326733633;10697.786044279226;10717.83923241152;10632.574697012033;10635.361878287327;10660.893391066145;10683.465826708634;10685.091767199876;7801.6879324827505;7942.285194332538;7942.602869856484;8139.226773456512;8155.469894456842;5824.126381042877;5875.706214689334;6019.819405417845;6054.576179667381;6055.788047418319;9637.518124093627;9750.21998240125;9789.9021009789;9796.559154838193;9800.803983920425;9632.855507167258;9679.758006049906;9918.851217231891;9997.700068997941;10178.541965611417;6837.418819400406;7020.227774944854;7026.683799229149;7394.667239974106;7630.351420129351;7705.075390953121;7781.5331080135375;7905.762827115196;8164.918350816416;8185.508869467612;7321.78034658961;7397.445191610543;7440.516366436164;7448.292412220831;7581.772546823604;7047.753328633473;7235.529690570306;7370.77887663371;7380.520266182681;7403.481756277009;6859.897101543381;6880.965595171992;6929.792106236821;6931.202911665021;6933.237343892269;4542.818287268605;4926.458089610212;4977.278294647328;5007.223880298559;5109.029284435946;4036.7174297798874;4971.353724015999;5054.671446355975;5099.056674515226;5106.10643137442 17:50:53 INFO - PROCESS | 1925 | |4;prototype.html;291.2267928649434;296.90499040307105;303.6170244536137;309.6872159119289;311.8269360504922;4322.394864718942;5303.840884773458;5358.49094336038;5375.043725950731;5380.488853558915;4741.060542463677;4778.81853378294;5041.6974981501135;5299.012870173685;5309.115264081253;4031.5766844481345;4080.3063479208604;4087.7547347159116;4101.446304748865;4118.567550407209;3398.8130652814084;4083.1221287423346;4121.793910304487;4131;4154.563770804068;3694.9815250923707;3725.739198256123;3779.603141670126;3824.579296277407;4110.876673699794;3224.709776120137;3857.148568687046;3899.4345819856103;4047.251258517184;4098.684243402468;3536.257385948954;3556.3065202285566;3556.6798988090936;3563.3585954528176;3569.125564236753;4850.466448690641;5199.518137330842;5239.659422137549;5247.501487358695;5251.86870328241;3803.619638036202;4491.214037543416;4527.728336299832;4543.31850222466;4578.977105114486;4232.36514522824;5475.014497390468;5493.176023596453;5545.556355491592;5555.666660000413;3028.97015014896;3419.863205471778;3720.516332876709;3785.9810700946596;3800.4869342638667;5103.617228707862;5800.506956908668;5816.109764326568;5823.184754134425;5858.736356863951;3795.2978698940783;3795.9430608540893;3803.3344164771056;3811.6950643948417;3834.041489627593;3881;3881.476000739864;3882.8446862125197;3883.8446462141196;3890.844366225376;4243.193793179348;4497.392851964943;4552.795124219352;4554.453465584085;4568.428946381702;4918.975405122951;5557.499825015687;5571.220029195916;5595.062592139159;5613.382527921925;1308.6978456435907;1339.71196192819;1341.5304643374936;1342.4093398904452;1345;803.2850762821093;822.6133717153048;840.6091167607092;843.2115971566604;844.4933040175845;4973.90052198954;5144.048351055067;5207.973960130175;5273.736313184402;5276.445973172838;5044.041632089891;5438.91841622376;5692.402297758758;5715.5427565794635;5715.971125197504;4617.353570500132;4990.8253211137435;5051.318072060299;5189.532942035235;5226.033183860998;2647.3249321422913;2938.412317536501;2959.289770455075;2993.461176988163;3027.1826606816476;4931.903940825135;5242.842714718565;5921.572155416258;5944.762209511571;5974.880502389928;4423;4940.728259945666;5859.5019423349495;5884;5887.175795388649;4541.342410020319;5734.046965812609;5878.911816322759;5904.911426328608;5963.761449542143;5409.810656626999;5438.75525601341;5621.775128994958;5666.773329066956;5759.712014399347;5203.591647470913;5353.919691204557;5635.323761148525;5640.743702519026;5659.9812033834305;5680.630759000652;5918.763249469973;5951.619352259131;5971.4327138921735;5985.970070149621;3319.170207448138;3393.423118069885;3834.4087203811223;3865.806709664562;3896.7661940283674;5029.06962211992;5614.606977511535;5732.71336433185;5749.281339832521;5749.741261643152;5155.639105262746;5652.349979752381;5672.631278966854;5678.971605141948;5694.032014557618;4374.309799473237;4621.514740952151;4866.7079975201605;5106.740057740533;5316.521513063843;4681;5210.244514545294;5417.8103766367985;5459.044667183306;5462.371827239918;5403.729813509387;5555.694436806015;5606.906653202586;5614.915776263278;5665.801696940587;5495.862603434946;5505.642133261325;5529.529989950899;5534.7786088556;5548.722563871709 17:50:53 INFO - PROCESS | 1925 | |5;yui.html;717.7416130193131;728.3626826526789;734.9418417998171;751.2525037587601;812.6058861452196;15467.30397132129;16630.253823348525;16655.33378664852;16689.83310166898;16726.33094676213;14932.925335373307;15039.92480037601;15053.698926021472;15054.397824087051;15078;662.6951602262965;686.193722376206;826.9710560130396;828.2918105020196;854.6538651846013;838.8423974914609;860.5439117267858;862.3920136303922;864.5201912938348;866.7269810009858;921.1387352825096;923.2198792020732;923.745969858291;924.856647219682;926.7405126564573;705.6436499567736;734.8016035670339;737.5832654550162;921.5760750054983;923.1322556796623;539.1939051118563;541.545102114222;655.298830251631;678.4199509419474;683.4498228925762;15062.246887655683;15104.244787760568;15104.54686359411;15130.848691513054;15143.772843407358;14033.228172450512;14353.64115897111;14442.133471991609;14447.711045779026;14651.413943442249;13072.346382680827;13314.201147931155;13437.932810335984;13460.461581536727;13670.384832682554;4568.291914753218;4588.172732378116;4588.816447342102;4594;4869.391326084239;124060;125496.37251813774;129481;129722.702772972;130667;9584.952075239578;11422.885771142348;11475.713107172387;11584.594539191088;12007.51969921211;23471.41321466943;23482.53034939326;23518.529629407316;23530;23546.411339716644;7838.764837054783;7844.058712954368;7879.251471110236;7882.251186137308;8034;4360.4767427908855;4420;4518.909621807611;4587.79354929029;4685.109829132454;3504.807235602015;3590.622984586633;3612.1150318172217;3624.130208749881;3624.7100231981376;641.1878849236543;642.068999950065;655.7803135949415;667.1860330396908;668.1447746883996;579.8550362409397;605.231356177652;631.6273398694792;635.3836778325017;637.6365471681097;8728.69449569262;9019.503927283931;9120.653989975432;9212.53937303132;9266.44401335922;490.4213028626255;529.2167591963915;541.7859945321576;551.0191858491891;599.5473417569707;4081.77550234734;4436.977815110969;4443.533428989909;4455.910881782411;4511.796969136331;87521.12478875257;144285;153420;159089.20455397648;161485.19257403637;106320;127822.36088819496;127828.36085819511;129727;132358;83017;110140.4492977546;112396.43801780939;113953;114544.13639659075;8470.872936906075;8578.957105214684;8604.225619694258;8738.226753597428;8984.505852178192;46621.766891166684;60453;63901.041484376845;64444.67777661081;68299.3170068293;77346.944897163;96279;97583.51208244197;97832;100961.98076038585;11484.253523520945;11696.139278621824;11737.706557336134;11874.287542747463;12055.046513604224;12674;12702.428390722625;12922.612321630366;13154.60536183916;13438.731225375634;11800.3509806958;12045.217060891015;12065.81901271464;12363.93818030904;12450.875491245333;11640.5343786251;11942.582009629621;12059;12245.142840001114;12316.815247771112;10228.181745460493;11920.284782913053;11945.223560468541;12245.265284082983;12318.260904345769;8556.786080348042;9776.9022309776;10148.391096534231;10148.74628134273;10601.310914790514;8593.785155371166;9212.723618291462;10336;10337.586496540054;10337.9483102584 17:50:53 INFO - PROCESS | 1925 | __end_tp_report 17:50:53 INFO - PROCESS | 1925 | __start_cc_report 17:50:53 INFO - PROCESS | 1925 | _x_x_mozilla_cycle_collect,1326 17:50:53 INFO - PROCESS | 1925 | __end_cc_report 17:50:53 INFO - PROCESS | 1925 | __startTimestamp1471567853027__endTimestamp 17:50:53 INFO - PROCESS | 1925 | 17:50:53 INFO - PROCESS | 1925 | ------- Summary: start ------- 17:50:53 INFO - PROCESS | 1925 | Number of tests: 6 17:50:53 INFO - PROCESS | 1925 | 17:50:53 INFO - PROCESS | 1925 | [#0] dojo.html Cycles:165 Average:5812.68 Median:6084.91 stddev:657.80 (10.8%) stddev-sans-first:625.87 17:50:53 INFO - PROCESS | 1925 | Values: 3153.8 3361.3 3380.7 3391.6 3405.2 5677.2 5732.5 5812.1 5842.6 5861.5 5795.2 5807.0 5861.5 5901.0 5923.1 5174.2 5231.6 5285.1 5298.0 5395.7 5364.4 5416.6 5428.5 5430.2 5459.1 5201.1 5243.1 5278.1 5323.5 5326.6 5316.4 5319.9 5321.1 5327.5 5345.1 5177.3 5216.1 5244.8 5265.3 5287.7 4853.8 5743.4 5803.3 5849.1 5890.4 6315.0 6316.9 6326.7 6328.4 6340.7 6163.0 6219.0 6227.8 6279.7 6288.1 5811.9 5874.6 5882.4 5900.9 5945.6 6395.4 6402.1 6404.1 6406.8 6420.7 6082.3 6122.0 6246.9 6251.1 6259.0 6201.4 6278.2 6300.4 6305.9 6319.7 6280.9 6289.7 6291.3 6311.8 6314.2 6155.2 6216.9 6303.3 6305.1 6331.7 4932.8 4964.4 5011.9 5015.5 5017.5 4438.0 4482.2 4515.3 4547.5 4551.6 5816.2 5845.5 5874.2 5884.0 5903.3 6213.7 6266.6 6285.9 6287.8 6292.7 4792.3 5659.6 5694.9 5828.0 5840.8 5477.6 5490.8 5497.0 5531.3 5544.9 6378.5 6414.7 6452.7 6464.8 6470.0 6187.6 6202.0 6272.7 6283.7 6300.1 6415.5 6452.7 6464.7 6498.6 6504.1 6276.9 6278.6 6288.2 6298.0 6301.2 6174.6 6184.9 6276.6 6282.4 6292.7 6125.8 6162.4 6221.5 6232.9 6234.3 6161.8 6190.7 6206.0 6209.0 6223.4 5723.5 5952.9 6093.2 6093.5 6102.9 6133.5 6191.6 6198.0 6200.0 6202.3 5175.4 5845.6 6001.0 6066.0 6084.9 17:50:53 INFO - PROCESS | 1925 | 17:50:53 INFO - PROCESS | 1925 | [#1] ext.html Cycles:180 Average:68159.58 Median:34433.57 stddev:144314.47 (419.1%) stddev-sans-first:144629.78 17:50:53 INFO - PROCESS | 1925 | Values: 453.5 475.8 477.1 485.0 513.6 31155.0 33399.0 40097.2 40527.4 43042.9 41784.8 42197.8 42201.6 42305.4 42364.9 8818.7 9516.9 9941.9 9944.0 9946.4 8755.7 11842.6 12130.8 12138.3 12367.0 12105.5 12159.3 12188.8 12197.5 12237.4 10317.8 12077.8 12180.9 12182.8 12191.9 11974.9 12053.5 12087.5 12087.9 12089.3 39729.0 39958.4 40015.8 40026.4 40095.0 3338.2 3379.6 3389.0 3391.7 3508.6 31895.4 32236.8 33114.5 33531.7 34876.6 3267.8 3282.4 3312.4 3327.9 3422.6 489622.0 490451.0 497580.0 498915.0 506901.0 65303.0 66618.0 66626.0 66667.0 66735.0 35517.1 36240.8 70064.3 70655.0 70665.6 16619.6 17125.8 17143.7 17154.6 17174.7 11756.7 12068.0 12733.8 12952.2 13086.8 19968.9 20830.0 20844.2 20892.7 20893.8 7179.6 8545.6 8592.5 8635.5 8661.4 867.0 977.3 1048.5 1074.0 1076.2 29357.6 30187.7 30255.4 30660.0 31109.4 1025.9 1073.6 1100.2 1113.4 1120.8 15053.8 15648.9 15660.0 15806.2 15880.6 582074.0 587659.0 588076.0 589239.0 590729.0 57031.1 57577.0 57664.1 57679.4 57723.7 1116.7 1152.3 1234.1 1390.0 1415.2 30306.7 30968.5 31342.7 32031.5 32059.0 48327.0 48867.3 48877.0 48883.5 48950.0 534077.0 536034.0 538067.0 538557.0 538993.0 27100.7 27606.4 27923.0 28144.3 28499.6 46704.5 46767.5 47151.8 47374.8 47908.1 32275.8 38306.0 38527.0 38784.6 39525.8 32078.4 37725.8 38904.2 39837.8 40311.0 37550.2 37862.0 38460.2 38648.6 38707.8 32646.0 34377.5 34489.7 34630.5 35008.8 20144.6 23742.8 24785.4 25062.5 25416.2 17:50:53 INFO - PROCESS | 1925 | 17:50:53 INFO - PROCESS | 1925 | [#2] jquery.html Cycles:180 Average:35867.78 Median:22681.38 stddev:101919.79 (449.4%) stddev-sans-first:102170.77 17:50:53 INFO - PROCESS | 1925 | Values: 332.9 350.3 354.6 371.3 402.5 15642.8 17330.3 20254.0 20800.2 20812.7 20774.0 20925.0 20977.2 21181.6 21784.0 5860.4 5896.5 6059.5 6161.4 6174.6 4169.9 6044.5 6143.4 6155.3 6155.8 6478.4 6774.3 6794.4 6845.2 6892.7 6554.7 6754.1 6806.5 6822.0 6825.0 5733.5 5969.3 5972.4 5976.5 5982.8 26403.0 26644.3 26660.2 26956.3 27033.5 2205.9 2213.5 2283.7 2444.8 2494.6 15192.6 15889.8 16003.8 16273.7 16669.8 1902.5 1961.3 1977.8 1991.9 1997.4 117081.8 117477.4 117843.0 118354.4 120805.4 10418.7 10683.7 10684.0 10975.6 11186.4 13943.0 13968.9 13969.3 13977.5 13991.6 8401.0 8684.7 8692.2 8861.9 8864.5 8711.0 8731.7 8744.1 8904.0 8979.2 6656.6 6778.6 6797.6 6797.8 6804.9 1804.3 1836.9 1842.6 1842.7 1843.4 933.0 945.6 998.4 1002.8 1092.5 19454.2 22564.9 22797.9 23018.8 23037.4 918.5 990.3 1007.9 1011.6 1025.8 11570.4 11864.1 12248.7 12253.0 12297.4 596981.0 603792.0 603842.0 625501.0 628031.0 121044.0 124257.8 126416.0 128320.1 129628.0 987.5 1009.1 1018.9 1029.9 1035.8 19185.3 19315.7 20053.2 20272.5 20276.8 41672.8 55210.2 55988.7 57138.9 57555.0 80460.0 81440.2 83386.0 83729.6 85274.6 7770.6 7804.3 7926.0 8101.4 8154.8 8838.2 12676.3 12719.2 12864.4 13139.0 10943.5 11170.8 11227.5 11239.3 11367.8 8997.8 10895.6 11163.9 11360.2 11417.4 11144.7 11426.5 11451.5 11467.6 11471.4 7685.1 7918.0 7936.4 8180.6 8328.3 10207.6 11055.8 11093.3 11195.8 11535.9 17:50:53 INFO - PROCESS | 1925 | 17:50:53 INFO - PROCESS | 1925 | [#3] mootools.html Cycles:180 Average:5562.16 Median:5030.95 stddev:3115.88 (61.9%) stddev-sans-first:3108.08 17:50:53 INFO - PROCESS | 1925 | Values: 1290.2 1387.7 1500.8 1669.2 1872.7 7609.7 8337.2 8568.4 8571.2 8700.2 8463.8 8547.2 8554.4 8563.9 8564.7 2341.6 2375.8 2378.6 2378.6 2382.9 1932.9 2364.9 2387.7 2390.4 2394.1 2336.8 2367.0 2373.3 2381.3 2383.7 2320.7 2350.1 2354.0 2362.2 2364.2 2344.3 2347.2 2349.6 2352.5 2355.7 7357.7 8437.8 8633.3 8639.5 8651.7 5099.4 5301.1 5302.4 5356.1 5363.2 3067.5 3585.4 3673.7 3687.9 3688.3 1091.9 1092.7 1097.5 1103.3 1113.9 11259.9 11275.4 11317.9 11358.5 11369.9 4346.4 4387.2 4567.7 4570.8 4570.9 8901.3 8906.3 8907.5 8914.2 8919.6 5163.4 5172.4 5176.5 5185.3 5185.7 4331.1 4495.4 4649.8 4772.5 4841.2 3214.5 3533.3 3601.9 3677.6 3680.5 1162.2 1169.9 1171.9 1176.3 1177.5 768.1 792.4 829.4 875.3 893.7 5220.8 5222.6 5655.4 5916.3 6029.3 442.5 456.6 458.1 992.7 1020.2 3869.3 3997.6 4035.2 4058.5 4247.3 10488.5 10544.9 10693.5 10697.8 10717.8 10632.6 10635.4 10660.9 10683.5 10685.1 7801.7 7942.3 7942.6 8139.2 8155.5 5824.1 5875.7 6019.8 6054.6 6055.8 9637.5 9750.2 9789.9 9796.6 9800.8 9632.9 9679.8 9918.9 9997.7 10178.5 6837.4 7020.2 7026.7 7394.7 7630.4 7705.1 7781.5 7905.8 8164.9 8185.5 7321.8 7397.4 7440.5 7448.3 7581.8 7047.8 7235.5 7370.8 7380.5 7403.5 6859.9 6881.0 6929.8 6931.2 6933.2 4542.8 4926.5 4977.3 5007.2 5109.0 4036.7 4971.4 5054.7 5099.1 5106.1 17:50:53 INFO - PROCESS | 1925 | 17:50:53 INFO - PROCESS | 1925 | [#4] prototype.html Cycles:180 Average:4494.48 Median:5077.47 stddev:1390.06 (27.4%) stddev-sans-first:1357.69 17:50:53 INFO - PROCESS | 1925 | Values: 291.2 296.9 303.6 309.7 311.8 4322.4 5303.8 5358.5 5375.0 5380.5 4741.1 4778.8 5041.7 5299.0 5309.1 4031.6 4080.3 4087.8 4101.4 4118.6 3398.8 4083.1 4121.8 4131.0 4154.6 3695.0 3725.7 3779.6 3824.6 4110.9 3224.7 3857.1 3899.4 4047.3 4098.7 3536.3 3556.3 3556.7 3563.4 3569.1 4850.5 5199.5 5239.7 5247.5 5251.9 3803.6 4491.2 4527.7 4543.3 4579.0 4232.4 5475.0 5493.2 5545.6 5555.7 3029.0 3419.9 3720.5 3786.0 3800.5 5103.6 5800.5 5816.1 5823.2 5858.7 3795.3 3795.9 3803.3 3811.7 3834.0 3881.0 3881.5 3882.8 3883.8 3890.8 4243.2 4497.4 4552.8 4554.5 4568.4 4919.0 5557.5 5571.2 5595.1 5613.4 1308.7 1339.7 1341.5 1342.4 1345.0 803.3 822.6 840.6 843.2 844.5 4973.9 5144.0 5208.0 5273.7 5276.4 5044.0 5438.9 5692.4 5715.5 5716.0 4617.4 4990.8 5051.3 5189.5 5226.0 2647.3 2938.4 2959.3 2993.5 3027.2 4931.9 5242.8 5921.6 5944.8 5974.9 4423.0 4940.7 5859.5 5884.0 5887.2 4541.3 5734.0 5878.9 5904.9 5963.8 5409.8 5438.8 5621.8 5666.8 5759.7 5203.6 5353.9 5635.3 5640.7 5660.0 5680.6 5918.8 5951.6 5971.4 5986.0 3319.2 3393.4 3834.4 3865.8 3896.8 5029.1 5614.6 5732.7 5749.3 5749.7 5155.6 5652.3 5672.6 5679.0 5694.0 4374.3 4621.5 4866.7 5106.7 5316.5 4681.0 5210.2 5417.8 5459.0 5462.4 5403.7 5555.7 5606.9 5614.9 5665.8 5495.9 5505.6 5529.5 5534.8 5548.7 17:50:53 INFO - PROCESS | 1925 | 17:50:53 INFO - PROCESS | 1925 | [#5] yui.html Cycles:180 Average:24835.56 Median:4390.24 stddev:40156.58 (914.7%) stddev-sans-first:40228.40 17:50:53 INFO - PROCESS | 1925 | Values: 717.7 728.4 734.9 751.3 812.6 15467.3 16630.3 16655.3 16689.8 16726.3 14932.9 15039.9 15053.7 15054.4 15078.0 662.7 686.2 827.0 828.3 854.7 838.8 860.5 862.4 864.5 866.7 921.1 923.2 923.7 924.9 926.7 705.6 734.8 737.6 921.6 923.1 539.2 541.5 655.3 678.4 683.4 15062.2 15104.2 15104.5 15130.8 15143.8 14033.2 14353.6 14442.1 14447.7 14651.4 13072.3 13314.2 13437.9 13460.5 13670.4 4568.3 4588.2 4588.8 4594.0 4869.4 124060.0 125496.4 129481.0 129722.7 130667.0 9585.0 11422.9 11475.7 11584.6 12007.5 23471.4 23482.5 23518.5 23530.0 23546.4 7838.8 7844.1 7879.3 7882.3 8034.0 4360.5 4420.0 4518.9 4587.8 4685.1 3504.8 3590.6 3612.1 3624.1 3624.7 641.2 642.1 655.8 667.2 668.1 579.9 605.2 631.6 635.4 637.6 8728.7 9019.5 9120.7 9212.5 9266.4 490.4 529.2 541.8 551.0 599.5 4081.8 4437.0 4443.5 4455.9 4511.8 87521.1 144285.0 153420.0 159089.2 161485.2 106320.0 127822.4 127828.4 129727.0 132358.0 83017.0 110140.4 112396.4 113953.0 114544.1 8470.9 8579.0 8604.2 8738.2 8984.5 46621.8 60453.0 63901.0 64444.7 68299.3 77346.9 96279.0 97583.5 97832.0 100962.0 11484.3 11696.1 11737.7 11874.3 12055.0 12674.0 12702.4 12922.6 13154.6 13438.7 11800.4 12045.2 12065.8 12363.9 12450.9 11640.5 11942.6 12059.0 12245.1 12316.8 10228.2 11920.3 11945.2 12245.3 12318.3 8556.8 9776.9 10148.4 10148.7 10601.3 8593.8 9212.7 10336.0 10337.6 10337.9 17:50:53 INFO - PROCESS | 1925 | -------- Summary: end -------- 17:50:53 INFO - PROCESS | 1925 | 17:50:53 INFO - TEST-INFO | 1925: exit 0 17:50:53 INFO - TEST-OK | dromaeo_css | took 1118726ms 17:50:53 INFO - TEST-START | kraken 17:50:53 INFO - operating with platform_type : mac_ 17:50:53 INFO - Initialising browser for kraken test... 17:50:53 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmp8pDnvD/profile http://localhost:49766/getInfo.html) 17:50:56 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 17:50:56 INFO - PROCESS | 1930 | colorDepth:24 17:50:56 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 17:50:56 INFO - PROCESS | 1930 | __metrics 17:50:56 INFO - PROCESS | 1930 | 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" 17:50:56 INFO - PROCESS | 1930 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:50:56 INFO - TEST-INFO | 1930: exit 0 17:50:56 INFO - Browser initialized. 17:50:56 INFO - Running cycle 1/1 for kraken test... 17:50:56 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmp8pDnvD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:51:08 INFO - PROCESS | 1931 | RSS: Main: 228458496 17:51:08 INFO - PROCESS | 1931 | 17:51:11 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49766/tests/kraken/driver.html?testName=audio-beat-detection) 17:51:12 INFO - PROCESS | 1931 | RSS: Main: 284950528 17:51:12 INFO - PROCESS | 1931 | 17:51:14 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49766/tests/kraken/driver.html?testName=audio-dft) 17:51:15 INFO - PROCESS | 1931 | RSS: Main: 293797888 17:51:15 INFO - PROCESS | 1931 | 17:51:19 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49766/tests/kraken/driver.html?testName=audio-fft) 17:51:19 INFO - PROCESS | 1931 | RSS: Main: 389910528 17:51:19 INFO - PROCESS | 1931 | 17:51:21 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49766/tests/kraken/driver.html?testName=audio-oscillator) 17:51:22 INFO - PROCESS | 1931 | RSS: Main: 288256000 17:51:22 INFO - PROCESS | 1931 | 17:51:24 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49766/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:51:25 INFO - PROCESS | 1931 | RSS: Main: 293507072 17:51:25 INFO - PROCESS | 1931 | 17:51:28 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49766/tests/kraken/driver.html?testName=imaging-darkroom) 17:51:28 INFO - PROCESS | 1931 | RSS: Main: 332509184 17:51:28 INFO - PROCESS | 1931 | 17:51:31 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49766/tests/kraken/driver.html?testName=imaging-desaturate) 17:51:32 INFO - PROCESS | 1931 | RSS: Main: 332066816 17:51:32 INFO - PROCESS | 1931 | 17:51:35 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49766/tests/kraken/driver.html?testName=json-parse-financial) 17:51:35 INFO - PROCESS | 1931 | RSS: Main: 331440128 17:51:35 INFO - PROCESS | 1931 | 17:51:37 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49766/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:51:37 INFO - PROCESS | 1931 | RSS: Main: 300445696 17:51:37 INFO - PROCESS | 1931 | 17:51:39 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49766/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:51:40 INFO - PROCESS | 1931 | RSS: Main: 307617792 17:51:40 INFO - PROCESS | 1931 | 17:51:42 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49766/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:51:43 INFO - PROCESS | 1931 | RSS: Main: 307798016 17:51:43 INFO - PROCESS | 1931 | 17:51:46 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49766/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:51:46 INFO - PROCESS | 1931 | RSS: Main: 314998784 17:51:46 INFO - PROCESS | 1931 | 17:51:49 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49766/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:51:50 INFO - PROCESS | 1931 | RSS: Main: 317321216 17:51:50 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:49766/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49766/tests/kraken/driver.html?testName=ai-astar) 17:51:52 INFO - PROCESS | 1931 | RSS: Main: 360620032 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | __start_tp_report 17:51:52 INFO - PROCESS | 1931 | _x_x_mozilla_page_load 17:51:52 INFO - PROCESS | 1931 | _x_x_mozilla_page_load_details 17:51:52 INFO - PROCESS | 1931 | |i|pagename|runs| 17:51:52 INFO - PROCESS | 1931 | |0;ai-astar;103;112;112;114;107;113;113;113;113;112 17:51:52 INFO - PROCESS | 1931 | |1;audio-beat-detection;151;111;129;197;109;109;127;103;159;111 17:51:52 INFO - PROCESS | 1931 | |2;audio-dft;185;184;182;192;185;179;176;180;183;183 17:51:52 INFO - PROCESS | 1931 | |3;audio-fft;79;58;65;87;57;57;63;66;87;58 17:51:52 INFO - PROCESS | 1931 | |4;audio-oscillator;81;93;96;71;71;69;97;95;99;138 17:51:52 INFO - PROCESS | 1931 | |5;imaging-gaussian-blur;88;116;135;123;87;86;88;85;89;85 17:51:52 INFO - PROCESS | 1931 | |6;imaging-darkroom;93;110;150;100;93;93;93;94;94;93 17:51:52 INFO - PROCESS | 1931 | |7;imaging-desaturate;83;83;84;83;84;83;84;83;83;83 17:51:52 INFO - PROCESS | 1931 | |8;json-parse-financial;55;51;54;53;53;55;53;53;53;53 17:51:52 INFO - PROCESS | 1931 | |9;json-stringify-tinderbox;64;65;75;75;72;72;68;69;70;71 17:51:52 INFO - PROCESS | 1931 | |10;stanford-crypto-aes;87;88;85;84;86;87;89;84;87;87 17:51:52 INFO - PROCESS | 1931 | |11;stanford-crypto-ccm;142;138;134;139;139;136;139;136;225;149 17:51:52 INFO - PROCESS | 1931 | |12;stanford-crypto-pbkdf2;169;172;233;213;171;170;168;170;166;168 17:51:52 INFO - PROCESS | 1931 | |13;stanford-crypto-sha256-iterative;68;67;67;68;68;68;68;66;66;66 17:51:52 INFO - PROCESS | 1931 | __end_tp_report 17:51:52 INFO - PROCESS | 1931 | __start_cc_report 17:51:52 INFO - PROCESS | 1931 | _x_x_mozilla_cycle_collect,2070 17:51:52 INFO - PROCESS | 1931 | __end_cc_report 17:51:52 INFO - PROCESS | 1931 | __startTimestamp1471567912502__endTimestamp 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | ------- Summary: start ------- 17:51:52 INFO - PROCESS | 1931 | Number of tests: 14 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#0] ai-astar Cycles:10 Average:111.20 Median:113.00 stddev:3.46 (3.1%) stddev-sans-first:2.03 17:51:52 INFO - PROCESS | 1931 | Values: 103.0 112.0 112.0 114.0 107.0 113.0 113.0 113.0 113.0 112.0 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#1] audio-beat-detection Cycles:10 Average:130.60 Median:128.00 stddev:30.02 (23.5%) stddev-sans-first:30.92 17:51:52 INFO - PROCESS | 1931 | Values: 151.0 111.0 129.0 197.0 109.0 109.0 127.0 103.0 159.0 111.0 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.50 stddev:4.28 (2.3%) stddev-sans-first:4.47 17:51:52 INFO - PROCESS | 1931 | Values: 185.0 184.0 182.0 192.0 185.0 179.0 176.0 180.0 183.0 183.0 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#3] audio-fft Cycles:10 Average:67.70 Median:65.50 stddev:12.12 (18.5%) stddev-sans-first:12.15 17:51:52 INFO - PROCESS | 1931 | Values: 79.0 58.0 65.0 87.0 57.0 57.0 63.0 66.0 87.0 58.0 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:94.00 stddev:20.38 (21.7%) stddev-sans-first:21.29 17:51:52 INFO - PROCESS | 1931 | Values: 81.0 93.0 96.0 71.0 71.0 69.0 97.0 95.0 99.0 138.0 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#5] imaging-gaussian-blur Cycles:10 Average:98.20 Median:86.50 stddev:18.86 (21.8%) stddev-sans-first:19.64 17:51:52 INFO - PROCESS | 1931 | Values: 88.0 116.0 135.0 123.0 87.0 86.0 88.0 85.0 89.0 85.0 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:93.00 stddev:17.95 (19.3%) stddev-sans-first:18.79 17:51:52 INFO - PROCESS | 1931 | Values: 93.0 110.0 150.0 100.0 93.0 93.0 93.0 94.0 94.0 93.0 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#7] imaging-desaturate Cycles:10 Average:83.30 Median:83.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 17:51:52 INFO - PROCESS | 1931 | Values: 83.0 83.0 84.0 83.0 84.0 83.0 84.0 83.0 83.0 83.0 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:1.16 (2.2%) stddev-sans-first:1.05 17:51:52 INFO - PROCESS | 1931 | Values: 55.0 51.0 54.0 53.0 53.0 55.0 53.0 53.0 53.0 53.0 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:71.50 stddev:3.73 (5.2%) stddev-sans-first:3.23 17:51:52 INFO - PROCESS | 1931 | Values: 64.0 65.0 75.0 75.0 72.0 72.0 68.0 69.0 70.0 71.0 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:87.00 stddev:1.65 (1.9%) stddev-sans-first:1.73 17:51:52 INFO - PROCESS | 1931 | Values: 87.0 88.0 85.0 84.0 86.0 87.0 89.0 84.0 87.0 87.0 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:139.00 stddev:27.47 (19.8%) stddev-sans-first:29.06 17:51:52 INFO - PROCESS | 1931 | Values: 142.0 138.0 134.0 139.0 139.0 136.0 139.0 136.0 225.0 149.0 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.00 Median:170.50 stddev:23.21 (13.6%) stddev-sans-first:24.27 17:51:52 INFO - PROCESS | 1931 | Values: 169.0 172.0 233.0 213.0 171.0 170.0 168.0 170.0 166.0 168.0 17:51:52 INFO - PROCESS | 1931 | 17:51:52 INFO - PROCESS | 1931 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:68.00 stddev:0.92 (1.4%) stddev-sans-first:0.93 17:51:52 INFO - PROCESS | 1931 | Values: 68.0 67.0 67.0 68.0 68.0 68.0 68.0 66.0 66.0 66.0 17:51:52 INFO - PROCESS | 1931 | -------- Summary: end -------- 17:51:52 INFO - PROCESS | 1931 | 17:51:53 INFO - TEST-INFO | 1931: exit 0 17:51:53 INFO - TEST-OK | kraken | took 59743ms 17:51:53 INFO - SUITE-END | took 1178s 17:51:53 INFO - Completed test suite (00:19:38) 17:51:53 INFO - javascript benchmark 17:51:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3153.7634677399196, 3361.2773253750456, 3380.712639425649, 3391.609964854041, 3405.233822389961, 5677.233573467581, 5732.541396688265, 5812.070068788995, 5842.64944103353, 5861.472467477926, 5795.15970184323, 5807.0, 5861.501772349345, 5901.0, 5923.111533270012, 5174.172132458807, 5231.5814734821315, 5285.101532739424, 5298.046351656701, 5395.7032363220005, 5364.356277246736, 5416.593755468336, 5428.511433970962, 5430.212619170218, 5459.099248623972, 5201.063808514466, 5243.082460569385, 5278.129108697099, 5323.520883120537, 5326.573874090082, 5316.388615309212, 5319.893602127974, 5321.122014867581, 5327.520523152896, 5345.118055520835, 5177.275181474596, 5216.113260745645, 5244.81643142492, 5265.262863199156, 5287.682739035632, 4853.805847766121, 5743.425657434265, 5803.303603567557, 5849.093390524476, 5890.352061273256, 6314.968425157891, 6316.873662526769, 6326.68366581669, 6328.430441260309, 6340.746370145189, 6163.0, 6219.036049412348, 6227.813165605039, 6279.654618995954, 6288.088227207029, 5811.88376232473, 5874.5887787854435, 5882.382349853289, 5900.940990590125, 5945.613535120204, 6395.360463953567, 6402.103705481236, 6404.135441715309, 6406.839829004219, 6420.7431702731365, 6082.300535438393, 6121.959266924633, 6246.875062498815, 6251.0623406488485, 6258.968705156445, 6201.441870231611, 6278.183836101278, 6300.401461861115, 6305.905411418832, 6319.747210111544, 6280.937190628127, 6289.654069026156, 6291.307956124822, 6311.779087731999, 6314.242290925118, 6155.169052177988, 6216.937830621727, 6303.3066362700065, 6305.14880491137, 6331.746730130743, 4932.778024988812, 4964.429090654548, 5011.924821127686, 5015.523525265094, 5017.498250175026, 4437.955620443754, 4482.170798402306, 4515.345274935116, 4547.499775024745, 4551.590356867898, 5816.185733997244, 5845.503132233723, 5874.236349274567, 5883.970580147157, 5903.321118071391, 6213.689315534205, 6266.592671476339, 6285.937140628626, 6287.779927702509, 6292.685365731695, 4792.329073929653, 5659.6321239119325, 5694.943050569441, 5827.970860145757, 5840.766369345179, 5477.643953143033, 5490.835274941758, 5496.972515137399, 5531.25328080712, 5544.889102218014, 6378.45783108441, 6414.743410263631, 6452.677366131768, 6464.806055818333, 6470.0, 6187.566870319034, 6201.96899015502, 6272.655003974643, 6283.71723272463, 6300.117983482224, 6415.518836087405, 6452.7418903245225, 6464.676766161767, 6498.577592456664, 6504.089427480062, 6276.905846412216, 6278.623282603058, 6288.24541055058, 6297.96851015742, 6301.212348456443, 6174.567780255339, 6184.938150618436, 6276.623402595859, 6282.403171698681, 6292.653904035139, 6125.815438131279, 6162.414570615784, 6221.47117495018, 6232.937670623236, 6234.251889773256, 6161.84595385119, 6190.690465476618, 6205.968970155121, 6209.0, 6223.37766223374, 5723.513501352431, 5952.940470595238, 6093.238345206849, 6093.512518998381, 6102.908456373069, 6133.539984501091, 6191.628502289877, 6197.96901015492, 6199.969000154971, 6202.255729312331, 5175.404828444777, 5845.590808643354, 6000.97983342824, 6066.029435290332, 6084.90872636902], "value": 5769.714245232545, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.4581175495283, 475.8405934012106, 477.08399872245286, 484.9684770489916, 513.6173550704724, 31155.0, 33398.998030059105, 40097.195626196815, 40527.39208911861, 43042.92392690176, 41784.791076044654, 42197.789011054905, 42201.57798422015, 42305.365419518734, 42364.940876478024, 8818.73543793684, 9516.90483095169, 9941.90058099417, 9943.95028024859, 9946.403215807039, 8755.693550725726, 11842.644720658394, 12130.818037729441, 12138.271703697763, 12367.0, 12105.515779368814, 12159.331236781973, 12188.81716774249, 12197.45111469986, 12237.388130593436, 10317.79364412715, 12077.818832717472, 12180.939095304555, 12182.756344873142, 12191.939040304742, 11974.940125299405, 12053.517859285706, 12087.456064477119, 12087.93956030223, 12089.274643521418, 39729.0, 39958.40062399067, 40015.7999210005, 40026.399604005965, 40095.0, 3338.2488939988307, 3379.560657114584, 3388.9830550847087, 3391.7286617070577, 3508.596511401196, 31895.36209275825, 32236.83881580577, 33114.50328245078, 33531.6646833531, 34876.604935802534, 3267.8202698851555, 3282.441984862579, 3312.38720836646, 3327.88352407667, 3422.6235114137426, 489622.0, 490451.0, 497580.0, 498915.0, 506900.99999999633, 65303.020454693215, 66618.0, 66626.0006099909, 66666.99999500011, 66734.99897501542, 35517.112072198404, 36240.81879590638, 70064.29935700679, 70655.0, 70665.64667176631, 16619.584510387336, 17125.828741712674, 17143.74284385735, 17154.571135721457, 17174.656506869793, 11756.706082347839, 12068.0, 12733.80899286493, 12952.158109722837, 13086.803697944539, 19968.90015549913, 20830.0, 20844.166233350497, 20892.686609700868, 20893.791062089487, 7179.605121718356, 8545.563912764626, 8592.484450932963, 8635.481871087755, 8661.437006594551, 866.9523176225242, 977.2621670638622, 1048.4548035021899, 1073.978520429587, 1076.1928553584812, 29357.559636605467, 30187.698123018927, 30255.394892102035, 30660.0, 31109.377812443625, 1025.8820235672845, 1073.5598404654054, 1100.1913593508764, 1113.3932007056173, 1120.7702421003676, 15053.774193387108, 15648.92175539115, 15660.0, 15806.20968951548, 15880.602984925239, 582074.0, 587659.0, 588076.0, 589239.0, 590729.0, 57031.14453283204, 57577.0, 57664.13503797446, 57679.423205768246, 57723.71138144367, 1116.7375666718315, 1152.291340825403, 1234.0682784497646, 1389.9930500347434, 1415.2287003583072, 30306.696933030387, 30968.535471967487, 31342.686573134888, 32031.519527206645, 32059.0, 48327.0, 48867.26699099445, 48877.0, 48883.511164887896, 48950.0, 534077.0, 536034.0, 538067.0, 538557.0, 538993.0, 27100.72899270982, 27606.447871042867, 27923.0, 28144.29639259035, 28499.572506412835, 46704.53295467138, 46767.532324676315, 47151.76424117857, 47374.763126184145, 47908.05333226593, 32275.838620806746, 38306.0423489415, 38527.03682407962, 38784.6121538781, 39525.80237098796, 32078.358432830744, 37725.81137094297, 38904.2219155621, 39837.80081099576, 40310.992225194605, 37550.24899502049, 37862.0, 38460.230795384494, 38648.613513864504, 38707.80646096864, 32646.020619381456, 34377.484337735455, 34489.65510344865, 34630.480542791374, 35008.824955875054, 20144.597108057464, 23742.762572374748, 24785.380365491008, 25062.498750024533, 25416.237512874643], "value": 20470.064388927447, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.9334133173366, 350.29590523048665, 354.61878480633317, 371.32604323153475, 402.45266437644796, 15642.765358519617, 17330.306787728492, 20253.987300634984, 20800.167993280287, 20812.687809682848, 20773.96130193492, 20924.953752312398, 20977.216936560373, 21181.576368472623, 21784.0, 5860.384659610743, 5896.5282777377815, 6059.515238780887, 6161.445469907708, 6174.598651087687, 4169.874903752892, 6044.486218671418, 6143.385661433843, 6155.292141403751, 6155.8153255402185, 6478.445173158431, 6774.322567743211, 6794.422474089708, 6845.24702282751, 6892.655367231619, 6554.737810487575, 6754.121964144679, 6806.489513286499, 6822.0, 6825.0, 5733.541316694655, 5969.283685957713, 5972.432618901197, 5976.5218782497295, 5982.7906023289415, 26403.0, 26644.333891652863, 26660.200193994213, 26956.326091847666, 27033.459330813275, 2205.900734466953, 2213.4908970936613, 2283.7373702024147, 2444.804415646744, 2494.5634513959985, 15192.620184495365, 15889.841101589067, 16003.759943600855, 16273.737082956957, 16669.83330166695, 1902.5053486093577, 1961.3037371732971, 1977.8022197780251, 1991.930282440122, 1997.3608445297511, 117081.8291817088, 117477.41261293639, 117843.0, 118354.40822795831, 120805.39597301958, 10418.739531511621, 10683.73290667724, 10684.0, 10975.615853445091, 11186.44067796607, 13943.0, 13968.930155349159, 13969.301534923214, 13977.510787122605, 13991.580252592235, 8401.0, 8684.6526138955, 8692.174243446863, 8861.911380886237, 8864.468131912106, 8711.0, 8731.738047858575, 8744.125587441205, 8903.955480222558, 8979.191872731486, 6656.567323123982, 6778.627175505296, 6797.592144471348, 6797.830054248584, 6804.897926531106, 1804.2873065139197, 1836.9265229390676, 1842.5577861313457, 1842.6591080650123, 1843.4285371534868, 932.9860052099224, 945.5603144537823, 998.3760149906309, 1002.7793885345212, 1092.4865313302735, 19454.221831126597, 22564.871756412114, 22797.88601056984, 23018.769812301663, 23037.424064398525, 918.4718786697675, 990.277097718655, 1007.9143272821746, 1011.6307547745167, 1025.779457416659, 11570.42147892602, 11864.08485199882, 12248.693782655326, 12252.958498527725, 12297.385130743427, 596981.0, 603792.0, 603842.0, 625501.0, 628031.0, 121044.0, 124257.75742242462, 126416.0, 128320.07519887209, 129628.0, 987.481572174614, 1009.1271050541376, 1018.923580731448, 1029.9330543514648, 1035.8290882004535, 19185.32851350196, 19315.710264346326, 20053.197063681822, 20272.493187669836, 20276.79723202749, 41672.79163604163, 55210.171847421516, 55988.720056399456, 57138.88350792604, 57555.0, 80460.0, 81440.18559814326, 83386.0, 83729.58135209285, 85274.58901644112, 7770.611469426619, 7804.292509684657, 7925.960370198112, 8101.392395570238, 8154.75535733929, 8838.196076098682, 12676.302803345905, 12719.236845789283, 12864.421101050288, 13139.0, 10943.507542160463, 11170.776584468427, 11227.494762735532, 11239.325640461599, 11367.772644547229, 8997.820043599222, 10895.564177432814, 11163.944180279046, 11360.204785664924, 11417.429128543707, 11144.665660030212, 11426.486173041918, 11451.541938322374, 11467.598634047768, 11471.4264286787, 7685.11621163551, 7917.960410197912, 7936.365090792839, 8180.550069746221, 8328.250457458858, 10207.591696332063, 11055.778884422427, 11093.33439993603, 11195.832062518906, 11535.884641153816], "value": 9987.0808991353, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1290.1871820752924, 1387.7432674955135, 1500.8349081601025, 1669.157075676783, 1872.728454374115, 7609.695612175513, 8337.24964753173, 8568.400211985156, 8571.185737354941, 8700.173483519071, 8463.788405289872, 8547.188017138378, 8554.44396114252, 8563.91436085639, 8564.74305770828, 2341.625339945605, 2375.798057165143, 2378.5718570657273, 2378.63131214662, 2382.9046838126455, 1932.9033548322527, 2364.9239595983786, 2387.7373488916205, 2390.414348484615, 2394.0782798622567, 2336.7780060894274, 2366.9644955325684, 2373.32360277321, 2381.30942026812, 2383.7497062808416, 2320.6867072945106, 2350.083467447697, 2353.95292094158, 2362.208660098869, 2364.207990323244, 2344.296710986714, 2347.1550241912896, 2349.565330413879, 2352.45893444507, 2355.658429527726, 7357.668904899292, 8437.83124337516, 8633.309335253165, 8639.481631102153, 8651.740447786577, 5099.36257967754, 5301.072312345363, 5302.390225124083, 5356.11624082026, 5363.222332761767, 3067.4631939410738, 3585.4084076127406, 3673.742838001341, 3687.907802304937, 3688.3360995020885, 1091.879893211746, 1092.748667806409, 1097.462243500687, 1103.3104309806372, 1113.8663360396804, 11259.943700281528, 11275.379854108021, 11317.943410282978, 11358.545658173745, 11369.886301136965, 4346.391505189276, 4387.232234358974, 4567.725936443758, 4570.840020599263, 4570.9314360284625, 8901.287896968228, 8906.332025098143, 8907.465552066897, 8914.242289405343, 8919.643214271486, 5163.4062082860955, 5172.405173405032, 5176.456472070378, 5185.274061631375, 5185.740712964337, 4331.0904710011255, 4495.43807024122, 4649.767511624405, 4772.475027746945, 4841.15279826029, 3214.5499630051813, 3533.258015816656, 3601.94597081044, 3677.5770786359376, 3680.5399325084363, 1162.1980833225048, 1169.8947094761343, 1171.94453784837, 1176.264834478451, 1177.5113327968797, 768.0667988394127, 792.397777688949, 829.402829962426, 875.2821893936338, 893.6693423433371, 5220.799216180224, 5222.634415590948, 5655.406182350876, 5916.26046744157, 6029.276486821609, 442.4579889635235, 456.62100456620925, 458.0998338265258, 992.7071513903416, 1020.1940467031111, 3869.3228684980018, 3997.600239975979, 4035.21313343901, 4058.492688413948, 4247.299195632748, 10488.528016239288, 10544.894551054545, 10693.465326733633, 10697.786044279226, 10717.83923241152, 10632.574697012033, 10635.361878287327, 10660.893391066145, 10683.465826708634, 10685.091767199876, 7801.6879324827505, 7942.285194332538, 7942.602869856484, 8139.226773456512, 8155.469894456842, 5824.126381042877, 5875.706214689334, 6019.819405417845, 6054.576179667381, 6055.788047418319, 9637.518124093627, 9750.21998240125, 9789.9021009789, 9796.559154838193, 9800.803983920425, 9632.855507167258, 9679.758006049906, 9918.851217231891, 9997.700068997941, 10178.541965611417, 6837.418819400406, 7020.227774944854, 7026.683799229149, 7394.667239974106, 7630.351420129351, 7705.075390953121, 7781.5331080135375, 7905.762827115196, 8164.918350816416, 8185.508869467612, 7321.78034658961, 7397.445191610543, 7440.516366436164, 7448.292412220831, 7581.772546823604, 7047.753328633473, 7235.529690570306, 7370.77887663371, 7380.520266182681, 7403.481756277009, 6859.897101543381, 6880.965595171992, 6929.792106236821, 6931.202911665021, 6933.237343892269, 4542.818287268605, 4926.458089610212, 4977.278294647328, 5007.223880298559, 5109.029284435946, 4036.7174297798874, 4971.353724015999, 5054.671446355975, 5099.056674515226, 5106.10643137442], "value": 4441.446127793344, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.2267928649434, 296.90499040307105, 303.6170244536137, 309.6872159119289, 311.8269360504922, 4322.394864718942, 5303.840884773458, 5358.49094336038, 5375.043725950731, 5380.488853558915, 4741.060542463677, 4778.81853378294, 5041.6974981501135, 5299.012870173685, 5309.115264081253, 4031.5766844481345, 4080.3063479208604, 4087.7547347159116, 4101.446304748865, 4118.567550407209, 3398.8130652814084, 4083.1221287423346, 4121.793910304487, 4131.0, 4154.563770804068, 3694.9815250923707, 3725.739198256123, 3779.603141670126, 3824.579296277407, 4110.876673699794, 3224.709776120137, 3857.148568687046, 3899.4345819856103, 4047.251258517184, 4098.684243402468, 3536.257385948954, 3556.3065202285566, 3556.6798988090936, 3563.3585954528176, 3569.125564236753, 4850.466448690641, 5199.518137330842, 5239.659422137549, 5247.501487358695, 5251.86870328241, 3803.619638036202, 4491.214037543416, 4527.728336299832, 4543.31850222466, 4578.977105114486, 4232.36514522824, 5475.014497390468, 5493.176023596453, 5545.556355491592, 5555.666660000413, 3028.97015014896, 3419.863205471778, 3720.516332876709, 3785.9810700946596, 3800.4869342638667, 5103.617228707862, 5800.506956908668, 5816.109764326568, 5823.184754134425, 5858.736356863951, 3795.2978698940783, 3795.9430608540893, 3803.3344164771056, 3811.6950643948417, 3834.041489627593, 3881.0, 3881.476000739864, 3882.8446862125197, 3883.8446462141196, 3890.844366225376, 4243.193793179348, 4497.392851964943, 4552.795124219352, 4554.453465584085, 4568.428946381702, 4918.975405122951, 5557.499825015687, 5571.220029195916, 5595.062592139159, 5613.382527921925, 1308.6978456435907, 1339.71196192819, 1341.5304643374936, 1342.4093398904452, 1345.0, 803.2850762821093, 822.6133717153048, 840.6091167607092, 843.2115971566604, 844.4933040175845, 4973.90052198954, 5144.048351055067, 5207.973960130175, 5273.736313184402, 5276.445973172838, 5044.041632089891, 5438.91841622376, 5692.402297758758, 5715.5427565794635, 5715.971125197504, 4617.353570500132, 4990.8253211137435, 5051.318072060299, 5189.532942035235, 5226.033183860998, 2647.3249321422913, 2938.412317536501, 2959.289770455075, 2993.461176988163, 3027.1826606816476, 4931.903940825135, 5242.842714718565, 5921.572155416258, 5944.762209511571, 5974.880502389928, 4423.0, 4940.728259945666, 5859.5019423349495, 5884.0, 5887.175795388649, 4541.342410020319, 5734.046965812609, 5878.911816322759, 5904.911426328608, 5963.761449542143, 5409.810656626999, 5438.75525601341, 5621.775128994958, 5666.773329066956, 5759.712014399347, 5203.591647470913, 5353.919691204557, 5635.323761148525, 5640.743702519026, 5659.9812033834305, 5680.630759000652, 5918.763249469973, 5951.619352259131, 5971.4327138921735, 5985.970070149621, 3319.170207448138, 3393.423118069885, 3834.4087203811223, 3865.806709664562, 3896.7661940283674, 5029.06962211992, 5614.606977511535, 5732.71336433185, 5749.281339832521, 5749.741261643152, 5155.639105262746, 5652.349979752381, 5672.631278966854, 5678.971605141948, 5694.032014557618, 4374.309799473237, 4621.514740952151, 4866.7079975201605, 5106.740057740533, 5316.521513063843, 4681.0, 5210.244514545294, 5417.8103766367985, 5459.044667183306, 5462.371827239918, 5403.729813509387, 5555.694436806015, 5606.906653202586, 5614.915776263278, 5665.801696940587, 5495.862603434946, 5505.642133261325, 5529.529989950899, 5534.7786088556, 5548.722563871709], "value": 4049.0913283961054, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.7416130193131, 728.3626826526789, 734.9418417998171, 751.2525037587601, 812.6058861452196, 15467.30397132129, 16630.253823348525, 16655.33378664852, 16689.83310166898, 16726.33094676213, 14932.925335373307, 15039.92480037601, 15053.698926021472, 15054.397824087051, 15078.0, 662.6951602262965, 686.193722376206, 826.9710560130396, 828.2918105020196, 854.6538651846013, 838.8423974914609, 860.5439117267858, 862.3920136303922, 864.5201912938348, 866.7269810009858, 921.1387352825096, 923.2198792020732, 923.745969858291, 924.856647219682, 926.7405126564573, 705.6436499567736, 734.8016035670339, 737.5832654550162, 921.5760750054983, 923.1322556796623, 539.1939051118563, 541.545102114222, 655.298830251631, 678.4199509419474, 683.4498228925762, 15062.246887655683, 15104.244787760568, 15104.54686359411, 15130.848691513054, 15143.772843407358, 14033.228172450512, 14353.64115897111, 14442.133471991609, 14447.711045779026, 14651.413943442249, 13072.346382680827, 13314.201147931155, 13437.932810335984, 13460.461581536727, 13670.384832682554, 4568.291914753218, 4588.172732378116, 4588.816447342102, 4594.0, 4869.391326084239, 124060.0, 125496.37251813774, 129481.0, 129722.702772972, 130667.0, 9584.952075239578, 11422.885771142348, 11475.713107172387, 11584.594539191088, 12007.51969921211, 23471.41321466943, 23482.53034939326, 23518.529629407316, 23530.0, 23546.411339716644, 7838.764837054783, 7844.058712954368, 7879.251471110236, 7882.251186137308, 8034.0, 4360.4767427908855, 4420.0, 4518.909621807611, 4587.79354929029, 4685.109829132454, 3504.807235602015, 3590.622984586633, 3612.1150318172217, 3624.130208749881, 3624.7100231981376, 641.1878849236543, 642.068999950065, 655.7803135949415, 667.1860330396908, 668.1447746883996, 579.8550362409397, 605.231356177652, 631.6273398694792, 635.3836778325017, 637.6365471681097, 8728.69449569262, 9019.503927283931, 9120.653989975432, 9212.53937303132, 9266.44401335922, 490.4213028626255, 529.2167591963915, 541.7859945321576, 551.0191858491891, 599.5473417569707, 4081.77550234734, 4436.977815110969, 4443.533428989909, 4455.910881782411, 4511.796969136331, 87521.12478875257, 144285.0, 153420.0, 159089.20455397648, 161485.19257403637, 106320.0, 127822.36088819496, 127828.36085819511, 129727.0, 132358.0, 83017.0, 110140.4492977546, 112396.43801780939, 113953.0, 114544.13639659075, 8470.872936906075, 8578.957105214684, 8604.225619694258, 8738.226753597428, 8984.505852178192, 46621.766891166684, 60453.0, 63901.041484376845, 64444.67777661081, 68299.3170068293, 77346.944897163, 96279.0, 97583.51208244197, 97832.0, 100961.98076038585, 11484.253523520945, 11696.139278621824, 11737.706557336134, 11874.287542747463, 12055.046513604224, 12674.0, 12702.428390722625, 12922.612321630366, 13154.60536183916, 13438.731225375634, 11800.3509806958, 12045.217060891015, 12065.81901271464, 12363.93818030904, 12450.875491245333, 11640.5343786251, 11942.582009629621, 12059.0, 12245.142840001114, 12316.815247771112, 10228.181745460493, 11920.284782913053, 11945.223560468541, 12245.265284082983, 12318.260904345769, 8556.786080348042, 9776.9022309776, 10148.391096534231, 10148.74628134273, 10601.310914790514, 8593.785155371166, 9212.723618291462, 10336.0, 10337.586496540054, 10337.9483102584], "value": 7660.853313313022, "unit": "score"}], "name": "dromaeo_css", "value": 7387.316261912652, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 112.0, 114.0, 107.0, 113.0, 113.0, 113.0, 113.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 111.0, 129.0, 197.0, 109.0, 109.0, 127.0, 103.0, 159.0, 111.0], "value": 130.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 182.0, 192.0, 185.0, 179.0, 176.0, 180.0, 183.0, 183.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 65.0, 87.0, 57.0, 57.0, 63.0, 66.0, 87.0, 58.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 93.0, 96.0, 71.0, 71.0, 69.0, 97.0, 95.0, 99.0, 138.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 116.0, 135.0, 123.0, 87.0, 86.0, 88.0, 85.0, 89.0, 85.0], "value": 98.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 110.0, 150.0, 100.0, 93.0, 93.0, 93.0, 94.0, 94.0, 93.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 83.0, 84.0, 83.0, 84.0, 83.0, 83.0, 83.0], "value": 83.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 51.0, 54.0, 53.0, 53.0, 55.0, 53.0, 53.0, 53.0, 53.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 75.0, 75.0, 72.0, 72.0, 68.0, 69.0, 70.0, 71.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 85.0, 84.0, 86.0, 87.0, 89.0, 84.0, 87.0, 87.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 138.0, 134.0, 139.0, 139.0, 136.0, 139.0, 136.0, 225.0, 149.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 172.0, 233.0, 213.0, 171.0, 170.0, 168.0, 170.0, 166.0, 168.0], "value": 180.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 68.0, 68.0, 68.0, 68.0, 66.0, 66.0, 66.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1470.9, "alertThreshold": 2.0}]} 17:51:53 INFO - Return code: 0 17:51:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:51:53 INFO - # TBPL SUCCESS # 17:51:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:51:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:51:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:51:53 INFO - 2016-08-18 17:51:53.807 screenresolution[1935:12615] starting screenresolution argv=screenresolution get 17:51:53 INFO - 2016-08-18 17:51:53.810 screenresolution[1935:12615] Display 0: 1600x1200x32@60 17:51:53 INFO - 2016-08-18 17:51:53.817 screenresolution[1936:12618] starting screenresolution argv=screenresolution list 17:51:53 INFO - Available Modes on Display 0 17:51:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:51:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:51:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:51:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:51:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:51:54 INFO - Intel Iris: 17:51:54 INFO - Chipset Model: Intel Iris 17:51:54 INFO - Type: GPU 17:51:54 INFO - Bus: Built-In 17:51:54 INFO - VRAM (Dynamic, Max): 1536 MB 17:51:54 INFO - Vendor: Intel (0x8086) 17:51:54 INFO - Device ID: 0x0a2e 17:51:54 INFO - Revision ID: 0x0009 17:51:54 INFO - Displays: 17:51:54 INFO - Display: 17:51:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:51:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:51:54 INFO - Display Serial Number: 1600x1200 60 17:51:54 INFO - Main Display: Yes 17:51:54 INFO - Mirror: Off 17:51:54 INFO - Online: Yes 17:51:54 INFO - Rotation: Supported 17:51:54 INFO - Return code: 0 17:51:54 INFO - Running post-action listener: _resource_record_post_action 17:51:54 INFO - [mozharness: 2016-08-19 00:51:54.055903Z] Finished run-tests step (success) 17:51:54 INFO - Running post-run listener: _resource_record_post_run 17:51:54 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 159744; Write bytes: 701264384; Read time: 5; Write time: 1920 17:51:54 INFO - TinderboxPrint: CPU usage
53.5% 17:51:54 INFO - TinderboxPrint: I/O read bytes / time
159,744 / 5 17:51:54 INFO - TinderboxPrint: I/O write bytes / time
701,264,384 / 1,920 17:51:54 INFO - TinderboxPrint: CPU idle
2,257.9 (46.5%) 17:51:54 INFO - TinderboxPrint: CPU system
968.5 (20.0%) 17:51:54 INFO - TinderboxPrint: CPU user
1,627.3 (33.5%) 17:51:54 INFO - TinderboxPrint: Swap in / out
405,757,952 / 0 17:51:54 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 394453504; Write bytes: 388298240; Read time: 29434; Write time: 696 17:51:54 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 159744; Write bytes: 292953088; Read time: 5; Write time: 1177 17:51:54 INFO - Running post-run listener: _upload_blobber_files 17:51:54 INFO - Blob upload gear active. 17:51:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:51:54 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 17:51:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:51:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:51:54 INFO - (blobuploader) - INFO - Open directory for files ... 17:51:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:51:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:51:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:51:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:51:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:51:56 INFO - (blobuploader) - INFO - Done attempting. 17:51:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:51:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:51:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:51:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:51:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:51:57 INFO - (blobuploader) - INFO - Done attempting. 17:51:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:51:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:51:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:51:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:51:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:51:59 INFO - (blobuploader) - INFO - Done attempting. 17:51:59 INFO - (blobuploader) - INFO - Iteration through files over. 17:51:59 INFO - Return code: 0 17:51:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:51:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:51:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c89d1a0e3b09ddb951f8559b0011101be1cc267536f4b9236240e77d16882d4a2f890984942b3d1c29eccdcc272c65469f5a83f71bebd3d582230d00a46235b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/420efd301dd61601b9a511966e284fa041a289121fa7cfcffcce1c64e9fd9d4e23cfaf953a03d34940c137145efd741c27346ae6bf25250157913c887942717f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0542c4fb05c94332323a28cdeffa0dae6e7b8565ea741bbdaefb4fb6922fe06dd1daeb601b30e9520fc77c30d90a0dc6ae9221a988919ae6efe7e89ba6e24a4"} 17:51:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:51:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:51:59 INFO - Contents: 17:51:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c89d1a0e3b09ddb951f8559b0011101be1cc267536f4b9236240e77d16882d4a2f890984942b3d1c29eccdcc272c65469f5a83f71bebd3d582230d00a46235b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/420efd301dd61601b9a511966e284fa041a289121fa7cfcffcce1c64e9fd9d4e23cfaf953a03d34940c137145efd741c27346ae6bf25250157913c887942717f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0542c4fb05c94332323a28cdeffa0dae6e7b8565ea741bbdaefb4fb6922fe06dd1daeb601b30e9520fc77c30d90a0dc6ae9221a988919ae6efe7e89ba6e24a4"} 17:51:59 INFO - Running post-run listener: copy_logs_to_upload_dir 17:51:59 INFO - Copying logs to upload dir... 17:51:59 INFO - mkdir: /builds/slave/test/build/upload/logs 17:51:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.581690 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-18 17:51:59.371646) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 17:51:59.375477) ========= 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.5L068MTf0N/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.vrjmfKqux0/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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/Firefox/sha512/9c89d1a0e3b09ddb951f8559b0011101be1cc267536f4b9236240e77d16882d4a2f890984942b3d1c29eccdcc272c65469f5a83f71bebd3d582230d00a46235b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/420efd301dd61601b9a511966e284fa041a289121fa7cfcffcce1c64e9fd9d4e23cfaf953a03d34940c137145efd741c27346ae6bf25250157913c887942717f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0542c4fb05c94332323a28cdeffa0dae6e7b8565ea741bbdaefb4fb6922fe06dd1daeb601b30e9520fc77c30d90a0dc6ae9221a988919ae6efe7e89ba6e24a4"} build_url:https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011343 build_url: 'https://queue.taskcluster.net/v1/task/QLrHswiCTCirjVwBo5Ecyw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c89d1a0e3b09ddb951f8559b0011101be1cc267536f4b9236240e77d16882d4a2f890984942b3d1c29eccdcc272c65469f5a83f71bebd3d582230d00a46235b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/420efd301dd61601b9a511966e284fa041a289121fa7cfcffcce1c64e9fd9d4e23cfaf953a03d34940c137145efd741c27346ae6bf25250157913c887942717f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0542c4fb05c94332323a28cdeffa0dae6e7b8565ea741bbdaefb4fb6922fe06dd1daeb601b30e9520fc77c30d90a0dc6ae9221a988919ae6efe7e89ba6e24a4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 17:51:59.409209) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 17:51:59.409551) ========= 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.5L068MTf0N/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.vrjmfKqux0/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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.005064 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 17:51:59.460903) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 17:51:59.461290) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 17:51:59.461758) ========= ========= Total master_lag: 0.58 =========