builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0336 starttime: 1472143926.9 results: success (0) buildid: 20160825071625 builduid: c55a8713ffac48bb9d43766cf0264edf revision: c0c2f6077694008794c06900943654db0143c2b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:06.900809) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:06.901276) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:06.901580) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:06.926762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:06.927064) ========= 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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-25 09:52:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 121M=0s 2016-08-25 09:52:07 (121 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133277 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:07.083088) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:07.083429) ========= 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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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.025960 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:07.131948) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:07.132291) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c0c2f6077694008794c06900943654db0143c2b9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev c0c2f6077694008794c06900943654db0143c2b9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RihCv0tQKq/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.4Jv4WNlQtc/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-25 09:52:07,219 truncating revision to first 12 chars 2016-08-25 09:52:07,219 Setting DEBUG logging. 2016-08-25 09:52:07,219 attempt 1/10 2016-08-25 09:52:07,219 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c0c2f6077694?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 09:52:07,508 unpacking tar archive at: fx-team-c0c2f6077694/testing/mozharness/ program finished with exit code 0 elapsedTime=0.552858 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:07.708976) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:07.709299) ========= script_repo_revision: c0c2f6077694008794c06900943654db0143c2b9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:07.709697) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:07.709963) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 09:52:07.729407) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-25 09:52:07.729717) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RihCv0tQKq/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.4Jv4WNlQtc/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 09:52:07 INFO - MultiFileLogger online at 20160825 09:52:07 in /builds/slave/test 09:52:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:52:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:52:07 INFO - {'append_to_log': False, 09:52:07 INFO - 'base_work_dir': '/builds/slave/test', 09:52:07 INFO - 'blob_upload_branch': 'Fx-Team', 09:52:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:52:07 INFO - 'branch': 'Fx-Team', 09:52:07 INFO - 'buildbot_json_path': 'buildprops.json', 09:52:07 INFO - 'config_files': ('talos/mac_config.py',), 09:52:07 INFO - 'default_actions': ('clobber', 09:52:07 INFO - 'read-buildbot-config', 09:52:07 INFO - 'download-and-extract', 09:52:07 INFO - 'populate-webroot', 09:52:07 INFO - 'create-virtualenv', 09:52:07 INFO - 'install', 09:52:07 INFO - 'run-tests'), 09:52:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:52:07 INFO - 'download_minidump_stackwalk': True, 09:52:07 INFO - 'download_symbols': 'ondemand', 09:52:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:52:07 INFO - 'tooltool.py': '/tools/tooltool.py', 09:52:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:52:07 INFO - '/tools/misc-python/virtualenv.py')}, 09:52:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:52:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:52:07 INFO - 'installer_path': 'installer.exe', 09:52:07 INFO - 'log_level': 'info', 09:52:07 INFO - 'log_name': 'talos', 09:52:07 INFO - 'log_to_console': True, 09:52:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:52:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:52:07 INFO - 'opt_config_files': (), 09:52:07 INFO - 'pip_index': False, 09:52:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:52:07 INFO - 'cmd': ('bash', 09:52:07 INFO - '-c', 09:52:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:52:07 INFO - 'enabled': True, 09:52:07 INFO - 'halt_on_failure': False, 09:52:07 INFO - 'name': 'check_screen_resolution'},), 09:52:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:52:07 INFO - 'cmd': ('bash', 09:52:07 INFO - '-c', 09:52:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:52:07 INFO - 'enabled': True, 09:52:07 INFO - 'halt_on_failure': False, 09:52:07 INFO - 'name': 'check_screen_resolution'},), 09:52:07 INFO - 'run_cmd_checks_enabled': True, 09:52:07 INFO - 'sps_profile': False, 09:52:07 INFO - 'sps_profile_interval': 0, 09:52:07 INFO - 'suite': 'dromaeojs', 09:52:07 INFO - 'system_bits': '32', 09:52:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:52:07 INFO - 'title': 't-yosemite-r7-0336', 09:52:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:52:07 INFO - 'use_talos_json': True, 09:52:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:52:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:52:07 INFO - 'work_dir': 'build'} 09:52:07 INFO - [mozharness: 2016-08-25 16:52:07.926139Z] Running clobber step. 09:52:07 INFO - Running pre-action listener: _resource_record_pre_action 09:52:07 INFO - Running main action method: clobber 09:52:07 INFO - rmtree: /builds/slave/test/build 09:52:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:52:09 INFO - Running post-action listener: _resource_record_post_action 09:52:09 INFO - [mozharness: 2016-08-25 16:52:09.961420Z] Finished clobber step (success) 09:52:09 INFO - [mozharness: 2016-08-25 16:52:09.961611Z] Running read-buildbot-config step. 09:52:09 INFO - Running pre-action listener: _resource_record_pre_action 09:52:09 INFO - Running main action method: read_buildbot_config 09:52:09 INFO - Using buildbot properties: 09:52:09 INFO - { 09:52:09 INFO - "project": "", 09:52:09 INFO - "product": "firefox", 09:52:09 INFO - "script_repo_revision": "production", 09:52:09 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:52:09 INFO - "repository": "", 09:52:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:52:09 INFO - "buildid": "20160825071625", 09:52:09 INFO - "pgo_build": "False", 09:52:09 INFO - "basedir": "/builds/slave/test", 09:52:09 INFO - "buildnumber": 826, 09:52:09 INFO - "slavename": "t-yosemite-r7-0336", 09:52:09 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:52:09 INFO - "platform": "yosemite_r7", 09:52:09 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:52:09 INFO - "branch": "fx-team", 09:52:09 INFO - "repo_path": "integration/fx-team", 09:52:09 INFO - "revision": "c0c2f6077694008794c06900943654db0143c2b9", 09:52:09 INFO - "stage_platform": "macosx64", 09:52:09 INFO - "builduid": "c55a8713ffac48bb9d43766cf0264edf", 09:52:09 INFO - "slavebuilddir": "test" 09:52:09 INFO - } 09:52:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:52:09 INFO - Running post-action listener: _resource_record_post_action 09:52:09 INFO - [mozharness: 2016-08-25 16:52:09.967254Z] Finished read-buildbot-config step (success) 09:52:09 INFO - [mozharness: 2016-08-25 16:52:09.967476Z] Running download-and-extract step. 09:52:09 INFO - Running pre-action listener: _resource_record_pre_action 09:52:09 INFO - Running main action method: download_and_extract 09:52:09 INFO - mkdir: /builds/slave/test/build/tests 09:52:09 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')]} 09:52:09 INFO - trying https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:52:09 INFO - Downloading https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/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 09:52:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/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 09:52:10 INFO - Downloaded 1199 bytes. 09:52:10 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:52:10 INFO - Using the following test package requirements: 09:52:10 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:52:10 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:52:10 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:52:10 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:52:10 INFO - u'jsshell-mac.zip'], 09:52:10 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:52:10 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:52:10 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:52:10 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:52:10 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:52:10 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:52:10 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:52:10 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')]} 09:52:10 INFO - trying https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:52:10 INFO - Downloading https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/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 09:52:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/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 09:52:11 INFO - Downloaded 16345980 bytes. 09:52:11 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:52:18 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 09:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:18 INFO - trying https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:52:18 INFO - Downloading https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/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 09:52:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/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 09:52:19 INFO - Downloaded 16345980 bytes. 09:52:19 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:52:26 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')]} 09:52:26 INFO - trying https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:52:26 INFO - Downloading https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/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 09:52:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/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 09:52:27 INFO - Downloaded 14413981 bytes. 09:52:27 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:52:27 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')]} 09:52:27 INFO - trying https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:52:27 INFO - Downloading https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:52:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/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 09:52:30 INFO - Downloaded 111545861 bytes. 09:52:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:52:30 INFO - mkdir: /builds/slave/test/properties 09:52:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:52:30 INFO - Writing to file /builds/slave/test/properties/build_url 09:52:30 INFO - Contents: 09:52:30 INFO - build_url:https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:52:31 INFO - Running post-action listener: _resource_record_post_action 09:52:31 INFO - Running post-action listener: set_extra_try_arguments 09:52:31 INFO - [mozharness: 2016-08-25 16:52:31.578517Z] Finished download-and-extract step (success) 09:52:31 INFO - [mozharness: 2016-08-25 16:52:31.578674Z] Running populate-webroot step. 09:52:31 INFO - Running pre-action listener: _resource_record_pre_action 09:52:31 INFO - Running main action method: populate_webroot 09:52:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:52:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:52:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:52:31 INFO - '--tppagecycles', 09:52:31 INFO - '7'], 09:52:31 INFO - 'tests': ['tsvgm']}}, 09:52:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:52:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:52:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:52:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:52:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:52:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:52:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:31 INFO - 'talos_options': ['--disable-e10s'], 09:52:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:52:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:52:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:31 INFO - 'talos_options': ['--disable-e10s'], 09:52:31 INFO - 'tests': ['damp', 'tps']}, 09:52:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:31 INFO - 'tests': ['damp', 'tps']}, 09:52:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:52:31 INFO - 'tests': ['dromaeo_dom']}, 09:52:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:52:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:52:31 INFO - 'tests': ['basic_compositor_video']}, 09:52:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:52:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:52:31 INFO - 'tests': ['a11yr', 09:52:31 INFO - 'ts_paint', 09:52:31 INFO - 'tpaint', 09:52:31 INFO - 'sessionrestore', 09:52:31 INFO - 'sessionrestore_no_auto_restore', 09:52:31 INFO - 'tabpaint']}, 09:52:31 INFO - 'other-e10s': {'tests': ['a11yr', 09:52:31 INFO - 'ts_paint', 09:52:31 INFO - 'tpaint', 09:52:31 INFO - 'sessionrestore', 09:52:31 INFO - 'sessionrestore_no_auto_restore', 09:52:31 INFO - 'tabpaint']}, 09:52:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:52:31 INFO - 'ts_paint', 09:52:31 INFO - 'tpaint', 09:52:31 INFO - 'sessionrestore', 09:52:31 INFO - 'sessionrestore_no_auto_restore', 09:52:31 INFO - 'tabpaint']}, 09:52:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:52:31 INFO - 'ts_paint', 09:52:31 INFO - 'tpaint', 09:52:31 INFO - 'sessionrestore', 09:52:31 INFO - 'sessionrestore_no_auto_restore', 09:52:31 INFO - 'tabpaint']}, 09:52:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:52:31 INFO - 'tests': ['a11yr', 09:52:31 INFO - 'ts_paint', 09:52:31 INFO - 'tpaint', 09:52:31 INFO - 'sessionrestore', 09:52:31 INFO - 'sessionrestore_no_auto_restore', 09:52:31 INFO - 'tabpaint']}, 09:52:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:52:31 INFO - 'tests': ['a11yr', 09:52:31 INFO - 'ts_paint', 09:52:31 INFO - 'tpaint', 09:52:31 INFO - 'sessionrestore', 09:52:31 INFO - 'sessionrestore_no_auto_restore', 09:52:31 INFO - 'tabpaint']}, 09:52:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:52:31 INFO - 'tests': ['tsvgx', 09:52:31 INFO - 'tsvgr_opacity', 09:52:31 INFO - 'tart', 09:52:31 INFO - 'tscrollx', 09:52:31 INFO - 'cart']}, 09:52:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:52:31 INFO - 'tsvgr_opacity', 09:52:31 INFO - 'tart', 09:52:31 INFO - 'tscrollx', 09:52:31 INFO - 'cart']}, 09:52:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:31 INFO - 'talos_options': ['--disable-e10s'], 09:52:31 INFO - 'tests': ['tp5o']}, 09:52:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:31 INFO - 'tests': ['tp5o']}, 09:52:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:31 INFO - 'talos_options': ['--disable-e10s', 09:52:31 INFO - '--xperf_path', 09:52:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:52:31 INFO - 'tests': ['tp5n']}, 09:52:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:31 INFO - 'talos_options': ['--xperf_path', 09:52:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:52:31 INFO - 'tests': ['tp5n']}}, 09:52:31 INFO - 'talos.zip': {'path': '', 09:52:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:52:31 INFO - Running post-action listener: _resource_record_post_action 09:52:31 INFO - [mozharness: 2016-08-25 16:52:31.598025Z] Finished populate-webroot step (success) 09:52:31 INFO - [mozharness: 2016-08-25 16:52:31.598171Z] Running create-virtualenv step. 09:52:31 INFO - Running pre-action listener: _resource_record_pre_action 09:52:31 INFO - Running main action method: create_virtualenv 09:52:31 INFO - Creating virtualenv /builds/slave/test/build/venv 09:52:31 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 09:52:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:52:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:52:31 INFO - Using real prefix '/tools/python27' 09:52:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:52:32 INFO - Installing distribute.............................................................................................................................................................................................done. 09:52:35 INFO - Installing pip.................done. 09:52:35 INFO - Return code: 0 09:52:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:52:35 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')]} 09:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:35 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')]} 09:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:35 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 0x1091a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff91a428d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910dd30>, '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 0x108e72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109105b20>, '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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 09:52:35 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 09:52:35 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 09:52:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RihCv0tQKq/Render', 09:52:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:35 INFO - 'HOME': '/Users/cltbld', 09:52:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:35 INFO - 'LOGNAME': 'cltbld', 09:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:35 INFO - 'MOZ_NO_REMOTE': '1', 09:52:35 INFO - 'NO_EM_RESTART': '1', 09:52:35 INFO - 'PAGER': '/bin/cat', 09:52:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:35 INFO - 'PWD': '/builds/slave/test', 09:52:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:35 INFO - 'SHELL': '/bin/bash', 09:52:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Jv4WNlQtc/Listeners', 09:52:35 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 09:52:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:35 INFO - 'USER': 'cltbld', 09:52:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:35 INFO - 'XPC_FLAGS': '0x0', 09:52:35 INFO - 'XPC_SERVICE_NAME': '0', 09:52:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:35 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 09:52:35 INFO - Cleaning up... 09:52:35 INFO - Return code: 0 09:52:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:52:35 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')]} 09:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:35 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')]} 09:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:35 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 0x1091a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff91a428d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910dd30>, '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 0x108e72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109105b20>, '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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 09:52:35 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 09:52:35 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 09:52:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RihCv0tQKq/Render', 09:52:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:35 INFO - 'HOME': '/Users/cltbld', 09:52:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:35 INFO - 'LOGNAME': 'cltbld', 09:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:35 INFO - 'MOZ_NO_REMOTE': '1', 09:52:35 INFO - 'NO_EM_RESTART': '1', 09:52:35 INFO - 'PAGER': '/bin/cat', 09:52:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:35 INFO - 'PWD': '/builds/slave/test', 09:52:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:35 INFO - 'SHELL': '/bin/bash', 09:52:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Jv4WNlQtc/Listeners', 09:52:35 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 09:52:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:35 INFO - 'USER': 'cltbld', 09:52:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:35 INFO - 'XPC_FLAGS': '0x0', 09:52:35 INFO - 'XPC_SERVICE_NAME': '0', 09:52:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:35 INFO - Downloading/unpacking psutil>=3.1.1 09:52: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 09:52: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 09:52: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 09:52: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 09:52: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 09:52: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 09:52:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:52:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:38 INFO - Installing collected packages: psutil 09:52:38 INFO - Running setup.py install for psutil 09:52:38 INFO - building 'psutil._psutil_osx' extension 09:52:38 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 09:52:39 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 09:52:39 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 09:52:39 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 09:52:39 INFO - building 'psutil._psutil_posix' extension 09:52:39 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 09:52:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:52:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:52:39 INFO - ^ 09:52:39 INFO - 1 warning generated. 09:52:39 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 09:52:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:39 INFO - Successfully installed psutil 09:52:39 INFO - Cleaning up... 09:52:39 INFO - Return code: 0 09:52:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1091a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff91a428d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910dd30>, '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 0x108e72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109105b20>, '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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 09:52:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:52:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:52:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RihCv0tQKq/Render', 09:52:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:40 INFO - 'HOME': '/Users/cltbld', 09:52:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:40 INFO - 'LOGNAME': 'cltbld', 09:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:40 INFO - 'MOZ_NO_REMOTE': '1', 09:52:40 INFO - 'NO_EM_RESTART': '1', 09:52:40 INFO - 'PAGER': '/bin/cat', 09:52:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:40 INFO - 'PWD': '/builds/slave/test', 09:52:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:40 INFO - 'SHELL': '/bin/bash', 09:52:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Jv4WNlQtc/Listeners', 09:52:40 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 09:52:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:40 INFO - 'USER': 'cltbld', 09:52:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:40 INFO - 'XPC_FLAGS': '0x0', 09:52:40 INFO - 'XPC_SERVICE_NAME': '0', 09:52:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:52:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:52:42 INFO - Installing collected packages: mozsystemmonitor 09:52:42 INFO - Running setup.py install for mozsystemmonitor 09:52:42 INFO - Successfully installed mozsystemmonitor 09:52:42 INFO - Cleaning up... 09:52:42 INFO - Return code: 0 09:52:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1091a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff91a428d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910dd30>, '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 0x108e72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109105b20>, '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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 09:52:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:52:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RihCv0tQKq/Render', 09:52:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:42 INFO - 'HOME': '/Users/cltbld', 09:52:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:42 INFO - 'LOGNAME': 'cltbld', 09:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:42 INFO - 'MOZ_NO_REMOTE': '1', 09:52:42 INFO - 'NO_EM_RESTART': '1', 09:52:42 INFO - 'PAGER': '/bin/cat', 09:52:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:42 INFO - 'PWD': '/builds/slave/test', 09:52:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:42 INFO - 'SHELL': '/bin/bash', 09:52:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Jv4WNlQtc/Listeners', 09:52:42 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 09:52:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:42 INFO - 'USER': 'cltbld', 09:52:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:42 INFO - 'XPC_FLAGS': '0x0', 09:52:42 INFO - 'XPC_SERVICE_NAME': '0', 09:52:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:43 INFO - Downloading/unpacking jsonschema==2.5.1 09:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:52:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:52:45 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 09:52:45 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 09:52:45 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 09:52:45 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 09:52:45 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 09:52:45 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 09:52:46 INFO - Downloading functools32-3.2.3-2.tar.gz 09:52:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:52:46 INFO - warning: no files found matching '*.txt' 09:52:46 INFO - no previously-included directories found matching 'build' 09:52:46 INFO - no previously-included directories found matching 'dist' 09:52:46 INFO - no previously-included directories found matching '.git*' 09:52:46 INFO - Installing collected packages: jsonschema, functools32 09:52:46 INFO - Running setup.py install for functools32 09:52:46 INFO - warning: no files found matching '*.txt' 09:52:46 INFO - no previously-included directories found matching 'build' 09:52:46 INFO - no previously-included directories found matching 'dist' 09:52:46 INFO - no previously-included directories found matching '.git*' 09:52:46 INFO - Successfully installed jsonschema functools32 09:52:46 INFO - Cleaning up... 09:52:46 INFO - Return code: 0 09:52:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1091a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff91a428d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910dd30>, '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 0x108e72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109105b20>, '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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 09:52:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:52:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RihCv0tQKq/Render', 09:52:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:46 INFO - 'HOME': '/Users/cltbld', 09:52:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:46 INFO - 'LOGNAME': 'cltbld', 09:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:46 INFO - 'MOZ_NO_REMOTE': '1', 09:52:46 INFO - 'NO_EM_RESTART': '1', 09:52:46 INFO - 'PAGER': '/bin/cat', 09:52:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:46 INFO - 'PWD': '/builds/slave/test', 09:52:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:46 INFO - 'SHELL': '/bin/bash', 09:52:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Jv4WNlQtc/Listeners', 09:52:46 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 09:52:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:46 INFO - 'USER': 'cltbld', 09:52:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:46 INFO - 'XPC_FLAGS': '0x0', 09:52:46 INFO - 'XPC_SERVICE_NAME': '0', 09:52:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:52:46 INFO - Cleaning up... 09:52:46 INFO - Return code: 0 09:52:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1091a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff91a428d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910dd30>, '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 0x108e72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109105b20>, '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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 09:52:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:52:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RihCv0tQKq/Render', 09:52:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:46 INFO - 'HOME': '/Users/cltbld', 09:52:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:46 INFO - 'LOGNAME': 'cltbld', 09:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:46 INFO - 'MOZ_NO_REMOTE': '1', 09:52:46 INFO - 'NO_EM_RESTART': '1', 09:52:46 INFO - 'PAGER': '/bin/cat', 09:52:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:46 INFO - 'PWD': '/builds/slave/test', 09:52:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:46 INFO - 'SHELL': '/bin/bash', 09:52:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Jv4WNlQtc/Listeners', 09:52:46 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 09:52:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:46 INFO - 'USER': 'cltbld', 09:52:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:46 INFO - 'XPC_FLAGS': '0x0', 09:52:46 INFO - 'XPC_SERVICE_NAME': '0', 09:52:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:46 INFO - Downloading/unpacking blobuploader==1.2.4 09:52:46 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 09:52:46 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 09:52:46 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 09:52:46 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 09:52:46 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 09:52:46 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 09:52:49 INFO - Downloading blobuploader-1.2.4.tar.gz 09:52:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:52:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:52:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:52:50 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 09:52:50 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 09:52:50 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 09:52:50 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 09:52:50 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 09:52:50 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 09:52:50 INFO - Downloading docopt-0.6.1.tar.gz 09:52:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:52:50 INFO - Installing collected packages: blobuploader, requests, docopt 09:52:50 INFO - Running setup.py install for blobuploader 09:52:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:52:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:52:50 INFO - Running setup.py install for requests 09:52:51 INFO - Running setup.py install for docopt 09:52:51 INFO - Successfully installed blobuploader requests docopt 09:52:51 INFO - Cleaning up... 09:52:51 INFO - Return code: 0 09:52:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:52:51 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')]} 09:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:51 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')]} 09:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:51 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 0x1091a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff91a428d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910dd30>, '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 0x108e72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109105b20>, '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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 09:52:51 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 09:52:51 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 09:52:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RihCv0tQKq/Render', 09:52:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:51 INFO - 'HOME': '/Users/cltbld', 09:52:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:51 INFO - 'LOGNAME': 'cltbld', 09:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:51 INFO - 'MOZ_NO_REMOTE': '1', 09:52:51 INFO - 'NO_EM_RESTART': '1', 09:52:51 INFO - 'PAGER': '/bin/cat', 09:52:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:51 INFO - 'PWD': '/builds/slave/test', 09:52:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:51 INFO - 'SHELL': '/bin/bash', 09:52:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Jv4WNlQtc/Listeners', 09:52:51 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 09:52:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:51 INFO - 'USER': 'cltbld', 09:52:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:51 INFO - 'XPC_FLAGS': '0x0', 09:52:51 INFO - 'XPC_SERVICE_NAME': '0', 09:52:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:52:51 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-c3FH2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:52:51 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-4UGWMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:52:52 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-cLqJKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:52:52 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-gJQfYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:52:52 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-ognMZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:52:52 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-cRil8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:52:52 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-TzuX_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:52:52 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-1bLoUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:52:52 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-FCHNWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:52:52 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-yWW0L7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:52:52 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-aTLMLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:52:53 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-Rm7G5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:52:53 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-JBuIRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:52:53 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-AFIWSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:53 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-MTzqWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:52:53 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-oKJwFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:52:53 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-7a9TSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:52:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:52:53 INFO - Running setup.py install for manifestparser 09:52:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:52:53 INFO - Running setup.py install for mozcrash 09:52:53 INFO - Running setup.py install for mozdebug 09:52:54 INFO - Running setup.py install for mozdevice 09:52:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:52:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:52:54 INFO - Running setup.py install for mozfile 09:52:54 INFO - Running setup.py install for mozhttpd 09:52:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:52:54 INFO - Running setup.py install for mozinfo 09:52:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:52:54 INFO - Running setup.py install for mozInstall 09:52:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:52:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:52:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:52:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:52:54 INFO - Running setup.py install for mozleak 09:52:54 INFO - Running setup.py install for mozlog 09:52:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:52:55 INFO - Running setup.py install for moznetwork 09:52:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:52:55 INFO - Running setup.py install for mozprocess 09:52:55 INFO - Running setup.py install for mozprofile 09:52:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:52:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:52:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:52:55 INFO - Running setup.py install for mozrunner 09:52:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:52:55 INFO - Running setup.py install for mozscreenshot 09:52:55 INFO - Running setup.py install for moztest 09:52:56 INFO - Running setup.py install for mozversion 09:52:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:52:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:52:56 INFO - Cleaning up... 09:52:56 INFO - Return code: 0 09:52:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:56 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 0x1091a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff91a428d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910dd30>, '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 0x108e72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109105b20>, '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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 09:52:56 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 09:52:56 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 09:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RihCv0tQKq/Render', 09:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:56 INFO - 'HOME': '/Users/cltbld', 09:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:56 INFO - 'LOGNAME': 'cltbld', 09:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:56 INFO - 'MOZ_NO_REMOTE': '1', 09:52:56 INFO - 'NO_EM_RESTART': '1', 09:52:56 INFO - 'PAGER': '/bin/cat', 09:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:56 INFO - 'PWD': '/builds/slave/test', 09:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:56 INFO - 'SHELL': '/bin/bash', 09:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Jv4WNlQtc/Listeners', 09:52:56 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 09:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:56 INFO - 'USER': 'cltbld', 09:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:56 INFO - 'XPC_FLAGS': '0x0', 09:52:56 INFO - 'XPC_SERVICE_NAME': '0', 09:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:52:56 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-nQcwjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:52:56 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)) 09:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:52:56 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-4gK1as-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:52:56 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)) 09:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:52:56 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-No2zr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:52:56 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)) 09:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:52:56 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-iZkPPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:52:56 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)) 09:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:52:56 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-lPj7mR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:52:57 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)) 09:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:52:57 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-gRrgkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:52:57 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)) 09:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:52:57 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-2JQBg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:52:57 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)) 09:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:52:57 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-AyXAEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:52:57 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)) 09:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:52:57 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-Ofkm1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:52:57 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)) 09:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:52:57 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-9qKhuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:52:57 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)) 09:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:52:57 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-OdtYrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:52:57 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)) 09:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:52:57 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-Vnal4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:52:57 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)) 09:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:52:57 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-DopIKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:52:57 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)) 09:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:52:57 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-OybZLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:52:58 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)) 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:58 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-Ee1S7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:58 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)) 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:52:58 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-YcbYtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:52:58 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)) 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:52:58 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-kGCGbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:52:58 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)) 09:52:58 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)) 09:52:58 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)) 09:52:58 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)) 09:52:58 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)) 09:52:58 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)) 09:52:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:52:58 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 09:52:58 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 09:52:58 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 09:52:58 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 09:52:58 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 09:52:58 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 09:53:00 INFO - Downloading blessings-1.6.tar.gz 09:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:53:00 INFO - Installing collected packages: blessings 09:53:00 INFO - Running setup.py install for blessings 09:53:01 INFO - Successfully installed blessings 09:53:01 INFO - Cleaning up... 09:53:01 INFO - Return code: 0 09:53:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:53:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:53:01 INFO - Reading from file tmpfile_stdout 09:53:01 INFO - Current package versions: 09:53:01 INFO - blessings == 1.6 09:53:01 INFO - blobuploader == 1.2.4 09:53:01 INFO - docopt == 0.6.1 09:53:01 INFO - functools32 == 3.2.3-2 09:53:01 INFO - jsonschema == 2.5.1 09:53:01 INFO - manifestparser == 1.1 09:53:01 INFO - mozInstall == 1.12 09:53:01 INFO - mozcrash == 0.17 09:53:01 INFO - mozdebug == 0.1 09:53:01 INFO - mozdevice == 0.48 09:53:01 INFO - mozfile == 1.2 09:53:01 INFO - mozhttpd == 0.7 09:53:01 INFO - mozinfo == 0.9 09:53:01 INFO - mozleak == 0.1 09:53:01 INFO - mozlog == 3.3 09:53:01 INFO - moznetwork == 0.27 09:53:01 INFO - mozprocess == 0.23 09:53:01 INFO - mozprofile == 0.28 09:53:01 INFO - mozrunner == 6.12 09:53:01 INFO - mozscreenshot == 0.1 09:53:01 INFO - mozsystemmonitor == 0.3 09:53:01 INFO - moztest == 0.7 09:53:01 INFO - mozversion == 1.4 09:53:01 INFO - psutil == 3.1.1 09:53:01 INFO - requests == 1.2.3 09:53:01 INFO - wsgiref == 0.1.2 09:53:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:53: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')]} 09:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53: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')]} 09:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:01 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 0x1091a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff91a428d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910dd30>, '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 0x108e72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109105b20>, '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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 09:53:01 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 09:53:01 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 09:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RihCv0tQKq/Render', 09:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:01 INFO - 'HOME': '/Users/cltbld', 09:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:01 INFO - 'LOGNAME': 'cltbld', 09:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:01 INFO - 'MOZ_NO_REMOTE': '1', 09:53:01 INFO - 'NO_EM_RESTART': '1', 09:53:01 INFO - 'PAGER': '/bin/cat', 09:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:01 INFO - 'PWD': '/builds/slave/test', 09:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:01 INFO - 'SHELL': '/bin/bash', 09:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Jv4WNlQtc/Listeners', 09:53:01 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 09:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:01 INFO - 'USER': 'cltbld', 09:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:01 INFO - 'XPC_FLAGS': '0x0', 09:53:01 INFO - 'XPC_SERVICE_NAME': '0', 09:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 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)) 09:53:02 INFO - Cleaning up... 09:53:02 INFO - Return code: 0 09:53:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:53:02 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')]} 09:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:02 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')]} 09:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:02 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 0x1091a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff91a428d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910dd30>, '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 0x108e72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109105b20>, '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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 09:53:02 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 09:53:02 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 09:53:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RihCv0tQKq/Render', 09:53:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:02 INFO - 'HOME': '/Users/cltbld', 09:53:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:02 INFO - 'LOGNAME': 'cltbld', 09:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:02 INFO - 'MOZ_NO_REMOTE': '1', 09:53:02 INFO - 'NO_EM_RESTART': '1', 09:53:02 INFO - 'PAGER': '/bin/cat', 09:53:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:02 INFO - 'PWD': '/builds/slave/test', 09:53:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:02 INFO - 'SHELL': '/bin/bash', 09:53:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Jv4WNlQtc/Listeners', 09:53:02 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 09:53:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:02 INFO - 'USER': 'cltbld', 09:53:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:02 INFO - 'XPC_FLAGS': '0x0', 09:53:02 INFO - 'XPC_SERVICE_NAME': '0', 09:53:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:53:02 INFO - Cleaning up... 09:53:02 INFO - Return code: 0 09:53:02 INFO - Running post-action listener: _resource_record_post_action 09:53:02 INFO - Running post-action listener: _start_resource_monitoring 09:53:02 INFO - Starting resource monitoring. 09:53:02 INFO - [mozharness: 2016-08-25 16:53:02.362973Z] Finished create-virtualenv step (success) 09:53:02 INFO - [mozharness: 2016-08-25 16:53:02.363981Z] Running install step. 09:53:02 INFO - Running pre-action listener: _resource_record_pre_action 09:53:02 INFO - Running main action method: install 09:53:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:53:02 INFO - Reading from file tmpfile_stdout 09:53:02 INFO - Detecting whether we're running mozinstall >=1.0... 09:53:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:53:02 INFO - Reading from file tmpfile_stdout 09:53:02 INFO - Output received: 09:53:02 INFO - Usage: mozinstall [options] installer 09:53:02 INFO - Options: 09:53:02 INFO - -h, --help show this help message and exit 09:53:02 INFO - -d DEST, --destination=DEST 09:53:02 INFO - Directory to install application into. [default: 09:53:02 INFO - "/builds/slave/test"] 09:53:02 INFO - --app=APP Application being installed. [default: firefox] 09:53:02 INFO - mkdir: /builds/slave/test/build/application 09:53:02 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'] 09:53:02 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 09:53:37 INFO - Reading from file tmpfile_stdout 09:53:37 INFO - Output received: 09:53:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:53:37 INFO - Running post-action listener: _resource_record_post_action 09:53:37 INFO - [mozharness: 2016-08-25 16:53:37.025651Z] Finished install step (success) 09:53:37 INFO - [mozharness: 2016-08-25 16:53:37.025789Z] Running run-tests step. 09:53:37 INFO - Running pre-action listener: _resource_record_pre_action 09:53:37 INFO - Running main action method: run_tests 09:53:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:53:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:53:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:53:37 INFO - 2016-08-25 09:53:37.040 screenresolution[1633:7443] starting screenresolution argv=screenresolution get 09:53:37 INFO - 2016-08-25 09:53:37.042 screenresolution[1633:7443] Display 0: 1600x1200x32@60 09:53:37 INFO - 2016-08-25 09:53:37.048 screenresolution[1634:7445] starting screenresolution argv=screenresolution list 09:53:37 INFO - Available Modes on Display 0 09:53:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:53:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:53:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:53:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:53:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:53:37 INFO - Intel Iris: 09:53:37 INFO - Chipset Model: Intel Iris 09:53:37 INFO - Type: GPU 09:53:37 INFO - Bus: Built-In 09:53:37 INFO - VRAM (Dynamic, Max): 1536 MB 09:53:37 INFO - Vendor: Intel (0x8086) 09:53:37 INFO - Device ID: 0x0a2e 09:53:37 INFO - Revision ID: 0x0009 09:53:37 INFO - Displays: 09:53:37 INFO - Display: 09:53:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:53:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:53:37 INFO - Display Serial Number: 1600x1200 60 09:53:37 INFO - Main Display: Yes 09:53:37 INFO - Mirror: Off 09:53:37 INFO - Online: Yes 09:53:37 INFO - Rotation: Supported 09:53:37 INFO - Return code: 0 09:53:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:53:37 INFO - Python 2.7.3 09:53:37 INFO - Return code: 0 09:53:37 INFO - grabbing minidump binary from tooltool 09:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:37 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 0x10910dd30>, '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 0x108e72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109105b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:53:37 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 09:53:37 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 09:53:37 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 09:53:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:53:37 INFO - Return code: 0 09:53:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:53:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:53:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:53:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:53:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:53:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0336', '--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 09:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0336 --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 09:53:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RihCv0tQKq/Render', 09:53:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:37 INFO - 'HOME': '/Users/cltbld', 09:53:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:37 INFO - 'LOGNAME': 'cltbld', 09:53:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:53:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:53:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:37 INFO - 'MOZ_NO_REMOTE': '1', 09:53:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:53:37 INFO - 'NO_EM_RESTART': '1', 09:53:37 INFO - 'PAGER': '/bin/cat', 09:53:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:37 INFO - 'PWD': '/builds/slave/test', 09:53:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:53:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:37 INFO - 'SHELL': '/bin/bash', 09:53:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Jv4WNlQtc/Listeners', 09:53:37 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 09:53:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:37 INFO - 'USER': 'cltbld', 09:53:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:37 INFO - 'XPC_FLAGS': '0x0', 09:53:37 INFO - 'XPC_SERVICE_NAME': '0', 09:53:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0336', '--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 09:53:37 INFO - mozversion application_buildid: 20160825071625 09:53:37 INFO - mozversion application_changeset: c0c2f6077694008794c06900943654db0143c2b9 09:53:37 INFO - mozversion application_display_name: Nightly 09:53:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:53:37 INFO - mozversion application_name: Firefox 09:53:37 INFO - mozversion application_remotingname: firefox 09:53:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:53:37 INFO - mozversion application_vendor: Mozilla 09:53:37 INFO - mozversion application_version: 51.0a1 09:53:37 INFO - mozversion platform_buildid: 20160825071625 09:53:37 INFO - mozversion platform_changeset: c0c2f6077694008794c06900943654db0143c2b9 09:53:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:53:37 INFO - mozversion platform_version: 51.0a1 09:53:37 INFO - using testdate: 1472144017 09:53:37 INFO - actual date: 1472144017 09:53:37 INFO - starting webserver on 'localhost:49267' 09:53:37 INFO - SUITE-START | Running 2 tests 09:53:37 INFO - TEST-START | dromaeo_css 09:53:37 INFO - operating with platform_type : mac_ 09:53:37 INFO - Initialising browser for dromaeo_css test... 09:53:37 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmp58DZeQ/profile http://localhost:49267/getInfo.html) 09:53:39 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 09:53:39 INFO - PROCESS | 1644 | colorDepth:24 09:53:39 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 09:53:39 INFO - PROCESS | 1644 | __metrics 09:53:40 INFO - PROCESS | 1644 | 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" 09:53:40 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:53:40 INFO - TEST-INFO | 1644: exit 0 09:53:40 INFO - Browser initialized. 09:53:40 INFO - Running cycle 1/1 for dromaeo_css test... 09:53:40 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmp58DZeQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:52 INFO - PROCESS | 1646 | RSS: Main: 232202240 09:53:52 INFO - PROCESS | 1646 | 09:54:43 INFO - PROCESS | 1646 | 1472144083831 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] 09:56:55 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 09:56:57 INFO - PROCESS | 1646 | RSS: Main: 380121088 09:56:57 INFO - PROCESS | 1646 | 10:00:00 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 10:00:00 INFO - PROCESS | 1646 | RSS: Main: 367394816 10:00:00 INFO - PROCESS | 1646 | 10:03:03 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 10:03:03 INFO - PROCESS | 1646 | RSS: Main: 358064128 10:03:03 INFO - PROCESS | 1646 | 10:06:06 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 10:06:06 INFO - PROCESS | 1646 | RSS: Main: 341790720 10:06:06 INFO - PROCESS | 1646 | 10:09:10 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 10:09:10 INFO - PROCESS | 1646 | RSS: Main: 322711552 10:09:10 INFO - PROCESS | 1646 | 10:12:14 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 10:12:14 INFO - PROCESS | 1646 | RSS: Main: 411037696 10:12:14 INFO - PROCESS | 1646 | 10:12:14 INFO - PROCESS | 1646 | __start_tp_report 10:12:14 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 10:12:14 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 10:12:14 INFO - PROCESS | 1646 | |i|pagename|runs| 10:12:14 INFO - PROCESS | 1646 | |0;dojo.html;3354.7148492378146;3363.276895467475;3377.459606462968;3421.7775844570115;3456.1877958679706;5610.915836262459;5669.036263835148;5744.712764361786;5773.711314434283;5817.243758311419;5742.942570574293;5762.049261871796;5843.123531470271;5851.122331650255;5861.413858614136;5149.639525233235;5177.715225662587;5255.684658920458;5259.31628888244;5329.493698098674;5276.577873770108;5330.813421530247;5337.4662533746705;5349.251104845324;5389.164679474688;5120;5172.508611681884;5180.870478238036;5217.165253559412;5260.316158899357;5196.714180720059;5227;5241.973790131063;5254.211868219798;5284.867878303035;5243.86890327741;5250.159974404115;5257.053730328539;5272.630915835893;5296.68219906803;5637.548996080303;5693.5445164387165;5715.657060576337;5797.304323481167;5832.125181222808;6210.751569937197;6239.220097487814;6286.277078136027;6286.87426251477;6296.181496405484;5239.633225674204;6193.2877719062435;6258.530610204253;6274.592151510137;6276.623402595859;5689.089745640677;5771.88456230873;5835.708214589338;5844.123381492767;5850.795222167204;6217.036359364306;6272.1219029335925;6274.937250627527;6284.277308109535;6299.464545513591;5106.846794596168;5245.9737701312015;6195.132681424604;6218.409251121137;6219.968900155471;6131.233595800525;6168.383161683885;6203;6237.376262373727;6244.1570387997035;6122.7550897964475;6138.386161383915;6143.109249158936;6160.075988601746;6262.5929314594405;6106.567337403798;6129.981545214243;6137.263528376623;6139.140520327069;6151.938480615226;4933.925991110136;4987.002599480046;4990.800367985313;5030.245463180552;5078.695278283315;4471.441069866267;4477.082198149372;4551.840685575989;4554.225781617133;4558.612517936012;5090.083784918676;5521.861953451196;5723.914141287884;5750.108733146285;5765.971170144207;6102.847428814315;6103.125543060039;6123.008104567321;6135.692037377249;6139.7988032087915;5737.454941780524;5739.483446489836;5756.251687280626;5779.422057794187;5827.329857066492;5436.565074794007;5441.374241962146;5446.809361672323;5455.509004189642;5464.7814087436855;6131.651934473353;6175.166352542439;6230.158928544589;6260.530460215593;6362.491000720024;6134.171886795404;6147.108669243024;6148.231471066117;6161.507079433724;6173.907391389043;6299.6535190564955;6314.147590075281;6325.493960483058;6344;6351.047342898602;6027.60820546663;6029.939700602938;6075.2405949256345;6101.084837274444;6142.253225532332;6054.836442658935;6154.953657878082;6167.229096362955;6173.81478555644;6195.845103872439;6023.096535519707;6046.365131661112;6050.667213303311;6068.6358818471035;6100.267967843887;5969.224000879858;6031.668258245663;6089.69551522431;6131.908021379772;6160.199174107337;5997.280326360865;6031.819045428644;6053.697315134314;6062.120992456157;6092;6123.387661233841;6126.938730612636;6168.660723660242;6168.814935551941;6173.59871608344;5709.914351284817;5770.682612456355;5771.451712087345;5868.647881127146;5899.144624029577 10:12:14 INFO - PROCESS | 1646 | |1;ext.html;514.626895500762;514.9176131818908;520.4977197004894;529.0345120155716;543.7824870051978;30140.397192056174;31085.378292434165;31992.040238792815;32105.83947080267;40037.79981100091;40459.79770101153;42700.78649606755;42734.57265427345;42735.57264427355;42752.93117672052;9671.17794987425;9838.655647052354;9842.852357214648;9848.409095454295;9867.111959923606;8860.20258176764;8949.910500894974;9721.222302215841;9839.45882976436;9840.655577054804;11875.287482751019;12160.027197824153;12177.260116473812;12369.257844529313;12370.319632420213;12038.157328986974;12142.664306926232;12153.574624888131;12156.209846359958;12168.26214101829;8640.870386944202;11630.88369116315;11930.642080737505;11988.400579971054;12043.879561204363;39909.80270591887;40283;40335;40358.192836143404;40375.39436908449;3408.3183363327184;3437.673421025024;3553.751237413382;3572.7499075064757;3819.0070581648692;30838;31895.52156717651;32073.198170045704;32080.839595802106;32092;3290.0623322353013;3353.564036675241;3389.559357283562;3410.0451873475467;3411.64177761334;367789;367912;368563;368605;373195;57016.429835701114;57275.85448291011;57296;57385.71307143437;57412.42587574071;58526;58546.12180817291;58574.7071264641;58612.41387586069;58657.41342586605;15839;16081.839181608268;16102.75845862313;16114.919425402797;16124.838751612568;12606.495740170474;12689.111762176559;12691.238525688488;12698.984081273476;12709.936450317875;7536.924630753732;7723.189065148191;7724.806879828049;7843.882341764878;14896.031757935916;6407.839804004844;7025.278806692291;7790.337821285141;7802.375809935192;7876.763697089096;807.4307613132727;830.7009476588544;960.5149399553181;1041.8958104189671;1059.4490864750287;29255.414891702047;29994.550081748792;29998.700013000027;30608.54087188694;30842.53736193959;1040.8602580174697;1093.191038631407;1155.2144541711548;1169.9005584525241;1179.4987130469688;14854.777178342334;15001.924990374979;15407.845921540866;15516.301766420538;15603.765943510856;712856;716017;718142;722125;722777;48794;49258;49294.26058609124;56744.71627641835;57492;1253.3232054690366;1281.7244292477162;1346.5489061164424;1372.7323171981368;1395.7487652222696;30513.694863051085;31446.52830207503;31919.173900978592;31951.36097278088;32539;48418.757906210245;49026.50973490362;49075.26387104125;49154;49177.75411122922;563341;566377.1681141568;566642;568059;570058;23522.764772352057;26636.467270654866;27335.179944601692;27466.313342166606;28224.85887570549;46623.76688116538;47484.2877356833;47514.76242618765;48160.795980100775;48544.02911941812;31053.378932420772;37483.81258093692;38650.03374915649;38768.6123138765;39543;33644;38479.23041539209;39467.40798888076;39599.40600890931;39963.2007359857;37561.060973474785;37937.43093853539;38403;38705;38778.80610596929;32441.675583243865;34278.82860585681;34413;34528.82735586306;34631.82684086564;24854.627180591942;25203.11789087373;25444.618330725425;25610.10364637229;25798.61302080433 10:12:14 INFO - PROCESS | 1646 | |2;jquery.html;356.5649907113321;364.8449409001174;376.0804832185308;387.20622723416994;412.73378670757364;15660.451884184056;16655.333786648534;21760.02079906404;21911.780882191175;22143.335699928986;20966.580668386625;21518.89240553799;22260.220892268775;22498;22548.21081262156;5297.655652382602;5977.611455255416;6002.909956350658;6186.659733714644;6232.189815324002;4234.66122710184;6065.120557519157;6162.661053642048;6187.845303867417;6194.628322300677;6454.741810327581;6846.075779769742;6909.654517274116;6940.479464040217;6947.826304342381;6628.276648071512;6822.658867056677;6877.65611719412;6890.414314783249;6892.104026476551;5779.913301300484;5796.913046304308;5961.731722072517;5993.700314984233;6004.849878753066;27100.457990840074;27774.722252777417;27869.024584139457;28066;28258.576121358197;2261.7285925688857;2391.2826152154457;2410.9397265068337;2515.9748402515925;2794.9021784237657;16656.58358541034;17496.475105746722;17672.646547069115;17803.842750221324;18155.72766408505;1747.4233502943998;2025.382258411184;2087.812096911285;2130.7230060092243;2136.572685462898;113031.43484282609;127160;133433;134856.3257183708;137618.62381376157;17589.824101759074;20251.08870100849;21220.78779212219;21713;21774;34420.79527216586;35046.47430288548;35053.47419788705;35501.8224908879;35626.28747425037;10921.617743379102;11136.554537818396;11145.832812507819;11201.831972520418;11363.431828408711;9105.089491050841;9109.043550427243;9142;9303.627854885865;9308.906910930804;9416.858747118798;9727.951360243154;9867.654632087842;9878.851817222747;9894.455804930798;3185.347003864202;3269.7711160218555;3381.934690572462;3704.7406681532034;3715.758475699071;837.2130197614223;899.4738078224312;971.6113554578226;1015.5480811039017;1026.958921643126;17406.91296543509;20926.605412863166;22258.666120008213;22342.776572234394;22397.776022239894;912.4753266871576;968.7626531499827;981.7580760338178;999.9850002249972;1033.6485594897772;8219.383546234056;11386;11612;11647.30116193031;11675.76648467026;566117;566868;583396.0830195822;611556;614254;83500.16499835045;121423;123842.76157238313;133026;136715;965.4834663455018;988.6984469736799;996.7209181429212;997.0079770628126;997.581015973293;19286;19505.317313893946;19514.60970780548;19611.901940490206;19706.90146549258;54693.53618224617;55743.72128139334;55802;57049.71475142598;57126.714366429565;79440.60279698565;80426.59786701029;80534.19465805267;80841;82907.17092828994;8200.466969646954;8245.670173193004;8349.206825351583;8413.326933845156;8512.148430275927;8820.647174112964;11808.82286765682;13049;13100.475980960655;13417.262050586922;11322.32066076038;11564.94217528907;11736;11820.995215406787;11827.290362578273;11231.55073797039;11262.887371126184;11312.886871131182;11570.628021255454;11605.76788464243;11483.827742584035;11613.3612651305;11874.643760687193;11893.881061189624;11978.76042479128;8254.793630159294;8270.751877443687;8441.788955276168;8442.788930276793;8602.698905538276;8394.118617545315;9541.666041688508;11419;11562.364069975896;11878.287302761863 10:12:14 INFO - PROCESS | 1646 | |3;mootools.html;1342.0873805812057;1360.149906308557;1498.5654160293514;1667.1247595012617;1894.7442095317138;7687.923120768791;8169.591520423977;8173.060098088729;8385.874211886827;8766.47401155931;8570.614322355494;8682.044975052739;8682.652693892236;8683.696070637528;8710.738677839676;2364.3498038039547;2412.2763171048705;2415.613501839706;2427.6965379327585;2429.514097180562;2334.3463830127594;2375.7149142102885;2383.2254517281867;2386.8806559672034;2388.892499837503;2361.5985282502015;2374.299581623425;2392.820538459614;2398.436367453647;2402.8197885158597;2342.227065068523;2375.667406563074;2377.9762202378015;2383.642453631969;2384.034466041256;2031.9796802031938;2326.581215381231;2365.1721897335797;2367.7040369953756;2369.194473878872;8577.313814894793;8746.956265218696;8753;8754.86867696985;8762.992255890527;5177.974110129425;5215.217717342391;5369.731513424314;5377.7311134443125;5386.973065134688;3508.5088087667746;3581.4269716845442;3677.6506231907924;3682.779033258013;3691.0956815580353;970.6069042037985;985.4333758089056;1107.5514416661265;1119.043218048565;1134.5915470430639;11426.571678540182;11445.084393248604;11466.31202127875;11472.885271147265;11479.598214062551;4469.726128053554;4644.3265726470145;4693.319468677091;4887.633427492952;4948.604111671058;9077.183053525083;9129.634814607341;9140.268778497704;9141.908580914238;9157.358984870994;5264.894702106013;5265.947340526623;5268.57851371897;5269.736513174326;5278.762455689426;4254.829806807755;4399.38408622793;4474.530174331649;4898.338724272177;4935.037667654773;3697.7041836653;3735.1595890924323;3785.413260944558;3834.827432765532;3845.2309538091936;1165.2367699157066;1168;1172.5075468303162;1181.8227265910011;1185.158537438411;651.2413038809829;764.6100488750777;865.8614621660504;898.9730308090768;971.455984648599;5720.628159169641;5900.409959004065;5963.5825492216;6064.151018857364;6108.541859360477;945.3220533552861;946.9621215151454;953.5899563187896;969.3021024862088;990.029770824596;3877.41838724188;4060.6954478414236;4098.979505102456;4261.572373254932;4272.33778764292;10541.31481453703;10752.569897204181;10777.461126943621;10786.892131078588;10787.78424431507;10573.101286390589;10715.678529644123;10727.78544429107;10745.677629671123;10757.547731056364;7660.2722741339485;8007.639656215484;8040.115587285394;8054.999999999883;8246.670133194604;5832.883342333214;5876.529877609866;6034;6112.908306375319;6163.691815409301;8959.865602015845;9440;9750.463724494937;10021.799564008825;10072.194224461879;8122.5126492410645;10133.442660653734;10160.593576256866;10346.172306215636;10373.170146388422;7330.5968171751065;7590;7646.65590048438;7662.463627546018;7670.0795904491815;8218.547979860927;8298.419110662197;8307.501549907025;8310.916890831015;8320.791980200302;7201.495895287279;7535.171131175457;7554.2445755426215;7561.130469996021;7613.847723045619;7356.521826081287;7404.593507110319;7420.443466740124;7473.663685134074;7498.962505187439;6977.302269773185;7081.752138675121;7088.787336379917;7089.929100708927;7100.751473698396;4897.975510122426;4967.3045773591;4975.378077740283;5014.974925125497;5135.229715542699;4481.215787237155;4565.429321334833;4599;5020.196768517019;5203 10:12:14 INFO - PROCESS | 1646 | |4;prototype.html;266.39528270825224;280.56512405771053;301.6666583425315;315.3252040633044;316.2741508238592;4942.937268487274;5168.896622067557;5191.221316802476;5208.8697782555455;5252.579793616511;4427.667924905629;4763.2378819388905;4984.725840078794;5164.302819119418;5192.871667024715;3451.0509609857304;3937.8424863005444;4011.65900898424;4041.2321658884866;4048.7165898387125;3369.2143164123054;4030.415589739486;4034.4150098235887;4036.555978842325;4070.229450189177;3506.246876561719;3774.301754175486;4031.5766844481345;4037.132016616427;4068.5321188063454;4015.2371049500794;4015.4177644241568;4019.678425725935;4022.939655905164;4045.089854782665;3512.207794803138;3520.331137083946;3525.7179425646145;3529.735269854771;3529.7645823961407;4944.632944127379;5042.924356134661;5104.846854594368;5125.487451254882;5155.020546096267;4571.3143028545655;4581.473130589991;4589.334546490773;4623.352730617717;4629.699069560502;5495.900819836049;5514.3658479275;5522.834314970557;5530.723463826793;5584.497395234408;3713.925721485582;3869.9613003870163;3948.3614300065437;3954.209158168349;3969.6625786808154;4905.583025442842;5736.827895163152;5751.654900705971;5756.251687280626;5763.6541807491685;3363.915902102418;3927.7250592458254;3930.4300876372768;3980.0646847990697;3980.980095099506;4008.178323443687;4013.8996525086523;4021.698372622065;4037.4145748866836;4071.959280407158;4406.33904914259;4470.776461176928;4569.634429245719;4581.908361832745;4751.40607424072;4612.53874612536;4909.337239472697;5372.623916325898;5518.902816929541;5528.75120619573;1194.6416075177412;1238.9876101239051;1364.4405793624567;1366.4875671623142;1367.4871923028863;773.5938632218131;845.9534725590152;874.3492956356574;876.4697358098387;930.3348106104168;4801.591864691471;5076.263941728429;5076.263941728429;5132.2814805926455;5170.224466330005;4591.885202869955;5021.096202683479;5151.356080489939;5423.688167906524;5670;4596.850787303174;4762.52374762521;4855.44162421319;4879.487653796371;5096.770645320969;2674.7592716655204;2848.1170837040586;2933.5478937925864;2940.2943293609806;2953.3354995125924;5343.465653434703;5407.080796264644;5692.918345514339;5757.971210144006;5866.472017518444;5369.946300537023;5471;5630.324361076615;5726.484616384462;5744.420284421733;4514.322851572225;5512.255845460892;5728.398518155617;5765.88468230633;5836.358000619844;5168.320295903771;5282.445343239058;5369.865753356197;5601.887962240651;5621.245589661118;4459.866204013885;4966.758310422394;5362.51737343641;5552.556335352918;5564.10974244119;5339.546138578109;5397.8110766123;5822.359540450631;5822.7670893163795;5890.823275301748;3293.793158583707;3680.327140190332;3892.995107519573;3910.2570511602703;3943.726706833379;5507.834764957058;5536.169574563848;5564.666120032811;5568.9443105568425;5595.944040559705;4471.28459446487;4571.451425828789;5387.865303367447;5403.86490337729;5404.72976351173;4807.735574543294;5226.660267082628;5346.599005074557;5350.796070884176;5464.207689885024;4607.976960115178;4680;4908;5131.9985205769035;5193.896122077462;4371.781410929504;5104.876927076038;5172.103621971708;5378.031954248117;5497.697626630414;4595.540445955377;4948.109340318777;4982.950170498249;5416.431274716098;5417.618507280618 10:12:14 INFO - PROCESS | 1646 | |5;yui.html;722.714304176235;725.5211560370155;746.1717493582123;814.3892080939296;817.975460736178;15406.383744650215;16574.66850662986;16581.66836663266;16596.33614655414;16597.75103373449;14328.426862925497;14463.421463141462;14464;14470.42118315266;14482.420703171887;670.8457054877381;695.1241435790889;835.7743409279491;838.9580520973957;867.3408209760596;685.3694600967123;862.5083702289697;865.0052439694371;865.2385900407633;867.3668222197799;912.9726108216764;913.4245425382001;913.8720579118929;914.1955079530004;916.5463095767604;701.1726163127558;882.5013867165031;913.5158366065963;913.5432283858071;914.7850255190025;682.3551743602297;682.5699809120223;684.4216636941776;686.7699320727562;686.8660611180821;14800;14817.70364592713;14890.702185956328;14899.627509312246;14925.70148597022;11419;13309.800352994713;13729.931350343184;14341.139531628136;14486.34811433488;12813.35933203336;13149.539766108235;13151.473941042346;13212.735745285137;13433.86566134336;4156.0593808340145;4659.603933665642;4689.20283551797;4861.453554688851;4880.853574392774;111270;111842.44078779635;112032;112474.43762781215;112734;9991.850122248026;10363.067323940732;10588.417637030037;10680.092192163598;10710.143188544898;17095.48713538596;20740.274090406765;20950.895245523676;20961;20996.475088122614;7644.197359277307;7716.4212684048925;7796.986391769034;7810.609469526501;7941.801454963672;4292.163028209469;4348.260795664744;4364.214441400515;4364.454443194601;4407.911841763147;3424.6232914379398;3519.894403167909;3544.3442963051916;3557.181848174934;3559.8252576649643;540.0090833320821;657.9769708060195;663.5990548439249;706.4666177036407;718.389369036315;573.4724053870449;668.6021817018866;682.8712307044239;682.9146356705412;699.923008469068;8699.695510657224;9390.812183756287;9514.33399662017;9586.089321514444;9610.471424071604;570.3412558494945;627.9309275979639;636.5830381100386;645.2805122288614;666.6033709942576;4488.39406680094;4632.02727427244;4646.279826626878;4675.812967481331;4716.29255611661;104312;105334.47332763288;122450;162934;171122;133682;136293;139090;140193;141064.29467852594;70980.25687559674;75344;77616.61191694005;98581.50709246407;115143;7479.925200747923;7980.441369104339;8668.049688819965;9297.6280948764;9776.755581110529;54620;57319.713401432724;57618.42381576131;59504.70247648734;60054.49863753441;80235.37175538973;90785.92140785868;91014.54492727494;91651;92345;10626.84059739089;12951;13054.804177937149;13122.278274694983;13226.669333266746;12546.309952952675;14776.220032124134;14914.925425372803;14953.476628317956;15169.08985460876;13452.26012569279;13721.176729396268;13776.517821876187;13923.164610123426;14048.578542643738;13289.867101328862;13599.980001500126;13714.38285277145;13740;14189.361478733275;10868.456577170951;13585.320733963064;13599.86400135986;13603;13604.455821767018;11065.446727663746;11170.27393219438;11383.715407114889;11781.05473631592;11908.285502869856;9363.297752668714;10135.7466063349;11048.613298534512;11480.311181329147;11555.653330400102 10:12:14 INFO - PROCESS | 1646 | __end_tp_report 10:12:14 INFO - PROCESS | 1646 | __start_cc_report 10:12:14 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,1270 10:12:14 INFO - PROCESS | 1646 | __end_cc_report 10:12:14 INFO - PROCESS | 1646 | __startTimestamp1472145134430__endTimestamp 10:12:14 INFO - PROCESS | 1646 | 10:12:14 INFO - PROCESS | 1646 | ------- Summary: start ------- 10:12:14 INFO - PROCESS | 1646 | Number of tests: 6 10:12:14 INFO - PROCESS | 1646 | 10:12:14 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5730.71 Median:5899.14 stddev:611.21 (10.4%) stddev-sans-first:583.97 10:12:14 INFO - PROCESS | 1646 | Values: 3354.7 3363.3 3377.5 3421.8 3456.2 5610.9 5669.0 5744.7 5773.7 5817.2 5742.9 5762.0 5843.1 5851.1 5861.4 5149.6 5177.7 5255.7 5259.3 5329.5 5276.6 5330.8 5337.5 5349.3 5389.2 5120.0 5172.5 5180.9 5217.2 5260.3 5196.7 5227.0 5242.0 5254.2 5284.9 5243.9 5250.2 5257.1 5272.6 5296.7 5637.5 5693.5 5715.7 5797.3 5832.1 6210.8 6239.2 6286.3 6286.9 6296.2 5239.6 6193.3 6258.5 6274.6 6276.6 5689.1 5771.9 5835.7 5844.1 5850.8 6217.0 6272.1 6274.9 6284.3 6299.5 5106.8 5246.0 6195.1 6218.4 6220.0 6131.2 6168.4 6203.0 6237.4 6244.2 6122.8 6138.4 6143.1 6160.1 6262.6 6106.6 6130.0 6137.3 6139.1 6151.9 4933.9 4987.0 4990.8 5030.2 5078.7 4471.4 4477.1 4551.8 4554.2 4558.6 5090.1 5521.9 5723.9 5750.1 5766.0 6102.8 6103.1 6123.0 6135.7 6139.8 5737.5 5739.5 5756.3 5779.4 5827.3 5436.6 5441.4 5446.8 5455.5 5464.8 6131.7 6175.2 6230.2 6260.5 6362.5 6134.2 6147.1 6148.2 6161.5 6173.9 6299.7 6314.1 6325.5 6344.0 6351.0 6027.6 6029.9 6075.2 6101.1 6142.3 6054.8 6155.0 6167.2 6173.8 6195.8 6023.1 6046.4 6050.7 6068.6 6100.3 5969.2 6031.7 6089.7 6131.9 6160.2 5997.3 6031.8 6053.7 6062.1 6092.0 6123.4 6126.9 6168.7 6168.8 6173.6 5709.9 5770.7 5771.5 5868.6 5899.1 10:12:14 INFO - PROCESS | 1646 | 10:12:14 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:68178.12 Median:370900.00 stddev:152840.46 (41.2%) stddev-sans-first:153184.79 10:12:14 INFO - PROCESS | 1646 | Values: 514.6 514.9 520.5 529.0 543.8 30140.4 31085.4 31992.0 32105.8 40037.8 40459.8 42700.8 42734.6 42735.6 42752.9 9671.2 9838.7 9842.9 9848.4 9867.1 8860.2 8949.9 9721.2 9839.5 9840.7 11875.3 12160.0 12177.3 12369.3 12370.3 12038.2 12142.7 12153.6 12156.2 12168.3 8640.9 11630.9 11930.6 11988.4 12043.9 39909.8 40283.0 40335.0 40358.2 40375.4 3408.3 3437.7 3553.8 3572.7 3819.0 30838.0 31895.5 32073.2 32080.8 32092.0 3290.1 3353.6 3389.6 3410.0 3411.6 367789.0 367912.0 368563.0 368605.0 373195.0 57016.4 57275.9 57296.0 57385.7 57412.4 58526.0 58546.1 58574.7 58612.4 58657.4 15839.0 16081.8 16102.8 16114.9 16124.8 12606.5 12689.1 12691.2 12699.0 12709.9 7536.9 7723.2 7724.8 7843.9 14896.0 6407.8 7025.3 7790.3 7802.4 7876.8 807.4 830.7 960.5 1041.9 1059.4 29255.4 29994.6 29998.7 30608.5 30842.5 1040.9 1093.2 1155.2 1169.9 1179.5 14854.8 15001.9 15407.8 15516.3 15603.8 712856.0 716017.0 718142.0 722125.0 722777.0 48794.0 49258.0 49294.3 56744.7 57492.0 1253.3 1281.7 1346.5 1372.7 1395.7 30513.7 31446.5 31919.2 31951.4 32539.0 48418.8 49026.5 49075.3 49154.0 49177.8 563341.0 566377.2 566642.0 568059.0 570058.0 23522.8 26636.5 27335.2 27466.3 28224.9 46623.8 47484.3 47514.8 48160.8 48544.0 31053.4 37483.8 38650.0 38768.6 39543.0 33644.0 38479.2 39467.4 39599.4 39963.2 37561.1 37937.4 38403.0 38705.0 38778.8 32441.7 34278.8 34413.0 34528.8 34631.8 24854.6 25203.1 25444.6 25610.1 25798.6 10:12:14 INFO - PROCESS | 1646 | 10:12:14 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:36531.80 Median:18901.36 stddev:98266.29 (519.9%) stddev-sans-first:98504.41 10:12:14 INFO - PROCESS | 1646 | Values: 356.6 364.8 376.1 387.2 412.7 15660.5 16655.3 21760.0 21911.8 22143.3 20966.6 21518.9 22260.2 22498.0 22548.2 5297.7 5977.6 6002.9 6186.7 6232.2 4234.7 6065.1 6162.7 6187.8 6194.6 6454.7 6846.1 6909.7 6940.5 6947.8 6628.3 6822.7 6877.7 6890.4 6892.1 5779.9 5796.9 5961.7 5993.7 6004.8 27100.5 27774.7 27869.0 28066.0 28258.6 2261.7 2391.3 2410.9 2516.0 2794.9 16656.6 17496.5 17672.6 17803.8 18155.7 1747.4 2025.4 2087.8 2130.7 2136.6 113031.4 127160.0 133433.0 134856.3 137618.6 17589.8 20251.1 21220.8 21713.0 21774.0 34420.8 35046.5 35053.5 35501.8 35626.3 10921.6 11136.6 11145.8 11201.8 11363.4 9105.1 9109.0 9142.0 9303.6 9308.9 9416.9 9728.0 9867.7 9878.9 9894.5 3185.3 3269.8 3381.9 3704.7 3715.8 837.2 899.5 971.6 1015.5 1027.0 17406.9 20926.6 22258.7 22342.8 22397.8 912.5 968.8 981.8 1000.0 1033.6 8219.4 11386.0 11612.0 11647.3 11675.8 566117.0 566868.0 583396.1 611556.0 614254.0 83500.2 121423.0 123842.8 133026.0 136715.0 965.5 988.7 996.7 997.0 997.6 19286.0 19505.3 19514.6 19611.9 19706.9 54693.5 55743.7 55802.0 57049.7 57126.7 79440.6 80426.6 80534.2 80841.0 82907.2 8200.5 8245.7 8349.2 8413.3 8512.1 8820.6 11808.8 13049.0 13100.5 13417.3 11322.3 11564.9 11736.0 11821.0 11827.3 11231.6 11262.9 11312.9 11570.6 11605.8 11483.8 11613.4 11874.6 11893.9 11978.8 8254.8 8270.8 8441.8 8442.8 8602.7 8394.1 9541.7 11419.0 11562.4 11878.3 10:12:14 INFO - PROCESS | 1646 | 10:12:14 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5654.04 Median:5017.59 stddev:3138.63 (62.6%) stddev-sans-first:3130.70 10:12:14 INFO - PROCESS | 1646 | Values: 1342.1 1360.1 1498.6 1667.1 1894.7 7687.9 8169.6 8173.1 8385.9 8766.5 8570.6 8682.0 8682.7 8683.7 8710.7 2364.3 2412.3 2415.6 2427.7 2429.5 2334.3 2375.7 2383.2 2386.9 2388.9 2361.6 2374.3 2392.8 2398.4 2402.8 2342.2 2375.7 2378.0 2383.6 2384.0 2032.0 2326.6 2365.2 2367.7 2369.2 8577.3 8747.0 8753.0 8754.9 8763.0 5178.0 5215.2 5369.7 5377.7 5387.0 3508.5 3581.4 3677.7 3682.8 3691.1 970.6 985.4 1107.6 1119.0 1134.6 11426.6 11445.1 11466.3 11472.9 11479.6 4469.7 4644.3 4693.3 4887.6 4948.6 9077.2 9129.6 9140.3 9141.9 9157.4 5264.9 5265.9 5268.6 5269.7 5278.8 4254.8 4399.4 4474.5 4898.3 4935.0 3697.7 3735.2 3785.4 3834.8 3845.2 1165.2 1168.0 1172.5 1181.8 1185.2 651.2 764.6 865.9 899.0 971.5 5720.6 5900.4 5963.6 6064.2 6108.5 945.3 947.0 953.6 969.3 990.0 3877.4 4060.7 4099.0 4261.6 4272.3 10541.3 10752.6 10777.5 10786.9 10787.8 10573.1 10715.7 10727.8 10745.7 10757.5 7660.3 8007.6 8040.1 8055.0 8246.7 5832.9 5876.5 6034.0 6112.9 6163.7 8959.9 9440.0 9750.5 10021.8 10072.2 8122.5 10133.4 10160.6 10346.2 10373.2 7330.6 7590.0 7646.7 7662.5 7670.1 8218.5 8298.4 8307.5 8310.9 8320.8 7201.5 7535.2 7554.2 7561.1 7613.8 7356.5 7404.6 7420.4 7473.7 7499.0 6977.3 7081.8 7088.8 7089.9 7100.8 4898.0 4967.3 4975.4 5015.0 5135.2 4481.2 4565.4 4599.0 5020.2 5203.0 10:12:14 INFO - PROCESS | 1646 | 10:12:14 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4432.35 Median:4926.14 stddev:1324.19 (26.9%) stddev-sans-first:1290.45 10:12:14 INFO - PROCESS | 1646 | Values: 266.4 280.6 301.7 315.3 316.3 4942.9 5168.9 5191.2 5208.9 5252.6 4427.7 4763.2 4984.7 5164.3 5192.9 3451.1 3937.8 4011.7 4041.2 4048.7 3369.2 4030.4 4034.4 4036.6 4070.2 3506.2 3774.3 4031.6 4037.1 4068.5 4015.2 4015.4 4019.7 4022.9 4045.1 3512.2 3520.3 3525.7 3529.7 3529.8 4944.6 5042.9 5104.8 5125.5 5155.0 4571.3 4581.5 4589.3 4623.4 4629.7 5495.9 5514.4 5522.8 5530.7 5584.5 3713.9 3870.0 3948.4 3954.2 3969.7 4905.6 5736.8 5751.7 5756.3 5763.7 3363.9 3927.7 3930.4 3980.1 3981.0 4008.2 4013.9 4021.7 4037.4 4072.0 4406.3 4470.8 4569.6 4581.9 4751.4 4612.5 4909.3 5372.6 5518.9 5528.8 1194.6 1239.0 1364.4 1366.5 1367.5 773.6 846.0 874.3 876.5 930.3 4801.6 5076.3 5076.3 5132.3 5170.2 4591.9 5021.1 5151.4 5423.7 5670.0 4596.9 4762.5 4855.4 4879.5 5096.8 2674.8 2848.1 2933.5 2940.3 2953.3 5343.5 5407.1 5692.9 5758.0 5866.5 5369.9 5471.0 5630.3 5726.5 5744.4 4514.3 5512.3 5728.4 5765.9 5836.4 5168.3 5282.4 5369.9 5601.9 5621.2 4459.9 4966.8 5362.5 5552.6 5564.1 5339.5 5397.8 5822.4 5822.8 5890.8 3293.8 3680.3 3893.0 3910.3 3943.7 5507.8 5536.2 5564.7 5568.9 5595.9 4471.3 4571.5 5387.9 5403.9 5404.7 4807.7 5226.7 5346.6 5350.8 5464.2 4608.0 4680.0 4908.0 5132.0 5193.9 4371.8 5104.9 5172.1 5378.0 5497.7 4595.5 4948.1 4983.0 5416.4 5417.6 10:12:14 INFO - PROCESS | 1646 | 10:12:14 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:23898.11 Median:4320.21 stddev:38089.15 (881.7%) stddev-sans-first:38156.26 10:12:14 INFO - PROCESS | 1646 | Values: 722.7 725.5 746.2 814.4 818.0 15406.4 16574.7 16581.7 16596.3 16597.8 14328.4 14463.4 14464.0 14470.4 14482.4 670.8 695.1 835.8 839.0 867.3 685.4 862.5 865.0 865.2 867.4 913.0 913.4 913.9 914.2 916.5 701.2 882.5 913.5 913.5 914.8 682.4 682.6 684.4 686.8 686.9 14800.0 14817.7 14890.7 14899.6 14925.7 11419.0 13309.8 13729.9 14341.1 14486.3 12813.4 13149.5 13151.5 13212.7 13433.9 4156.1 4659.6 4689.2 4861.5 4880.9 111270.0 111842.4 112032.0 112474.4 112734.0 9991.9 10363.1 10588.4 10680.1 10710.1 17095.5 20740.3 20950.9 20961.0 20996.5 7644.2 7716.4 7797.0 7810.6 7941.8 4292.2 4348.3 4364.2 4364.5 4407.9 3424.6 3519.9 3544.3 3557.2 3559.8 540.0 658.0 663.6 706.5 718.4 573.5 668.6 682.9 682.9 699.9 8699.7 9390.8 9514.3 9586.1 9610.5 570.3 627.9 636.6 645.3 666.6 4488.4 4632.0 4646.3 4675.8 4716.3 104312.0 105334.5 122450.0 162934.0 171122.0 133682.0 136293.0 139090.0 140193.0 141064.3 70980.3 75344.0 77616.6 98581.5 115143.0 7479.9 7980.4 8668.0 9297.6 9776.8 54620.0 57319.7 57618.4 59504.7 60054.5 80235.4 90785.9 91014.5 91651.0 92345.0 10626.8 12951.0 13054.8 13122.3 13226.7 12546.3 14776.2 14914.9 14953.5 15169.1 13452.3 13721.2 13776.5 13923.2 14048.6 13289.9 13600.0 13714.4 13740.0 14189.4 10868.5 13585.3 13599.9 13603.0 13604.5 11065.4 11170.3 11383.7 11781.1 11908.3 9363.3 10135.7 11048.6 11480.3 11555.7 10:12:14 INFO - PROCESS | 1646 | -------- Summary: end -------- 10:12:14 INFO - PROCESS | 1646 | 10:12:15 INFO - TEST-INFO | 1646: exit 0 10:12:15 INFO - TEST-OK | dromaeo_css | took 1117429ms 10:12:15 INFO - TEST-START | kraken 10:12:15 INFO - operating with platform_type : mac_ 10:12:15 INFO - Initialising browser for kraken test... 10:12:15 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmptgsc3W/profile http://localhost:49267/getInfo.html) 10:12:17 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 10:12:17 INFO - PROCESS | 1655 | colorDepth:24 10:12:17 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 10:12:17 INFO - PROCESS | 1655 | __metrics 10:12:17 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:12:17 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:12:17 INFO - TEST-INFO | 1655: exit 0 10:12:17 INFO - Browser initialized. 10:12:17 INFO - Running cycle 1/1 for kraken test... 10:12:17 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmptgsc3W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:12:30 INFO - PROCESS | 1656 | RSS: Main: 232091648 10:12:30 INFO - PROCESS | 1656 | 10:12:32 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 10:12:33 INFO - PROCESS | 1656 | RSS: Main: 289697792 10:12:33 INFO - PROCESS | 1656 | 10:12:36 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 10:12:36 INFO - PROCESS | 1656 | RSS: Main: 285339648 10:12:36 INFO - PROCESS | 1656 | 10:12:40 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 10:12:40 INFO - PROCESS | 1656 | RSS: Main: 372363264 10:12:40 INFO - PROCESS | 1656 | 10:12:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 10:12:43 INFO - PROCESS | 1656 | RSS: Main: 275566592 10:12:43 INFO - PROCESS | 1656 | 10:12:45 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:12:46 INFO - PROCESS | 1656 | RSS: Main: 279486464 10:12:46 INFO - PROCESS | 1656 | 10:12:49 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 10:12:49 INFO - PROCESS | 1656 | RSS: Main: 318881792 10:12:49 INFO - PROCESS | 1656 | 10:12:52 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 10:12:53 INFO - PROCESS | 1656 | RSS: Main: 320253952 10:12:53 INFO - PROCESS | 1656 | 10:12:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 10:12:56 INFO - PROCESS | 1656 | RSS: Main: 317808640 10:12:56 INFO - PROCESS | 1656 | 10:12:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:12:58 INFO - PROCESS | 1656 | RSS: Main: 295739392 10:12:58 INFO - PROCESS | 1656 | 10:13:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:13:01 INFO - PROCESS | 1656 | RSS: Main: 300548096 10:13:01 INFO - PROCESS | 1656 | 10:13:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:13:04 INFO - PROCESS | 1656 | RSS: Main: 307093504 10:13:04 INFO - PROCESS | 1656 | 10:13:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:13:07 INFO - PROCESS | 1656 | RSS: Main: 309972992 10:13:07 INFO - PROCESS | 1656 | 10:13:10 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:13:11 INFO - PROCESS | 1656 | RSS: Main: 311287808 10:13:11 INFO - PROCESS | 1656 | 10:13:12 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 10:13:13 INFO - PROCESS | 1656 | RSS: Main: 354971648 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | __start_tp_report 10:13:13 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 10:13:13 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 10:13:13 INFO - PROCESS | 1656 | |i|pagename|runs| 10:13:13 INFO - PROCESS | 1656 | |0;ai-astar;112;112;129;114;113;114;113;112;114;114 10:13:13 INFO - PROCESS | 1656 | |1;audio-beat-detection;149;109;131;161;108;130;130;103;148;125 10:13:13 INFO - PROCESS | 1656 | |2;audio-dft;183;184;184;180;183;184;181;181;184;185 10:13:13 INFO - PROCESS | 1656 | |3;audio-fft;71;81;76;147;58;58;63;70;139;57 10:13:13 INFO - PROCESS | 1656 | |4;audio-oscillator;83;82;133;71;70;89;121;91;103;149 10:13:13 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;88;85;87;84;87;84;88;85;88;84 10:13:13 INFO - PROCESS | 1656 | |6;imaging-darkroom;94;94;94;94;93;94;94;94;95;98 10:13:13 INFO - PROCESS | 1656 | |7;imaging-desaturate;85;85;86;84;83;86;86;84;84;85 10:13:13 INFO - PROCESS | 1656 | |8;json-parse-financial;54;53;53;53;53;53;54;55;54;53 10:13:13 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;67;67;71;71;78;71;70;69;69;91 10:13:13 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;97;105;87;87;84;98;89;89;84;111 10:13:13 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;140;138;139;156;121;137;140;143;154;154 10:13:13 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;170;170;170;170;167;170;167;171;169;170 10:13:13 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;67;66;65;68;67;67;66;68;66;68 10:13:13 INFO - PROCESS | 1656 | __end_tp_report 10:13:13 INFO - PROCESS | 1656 | __start_cc_report 10:13:13 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2007 10:13:13 INFO - PROCESS | 1656 | __end_cc_report 10:13:13 INFO - PROCESS | 1656 | __startTimestamp1472145193221__endTimestamp 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | ------- Summary: start ------- 10:13:13 INFO - PROCESS | 1656 | Number of tests: 14 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:114.70 Median:114.00 stddev:5.10 (4.5%) stddev-sans-first:5.32 10:13:13 INFO - PROCESS | 1656 | Values: 112.0 112.0 129.0 114.0 113.0 114.0 113.0 112.0 114.0 114.0 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:130.50 stddev:19.21 (14.7%) stddev-sans-first:19.02 10:13:13 INFO - PROCESS | 1656 | Values: 149.0 109.0 131.0 161.0 108.0 130.0 130.0 103.0 148.0 125.0 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:182.90 Median:184.00 stddev:1.66 (0.9%) stddev-sans-first:1.76 10:13:13 INFO - PROCESS | 1656 | Values: 183.0 184.0 184.0 180.0 183.0 184.0 181.0 181.0 184.0 185.0 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:82.00 Median:66.50 stddev:33.19 (49.9%) stddev-sans-first:34.96 10:13:13 INFO - PROCESS | 1656 | Values: 71.0 81.0 76.0 147.0 58.0 58.0 63.0 70.0 139.0 57.0 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:99.20 Median:76.50 stddev:26.85 (35.1%) stddev-sans-first:27.83 10:13:13 INFO - PROCESS | 1656 | Values: 83.0 82.0 133.0 71.0 70.0 89.0 121.0 91.0 103.0 149.0 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.76 (2.0%) stddev-sans-first:1.72 10:13:13 INFO - PROCESS | 1656 | Values: 88.0 85.0 87.0 84.0 87.0 84.0 88.0 85.0 88.0 84.0 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:94.00 stddev:1.35 (1.4%) stddev-sans-first:1.42 10:13:13 INFO - PROCESS | 1656 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 95.0 98.0 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.03 (1.2%) stddev-sans-first:1.09 10:13:13 INFO - PROCESS | 1656 | Values: 85.0 85.0 86.0 84.0 83.0 86.0 86.0 84.0 84.0 85.0 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:53.50 stddev:0.71 (1.3%) stddev-sans-first:0.73 10:13:13 INFO - PROCESS | 1656 | Values: 54.0 53.0 53.0 53.0 53.0 53.0 54.0 55.0 54.0 53.0 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:71.00 stddev:7.23 (10.2%) stddev-sans-first:7.40 10:13:13 INFO - PROCESS | 1656 | Values: 67.0 67.0 71.0 71.0 78.0 71.0 70.0 69.0 69.0 91.0 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:93.10 Median:88.00 stddev:9.28 (10.5%) stddev-sans-first:9.73 10:13:13 INFO - PROCESS | 1656 | Values: 97.0 105.0 87.0 87.0 84.0 98.0 89.0 89.0 84.0 111.0 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:142.20 Median:141.50 stddev:10.45 (7.4%) stddev-sans-first:11.06 10:13:13 INFO - PROCESS | 1656 | Values: 140.0 138.0 139.0 156.0 121.0 137.0 140.0 143.0 154.0 154.0 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.40 Median:170.00 stddev:1.35 (0.8%) stddev-sans-first:1.41 10:13:13 INFO - PROCESS | 1656 | Values: 170.0 170.0 170.0 170.0 167.0 170.0 167.0 171.0 169.0 170.0 10:13:13 INFO - PROCESS | 1656 | 10:13:13 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.03 (1.5%) stddev-sans-first:1.09 10:13:13 INFO - PROCESS | 1656 | Values: 67.0 66.0 65.0 68.0 67.0 67.0 66.0 68.0 66.0 68.0 10:13:13 INFO - PROCESS | 1656 | -------- Summary: end -------- 10:13:13 INFO - PROCESS | 1656 | 10:13:14 INFO - TEST-INFO | 1656: exit 0 10:13:14 INFO - TEST-OK | kraken | took 58987ms 10:13:14 INFO - SUITE-END | took 1176s 10:13:14 INFO - Completed test suite (00:19:36) 10:13:14 INFO - javascript benchmark 10:13:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3354.7148492378146, 3363.276895467475, 3377.459606462968, 3421.7775844570115, 3456.1877958679706, 5610.915836262459, 5669.036263835148, 5744.712764361786, 5773.711314434283, 5817.243758311419, 5742.942570574293, 5762.049261871796, 5843.123531470271, 5851.122331650255, 5861.413858614136, 5149.639525233235, 5177.715225662587, 5255.684658920458, 5259.31628888244, 5329.493698098674, 5276.577873770108, 5330.813421530247, 5337.4662533746705, 5349.251104845324, 5389.164679474688, 5120.0, 5172.508611681884, 5180.870478238036, 5217.165253559412, 5260.316158899357, 5196.714180720059, 5227.0, 5241.973790131063, 5254.211868219798, 5284.867878303035, 5243.86890327741, 5250.159974404115, 5257.053730328539, 5272.630915835893, 5296.68219906803, 5637.548996080303, 5693.5445164387165, 5715.657060576337, 5797.304323481167, 5832.125181222808, 6210.751569937197, 6239.220097487814, 6286.277078136027, 6286.87426251477, 6296.181496405484, 5239.633225674204, 6193.2877719062435, 6258.530610204253, 6274.592151510137, 6276.623402595859, 5689.089745640677, 5771.88456230873, 5835.708214589338, 5844.123381492767, 5850.795222167204, 6217.036359364306, 6272.1219029335925, 6274.937250627527, 6284.277308109535, 6299.464545513591, 5106.846794596168, 5245.9737701312015, 6195.132681424604, 6218.409251121137, 6219.968900155471, 6131.233595800525, 6168.383161683885, 6203.0, 6237.376262373727, 6244.1570387997035, 6122.7550897964475, 6138.386161383915, 6143.109249158936, 6160.075988601746, 6262.5929314594405, 6106.567337403798, 6129.981545214243, 6137.263528376623, 6139.140520327069, 6151.938480615226, 4933.925991110136, 4987.002599480046, 4990.800367985313, 5030.245463180552, 5078.695278283315, 4471.441069866267, 4477.082198149372, 4551.840685575989, 4554.225781617133, 4558.612517936012, 5090.083784918676, 5521.861953451196, 5723.914141287884, 5750.108733146285, 5765.971170144207, 6102.847428814315, 6103.125543060039, 6123.008104567321, 6135.692037377249, 6139.7988032087915, 5737.454941780524, 5739.483446489836, 5756.251687280626, 5779.422057794187, 5827.329857066492, 5436.565074794007, 5441.374241962146, 5446.809361672323, 5455.509004189642, 5464.7814087436855, 6131.651934473353, 6175.166352542439, 6230.158928544589, 6260.530460215593, 6362.491000720024, 6134.171886795404, 6147.108669243024, 6148.231471066117, 6161.507079433724, 6173.907391389043, 6299.6535190564955, 6314.147590075281, 6325.493960483058, 6344.0, 6351.047342898602, 6027.60820546663, 6029.939700602938, 6075.2405949256345, 6101.084837274444, 6142.253225532332, 6054.836442658935, 6154.953657878082, 6167.229096362955, 6173.81478555644, 6195.845103872439, 6023.096535519707, 6046.365131661112, 6050.667213303311, 6068.6358818471035, 6100.267967843887, 5969.224000879858, 6031.668258245663, 6089.69551522431, 6131.908021379772, 6160.199174107337, 5997.280326360865, 6031.819045428644, 6053.697315134314, 6062.120992456157, 6092.0, 6123.387661233841, 6126.938730612636, 6168.660723660242, 6168.814935551941, 6173.59871608344, 5709.914351284817, 5770.682612456355, 5771.451712087345, 5868.647881127146, 5899.144624029577], "value": 5693.61204525513, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.626895500762, 514.9176131818908, 520.4977197004894, 529.0345120155716, 543.7824870051978, 30140.397192056174, 31085.378292434165, 31992.040238792815, 32105.83947080267, 40037.79981100091, 40459.79770101153, 42700.78649606755, 42734.57265427345, 42735.57264427355, 42752.93117672052, 9671.17794987425, 9838.655647052354, 9842.852357214648, 9848.409095454295, 9867.111959923606, 8860.20258176764, 8949.910500894974, 9721.222302215841, 9839.45882976436, 9840.655577054804, 11875.287482751019, 12160.027197824153, 12177.260116473812, 12369.257844529313, 12370.319632420213, 12038.157328986974, 12142.664306926232, 12153.574624888131, 12156.209846359958, 12168.26214101829, 8640.870386944202, 11630.88369116315, 11930.642080737505, 11988.400579971054, 12043.879561204363, 39909.80270591887, 40283.0, 40335.0, 40358.192836143404, 40375.39436908449, 3408.3183363327184, 3437.673421025024, 3553.751237413382, 3572.7499075064757, 3819.0070581648692, 30838.0, 31895.52156717651, 32073.198170045704, 32080.839595802106, 32092.0, 3290.0623322353013, 3353.564036675241, 3389.559357283562, 3410.0451873475467, 3411.64177761334, 367789.0, 367912.0, 368563.0, 368605.0, 373195.0, 57016.429835701114, 57275.85448291011, 57296.0, 57385.71307143437, 57412.42587574071, 58526.0, 58546.12180817291, 58574.7071264641, 58612.41387586069, 58657.41342586605, 15839.0, 16081.839181608268, 16102.75845862313, 16114.919425402797, 16124.838751612568, 12606.495740170474, 12689.111762176559, 12691.238525688488, 12698.984081273476, 12709.936450317875, 7536.924630753732, 7723.189065148191, 7724.806879828049, 7843.882341764878, 14896.031757935916, 6407.839804004844, 7025.278806692291, 7790.337821285141, 7802.375809935192, 7876.763697089096, 807.4307613132727, 830.7009476588544, 960.5149399553181, 1041.8958104189671, 1059.4490864750287, 29255.414891702047, 29994.550081748792, 29998.700013000027, 30608.54087188694, 30842.53736193959, 1040.8602580174697, 1093.191038631407, 1155.2144541711548, 1169.9005584525241, 1179.4987130469688, 14854.777178342334, 15001.924990374979, 15407.845921540866, 15516.301766420538, 15603.765943510856, 712856.0, 716017.0, 718142.0, 722125.0, 722777.0, 48794.0, 49258.0, 49294.26058609124, 56744.71627641835, 57492.0, 1253.3232054690366, 1281.7244292477162, 1346.5489061164424, 1372.7323171981368, 1395.7487652222696, 30513.694863051085, 31446.52830207503, 31919.173900978592, 31951.36097278088, 32539.0, 48418.757906210245, 49026.50973490362, 49075.26387104125, 49154.0, 49177.75411122922, 563341.0, 566377.1681141568, 566642.0, 568059.0, 570058.0, 23522.764772352057, 26636.467270654866, 27335.179944601692, 27466.313342166606, 28224.85887570549, 46623.76688116538, 47484.2877356833, 47514.76242618765, 48160.795980100775, 48544.02911941812, 31053.378932420772, 37483.81258093692, 38650.03374915649, 38768.6123138765, 39543.0, 33644.0, 38479.23041539209, 39467.40798888076, 39599.40600890931, 39963.2007359857, 37561.060973474785, 37937.43093853539, 38403.0, 38705.0, 38778.80610596929, 32441.675583243865, 34278.82860585681, 34413.0, 34528.82735586306, 34631.82684086564, 24854.627180591942, 25203.11789087373, 25444.618330725425, 25610.10364637229, 25798.61302080433], "value": 19672.67668216704, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.5649907113321, 364.8449409001174, 376.0804832185308, 387.20622723416994, 412.73378670757364, 15660.451884184056, 16655.333786648534, 21760.02079906404, 21911.780882191175, 22143.335699928986, 20966.580668386625, 21518.89240553799, 22260.220892268775, 22498.0, 22548.21081262156, 5297.655652382602, 5977.611455255416, 6002.909956350658, 6186.659733714644, 6232.189815324002, 4234.66122710184, 6065.120557519157, 6162.661053642048, 6187.845303867417, 6194.628322300677, 6454.741810327581, 6846.075779769742, 6909.654517274116, 6940.479464040217, 6947.826304342381, 6628.276648071512, 6822.658867056677, 6877.65611719412, 6890.414314783249, 6892.104026476551, 5779.913301300484, 5796.913046304308, 5961.731722072517, 5993.700314984233, 6004.849878753066, 27100.457990840074, 27774.722252777417, 27869.024584139457, 28066.0, 28258.576121358197, 2261.7285925688857, 2391.2826152154457, 2410.9397265068337, 2515.9748402515925, 2794.9021784237657, 16656.58358541034, 17496.475105746722, 17672.646547069115, 17803.842750221324, 18155.72766408505, 1747.4233502943998, 2025.382258411184, 2087.812096911285, 2130.7230060092243, 2136.572685462898, 113031.43484282609, 127160.0, 133433.0, 134856.3257183708, 137618.62381376157, 17589.824101759074, 20251.08870100849, 21220.78779212219, 21713.0, 21774.0, 34420.79527216586, 35046.47430288548, 35053.47419788705, 35501.8224908879, 35626.28747425037, 10921.617743379102, 11136.554537818396, 11145.832812507819, 11201.831972520418, 11363.431828408711, 9105.089491050841, 9109.043550427243, 9142.0, 9303.627854885865, 9308.906910930804, 9416.858747118798, 9727.951360243154, 9867.654632087842, 9878.851817222747, 9894.455804930798, 3185.347003864202, 3269.7711160218555, 3381.934690572462, 3704.7406681532034, 3715.758475699071, 837.2130197614223, 899.4738078224312, 971.6113554578226, 1015.5480811039017, 1026.958921643126, 17406.91296543509, 20926.605412863166, 22258.666120008213, 22342.776572234394, 22397.776022239894, 912.4753266871576, 968.7626531499827, 981.7580760338178, 999.9850002249972, 1033.6485594897772, 8219.383546234056, 11386.0, 11612.0, 11647.30116193031, 11675.76648467026, 566117.0, 566868.0, 583396.0830195822, 611556.0, 614254.0, 83500.16499835045, 121423.0, 123842.76157238313, 133026.0, 136715.0, 965.4834663455018, 988.6984469736799, 996.7209181429212, 997.0079770628126, 997.581015973293, 19286.0, 19505.317313893946, 19514.60970780548, 19611.901940490206, 19706.90146549258, 54693.53618224617, 55743.72128139334, 55802.0, 57049.71475142598, 57126.714366429565, 79440.60279698565, 80426.59786701029, 80534.19465805267, 80841.0, 82907.17092828994, 8200.466969646954, 8245.670173193004, 8349.206825351583, 8413.326933845156, 8512.148430275927, 8820.647174112964, 11808.82286765682, 13049.0, 13100.475980960655, 13417.262050586922, 11322.32066076038, 11564.94217528907, 11736.0, 11820.995215406787, 11827.290362578273, 11231.55073797039, 11262.887371126184, 11312.886871131182, 11570.628021255454, 11605.76788464243, 11483.827742584035, 11613.3612651305, 11874.643760687193, 11893.881061189624, 11978.76042479128, 8254.793630159294, 8270.751877443687, 8441.788955276168, 8442.788930276793, 8602.698905538276, 8394.118617545315, 9541.666041688508, 11419.0, 11562.364069975896, 11878.287302761863], "value": 10902.047696005811, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.0873805812057, 1360.149906308557, 1498.5654160293514, 1667.1247595012617, 1894.7442095317138, 7687.923120768791, 8169.591520423977, 8173.060098088729, 8385.874211886827, 8766.47401155931, 8570.614322355494, 8682.044975052739, 8682.652693892236, 8683.696070637528, 8710.738677839676, 2364.3498038039547, 2412.2763171048705, 2415.613501839706, 2427.6965379327585, 2429.514097180562, 2334.3463830127594, 2375.7149142102885, 2383.2254517281867, 2386.8806559672034, 2388.892499837503, 2361.5985282502015, 2374.299581623425, 2392.820538459614, 2398.436367453647, 2402.8197885158597, 2342.227065068523, 2375.667406563074, 2377.9762202378015, 2383.642453631969, 2384.034466041256, 2031.9796802031938, 2326.581215381231, 2365.1721897335797, 2367.7040369953756, 2369.194473878872, 8577.313814894793, 8746.956265218696, 8753.0, 8754.86867696985, 8762.992255890527, 5177.974110129425, 5215.217717342391, 5369.731513424314, 5377.7311134443125, 5386.973065134688, 3508.5088087667746, 3581.4269716845442, 3677.6506231907924, 3682.779033258013, 3691.0956815580353, 970.6069042037985, 985.4333758089056, 1107.5514416661265, 1119.043218048565, 1134.5915470430639, 11426.571678540182, 11445.084393248604, 11466.31202127875, 11472.885271147265, 11479.598214062551, 4469.726128053554, 4644.3265726470145, 4693.319468677091, 4887.633427492952, 4948.604111671058, 9077.183053525083, 9129.634814607341, 9140.268778497704, 9141.908580914238, 9157.358984870994, 5264.894702106013, 5265.947340526623, 5268.57851371897, 5269.736513174326, 5278.762455689426, 4254.829806807755, 4399.38408622793, 4474.530174331649, 4898.338724272177, 4935.037667654773, 3697.7041836653, 3735.1595890924323, 3785.413260944558, 3834.827432765532, 3845.2309538091936, 1165.2367699157066, 1168.0, 1172.5075468303162, 1181.8227265910011, 1185.158537438411, 651.2413038809829, 764.6100488750777, 865.8614621660504, 898.9730308090768, 971.455984648599, 5720.628159169641, 5900.409959004065, 5963.5825492216, 6064.151018857364, 6108.541859360477, 945.3220533552861, 946.9621215151454, 953.5899563187896, 969.3021024862088, 990.029770824596, 3877.41838724188, 4060.6954478414236, 4098.979505102456, 4261.572373254932, 4272.33778764292, 10541.31481453703, 10752.569897204181, 10777.461126943621, 10786.892131078588, 10787.78424431507, 10573.101286390589, 10715.678529644123, 10727.78544429107, 10745.677629671123, 10757.547731056364, 7660.2722741339485, 8007.639656215484, 8040.115587285394, 8054.999999999883, 8246.670133194604, 5832.883342333214, 5876.529877609866, 6034.0, 6112.908306375319, 6163.691815409301, 8959.865602015845, 9440.0, 9750.463724494937, 10021.799564008825, 10072.194224461879, 8122.5126492410645, 10133.442660653734, 10160.593576256866, 10346.172306215636, 10373.170146388422, 7330.5968171751065, 7590.0, 7646.65590048438, 7662.463627546018, 7670.0795904491815, 8218.547979860927, 8298.419110662197, 8307.501549907025, 8310.916890831015, 8320.791980200302, 7201.495895287279, 7535.171131175457, 7554.2445755426215, 7561.130469996021, 7613.847723045619, 7356.521826081287, 7404.593507110319, 7420.443466740124, 7473.663685134074, 7498.962505187439, 6977.302269773185, 7081.752138675121, 7088.787336379917, 7089.929100708927, 7100.751473698396, 4897.975510122426, 4967.3045773591, 4975.378077740283, 5014.974925125497, 5135.229715542699, 4481.215787237155, 4565.429321334833, 4599.0, 5020.196768517019, 5203.0], "value": 4545.828556718598, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.39528270825224, 280.56512405771053, 301.6666583425315, 315.3252040633044, 316.2741508238592, 4942.937268487274, 5168.896622067557, 5191.221316802476, 5208.8697782555455, 5252.579793616511, 4427.667924905629, 4763.2378819388905, 4984.725840078794, 5164.302819119418, 5192.871667024715, 3451.0509609857304, 3937.8424863005444, 4011.65900898424, 4041.2321658884866, 4048.7165898387125, 3369.2143164123054, 4030.415589739486, 4034.4150098235887, 4036.555978842325, 4070.229450189177, 3506.246876561719, 3774.301754175486, 4031.5766844481345, 4037.132016616427, 4068.5321188063454, 4015.2371049500794, 4015.4177644241568, 4019.678425725935, 4022.939655905164, 4045.089854782665, 3512.207794803138, 3520.331137083946, 3525.7179425646145, 3529.735269854771, 3529.7645823961407, 4944.632944127379, 5042.924356134661, 5104.846854594368, 5125.487451254882, 5155.020546096267, 4571.3143028545655, 4581.473130589991, 4589.334546490773, 4623.352730617717, 4629.699069560502, 5495.900819836049, 5514.3658479275, 5522.834314970557, 5530.723463826793, 5584.497395234408, 3713.925721485582, 3869.9613003870163, 3948.3614300065437, 3954.209158168349, 3969.6625786808154, 4905.583025442842, 5736.827895163152, 5751.654900705971, 5756.251687280626, 5763.6541807491685, 3363.915902102418, 3927.7250592458254, 3930.4300876372768, 3980.0646847990697, 3980.980095099506, 4008.178323443687, 4013.8996525086523, 4021.698372622065, 4037.4145748866836, 4071.959280407158, 4406.33904914259, 4470.776461176928, 4569.634429245719, 4581.908361832745, 4751.40607424072, 4612.53874612536, 4909.337239472697, 5372.623916325898, 5518.902816929541, 5528.75120619573, 1194.6416075177412, 1238.9876101239051, 1364.4405793624567, 1366.4875671623142, 1367.4871923028863, 773.5938632218131, 845.9534725590152, 874.3492956356574, 876.4697358098387, 930.3348106104168, 4801.591864691471, 5076.263941728429, 5076.263941728429, 5132.2814805926455, 5170.224466330005, 4591.885202869955, 5021.096202683479, 5151.356080489939, 5423.688167906524, 5670.0, 4596.850787303174, 4762.52374762521, 4855.44162421319, 4879.487653796371, 5096.770645320969, 2674.7592716655204, 2848.1170837040586, 2933.5478937925864, 2940.2943293609806, 2953.3354995125924, 5343.465653434703, 5407.080796264644, 5692.918345514339, 5757.971210144006, 5866.472017518444, 5369.946300537023, 5471.0, 5630.324361076615, 5726.484616384462, 5744.420284421733, 4514.322851572225, 5512.255845460892, 5728.398518155617, 5765.88468230633, 5836.358000619844, 5168.320295903771, 5282.445343239058, 5369.865753356197, 5601.887962240651, 5621.245589661118, 4459.866204013885, 4966.758310422394, 5362.51737343641, 5552.556335352918, 5564.10974244119, 5339.546138578109, 5397.8110766123, 5822.359540450631, 5822.7670893163795, 5890.823275301748, 3293.793158583707, 3680.327140190332, 3892.995107519573, 3910.2570511602703, 3943.726706833379, 5507.834764957058, 5536.169574563848, 5564.666120032811, 5568.9443105568425, 5595.944040559705, 4471.28459446487, 4571.451425828789, 5387.865303367447, 5403.86490337729, 5404.72976351173, 4807.735574543294, 5226.660267082628, 5346.599005074557, 5350.796070884176, 5464.207689885024, 4607.976960115178, 4680.0, 4908.0, 5131.9985205769035, 5193.896122077462, 4371.781410929504, 5104.876927076038, 5172.103621971708, 5378.031954248117, 5497.697626630414, 4595.540445955377, 4948.109340318777, 4982.950170498249, 5416.431274716098, 5417.618507280618], "value": 4006.3480515558495, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.714304176235, 725.5211560370155, 746.1717493582123, 814.3892080939296, 817.975460736178, 15406.383744650215, 16574.66850662986, 16581.66836663266, 16596.33614655414, 16597.75103373449, 14328.426862925497, 14463.421463141462, 14464.0, 14470.42118315266, 14482.420703171887, 670.8457054877381, 695.1241435790889, 835.7743409279491, 838.9580520973957, 867.3408209760596, 685.3694600967123, 862.5083702289697, 865.0052439694371, 865.2385900407633, 867.3668222197799, 912.9726108216764, 913.4245425382001, 913.8720579118929, 914.1955079530004, 916.5463095767604, 701.1726163127558, 882.5013867165031, 913.5158366065963, 913.5432283858071, 914.7850255190025, 682.3551743602297, 682.5699809120223, 684.4216636941776, 686.7699320727562, 686.8660611180821, 14800.0, 14817.70364592713, 14890.702185956328, 14899.627509312246, 14925.70148597022, 11419.0, 13309.800352994713, 13729.931350343184, 14341.139531628136, 14486.34811433488, 12813.35933203336, 13149.539766108235, 13151.473941042346, 13212.735745285137, 13433.86566134336, 4156.0593808340145, 4659.603933665642, 4689.20283551797, 4861.453554688851, 4880.853574392774, 111270.0, 111842.44078779635, 112032.0, 112474.43762781215, 112734.0, 9991.850122248026, 10363.067323940732, 10588.417637030037, 10680.092192163598, 10710.143188544898, 17095.48713538596, 20740.274090406765, 20950.895245523676, 20961.0, 20996.475088122614, 7644.197359277307, 7716.4212684048925, 7796.986391769034, 7810.609469526501, 7941.801454963672, 4292.163028209469, 4348.260795664744, 4364.214441400515, 4364.454443194601, 4407.911841763147, 3424.6232914379398, 3519.894403167909, 3544.3442963051916, 3557.181848174934, 3559.8252576649643, 540.0090833320821, 657.9769708060195, 663.5990548439249, 706.4666177036407, 718.389369036315, 573.4724053870449, 668.6021817018866, 682.8712307044239, 682.9146356705412, 699.923008469068, 8699.695510657224, 9390.812183756287, 9514.33399662017, 9586.089321514444, 9610.471424071604, 570.3412558494945, 627.9309275979639, 636.5830381100386, 645.2805122288614, 666.6033709942576, 4488.39406680094, 4632.02727427244, 4646.279826626878, 4675.812967481331, 4716.29255611661, 104312.0, 105334.47332763288, 122450.0, 162934.0, 171122.0, 133682.0, 136293.0, 139090.0, 140193.0, 141064.29467852594, 70980.25687559674, 75344.0, 77616.61191694005, 98581.50709246407, 115143.0, 7479.925200747923, 7980.441369104339, 8668.049688819965, 9297.6280948764, 9776.755581110529, 54620.0, 57319.713401432724, 57618.42381576131, 59504.70247648734, 60054.49863753441, 80235.37175538973, 90785.92140785868, 91014.54492727494, 91651.0, 92345.0, 10626.84059739089, 12951.0, 13054.804177937149, 13122.278274694983, 13226.669333266746, 12546.309952952675, 14776.220032124134, 14914.925425372803, 14953.476628317956, 15169.08985460876, 13452.26012569279, 13721.176729396268, 13776.517821876187, 13923.164610123426, 14048.578542643738, 13289.867101328862, 13599.980001500126, 13714.38285277145, 13740.0, 14189.361478733275, 10868.456577170951, 13585.320733963064, 13599.86400135986, 13603.0, 13604.455821767018, 11065.446727663746, 11170.27393219438, 11383.715407114889, 11781.05473631592, 11908.285502869856, 9363.297752668714, 10135.7466063349, 11048.613298534512, 11480.311181329147, 11555.653330400102], "value": 7750.877638511362, "unit": "score"}], "name": "dromaeo_css", "value": 7460.251674079602, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 129.0, 114.0, 113.0, 114.0, 113.0, 112.0, 114.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 109.0, 131.0, 161.0, 108.0, 130.0, 130.0, 103.0, 148.0, 125.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 184.0, 180.0, 183.0, 184.0, 181.0, 181.0, 184.0, 185.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 81.0, 76.0, 147.0, 58.0, 58.0, 63.0, 70.0, 139.0, 57.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 133.0, 71.0, 70.0, 89.0, 121.0, 91.0, 103.0, 149.0], "value": 99.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 84.0, 87.0, 84.0, 88.0, 85.0, 88.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 95.0, 98.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 84.0, 83.0, 86.0, 86.0, 84.0, 84.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 53.0, 53.0, 53.0, 54.0, 55.0, 54.0, 53.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 71.0, 71.0, 78.0, 71.0, 70.0, 69.0, 69.0, 91.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 105.0, 87.0, 87.0, 84.0, 98.0, 89.0, 89.0, 84.0, 111.0], "value": 93.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 139.0, 156.0, 121.0, 137.0, 140.0, 143.0, 154.0, 154.0], "value": 142.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 170.0, 170.0, 167.0, 170.0, 167.0, 171.0, 169.0, 170.0], "value": 169.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 65.0, 68.0, 67.0, 67.0, 66.0, 68.0, 66.0, 68.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1470.8, "alertThreshold": 2.0}]} 10:13:14 INFO - Return code: 0 10:13:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:13:14 INFO - # TBPL SUCCESS # 10:13:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:13:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:13:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:13:14 INFO - 2016-08-25 10:13:14.555 screenresolution[1660:9787] starting screenresolution argv=screenresolution get 10:13:14 INFO - 2016-08-25 10:13:14.557 screenresolution[1660:9787] Display 0: 1600x1200x32@60 10:13:14 INFO - 2016-08-25 10:13:14.563 screenresolution[1661:9790] starting screenresolution argv=screenresolution list 10:13:14 INFO - Available Modes on Display 0 10:13:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:13:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:13:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:13:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:13:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:13:14 INFO - Intel Iris: 10:13:14 INFO - Chipset Model: Intel Iris 10:13:14 INFO - Type: GPU 10:13:14 INFO - Bus: Built-In 10:13:14 INFO - VRAM (Dynamic, Max): 1536 MB 10:13:14 INFO - Vendor: Intel (0x8086) 10:13:14 INFO - Device ID: 0x0a2e 10:13:14 INFO - Revision ID: 0x0009 10:13:14 INFO - Displays: 10:13:14 INFO - Display: 10:13:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:13:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:13:14 INFO - Display Serial Number: 1600x1200 60 10:13:14 INFO - Main Display: Yes 10:13:14 INFO - Mirror: Off 10:13:14 INFO - Online: Yes 10:13:14 INFO - Rotation: Supported 10:13:14 INFO - Return code: 0 10:13:14 INFO - Running post-action listener: _resource_record_post_action 10:13:14 INFO - [mozharness: 2016-08-25 17:13:14.802025Z] Finished run-tests step (success) 10:13:14 INFO - Running post-run listener: _resource_record_post_run 10:13:14 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37315584; Write bytes: 701420544; Read time: 245; Write time: 1987 10:13:14 INFO - TinderboxPrint: CPU usage
53.4% 10:13:14 INFO - TinderboxPrint: I/O read bytes / time
37,315,584 / 245 10:13:14 INFO - TinderboxPrint: I/O write bytes / time
701,420,544 / 1,987 10:13:14 INFO - TinderboxPrint: CPU idle
2,255.8 (46.6%) 10:13:14 INFO - TinderboxPrint: CPU system
963.4 (19.9%) 10:13:14 INFO - TinderboxPrint: CPU user
1,626.4 (33.6%) 10:13:14 INFO - TinderboxPrint: Swap in / out
442,675,200 / 0 10:13:14 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 393169408; Write bytes: 386958848; Read time: 29192; Write time: 829 10:13:14 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 35415040; Write bytes: 292371968; Read time: 221; Write time: 1106 10:13:15 INFO - Running post-run listener: _upload_blobber_files 10:13:15 INFO - Blob upload gear active. 10:13:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:13:15 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 10:13:15 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:13:15 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:13:15 INFO - (blobuploader) - INFO - Open directory for files ... 10:13:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:13:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:13:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:16 INFO - (blobuploader) - INFO - Done attempting. 10:13:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:13:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:13:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:17 INFO - (blobuploader) - INFO - Done attempting. 10:13:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:13:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:13:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:19 INFO - (blobuploader) - INFO - Done attempting. 10:13:19 INFO - (blobuploader) - INFO - Iteration through files over. 10:13:19 INFO - Return code: 0 10:13:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:13:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:13:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/08f9f2a80c2c2912de461268a88c9f2c8d06f7b6e5ca62e60ff59a23c5ddac5fb33c7f94a19fe1886351bbcdfb08e61a1d88b3b392988a6e5cfe6638b623cba1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53e2a6392208c426610685e1a021e58a1e30488e07c2b7dd0987b69dcb80c20e5928977afef3ad6532894d13fb461415ec5997f03123edd75011d0e27c993beb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6edb0f637b06880cfef3fb452d8dfa7affa0f81cc32140c9cea1cd7f8142cb937f691029312af58f96692645deaec11436472d4e648bdcb86bd570bb02cad5c1"} 10:13:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:13:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:13:19 INFO - Contents: 10:13:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/08f9f2a80c2c2912de461268a88c9f2c8d06f7b6e5ca62e60ff59a23c5ddac5fb33c7f94a19fe1886351bbcdfb08e61a1d88b3b392988a6e5cfe6638b623cba1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53e2a6392208c426610685e1a021e58a1e30488e07c2b7dd0987b69dcb80c20e5928977afef3ad6532894d13fb461415ec5997f03123edd75011d0e27c993beb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6edb0f637b06880cfef3fb452d8dfa7affa0f81cc32140c9cea1cd7f8142cb937f691029312af58f96692645deaec11436472d4e648bdcb86bd570bb02cad5c1"} 10:13:19 INFO - Running post-run listener: copy_logs_to_upload_dir 10:13:19 INFO - Copying logs to upload dir... 10:13:19 INFO - mkdir: /builds/slave/test/build/upload/logs 10:13:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.055103 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-25 10:13:19.825354) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 10:13:19.828922) ========= 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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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/Fx-Team/sha512/08f9f2a80c2c2912de461268a88c9f2c8d06f7b6e5ca62e60ff59a23c5ddac5fb33c7f94a19fe1886351bbcdfb08e61a1d88b3b392988a6e5cfe6638b623cba1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53e2a6392208c426610685e1a021e58a1e30488e07c2b7dd0987b69dcb80c20e5928977afef3ad6532894d13fb461415ec5997f03123edd75011d0e27c993beb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6edb0f637b06880cfef3fb452d8dfa7affa0f81cc32140c9cea1cd7f8142cb937f691029312af58f96692645deaec11436472d4e648bdcb86bd570bb02cad5c1"} build_url:https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011116 build_url: 'https://queue.taskcluster.net/v1/task/I8HfZc7qQVutN-AhoGabbQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/08f9f2a80c2c2912de461268a88c9f2c8d06f7b6e5ca62e60ff59a23c5ddac5fb33c7f94a19fe1886351bbcdfb08e61a1d88b3b392988a6e5cfe6638b623cba1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53e2a6392208c426610685e1a021e58a1e30488e07c2b7dd0987b69dcb80c20e5928977afef3ad6532894d13fb461415ec5997f03123edd75011d0e27c993beb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6edb0f637b06880cfef3fb452d8dfa7affa0f81cc32140c9cea1cd7f8142cb937f691029312af58f96692645deaec11436472d4e648bdcb86bd570bb02cad5c1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 10:13:19.862799) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 10:13:19.863190) ========= 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.RihCv0tQKq/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.4Jv4WNlQtc/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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.004751 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 10:13:19.911509) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 10:13:19.911829) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 10:13:19.912195) ========= ========= Total master_lag: 0.18 =========