builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0332 starttime: 1472751642.07 results: success (0) buildid: 20160901084620 builduid: 76986f53fb6a4c6691d44394a6d70619 revision: 18b2446bbf07efbcd62ea95009bfdb8177482edc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 10:40:42.066234) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 10:40:42.066686) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 10:40:42.066965) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 10:40:42.092016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 10:40:42.092552) ========= 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.Gq1gtteY1d/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.QnND0L8586/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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-09-01 10:40:42-- 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% 20.9M=0.001s 2016-09-01 10:40:42 (20.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132606 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 10:40:42.246157) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 10:40:42.246470) ========= 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.Gq1gtteY1d/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.QnND0L8586/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.026502 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 10:40:42.295057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-01 10:40:42.295405) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 18b2446bbf07efbcd62ea95009bfdb8177482edc --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 18b2446bbf07efbcd62ea95009bfdb8177482edc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Gq1gtteY1d/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.QnND0L8586/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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-09-01 10:40:42,382 truncating revision to first 12 chars 2016-09-01 10:40:42,382 Setting DEBUG logging. 2016-09-01 10:40:42,382 attempt 1/10 2016-09-01 10:40:42,382 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/18b2446bbf07?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 10:40:43,505 unpacking tar archive at: mozilla-central-18b2446bbf07/testing/mozharness/ program finished with exit code 0 elapsedTime=1.382883 ========= master_lag: 3.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-01 10:40:46.900144) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 10:40:46.900481) ========= script_repo_revision: 18b2446bbf07efbcd62ea95009bfdb8177482edc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 10:40:46.900899) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 10:40:46.901191) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 10:40:46.921782) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-01 10:40:46.922168) ========= /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.Gq1gtteY1d/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.QnND0L8586/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:40:47 INFO - MultiFileLogger online at 20160901 10:40:47 in /builds/slave/test 10:40:47 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 10:40:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:40:47 INFO - {'append_to_log': False, 10:40:47 INFO - 'base_work_dir': '/builds/slave/test', 10:40:47 INFO - 'blob_upload_branch': 'Firefox', 10:40:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:40:47 INFO - 'branch': 'Firefox', 10:40:47 INFO - 'buildbot_json_path': 'buildprops.json', 10:40:47 INFO - 'config_files': ('talos/mac_config.py',), 10:40:47 INFO - 'default_actions': ('clobber', 10:40:47 INFO - 'read-buildbot-config', 10:40:47 INFO - 'download-and-extract', 10:40:47 INFO - 'populate-webroot', 10:40:47 INFO - 'create-virtualenv', 10:40:47 INFO - 'install', 10:40:47 INFO - 'run-tests'), 10:40:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:40:47 INFO - 'download_minidump_stackwalk': True, 10:40:47 INFO - 'download_symbols': 'ondemand', 10:40:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:40:47 INFO - 'tooltool.py': '/tools/tooltool.py', 10:40:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:40:47 INFO - '/tools/misc-python/virtualenv.py')}, 10:40:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:40:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:40:47 INFO - 'installer_path': 'installer.exe', 10:40:47 INFO - 'log_level': 'info', 10:40:47 INFO - 'log_name': 'talos', 10:40:47 INFO - 'log_to_console': True, 10:40:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:40:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:40:47 INFO - 'opt_config_files': (), 10:40:47 INFO - 'pip_index': False, 10:40:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:40:47 INFO - 'cmd': ('bash', 10:40:47 INFO - '-c', 10:40:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:40:47 INFO - 'enabled': True, 10:40:47 INFO - 'halt_on_failure': False, 10:40:47 INFO - 'name': 'check_screen_resolution'},), 10:40:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:40:47 INFO - 'cmd': ('bash', 10:40:47 INFO - '-c', 10:40:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:40:47 INFO - 'enabled': True, 10:40:47 INFO - 'halt_on_failure': False, 10:40:47 INFO - 'name': 'check_screen_resolution'},), 10:40:47 INFO - 'run_cmd_checks_enabled': True, 10:40:47 INFO - 'sps_profile': False, 10:40:47 INFO - 'sps_profile_interval': 0, 10:40:47 INFO - 'suite': 'dromaeojs', 10:40:47 INFO - 'system_bits': '32', 10:40:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:40:47 INFO - 'title': 't-yosemite-r7-0332', 10:40:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:40:47 INFO - 'use_talos_json': True, 10:40:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:40:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:40:47 INFO - 'work_dir': 'build'} 10:40:47 INFO - [mozharness: 2016-09-01 17:40:47.120121Z] Running clobber step. 10:40:47 INFO - Running pre-action listener: _resource_record_pre_action 10:40:47 INFO - Running main action method: clobber 10:40:47 INFO - rmtree: /builds/slave/test/build 10:40:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:40:49 INFO - Running post-action listener: _resource_record_post_action 10:40:49 INFO - [mozharness: 2016-09-01 17:40:49.172879Z] Finished clobber step (success) 10:40:49 INFO - [mozharness: 2016-09-01 17:40:49.173033Z] Running read-buildbot-config step. 10:40:49 INFO - Running pre-action listener: _resource_record_pre_action 10:40:49 INFO - Running main action method: read_buildbot_config 10:40:49 INFO - Using buildbot properties: 10:40:49 INFO - { 10:40:49 INFO - "project": "", 10:40:49 INFO - "product": "firefox", 10:40:49 INFO - "script_repo_revision": "production", 10:40:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:40:49 INFO - "repository": "", 10:40:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:40:49 INFO - "buildid": "20160901084620", 10:40:49 INFO - "pgo_build": "False", 10:40:49 INFO - "basedir": "/builds/slave/test", 10:40:49 INFO - "buildnumber": 89, 10:40:49 INFO - "slavename": "t-yosemite-r7-0332", 10:40:49 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 10:40:49 INFO - "platform": "yosemite_r7", 10:40:49 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:40:49 INFO - "branch": "mozilla-central", 10:40:49 INFO - "repo_path": "mozilla-central", 10:40:49 INFO - "revision": "18b2446bbf07efbcd62ea95009bfdb8177482edc", 10:40:49 INFO - "stage_platform": "macosx64", 10:40:49 INFO - "builduid": "76986f53fb6a4c6691d44394a6d70619", 10:40:49 INFO - "slavebuilddir": "test" 10:40:49 INFO - } 10:40:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:40:49 INFO - Running post-action listener: _resource_record_post_action 10:40:49 INFO - [mozharness: 2016-09-01 17:40:49.175950Z] Finished read-buildbot-config step (success) 10:40:49 INFO - [mozharness: 2016-09-01 17:40:49.176059Z] Running download-and-extract step. 10:40:49 INFO - Running pre-action listener: _resource_record_pre_action 10:40:49 INFO - Running main action method: download_and_extract 10:40:49 INFO - mkdir: /builds/slave/test/build/tests 10:40:49 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')]} 10:40:49 INFO - trying https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:40:49 INFO - Downloading https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/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 10:40:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/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 10:40:50 INFO - Downloaded 1199 bytes. 10:40:50 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:40:50 INFO - Using the following test package requirements: 10:40:50 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:40:50 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:40:50 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:40:50 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:40:50 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:40:50 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:40:50 INFO - u'jsshell-mac.zip'], 10:40:50 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:40:50 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:40:50 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:40:50 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:40:50 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:40:50 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:40:50 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:40:50 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:40:50 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:40:50 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:40:50 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:40:50 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:40:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:50 INFO - trying https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:40:50 INFO - Downloading https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/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 10:40:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/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 10:40:51 INFO - Downloaded 16400989 bytes. 10:40:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 10:40:57 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 10:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:57 INFO - trying https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:40:57 INFO - Downloading https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/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 10:40:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/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 10:40:58 INFO - Downloaded 16400989 bytes. 10:40:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 10:41: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')]} 10:41:05 INFO - trying https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:41:05 INFO - Downloading https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/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 10:41:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/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 10:41:06 INFO - Downloaded 14413998 bytes. 10:41:06 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 10:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:06 INFO - trying https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:41:06 INFO - Downloading https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:41:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/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 10:41:09 INFO - Downloaded 111575109 bytes. 10:41:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:41:09 INFO - mkdir: /builds/slave/test/properties 10:41:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:41:09 INFO - Writing to file /builds/slave/test/properties/build_url 10:41:09 INFO - Contents: 10:41:09 INFO - build_url:https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:41:10 INFO - Running post-action listener: _resource_record_post_action 10:41:10 INFO - Running post-action listener: set_extra_try_arguments 10:41:10 INFO - [mozharness: 2016-09-01 17:41:10.297177Z] Finished download-and-extract step (success) 10:41:10 INFO - [mozharness: 2016-09-01 17:41:10.297327Z] Running populate-webroot step. 10:41:10 INFO - Running pre-action listener: _resource_record_pre_action 10:41:10 INFO - Running main action method: populate_webroot 10:41:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:41:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:41:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:41:10 INFO - '--tppagecycles', 10:41:10 INFO - '7'], 10:41:10 INFO - 'tests': ['tsvgm']}}, 10:41:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:41:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:41:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:41:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:41:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:41:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:41:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['damp', 'tps']}, 10:41:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'tests': ['damp', 'tps']}, 10:41:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['dromaeo_dom']}, 10:41:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:41:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['basic_compositor_video']}, 10:41:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:41:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['a11yr', 10:41:10 INFO - 'ts_paint', 10:41:10 INFO - 'tpaint', 10:41:10 INFO - 'sessionrestore', 10:41:10 INFO - 'sessionrestore_no_auto_restore', 10:41:10 INFO - 'tabpaint']}, 10:41:10 INFO - 'other-e10s': {'tests': ['a11yr', 10:41:10 INFO - 'ts_paint', 10:41:10 INFO - 'tpaint', 10:41:10 INFO - 'sessionrestore', 10:41:10 INFO - 'sessionrestore_no_auto_restore', 10:41:10 INFO - 'tabpaint']}, 10:41:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:41:10 INFO - 'ts_paint', 10:41:10 INFO - 'tpaint', 10:41:10 INFO - 'sessionrestore', 10:41:10 INFO - 'sessionrestore_no_auto_restore', 10:41:10 INFO - 'tabpaint']}, 10:41:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:41:10 INFO - 'ts_paint', 10:41:10 INFO - 'tpaint', 10:41:10 INFO - 'sessionrestore', 10:41:10 INFO - 'sessionrestore_no_auto_restore', 10:41:10 INFO - 'tabpaint']}, 10:41:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['a11yr', 10:41:10 INFO - 'ts_paint', 10:41:10 INFO - 'tpaint', 10:41:10 INFO - 'sessionrestore', 10:41:10 INFO - 'sessionrestore_no_auto_restore', 10:41:10 INFO - 'tabpaint']}, 10:41:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['a11yr', 10:41:10 INFO - 'ts_paint', 10:41:10 INFO - 'tpaint', 10:41:10 INFO - 'sessionrestore', 10:41:10 INFO - 'sessionrestore_no_auto_restore', 10:41:10 INFO - 'tabpaint']}, 10:41:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['tsvgx', 10:41:10 INFO - 'tsvgr_opacity', 10:41:10 INFO - 'tart', 10:41:10 INFO - 'tscrollx', 10:41:10 INFO - 'cart']}, 10:41:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:41:10 INFO - 'tsvgr_opacity', 10:41:10 INFO - 'tart', 10:41:10 INFO - 'tscrollx', 10:41:10 INFO - 'cart']}, 10:41:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'talos_options': ['--disable-e10s'], 10:41:10 INFO - 'tests': ['tp5o']}, 10:41:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'tests': ['tp5o']}, 10:41:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'talos_options': ['--disable-e10s', 10:41:10 INFO - '--xperf_path', 10:41:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:41:10 INFO - 'tests': ['tp5n']}, 10:41:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:10 INFO - 'talos_options': ['--xperf_path', 10:41:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:41:10 INFO - 'tests': ['tp5n']}}, 10:41:10 INFO - 'talos.zip': {'path': '', 10:41:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:41:10 INFO - Running post-action listener: _resource_record_post_action 10:41:10 INFO - [mozharness: 2016-09-01 17:41:10.315826Z] Finished populate-webroot step (success) 10:41:10 INFO - [mozharness: 2016-09-01 17:41:10.315932Z] Running create-virtualenv step. 10:41:10 INFO - Running pre-action listener: _resource_record_pre_action 10:41:10 INFO - Running main action method: create_virtualenv 10:41:10 INFO - Creating virtualenv /builds/slave/test/build/venv 10:41:10 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 10:41:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:41:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:41:10 INFO - Using real prefix '/tools/python27' 10:41:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:41:11 INFO - Installing distribute.............................................................................................................................................................................................done. 10:41:14 INFO - Installing pip.................done. 10:41:14 INFO - Return code: 0 10:41:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:41: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')]} 10:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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')]} 10:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:14 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 0x10b65aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3dad76b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5bed30>, '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 0x10b323db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b6b20>, '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.Gq1gtteY1d/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.QnND0L8586/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:41:14 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 10:41:14 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 10:41:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gq1gtteY1d/Render', 10:41:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:14 INFO - 'HOME': '/Users/cltbld', 10:41:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:14 INFO - 'LOGNAME': 'cltbld', 10:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:14 INFO - 'MOZ_NO_REMOTE': '1', 10:41:14 INFO - 'NO_EM_RESTART': '1', 10:41:14 INFO - 'PAGER': '/bin/cat', 10:41:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:14 INFO - 'PWD': '/builds/slave/test', 10:41:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:14 INFO - 'SHELL': '/bin/bash', 10:41:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnND0L8586/Listeners', 10:41:14 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:41:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:14 INFO - 'USER': 'cltbld', 10:41:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:14 INFO - 'XPC_FLAGS': '0x0', 10:41:14 INFO - 'XPC_SERVICE_NAME': '0', 10:41:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:14 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 10:41:14 INFO - Cleaning up... 10:41:14 INFO - Return code: 0 10:41:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:41: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')]} 10:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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')]} 10:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:14 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 0x10b65aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3dad76b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5bed30>, '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 0x10b323db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b6b20>, '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.Gq1gtteY1d/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.QnND0L8586/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:41:14 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 10:41:14 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 10:41:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gq1gtteY1d/Render', 10:41:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:14 INFO - 'HOME': '/Users/cltbld', 10:41:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:14 INFO - 'LOGNAME': 'cltbld', 10:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:14 INFO - 'MOZ_NO_REMOTE': '1', 10:41:14 INFO - 'NO_EM_RESTART': '1', 10:41:14 INFO - 'PAGER': '/bin/cat', 10:41:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:14 INFO - 'PWD': '/builds/slave/test', 10:41:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:14 INFO - 'SHELL': '/bin/bash', 10:41:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnND0L8586/Listeners', 10:41:14 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:41:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:14 INFO - 'USER': 'cltbld', 10:41:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:14 INFO - 'XPC_FLAGS': '0x0', 10:41:14 INFO - 'XPC_SERVICE_NAME': '0', 10:41:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:14 INFO - Downloading/unpacking psutil>=3.1.1 10:41:14 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 10:41:14 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 10:41:14 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 10:41:14 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 10:41:14 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 10:41:14 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 10:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:41:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:41:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:41:17 INFO - Installing collected packages: psutil 10:41:17 INFO - Running setup.py install for psutil 10:41:17 INFO - building 'psutil._psutil_osx' extension 10:41:17 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 10:41:17 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 10:41:17 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 10:41:18 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 10:41:18 INFO - building 'psutil._psutil_posix' extension 10:41:18 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 10:41:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:41:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:41:18 INFO - ^ 10:41:18 INFO - 1 warning generated. 10:41:18 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 10:41:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:41:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:41:18 INFO - Successfully installed psutil 10:41:18 INFO - Cleaning up... 10:41:18 INFO - Return code: 0 10:41:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:41:18 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')]} 10:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:18 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')]} 10:41:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:18 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 0x10b65aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3dad76b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5bed30>, '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 0x10b323db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b6b20>, '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.Gq1gtteY1d/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.QnND0L8586/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:41:18 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 10:41:18 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 10:41:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gq1gtteY1d/Render', 10:41:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:18 INFO - 'HOME': '/Users/cltbld', 10:41:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:18 INFO - 'LOGNAME': 'cltbld', 10:41:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:18 INFO - 'MOZ_NO_REMOTE': '1', 10:41:18 INFO - 'NO_EM_RESTART': '1', 10:41:18 INFO - 'PAGER': '/bin/cat', 10:41:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:18 INFO - 'PWD': '/builds/slave/test', 10:41:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:18 INFO - 'SHELL': '/bin/bash', 10:41:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnND0L8586/Listeners', 10:41:18 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:41:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:18 INFO - 'USER': 'cltbld', 10:41:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:18 INFO - 'XPC_FLAGS': '0x0', 10:41:18 INFO - 'XPC_SERVICE_NAME': '0', 10:41:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:41:18 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 10:41:18 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 10:41:18 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 10:41:18 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 10:41:18 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 10:41:18 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 10:41:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:41:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:41:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:41:21 INFO - Installing collected packages: mozsystemmonitor 10:41:21 INFO - Running setup.py install for mozsystemmonitor 10:41:21 INFO - Successfully installed mozsystemmonitor 10:41:21 INFO - Cleaning up... 10:41:21 INFO - Return code: 0 10:41:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:41:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:21 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 0x10b65aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3dad76b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5bed30>, '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 0x10b323db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b6b20>, '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.Gq1gtteY1d/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.QnND0L8586/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:41:21 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 10:41:21 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 10:41:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gq1gtteY1d/Render', 10:41:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:21 INFO - 'HOME': '/Users/cltbld', 10:41:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:21 INFO - 'LOGNAME': 'cltbld', 10:41:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:21 INFO - 'MOZ_NO_REMOTE': '1', 10:41:21 INFO - 'NO_EM_RESTART': '1', 10:41:21 INFO - 'PAGER': '/bin/cat', 10:41:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:21 INFO - 'PWD': '/builds/slave/test', 10:41:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:21 INFO - 'SHELL': '/bin/bash', 10:41:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnND0L8586/Listeners', 10:41:21 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:41:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:21 INFO - 'USER': 'cltbld', 10:41:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:21 INFO - 'XPC_FLAGS': '0x0', 10:41:21 INFO - 'XPC_SERVICE_NAME': '0', 10:41:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:21 INFO - Downloading/unpacking jsonschema==2.5.1 10:41:21 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 10:41:21 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 10:41:21 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 10:41:21 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 10:41:21 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 10:41:21 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 10:41:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:41:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:41:24 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 10:41:24 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 10:41:24 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 10:41:24 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 10:41:24 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 10:41:24 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 10:41:24 INFO - Downloading functools32-3.2.3-2.tar.gz 10:41:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:41:24 INFO - warning: no files found matching '*.txt' 10:41:24 INFO - no previously-included directories found matching 'build' 10:41:24 INFO - no previously-included directories found matching 'dist' 10:41:24 INFO - no previously-included directories found matching '.git*' 10:41:24 INFO - Installing collected packages: jsonschema, functools32 10:41:24 INFO - Running setup.py install for functools32 10:41:24 INFO - warning: no files found matching '*.txt' 10:41:24 INFO - no previously-included directories found matching 'build' 10:41:24 INFO - no previously-included directories found matching 'dist' 10:41:24 INFO - no previously-included directories found matching '.git*' 10:41:24 INFO - Successfully installed jsonschema functools32 10:41:24 INFO - Cleaning up... 10:41:25 INFO - Return code: 0 10:41:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:41:25 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')]} 10:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:25 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')]} 10:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:25 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 0x10b65aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3dad76b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5bed30>, '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 0x10b323db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b6b20>, '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.Gq1gtteY1d/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.QnND0L8586/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:41:25 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 10:41:25 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 10:41:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gq1gtteY1d/Render', 10:41:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:25 INFO - 'HOME': '/Users/cltbld', 10:41:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:25 INFO - 'LOGNAME': 'cltbld', 10:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:25 INFO - 'MOZ_NO_REMOTE': '1', 10:41:25 INFO - 'NO_EM_RESTART': '1', 10:41:25 INFO - 'PAGER': '/bin/cat', 10:41:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:25 INFO - 'PWD': '/builds/slave/test', 10:41:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:25 INFO - 'SHELL': '/bin/bash', 10:41:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnND0L8586/Listeners', 10:41:25 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:41:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:25 INFO - 'USER': 'cltbld', 10:41:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:25 INFO - 'XPC_FLAGS': '0x0', 10:41:25 INFO - 'XPC_SERVICE_NAME': '0', 10:41:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:41:25 INFO - Cleaning up... 10:41:25 INFO - Return code: 0 10:41:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:41:25 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')]} 10:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:25 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')]} 10:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:25 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 0x10b65aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3dad76b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5bed30>, '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 0x10b323db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b6b20>, '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.Gq1gtteY1d/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.QnND0L8586/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:41:25 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 10:41:25 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 10:41:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gq1gtteY1d/Render', 10:41:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:25 INFO - 'HOME': '/Users/cltbld', 10:41:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:25 INFO - 'LOGNAME': 'cltbld', 10:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:25 INFO - 'MOZ_NO_REMOTE': '1', 10:41:25 INFO - 'NO_EM_RESTART': '1', 10:41:25 INFO - 'PAGER': '/bin/cat', 10:41:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:25 INFO - 'PWD': '/builds/slave/test', 10:41:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:25 INFO - 'SHELL': '/bin/bash', 10:41:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnND0L8586/Listeners', 10:41:25 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:41:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:25 INFO - 'USER': 'cltbld', 10:41:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:25 INFO - 'XPC_FLAGS': '0x0', 10:41:25 INFO - 'XPC_SERVICE_NAME': '0', 10:41:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:25 INFO - Downloading/unpacking blobuploader==1.2.4 10:41:25 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 10:41:25 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 10:41:25 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 10:41:25 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 10:41:25 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 10:41:25 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 10:41:27 INFO - Downloading blobuploader-1.2.4.tar.gz 10:41:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:41:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:41:28 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 10:41:28 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 10:41:28 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 10:41:28 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 10:41:28 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 10:41:28 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 10:41:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:41:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:41:28 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 10:41:28 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 10:41:28 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 10:41:28 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 10:41:28 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 10:41:28 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 10:41:29 INFO - Downloading docopt-0.6.1.tar.gz 10:41:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:41:29 INFO - Installing collected packages: blobuploader, requests, docopt 10:41:29 INFO - Running setup.py install for blobuploader 10:41:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:41:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:41:29 INFO - Running setup.py install for requests 10:41:29 INFO - Running setup.py install for docopt 10:41:30 INFO - Successfully installed blobuploader requests docopt 10:41:30 INFO - Cleaning up... 10:41:30 INFO - Return code: 0 10:41:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:30 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 0x10b65aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3dad76b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5bed30>, '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 0x10b323db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b6b20>, '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.Gq1gtteY1d/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.QnND0L8586/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:41:30 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 10:41:30 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 10:41:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gq1gtteY1d/Render', 10:41:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:30 INFO - 'HOME': '/Users/cltbld', 10:41:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:30 INFO - 'LOGNAME': 'cltbld', 10:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:30 INFO - 'MOZ_NO_REMOTE': '1', 10:41:30 INFO - 'NO_EM_RESTART': '1', 10:41:30 INFO - 'PAGER': '/bin/cat', 10:41:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:30 INFO - 'PWD': '/builds/slave/test', 10:41:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:30 INFO - 'SHELL': '/bin/bash', 10:41:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnND0L8586/Listeners', 10:41:30 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:41:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:30 INFO - 'USER': 'cltbld', 10:41:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:30 INFO - 'XPC_FLAGS': '0x0', 10:41:30 INFO - 'XPC_SERVICE_NAME': '0', 10:41:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:41:30 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-ORXSIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:41:30 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-bBnaRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:41:30 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-4iF8OW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:41:30 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-hSHGPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:41:30 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-6RzXje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:41:30 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-5pDEAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:41:31 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-N5ERDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:41:31 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-qvp2qn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:41:31 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-QeAH4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:41:31 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-c6QFAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:41:31 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-XeJP9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:41:31 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-nqMZMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:41:31 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-B8sJwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:41:31 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-ibZzAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:31 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-ormivU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:41:31 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-BQnOeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:41:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:41:32 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-5etTbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:41:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:41:32 INFO - Running setup.py install for manifestparser 10:41:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:41:32 INFO - Running setup.py install for mozcrash 10:41:32 INFO - Running setup.py install for mozdebug 10:41:32 INFO - Running setup.py install for mozdevice 10:41:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:41:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:41:32 INFO - Running setup.py install for mozfile 10:41:32 INFO - Running setup.py install for mozhttpd 10:41:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:41:33 INFO - Running setup.py install for mozinfo 10:41:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:41:33 INFO - Running setup.py install for mozInstall 10:41:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:41:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:41:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:41:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:41:33 INFO - Running setup.py install for mozleak 10:41:33 INFO - Running setup.py install for mozlog 10:41:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:41:33 INFO - Running setup.py install for moznetwork 10:41:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:41:33 INFO - Running setup.py install for mozprocess 10:41:33 INFO - Running setup.py install for mozprofile 10:41:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Running setup.py install for mozrunner 10:41:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Running setup.py install for mozscreenshot 10:41:34 INFO - Running setup.py install for moztest 10:41:34 INFO - Running setup.py install for mozversion 10:41:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:41:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:41:34 INFO - Cleaning up... 10:41:34 INFO - Return code: 0 10:41:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:34 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 0x10b65aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3dad76b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5bed30>, '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 0x10b323db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b6b20>, '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.Gq1gtteY1d/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.QnND0L8586/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:41:34 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 10:41:34 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 10:41:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gq1gtteY1d/Render', 10:41:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:34 INFO - 'HOME': '/Users/cltbld', 10:41:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:34 INFO - 'LOGNAME': 'cltbld', 10:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:34 INFO - 'MOZ_NO_REMOTE': '1', 10:41:34 INFO - 'NO_EM_RESTART': '1', 10:41:34 INFO - 'PAGER': '/bin/cat', 10:41:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:34 INFO - 'PWD': '/builds/slave/test', 10:41:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:34 INFO - 'SHELL': '/bin/bash', 10:41:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnND0L8586/Listeners', 10:41:34 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:41:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:34 INFO - 'USER': 'cltbld', 10:41:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:34 INFO - 'XPC_FLAGS': '0x0', 10:41:34 INFO - 'XPC_SERVICE_NAME': '0', 10:41:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:41:35 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-gtR1Gb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:41:35 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)) 10:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:41:35 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-jyJ4L4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:41:35 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)) 10:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:41:35 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-3ZI02p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:41:35 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)) 10:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:41:35 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-j2py_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:41:35 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)) 10:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:41:35 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-sBFxtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:41:35 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)) 10:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:41:35 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-4LwWhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:41:35 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)) 10:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:41:35 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-AqBlQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:41:35 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)) 10:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:41:35 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-gnagK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:41:35 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)) 10:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:41:35 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-MIQV2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:41:35 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)) 10:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:41:35 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-LByjGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:41:36 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-tsjdaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:41:36 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Q4OOyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:41:36 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-dXZ5fU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:41:36 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-QkqcyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:36 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-pjUvQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:41:36 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-BhdEbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:41:36 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)) 10:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:41:36 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-dv0Cx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:41:36 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)) 10:41:36 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)) 10:41:36 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)) 10:41:36 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)) 10:41:36 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)) 10:41:36 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)) 10:41:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:41:36 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 10:41:36 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 10:41:36 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 10:41:36 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 10:41:36 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 10:41:36 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 10:41:39 INFO - Downloading blessings-1.6.tar.gz 10:41:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:41:39 INFO - Installing collected packages: blessings 10:41:39 INFO - Running setup.py install for blessings 10:41:39 INFO - Successfully installed blessings 10:41:39 INFO - Cleaning up... 10:41:39 INFO - Return code: 0 10:41:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:41:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:41:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:41:39 INFO - Reading from file tmpfile_stdout 10:41:39 INFO - Current package versions: 10:41:39 INFO - blessings == 1.6 10:41:39 INFO - blobuploader == 1.2.4 10:41:39 INFO - docopt == 0.6.1 10:41:39 INFO - functools32 == 3.2.3-2 10:41:39 INFO - jsonschema == 2.5.1 10:41:39 INFO - manifestparser == 1.1 10:41:39 INFO - mozInstall == 1.12 10:41:39 INFO - mozcrash == 0.17 10:41:39 INFO - mozdebug == 0.1 10:41:39 INFO - mozdevice == 0.48 10:41:39 INFO - mozfile == 1.2 10:41:39 INFO - mozhttpd == 0.7 10:41:39 INFO - mozinfo == 0.9 10:41:39 INFO - mozleak == 0.1 10:41:39 INFO - mozlog == 3.3 10:41:39 INFO - moznetwork == 0.27 10:41:39 INFO - mozprocess == 0.23 10:41:39 INFO - mozprofile == 0.28 10:41:39 INFO - mozrunner == 6.12 10:41:39 INFO - mozscreenshot == 0.1 10:41:39 INFO - mozsystemmonitor == 0.3 10:41:39 INFO - moztest == 0.7 10:41:39 INFO - mozversion == 1.4 10:41:39 INFO - psutil == 3.1.1 10:41:39 INFO - requests == 1.2.3 10:41:39 INFO - wsgiref == 0.1.2 10:41:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41: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')]} 10:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:40 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 0x10b65aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3dad76b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5bed30>, '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 0x10b323db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b6b20>, '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.Gq1gtteY1d/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.QnND0L8586/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:41:40 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 10:41:40 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 10:41:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gq1gtteY1d/Render', 10:41:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:40 INFO - 'HOME': '/Users/cltbld', 10:41:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:40 INFO - 'LOGNAME': 'cltbld', 10:41:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:40 INFO - 'MOZ_NO_REMOTE': '1', 10:41:40 INFO - 'NO_EM_RESTART': '1', 10:41:40 INFO - 'PAGER': '/bin/cat', 10:41:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:40 INFO - 'PWD': '/builds/slave/test', 10:41:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:40 INFO - 'SHELL': '/bin/bash', 10:41:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnND0L8586/Listeners', 10:41:40 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:41:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:40 INFO - 'USER': 'cltbld', 10:41:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:40 INFO - 'XPC_FLAGS': '0x0', 10:41:40 INFO - 'XPC_SERVICE_NAME': '0', 10:41:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:40 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)) 10:41:40 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)) 10:41:40 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)) 10:41:40 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)) 10:41:40 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)) 10:41:40 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)) 10:41:40 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)) 10:41:40 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)) 10:41:40 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)) 10:41:40 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)) 10:41:40 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)) 10:41:40 INFO - Cleaning up... 10:41:40 INFO - Return code: 0 10:41:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b65aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3dad76b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5bed30>, '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 0x10b323db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b6b20>, '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.Gq1gtteY1d/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.QnND0L8586/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:41:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:41:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:41:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gq1gtteY1d/Render', 10:41:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:40 INFO - 'HOME': '/Users/cltbld', 10:41:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:40 INFO - 'LOGNAME': 'cltbld', 10:41:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:40 INFO - 'MOZ_NO_REMOTE': '1', 10:41:40 INFO - 'NO_EM_RESTART': '1', 10:41:40 INFO - 'PAGER': '/bin/cat', 10:41:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:40 INFO - 'PWD': '/builds/slave/test', 10:41:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:40 INFO - 'SHELL': '/bin/bash', 10:41:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnND0L8586/Listeners', 10:41:40 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:41:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:40 INFO - 'USER': 'cltbld', 10:41:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:40 INFO - 'XPC_FLAGS': '0x0', 10:41:40 INFO - 'XPC_SERVICE_NAME': '0', 10:41:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:41:40 INFO - Cleaning up... 10:41:40 INFO - Return code: 0 10:41:40 INFO - Running post-action listener: _resource_record_post_action 10:41:40 INFO - Running post-action listener: _start_resource_monitoring 10:41:40 INFO - Starting resource monitoring. 10:41:40 INFO - [mozharness: 2016-09-01 17:41:40.854318Z] Finished create-virtualenv step (success) 10:41:40 INFO - [mozharness: 2016-09-01 17:41:40.855286Z] Running install step. 10:41:40 INFO - Running pre-action listener: _resource_record_pre_action 10:41:40 INFO - Running main action method: install 10:41:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:41:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:41:41 INFO - Reading from file tmpfile_stdout 10:41:41 INFO - Detecting whether we're running mozinstall >=1.0... 10:41:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:41:41 INFO - Reading from file tmpfile_stdout 10:41:41 INFO - Output received: 10:41:41 INFO - Usage: mozinstall [options] installer 10:41:41 INFO - Options: 10:41:41 INFO - -h, --help show this help message and exit 10:41:41 INFO - -d DEST, --destination=DEST 10:41:41 INFO - Directory to install application into. [default: 10:41:41 INFO - "/builds/slave/test"] 10:41:41 INFO - --app=APP Application being installed. [default: firefox] 10:41:41 INFO - mkdir: /builds/slave/test/build/application 10:41:41 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'] 10:41:41 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 10:42:15 INFO - Reading from file tmpfile_stdout 10:42:15 INFO - Output received: 10:42:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:42:15 INFO - Running post-action listener: _resource_record_post_action 10:42:15 INFO - [mozharness: 2016-09-01 17:42:15.446073Z] Finished install step (success) 10:42:15 INFO - [mozharness: 2016-09-01 17:42:15.446224Z] Running run-tests step. 10:42:15 INFO - Running pre-action listener: _resource_record_pre_action 10:42:15 INFO - Running main action method: run_tests 10:42:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:42:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:42:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:42:15 INFO - 2016-09-01 10:42:15.460 screenresolution[1641:7296] starting screenresolution argv=screenresolution get 10:42:15 INFO - 2016-09-01 10:42:15.463 screenresolution[1641:7296] Display 0: 1600x1200x32@60 10:42:15 INFO - 2016-09-01 10:42:15.469 screenresolution[1642:7298] starting screenresolution argv=screenresolution list 10:42:15 INFO - Available Modes on Display 0 10:42:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:42:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:42:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:42:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:42:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:42:15 INFO - Intel Iris: 10:42:15 INFO - Chipset Model: Intel Iris 10:42:15 INFO - Type: GPU 10:42:15 INFO - Bus: Built-In 10:42:15 INFO - VRAM (Dynamic, Max): 1536 MB 10:42:15 INFO - Vendor: Intel (0x8086) 10:42:15 INFO - Device ID: 0x0a2e 10:42:15 INFO - Revision ID: 0x0009 10:42:15 INFO - Displays: 10:42:15 INFO - Display: 10:42:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:42:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:42:15 INFO - Display Serial Number: 1600x1200 60 10:42:15 INFO - Main Display: Yes 10:42:15 INFO - Mirror: Off 10:42:15 INFO - Online: Yes 10:42:15 INFO - Rotation: Supported 10:42:15 INFO - Return code: 0 10:42:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:42:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:42:15 INFO - Python 2.7.3 10:42:15 INFO - Return code: 0 10:42:15 INFO - grabbing minidump binary from tooltool 10:42:15 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')]} 10:42:15 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 0x10b5bed30>, '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 0x10b323db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:42:15 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 10:42:15 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 10:42:15 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 10:42:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:42:15 INFO - Return code: 0 10:42:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:42:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:42:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:42:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:42:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:42:15 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/NI0UzYkSQEGoh6D_E10sTw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--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 10:42:15 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/NI0UzYkSQEGoh6D_E10sTw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0332 --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 10:42:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gq1gtteY1d/Render', 10:42:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:15 INFO - 'HOME': '/Users/cltbld', 10:42:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:15 INFO - 'LOGNAME': 'cltbld', 10:42:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:42:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:42:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:15 INFO - 'MOZ_NO_REMOTE': '1', 10:42:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:42:15 INFO - 'NO_EM_RESTART': '1', 10:42:15 INFO - 'PAGER': '/bin/cat', 10:42:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:15 INFO - 'PWD': '/builds/slave/test', 10:42:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:42:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:15 INFO - 'SHELL': '/bin/bash', 10:42:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnND0L8586/Listeners', 10:42:15 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:42:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:15 INFO - 'USER': 'cltbld', 10:42:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:15 INFO - 'XPC_FLAGS': '0x0', 10:42:15 INFO - 'XPC_SERVICE_NAME': '0', 10:42:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:15 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/NI0UzYkSQEGoh6D_E10sTw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--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 10:42:16 INFO - mozversion application_buildid: 20160901084620 10:42:16 INFO - mozversion application_changeset: 18b2446bbf07efbcd62ea95009bfdb8177482edc 10:42:16 INFO - mozversion application_display_name: Nightly 10:42:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:42:16 INFO - mozversion application_name: Firefox 10:42:16 INFO - mozversion application_remotingname: firefox 10:42:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:42:16 INFO - mozversion application_vendor: Mozilla 10:42:16 INFO - mozversion application_version: 51.0a1 10:42:16 INFO - mozversion platform_buildid: 20160901084620 10:42:16 INFO - mozversion platform_changeset: 18b2446bbf07efbcd62ea95009bfdb8177482edc 10:42:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:42:16 INFO - mozversion platform_version: 51.0a1 10:42:16 INFO - using testdate: 1472751736 10:42:16 INFO - actual date: 1472751736 10:42:16 INFO - starting webserver on 'localhost:49262' 10:42:16 INFO - SUITE-START | Running 2 tests 10:42:16 INFO - TEST-START | dromaeo_css 10:42:16 INFO - operating with platform_type : mac_ 10:42:16 INFO - Initialising browser for dromaeo_css test... 10:42:16 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpn4amQm/profile http://localhost:49262/getInfo.html) 10:42:18 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 10:42:18 INFO - PROCESS | 1652 | colorDepth:24 10:42:18 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 10:42:18 INFO - PROCESS | 1652 | __metrics 10:42:18 INFO - PROCESS | 1652 | 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" 10:42:18 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:42:19 INFO - TEST-INFO | 1652: exit 0 10:42:19 INFO - Browser initialized. 10:42:19 INFO - Running cycle 1/1 for dromaeo_css test... 10:42:19 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpn4amQm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:42:31 INFO - PROCESS | 1654 | RSS: Main: 234278912 10:42:31 INFO - PROCESS | 1654 | 10:43:22 INFO - PROCESS | 1654 | 1472751802852 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] 10:45:35 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49262/tests/dromaeo/cssquery-ext.html) 10:45:36 INFO - PROCESS | 1654 | RSS: Main: 380059648 10:45:36 INFO - PROCESS | 1654 | 10:48:39 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-ext.html (next: http://localhost:49262/tests/dromaeo/cssquery-jquery.html) 10:48:39 INFO - PROCESS | 1654 | RSS: Main: 376287232 10:48:39 INFO - PROCESS | 1654 | 10:51:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49262/tests/dromaeo/cssquery-mootools.html) 10:51:42 INFO - PROCESS | 1654 | RSS: Main: 352256000 10:51:42 INFO - PROCESS | 1654 | 10:54:45 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49262/tests/dromaeo/cssquery-prototype.html) 10:54:45 INFO - PROCESS | 1654 | RSS: Main: 343244800 10:54:45 INFO - PROCESS | 1654 | 10:57:49 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49262/tests/dromaeo/cssquery-yui.html) 10:57:49 INFO - PROCESS | 1654 | RSS: Main: 325451776 10:57:49 INFO - PROCESS | 1654 | 11:00:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-yui.html (next: http://localhost:49262/tests/dromaeo/cssquery-dojo.html) 11:00:53 INFO - PROCESS | 1654 | RSS: Main: 421969920 11:00:53 INFO - PROCESS | 1654 | 11:00:53 INFO - PROCESS | 1654 | __start_tp_report 11:00:53 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 11:00:53 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 11:00:53 INFO - PROCESS | 1654 | |i|pagename|runs| 11:00:53 INFO - PROCESS | 1654 | |0;dojo.html;3303.372359251742;3316.5854268216467;3344.996501049683;3351.9329613407717;3410.215650400408;5635.126555383922;5696.715164241787;5737.426257374265;5744.511716504102;5773.047447171217;5682.886342273152;5714.77140914364;5722.7424765885535;5738.42615738426;5738.655680659163;5188.792448302072;5221.347331583552;5237.816676416326;5256.737163141846;5263.86840328991;4379.781010949456;5277.656952298088;5302.973485132569;5321.4412486688925;5323.068463018975;5205.193195054753;5215.035218484592;5234.6335756497065;5270.077736396115;5319.12234481312;5298.364196296469;5300.79054722166;5308.389535203462;5326.280952071498;5343.625946183769;5131.204663277198;5141.485851414866;5143.5113664201845;5231.895362092737;5244.134717771602;5186.273921650972;5281.15501519755;5681.687507187103;5749.137629355589;5798.043322851724;6256;6264.248290205204;6274.058891166316;6275.7489700411925;6283.842903927392;6145.938540614581;6221.751129954796;6273.811785646438;6274.403931626488;6346.2067241594805;5823.7379317929945;5835.124731290341;5840.59115861894;5865.76536938522;5891.410858914074;6292.528060395489;6292.653904035231;6306.842328941813;6307.621542707452;6322.810315690536;6050.788222412194;6075.908861367083;6102.095423428478;6207.068939659087;6209.96895015522;6253.530985176039;6305.621662700161;6319.747210111636;6335.588186767845;6338.841528961812;6191.690415479208;6266.874662506724;6268;6268.49852011838;6288.245410550763;6114.11489084418;6114.6636934969;6168.167297414881;6316.178896743394;6322.178116844872;4946.985867897072;4949.529794669501;4957.950420495749;4958.603311735053;5002.749862506933;4387.824487020547;4389.726979176067;4401.273789824707;4417.160739459557;4451.888702782457;4277.251480990814;5478.479544443271;5781.103928891029;5799.3620701722775;5812.041013232853;6251.593646412968;6286.748530058838;6294.433500984934;6306.527010474233;6320;5733.713314334266;5767.394423585547;5771.85570360736;5773.336066352339;5816.94183058164;5353.544948679328;5356.5179133877355;5455.563554915597;5456.236126942231;5463.31708536433;6246.469050130699;6284.528660350584;6299.874002520016;6323.968380158254;6349.333320001333;6330.8733825322315;6352.460040896391;6355.745770169141;6366.745330186741;6374.394432528902;6237.937620623918;6340.302566717565;6343.746250150127;6350.047492876106;6353.047042943595;6124.716609780976;6142.98993493194;6168.506519478341;6212.0371342441995;6279.874402511833;6324.841878953063;6328.272248691459;6333.334999825136;6334.303226644974;6344.841378965562;5778.670905691631;5906.911396329145;5996.430339117776;6117.694115294306;6141.815745527641;5964.850878728066;6065.54508411862;6072.908906366496;6077.48341390969;6107.175531303217;5983.192269043623;5984.760609575568;5988.431099045583;5988.610740301867;6003.219581454383;5983.132445795422;6020.638761674313;6128.66292353907;6135.877282454415;6145.9692701536205;5930.34766175729;5961.463468287875;5983.461488466059;6000.5799594028;6045.697715114139 11:00:53 INFO - PROCESS | 1654 | |1;ext.html;442.18637706619825;477.73724451551635;530.4059453412176;548.6598309048389;588.2734822494222;30757.231069223282;32168.195795105134;32208.99999999997;40252.99367515806;41534.792326038405;34733;42810.57189428105;42908.570914290925;42987;43063.35404968928;9629.325947183717;9788.951055244714;9790.657326993556;9791.755206119833;9806.754831129243;9594.616215351412;9705.61177552897;9706.029397060272;9716.465594392306;9737.367071140354;8492.957535212316;9629.470379129145;9686.273529485281;9738.610455581767;9743.707688769313;9652.034796520327;9704;9728.075832795874;9732.270079744048;9751.512424378823;9436.716898493056;9527.428354298694;9532.285078619061;9533.285003624687;9549.904500954972;40256;40499;40503.79748101241;40580.79709601463;40682.77951661425;3437.6218615952225;3511.7015053720465;3531.2054787672823;3535.202566708506;3801.8099095045413;29602;30364.089077327717;31063.378732425226;31183.064508064796;31536.211594710087;3020.5318175682805;3243.1243564237525;3291.7037466628117;3335.0495108893847;3379.4423920053164;434579.65420345706;533025;538716;539996;540066.2996684992;60120;60490;60918.69540652268;60942;61074.083888741705;54169;63796.68101659462;63863.36136638574;64144;64325.67837160784;15714;16361.672766544603;16389.42637007699;16625.418110366078;16727;12544.059195560369;12638.431270592662;12659.177153484994;12664.873351266553;12678.366081695878;16870.493885183463;17590.648187036186;18719.157637906326;18765;18878.24487020504;6712.865742685217;7701.884471732817;7862.960685196537;7890.447668663139;7923.603819808986;912.9224015958717;970.3983530211315;986.8174387738292;1009.6819501856892;1061.8513408122715;30076;30083.398332033237;30432;30627.693723062483;30715.078547643607;1078.487718333785;1126.7633796902578;1140.2645293785463;1157.7221466848064;1221.1635030004475;14295.07082039664;14668.413263469341;15411.778788604324;15488.457903973533;15721.68556628861;189049;189349;189770;190272;666334;56319.43680563142;56770.14844777332;56824;56976;57191;1272.923624582491;1340.4370164530922;1378.2901805570139;1387.1538361599619;1389.7637401641746;23601.881990589936;27471.45057098807;29153.562696559144;29369.97205097897;30050.3989920196;48215.5178448211;48499.78750531265;48593;48694;48772.5122748768;581276;582405;583774;583812.0809395926;584349;25750.613740793528;26124.477510450066;26163.345916352242;26212.73787262103;26236.73763262343;45743.08513829771;45866.312005319276;46325.76837115793;46809.2978605328;46983.29525057058;38246.617533824305;38539;38754;39128.804355978034;39220;38044.42933356057;38635.39276170053;39175.608243917195;39504.60495395009;39516.80241598774;36704.449433257985;37885.81057094807;38146.618533814304;38273.61726382812;38366;32966.175845604055;33362.999110026736;34036.65963340434;34334.31331373309;34356.96929092131;23294.30117096422;25140.497190055732;25205.991760330115;25458.872705636353;25799.871000645628 11:00:53 INFO - PROCESS | 1654 | |2;jquery.html;351.55879371388903;368.55589015236643;374.98125093745307;382.7492992090181;406.56497547624036;14827.11037337759;15081;20246.595068098628;20312.796872031275;20480.77115373077;19980.400587982385;20295.492612684655;20525.281615143434;20959.895200523977;20971.47571310718;5328.17413300937;5742.655440673562;5851.441694806392;6013.939860601404;6016.8495787605225;5856.882862342751;6051.334353221142;6056.06131049688;6056.212692349988;6065.393460653943;5714.200011998323;5861.677607731573;5950.761969521214;5989.341172471025;5997.430244126823;5886.5290776737975;5931.436513531207;5944.821655350346;5951.375105613914;5955.761769529214;5099.184130539096;5178.715170665612;5316.627836051478;5408.107662235727;5433.402325744165;12798.808017879739;12852.357382130856;12887.097903146783;13025.413856376392;13039;1814.7187185986193;2062.5874825035053;2102.48489120165;2297.241910169657;2326.790588846995;9606.75983100421;9627.037296270388;9643.614255429775;10106.191504679608;10203.285769996104;1773.813749556291;1788.3740690758132;1823.7811462624438;1841.1990784009;1899.3144132653122;119827.80172198254;128694.208760606;128799.35600322032;128988;129390;9433.622655093719;11231.26996745209;11455;11534.077273818075;11594.536218551164;14280.42878284857;14288.85711142896;14303.713925721428;14328.570142895729;14341.856581434053;8294;8410.327173826201;8442.957785211158;8447.408681392244;8593.140685931481;8423.536705481134;8441.915580844236;8627.43921645091;8628.396012279205;8629.180227878342;4359.934600980988;4427.933580996288;4450.310201918708;4494;4525.773711314487;1828.6434145341798;1840.3466769296706;1858.9628207435776;1882.1906580170657;1892.8769629974283;710.6923261199315;828.6892415344246;879.8108406692592;909.7998440343114;1019.1362272015512;20526.897365513374;20556.07497662609;21415.678764818542;21446.49121930243;21723.134753616436;711.7366574367517;927.2257664849926;936.180841763457;941.449252187478;952.219180272184;10577.524011419504;11054.613088541862;11132.944335278435;11164.553417863357;11189.944050279859;586018;587409.062954691;610272.2809403387;614373;627370.3050088271;93668;99343.50984735235;126386.7361326375;134613;142586.28706856398;851.2636569367453;947.7535840681335;980.5979548385128;980.9607615695293;983.1151963233004;15911.124888131264;17617.39147667066;18278;18294.62790290707;19556.90221548883;55636.72181639066;55901.16148257861;56501.71749141228;56693;56975.14537282027;76045.61977190079;77987.61006194932;78979;79816.60091699737;79933;6354.491640668828;7569.394448444001;7714.305712485903;8007.239312265326;8125.684180008906;10718.303310284806;12415.813762793747;12520.686982825502;12903.225806451643;12923.935380323039;10489.583906172875;10940.56237750481;11110.277831940897;11172.553097875994;11390.772184556428;10700.518476668723;10738.14094872424;10793.29843560166;11022.008019277982;11115.22193446451;10569.312994655324;11242.213045086766;11280.2667826591;11485.712857178638;11598.362090084789;6870.656467176721;7131.536450130725;7919.366450683816;7939.538742989461;8153.877691834508;7666.884996724942;7812.062552493737;7824.4522883399895;8204.507729536246;9473.715788526355 11:00:53 INFO - PROCESS | 1654 | |3;mootools.html;1322.775128228201;1486.8438813924533;1591.4191320168143;1821.5355084453463;1860.265195247878;7981.842632818238;8401.201885820845;8954.37319387643;8969.103089691027;8976.775580610489;7813.804654883631;8060.758177254677;8453.61958711858;8481.703140390087;8693;2342.75401082886;2378.4529558201625;2378.7264464586533;2379.5954687703047;2381.9761802382013;2333.708286464192;2356.9410764730846;2373.774491423312;2374.7031621047367;2376.298991797415;2014.879107253562;2353.670486131943;2359.834811563191;2370.4073981504625;2393.5571919194917;2337.3338598499518;2354.799842013422;2365.503244318678;2366.7988221001237;2374.905003799863;2343.0979073056824;2347.988260058689;2348.8825558722165;2351.576716191102;2356.0458014503984;8706;8921.821563568692;8928.508932008737;8929.73210803677;8944.776380590472;5079.771410286509;5186.92219616706;5329.733513324319;5334.013207556575;5334.253204551366;3453.5510383650217;3597.226596281812;3664.8900532984053;3669.6330366963357;3680.4663323817895;1048.3238311289251;1083.9241253112286;1090.1061129873508;1108.4734750993293;1108.6230681568225;11535.25020873649;11644.941775291069;11654.475548600249;11670.649880503599;11685.006774424184;4494.438195225597;4599.563041511118;4736.171170045228;4796.040791841646;4807.4471435784635;9891.604335826487;9893.01069893005;9893.45585992763;9897.030890732894;9918.603255869828;5334.333208348957;5336.252924590561;5338.199270109515;5339.973300133474;5352.785888564414;4414.183376075436;4561.908761824745;4747.95252047482;4853.878653033702;4894.143524883131;3851.9614803851605;3895.162540053902;3991.16185601027;4064.20747954152;4082.081531655413;1105.4969988655143;1294.643972907458;1296.4878872845172;1299.0381929201435;1314.3756715560223;774.5430196184278;820.2289847543291;835.3317346123199;870.9836727974719;894.9418287811402;5489.149181876815;5700.719712115104;5851.210086638334;6012.188354572164;6035.7283922223605;408.8793805827288;428.7363271588013;432.81172689880003;433.47982421094184;439.1524357989048;3084.568160457538;3833.9616603833606;3929.4891664083484;3999.480067591253;4063.2889244382113;10521.736956576147;10760.569577216824;10870.347779133277;10874.347539147677;10874.510647020903;10414.218933580012;10664.733381665365;10823.621173259056;10831.916808319264;10851.457427128613;8052.516848989079;8257.256846883809;8263.091059983397;8271.048829384697;8271.296939760068;4868.61051115917;5675.858103547444;5696.287964004499;5731.684757338387;5784.739686714192;9827.901720982985;10106.343087699275;10165.237607179639;10188.064267789096;10192.235582331206;9897.901020989986;10262.486875656337;10271.691849244535;10347;10367.94816025915;6796.592204467748;7013.333733295329;7163.713451461883;7427.331540161412;7471.88792168107;8117.715879944174;8436.789080273042;8445.704400345958;8446.873296900674;8455.196756308142;7041.155061392666;7070.893936590853;7492.250774922464;7517.774466766005;7549.282258387086;7142.5714457132735;7284.125904891235;7315.268473152855;7357.374623157091;7404.296591823768;6836.145481814773;6862.245153033063;6939.861202776017;6942.201646810479;6952.339527744856;4232.809523571385;4885.633577481632;4897.46127925935;4954.430240522242;4981.701097934136;4909.386326709161;5010;5017.799288028584;5079.77141028662;5082.567981721595 11:00:53 INFO - PROCESS | 1654 | |4;prototype.html;256.74453918351236;300.77892748829606;308.82551358482465;311.3492800047901;315.62125449460655;4582.564656357649;5241.2400201970695;5349.7057661828685;5395.919061214085;5410.188471729242;4517.661175411849;4967.509747075881;5167.173252279636;5206.843794686156;5262.4474430184755;4098.733582317147;4101.610347017028;4109.712320137576;4125.174965006996;4127.669786417079;4111.835526578933;4114.958850411503;4136.834526618932;4137.896552586194;4143.399207114967;3322.3687499375164;3415.777974431654;3824.7322687411893;4049.5950404959417;4066.7189835201966;3344.849481773314;4051.4733084699096;4055.1078762672164;4066.2477441673386;4068.8982775430554;2667.7865770738294;3159.162821852211;3444.431668774649;3479.68682818545;3486.9651303486894;4425.402570652953;4661.370714953506;5081.034603425337;5098.209777484496;5135.537801597837;4312.003157805327;4610.746408947506;4619.729574367042;4624.213883639789;4660.207764679979;5154.561862241714;5354;5614.971925140389;5616.32604087508;5621.550275977912;3234.67653234677;3465.1683595937047;3592.832329492899;3833.1792398610714;3833.3291673956946;4684.742339171345;4959.859232376573;5091.490850914879;5782.132680097977;5941.732622032019;3624.6012938576737;3679.0802299425145;3851.557070936878;3885.553161386477;3890.6692931101175;3348.9283429302877;3778.9433158502643;3968.206358728266;3974.4038394241097;3977.900552486153;4315.3095504719095;4576.382188404589;4608.193566125981;4647.070585882837;4658.883527911829;4657.74382408964;5194.4026436959475;5600.9673035414435;5663.575231857627;5679.119736440775;1188.781498963552;1244.3653736594222;1279.961601151967;1349.5748839115647;1366.644672385187;848.6478111583747;884.5577211394303;900.9234215091659;908.8545832666741;912.6896855069175;4593.793279302439;5017.67385119966;5180.533751962266;5269.183276592058;5291.973540132351;4440.978574927784;5735.9388513125205;5753.539716822645;5760.934227167903;5809.663777331238;4962.975185124052;5017.37282839645;5065.860181459142;5109;5185.377754669464;2804.9621639993334;2960.259935016246;2997.955030674542;3000.7599392048583;3011.4428830666398;5592.7762889483965;5875.500582450454;5891.440313170242;5903.675297858659;5969.403059694083;5881.500072493886;5894.96838053339;5899.345294617439;5908.675022873697;5911.674857882857;5428.6199966002005;5891.823245302648;5898.852528686817;5912.320083190402;5942.435468630473;4696.976515117402;5097.362829646294;5732.570057245806;5775.797847075332;5838.766449341979;4698.412698412699;4731.45588257341;5403.432639572946;5815.738291776795;5872.911906321494;5906.852328691645;5997.580169388101;6092;6107.206063211754;6136.478399336106;3826.7895265759535;3856.305864944337;3898;3906.5312162540677;3914.0801911551334;4926.901461970812;5877.324962385699;5878.882422351615;5926.348101708722;5951.345352011362;5105;5420.214068960057;5492.791585851106;5670.631408958405;5778.768849245983;5238.345206849144;5244.879296818387;5690.2887139107615;5701.8333033447025;5712.885742285214;5193.766280517461;5347.5828905340895;5387.111126664056;5568.2761241040025;5588.329400471969;4764.880877978078;5000.5999520037585;5557.972210138923;5655.406182350958;5735.483806457439;5460;5505.889882202254;5621.578381621313;5622.24099746529;5640.407757185437 11:00:53 INFO - PROCESS | 1654 | |5;yui.html;704.940080093192;716.1656669979474;742.1761844352769;769.2615089514073;771.3096778383347;15040.097594144345;15712;15726.685466290668;15728.370865165394;15758.76361854573;11972.940135299312;13256.602301930934;13725.93137034316;13796.448142074305;13805.37875795589;650.7754824585534;670.3062330487946;805.8589746794316;819.0703551469081;834.0575150080431;825.8100636853527;840.231188462556;842.6334544473143;843.1652663862762;844.679021971653;881.6737807011415;881.7619242804438;882.2544949517679;883.1654086887894;885.7696998780299;684.1550684904139;701.1446035836271;705.4849959529572;879.0725784297564;879.5162660536711;529.3145376737135;639.9392057754505;661.0018871504063;661.3584822721951;662.9204495460575;14877;14901.776473352907;14920.850791492163;14935.327910244065;15009.699806003928;13492.932535337359;13753.862461375358;13839.792403113961;13843.653908652264;14066.478426710279;12202.450889709895;12394.380280986004;12395.256284622952;12432.813507797391;12882.291473968928;4342.739435633841;4390.209762242795;4469.329600559942;4505.684602077856;4593.586577208067;121472.96317592042;122309;122623.3868830659;124762.3761881194;126351;9417.858732119024;10814.675559733221;10877.619283325046;11056.447177641086;11162.441877906072;22076.337709868407;22091.005904734324;22098.44753881166;22100.2264920727;22106.668399974013;7723.497972631761;7736.187700291388;7836.059672839296;7871.527708337518;7876.527408355517;4253.128108737701;4298.140371925628;4365.738055716667;4498.280275155959;4537.727736335831;3400.4219282721997;3476.687098161127;3494.685478306965;3509.280597477511;3516.771409858351;629.1569297141851;630.8012975912573;631.5326658272845;643.530222937249;653.7711800869657;553.3663954771764;600.6996501749126;604.7853012180612;621.869407424446;624.519120277393;8836.248918841971;8931.106889311017;8973.237274831581;9041.864372034424;9095.454272743656;474.49703314486067;511.0851575679492;530.3132443485696;632.9240491141093;655.8360409897525;4401.471823381215;4480.350349199413;4504.031633198878;4589.54104589545;4649.95350046502;146313.26843365715;151436;151623;155163.3627477921;158476;84388;86147.13852861516;112414;132266;138907.91638125436;103388;104063.4796826011;106735;108465.37301940477;112021;8667.566621669017;9552.95223523878;9927.40435573839;9934.105930466292;9955.402675839472;49047.754761225966;55723.721381394455;64051.679741600994;64542.67728661327;65579.67210163918;78557.82163267442;90541;95028;97825.51087244519;98788.50605747213;12047.698807529881;12727.809082863949;12854.485820567073;13295.534656286984;13297.26865022433;14291.57125286243;14561.92719036398;14807.925960370128;14992.855786812717;15077.396904123712;13459.596212113653;13717.0398072134;13966.231857247949;14127.9404044695;14132.858671413154;12018.699532511757;13564.660883477598;13612.115212511155;13855;14483.275836208359;11324.32054076758;11527.193096483166;11597.304161750322;11827.172097953406;11864.22882512634;7792.777741760124;9560.474173920502;9995.800083998134;10486.895131048897;11506.539738410369;9555.808883822145;10506.684799456028;11403;11460.598879039528;11578.652640420802 11:00:53 INFO - PROCESS | 1654 | __end_tp_report 11:00:53 INFO - PROCESS | 1654 | __start_cc_report 11:00:53 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1420 11:00:53 INFO - PROCESS | 1654 | __end_cc_report 11:00:53 INFO - PROCESS | 1654 | __startTimestamp1472752853355__endTimestamp 11:00:53 INFO - PROCESS | 1654 | 11:00:53 INFO - PROCESS | 1654 | ------- Summary: start ------- 11:00:53 INFO - PROCESS | 1654 | Number of tests: 6 11:00:53 INFO - PROCESS | 1654 | 11:00:53 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5754.21 Median:5983.46 stddev:664.15 (11.1%) stddev-sans-first:637.75 11:00:53 INFO - PROCESS | 1654 | Values: 3303.4 3316.6 3345.0 3351.9 3410.2 5635.1 5696.7 5737.4 5744.5 5773.0 5682.9 5714.8 5722.7 5738.4 5738.7 5188.8 5221.3 5237.8 5256.7 5263.9 4379.8 5277.7 5303.0 5321.4 5323.1 5205.2 5215.0 5234.6 5270.1 5319.1 5298.4 5300.8 5308.4 5326.3 5343.6 5131.2 5141.5 5143.5 5231.9 5244.1 5186.3 5281.2 5681.7 5749.1 5798.0 6256.0 6264.2 6274.1 6275.7 6283.8 6145.9 6221.8 6273.8 6274.4 6346.2 5823.7 5835.1 5840.6 5865.8 5891.4 6292.5 6292.7 6306.8 6307.6 6322.8 6050.8 6075.9 6102.1 6207.1 6210.0 6253.5 6305.6 6319.7 6335.6 6338.8 6191.7 6266.9 6268.0 6268.5 6288.2 6114.1 6114.7 6168.2 6316.2 6322.2 4947.0 4949.5 4958.0 4958.6 5002.7 4387.8 4389.7 4401.3 4417.2 4451.9 4277.3 5478.5 5781.1 5799.4 5812.0 6251.6 6286.7 6294.4 6306.5 6320.0 5733.7 5767.4 5771.9 5773.3 5816.9 5353.5 5356.5 5455.6 5456.2 5463.3 6246.5 6284.5 6299.9 6324.0 6349.3 6330.9 6352.5 6355.7 6366.7 6374.4 6237.9 6340.3 6343.7 6350.0 6353.0 6124.7 6143.0 6168.5 6212.0 6279.9 6324.8 6328.3 6333.3 6334.3 6344.8 5778.7 5906.9 5996.4 6117.7 6141.8 5964.9 6065.5 6072.9 6077.5 6107.2 5983.2 5984.8 5988.4 5988.6 6003.2 5983.1 6020.6 6128.7 6135.9 6146.0 5930.3 5961.5 5983.5 6000.6 6045.7 11:00:53 INFO - PROCESS | 1654 | 11:00:53 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:60985.45 Median:39152.21 stddev:132024.52 (337.2%) stddev-sans-first:132316.63 11:00:53 INFO - PROCESS | 1654 | Values: 442.2 477.7 530.4 548.7 588.3 30757.2 32168.2 32209.0 40253.0 41534.8 34733.0 42810.6 42908.6 42987.0 43063.4 9629.3 9789.0 9790.7 9791.8 9806.8 9594.6 9705.6 9706.0 9716.5 9737.4 8493.0 9629.5 9686.3 9738.6 9743.7 9652.0 9704.0 9728.1 9732.3 9751.5 9436.7 9527.4 9532.3 9533.3 9549.9 40256.0 40499.0 40503.8 40580.8 40682.8 3437.6 3511.7 3531.2 3535.2 3801.8 29602.0 30364.1 31063.4 31183.1 31536.2 3020.5 3243.1 3291.7 3335.0 3379.4 434579.7 533025.0 538716.0 539996.0 540066.3 60120.0 60490.0 60918.7 60942.0 61074.1 54169.0 63796.7 63863.4 64144.0 64325.7 15714.0 16361.7 16389.4 16625.4 16727.0 12544.1 12638.4 12659.2 12664.9 12678.4 16870.5 17590.6 18719.2 18765.0 18878.2 6712.9 7701.9 7863.0 7890.4 7923.6 912.9 970.4 986.8 1009.7 1061.9 30076.0 30083.4 30432.0 30627.7 30715.1 1078.5 1126.8 1140.3 1157.7 1221.2 14295.1 14668.4 15411.8 15488.5 15721.7 189049.0 189349.0 189770.0 190272.0 666334.0 56319.4 56770.1 56824.0 56976.0 57191.0 1272.9 1340.4 1378.3 1387.2 1389.8 23601.9 27471.5 29153.6 29370.0 30050.4 48215.5 48499.8 48593.0 48694.0 48772.5 581276.0 582405.0 583774.0 583812.1 584349.0 25750.6 26124.5 26163.3 26212.7 26236.7 45743.1 45866.3 46325.8 46809.3 46983.3 38246.6 38539.0 38754.0 39128.8 39220.0 38044.4 38635.4 39175.6 39504.6 39516.8 36704.4 37885.8 38146.6 38273.6 38366.0 32966.2 33363.0 34036.7 34334.3 34357.0 23294.3 25140.5 25206.0 25458.9 25799.9 11:00:53 INFO - PROCESS | 1654 | 11:00:53 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34761.94 Median:4393.93 stddev:101154.76 (2302.1%) stddev-sans-first:101405.53 11:00:53 INFO - PROCESS | 1654 | Values: 351.6 368.6 375.0 382.7 406.6 14827.1 15081.0 20246.6 20312.8 20480.8 19980.4 20295.5 20525.3 20959.9 20971.5 5328.2 5742.7 5851.4 6013.9 6016.8 5856.9 6051.3 6056.1 6056.2 6065.4 5714.2 5861.7 5950.8 5989.3 5997.4 5886.5 5931.4 5944.8 5951.4 5955.8 5099.2 5178.7 5316.6 5408.1 5433.4 12798.8 12852.4 12887.1 13025.4 13039.0 1814.7 2062.6 2102.5 2297.2 2326.8 9606.8 9627.0 9643.6 10106.2 10203.3 1773.8 1788.4 1823.8 1841.2 1899.3 119827.8 128694.2 128799.4 128988.0 129390.0 9433.6 11231.3 11455.0 11534.1 11594.5 14280.4 14288.9 14303.7 14328.6 14341.9 8294.0 8410.3 8443.0 8447.4 8593.1 8423.5 8441.9 8627.4 8628.4 8629.2 4359.9 4427.9 4450.3 4494.0 4525.8 1828.6 1840.3 1859.0 1882.2 1892.9 710.7 828.7 879.8 909.8 1019.1 20526.9 20556.1 21415.7 21446.5 21723.1 711.7 927.2 936.2 941.4 952.2 10577.5 11054.6 11132.9 11164.6 11189.9 586018.0 587409.1 610272.3 614373.0 627370.3 93668.0 99343.5 126386.7 134613.0 142586.3 851.3 947.8 980.6 981.0 983.1 15911.1 17617.4 18278.0 18294.6 19556.9 55636.7 55901.2 56501.7 56693.0 56975.1 76045.6 77987.6 78979.0 79816.6 79933.0 6354.5 7569.4 7714.3 8007.2 8125.7 10718.3 12415.8 12520.7 12903.2 12923.9 10489.6 10940.6 11110.3 11172.6 11390.8 10700.5 10738.1 10793.3 11022.0 11115.2 10569.3 11242.2 11280.3 11485.7 11598.4 6870.7 7131.5 7919.4 7939.5 8153.9 7666.9 7812.1 7824.5 8204.5 9473.7 11:00:53 INFO - PROCESS | 1654 | 11:00:53 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5667.40 Median:4801.74 stddev:3226.50 (67.2%) stddev-sans-first:3219.03 11:00:53 INFO - PROCESS | 1654 | Values: 1322.8 1486.8 1591.4 1821.5 1860.3 7981.8 8401.2 8954.4 8969.1 8976.8 7813.8 8060.8 8453.6 8481.7 8693.0 2342.8 2378.5 2378.7 2379.6 2382.0 2333.7 2356.9 2373.8 2374.7 2376.3 2014.9 2353.7 2359.8 2370.4 2393.6 2337.3 2354.8 2365.5 2366.8 2374.9 2343.1 2348.0 2348.9 2351.6 2356.0 8706.0 8921.8 8928.5 8929.7 8944.8 5079.8 5186.9 5329.7 5334.0 5334.3 3453.6 3597.2 3664.9 3669.6 3680.5 1048.3 1083.9 1090.1 1108.5 1108.6 11535.3 11644.9 11654.5 11670.6 11685.0 4494.4 4599.6 4736.2 4796.0 4807.4 9891.6 9893.0 9893.5 9897.0 9918.6 5334.3 5336.3 5338.2 5340.0 5352.8 4414.2 4561.9 4748.0 4853.9 4894.1 3852.0 3895.2 3991.2 4064.2 4082.1 1105.5 1294.6 1296.5 1299.0 1314.4 774.5 820.2 835.3 871.0 894.9 5489.1 5700.7 5851.2 6012.2 6035.7 408.9 428.7 432.8 433.5 439.2 3084.6 3834.0 3929.5 3999.5 4063.3 10521.7 10760.6 10870.3 10874.3 10874.5 10414.2 10664.7 10823.6 10831.9 10851.5 8052.5 8257.3 8263.1 8271.0 8271.3 4868.6 5675.9 5696.3 5731.7 5784.7 9827.9 10106.3 10165.2 10188.1 10192.2 9897.9 10262.5 10271.7 10347.0 10367.9 6796.6 7013.3 7163.7 7427.3 7471.9 8117.7 8436.8 8445.7 8446.9 8455.2 7041.2 7070.9 7492.3 7517.8 7549.3 7142.6 7284.1 7315.3 7357.4 7404.3 6836.1 6862.2 6939.9 6942.2 6952.3 4232.8 4885.6 4897.5 4954.4 4981.7 4909.4 5010.0 5017.8 5079.8 5082.6 11:00:53 INFO - PROCESS | 1654 | 11:00:53 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4528.48 Median:5086.26 stddev:1410.69 (27.7%) stddev-sans-first:1377.73 11:00:53 INFO - PROCESS | 1654 | Values: 256.7 300.8 308.8 311.3 315.6 4582.6 5241.2 5349.7 5395.9 5410.2 4517.7 4967.5 5167.2 5206.8 5262.4 4098.7 4101.6 4109.7 4125.2 4127.7 4111.8 4115.0 4136.8 4137.9 4143.4 3322.4 3415.8 3824.7 4049.6 4066.7 3344.8 4051.5 4055.1 4066.2 4068.9 2667.8 3159.2 3444.4 3479.7 3487.0 4425.4 4661.4 5081.0 5098.2 5135.5 4312.0 4610.7 4619.7 4624.2 4660.2 5154.6 5354.0 5615.0 5616.3 5621.6 3234.7 3465.2 3592.8 3833.2 3833.3 4684.7 4959.9 5091.5 5782.1 5941.7 3624.6 3679.1 3851.6 3885.6 3890.7 3348.9 3778.9 3968.2 3974.4 3977.9 4315.3 4576.4 4608.2 4647.1 4658.9 4657.7 5194.4 5601.0 5663.6 5679.1 1188.8 1244.4 1280.0 1349.6 1366.6 848.6 884.6 900.9 908.9 912.7 4593.8 5017.7 5180.5 5269.2 5292.0 4441.0 5735.9 5753.5 5760.9 5809.7 4963.0 5017.4 5065.9 5109.0 5185.4 2805.0 2960.3 2998.0 3000.8 3011.4 5592.8 5875.5 5891.4 5903.7 5969.4 5881.5 5895.0 5899.3 5908.7 5911.7 5428.6 5891.8 5898.9 5912.3 5942.4 4697.0 5097.4 5732.6 5775.8 5838.8 4698.4 4731.5 5403.4 5815.7 5872.9 5906.9 5997.6 6092.0 6107.2 6136.5 3826.8 3856.3 3898.0 3906.5 3914.1 4926.9 5877.3 5878.9 5926.3 5951.3 5105.0 5420.2 5492.8 5670.6 5778.8 5238.3 5244.9 5690.3 5701.8 5712.9 5193.8 5347.6 5387.1 5568.3 5588.3 4764.9 5000.6 5558.0 5655.4 5735.5 5460.0 5505.9 5621.6 5622.2 5640.4 11:00:53 INFO - PROCESS | 1654 | 11:00:53 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:24602.78 Median:4354.24 stddev:39615.71 (909.8%) stddev-sans-first:39686.21 11:00:53 INFO - PROCESS | 1654 | Values: 704.9 716.2 742.2 769.3 771.3 15040.1 15712.0 15726.7 15728.4 15758.8 11972.9 13256.6 13725.9 13796.4 13805.4 650.8 670.3 805.9 819.1 834.1 825.8 840.2 842.6 843.2 844.7 881.7 881.8 882.3 883.2 885.8 684.2 701.1 705.5 879.1 879.5 529.3 639.9 661.0 661.4 662.9 14877.0 14901.8 14920.9 14935.3 15009.7 13492.9 13753.9 13839.8 13843.7 14066.5 12202.5 12394.4 12395.3 12432.8 12882.3 4342.7 4390.2 4469.3 4505.7 4593.6 121473.0 122309.0 122623.4 124762.4 126351.0 9417.9 10814.7 10877.6 11056.4 11162.4 22076.3 22091.0 22098.4 22100.2 22106.7 7723.5 7736.2 7836.1 7871.5 7876.5 4253.1 4298.1 4365.7 4498.3 4537.7 3400.4 3476.7 3494.7 3509.3 3516.8 629.2 630.8 631.5 643.5 653.8 553.4 600.7 604.8 621.9 624.5 8836.2 8931.1 8973.2 9041.9 9095.5 474.5 511.1 530.3 632.9 655.8 4401.5 4480.4 4504.0 4589.5 4650.0 146313.3 151436.0 151623.0 155163.4 158476.0 84388.0 86147.1 112414.0 132266.0 138907.9 103388.0 104063.5 106735.0 108465.4 112021.0 8667.6 9553.0 9927.4 9934.1 9955.4 49047.8 55723.7 64051.7 64542.7 65579.7 78557.8 90541.0 95028.0 97825.5 98788.5 12047.7 12727.8 12854.5 13295.5 13297.3 14291.6 14561.9 14807.9 14992.9 15077.4 13459.6 13717.0 13966.2 14127.9 14132.9 12018.7 13564.7 13612.1 13855.0 14483.3 11324.3 11527.2 11597.3 11827.2 11864.2 7792.8 9560.5 9995.8 10486.9 11506.5 9555.8 10506.7 11403.0 11460.6 11578.7 11:00:53 INFO - PROCESS | 1654 | -------- Summary: end -------- 11:00:53 INFO - PROCESS | 1654 | 11:00:53 INFO - TEST-INFO | 1654: exit 0 11:00:53 INFO - TEST-OK | dromaeo_css | took 1117934ms 11:00:53 INFO - TEST-START | kraken 11:00:53 INFO - operating with platform_type : mac_ 11:00:53 INFO - Initialising browser for kraken test... 11:00:53 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmprVwZpZ/profile http://localhost:49262/getInfo.html) 11:00:56 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 11:00:56 INFO - PROCESS | 1665 | colorDepth:24 11:00:56 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 11:00:56 INFO - PROCESS | 1665 | __metrics 11:00:56 INFO - PROCESS | 1665 | 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" 11:00:56 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:00:56 INFO - TEST-INFO | 1665: exit 0 11:00:56 INFO - Browser initialized. 11:00:56 INFO - Running cycle 1/1 for kraken test... 11:00:56 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmprVwZpZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:01:08 INFO - PROCESS | 1666 | RSS: Main: 234123264 11:01:08 INFO - PROCESS | 1666 | 11:01:11 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection) 11:01:12 INFO - PROCESS | 1666 | RSS: Main: 292810752 11:01:12 INFO - PROCESS | 1666 | 11:01:15 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-dft) 11:01:15 INFO - PROCESS | 1666 | RSS: Main: 288927744 11:01:15 INFO - PROCESS | 1666 | 11:01:19 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-fft) 11:01:19 INFO - PROCESS | 1666 | RSS: Main: 380784640 11:01:19 INFO - PROCESS | 1666 | 11:01:22 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator) 11:01:22 INFO - PROCESS | 1666 | RSS: Main: 283607040 11:01:22 INFO - PROCESS | 1666 | 11:01:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:01:25 INFO - PROCESS | 1666 | RSS: Main: 287408128 11:01:25 INFO - PROCESS | 1666 | 11:01:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom) 11:01:28 INFO - PROCESS | 1666 | RSS: Main: 325443584 11:01:28 INFO - PROCESS | 1666 | 11:01:31 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate) 11:01:32 INFO - PROCESS | 1666 | RSS: Main: 325947392 11:01:32 INFO - PROCESS | 1666 | 11:01:35 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial) 11:01:35 INFO - PROCESS | 1666 | RSS: Main: 324411392 11:01:35 INFO - PROCESS | 1666 | 11:01:37 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:01:37 INFO - PROCESS | 1666 | RSS: Main: 297811968 11:01:37 INFO - PROCESS | 1666 | 11:01:39 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:01:40 INFO - PROCESS | 1666 | RSS: Main: 305004544 11:01:40 INFO - PROCESS | 1666 | 11:01:42 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:01:43 INFO - PROCESS | 1666 | RSS: Main: 304037888 11:01:43 INFO - PROCESS | 1666 | 11:01:46 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:01:46 INFO - PROCESS | 1666 | RSS: Main: 319418368 11:01:46 INFO - PROCESS | 1666 | 11:01:49 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:01:50 INFO - PROCESS | 1666 | RSS: Main: 315658240 11:01:50 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49262/tests/kraken/driver.html?testName=ai-astar) 11:01:52 INFO - PROCESS | 1666 | RSS: Main: 359440384 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | __start_tp_report 11:01:52 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 11:01:52 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 11:01:52 INFO - PROCESS | 1666 | |i|pagename|runs| 11:01:52 INFO - PROCESS | 1666 | |0;ai-astar;114;114;115;112;113;114;114;113;112;123 11:01:52 INFO - PROCESS | 1666 | |1;audio-beat-detection;146;118;161;196;108;128;127;108;179;106 11:01:52 INFO - PROCESS | 1666 | |2;audio-dft;185;183;182;182;185;187;260;181;185;182 11:01:52 INFO - PROCESS | 1666 | |3;audio-fft;77;91;105;189;49;60;82;84;144;57 11:01:52 INFO - PROCESS | 1666 | |4;audio-oscillator;82;83;135;70;71;70;123;69;69;117 11:01:52 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;102;131;115;88;91;87;91;88;91;87 11:01:52 INFO - PROCESS | 1666 | |6;imaging-darkroom;96;94;95;98;94;94;95;97;95;94 11:01:52 INFO - PROCESS | 1666 | |7;imaging-desaturate;86;85;83;85;85;85;86;89;75;133 11:01:52 INFO - PROCESS | 1666 | |8;json-parse-financial;55;53;54;54;54;55;55;55;53;55 11:01:52 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;68;66;73;74;73;77;72;72;71;69 11:01:52 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;85;84;85;85;85;84;86;86;91;85 11:01:52 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;142;145;154;138;138;136;149;153;177;154 11:01:52 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;170;191;175;175;173;169;175;168;171;172 11:01:52 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;68;67;70;67;67;67;69;66;68;70 11:01:52 INFO - PROCESS | 1666 | __end_tp_report 11:01:52 INFO - PROCESS | 1666 | __start_cc_report 11:01:52 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2041 11:01:52 INFO - PROCESS | 1666 | __end_cc_report 11:01:52 INFO - PROCESS | 1666 | __startTimestamp1472752912487__endTimestamp 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | ------- Summary: start ------- 11:01:52 INFO - PROCESS | 1666 | Number of tests: 14 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:114.40 Median:114.00 stddev:3.17 (2.8%) stddev-sans-first:3.36 11:01:52 INFO - PROCESS | 1666 | Values: 114.0 114.0 115.0 112.0 113.0 114.0 114.0 113.0 112.0 123.0 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:137.00 stddev:31.77 (23.2%) stddev-sans-first:33.55 11:01:52 INFO - PROCESS | 1666 | Values: 146.0 118.0 161.0 196.0 108.0 128.0 127.0 108.0 179.0 106.0 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:191.20 Median:185.00 stddev:24.25 (13.1%) stddev-sans-first:25.61 11:01:52 INFO - PROCESS | 1666 | Values: 185.0 183.0 182.0 182.0 185.0 187.0 260.0 181.0 185.0 182.0 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:93.80 Median:68.50 stddev:43.12 (63.0%) stddev-sans-first:45.31 11:01:52 INFO - PROCESS | 1666 | Values: 77.0 91.0 105.0 189.0 49.0 60.0 82.0 84.0 144.0 57.0 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:70.00 stddev:25.79 (36.8%) stddev-sans-first:27.24 11:01:52 INFO - PROCESS | 1666 | Values: 82.0 83.0 135.0 70.0 71.0 70.0 123.0 69.0 69.0 117.0 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:97.10 Median:88.00 stddev:14.81 (16.8%) stddev-sans-first:15.61 11:01:52 INFO - PROCESS | 1666 | Values: 102.0 131.0 115.0 88.0 91.0 87.0 91.0 88.0 91.0 87.0 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:95.00 stddev:1.40 (1.5%) stddev-sans-first:1.45 11:01:52 INFO - PROCESS | 1666 | Values: 96.0 94.0 95.0 98.0 94.0 94.0 95.0 97.0 95.0 94.0 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:89.20 Median:85.00 stddev:15.81 (18.6%) stddev-sans-first:16.73 11:01:52 INFO - PROCESS | 1666 | Values: 86.0 85.0 83.0 85.0 85.0 85.0 86.0 89.0 75.0 133.0 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:55.00 stddev:0.82 (1.5%) stddev-sans-first:0.83 11:01:52 INFO - PROCESS | 1666 | Values: 55.0 53.0 54.0 54.0 54.0 55.0 55.0 55.0 53.0 55.0 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.50 stddev:3.17 (4.4%) stddev-sans-first:3.10 11:01:52 INFO - PROCESS | 1666 | Values: 68.0 66.0 73.0 74.0 73.0 77.0 72.0 72.0 71.0 69.0 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:85.00 stddev:2.01 (2.4%) stddev-sans-first:2.12 11:01:52 INFO - PROCESS | 1666 | Values: 85.0 84.0 85.0 85.0 85.0 84.0 86.0 86.0 91.0 85.0 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:148.60 Median:151.00 stddev:12.13 (8.0%) stddev-sans-first:12.63 11:01:52 INFO - PROCESS | 1666 | Values: 142.0 145.0 154.0 138.0 138.0 136.0 149.0 153.0 177.0 154.0 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.90 Median:174.00 stddev:6.52 (3.7%) stddev-sans-first:6.76 11:01:52 INFO - PROCESS | 1666 | Values: 170.0 191.0 175.0 175.0 173.0 169.0 175.0 168.0 171.0 172.0 11:01:52 INFO - PROCESS | 1666 | 11:01:52 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:1.37 (2.0%) stddev-sans-first:1.45 11:01:52 INFO - PROCESS | 1666 | Values: 68.0 67.0 70.0 67.0 67.0 67.0 69.0 66.0 68.0 70.0 11:01:52 INFO - PROCESS | 1666 | -------- Summary: end -------- 11:01:52 INFO - PROCESS | 1666 | 11:01:53 INFO - TEST-INFO | 1666: exit 0 11:01:53 INFO - TEST-OK | kraken | took 59413ms 11:01:53 INFO - SUITE-END | took 1177s 11:01:53 INFO - Completed test suite (00:19:37) 11:01:53 INFO - javascript benchmark 11:01:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3303.372359251742, 3316.5854268216467, 3344.996501049683, 3351.9329613407717, 3410.215650400408, 5635.126555383922, 5696.715164241787, 5737.426257374265, 5744.511716504102, 5773.047447171217, 5682.886342273152, 5714.77140914364, 5722.7424765885535, 5738.42615738426, 5738.655680659163, 5188.792448302072, 5221.347331583552, 5237.816676416326, 5256.737163141846, 5263.86840328991, 4379.781010949456, 5277.656952298088, 5302.973485132569, 5321.4412486688925, 5323.068463018975, 5205.193195054753, 5215.035218484592, 5234.6335756497065, 5270.077736396115, 5319.12234481312, 5298.364196296469, 5300.79054722166, 5308.389535203462, 5326.280952071498, 5343.625946183769, 5131.204663277198, 5141.485851414866, 5143.5113664201845, 5231.895362092737, 5244.134717771602, 5186.273921650972, 5281.15501519755, 5681.687507187103, 5749.137629355589, 5798.043322851724, 6256.0, 6264.248290205204, 6274.058891166316, 6275.7489700411925, 6283.842903927392, 6145.938540614581, 6221.751129954796, 6273.811785646438, 6274.403931626488, 6346.2067241594805, 5823.7379317929945, 5835.124731290341, 5840.59115861894, 5865.76536938522, 5891.410858914074, 6292.528060395489, 6292.653904035231, 6306.842328941813, 6307.621542707452, 6322.810315690536, 6050.788222412194, 6075.908861367083, 6102.095423428478, 6207.068939659087, 6209.96895015522, 6253.530985176039, 6305.621662700161, 6319.747210111636, 6335.588186767845, 6338.841528961812, 6191.690415479208, 6266.874662506724, 6268.0, 6268.49852011838, 6288.245410550763, 6114.11489084418, 6114.6636934969, 6168.167297414881, 6316.178896743394, 6322.178116844872, 4946.985867897072, 4949.529794669501, 4957.950420495749, 4958.603311735053, 5002.749862506933, 4387.824487020547, 4389.726979176067, 4401.273789824707, 4417.160739459557, 4451.888702782457, 4277.251480990814, 5478.479544443271, 5781.103928891029, 5799.3620701722775, 5812.041013232853, 6251.593646412968, 6286.748530058838, 6294.433500984934, 6306.527010474233, 6320.0, 5733.713314334266, 5767.394423585547, 5771.85570360736, 5773.336066352339, 5816.94183058164, 5353.544948679328, 5356.5179133877355, 5455.563554915597, 5456.236126942231, 5463.31708536433, 6246.469050130699, 6284.528660350584, 6299.874002520016, 6323.968380158254, 6349.333320001333, 6330.8733825322315, 6352.460040896391, 6355.745770169141, 6366.745330186741, 6374.394432528902, 6237.937620623918, 6340.302566717565, 6343.746250150127, 6350.047492876106, 6353.047042943595, 6124.716609780976, 6142.98993493194, 6168.506519478341, 6212.0371342441995, 6279.874402511833, 6324.841878953063, 6328.272248691459, 6333.334999825136, 6334.303226644974, 6344.841378965562, 5778.670905691631, 5906.911396329145, 5996.430339117776, 6117.694115294306, 6141.815745527641, 5964.850878728066, 6065.54508411862, 6072.908906366496, 6077.48341390969, 6107.175531303217, 5983.192269043623, 5984.760609575568, 5988.431099045583, 5988.610740301867, 6003.219581454383, 5983.132445795422, 6020.638761674313, 6128.66292353907, 6135.877282454415, 6145.9692701536205, 5930.34766175729, 5961.463468287875, 5983.461488466059, 6000.5799594028, 6045.697715114139], "value": 5710.817505203207, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [442.18637706619825, 477.73724451551635, 530.4059453412176, 548.6598309048389, 588.2734822494222, 30757.231069223282, 32168.195795105134, 32208.99999999997, 40252.99367515806, 41534.792326038405, 34733.0, 42810.57189428105, 42908.570914290925, 42987.0, 43063.35404968928, 9629.325947183717, 9788.951055244714, 9790.657326993556, 9791.755206119833, 9806.754831129243, 9594.616215351412, 9705.61177552897, 9706.029397060272, 9716.465594392306, 9737.367071140354, 8492.957535212316, 9629.470379129145, 9686.273529485281, 9738.610455581767, 9743.707688769313, 9652.034796520327, 9704.0, 9728.075832795874, 9732.270079744048, 9751.512424378823, 9436.716898493056, 9527.428354298694, 9532.285078619061, 9533.285003624687, 9549.904500954972, 40256.0, 40499.0, 40503.79748101241, 40580.79709601463, 40682.77951661425, 3437.6218615952225, 3511.7015053720465, 3531.2054787672823, 3535.202566708506, 3801.8099095045413, 29602.0, 30364.089077327717, 31063.378732425226, 31183.064508064796, 31536.211594710087, 3020.5318175682805, 3243.1243564237525, 3291.7037466628117, 3335.0495108893847, 3379.4423920053164, 434579.65420345706, 533025.0, 538716.0, 539996.0, 540066.2996684992, 60120.0, 60490.0, 60918.69540652268, 60942.0, 61074.083888741705, 54169.0, 63796.68101659462, 63863.36136638574, 64144.0, 64325.67837160784, 15714.0, 16361.672766544603, 16389.42637007699, 16625.418110366078, 16727.0, 12544.059195560369, 12638.431270592662, 12659.177153484994, 12664.873351266553, 12678.366081695878, 16870.493885183463, 17590.648187036186, 18719.157637906326, 18765.0, 18878.24487020504, 6712.865742685217, 7701.884471732817, 7862.960685196537, 7890.447668663139, 7923.603819808986, 912.9224015958717, 970.3983530211315, 986.8174387738292, 1009.6819501856892, 1061.8513408122715, 30076.0, 30083.398332033237, 30432.0, 30627.693723062483, 30715.078547643607, 1078.487718333785, 1126.7633796902578, 1140.2645293785463, 1157.7221466848064, 1221.1635030004475, 14295.07082039664, 14668.413263469341, 15411.778788604324, 15488.457903973533, 15721.68556628861, 189049.0, 189349.0, 189770.0, 190272.0, 666334.0, 56319.43680563142, 56770.14844777332, 56824.0, 56976.0, 57191.0, 1272.923624582491, 1340.4370164530922, 1378.2901805570139, 1387.1538361599619, 1389.7637401641746, 23601.881990589936, 27471.45057098807, 29153.562696559144, 29369.97205097897, 30050.3989920196, 48215.5178448211, 48499.78750531265, 48593.0, 48694.0, 48772.5122748768, 581276.0, 582405.0, 583774.0, 583812.0809395926, 584349.0, 25750.613740793528, 26124.477510450066, 26163.345916352242, 26212.73787262103, 26236.73763262343, 45743.08513829771, 45866.312005319276, 46325.76837115793, 46809.2978605328, 46983.29525057058, 38246.617533824305, 38539.0, 38754.0, 39128.804355978034, 39220.0, 38044.42933356057, 38635.39276170053, 39175.608243917195, 39504.60495395009, 39516.80241598774, 36704.449433257985, 37885.81057094807, 38146.618533814304, 38273.61726382812, 38366.0, 32966.175845604055, 33362.999110026736, 34036.65963340434, 34334.31331373309, 34356.96929092131, 23294.30117096422, 25140.497190055732, 25205.991760330115, 25458.872705636353, 25799.871000645628], "value": 19480.875149700994, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.55879371388903, 368.55589015236643, 374.98125093745307, 382.7492992090181, 406.56497547624036, 14827.11037337759, 15081.0, 20246.595068098628, 20312.796872031275, 20480.77115373077, 19980.400587982385, 20295.492612684655, 20525.281615143434, 20959.895200523977, 20971.47571310718, 5328.17413300937, 5742.655440673562, 5851.441694806392, 6013.939860601404, 6016.8495787605225, 5856.882862342751, 6051.334353221142, 6056.06131049688, 6056.212692349988, 6065.393460653943, 5714.200011998323, 5861.677607731573, 5950.761969521214, 5989.341172471025, 5997.430244126823, 5886.5290776737975, 5931.436513531207, 5944.821655350346, 5951.375105613914, 5955.761769529214, 5099.184130539096, 5178.715170665612, 5316.627836051478, 5408.107662235727, 5433.402325744165, 12798.808017879739, 12852.357382130856, 12887.097903146783, 13025.413856376392, 13039.0, 1814.7187185986193, 2062.5874825035053, 2102.48489120165, 2297.241910169657, 2326.790588846995, 9606.75983100421, 9627.037296270388, 9643.614255429775, 10106.191504679608, 10203.285769996104, 1773.813749556291, 1788.3740690758132, 1823.7811462624438, 1841.1990784009, 1899.3144132653122, 119827.80172198254, 128694.208760606, 128799.35600322032, 128988.0, 129390.0, 9433.622655093719, 11231.26996745209, 11455.0, 11534.077273818075, 11594.536218551164, 14280.42878284857, 14288.85711142896, 14303.713925721428, 14328.570142895729, 14341.856581434053, 8294.0, 8410.327173826201, 8442.957785211158, 8447.408681392244, 8593.140685931481, 8423.536705481134, 8441.915580844236, 8627.43921645091, 8628.396012279205, 8629.180227878342, 4359.934600980988, 4427.933580996288, 4450.310201918708, 4494.0, 4525.773711314487, 1828.6434145341798, 1840.3466769296706, 1858.9628207435776, 1882.1906580170657, 1892.8769629974283, 710.6923261199315, 828.6892415344246, 879.8108406692592, 909.7998440343114, 1019.1362272015512, 20526.897365513374, 20556.07497662609, 21415.678764818542, 21446.49121930243, 21723.134753616436, 711.7366574367517, 927.2257664849926, 936.180841763457, 941.449252187478, 952.219180272184, 10577.524011419504, 11054.613088541862, 11132.944335278435, 11164.553417863357, 11189.944050279859, 586018.0, 587409.062954691, 610272.2809403387, 614373.0, 627370.3050088271, 93668.0, 99343.50984735235, 126386.7361326375, 134613.0, 142586.28706856398, 851.2636569367453, 947.7535840681335, 980.5979548385128, 980.9607615695293, 983.1151963233004, 15911.124888131264, 17617.39147667066, 18278.0, 18294.62790290707, 19556.90221548883, 55636.72181639066, 55901.16148257861, 56501.71749141228, 56693.0, 56975.14537282027, 76045.61977190079, 77987.61006194932, 78979.0, 79816.60091699737, 79933.0, 6354.491640668828, 7569.394448444001, 7714.305712485903, 8007.239312265326, 8125.684180008906, 10718.303310284806, 12415.813762793747, 12520.686982825502, 12903.225806451643, 12923.935380323039, 10489.583906172875, 10940.56237750481, 11110.277831940897, 11172.553097875994, 11390.772184556428, 10700.518476668723, 10738.14094872424, 10793.29843560166, 11022.008019277982, 11115.22193446451, 10569.312994655324, 11242.213045086766, 11280.2667826591, 11485.712857178638, 11598.362090084789, 6870.656467176721, 7131.536450130725, 7919.366450683816, 7939.538742989461, 8153.877691834508, 7666.884996724942, 7812.062552493737, 7824.4522883399895, 8204.507729536246, 9473.715788526355], "value": 9153.847464822169, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.775128228201, 1486.8438813924533, 1591.4191320168143, 1821.5355084453463, 1860.265195247878, 7981.842632818238, 8401.201885820845, 8954.37319387643, 8969.103089691027, 8976.775580610489, 7813.804654883631, 8060.758177254677, 8453.61958711858, 8481.703140390087, 8693.0, 2342.75401082886, 2378.4529558201625, 2378.7264464586533, 2379.5954687703047, 2381.9761802382013, 2333.708286464192, 2356.9410764730846, 2373.774491423312, 2374.7031621047367, 2376.298991797415, 2014.879107253562, 2353.670486131943, 2359.834811563191, 2370.4073981504625, 2393.5571919194917, 2337.3338598499518, 2354.799842013422, 2365.503244318678, 2366.7988221001237, 2374.905003799863, 2343.0979073056824, 2347.988260058689, 2348.8825558722165, 2351.576716191102, 2356.0458014503984, 8706.0, 8921.821563568692, 8928.508932008737, 8929.73210803677, 8944.776380590472, 5079.771410286509, 5186.92219616706, 5329.733513324319, 5334.013207556575, 5334.253204551366, 3453.5510383650217, 3597.226596281812, 3664.8900532984053, 3669.6330366963357, 3680.4663323817895, 1048.3238311289251, 1083.9241253112286, 1090.1061129873508, 1108.4734750993293, 1108.6230681568225, 11535.25020873649, 11644.941775291069, 11654.475548600249, 11670.649880503599, 11685.006774424184, 4494.438195225597, 4599.563041511118, 4736.171170045228, 4796.040791841646, 4807.4471435784635, 9891.604335826487, 9893.01069893005, 9893.45585992763, 9897.030890732894, 9918.603255869828, 5334.333208348957, 5336.252924590561, 5338.199270109515, 5339.973300133474, 5352.785888564414, 4414.183376075436, 4561.908761824745, 4747.95252047482, 4853.878653033702, 4894.143524883131, 3851.9614803851605, 3895.162540053902, 3991.16185601027, 4064.20747954152, 4082.081531655413, 1105.4969988655143, 1294.643972907458, 1296.4878872845172, 1299.0381929201435, 1314.3756715560223, 774.5430196184278, 820.2289847543291, 835.3317346123199, 870.9836727974719, 894.9418287811402, 5489.149181876815, 5700.719712115104, 5851.210086638334, 6012.188354572164, 6035.7283922223605, 408.8793805827288, 428.7363271588013, 432.81172689880003, 433.47982421094184, 439.1524357989048, 3084.568160457538, 3833.9616603833606, 3929.4891664083484, 3999.480067591253, 4063.2889244382113, 10521.736956576147, 10760.569577216824, 10870.347779133277, 10874.347539147677, 10874.510647020903, 10414.218933580012, 10664.733381665365, 10823.621173259056, 10831.916808319264, 10851.457427128613, 8052.516848989079, 8257.256846883809, 8263.091059983397, 8271.048829384697, 8271.296939760068, 4868.61051115917, 5675.858103547444, 5696.287964004499, 5731.684757338387, 5784.739686714192, 9827.901720982985, 10106.343087699275, 10165.237607179639, 10188.064267789096, 10192.235582331206, 9897.901020989986, 10262.486875656337, 10271.691849244535, 10347.0, 10367.94816025915, 6796.592204467748, 7013.333733295329, 7163.713451461883, 7427.331540161412, 7471.88792168107, 8117.715879944174, 8436.789080273042, 8445.704400345958, 8446.873296900674, 8455.196756308142, 7041.155061392666, 7070.893936590853, 7492.250774922464, 7517.774466766005, 7549.282258387086, 7142.5714457132735, 7284.125904891235, 7315.268473152855, 7357.374623157091, 7404.296591823768, 6836.145481814773, 6862.245153033063, 6939.861202776017, 6942.201646810479, 6952.339527744856, 4232.809523571385, 4885.633577481632, 4897.46127925935, 4954.430240522242, 4981.701097934136, 4909.386326709161, 5010.0, 5017.799288028584, 5079.77141028662, 5082.567981721595], "value": 4460.870844746199, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.74453918351236, 300.77892748829606, 308.82551358482465, 311.3492800047901, 315.62125449460655, 4582.564656357649, 5241.2400201970695, 5349.7057661828685, 5395.919061214085, 5410.188471729242, 4517.661175411849, 4967.509747075881, 5167.173252279636, 5206.843794686156, 5262.4474430184755, 4098.733582317147, 4101.610347017028, 4109.712320137576, 4125.174965006996, 4127.669786417079, 4111.835526578933, 4114.958850411503, 4136.834526618932, 4137.896552586194, 4143.399207114967, 3322.3687499375164, 3415.777974431654, 3824.7322687411893, 4049.5950404959417, 4066.7189835201966, 3344.849481773314, 4051.4733084699096, 4055.1078762672164, 4066.2477441673386, 4068.8982775430554, 2667.7865770738294, 3159.162821852211, 3444.431668774649, 3479.68682818545, 3486.9651303486894, 4425.402570652953, 4661.370714953506, 5081.034603425337, 5098.209777484496, 5135.537801597837, 4312.003157805327, 4610.746408947506, 4619.729574367042, 4624.213883639789, 4660.207764679979, 5154.561862241714, 5354.0, 5614.971925140389, 5616.32604087508, 5621.550275977912, 3234.67653234677, 3465.1683595937047, 3592.832329492899, 3833.1792398610714, 3833.3291673956946, 4684.742339171345, 4959.859232376573, 5091.490850914879, 5782.132680097977, 5941.732622032019, 3624.6012938576737, 3679.0802299425145, 3851.557070936878, 3885.553161386477, 3890.6692931101175, 3348.9283429302877, 3778.9433158502643, 3968.206358728266, 3974.4038394241097, 3977.900552486153, 4315.3095504719095, 4576.382188404589, 4608.193566125981, 4647.070585882837, 4658.883527911829, 4657.74382408964, 5194.4026436959475, 5600.9673035414435, 5663.575231857627, 5679.119736440775, 1188.781498963552, 1244.3653736594222, 1279.961601151967, 1349.5748839115647, 1366.644672385187, 848.6478111583747, 884.5577211394303, 900.9234215091659, 908.8545832666741, 912.6896855069175, 4593.793279302439, 5017.67385119966, 5180.533751962266, 5269.183276592058, 5291.973540132351, 4440.978574927784, 5735.9388513125205, 5753.539716822645, 5760.934227167903, 5809.663777331238, 4962.975185124052, 5017.37282839645, 5065.860181459142, 5109.0, 5185.377754669464, 2804.9621639993334, 2960.259935016246, 2997.955030674542, 3000.7599392048583, 3011.4428830666398, 5592.7762889483965, 5875.500582450454, 5891.440313170242, 5903.675297858659, 5969.403059694083, 5881.500072493886, 5894.96838053339, 5899.345294617439, 5908.675022873697, 5911.674857882857, 5428.6199966002005, 5891.823245302648, 5898.852528686817, 5912.320083190402, 5942.435468630473, 4696.976515117402, 5097.362829646294, 5732.570057245806, 5775.797847075332, 5838.766449341979, 4698.412698412699, 4731.45588257341, 5403.432639572946, 5815.738291776795, 5872.911906321494, 5906.852328691645, 5997.580169388101, 6092.0, 6107.206063211754, 6136.478399336106, 3826.7895265759535, 3856.305864944337, 3898.0, 3906.5312162540677, 3914.0801911551334, 4926.901461970812, 5877.324962385699, 5878.882422351615, 5926.348101708722, 5951.345352011362, 5105.0, 5420.214068960057, 5492.791585851106, 5670.631408958405, 5778.768849245983, 5238.345206849144, 5244.879296818387, 5690.2887139107615, 5701.8333033447025, 5712.885742285214, 5193.766280517461, 5347.5828905340895, 5387.111126664056, 5568.2761241040025, 5588.329400471969, 4764.880877978078, 5000.5999520037585, 5557.972210138923, 5655.406182350958, 5735.483806457439, 5460.0, 5505.889882202254, 5621.578381621313, 5622.24099746529, 5640.407757185437], "value": 4076.518908589945, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.940080093192, 716.1656669979474, 742.1761844352769, 769.2615089514073, 771.3096778383347, 15040.097594144345, 15712.0, 15726.685466290668, 15728.370865165394, 15758.76361854573, 11972.940135299312, 13256.602301930934, 13725.93137034316, 13796.448142074305, 13805.37875795589, 650.7754824585534, 670.3062330487946, 805.8589746794316, 819.0703551469081, 834.0575150080431, 825.8100636853527, 840.231188462556, 842.6334544473143, 843.1652663862762, 844.679021971653, 881.6737807011415, 881.7619242804438, 882.2544949517679, 883.1654086887894, 885.7696998780299, 684.1550684904139, 701.1446035836271, 705.4849959529572, 879.0725784297564, 879.5162660536711, 529.3145376737135, 639.9392057754505, 661.0018871504063, 661.3584822721951, 662.9204495460575, 14877.0, 14901.776473352907, 14920.850791492163, 14935.327910244065, 15009.699806003928, 13492.932535337359, 13753.862461375358, 13839.792403113961, 13843.653908652264, 14066.478426710279, 12202.450889709895, 12394.380280986004, 12395.256284622952, 12432.813507797391, 12882.291473968928, 4342.739435633841, 4390.209762242795, 4469.329600559942, 4505.684602077856, 4593.586577208067, 121472.96317592042, 122309.0, 122623.3868830659, 124762.3761881194, 126351.0, 9417.858732119024, 10814.675559733221, 10877.619283325046, 11056.447177641086, 11162.441877906072, 22076.337709868407, 22091.005904734324, 22098.44753881166, 22100.2264920727, 22106.668399974013, 7723.497972631761, 7736.187700291388, 7836.059672839296, 7871.527708337518, 7876.527408355517, 4253.128108737701, 4298.140371925628, 4365.738055716667, 4498.280275155959, 4537.727736335831, 3400.4219282721997, 3476.687098161127, 3494.685478306965, 3509.280597477511, 3516.771409858351, 629.1569297141851, 630.8012975912573, 631.5326658272845, 643.530222937249, 653.7711800869657, 553.3663954771764, 600.6996501749126, 604.7853012180612, 621.869407424446, 624.519120277393, 8836.248918841971, 8931.106889311017, 8973.237274831581, 9041.864372034424, 9095.454272743656, 474.49703314486067, 511.0851575679492, 530.3132443485696, 632.9240491141093, 655.8360409897525, 4401.471823381215, 4480.350349199413, 4504.031633198878, 4589.54104589545, 4649.95350046502, 146313.26843365715, 151436.0, 151623.0, 155163.3627477921, 158476.0, 84388.0, 86147.13852861516, 112414.0, 132266.0, 138907.91638125436, 103388.0, 104063.4796826011, 106735.0, 108465.37301940477, 112021.0, 8667.566621669017, 9552.95223523878, 9927.40435573839, 9934.105930466292, 9955.402675839472, 49047.754761225966, 55723.721381394455, 64051.679741600994, 64542.67728661327, 65579.67210163918, 78557.82163267442, 90541.0, 95028.0, 97825.51087244519, 98788.50605747213, 12047.698807529881, 12727.809082863949, 12854.485820567073, 13295.534656286984, 13297.26865022433, 14291.57125286243, 14561.92719036398, 14807.925960370128, 14992.855786812717, 15077.396904123712, 13459.596212113653, 13717.0398072134, 13966.231857247949, 14127.9404044695, 14132.858671413154, 12018.699532511757, 13564.660883477598, 13612.115212511155, 13855.0, 14483.275836208359, 11324.32054076758, 11527.193096483166, 11597.304161750322, 11827.172097953406, 11864.22882512634, 7792.777741760124, 9560.474173920502, 9995.800083998134, 10486.895131048897, 11506.539738410369, 9555.808883822145, 10506.684799456028, 11403.0, 11460.598879039528, 11578.652640420802], "value": 7641.976802647053, "unit": "score"}], "name": "dromaeo_css", "value": 7219.023623981262, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 112.0, 113.0, 114.0, 114.0, 113.0, 112.0, 123.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 161.0, 196.0, 108.0, 128.0, 127.0, 108.0, 179.0, 106.0], "value": 137.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 182.0, 182.0, 185.0, 187.0, 260.0, 181.0, 185.0, 182.0], "value": 191.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 91.0, 105.0, 189.0, 49.0, 60.0, 82.0, 84.0, 144.0, 57.0], "value": 93.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 135.0, 70.0, 71.0, 70.0, 123.0, 69.0, 69.0, 117.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 131.0, 115.0, 88.0, 91.0, 87.0, 91.0, 88.0, 91.0, 87.0], "value": 97.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 98.0, 94.0, 94.0, 95.0, 97.0, 95.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 83.0, 85.0, 85.0, 85.0, 86.0, 89.0, 75.0, 133.0], "value": 89.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 54.0, 54.0, 54.0, 55.0, 55.0, 55.0, 53.0, 55.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 73.0, 74.0, 73.0, 77.0, 72.0, 72.0, 71.0, 69.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 85.0, 85.0, 84.0, 86.0, 86.0, 91.0, 85.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 145.0, 154.0, 138.0, 138.0, 136.0, 149.0, 153.0, 177.0, 154.0], "value": 148.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 191.0, 175.0, 175.0, 173.0, 169.0, 175.0, 168.0, 171.0, 172.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 70.0, 67.0, 67.0, 67.0, 69.0, 66.0, 68.0, 70.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1509.3, "alertThreshold": 2.0}]} 11:01:53 INFO - Return code: 0 11:01:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:01:53 INFO - # TBPL SUCCESS # 11:01:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:01:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:01:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:01:53 INFO - 2016-09-01 11:01:53.794 screenresolution[1670:9596] starting screenresolution argv=screenresolution get 11:01:53 INFO - 2016-09-01 11:01:53.797 screenresolution[1670:9596] Display 0: 1600x1200x32@60 11:01:53 INFO - 2016-09-01 11:01:53.803 screenresolution[1671:9599] starting screenresolution argv=screenresolution list 11:01:53 INFO - Available Modes on Display 0 11:01:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:01:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:01:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:01:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:01:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:01:54 INFO - Intel Iris: 11:01:54 INFO - Chipset Model: Intel Iris 11:01:54 INFO - Type: GPU 11:01:54 INFO - Bus: Built-In 11:01:54 INFO - VRAM (Dynamic, Max): 1536 MB 11:01:54 INFO - Vendor: Intel (0x8086) 11:01:54 INFO - Device ID: 0x0a2e 11:01:54 INFO - Revision ID: 0x0009 11:01:54 INFO - Displays: 11:01:54 INFO - Display: 11:01:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:01:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:01:54 INFO - Display Serial Number: 1600x1200 60 11:01:54 INFO - Main Display: Yes 11:01:54 INFO - Mirror: Off 11:01:54 INFO - Online: Yes 11:01:54 INFO - Rotation: Supported 11:01:54 INFO - Return code: 0 11:01:54 INFO - Running post-action listener: _resource_record_post_action 11:01:54 INFO - [mozharness: 2016-09-01 18:01:54.039742Z] Finished run-tests step (success) 11:01:54 INFO - Running post-run listener: _resource_record_post_run 11:01:54 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 37753856; Write bytes: 704898048; Read time: 242; Write time: 2001 11:01:54 INFO - TinderboxPrint: CPU usage
53.4% 11:01:54 INFO - TinderboxPrint: I/O read bytes / time
37,753,856 / 242 11:01:54 INFO - TinderboxPrint: I/O write bytes / time
704,898,048 / 2,001 11:01:54 INFO - TinderboxPrint: CPU idle
2,260.7 (46.6%) 11:01:54 INFO - TinderboxPrint: CPU system
967.5 (20.0%) 11:01:54 INFO - TinderboxPrint: CPU user
1,621.4 (33.4%) 11:01:54 INFO - TinderboxPrint: Swap in / out
443,441,152 / 0 11:01:54 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 394609152; Write bytes: 388568576; Read time: 29446; Write time: 844 11:01:54 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 35955712; Write bytes: 295546368; Read time: 227; Write time: 1105 11:01:54 INFO - Running post-run listener: _upload_blobber_files 11:01:54 INFO - Blob upload gear active. 11:01:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:01: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 11:01: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'] 11:01: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 11:01:54 INFO - (blobuploader) - INFO - Open directory for files ... 11:01:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:01:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:01:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:01:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:01:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:01:56 INFO - (blobuploader) - INFO - Done attempting. 11:01:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:01:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:01:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:01:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:01:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:01:56 INFO - (blobuploader) - INFO - Done attempting. 11:01:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:01:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:01:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:01:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:01:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:01:59 INFO - (blobuploader) - INFO - Done attempting. 11:01:59 INFO - (blobuploader) - INFO - Iteration through files over. 11:01:59 INFO - Return code: 0 11:01:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:01:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:01:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/131b653caebafb6a3e7385c0073e2fdba0433d948490a40f6995614b4cd2cd29671df03f6e2fadef62cb401ce099ef90b3dade808262ea9413c17898548d0888", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5416d26b2bd4fe58c8861318bc573c6e985a48daf05f32af52cf174d5a6b57711c8654da73af58ef942372a1aa57a1e2b5114160bed5159ccc210c42877b39c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49b945dde98c5f8bd43fa5b89b96986444e52da47147c80965c0e12422896e279516a1019181fd3c93bb8160c1c0c70c4b19a29a605a018891bccbbee6b1e6d0"} 11:01:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:01:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:01:59 INFO - Contents: 11:01:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/131b653caebafb6a3e7385c0073e2fdba0433d948490a40f6995614b4cd2cd29671df03f6e2fadef62cb401ce099ef90b3dade808262ea9413c17898548d0888", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5416d26b2bd4fe58c8861318bc573c6e985a48daf05f32af52cf174d5a6b57711c8654da73af58ef942372a1aa57a1e2b5114160bed5159ccc210c42877b39c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49b945dde98c5f8bd43fa5b89b96986444e52da47147c80965c0e12422896e279516a1019181fd3c93bb8160c1c0c70c4b19a29a605a018891bccbbee6b1e6d0"} 11:01:59 INFO - Running post-run listener: copy_logs_to_upload_dir 11:01:59 INFO - Copying logs to upload dir... 11:01:59 INFO - mkdir: /builds/slave/test/build/upload/logs 11:01:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.519216 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-01 11:01:59.478177) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 11:01:59.481399) ========= 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.Gq1gtteY1d/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.QnND0L8586/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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/131b653caebafb6a3e7385c0073e2fdba0433d948490a40f6995614b4cd2cd29671df03f6e2fadef62cb401ce099ef90b3dade808262ea9413c17898548d0888", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5416d26b2bd4fe58c8861318bc573c6e985a48daf05f32af52cf174d5a6b57711c8654da73af58ef942372a1aa57a1e2b5114160bed5159ccc210c42877b39c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49b945dde98c5f8bd43fa5b89b96986444e52da47147c80965c0e12422896e279516a1019181fd3c93bb8160c1c0c70c4b19a29a605a018891bccbbee6b1e6d0"} build_url:https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011259 build_url: 'https://queue.taskcluster.net/v1/task/NI0UzYkSQEGoh6D_E10sTw/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/131b653caebafb6a3e7385c0073e2fdba0433d948490a40f6995614b4cd2cd29671df03f6e2fadef62cb401ce099ef90b3dade808262ea9413c17898548d0888", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5416d26b2bd4fe58c8861318bc573c6e985a48daf05f32af52cf174d5a6b57711c8654da73af58ef942372a1aa57a1e2b5114160bed5159ccc210c42877b39c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49b945dde98c5f8bd43fa5b89b96986444e52da47147c80965c0e12422896e279516a1019181fd3c93bb8160c1c0c70c4b19a29a605a018891bccbbee6b1e6d0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 11:01:59.514831) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:01:59.515170) ========= 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.Gq1gtteY1d/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.QnND0L8586/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.005085 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:01:59.563700) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 11:01:59.564048) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 11:01:59.564344) ========= ========= Total master_lag: 3.37 =========