builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0105 starttime: 1472738785.43 results: success (0) buildid: 20160901052024 builduid: 3c5cb53d644d493c8e1dafa259ad5027 revision: d29b950695f42eaa02de85e40c18ba65eea29c50 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 07:06:25.432500) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 07:06:25.433033) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 5 secs) (at 2016-09-01 07:06:25.433354) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 5 secs) (at 2016-09-01 07:06:31.229568) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 07:06:31.229996) ========= 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.bPOnj24woP/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.TGp23yDjaC/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:06:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.05M=0.001s 2016-09-01 07:06:31 (8.05 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.556564 ========= master_lag: 0.43 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 07:06:32.216482) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 8 secs) (at 2016-09-01 07:06:32.216827) ========= 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.bPOnj24woP/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.TGp23yDjaC/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.020081 ========= master_lag: 8.22 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 8 secs) (at 2016-09-01 07:06:40.460952) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 07:06:40.461348) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d29b950695f42eaa02de85e40c18ba65eea29c50 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev d29b950695f42eaa02de85e40c18ba65eea29c50 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bPOnj24woP/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.TGp23yDjaC/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:06:40,538 truncating revision to first 12 chars 2016-09-01 07:06:40,538 Setting DEBUG logging. 2016-09-01 07:06:40,538 attempt 1/10 2016-09-01 07:06:40,538 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d29b950695f4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 07:06:41,482 unpacking tar archive at: autoland-d29b950695f4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.223000 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 07:06:41.712336) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 07:06:41.712748) ========= script_repo_revision: d29b950695f42eaa02de85e40c18ba65eea29c50 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 07:06:41.713203) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 07:06:41.713489) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 07:06:41.732559) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-01 07:06:41.732950) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bPOnj24woP/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.TGp23yDjaC/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:06:41 INFO - MultiFileLogger online at 20160901 07:06:41 in /builds/slave/test 07:06:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 07:06:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:06:41 INFO - {'append_to_log': False, 07:06:41 INFO - 'base_work_dir': '/builds/slave/test', 07:06:41 INFO - 'blob_upload_branch': 'Autoland', 07:06:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:06:41 INFO - 'branch': 'Autoland', 07:06:41 INFO - 'buildbot_json_path': 'buildprops.json', 07:06:41 INFO - 'config_files': ('talos/mac_config.py',), 07:06:41 INFO - 'default_actions': ('clobber', 07:06:41 INFO - 'read-buildbot-config', 07:06:41 INFO - 'download-and-extract', 07:06:41 INFO - 'populate-webroot', 07:06:41 INFO - 'create-virtualenv', 07:06:41 INFO - 'install', 07:06:41 INFO - 'run-tests'), 07:06:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:06:41 INFO - 'download_minidump_stackwalk': True, 07:06:41 INFO - 'download_symbols': 'ondemand', 07:06:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:06:41 INFO - 'tooltool.py': '/tools/tooltool.py', 07:06:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:06:41 INFO - '/tools/misc-python/virtualenv.py')}, 07:06:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:06:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:06:41 INFO - 'installer_path': 'installer.exe', 07:06:41 INFO - 'log_level': 'info', 07:06:41 INFO - 'log_name': 'talos', 07:06:41 INFO - 'log_to_console': True, 07:06:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:06:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:06:41 INFO - 'opt_config_files': (), 07:06:41 INFO - 'pip_index': False, 07:06:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:06:41 INFO - 'cmd': ('bash', 07:06:41 INFO - '-c', 07:06:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:06:41 INFO - 'enabled': True, 07:06:41 INFO - 'halt_on_failure': False, 07:06:41 INFO - 'name': 'check_screen_resolution'},), 07:06:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:06:41 INFO - 'cmd': ('bash', 07:06:41 INFO - '-c', 07:06:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:06:41 INFO - 'enabled': True, 07:06:41 INFO - 'halt_on_failure': False, 07:06:41 INFO - 'name': 'check_screen_resolution'},), 07:06:41 INFO - 'run_cmd_checks_enabled': True, 07:06:41 INFO - 'sps_profile': False, 07:06:41 INFO - 'sps_profile_interval': 0, 07:06:41 INFO - 'suite': 'dromaeojs', 07:06:41 INFO - 'system_bits': '32', 07:06:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:06:41 INFO - 'title': 't-yosemite-r7-0105', 07:06:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:06:41 INFO - 'use_talos_json': True, 07:06:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:06:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:06:41 INFO - 'work_dir': 'build'} 07:06:41 INFO - [mozharness: 2016-09-01 14:06:41.923812Z] Running clobber step. 07:06:41 INFO - Running pre-action listener: _resource_record_pre_action 07:06:41 INFO - Running main action method: clobber 07:06:41 INFO - rmtree: /builds/slave/test/build 07:06:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:06:43 INFO - Running post-action listener: _resource_record_post_action 07:06:43 INFO - [mozharness: 2016-09-01 14:06:43.408885Z] Finished clobber step (success) 07:06:43 INFO - [mozharness: 2016-09-01 14:06:43.409117Z] Running read-buildbot-config step. 07:06:43 INFO - Running pre-action listener: _resource_record_pre_action 07:06:43 INFO - Running main action method: read_buildbot_config 07:06:43 INFO - Using buildbot properties: 07:06:43 INFO - { 07:06:43 INFO - "project": "", 07:06:43 INFO - "product": "firefox", 07:06:43 INFO - "script_repo_revision": "production", 07:06:43 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 07:06:43 INFO - "repository": "", 07:06:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 07:06:43 INFO - "buildid": "20160901052024", 07:06:43 INFO - "pgo_build": "False", 07:06:43 INFO - "basedir": "/builds/slave/test", 07:06:43 INFO - "buildnumber": 210, 07:06:43 INFO - "slavename": "t-yosemite-r7-0105", 07:06:43 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:06:43 INFO - "platform": "yosemite_r7", 07:06:43 INFO - "scheduler": "tests-autoland-macosx64-talos", 07:06:43 INFO - "branch": "autoland", 07:06:43 INFO - "repo_path": "integration/autoland", 07:06:43 INFO - "revision": "d29b950695f42eaa02de85e40c18ba65eea29c50", 07:06:43 INFO - "stage_platform": "macosx64", 07:06:43 INFO - "builduid": "3c5cb53d644d493c8e1dafa259ad5027", 07:06:43 INFO - "slavebuilddir": "test" 07:06:43 INFO - } 07:06:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 07:06:43 INFO - Running post-action listener: _resource_record_post_action 07:06:43 INFO - [mozharness: 2016-09-01 14:06:43.416043Z] Finished read-buildbot-config step (success) 07:06:43 INFO - [mozharness: 2016-09-01 14:06:43.416291Z] Running download-and-extract step. 07:06:43 INFO - Running pre-action listener: _resource_record_pre_action 07:06:43 INFO - Running main action method: download_and_extract 07:06:43 INFO - mkdir: /builds/slave/test/build/tests 07:06:43 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')]} 07:06:43 INFO - trying https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 07:06:43 INFO - Downloading https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/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 07:06:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/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 07:06:45 INFO - Downloaded 1199 bytes. 07:06:45 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 07:06:45 INFO - Using the following test package requirements: 07:06:45 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:06:45 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:06:45 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 07:06:45 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:06:45 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 07:06:45 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:06:45 INFO - u'jsshell-mac.zip'], 07:06:45 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:06:45 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 07:06:45 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:06:45 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:06:45 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 07:06:45 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:06:45 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 07:06:45 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:06:45 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 07:06:45 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:06:45 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 07:06:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:06:45 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')]} 07:06:45 INFO - trying https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:06:45 INFO - Downloading https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/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 07:06:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/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 07:06:46 INFO - Downloaded 16398044 bytes. 07:06:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 07:06:53 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 07:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:06:53 INFO - trying https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:06:53 INFO - Downloading https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/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 07:06:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/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 07:06:55 INFO - Downloaded 16398044 bytes. 07:06:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 07:07:01 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')]} 07:07:01 INFO - trying https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 07:07:01 INFO - Downloading https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/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 07:07:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/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 07:07:03 INFO - Downloaded 14413998 bytes. 07:07:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 07:07:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:03 INFO - trying https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:07:03 INFO - Downloading https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 07:07:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/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 07:07:07 INFO - Downloaded 111592674 bytes. 07:07:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:07:07 INFO - mkdir: /builds/slave/test/properties 07:07:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:07:07 INFO - Writing to file /builds/slave/test/properties/build_url 07:07:07 INFO - Contents: 07:07:07 INFO - build_url:https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:07:08 INFO - Running post-action listener: _resource_record_post_action 07:07:08 INFO - Running post-action listener: set_extra_try_arguments 07:07:08 INFO - [mozharness: 2016-09-01 14:07:08.413760Z] Finished download-and-extract step (success) 07:07:08 INFO - [mozharness: 2016-09-01 14:07:08.413923Z] Running populate-webroot step. 07:07:08 INFO - Running pre-action listener: _resource_record_pre_action 07:07:08 INFO - Running main action method: populate_webroot 07:07:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:07:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:07:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:07:08 INFO - '--tppagecycles', 07:07:08 INFO - '7'], 07:07:08 INFO - 'tests': ['tsvgm']}}, 07:07:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:07:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:07:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:07:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:07:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:07:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:07:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:08 INFO - 'talos_options': ['--disable-e10s'], 07:07:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:07:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:07:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:08 INFO - 'talos_options': ['--disable-e10s'], 07:07:08 INFO - 'tests': ['damp', 'tps']}, 07:07:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:08 INFO - 'tests': ['damp', 'tps']}, 07:07:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:07:08 INFO - 'tests': ['dromaeo_dom']}, 07:07:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:07:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:07:08 INFO - 'tests': ['basic_compositor_video']}, 07:07:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:07:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:07:08 INFO - 'tests': ['a11yr', 07:07:08 INFO - 'ts_paint', 07:07:08 INFO - 'tpaint', 07:07:08 INFO - 'sessionrestore', 07:07:08 INFO - 'sessionrestore_no_auto_restore', 07:07:08 INFO - 'tabpaint']}, 07:07:08 INFO - 'other-e10s': {'tests': ['a11yr', 07:07:08 INFO - 'ts_paint', 07:07:08 INFO - 'tpaint', 07:07:08 INFO - 'sessionrestore', 07:07:08 INFO - 'sessionrestore_no_auto_restore', 07:07:08 INFO - 'tabpaint']}, 07:07:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:07:08 INFO - 'ts_paint', 07:07:08 INFO - 'tpaint', 07:07:08 INFO - 'sessionrestore', 07:07:08 INFO - 'sessionrestore_no_auto_restore', 07:07:08 INFO - 'tabpaint']}, 07:07:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:07:08 INFO - 'ts_paint', 07:07:08 INFO - 'tpaint', 07:07:08 INFO - 'sessionrestore', 07:07:08 INFO - 'sessionrestore_no_auto_restore', 07:07:08 INFO - 'tabpaint']}, 07:07:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:07:08 INFO - 'tests': ['a11yr', 07:07:08 INFO - 'ts_paint', 07:07:08 INFO - 'tpaint', 07:07:08 INFO - 'sessionrestore', 07:07:08 INFO - 'sessionrestore_no_auto_restore', 07:07:08 INFO - 'tabpaint']}, 07:07:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:07:08 INFO - 'tests': ['a11yr', 07:07:08 INFO - 'ts_paint', 07:07:08 INFO - 'tpaint', 07:07:08 INFO - 'sessionrestore', 07:07:08 INFO - 'sessionrestore_no_auto_restore', 07:07:08 INFO - 'tabpaint']}, 07:07:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:07:08 INFO - 'tests': ['tsvgx', 07:07:08 INFO - 'tsvgr_opacity', 07:07:08 INFO - 'tart', 07:07:08 INFO - 'tscrollx', 07:07:08 INFO - 'cart']}, 07:07:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:07:08 INFO - 'tsvgr_opacity', 07:07:08 INFO - 'tart', 07:07:08 INFO - 'tscrollx', 07:07:08 INFO - 'cart']}, 07:07:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:08 INFO - 'talos_options': ['--disable-e10s'], 07:07:08 INFO - 'tests': ['tp5o']}, 07:07:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:08 INFO - 'tests': ['tp5o']}, 07:07:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:08 INFO - 'talos_options': ['--disable-e10s', 07:07:08 INFO - '--xperf_path', 07:07:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:07:08 INFO - 'tests': ['tp5n']}, 07:07:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:08 INFO - 'talos_options': ['--xperf_path', 07:07:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:07:08 INFO - 'tests': ['tp5n']}}, 07:07:08 INFO - 'talos.zip': {'path': '', 07:07:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:07:08 INFO - Running post-action listener: _resource_record_post_action 07:07:08 INFO - [mozharness: 2016-09-01 14:07:08.432699Z] Finished populate-webroot step (success) 07:07:08 INFO - [mozharness: 2016-09-01 14:07:08.432840Z] Running create-virtualenv step. 07:07:08 INFO - Running pre-action listener: _resource_record_pre_action 07:07:08 INFO - Running main action method: create_virtualenv 07:07:08 INFO - Creating virtualenv /builds/slave/test/build/venv 07:07:08 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 07:07:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:07:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:07:08 INFO - Using real prefix '/tools/python27' 07:07:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:07:09 INFO - Installing distribute.............................................................................................................................................................................................done. 07:07:12 INFO - Installing pip.................done. 07:07:12 INFO - Return code: 0 07:07:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda084771a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab72d30>, '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 0x10a8d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6ab20>, '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.bPOnj24woP/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.TGp23yDjaC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:07:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:07:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:07:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPOnj24woP/Render', 07:07:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:12 INFO - 'HOME': '/Users/cltbld', 07:07:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:12 INFO - 'LOGNAME': 'cltbld', 07:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:12 INFO - 'MOZ_NO_REMOTE': '1', 07:07:12 INFO - 'NO_EM_RESTART': '1', 07:07:12 INFO - 'PAGER': '/bin/cat', 07:07:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:12 INFO - 'PWD': '/builds/slave/test', 07:07:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:12 INFO - 'SHELL': '/bin/bash', 07:07:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGp23yDjaC/Listeners', 07:07:12 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:07:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:12 INFO - 'USER': 'cltbld', 07:07:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:12 INFO - 'XPC_FLAGS': '0x0', 07:07:12 INFO - 'XPC_SERVICE_NAME': '0', 07:07:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:12 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 07:07:12 INFO - Cleaning up... 07:07:12 INFO - Return code: 0 07:07:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ac0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda084771a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab72d30>, '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 0x10a8d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6ab20>, '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.bPOnj24woP/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.TGp23yDjaC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:07:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:07:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:07:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPOnj24woP/Render', 07:07:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:12 INFO - 'HOME': '/Users/cltbld', 07:07:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:12 INFO - 'LOGNAME': 'cltbld', 07:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:12 INFO - 'MOZ_NO_REMOTE': '1', 07:07:12 INFO - 'NO_EM_RESTART': '1', 07:07:12 INFO - 'PAGER': '/bin/cat', 07:07:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:12 INFO - 'PWD': '/builds/slave/test', 07:07:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:12 INFO - 'SHELL': '/bin/bash', 07:07:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGp23yDjaC/Listeners', 07:07:12 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:07:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:12 INFO - 'USER': 'cltbld', 07:07:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:12 INFO - 'XPC_FLAGS': '0x0', 07:07:12 INFO - 'XPC_SERVICE_NAME': '0', 07:07:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:12 INFO - Downloading/unpacking psutil>=3.1.1 07:07:12 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 07:07:12 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 07:07:12 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 07:07:12 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 07:07:12 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 07:07:12 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 07:07:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:07:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:07:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:07:16 INFO - Installing collected packages: psutil 07:07:16 INFO - Running setup.py install for psutil 07:07:16 INFO - building 'psutil._psutil_osx' extension 07:07:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:07:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:07:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:07:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:07:16 INFO - building 'psutil._psutil_posix' extension 07:07:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:07:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:07:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:07:16 INFO - ^ 07:07:16 INFO - 1 warning generated. 07:07:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:07:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:07:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:07:16 INFO - Successfully installed psutil 07:07:16 INFO - Cleaning up... 07:07:17 INFO - Return code: 0 07:07:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:07:17 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')]} 07:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:17 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')]} 07:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:17 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 0x10ac0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda084771a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab72d30>, '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 0x10a8d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6ab20>, '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.bPOnj24woP/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.TGp23yDjaC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:07:17 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 07:07:17 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 07:07:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPOnj24woP/Render', 07:07:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:17 INFO - 'HOME': '/Users/cltbld', 07:07:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:17 INFO - 'LOGNAME': 'cltbld', 07:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:17 INFO - 'MOZ_NO_REMOTE': '1', 07:07:17 INFO - 'NO_EM_RESTART': '1', 07:07:17 INFO - 'PAGER': '/bin/cat', 07:07:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:17 INFO - 'PWD': '/builds/slave/test', 07:07:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:17 INFO - 'SHELL': '/bin/bash', 07:07:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGp23yDjaC/Listeners', 07:07:17 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:07:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:17 INFO - 'USER': 'cltbld', 07:07:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:17 INFO - 'XPC_FLAGS': '0x0', 07:07:17 INFO - 'XPC_SERVICE_NAME': '0', 07:07:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:07:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:07:19 INFO - Installing collected packages: mozsystemmonitor 07:07:19 INFO - Running setup.py install for mozsystemmonitor 07:07:19 INFO - Successfully installed mozsystemmonitor 07:07:19 INFO - Cleaning up... 07:07:20 INFO - Return code: 0 07:07:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:07:20 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')]} 07:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:20 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')]} 07:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:20 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 0x10ac0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda084771a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab72d30>, '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 0x10a8d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6ab20>, '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.bPOnj24woP/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.TGp23yDjaC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:07:20 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 07:07:20 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 07:07:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPOnj24woP/Render', 07:07:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:20 INFO - 'HOME': '/Users/cltbld', 07:07:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:20 INFO - 'LOGNAME': 'cltbld', 07:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:20 INFO - 'MOZ_NO_REMOTE': '1', 07:07:20 INFO - 'NO_EM_RESTART': '1', 07:07:20 INFO - 'PAGER': '/bin/cat', 07:07:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:20 INFO - 'PWD': '/builds/slave/test', 07:07:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:20 INFO - 'SHELL': '/bin/bash', 07:07:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGp23yDjaC/Listeners', 07:07:20 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:07:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:20 INFO - 'USER': 'cltbld', 07:07:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:20 INFO - 'XPC_FLAGS': '0x0', 07:07:20 INFO - 'XPC_SERVICE_NAME': '0', 07:07:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:20 INFO - Downloading/unpacking jsonschema==2.5.1 07:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:07:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:23 INFO - Downloading functools32-3.2.3-2.tar.gz 07:07:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:07:23 INFO - warning: no files found matching '*.txt' 07:07:23 INFO - no previously-included directories found matching 'build' 07:07:23 INFO - no previously-included directories found matching 'dist' 07:07:23 INFO - no previously-included directories found matching '.git*' 07:07:23 INFO - Installing collected packages: jsonschema, functools32 07:07:23 INFO - Running setup.py install for functools32 07:07:23 INFO - warning: no files found matching '*.txt' 07:07:23 INFO - no previously-included directories found matching 'build' 07:07:23 INFO - no previously-included directories found matching 'dist' 07:07:23 INFO - no previously-included directories found matching '.git*' 07:07:23 INFO - Successfully installed jsonschema functools32 07:07:23 INFO - Cleaning up... 07:07:23 INFO - Return code: 0 07:07:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda084771a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab72d30>, '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 0x10a8d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6ab20>, '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.bPOnj24woP/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.TGp23yDjaC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:07:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:07:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPOnj24woP/Render', 07:07:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:23 INFO - 'HOME': '/Users/cltbld', 07:07:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:23 INFO - 'LOGNAME': 'cltbld', 07:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:23 INFO - 'MOZ_NO_REMOTE': '1', 07:07:23 INFO - 'NO_EM_RESTART': '1', 07:07:23 INFO - 'PAGER': '/bin/cat', 07:07:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:23 INFO - 'PWD': '/builds/slave/test', 07:07:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:23 INFO - 'SHELL': '/bin/bash', 07:07:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGp23yDjaC/Listeners', 07:07:23 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:07:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:23 INFO - 'USER': 'cltbld', 07:07:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:23 INFO - 'XPC_FLAGS': '0x0', 07:07:23 INFO - 'XPC_SERVICE_NAME': '0', 07:07:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:07:23 INFO - Cleaning up... 07:07:23 INFO - Return code: 0 07:07:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda084771a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab72d30>, '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 0x10a8d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6ab20>, '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.bPOnj24woP/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.TGp23yDjaC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:07:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:07:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPOnj24woP/Render', 07:07:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:23 INFO - 'HOME': '/Users/cltbld', 07:07:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:23 INFO - 'LOGNAME': 'cltbld', 07:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:23 INFO - 'MOZ_NO_REMOTE': '1', 07:07:23 INFO - 'NO_EM_RESTART': '1', 07:07:23 INFO - 'PAGER': '/bin/cat', 07:07:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:23 INFO - 'PWD': '/builds/slave/test', 07:07:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:23 INFO - 'SHELL': '/bin/bash', 07:07:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGp23yDjaC/Listeners', 07:07:23 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:07:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:23 INFO - 'USER': 'cltbld', 07:07:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:23 INFO - 'XPC_FLAGS': '0x0', 07:07:23 INFO - 'XPC_SERVICE_NAME': '0', 07:07:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:24 INFO - Downloading/unpacking blobuploader==1.2.4 07:07: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 07:07: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 07:07: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 07:07: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 07:07: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 07:07: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 07:07:26 INFO - Downloading blobuploader-1.2.4.tar.gz 07:07:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:07:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:07:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:27 INFO - Downloading docopt-0.6.1.tar.gz 07:07:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:07:28 INFO - Installing collected packages: blobuploader, requests, docopt 07:07:28 INFO - Running setup.py install for blobuploader 07:07:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:07:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:07:28 INFO - Running setup.py install for requests 07:07:28 INFO - Running setup.py install for docopt 07:07:28 INFO - Successfully installed blobuploader requests docopt 07:07:28 INFO - Cleaning up... 07:07:28 INFO - Return code: 0 07:07:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda084771a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab72d30>, '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 0x10a8d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6ab20>, '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.bPOnj24woP/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.TGp23yDjaC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:07:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:07:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:07:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPOnj24woP/Render', 07:07:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:28 INFO - 'HOME': '/Users/cltbld', 07:07:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:28 INFO - 'LOGNAME': 'cltbld', 07:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:28 INFO - 'MOZ_NO_REMOTE': '1', 07:07:28 INFO - 'NO_EM_RESTART': '1', 07:07:28 INFO - 'PAGER': '/bin/cat', 07:07:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:28 INFO - 'PWD': '/builds/slave/test', 07:07:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:28 INFO - 'SHELL': '/bin/bash', 07:07:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGp23yDjaC/Listeners', 07:07:28 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:07:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:28 INFO - 'USER': 'cltbld', 07:07:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:28 INFO - 'XPC_FLAGS': '0x0', 07:07:28 INFO - 'XPC_SERVICE_NAME': '0', 07:07:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:07:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-PD5Wlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:07:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-eIp0li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:07:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-O7GWp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:07:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-8_Hn_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:07:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-xQt8zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:07:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-aeY12b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:07:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-vja1bp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:07:29 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-q0QfUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:07:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-u7IuWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:07:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-kquDja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:07:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-J4px6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:07:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-bQY3SA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:07:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-1zGFl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:07:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-tagyfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-os01AD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:07:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-mOrWAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:07:30 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-19nhVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:07:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:07:30 INFO - Running setup.py install for manifestparser 07:07:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:07:31 INFO - Running setup.py install for mozcrash 07:07:31 INFO - Running setup.py install for mozdebug 07:07:31 INFO - Running setup.py install for mozdevice 07:07:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:07:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:07:31 INFO - Running setup.py install for mozfile 07:07:31 INFO - Running setup.py install for mozhttpd 07:07:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:07:31 INFO - Running setup.py install for mozinfo 07:07:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:07:32 INFO - Running setup.py install for mozInstall 07:07:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:07:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:07:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:07:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:07:32 INFO - Running setup.py install for mozleak 07:07:32 INFO - Running setup.py install for mozlog 07:07:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:07:32 INFO - Running setup.py install for moznetwork 07:07:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:07:32 INFO - Running setup.py install for mozprocess 07:07:32 INFO - Running setup.py install for mozprofile 07:07:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:07:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:07:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:07:32 INFO - Running setup.py install for mozrunner 07:07:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:07:33 INFO - Running setup.py install for mozscreenshot 07:07:33 INFO - Running setup.py install for moztest 07:07:33 INFO - Running setup.py install for mozversion 07:07:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:07:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:07:33 INFO - Cleaning up... 07:07:33 INFO - Return code: 0 07:07:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda084771a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab72d30>, '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 0x10a8d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6ab20>, '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.bPOnj24woP/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.TGp23yDjaC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:07:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:07:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPOnj24woP/Render', 07:07:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:33 INFO - 'HOME': '/Users/cltbld', 07:07:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:33 INFO - 'LOGNAME': 'cltbld', 07:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:33 INFO - 'MOZ_NO_REMOTE': '1', 07:07:33 INFO - 'NO_EM_RESTART': '1', 07:07:33 INFO - 'PAGER': '/bin/cat', 07:07:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:33 INFO - 'PWD': '/builds/slave/test', 07:07:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:33 INFO - 'SHELL': '/bin/bash', 07:07:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGp23yDjaC/Listeners', 07:07:33 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:07:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:33 INFO - 'USER': 'cltbld', 07:07:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:33 INFO - 'XPC_FLAGS': '0x0', 07:07:33 INFO - 'XPC_SERVICE_NAME': '0', 07:07:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:07:33 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-I83a3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:07:33 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Q3rB4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:07:34 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)) 07:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:07:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-TD8mXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:07:34 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)) 07:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:07:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-kTzDn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:07:34 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)) 07:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:07:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ZAW4_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:07:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:07:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-W2sdOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:07:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:07:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-nin7Gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:07:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:07:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-RYvdO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:07:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:07:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-5ky8mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:07:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:07:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-eyWIfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:07:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:07:34 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-SoDJa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:07:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:07:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-K7g1iP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:07:35 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)) 07:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:07:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-nQwDX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:07:35 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)) 07:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:07:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-kZyIOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-uUFsCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:07:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-IvMS04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:07:35 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-xqro9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:07:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:38 INFO - Downloading blessings-1.6.tar.gz 07:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:07:38 INFO - Installing collected packages: blessings 07:07:38 INFO - Running setup.py install for blessings 07:07:38 INFO - Successfully installed blessings 07:07:38 INFO - Cleaning up... 07:07:38 INFO - Return code: 0 07:07:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:07:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:07:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:07:38 INFO - Reading from file tmpfile_stdout 07:07:38 INFO - Current package versions: 07:07:38 INFO - blessings == 1.6 07:07:38 INFO - blobuploader == 1.2.4 07:07:38 INFO - docopt == 0.6.1 07:07:38 INFO - functools32 == 3.2.3-2 07:07:38 INFO - jsonschema == 2.5.1 07:07:38 INFO - manifestparser == 1.1 07:07:38 INFO - mozInstall == 1.12 07:07:38 INFO - mozcrash == 0.17 07:07:38 INFO - mozdebug == 0.1 07:07:38 INFO - mozdevice == 0.48 07:07:38 INFO - mozfile == 1.2 07:07:38 INFO - mozhttpd == 0.7 07:07:38 INFO - mozinfo == 0.9 07:07:38 INFO - mozleak == 0.1 07:07:38 INFO - mozlog == 3.3 07:07:38 INFO - moznetwork == 0.27 07:07:38 INFO - mozprocess == 0.23 07:07:38 INFO - mozprofile == 0.28 07:07:38 INFO - mozrunner == 6.12 07:07:38 INFO - mozscreenshot == 0.1 07:07:38 INFO - mozsystemmonitor == 0.3 07:07:38 INFO - moztest == 0.7 07:07:38 INFO - mozversion == 1.4 07:07:38 INFO - psutil == 3.1.1 07:07:38 INFO - requests == 1.2.3 07:07:38 INFO - wsgiref == 0.1.2 07:07:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:38 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 0x10ac0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda084771a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab72d30>, '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 0x10a8d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6ab20>, '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.bPOnj24woP/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.TGp23yDjaC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:07:38 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 07:07:38 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 07:07:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPOnj24woP/Render', 07:07:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:38 INFO - 'HOME': '/Users/cltbld', 07:07:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:38 INFO - 'LOGNAME': 'cltbld', 07:07:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:38 INFO - 'MOZ_NO_REMOTE': '1', 07:07:38 INFO - 'NO_EM_RESTART': '1', 07:07:38 INFO - 'PAGER': '/bin/cat', 07:07:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:38 INFO - 'PWD': '/builds/slave/test', 07:07:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:38 INFO - 'SHELL': '/bin/bash', 07:07:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGp23yDjaC/Listeners', 07:07:38 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:07:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:38 INFO - 'USER': 'cltbld', 07:07:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:38 INFO - 'XPC_FLAGS': '0x0', 07:07:38 INFO - 'XPC_SERVICE_NAME': '0', 07:07:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:38 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)) 07:07:38 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)) 07:07:38 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)) 07:07:38 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)) 07:07:38 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)) 07:07:38 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)) 07:07:38 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)) 07:07:38 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)) 07:07:38 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)) 07:07:38 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)) 07:07:38 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)) 07:07:38 INFO - Cleaning up... 07:07:39 INFO - Return code: 0 07:07:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:07: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')]} 07:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07: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')]} 07:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda084771a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab72d30>, '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 0x10a8d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6ab20>, '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.bPOnj24woP/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.TGp23yDjaC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:07:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:07:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:07:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPOnj24woP/Render', 07:07:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:39 INFO - 'HOME': '/Users/cltbld', 07:07:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:39 INFO - 'LOGNAME': 'cltbld', 07:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:39 INFO - 'MOZ_NO_REMOTE': '1', 07:07:39 INFO - 'NO_EM_RESTART': '1', 07:07:39 INFO - 'PAGER': '/bin/cat', 07:07:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:39 INFO - 'PWD': '/builds/slave/test', 07:07:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:39 INFO - 'SHELL': '/bin/bash', 07:07:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGp23yDjaC/Listeners', 07:07:39 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:07:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:39 INFO - 'USER': 'cltbld', 07:07:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:39 INFO - 'XPC_FLAGS': '0x0', 07:07:39 INFO - 'XPC_SERVICE_NAME': '0', 07:07:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:07:39 INFO - Cleaning up... 07:07:39 INFO - Return code: 0 07:07:39 INFO - Running post-action listener: _resource_record_post_action 07:07:39 INFO - Running post-action listener: _start_resource_monitoring 07:07:39 INFO - Starting resource monitoring. 07:07:39 INFO - [mozharness: 2016-09-01 14:07:39.300717Z] Finished create-virtualenv step (success) 07:07:39 INFO - [mozharness: 2016-09-01 14:07:39.301718Z] Running install step. 07:07:39 INFO - Running pre-action listener: _resource_record_pre_action 07:07:39 INFO - Running main action method: install 07:07:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:07:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:07:39 INFO - Reading from file tmpfile_stdout 07:07:39 INFO - Detecting whether we're running mozinstall >=1.0... 07:07:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:07:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:07:39 INFO - Reading from file tmpfile_stdout 07:07:39 INFO - Output received: 07:07:39 INFO - Usage: mozinstall [options] installer 07:07:39 INFO - Options: 07:07:39 INFO - -h, --help show this help message and exit 07:07:39 INFO - -d DEST, --destination=DEST 07:07:39 INFO - Directory to install application into. [default: 07:07:39 INFO - "/builds/slave/test"] 07:07:39 INFO - --app=APP Application being installed. [default: firefox] 07:07:39 INFO - mkdir: /builds/slave/test/build/application 07:07:39 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'] 07:07:39 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 07:08:13 INFO - Reading from file tmpfile_stdout 07:08:13 INFO - Output received: 07:08:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:08:13 INFO - Running post-action listener: _resource_record_post_action 07:08:13 INFO - [mozharness: 2016-09-01 14:08:13.709751Z] Finished install step (success) 07:08:13 INFO - [mozharness: 2016-09-01 14:08:13.709913Z] Running run-tests step. 07:08:13 INFO - Running pre-action listener: _resource_record_pre_action 07:08:13 INFO - Running main action method: run_tests 07:08:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:08:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:08:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:08:13 INFO - 2016-09-01 07:08:13.723 screenresolution[1924:19889] starting screenresolution argv=screenresolution get 07:08:13 INFO - 2016-09-01 07:08:13.725 screenresolution[1924:19889] Display 0: 1600x1200x32@60 07:08:13 INFO - 2016-09-01 07:08:13.731 screenresolution[1925:19891] starting screenresolution argv=screenresolution list 07:08:13 INFO - Available Modes on Display 0 07:08:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:08:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:08:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:08:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:08:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:08:13 INFO - Intel Iris: 07:08:13 INFO - Chipset Model: Intel Iris 07:08:13 INFO - Type: GPU 07:08:13 INFO - Bus: Built-In 07:08:13 INFO - VRAM (Dynamic, Max): 1536 MB 07:08:13 INFO - Vendor: Intel (0x8086) 07:08:13 INFO - Device ID: 0x0a2e 07:08:13 INFO - Revision ID: 0x0009 07:08:13 INFO - Displays: 07:08:13 INFO - Display: 07:08:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:08:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:08:13 INFO - Display Serial Number: 1600x1200 60 07:08:13 INFO - Main Display: Yes 07:08:13 INFO - Mirror: Off 07:08:13 INFO - Online: Yes 07:08:13 INFO - Rotation: Supported 07:08:13 INFO - Return code: 0 07:08:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:08:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:08:13 INFO - Python 2.7.3 07:08:13 INFO - Return code: 0 07:08:13 INFO - grabbing minidump binary from tooltool 07:08:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:13 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 0x10ab72d30>, '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 0x10a8d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:08:13 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 07:08:13 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 07:08:13 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 07:08:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:08:14 INFO - Return code: 0 07:08:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:08:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:08:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:08:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:08:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:08:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 07:08:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0105 --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 07:08:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPOnj24woP/Render', 07:08:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:14 INFO - 'HOME': '/Users/cltbld', 07:08:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:14 INFO - 'LOGNAME': 'cltbld', 07:08:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:08:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:08:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:14 INFO - 'MOZ_NO_REMOTE': '1', 07:08:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:08:14 INFO - 'NO_EM_RESTART': '1', 07:08:14 INFO - 'PAGER': '/bin/cat', 07:08:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:14 INFO - 'PWD': '/builds/slave/test', 07:08:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:08:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:14 INFO - 'SHELL': '/bin/bash', 07:08:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGp23yDjaC/Listeners', 07:08:14 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:08:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:14 INFO - 'USER': 'cltbld', 07:08:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:14 INFO - 'XPC_FLAGS': '0x0', 07:08:14 INFO - 'XPC_SERVICE_NAME': '0', 07:08:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 07:08:14 INFO - mozversion application_buildid: 20160901052024 07:08:14 INFO - mozversion application_changeset: d29b950695f42eaa02de85e40c18ba65eea29c50 07:08:14 INFO - mozversion application_display_name: Nightly 07:08:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:08:14 INFO - mozversion application_name: Firefox 07:08:14 INFO - mozversion application_remotingname: firefox 07:08:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 07:08:14 INFO - mozversion application_vendor: Mozilla 07:08:14 INFO - mozversion application_version: 51.0a1 07:08:14 INFO - mozversion platform_buildid: 20160901052024 07:08:14 INFO - mozversion platform_changeset: d29b950695f42eaa02de85e40c18ba65eea29c50 07:08:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 07:08:14 INFO - mozversion platform_version: 51.0a1 07:08:14 INFO - using testdate: 1472738894 07:08:14 INFO - actual date: 1472738894 07:08:14 INFO - starting webserver on 'localhost:55761' 07:08:14 INFO - SUITE-START | Running 2 tests 07:08:14 INFO - TEST-START | dromaeo_css 07:08:14 INFO - operating with platform_type : mac_ 07:08:14 INFO - Initialising browser for dromaeo_css test... 07:08:14 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpzw2wkD/profile http://localhost:55761/getInfo.html) 07:08:16 INFO - PROCESS | 1935 | __metrics Screen width/height:1600/1200 07:08:16 INFO - PROCESS | 1935 | colorDepth:24 07:08:16 INFO - PROCESS | 1935 | Browser inner width/height: 1024/689 07:08:16 INFO - PROCESS | 1935 | __metrics 07:08:16 INFO - PROCESS | 1935 | 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" 07:08:16 INFO - PROCESS | 1935 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:08:17 INFO - TEST-INFO | 1935: exit 0 07:08:17 INFO - Browser initialized. 07:08:17 INFO - Running cycle 1/1 for dromaeo_css test... 07:08:17 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpzw2wkD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:08:29 INFO - PROCESS | 1937 | RSS: Main: 234115072 07:08:29 INFO - PROCESS | 1937 | 07:09:20 INFO - PROCESS | 1937 | 1472738960992 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] 07:11:32 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:55761/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55761/tests/dromaeo/cssquery-ext.html) 07:11:34 INFO - PROCESS | 1937 | RSS: Main: 345374720 07:11:34 INFO - PROCESS | 1937 | 07:14:37 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:55761/tests/dromaeo/cssquery-ext.html (next: http://localhost:55761/tests/dromaeo/cssquery-jquery.html) 07:14:37 INFO - PROCESS | 1937 | RSS: Main: 375451648 07:14:37 INFO - PROCESS | 1937 | 07:17:40 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:55761/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55761/tests/dromaeo/cssquery-mootools.html) 07:17:40 INFO - PROCESS | 1937 | RSS: Main: 359387136 07:17:40 INFO - PROCESS | 1937 | 07:20:42 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:55761/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55761/tests/dromaeo/cssquery-prototype.html) 07:20:43 INFO - PROCESS | 1937 | RSS: Main: 337002496 07:20:43 INFO - PROCESS | 1937 | 07:23:46 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:55761/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55761/tests/dromaeo/cssquery-yui.html) 07:23:47 INFO - PROCESS | 1937 | RSS: Main: 323571712 07:23:47 INFO - PROCESS | 1937 | 07:26:51 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:55761/tests/dromaeo/cssquery-yui.html (next: http://localhost:55761/tests/dromaeo/cssquery-dojo.html) 07:26:51 INFO - PROCESS | 1937 | RSS: Main: 411885568 07:26:51 INFO - PROCESS | 1937 | 07:26:51 INFO - PROCESS | 1937 | __start_tp_report 07:26:51 INFO - PROCESS | 1937 | _x_x_mozilla_page_load 07:26:51 INFO - PROCESS | 1937 | _x_x_mozilla_page_load_details 07:26:51 INFO - PROCESS | 1937 | |i|pagename|runs| 07:26:51 INFO - PROCESS | 1937 | |0;dojo.html;3330.6336303006665;3345.2640419107797;3358.04295775704;3400.608929973054;3429.6741809528103;5631.099024156136;5728.942710572893;5765.452282033205;5823.941760582398;5826.446487583683;5763;5797.623154494965;5850.707464626773;5860.443257890493;5884.0879663652095;5156.8710782230555;5185.2999845021;5214.087534681435;5263.105272103732;5335.413104558495;5309.097453432925;5350.839474815762;5354.170103633943;5359.222912677659;5381.650192737479;4398.846040388603;5150.9742451287875;5183.740812959337;5198.610104242159;5212.50481204285;5188.714620695898;5208.244804503363;5223.112070947948;5238.607104467142;5349.384820745625;5178.689278643255;5184.248283998799;5201.45384734605;5231.267622532849;5259.894802103975;5650.29371328584;5695.34503532095;5806.042003069489;5811.622244554091;5816.185733997244;6113.663748493831;6116.822755456448;6118.969405152946;6123.2652081750475;6152.753889844401;6156.261248650146;6200.255969283715;6230.501559875199;6240.407161319713;6251.87496250077;5732.799352022659;5900.055991041413;5913.645181289136;5918.556108291809;5918.763249470016;6161.353057928853;6230.501559875199;6239.812805615838;6244.313125556185;6336.6831658416895;6166.506679465632;6180.6909654517995;6185.783497577563;6186.81439556814;6200.596961197598;6167.105769663463;6175.84560385994;6255.999040153553;6259.18630578031;6286.4342209201395;6107.969460152671;6121.7578389577775;6126.731429653714;6176.598521096114;6182.28903676083;6273.96863015682;6275.780347687809;6282.748690052438;6283.465905398001;6295.5907865988565;4959.082569724613;4965.3048573199785;4966.975165124223;4974.8756281093265;4995.9500404996215;4375.321825117112;4402.84590039347;4405.559444055569;4464.598186163261;4490.753008584494;5780.913286300709;5833.649981001154;5851.678157701367;5871.765129394862;5890.705464726747;6254.312025677172;6254.562180647402;6260.780872669435;6263.060540918807;6282.120503129566;5598.720063996784;5686.175504551817;5737.168110623936;5804.912926306109;5814.389489103668;5464.863378415492;5484.314460692413;5492.47821456961;5540.168974653834;5541.418151094158;6303.621782693053;6393.104965304954;6403.967980160162;6408.29508754046;6409.230892292955;6163.414475624809;6187.659678717714;6251.062340648939;6272.184616000073;6285.2457705075685;6294.748210071729;6399.904001440075;6406.903896441464;6407.807765767035;6419.582727122722;6220.7511699533325;6230.657313847601;6233.252009758858;6243.43809057187;6250.281217660027;6090.5757605786985;6148.077788331607;6267.592606480564;6280.152179455898;6306.400891915261;4668.416447944007;6155.753769849335;6195.2875419325555;6212.596181248205;6220.222472190976;6151.0773383992755;6190.783322583688;6191.535634827496;6205.100260462298;6217.378262173746;4961.578265847444;5880.8235752927485;6076.817695469143;6088.178095957166;6105.603135796159;6131.019036954066;6163.691815409301;6167.969160154171;6183.659898705434;6190.597611155441;4989.875253118701;6037.754694366689;6046.455818976313;6048.969755151196;6125.601835880653 07:26:51 INFO - PROCESS | 1937 | |1;ext.html;468.4495717531899;484.7479310758406;496.4836569967234;522.1306524636481;540.9675419474831;31006;41096.38355424667;41725.37411938823;41777;43574.56425435745;42361.364579531335;42679;42716.359254611125;42811;42833.35749963753;9687.70936871895;9864.506774661275;9868.21054315653;9875.40747555149;9885.456299903502;8650.913490865074;8867.556622168899;11967.760644787098;12201.145919785618;12220.938895305511;12189.26864388135;12273.693157671041;12281.324527151002;12293.938530307336;12310;12204.816927746091;12259.325737084457;12273.20224185434;12273.509059637603;12283.754324913452;12090.274583524928;12147.757044859054;12153.210041347284;12161.087918406154;12166.878331216663;39911.60088399137;40216.79349619516;40220;40274.59725402738;40506.39240411396;3514.3498452786316;3524.8942531724088;3538.9115272118147;3780.262848744496;3791.563970143441;30665.540016899766;31220.9072682455;31239.531407028913;31627.683723162932;31681.52477712836;1369.9657508562366;2719.415325704963;3168.461361568539;3238.4656531672244;3298.835058247078;207551;365451.0729356247;487250;489577.99999999645;490848;61108.69445652832;61659.075113873325;61942;61995;62070.68964655148;58210.70894645499;64704;64770.676146618964;64833.35166648367;64896;15385.461508847136;16413;16427.342906283615;16448.75326870098;16483.423080192137;11645.301281923112;11828.34944078084;12597.055220858289;12802.487900484062;12830.29433381173;19121.61756764857;19129.808701912803;19425.708614370797;19700.70448943267;19710.605787884448;6570.441512471398;7777;7927.027364738223;8012.479188852706;8012.79968000793;779.3648176735942;812.5409143833715;883.262036052526;930.9813803723888;969.6751588217885;22634;29465.85267073694;29665;30251.697483025328;30681.232969175948;998.55065220651;1040.2926756427228;1048.7587854793287;1077.2082519348273;1190.7737529869298;15219.391224350902;15862.365505379887;15867.841321586637;15931.964422312512;16325.34698612066;610576;614311;616522.5955793718;617296;618707;57258;57807.71096144492;57890.4210957885;57980.71009645009;58002.12996805051;1206.1376116077047;1249.5314257153568;1325.688463211164;1339.34372157644;1357.2942070123481;30179.52111058418;30808.767649294674;30833.61248743767;31468.42657867052;32031.839840800647;49279.753601231765;49773;49789.751051244515;49816;49974;508713;511035;512235;513255;514517;23750.287491375286;24225.394365140306;24241.03035878545;26872.865635671697;27189.456210876066;41476.79261603673;43327.35008974805;45456.31815522836;46848;47357.76321118372;37975;38695;38943;39604;40433.59566404416;38291;38653;38672;40026.39960400655;40188.79905600454;37143.44284835675;38000.23999520049;38469.807650961564;38539.036524087125;38615.2276954465;30677.693223067483;33907.83046084853;34702.65297346994;34844;34940.82529587336;25332.366690832878;25684.871575642002;25840.48319033571;31698.049058528282;32292 07:26:51 INFO - PROCESS | 1937 | |2;jquery.html;351.906744712651;374.5019124564329;377.52054890289315;386.30079556003625;409.5679058593183;14873.405063797449;15041.398344066238;20445;20532.89733551334;20609.175632974664;19325.516862078453;20277;20466.69299960498;20469.48826279344;21127.683084753742;5165.974170129144;5846.21076154718;6005.249343832021;6052.939470605282;6069.908951365733;5908.497777688902;5944.583879128462;5959.731812068468;5961.37405572413;5971.940280597204;5584.944150558483;5898.439648233432;5926.822195334147;5935.28776546815;5954.761809527614;5735.799247026334;5812.82561523155;5848.912266316009;5867.647941123525;5871.853203669921;5735.655860648333;5830.825075247707;5867.76528938842;5873.412658734135;5877;13161.605151845364;13388.665283367896;23294.417639559146;24060.759392406027;25395.365115872068;2076.1280262289724;2296.7240101656616;2345.94135146621;2913.7231962963483;2930.1356099950567;9912.55393507294;10082.143017843493;10111.595536178544;10153.746156346077;10204.591816327413;1779.74193741908;1793.5516120969758;1800.2798880447795;1813.7370081338263;1884.8492120630453;126467;126557.36721316335;129396.7060329394;130591;134016;9966.35218710796;10143.847842282372;11374.033207177483;11482.94258528702;11652;14454.783178252335;14456;14458.349374278183;14476.34856431463;14478.782818257734;8147.75556733299;8452.78868028292;8475.406721529556;8618;8639.13608639131;6927.93072069283;8466.872996905051;8476.660933562713;8632.525211113323;8640.740777776551;3767.981160094182;4349.093280245775;4444.177827101932;4478.1715382653665;4603.447586289667;1545.65995480994;1726.9481915542553;1942.990285048594;1943.9016955420143;1948.8051194880682;762.5119923249125;791.5962858941981;796.7689370082727;914.721010091915;926.3006430145198;20617.896910515352;21147.894260528905;21252.36242912715;21278.680819787714;21446.78553214479;690.9601050419187;894.5661354243186;918.42598376015;919.7516670498928;942.8161508505913;10881.891181088246;10975.451227438596;11192.496337664825;11193.328400296008;11199.944000279946;557248.1302243756;572484;580762;592144;593891;92718;101852.23296974692;120186;126828;136689;929.9349045566745;955.9665411710557;966.3766870368573;1009.3489699144158;1011.9089281964511;18935.053247337302;18945.621087578445;19034.904825475784;19133.8086619132;19172.80827191766;35858.64141358553;45509.54490455053;50101.49898500968;51300.97398051943;52661.47338526719;56452;68331.31668683449;72078.63960680163;77731.61134194293;79844.60077699574;7815.452918295665;7989.001374828146;8208.630611622371;8220.671173153007;8315.126911674188;9176.403533770284;12410.75178496407;13115.344232788513;13154.21074735519;13744.518941836988;10999.780004400027;11055.668329950115;11254.887451125383;11707.356095414834;11740.647780566596;11087.392328112539;11384.772304554028;11562.768744624893;11578.478968446272;11863.34751588671;11530.654080377602;11586.304821710724;11901.702457438634;11920.76158476843;12006.519739210335;7973.880391794011;7988.6405111768945;8147.714829980922;8278.461899976483;8411.789705257173;8359.498430094214;8847.911520884709;8927.687530936646;11100.278481898651;11167.832482512606 07:26:51 INFO - PROCESS | 1937 | |3;mootools.html;1481.570344600066;1519.7112548615764;1708.5899384147808;1839.7884243312024;1876.2682553804018;8535.573221338931;9065.546722663858;9072.183503484684;9085.00064992851;9107.90892091079;7942.602869856513;8189.181081891823;8567.443116197443;8761.912380876205;8876.8668469973;2389.8685572293475;2422.0432928993037;2428.720697119836;2429.453372991072;2437.963865357216;2343.5547246023284;2370.7866292033714;2390.7011623547055;2390.9043638254448;2392.461696118377;2377.3105799318178;2393.2222027840935;2400.2799160251943;2404.627282771173;2409.650600662903;2367.9171229007075;2386.7971222446113;2396.1972739132325;2398.988005059981;2399.244238065004;2365.1840115160244;2384.9165279215317;2385.9642105368434;2388.677528533643;2393.054743376357;8849.601767920394;8877.422967507091;8963;8971.237444817132;8982.910170898338;5256.894862102775;5421.457854214586;5436.293281873371;5439.048166570874;5442.183672449125;3590.120420496995;3598.46023096535;3653.2328211075705;3657.871974480907;3680.963190368089;1075.4837677914566;1079.6760971708534;1081.967540973772;1096.901278884896;1105.6572462536558;11816.291022538675;11897.107716921266;11914.404279785977;11920.165588408729;11972.820407693891;4107.958920410817;4494.393256910341;4628.490866004737;4781.617470602343;4813.181759100961;9998.05018523239;10001.049900259464;10006.54970526328;10022.899771002196;10044.648437304659;4528.071745292207;5355.464453554691;5360.410354860962;5366.838994830161;5371.597130215249;4405.691601587922;4568.725876447424;4768.976155119202;4886.413630364379;4936.284238785356;3309.93380132396;3820.8280627371837;3850.4224366345275;3934.2918274710255;3947.151362457028;1108.7394462301352;1279.0982357437983;1288.3558220889556;1294.15932029195;1295.799151131576;787.4409169489612;815.824597711483;823.168599714281;910.9544522773834;952.9469935720929;5774.451427114417;5841.299044114733;5961.152042866725;5974.880502390015;6152.138700581922;380.4141621902295;435.3491530162427;439.4068008188983;449.2632083383254;457.6521843398974;3530.629283925151;3866.555346135174;3970.3250447424007;4042.818073186655;4081.8775436736946;10319.94840025795;10589.84115238272;10829.945850270698;10955.83566246507;11011.779764404668;10897;10899.128069754559;10908.727281817859;10912.399818009926;10914.072303854102;8056.637451314705;8247.628856701464;8345.54099524532;8352.206540378655;8354.916450835535;4995.375578052744;5923.674197919156;5935.34711181761;5956.344802071682;6014.939850601613;9966.352187107816;10162.237832162766;10185.185185185019;10208.897911020795;10264.281500294908;9897.307188496736;10267.383956962607;10370.948145259226;10377.636782712569;10379.429131397847;6945.791626251221;7155.356017958408;7158.248383919614;7530.359919406912;7705.267999540035;7959.4030447715495;8026.719064832703;8285.3785966054;8483.703070392754;8529.189726975927;7609.923900760921;7895.84208315819;7919.08930473003;7924.722634707758;8007.719729809429;7046.471514636525;7145.356917877416;7549.886751698838;7651.081870175614;7679.193684663253;6868.038474613458;7028;7028.718851246097;7048.330408611174;7053.894191587027;4915.6313276505125;5106.208537676666;5298.549623282064;5320.9467905322;5486.972565137149;5171.45699701541;5287.576993840406;5332.626716129989;5338.172583249602;5498.477644623755 07:26:51 INFO - PROCESS | 1937 | |4;prototype.html;300.9864556094977;303.6250230964759;313.2122711380878;317.88873894137055;318.2378204200937;4243.575642435755;5165.896682066356;5348.973255133719;5476.452354764527;5486.231927530144;5199.350081239845;5277.6305658603815;5327.537100884418;5341.385740639827;5407.83776486705;3292.736581073508;4048.2510735514024;4165.166966606683;4165.937510937339;4187.664986801064;3581.910452238689;3898.298306304864;4120.793960301988;4198.8530401435955;4199.55904630014;3416.6241713411505;4120.814563344657;4148.730332528376;4154.584541545852;4172.937405938898;4084.1831633673382;4084.8774536763794;4118.176364727066;4122.051928056564;4146.740026685464;3010.3979204159255;3636.236390358028;3636.8180341389157;3641.453781932731;3668.4497325401135;4397.81259060052;4856.611471082305;4885.804567817283;5023.472535383805;5188.169892817169;3921.313770090223;4677;4726.550977657117;4727.881802954919;4733.384659994179;4710.905781884343;5580.441955804387;5655.604107712462;5697.658140511541;5707.77168913243;3188;3949.2891279569667;3959.1289916218384;3976.70174736893;3976.7415118017525;5187.7406129693745;5910.852228694274;5957.8808423831715;5959.761609535613;5964.463198312129;4007;4026.275270451347;4038.5759495253164;4068.5524592294823;4081.591840815954;3285.342931413679;3465.059566642688;4039.3537034074407;4053.533843608023;4124.91750164995;3884.902877428087;4425.645948324126;4681.648876334289;4701.976490117528;4716.57550820421;5428.701421421781;5687.374388817227;5742.885142297131;5752.9424705753245;5770.172615418414;1124.5333186727416;1310.462710288777;1354.3296068446182;1357.260293140241;1378.9793153102712;912.4707669551644;922.6678395777515;936.2556767369957;958.8274110660009;968.3415277611292;5223.634345595848;5231.267622532849;5250.264962905197;5264.868378290496;5270.49930256625;5271.973640131775;5756.453136951983;5763.394843541451;5814.273215848019;5818.44724751148;3982.9402558961638;4642.280446530793;5189.247559103985;5220.556252718561;5243.68537887728;2180.4439867833603;2368.62102063669;2508.83692559983;2550.7831834294293;2558.5799881065814;4417.734935903856;4435.558443505809;4768.237082066922;5046.073277499115;5078.6795433187635;3954.963370863869;4219.936700949488;4397.978010109993;4945.67853089548;4973.378327709036;4307.806148723315;4318.050028993616;4984.40187177541;5008.223725322581;5035.043341765053;4091.133878484922;4500.549945005604;4634.698744581591;4825.927611085906;4902.681325713817;4519.322101684774;4677.415323084614;4702.764861756967;4753.809847606057;4764.595009424238;5001.949980500149;5632.9436705632415;6107.297660769068;6188.226471691039;6235.906461403173;3888.6694630955553;3908.550516690503;3933.4886464759397;3941.9408708869964;3968.265870813909;5485.561155107662;5923.555733319932;5938.138969849434;5962.105684147413;5985.730642121028;4647.604953578849;4812.663113582015;5807.245058142415;5809.331926828469;5819.883602328015;5260.736963151904;5763.567732420001;5767.8558036049435;5790.449907258803;5810.966161843321;4861.208257559109;4953.356063711694;5037.168867136881;5315.013739695229;5653.462921022497;4851.539103785135;5397.27136836522;5752.9712351437975;5777.8555536111935;5795.275590551181;5338.064104608589;5442.863928401822;5632.577556683347;5633.77465900975;5731.627444216113 07:26:51 INFO - PROCESS | 1937 | |5;yui.html;705.3158436316771;751.6918063593924;754.8678981178296;786.4927122006306;840.1136800675287;15278.236088195588;15779.974301670383;15966.521004369859;15985.840141598595;16711.91644041778;15931.203439827992;15987.600309992256;15991.920040399782;15996.440124595641;16049.35802567896;845.0746432656247;875.3434923807145;876.6975393489236;877.675260153744;883.690708252113;687.0312858868996;705.6224919667978;707.9646017699121;711.7259854955853;713.5754226235407;883.7083762358438;906.4561263242035;909.0636644256426;919.9034101419355;921.4056933278032;902.1654969153507;902.309733054214;905.1310741687954;905.6649039855296;905.9320550958638;529.6001518853241;672.2806596941275;672.6233309346733;672.7947975867359;674.4469534981317;14515.346809393603;14888.702225955529;14964.850351496454;14967.850321496755;15029.624259393493;13918.373673184731;14260.85739142616;14360;14381.568552943323;14545.27273636314;11616.59341923037;11852.940735296355;11922.344271065089;11931.701707457296;12260.44827982743;3777.9811100944594;3789.3558095123894;3805.315043292206;3847.7498962567342;3948.8617898373705;120431.1935320971;121920;124462;125229;127468.36265818705;11002.669919902415;11043.570414508795;11064.723381915517;11148.721281968015;11270.042046425982;22642;22654.320370388916;22657.88671056634;22700.65949010766;22715.43211419695;7749.341305988942;7900.249476299742;8103.756887293391;8104.189581041848;8110.878336824952;4160.313548264502;4275.080857615628;4294.055307832272;4349.456317960255;4409.140217657526;3057.1745628680133;3498.160441494023;3527.030066731669;3613.5663720353728;3614.530111085595;555.5111501878321;594.7947957954499;629.156929714176;639.996800015997;649.2792999770252;504.90715982254085;537.792655488431;665.7603262825379;686.3959715450471;693.5318659904544;9238.630454781733;9257.21313688344;9313.34806563534;9393.62425502986;9420;547.9995045212437;637.9283748749755;659.9175103112111;664.833791552112;693.3032302535919;4452.30989196675;4563.247064234431;4580.885477863014;4591.885202869888;4646.651501137429;95914;98944;130006.34996824956;167555.32444675398;169346;134801.3259933694;136825;138372;140120;141901.29049354687;82258.12096576711;115422;120208.19687704691;121946;124423;8828.735137945872;8840.734777956672;8894.644214231359;9052;9165.129312715282;59195.704021479614;64315.105820237266;65375;66646;67984;84816.57591712002;86403.56798215969;91247.08752912386;100501.98996020186;108920;13262.485441357854;13311.66720831987;13431.261280629658;13718.588442346745;13851.584452466443;14880.702385952436;15011.474598389004;15049.54851354461;15066.849331506544;15478.071315721092;11261.71845703864;12157.817632735692;13479.865121891979;13814.033017688665;13987.720245595234;12894.355282235663;13789.655258618615;13808.792868107188;13855.445782168601;14247.430102795772;13463.306607084334;14070.577882663536;14151.929240353733;14171.291435427982;14231;10780.946095269474;11255.66233013011;11258.718532036764;11295.887041129812;11495.48270327854;10082.546285417027;10456.74519057718;11079.689724874579;11378.54485820558;11501.654950351503 07:26:51 INFO - PROCESS | 1937 | __end_tp_report 07:26:51 INFO - PROCESS | 1937 | __start_cc_report 07:26:51 INFO - PROCESS | 1937 | _x_x_mozilla_cycle_collect,1163 07:26:51 INFO - PROCESS | 1937 | __end_cc_report 07:26:51 INFO - PROCESS | 1937 | __startTimestamp1472740011278__endTimestamp 07:26:51 INFO - PROCESS | 1937 | 07:26:51 INFO - PROCESS | 1937 | ------- Summary: start ------- 07:26:51 INFO - PROCESS | 1937 | Number of tests: 6 07:26:51 INFO - PROCESS | 1937 | 07:26:51 INFO - PROCESS | 1937 | [#0] dojo.html Cycles:165 Average:5763.52 Median:6076.82 stddev:656.01 (10.8%) stddev-sans-first:629.64 07:26:51 INFO - PROCESS | 1937 | Values: 3330.6 3345.3 3358.0 3400.6 3429.7 5631.1 5728.9 5765.5 5823.9 5826.4 5763.0 5797.6 5850.7 5860.4 5884.1 5156.9 5185.3 5214.1 5263.1 5335.4 5309.1 5350.8 5354.2 5359.2 5381.7 4398.8 5151.0 5183.7 5198.6 5212.5 5188.7 5208.2 5223.1 5238.6 5349.4 5178.7 5184.2 5201.5 5231.3 5259.9 5650.3 5695.3 5806.0 5811.6 5816.2 6113.7 6116.8 6119.0 6123.3 6152.8 6156.3 6200.3 6230.5 6240.4 6251.9 5732.8 5900.1 5913.6 5918.6 5918.8 6161.4 6230.5 6239.8 6244.3 6336.7 6166.5 6180.7 6185.8 6186.8 6200.6 6167.1 6175.8 6256.0 6259.2 6286.4 6108.0 6121.8 6126.7 6176.6 6182.3 6274.0 6275.8 6282.7 6283.5 6295.6 4959.1 4965.3 4967.0 4974.9 4996.0 4375.3 4402.8 4405.6 4464.6 4490.8 5780.9 5833.6 5851.7 5871.8 5890.7 6254.3 6254.6 6260.8 6263.1 6282.1 5598.7 5686.2 5737.2 5804.9 5814.4 5464.9 5484.3 5492.5 5540.2 5541.4 6303.6 6393.1 6404.0 6408.3 6409.2 6163.4 6187.7 6251.1 6272.2 6285.2 6294.7 6399.9 6406.9 6407.8 6419.6 6220.8 6230.7 6233.3 6243.4 6250.3 6090.6 6148.1 6267.6 6280.2 6306.4 4668.4 6155.8 6195.3 6212.6 6220.2 6151.1 6190.8 6191.5 6205.1 6217.4 4961.6 5880.8 6076.8 6088.2 6105.6 6131.0 6163.7 6168.0 6183.7 6190.6 4989.9 6037.8 6046.5 6049.0 6125.6 07:26:51 INFO - PROCESS | 1937 | 07:26:51 INFO - PROCESS | 1937 | [#1] ext.html Cycles:180 Average:65818.13 Median:20883.28 stddev:139462.92 (667.8%) stddev-sans-first:139767.84 07:26:51 INFO - PROCESS | 1937 | Values: 468.4 484.7 496.5 522.1 541.0 31006.0 41096.4 41725.4 41777.0 43574.6 42361.4 42679.0 42716.4 42811.0 42833.4 9687.7 9864.5 9868.2 9875.4 9885.5 8650.9 8867.6 11967.8 12201.1 12220.9 12189.3 12273.7 12281.3 12293.9 12310.0 12204.8 12259.3 12273.2 12273.5 12283.8 12090.3 12147.8 12153.2 12161.1 12166.9 39911.6 40216.8 40220.0 40274.6 40506.4 3514.3 3524.9 3538.9 3780.3 3791.6 30665.5 31220.9 31239.5 31627.7 31681.5 1370.0 2719.4 3168.5 3238.5 3298.8 207551.0 365451.1 487250.0 489578.0 490848.0 61108.7 61659.1 61942.0 61995.0 62070.7 58210.7 64704.0 64770.7 64833.4 64896.0 15385.5 16413.0 16427.3 16448.8 16483.4 11645.3 11828.3 12597.1 12802.5 12830.3 19121.6 19129.8 19425.7 19700.7 19710.6 6570.4 7777.0 7927.0 8012.5 8012.8 779.4 812.5 883.3 931.0 969.7 22634.0 29465.9 29665.0 30251.7 30681.2 998.6 1040.3 1048.8 1077.2 1190.8 15219.4 15862.4 15867.8 15932.0 16325.3 610576.0 614311.0 616522.6 617296.0 618707.0 57258.0 57807.7 57890.4 57980.7 58002.1 1206.1 1249.5 1325.7 1339.3 1357.3 30179.5 30808.8 30833.6 31468.4 32031.8 49279.8 49773.0 49789.8 49816.0 49974.0 508713.0 511035.0 512235.0 513255.0 514517.0 23750.3 24225.4 24241.0 26872.9 27189.5 41476.8 43327.4 45456.3 46848.0 47357.8 37975.0 38695.0 38943.0 39604.0 40433.6 38291.0 38653.0 38672.0 40026.4 40188.8 37143.4 38000.2 38469.8 38539.0 38615.2 30677.7 33907.8 34702.7 34844.0 34940.8 25332.4 25684.9 25840.5 31698.0 32292.0 07:26:51 INFO - PROCESS | 1937 | 07:26:51 INFO - PROCESS | 1937 | [#2] jquery.html Cycles:180 Average:33890.14 Median:18105.27 stddev:96855.78 (535.0%) stddev-sans-first:97094.75 07:26:51 INFO - PROCESS | 1937 | Values: 351.9 374.5 377.5 386.3 409.6 14873.4 15041.4 20445.0 20532.9 20609.2 19325.5 20277.0 20466.7 20469.5 21127.7 5166.0 5846.2 6005.2 6052.9 6069.9 5908.5 5944.6 5959.7 5961.4 5971.9 5584.9 5898.4 5926.8 5935.3 5954.8 5735.8 5812.8 5848.9 5867.6 5871.9 5735.7 5830.8 5867.8 5873.4 5877.0 13161.6 13388.7 23294.4 24060.8 25395.4 2076.1 2296.7 2345.9 2913.7 2930.1 9912.6 10082.1 10111.6 10153.7 10204.6 1779.7 1793.6 1800.3 1813.7 1884.8 126467.0 126557.4 129396.7 130591.0 134016.0 9966.4 10143.8 11374.0 11482.9 11652.0 14454.8 14456.0 14458.3 14476.3 14478.8 8147.8 8452.8 8475.4 8618.0 8639.1 6927.9 8466.9 8476.7 8632.5 8640.7 3768.0 4349.1 4444.2 4478.2 4603.4 1545.7 1726.9 1943.0 1943.9 1948.8 762.5 791.6 796.8 914.7 926.3 20617.9 21147.9 21252.4 21278.7 21446.8 691.0 894.6 918.4 919.8 942.8 10881.9 10975.5 11192.5 11193.3 11199.9 557248.1 572484.0 580762.0 592144.0 593891.0 92718.0 101852.2 120186.0 126828.0 136689.0 929.9 956.0 966.4 1009.3 1011.9 18935.1 18945.6 19034.9 19133.8 19172.8 35858.6 45509.5 50101.5 51301.0 52661.5 56452.0 68331.3 72078.6 77731.6 79844.6 7815.5 7989.0 8208.6 8220.7 8315.1 9176.4 12410.8 13115.3 13154.2 13744.5 10999.8 11055.7 11254.9 11707.4 11740.6 11087.4 11384.8 11562.8 11578.5 11863.3 11530.7 11586.3 11901.7 11920.8 12006.5 7973.9 7988.6 8147.7 8278.5 8411.8 8359.5 8847.9 8927.7 11100.3 11167.8 07:26:51 INFO - PROCESS | 1937 | 07:26:51 INFO - PROCESS | 1937 | [#3] mootools.html Cycles:180 Average:5755.43 Median:2513.19 stddev:3268.21 (130.0%) stddev-sans-first:3261.60 07:26:51 INFO - PROCESS | 1937 | Values: 1481.6 1519.7 1708.6 1839.8 1876.3 8535.6 9065.5 9072.2 9085.0 9107.9 7942.6 8189.2 8567.4 8761.9 8876.9 2389.9 2422.0 2428.7 2429.5 2438.0 2343.6 2370.8 2390.7 2390.9 2392.5 2377.3 2393.2 2400.3 2404.6 2409.7 2367.9 2386.8 2396.2 2399.0 2399.2 2365.2 2384.9 2386.0 2388.7 2393.1 8849.6 8877.4 8963.0 8971.2 8982.9 5256.9 5421.5 5436.3 5439.0 5442.2 3590.1 3598.5 3653.2 3657.9 3681.0 1075.5 1079.7 1082.0 1096.9 1105.7 11816.3 11897.1 11914.4 11920.2 11972.8 4108.0 4494.4 4628.5 4781.6 4813.2 9998.1 10001.0 10006.5 10022.9 10044.6 4528.1 5355.5 5360.4 5366.8 5371.6 4405.7 4568.7 4769.0 4886.4 4936.3 3309.9 3820.8 3850.4 3934.3 3947.2 1108.7 1279.1 1288.4 1294.2 1295.8 787.4 815.8 823.2 911.0 952.9 5774.5 5841.3 5961.2 5974.9 6152.1 380.4 435.3 439.4 449.3 457.7 3530.6 3866.6 3970.3 4042.8 4081.9 10319.9 10589.8 10829.9 10955.8 11011.8 10897.0 10899.1 10908.7 10912.4 10914.1 8056.6 8247.6 8345.5 8352.2 8354.9 4995.4 5923.7 5935.3 5956.3 6014.9 9966.4 10162.2 10185.2 10208.9 10264.3 9897.3 10267.4 10370.9 10377.6 10379.4 6945.8 7155.4 7158.2 7530.4 7705.3 7959.4 8026.7 8285.4 8483.7 8529.2 7609.9 7895.8 7919.1 7924.7 8007.7 7046.5 7145.4 7549.9 7651.1 7679.2 6868.0 7028.0 7028.7 7048.3 7053.9 4915.6 5106.2 5298.5 5320.9 5487.0 5171.5 5287.6 5332.6 5338.2 5498.5 07:26:51 INFO - PROCESS | 1937 | 07:26:51 INFO - PROCESS | 1937 | [#4] prototype.html Cycles:180 Average:4430.68 Median:4766.42 stddev:1364.37 (28.6%) stddev-sans-first:1332.53 07:26:51 INFO - PROCESS | 1937 | Values: 301.0 303.6 313.2 317.9 318.2 4243.6 5165.9 5349.0 5476.5 5486.2 5199.4 5277.6 5327.5 5341.4 5407.8 3292.7 4048.3 4165.2 4165.9 4187.7 3581.9 3898.3 4120.8 4198.9 4199.6 3416.6 4120.8 4148.7 4154.6 4172.9 4084.2 4084.9 4118.2 4122.1 4146.7 3010.4 3636.2 3636.8 3641.5 3668.4 4397.8 4856.6 4885.8 5023.5 5188.2 3921.3 4677.0 4726.6 4727.9 4733.4 4710.9 5580.4 5655.6 5697.7 5707.8 3188.0 3949.3 3959.1 3976.7 3976.7 5187.7 5910.9 5957.9 5959.8 5964.5 4007.0 4026.3 4038.6 4068.6 4081.6 3285.3 3465.1 4039.4 4053.5 4124.9 3884.9 4425.6 4681.6 4702.0 4716.6 5428.7 5687.4 5742.9 5752.9 5770.2 1124.5 1310.5 1354.3 1357.3 1379.0 912.5 922.7 936.3 958.8 968.3 5223.6 5231.3 5250.3 5264.9 5270.5 5272.0 5756.5 5763.4 5814.3 5818.4 3982.9 4642.3 5189.2 5220.6 5243.7 2180.4 2368.6 2508.8 2550.8 2558.6 4417.7 4435.6 4768.2 5046.1 5078.7 3955.0 4219.9 4398.0 4945.7 4973.4 4307.8 4318.1 4984.4 5008.2 5035.0 4091.1 4500.5 4634.7 4825.9 4902.7 4519.3 4677.4 4702.8 4753.8 4764.6 5001.9 5632.9 6107.3 6188.2 6235.9 3888.7 3908.6 3933.5 3941.9 3968.3 5485.6 5923.6 5938.1 5962.1 5985.7 4647.6 4812.7 5807.2 5809.3 5819.9 5260.7 5763.6 5767.9 5790.4 5811.0 4861.2 4953.4 5037.2 5315.0 5653.5 4851.5 5397.3 5753.0 5777.9 5795.3 5338.1 5442.9 5632.6 5633.8 5731.6 07:26:51 INFO - PROCESS | 1937 | 07:26:51 INFO - PROCESS | 1937 | [#5] yui.html Cycles:180 Average:25386.27 Median:3512.60 stddev:40677.97 (1158.1%) stddev-sans-first:40749.87 07:26:51 INFO - PROCESS | 1937 | Values: 705.3 751.7 754.9 786.5 840.1 15278.2 15780.0 15966.5 15985.8 16711.9 15931.2 15987.6 15991.9 15996.4 16049.4 845.1 875.3 876.7 877.7 883.7 687.0 705.6 708.0 711.7 713.6 883.7 906.5 909.1 919.9 921.4 902.2 902.3 905.1 905.7 905.9 529.6 672.3 672.6 672.8 674.4 14515.3 14888.7 14964.9 14967.9 15029.6 13918.4 14260.9 14360.0 14381.6 14545.3 11616.6 11852.9 11922.3 11931.7 12260.4 3778.0 3789.4 3805.3 3847.7 3948.9 120431.2 121920.0 124462.0 125229.0 127468.4 11002.7 11043.6 11064.7 11148.7 11270.0 22642.0 22654.3 22657.9 22700.7 22715.4 7749.3 7900.2 8103.8 8104.2 8110.9 4160.3 4275.1 4294.1 4349.5 4409.1 3057.2 3498.2 3527.0 3613.6 3614.5 555.5 594.8 629.2 640.0 649.3 504.9 537.8 665.8 686.4 693.5 9238.6 9257.2 9313.3 9393.6 9420.0 548.0 637.9 659.9 664.8 693.3 4452.3 4563.2 4580.9 4591.9 4646.7 95914.0 98944.0 130006.3 167555.3 169346.0 134801.3 136825.0 138372.0 140120.0 141901.3 82258.1 115422.0 120208.2 121946.0 124423.0 8828.7 8840.7 8894.6 9052.0 9165.1 59195.7 64315.1 65375.0 66646.0 67984.0 84816.6 86403.6 91247.1 100502.0 108920.0 13262.5 13311.7 13431.3 13718.6 13851.6 14880.7 15011.5 15049.5 15066.8 15478.1 11261.7 12157.8 13479.9 13814.0 13987.7 12894.4 13789.7 13808.8 13855.4 14247.4 13463.3 14070.6 14151.9 14171.3 14231.0 10780.9 11255.7 11258.7 11295.9 11495.5 10082.5 10456.7 11079.7 11378.5 11501.7 07:26:51 INFO - PROCESS | 1937 | -------- Summary: end -------- 07:26:51 INFO - PROCESS | 1937 | 07:26:51 INFO - TEST-INFO | 1937: exit 0 07:26:51 INFO - TEST-OK | dromaeo_css | took 1117616ms 07:26:51 INFO - TEST-START | kraken 07:26:51 INFO - operating with platform_type : mac_ 07:26:51 INFO - Initialising browser for kraken test... 07:26:51 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmp92Gdv9/profile http://localhost:55761/getInfo.html) 07:26:54 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 07:26:54 INFO - PROCESS | 1939 | colorDepth:24 07:26:54 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 07:26:54 INFO - PROCESS | 1939 | __metrics 07:26:54 INFO - PROCESS | 1939 | 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" 07:26:54 INFO - PROCESS | 1939 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:26:54 INFO - TEST-INFO | 1939: exit 0 07:26:54 INFO - Browser initialized. 07:26:54 INFO - Running cycle 1/1 for kraken test... 07:26:54 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmp92Gdv9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:27:06 INFO - PROCESS | 1940 | RSS: Main: 233738240 07:27:06 INFO - PROCESS | 1940 | 07:27:09 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55761/tests/kraken/driver.html?testName=audio-beat-detection) 07:27:10 INFO - PROCESS | 1940 | RSS: Main: 292839424 07:27:10 INFO - PROCESS | 1940 | 07:27:13 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55761/tests/kraken/driver.html?testName=audio-dft) 07:27:13 INFO - PROCESS | 1940 | RSS: Main: 289329152 07:27:13 INFO - PROCESS | 1940 | 07:27:17 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55761/tests/kraken/driver.html?testName=audio-fft) 07:27:18 INFO - PROCESS | 1940 | RSS: Main: 380694528 07:27:18 INFO - PROCESS | 1940 | 07:27:20 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55761/tests/kraken/driver.html?testName=audio-oscillator) 07:27:20 INFO - PROCESS | 1940 | RSS: Main: 281350144 07:27:20 INFO - PROCESS | 1940 | 07:27:22 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55761/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:27:23 INFO - PROCESS | 1940 | RSS: Main: 285204480 07:27:23 INFO - PROCESS | 1940 | 07:27:26 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55761/tests/kraken/driver.html?testName=imaging-darkroom) 07:27:26 INFO - PROCESS | 1940 | RSS: Main: 322908160 07:27:26 INFO - PROCESS | 1940 | 07:27:29 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55761/tests/kraken/driver.html?testName=imaging-desaturate) 07:27:30 INFO - PROCESS | 1940 | RSS: Main: 323584000 07:27:30 INFO - PROCESS | 1940 | 07:27:33 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55761/tests/kraken/driver.html?testName=json-parse-financial) 07:27:33 INFO - PROCESS | 1940 | RSS: Main: 323010560 07:27:33 INFO - PROCESS | 1940 | 07:27:35 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55761/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:27:36 INFO - PROCESS | 1940 | RSS: Main: 297394176 07:27:36 INFO - PROCESS | 1940 | 07:27:37 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55761/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:27:38 INFO - PROCESS | 1940 | RSS: Main: 302735360 07:27:38 INFO - PROCESS | 1940 | 07:27:40 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55761/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:27:41 INFO - PROCESS | 1940 | RSS: Main: 297705472 07:27:41 INFO - PROCESS | 1940 | 07:27:44 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55761/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:27:44 INFO - PROCESS | 1940 | RSS: Main: 316432384 07:27:44 INFO - PROCESS | 1940 | 07:27:47 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55761/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:27:48 INFO - PROCESS | 1940 | RSS: Main: 312721408 07:27:48 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55761/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55761/tests/kraken/driver.html?testName=ai-astar) 07:27:50 INFO - PROCESS | 1940 | RSS: Main: 355876864 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | __start_tp_report 07:27:50 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 07:27:50 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 07:27:50 INFO - PROCESS | 1940 | |i|pagename|runs| 07:27:50 INFO - PROCESS | 1940 | |0;ai-astar;111;113;115;112;114;112;114;113;114;124 07:27:50 INFO - PROCESS | 1940 | |1;audio-beat-detection;154;109;137;191;94;152;158;136;182;106 07:27:50 INFO - PROCESS | 1940 | |2;audio-dft;187;184;183;186;184;186;224;185;183;181 07:27:50 INFO - PROCESS | 1940 | |3;audio-fft;78;74;75;156;58;58;57;59;149;57 07:27:50 INFO - PROCESS | 1940 | |4;audio-oscillator;82;84;137;71;73;82;120;69;69;117 07:27:50 INFO - PROCESS | 1940 | |5;imaging-gaussian-blur;86;84;87;84;87;90;133;114;87;84 07:27:50 INFO - PROCESS | 1940 | |6;imaging-darkroom;93;93;93;94;91;93;93;93;117;150 07:27:50 INFO - PROCESS | 1940 | |7;imaging-desaturate;99;135;127;85;85;86;86;86;85;86 07:27:50 INFO - PROCESS | 1940 | |8;json-parse-financial;52;52;55;54;53;53;55;53;54;54 07:27:50 INFO - PROCESS | 1940 | |9;json-stringify-tinderbox;67;68;77;77;73;71;72;71;70;73 07:27:50 INFO - PROCESS | 1940 | |10;stanford-crypto-aes;84;86;85;85;83;85;85;85;85;85 07:27:50 INFO - PROCESS | 1940 | |11;stanford-crypto-ccm;137;146;150;152;135;137;132;138;179;137 07:27:50 INFO - PROCESS | 1940 | |12;stanford-crypto-pbkdf2;169;168;168;167;167;162;166;168;167;169 07:27:50 INFO - PROCESS | 1940 | |13;stanford-crypto-sha256-iterative;66;67;67;67;67;67;66;66;65;66 07:27:50 INFO - PROCESS | 1940 | __end_tp_report 07:27:50 INFO - PROCESS | 1940 | __start_cc_report 07:27:50 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,2012 07:27:50 INFO - PROCESS | 1940 | __end_cc_report 07:27:50 INFO - PROCESS | 1940 | __startTimestamp1472740070289__endTimestamp 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | ------- Summary: start ------- 07:27:50 INFO - PROCESS | 1940 | Number of tests: 14 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.00 stddev:3.65 (3.2%) stddev-sans-first:3.68 07:27:50 INFO - PROCESS | 1940 | Values: 111.0 113.0 115.0 112.0 114.0 112.0 114.0 113.0 114.0 124.0 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#1] audio-beat-detection Cycles:10 Average:141.90 Median:156.00 stddev:32.06 (20.6%) stddev-sans-first:33.71 07:27:50 INFO - PROCESS | 1940 | Values: 154.0 109.0 137.0 191.0 94.0 152.0 158.0 136.0 182.0 106.0 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#2] audio-dft Cycles:10 Average:188.30 Median:185.50 stddev:12.67 (6.8%) stddev-sans-first:13.43 07:27:50 INFO - PROCESS | 1940 | Values: 187.0 184.0 183.0 186.0 184.0 186.0 224.0 185.0 183.0 181.0 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#3] audio-fft Cycles:10 Average:82.10 Median:58.50 stddev:38.04 (65.0%) stddev-sans-first:40.32 07:27:50 INFO - PROCESS | 1940 | Values: 78.0 74.0 75.0 156.0 58.0 58.0 57.0 59.0 149.0 57.0 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:72.00 stddev:24.79 (34.4%) stddev-sans-first:26.11 07:27:50 INFO - PROCESS | 1940 | Values: 82.0 84.0 137.0 71.0 73.0 82.0 120.0 69.0 69.0 117.0 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#5] imaging-gaussian-blur Cycles:10 Average:93.60 Median:86.50 stddev:16.49 (19.1%) stddev-sans-first:17.26 07:27:50 INFO - PROCESS | 1940 | Values: 86.0 84.0 87.0 84.0 87.0 90.0 133.0 114.0 87.0 84.0 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#6] imaging-darkroom Cycles:10 Average:101.00 Median:93.00 stddev:18.83 (20.2%) stddev-sans-first:19.74 07:27:50 INFO - PROCESS | 1940 | Values: 93.0 93.0 93.0 94.0 91.0 93.0 93.0 93.0 117.0 150.0 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:86.00 stddev:19.01 (22.1%) stddev-sans-first:20.14 07:27:50 INFO - PROCESS | 1940 | Values: 99.0 135.0 127.0 85.0 85.0 86.0 86.0 86.0 85.0 86.0 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:1.08 (2.0%) stddev-sans-first:1.00 07:27:50 INFO - PROCESS | 1940 | Values: 52.0 52.0 55.0 54.0 53.0 53.0 55.0 53.0 54.0 54.0 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.50 stddev:3.31 (4.6%) stddev-sans-first:3.00 07:27:50 INFO - PROCESS | 1940 | Values: 67.0 68.0 77.0 77.0 73.0 71.0 72.0 71.0 70.0 73.0 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:85.00 stddev:0.79 (0.9%) stddev-sans-first:0.78 07:27:50 INFO - PROCESS | 1940 | Values: 84.0 86.0 85.0 85.0 83.0 85.0 85.0 85.0 85.0 85.0 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#11] stanford-crypto-ccm Cycles:10 Average:144.30 Median:142.00 stddev:13.89 (9.8%) stddev-sans-first:14.48 07:27:50 INFO - PROCESS | 1940 | Values: 137.0 146.0 150.0 152.0 135.0 137.0 132.0 138.0 179.0 137.0 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.10 Median:168.00 stddev:2.02 (1.2%) stddev-sans-first:2.03 07:27:50 INFO - PROCESS | 1940 | Values: 169.0 168.0 168.0 167.0 167.0 162.0 166.0 168.0 167.0 169.0 07:27:50 INFO - PROCESS | 1940 | 07:27:50 INFO - PROCESS | 1940 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 07:27:50 INFO - PROCESS | 1940 | Values: 66.0 67.0 67.0 67.0 67.0 67.0 66.0 66.0 65.0 66.0 07:27:50 INFO - PROCESS | 1940 | -------- Summary: end -------- 07:27:50 INFO - PROCESS | 1940 | 07:27:51 INFO - TEST-INFO | 1940: exit 0 07:27:51 INFO - TEST-OK | kraken | took 59253ms 07:27:51 INFO - SUITE-END | took 1176s 07:27:51 INFO - Completed test suite (00:19:37) 07:27:51 INFO - javascript benchmark 07:27:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.6336303006665, 3345.2640419107797, 3358.04295775704, 3400.608929973054, 3429.6741809528103, 5631.099024156136, 5728.942710572893, 5765.452282033205, 5823.941760582398, 5826.446487583683, 5763.0, 5797.623154494965, 5850.707464626773, 5860.443257890493, 5884.0879663652095, 5156.8710782230555, 5185.2999845021, 5214.087534681435, 5263.105272103732, 5335.413104558495, 5309.097453432925, 5350.839474815762, 5354.170103633943, 5359.222912677659, 5381.650192737479, 4398.846040388603, 5150.9742451287875, 5183.740812959337, 5198.610104242159, 5212.50481204285, 5188.714620695898, 5208.244804503363, 5223.112070947948, 5238.607104467142, 5349.384820745625, 5178.689278643255, 5184.248283998799, 5201.45384734605, 5231.267622532849, 5259.894802103975, 5650.29371328584, 5695.34503532095, 5806.042003069489, 5811.622244554091, 5816.185733997244, 6113.663748493831, 6116.822755456448, 6118.969405152946, 6123.2652081750475, 6152.753889844401, 6156.261248650146, 6200.255969283715, 6230.501559875199, 6240.407161319713, 6251.87496250077, 5732.799352022659, 5900.055991041413, 5913.645181289136, 5918.556108291809, 5918.763249470016, 6161.353057928853, 6230.501559875199, 6239.812805615838, 6244.313125556185, 6336.6831658416895, 6166.506679465632, 6180.6909654517995, 6185.783497577563, 6186.81439556814, 6200.596961197598, 6167.105769663463, 6175.84560385994, 6255.999040153553, 6259.18630578031, 6286.4342209201395, 6107.969460152671, 6121.7578389577775, 6126.731429653714, 6176.598521096114, 6182.28903676083, 6273.96863015682, 6275.780347687809, 6282.748690052438, 6283.465905398001, 6295.5907865988565, 4959.082569724613, 4965.3048573199785, 4966.975165124223, 4974.8756281093265, 4995.9500404996215, 4375.321825117112, 4402.84590039347, 4405.559444055569, 4464.598186163261, 4490.753008584494, 5780.913286300709, 5833.649981001154, 5851.678157701367, 5871.765129394862, 5890.705464726747, 6254.312025677172, 6254.562180647402, 6260.780872669435, 6263.060540918807, 6282.120503129566, 5598.720063996784, 5686.175504551817, 5737.168110623936, 5804.912926306109, 5814.389489103668, 5464.863378415492, 5484.314460692413, 5492.47821456961, 5540.168974653834, 5541.418151094158, 6303.621782693053, 6393.104965304954, 6403.967980160162, 6408.29508754046, 6409.230892292955, 6163.414475624809, 6187.659678717714, 6251.062340648939, 6272.184616000073, 6285.2457705075685, 6294.748210071729, 6399.904001440075, 6406.903896441464, 6407.807765767035, 6419.582727122722, 6220.7511699533325, 6230.657313847601, 6233.252009758858, 6243.43809057187, 6250.281217660027, 6090.5757605786985, 6148.077788331607, 6267.592606480564, 6280.152179455898, 6306.400891915261, 4668.416447944007, 6155.753769849335, 6195.2875419325555, 6212.596181248205, 6220.222472190976, 6151.0773383992755, 6190.783322583688, 6191.535634827496, 6205.100260462298, 6217.378262173746, 4961.578265847444, 5880.8235752927485, 6076.817695469143, 6088.178095957166, 6105.603135796159, 6131.019036954066, 6163.691815409301, 6167.969160154171, 6183.659898705434, 6190.597611155441, 4989.875253118701, 6037.754694366689, 6046.455818976313, 6048.969755151196, 6125.601835880653], "value": 5721.978227714738, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.4495717531899, 484.7479310758406, 496.4836569967234, 522.1306524636481, 540.9675419474831, 31006.0, 41096.38355424667, 41725.37411938823, 41777.0, 43574.56425435745, 42361.364579531335, 42679.0, 42716.359254611125, 42811.0, 42833.35749963753, 9687.70936871895, 9864.506774661275, 9868.21054315653, 9875.40747555149, 9885.456299903502, 8650.913490865074, 8867.556622168899, 11967.760644787098, 12201.145919785618, 12220.938895305511, 12189.26864388135, 12273.693157671041, 12281.324527151002, 12293.938530307336, 12310.0, 12204.816927746091, 12259.325737084457, 12273.20224185434, 12273.509059637603, 12283.754324913452, 12090.274583524928, 12147.757044859054, 12153.210041347284, 12161.087918406154, 12166.878331216663, 39911.60088399137, 40216.79349619516, 40220.0, 40274.59725402738, 40506.39240411396, 3514.3498452786316, 3524.8942531724088, 3538.9115272118147, 3780.262848744496, 3791.563970143441, 30665.540016899766, 31220.9072682455, 31239.531407028913, 31627.683723162932, 31681.52477712836, 1369.9657508562366, 2719.415325704963, 3168.461361568539, 3238.4656531672244, 3298.835058247078, 207551.0, 365451.0729356247, 487250.0, 489577.99999999645, 490848.0, 61108.69445652832, 61659.075113873325, 61942.0, 61995.0, 62070.68964655148, 58210.70894645499, 64704.0, 64770.676146618964, 64833.35166648367, 64896.0, 15385.461508847136, 16413.0, 16427.342906283615, 16448.75326870098, 16483.423080192137, 11645.301281923112, 11828.34944078084, 12597.055220858289, 12802.487900484062, 12830.29433381173, 19121.61756764857, 19129.808701912803, 19425.708614370797, 19700.70448943267, 19710.605787884448, 6570.441512471398, 7777.0, 7927.027364738223, 8012.479188852706, 8012.79968000793, 779.3648176735942, 812.5409143833715, 883.262036052526, 930.9813803723888, 969.6751588217885, 22634.0, 29465.85267073694, 29665.0, 30251.697483025328, 30681.232969175948, 998.55065220651, 1040.2926756427228, 1048.7587854793287, 1077.2082519348273, 1190.7737529869298, 15219.391224350902, 15862.365505379887, 15867.841321586637, 15931.964422312512, 16325.34698612066, 610576.0, 614311.0, 616522.5955793718, 617296.0, 618707.0, 57258.0, 57807.71096144492, 57890.4210957885, 57980.71009645009, 58002.12996805051, 1206.1376116077047, 1249.5314257153568, 1325.688463211164, 1339.34372157644, 1357.2942070123481, 30179.52111058418, 30808.767649294674, 30833.61248743767, 31468.42657867052, 32031.839840800647, 49279.753601231765, 49773.0, 49789.751051244515, 49816.0, 49974.0, 508713.0, 511035.0, 512235.0, 513255.0, 514517.0, 23750.287491375286, 24225.394365140306, 24241.03035878545, 26872.865635671697, 27189.456210876066, 41476.79261603673, 43327.35008974805, 45456.31815522836, 46848.0, 47357.76321118372, 37975.0, 38695.0, 38943.0, 39604.0, 40433.59566404416, 38291.0, 38653.0, 38672.0, 40026.39960400655, 40188.79905600454, 37143.44284835675, 38000.23999520049, 38469.807650961564, 38539.036524087125, 38615.2276954465, 30677.693223067483, 33907.83046084853, 34702.65297346994, 34844.0, 34940.82529587336, 25332.366690832878, 25684.871575642002, 25840.48319033571, 31698.049058528282, 32292.0], "value": 20195.213732911205, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.906744712651, 374.5019124564329, 377.52054890289315, 386.30079556003625, 409.5679058593183, 14873.405063797449, 15041.398344066238, 20445.0, 20532.89733551334, 20609.175632974664, 19325.516862078453, 20277.0, 20466.69299960498, 20469.48826279344, 21127.683084753742, 5165.974170129144, 5846.21076154718, 6005.249343832021, 6052.939470605282, 6069.908951365733, 5908.497777688902, 5944.583879128462, 5959.731812068468, 5961.37405572413, 5971.940280597204, 5584.944150558483, 5898.439648233432, 5926.822195334147, 5935.28776546815, 5954.761809527614, 5735.799247026334, 5812.82561523155, 5848.912266316009, 5867.647941123525, 5871.853203669921, 5735.655860648333, 5830.825075247707, 5867.76528938842, 5873.412658734135, 5877.0, 13161.605151845364, 13388.665283367896, 23294.417639559146, 24060.759392406027, 25395.365115872068, 2076.1280262289724, 2296.7240101656616, 2345.94135146621, 2913.7231962963483, 2930.1356099950567, 9912.55393507294, 10082.143017843493, 10111.595536178544, 10153.746156346077, 10204.591816327413, 1779.74193741908, 1793.5516120969758, 1800.2798880447795, 1813.7370081338263, 1884.8492120630453, 126467.0, 126557.36721316335, 129396.7060329394, 130591.0, 134016.0, 9966.35218710796, 10143.847842282372, 11374.033207177483, 11482.94258528702, 11652.0, 14454.783178252335, 14456.0, 14458.349374278183, 14476.34856431463, 14478.782818257734, 8147.75556733299, 8452.78868028292, 8475.406721529556, 8618.0, 8639.13608639131, 6927.93072069283, 8466.872996905051, 8476.660933562713, 8632.525211113323, 8640.740777776551, 3767.981160094182, 4349.093280245775, 4444.177827101932, 4478.1715382653665, 4603.447586289667, 1545.65995480994, 1726.9481915542553, 1942.990285048594, 1943.9016955420143, 1948.8051194880682, 762.5119923249125, 791.5962858941981, 796.7689370082727, 914.721010091915, 926.3006430145198, 20617.896910515352, 21147.894260528905, 21252.36242912715, 21278.680819787714, 21446.78553214479, 690.9601050419187, 894.5661354243186, 918.42598376015, 919.7516670498928, 942.8161508505913, 10881.891181088246, 10975.451227438596, 11192.496337664825, 11193.328400296008, 11199.944000279946, 557248.1302243756, 572484.0, 580762.0, 592144.0, 593891.0, 92718.0, 101852.23296974692, 120186.0, 126828.0, 136689.0, 929.9349045566745, 955.9665411710557, 966.3766870368573, 1009.3489699144158, 1011.9089281964511, 18935.053247337302, 18945.621087578445, 19034.904825475784, 19133.8086619132, 19172.80827191766, 35858.64141358553, 45509.54490455053, 50101.49898500968, 51300.97398051943, 52661.47338526719, 56452.0, 68331.31668683449, 72078.63960680163, 77731.61134194293, 79844.60077699574, 7815.452918295665, 7989.001374828146, 8208.630611622371, 8220.671173153007, 8315.126911674188, 9176.403533770284, 12410.75178496407, 13115.344232788513, 13154.21074735519, 13744.518941836988, 10999.780004400027, 11055.668329950115, 11254.887451125383, 11707.356095414834, 11740.647780566596, 11087.392328112539, 11384.772304554028, 11562.768744624893, 11578.478968446272, 11863.34751588671, 11530.654080377602, 11586.304821710724, 11901.702457438634, 11920.76158476843, 12006.519739210335, 7973.880391794011, 7988.6405111768945, 8147.714829980922, 8278.461899976483, 8411.789705257173, 8359.498430094214, 8847.911520884709, 8927.687530936646, 11100.278481898651, 11167.832482512606], "value": 9345.260943441408, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1481.570344600066, 1519.7112548615764, 1708.5899384147808, 1839.7884243312024, 1876.2682553804018, 8535.573221338931, 9065.546722663858, 9072.183503484684, 9085.00064992851, 9107.90892091079, 7942.602869856513, 8189.181081891823, 8567.443116197443, 8761.912380876205, 8876.8668469973, 2389.8685572293475, 2422.0432928993037, 2428.720697119836, 2429.453372991072, 2437.963865357216, 2343.5547246023284, 2370.7866292033714, 2390.7011623547055, 2390.9043638254448, 2392.461696118377, 2377.3105799318178, 2393.2222027840935, 2400.2799160251943, 2404.627282771173, 2409.650600662903, 2367.9171229007075, 2386.7971222446113, 2396.1972739132325, 2398.988005059981, 2399.244238065004, 2365.1840115160244, 2384.9165279215317, 2385.9642105368434, 2388.677528533643, 2393.054743376357, 8849.601767920394, 8877.422967507091, 8963.0, 8971.237444817132, 8982.910170898338, 5256.894862102775, 5421.457854214586, 5436.293281873371, 5439.048166570874, 5442.183672449125, 3590.120420496995, 3598.46023096535, 3653.2328211075705, 3657.871974480907, 3680.963190368089, 1075.4837677914566, 1079.6760971708534, 1081.967540973772, 1096.901278884896, 1105.6572462536558, 11816.291022538675, 11897.107716921266, 11914.404279785977, 11920.165588408729, 11972.820407693891, 4107.958920410817, 4494.393256910341, 4628.490866004737, 4781.617470602343, 4813.181759100961, 9998.05018523239, 10001.049900259464, 10006.54970526328, 10022.899771002196, 10044.648437304659, 4528.071745292207, 5355.464453554691, 5360.410354860962, 5366.838994830161, 5371.597130215249, 4405.691601587922, 4568.725876447424, 4768.976155119202, 4886.413630364379, 4936.284238785356, 3309.93380132396, 3820.8280627371837, 3850.4224366345275, 3934.2918274710255, 3947.151362457028, 1108.7394462301352, 1279.0982357437983, 1288.3558220889556, 1294.15932029195, 1295.799151131576, 787.4409169489612, 815.824597711483, 823.168599714281, 910.9544522773834, 952.9469935720929, 5774.451427114417, 5841.299044114733, 5961.152042866725, 5974.880502390015, 6152.138700581922, 380.4141621902295, 435.3491530162427, 439.4068008188983, 449.2632083383254, 457.6521843398974, 3530.629283925151, 3866.555346135174, 3970.3250447424007, 4042.818073186655, 4081.8775436736946, 10319.94840025795, 10589.84115238272, 10829.945850270698, 10955.83566246507, 11011.779764404668, 10897.0, 10899.128069754559, 10908.727281817859, 10912.399818009926, 10914.072303854102, 8056.637451314705, 8247.628856701464, 8345.54099524532, 8352.206540378655, 8354.916450835535, 4995.375578052744, 5923.674197919156, 5935.34711181761, 5956.344802071682, 6014.939850601613, 9966.352187107816, 10162.237832162766, 10185.185185185019, 10208.897911020795, 10264.281500294908, 9897.307188496736, 10267.383956962607, 10370.948145259226, 10377.636782712569, 10379.429131397847, 6945.791626251221, 7155.356017958408, 7158.248383919614, 7530.359919406912, 7705.267999540035, 7959.4030447715495, 8026.719064832703, 8285.3785966054, 8483.703070392754, 8529.189726975927, 7609.923900760921, 7895.84208315819, 7919.08930473003, 7924.722634707758, 8007.719729809429, 7046.471514636525, 7145.356917877416, 7549.886751698838, 7651.081870175614, 7679.193684663253, 6868.038474613458, 7028.0, 7028.718851246097, 7048.330408611174, 7053.894191587027, 4915.6313276505125, 5106.208537676666, 5298.549623282064, 5320.9467905322, 5486.972565137149, 5171.45699701541, 5287.576993840406, 5332.626716129989, 5338.172583249602, 5498.477644623755], "value": 4530.061163883329, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.9864556094977, 303.6250230964759, 313.2122711380878, 317.88873894137055, 318.2378204200937, 4243.575642435755, 5165.896682066356, 5348.973255133719, 5476.452354764527, 5486.231927530144, 5199.350081239845, 5277.6305658603815, 5327.537100884418, 5341.385740639827, 5407.83776486705, 3292.736581073508, 4048.2510735514024, 4165.166966606683, 4165.937510937339, 4187.664986801064, 3581.910452238689, 3898.298306304864, 4120.793960301988, 4198.8530401435955, 4199.55904630014, 3416.6241713411505, 4120.814563344657, 4148.730332528376, 4154.584541545852, 4172.937405938898, 4084.1831633673382, 4084.8774536763794, 4118.176364727066, 4122.051928056564, 4146.740026685464, 3010.3979204159255, 3636.236390358028, 3636.8180341389157, 3641.453781932731, 3668.4497325401135, 4397.81259060052, 4856.611471082305, 4885.804567817283, 5023.472535383805, 5188.169892817169, 3921.313770090223, 4677.0, 4726.550977657117, 4727.881802954919, 4733.384659994179, 4710.905781884343, 5580.441955804387, 5655.604107712462, 5697.658140511541, 5707.77168913243, 3188.0, 3949.2891279569667, 3959.1289916218384, 3976.70174736893, 3976.7415118017525, 5187.7406129693745, 5910.852228694274, 5957.8808423831715, 5959.761609535613, 5964.463198312129, 4007.0, 4026.275270451347, 4038.5759495253164, 4068.5524592294823, 4081.591840815954, 3285.342931413679, 3465.059566642688, 4039.3537034074407, 4053.533843608023, 4124.91750164995, 3884.902877428087, 4425.645948324126, 4681.648876334289, 4701.976490117528, 4716.57550820421, 5428.701421421781, 5687.374388817227, 5742.885142297131, 5752.9424705753245, 5770.172615418414, 1124.5333186727416, 1310.462710288777, 1354.3296068446182, 1357.260293140241, 1378.9793153102712, 912.4707669551644, 922.6678395777515, 936.2556767369957, 958.8274110660009, 968.3415277611292, 5223.634345595848, 5231.267622532849, 5250.264962905197, 5264.868378290496, 5270.49930256625, 5271.973640131775, 5756.453136951983, 5763.394843541451, 5814.273215848019, 5818.44724751148, 3982.9402558961638, 4642.280446530793, 5189.247559103985, 5220.556252718561, 5243.68537887728, 2180.4439867833603, 2368.62102063669, 2508.83692559983, 2550.7831834294293, 2558.5799881065814, 4417.734935903856, 4435.558443505809, 4768.237082066922, 5046.073277499115, 5078.6795433187635, 3954.963370863869, 4219.936700949488, 4397.978010109993, 4945.67853089548, 4973.378327709036, 4307.806148723315, 4318.050028993616, 4984.40187177541, 5008.223725322581, 5035.043341765053, 4091.133878484922, 4500.549945005604, 4634.698744581591, 4825.927611085906, 4902.681325713817, 4519.322101684774, 4677.415323084614, 4702.764861756967, 4753.809847606057, 4764.595009424238, 5001.949980500149, 5632.9436705632415, 6107.297660769068, 6188.226471691039, 6235.906461403173, 3888.6694630955553, 3908.550516690503, 3933.4886464759397, 3941.9408708869964, 3968.265870813909, 5485.561155107662, 5923.555733319932, 5938.138969849434, 5962.105684147413, 5985.730642121028, 4647.604953578849, 4812.663113582015, 5807.245058142415, 5809.331926828469, 5819.883602328015, 5260.736963151904, 5763.567732420001, 5767.8558036049435, 5790.449907258803, 5810.966161843321, 4861.208257559109, 4953.356063711694, 5037.168867136881, 5315.013739695229, 5653.462921022497, 4851.539103785135, 5397.27136836522, 5752.9712351437975, 5777.8555536111935, 5795.275590551181, 5338.064104608589, 5442.863928401822, 5632.577556683347, 5633.77465900975, 5731.627444216113], "value": 4006.374544729837, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.3158436316771, 751.6918063593924, 754.8678981178296, 786.4927122006306, 840.1136800675287, 15278.236088195588, 15779.974301670383, 15966.521004369859, 15985.840141598595, 16711.91644041778, 15931.203439827992, 15987.600309992256, 15991.920040399782, 15996.440124595641, 16049.35802567896, 845.0746432656247, 875.3434923807145, 876.6975393489236, 877.675260153744, 883.690708252113, 687.0312858868996, 705.6224919667978, 707.9646017699121, 711.7259854955853, 713.5754226235407, 883.7083762358438, 906.4561263242035, 909.0636644256426, 919.9034101419355, 921.4056933278032, 902.1654969153507, 902.309733054214, 905.1310741687954, 905.6649039855296, 905.9320550958638, 529.6001518853241, 672.2806596941275, 672.6233309346733, 672.7947975867359, 674.4469534981317, 14515.346809393603, 14888.702225955529, 14964.850351496454, 14967.850321496755, 15029.624259393493, 13918.373673184731, 14260.85739142616, 14360.0, 14381.568552943323, 14545.27273636314, 11616.59341923037, 11852.940735296355, 11922.344271065089, 11931.701707457296, 12260.44827982743, 3777.9811100944594, 3789.3558095123894, 3805.315043292206, 3847.7498962567342, 3948.8617898373705, 120431.1935320971, 121920.0, 124462.0, 125229.0, 127468.36265818705, 11002.669919902415, 11043.570414508795, 11064.723381915517, 11148.721281968015, 11270.042046425982, 22642.0, 22654.320370388916, 22657.88671056634, 22700.65949010766, 22715.43211419695, 7749.341305988942, 7900.249476299742, 8103.756887293391, 8104.189581041848, 8110.878336824952, 4160.313548264502, 4275.080857615628, 4294.055307832272, 4349.456317960255, 4409.140217657526, 3057.1745628680133, 3498.160441494023, 3527.030066731669, 3613.5663720353728, 3614.530111085595, 555.5111501878321, 594.7947957954499, 629.156929714176, 639.996800015997, 649.2792999770252, 504.90715982254085, 537.792655488431, 665.7603262825379, 686.3959715450471, 693.5318659904544, 9238.630454781733, 9257.21313688344, 9313.34806563534, 9393.62425502986, 9420.0, 547.9995045212437, 637.9283748749755, 659.9175103112111, 664.833791552112, 693.3032302535919, 4452.30989196675, 4563.247064234431, 4580.885477863014, 4591.885202869888, 4646.651501137429, 95914.0, 98944.0, 130006.34996824956, 167555.32444675398, 169346.0, 134801.3259933694, 136825.0, 138372.0, 140120.0, 141901.29049354687, 82258.12096576711, 115422.0, 120208.19687704691, 121946.0, 124423.0, 8828.735137945872, 8840.734777956672, 8894.644214231359, 9052.0, 9165.129312715282, 59195.704021479614, 64315.105820237266, 65375.0, 66646.0, 67984.0, 84816.57591712002, 86403.56798215969, 91247.08752912386, 100501.98996020186, 108920.0, 13262.485441357854, 13311.66720831987, 13431.261280629658, 13718.588442346745, 13851.584452466443, 14880.702385952436, 15011.474598389004, 15049.54851354461, 15066.849331506544, 15478.071315721092, 11261.71845703864, 12157.817632735692, 13479.865121891979, 13814.033017688665, 13987.720245595234, 12894.355282235663, 13789.655258618615, 13808.792868107188, 13855.445782168601, 14247.430102795772, 13463.306607084334, 14070.577882663536, 14151.929240353733, 14171.291435427982, 14231.0, 10780.946095269474, 11255.66233013011, 11258.718532036764, 11295.887041129812, 11495.48270327854, 10082.546285417027, 10456.74519057718, 11079.689724874579, 11378.54485820558, 11501.654950351503], "value": 7855.4598576190065, "unit": "score"}], "name": "dromaeo_css", "value": 7321.107796048561, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 115.0, 112.0, 114.0, 112.0, 114.0, 113.0, 114.0, 124.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 109.0, 137.0, 191.0, 94.0, 152.0, 158.0, 136.0, 182.0, 106.0], "value": 141.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 183.0, 186.0, 184.0, 186.0, 224.0, 185.0, 183.0, 181.0], "value": 188.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 75.0, 156.0, 58.0, 58.0, 57.0, 59.0, 149.0, 57.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 137.0, 71.0, 73.0, 82.0, 120.0, 69.0, 69.0, 117.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 84.0, 87.0, 90.0, 133.0, 114.0, 87.0, 84.0], "value": 93.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 91.0, 93.0, 93.0, 93.0, 117.0, 150.0], "value": 101.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 135.0, 127.0, 85.0, 85.0, 86.0, 86.0, 86.0, 85.0, 86.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 55.0, 54.0, 53.0, 53.0, 55.0, 53.0, 54.0, 54.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 77.0, 77.0, 73.0, 71.0, 72.0, 71.0, 70.0, 73.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 85.0, 83.0, 85.0, 85.0, 85.0, 85.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 146.0, 150.0, 152.0, 135.0, 137.0, 132.0, 138.0, 179.0, 137.0], "value": 144.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 168.0, 167.0, 167.0, 162.0, 166.0, 168.0, 167.0, 169.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 67.0, 67.0, 67.0, 66.0, 66.0, 65.0, 66.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1495.5, "alertThreshold": 2.0}]} 07:27:51 INFO - Return code: 0 07:27:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:27:51 INFO - # TBPL SUCCESS # 07:27:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:27:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:27:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:27:51 INFO - 2016-09-01 07:27:51.600 screenresolution[1944:22056] starting screenresolution argv=screenresolution get 07:27:51 INFO - 2016-09-01 07:27:51.603 screenresolution[1944:22056] Display 0: 1600x1200x32@60 07:27:51 INFO - 2016-09-01 07:27:51.609 screenresolution[1945:22059] starting screenresolution argv=screenresolution list 07:27:51 INFO - Available Modes on Display 0 07:27:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:27:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:27:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:27:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:27:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:27:51 INFO - Intel Iris: 07:27:51 INFO - Chipset Model: Intel Iris 07:27:51 INFO - Type: GPU 07:27:51 INFO - Bus: Built-In 07:27:51 INFO - VRAM (Dynamic, Max): 1536 MB 07:27:51 INFO - Vendor: Intel (0x8086) 07:27:51 INFO - Device ID: 0x0a2e 07:27:51 INFO - Revision ID: 0x0009 07:27:51 INFO - Displays: 07:27:51 INFO - Display: 07:27:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:27:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:27:51 INFO - Display Serial Number: 1600x1200 60 07:27:51 INFO - Main Display: Yes 07:27:51 INFO - Mirror: Off 07:27:51 INFO - Online: Yes 07:27:51 INFO - Rotation: Supported 07:27:51 INFO - Return code: 0 07:27:51 INFO - Running post-action listener: _resource_record_post_action 07:27:51 INFO - [mozharness: 2016-09-01 14:27:51.844933Z] Finished run-tests step (success) 07:27:51 INFO - Running post-run listener: _resource_record_post_run 07:27:51 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 339968; Write bytes: 705422336; Read time: 4; Write time: 2015 07:27:51 INFO - TinderboxPrint: CPU usage
53.5% 07:27:51 INFO - TinderboxPrint: I/O read bytes / time
339,968 / 4 07:27:51 INFO - TinderboxPrint: I/O write bytes / time
705,422,336 / 2,015 07:27:51 INFO - TinderboxPrint: CPU idle
2,256.4 (46.5%) 07:27:51 INFO - TinderboxPrint: CPU system
966.4 (19.9%) 07:27:51 INFO - TinderboxPrint: CPU user
1,626.9 (33.5%) 07:27:51 INFO - TinderboxPrint: Swap in / out
405,929,984 / 0 07:27:51 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398582272; Write bytes: 393532928; Read time: 29624; Write time: 742 07:27:51 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 294912; Write bytes: 295017984; Read time: 3; Write time: 1231 07:27:52 INFO - Running post-run listener: _upload_blobber_files 07:27:52 INFO - Blob upload gear active. 07:27:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:27:52 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 07:27:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:27:52 INFO - (blobuploader) - INFO - Open directory for files ... 07:27:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:27:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:27:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:27:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:27:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:27:53 INFO - (blobuploader) - INFO - Done attempting. 07:27:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:27:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:27:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:27:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:27:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:27:54 INFO - (blobuploader) - INFO - Done attempting. 07:27:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:27:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:27:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:27:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:27:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:27:56 INFO - (blobuploader) - INFO - Done attempting. 07:27:56 INFO - (blobuploader) - INFO - Iteration through files over. 07:27:56 INFO - Return code: 0 07:27:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:27:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:27:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/62bd8bb83019fb195aac6ab5651714e91071b9ae3d8912e104a0e6df338d22403a33c8f45191b3c8e2345fc7bcd82dd6c34d6382786719b6f692d57a9760e2ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4f2a51fc950e37f2438da393f40476b84f75fdbe66399bb84a7be2bf501d1834ed1743ba7c5d6905b89536f7e76effad884dc1a681fcbf96be6e0de654171aba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/73cf2d67d231b194dd7a24006f9ff4a1cbf20457c48a2d73f6ad9ed932ecfa6af9d657d0997929ad9c3144bde9ae814a59bab211a80bdb7e01e10b8f0a4293eb"} 07:27:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:27:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:27:56 INFO - Contents: 07:27:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/62bd8bb83019fb195aac6ab5651714e91071b9ae3d8912e104a0e6df338d22403a33c8f45191b3c8e2345fc7bcd82dd6c34d6382786719b6f692d57a9760e2ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4f2a51fc950e37f2438da393f40476b84f75fdbe66399bb84a7be2bf501d1834ed1743ba7c5d6905b89536f7e76effad884dc1a681fcbf96be6e0de654171aba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/73cf2d67d231b194dd7a24006f9ff4a1cbf20457c48a2d73f6ad9ed932ecfa6af9d657d0997929ad9c3144bde9ae814a59bab211a80bdb7e01e10b8f0a4293eb"} 07:27:56 INFO - Running post-run listener: copy_logs_to_upload_dir 07:27:56 INFO - Copying logs to upload dir... 07:27:56 INFO - mkdir: /builds/slave/test/build/upload/logs 07:27:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.932783 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-01 07:27:56.707928) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 07:27:56.711543) ========= 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.bPOnj24woP/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.TGp23yDjaC/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/62bd8bb83019fb195aac6ab5651714e91071b9ae3d8912e104a0e6df338d22403a33c8f45191b3c8e2345fc7bcd82dd6c34d6382786719b6f692d57a9760e2ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4f2a51fc950e37f2438da393f40476b84f75fdbe66399bb84a7be2bf501d1834ed1743ba7c5d6905b89536f7e76effad884dc1a681fcbf96be6e0de654171aba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/73cf2d67d231b194dd7a24006f9ff4a1cbf20457c48a2d73f6ad9ed932ecfa6af9d657d0997929ad9c3144bde9ae814a59bab211a80bdb7e01e10b8f0a4293eb"} build_url:https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011194 build_url: 'https://queue.taskcluster.net/v1/task/PG0AVRFRTdO8CKMX42oaSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/62bd8bb83019fb195aac6ab5651714e91071b9ae3d8912e104a0e6df338d22403a33c8f45191b3c8e2345fc7bcd82dd6c34d6382786719b6f692d57a9760e2ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4f2a51fc950e37f2438da393f40476b84f75fdbe66399bb84a7be2bf501d1834ed1743ba7c5d6905b89536f7e76effad884dc1a681fcbf96be6e0de654171aba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/73cf2d67d231b194dd7a24006f9ff4a1cbf20457c48a2d73f6ad9ed932ecfa6af9d657d0997929ad9c3144bde9ae814a59bab211a80bdb7e01e10b8f0a4293eb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 07:27:56.745771) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 07:27:56.746128) ========= 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.bPOnj24woP/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.TGp23yDjaC/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.005258 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 07:27:56.794972) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 07:27:56.795291) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 07:27:56.795673) ========= ========= Total master_lag: 8.79 =========