builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0175 starttime: 1467296705.37 results: success (0) buildid: 20160630051527 builduid: 470f8419c3fa49e697b84b392c57da64 revision: 7940be96e124a4f6b812d2b3ad6762f2921be04b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 07:25:05.375151) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 07:25:05.376025) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 07:25:05.376678) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 07:25:05.400974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 07:25:05.401601) ========= 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.ADvqFmxrU7/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.FccnxTROU1/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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-06-30 07:25:05-- 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% 8.21M=0.001s 2016-06-30 07:25:05 (8.21 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.145908 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 07:25:05.571525) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 07:25:05.571886) ========= 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.ADvqFmxrU7/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.FccnxTROU1/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.020864 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 07:25:05.621160) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-30 07:25:05.621553) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7940be96e124a4f6b812d2b3ad6762f2921be04b --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 7940be96e124a4f6b812d2b3ad6762f2921be04b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ADvqFmxrU7/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.FccnxTROU1/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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-06-30 07:25:05,698 truncating revision to first 12 chars 2016-06-30 07:25:05,698 Setting DEBUG logging. 2016-06-30 07:25:05,698 attempt 1/10 2016-06-30 07:25:05,698 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7940be96e124?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-30 07:25:07,759 unpacking tar archive at: fx-team-7940be96e124/testing/mozharness/ program finished with exit code 0 elapsedTime=2.337790 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-30 07:25:07.981638) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 07:25:07.982004) ========= script_repo_revision: 7940be96e124a4f6b812d2b3ad6762f2921be04b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 07:25:07.982465) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 07:25:07.982780) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 07:25:08.003535) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-06-30 07:25:08.004034) ========= /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.ADvqFmxrU7/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.FccnxTROU1/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:25:08 INFO - MultiFileLogger online at 20160630 07:25:08 in /builds/slave/test 07:25:08 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 07:25:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:25:08 INFO - {'append_to_log': False, 07:25:08 INFO - 'base_work_dir': '/builds/slave/test', 07:25:08 INFO - 'blob_upload_branch': 'Fx-Team', 07:25:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:25:08 INFO - 'branch': 'Fx-Team', 07:25:08 INFO - 'buildbot_json_path': 'buildprops.json', 07:25:08 INFO - 'config_files': ('talos/mac_config.py',), 07:25:08 INFO - 'default_actions': ('clobber', 07:25:08 INFO - 'read-buildbot-config', 07:25:08 INFO - 'download-and-extract', 07:25:08 INFO - 'populate-webroot', 07:25:08 INFO - 'create-virtualenv', 07:25:08 INFO - 'install', 07:25:08 INFO - 'run-tests'), 07:25:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:25:08 INFO - 'download_minidump_stackwalk': True, 07:25:08 INFO - 'download_symbols': 'ondemand', 07:25:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:25:08 INFO - 'tooltool.py': '/tools/tooltool.py', 07:25:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:25:08 INFO - '/tools/misc-python/virtualenv.py')}, 07:25:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:25:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:25:08 INFO - 'installer_path': 'installer.exe', 07:25:08 INFO - 'log_level': 'info', 07:25:08 INFO - 'log_name': 'talos', 07:25:08 INFO - 'log_to_console': True, 07:25:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:25:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:25:08 INFO - 'opt_config_files': (), 07:25:08 INFO - 'pip_index': False, 07:25:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:25:08 INFO - 'cmd': ('bash', 07:25:08 INFO - '-c', 07:25:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:25:08 INFO - 'enabled': True, 07:25:08 INFO - 'halt_on_failure': False, 07:25:08 INFO - 'name': 'check_screen_resolution'},), 07:25:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:25:08 INFO - 'cmd': ('bash', 07:25:08 INFO - '-c', 07:25:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:25:08 INFO - 'enabled': True, 07:25:08 INFO - 'halt_on_failure': False, 07:25:08 INFO - 'name': 'check_screen_resolution'},), 07:25:08 INFO - 'run_cmd_checks_enabled': True, 07:25:08 INFO - 'sps_profile': False, 07:25:08 INFO - 'sps_profile_interval': 0, 07:25:08 INFO - 'suite': 'dromaeojs', 07:25:08 INFO - 'system_bits': '32', 07:25:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:25:08 INFO - 'title': 't-yosemite-r7-0175', 07:25:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:25:08 INFO - 'use_talos_json': True, 07:25:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:25:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:25:08 INFO - 'work_dir': 'build'} 07:25:08 INFO - ##### 07:25:08 INFO - ##### Running clobber step. 07:25:08 INFO - ##### 07:25:08 INFO - Running pre-action listener: _resource_record_pre_action 07:25:08 INFO - Running main action method: clobber 07:25:08 INFO - rmtree: /builds/slave/test/build 07:25:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:25:10 INFO - Running post-action listener: _resource_record_post_action 07:25:10 INFO - ##### 07:25:10 INFO - ##### Finished clobber step (success) 07:25:10 INFO - ##### 07:25:10 INFO - ##### 07:25:10 INFO - ##### Running read-buildbot-config step. 07:25:10 INFO - ##### 07:25:10 INFO - Running pre-action listener: _resource_record_pre_action 07:25:10 INFO - Running main action method: read_buildbot_config 07:25:10 INFO - Using buildbot properties: 07:25:10 INFO - { 07:25:10 INFO - "project": "", 07:25:10 INFO - "product": "firefox", 07:25:10 INFO - "script_repo_revision": "production", 07:25:10 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:25:10 INFO - "repository": "", 07:25:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:25:10 INFO - "buildid": "20160630051527", 07:25:10 INFO - "pgo_build": "False", 07:25:10 INFO - "basedir": "/builds/slave/test", 07:25:10 INFO - "buildnumber": 75, 07:25:10 INFO - "slavename": "t-yosemite-r7-0175", 07:25:10 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:25:10 INFO - "platform": "yosemite_r7", 07:25:10 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:25:10 INFO - "branch": "fx-team", 07:25:10 INFO - "repo_path": "integration/fx-team", 07:25:10 INFO - "revision": "7940be96e124a4f6b812d2b3ad6762f2921be04b", 07:25:10 INFO - "stage_platform": "macosx64", 07:25:10 INFO - "builduid": "470f8419c3fa49e697b84b392c57da64", 07:25:10 INFO - "slavebuilddir": "test" 07:25:10 INFO - } 07:25:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 07:25:10 INFO - Running post-action listener: _resource_record_post_action 07:25:10 INFO - ##### 07:25:10 INFO - ##### Finished read-buildbot-config step (success) 07:25:10 INFO - ##### 07:25:10 INFO - ##### 07:25:10 INFO - ##### Running download-and-extract step. 07:25:10 INFO - ##### 07:25:10 INFO - Running pre-action listener: _resource_record_pre_action 07:25:10 INFO - Running main action method: download_and_extract 07:25:10 INFO - mkdir: /builds/slave/test/build/tests 07:25: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:10 INFO - https://queue.taskcluster.net/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:25:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 07:25:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 07:25:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 07:25:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 07:25:12 INFO - Downloaded 1199 bytes. 07:25:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 07:25:12 INFO - Using the following test package requirements: 07:25:12 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 07:25:12 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:25:12 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 07:25:12 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:25:12 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 07:25:12 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:25:12 INFO - u'jsshell-mac.zip'], 07:25:12 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:25:12 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 07:25:12 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 07:25:12 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:25:12 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 07:25:12 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:25:12 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 07:25:12 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:25:12 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 07:25:12 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:25:12 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 07:25:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:12 INFO - https://queue.taskcluster.net/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:25:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:25:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:25:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:25:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:25:14 INFO - Downloaded 18788654 bytes. 07:25:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:25:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:25:17 INFO - Return code: 0 07:25:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:25:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:17 INFO - https://queue.taskcluster.net/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:25:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:25:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:25:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:25:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:25:18 INFO - Downloaded 18788654 bytes. 07:25:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:25:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:25:22 INFO - Return code: 0 07:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:22 INFO - https://queue.taskcluster.net/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:25:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 07:25:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 07:25:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 07:25:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:25:23 INFO - Downloaded 14405242 bytes. 07:25:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:25:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:25:24 INFO - Return code: 0 07:25:24 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:24 INFO - https://queue.taskcluster.net/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:25:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:25:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:25:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 07:25:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 07:25:28 INFO - Downloaded 112412235 bytes. 07:25:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:25:28 INFO - mkdir: /builds/slave/test/properties 07:25:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:25:28 INFO - Writing to file /builds/slave/test/properties/build_url 07:25:28 INFO - Contents: 07:25:28 INFO - build_url:https://queue.taskcluster.net/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:25:29 INFO - Running post-action listener: _resource_record_post_action 07:25:29 INFO - Running post-action listener: set_extra_try_arguments 07:25:29 INFO - ##### 07:25:29 INFO - ##### Finished download-and-extract step (success) 07:25:29 INFO - ##### 07:25:29 INFO - ##### 07:25:29 INFO - ##### Running populate-webroot step. 07:25:29 INFO - ##### 07:25:29 INFO - Running pre-action listener: _resource_record_pre_action 07:25:29 INFO - Running main action method: populate_webroot 07:25:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:25:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:25:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:25:29 INFO - '--tppagecycles', 07:25:29 INFO - '7'], 07:25:29 INFO - 'tests': ['tsvgm']}}, 07:25:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:25:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:25:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:25:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:25:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:25:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:25:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:29 INFO - 'talos_options': ['--disable-e10s'], 07:25:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:25:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:25:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:29 INFO - 'talos_options': ['--disable-e10s'], 07:25:29 INFO - 'tests': ['damp', 'tps']}, 07:25:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:29 INFO - 'tests': ['damp', 'tps']}, 07:25:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:25:29 INFO - 'tests': ['dromaeo_dom']}, 07:25:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:25:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:25:29 INFO - 'tests': ['basic_compositor_video']}, 07:25:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:25:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:25:29 INFO - 'tests': ['a11yr', 07:25:29 INFO - 'ts_paint', 07:25:29 INFO - 'tpaint', 07:25:29 INFO - 'sessionrestore', 07:25:29 INFO - 'sessionrestore_no_auto_restore', 07:25:29 INFO - 'tabpaint']}, 07:25:29 INFO - 'other-e10s': {'tests': ['a11yr', 07:25:29 INFO - 'ts_paint', 07:25:29 INFO - 'tpaint', 07:25:29 INFO - 'sessionrestore', 07:25:29 INFO - 'sessionrestore_no_auto_restore', 07:25:29 INFO - 'tabpaint']}, 07:25:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:25:29 INFO - 'ts_paint', 07:25:29 INFO - 'tpaint', 07:25:29 INFO - 'sessionrestore', 07:25:29 INFO - 'sessionrestore_no_auto_restore', 07:25:29 INFO - 'tabpaint']}, 07:25:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:25:29 INFO - 'ts_paint', 07:25:29 INFO - 'tpaint', 07:25:29 INFO - 'sessionrestore', 07:25:29 INFO - 'sessionrestore_no_auto_restore', 07:25:29 INFO - 'tabpaint']}, 07:25:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:25:29 INFO - 'tests': ['a11yr', 07:25:29 INFO - 'ts_paint', 07:25:29 INFO - 'tpaint', 07:25:29 INFO - 'sessionrestore', 07:25:29 INFO - 'sessionrestore_no_auto_restore', 07:25:29 INFO - 'tabpaint']}, 07:25:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:25:29 INFO - 'tests': ['a11yr', 07:25:29 INFO - 'ts_paint', 07:25:29 INFO - 'tpaint', 07:25:29 INFO - 'sessionrestore', 07:25:29 INFO - 'sessionrestore_no_auto_restore', 07:25:29 INFO - 'tabpaint']}, 07:25:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:25:29 INFO - 'tests': ['tsvgx', 07:25:29 INFO - 'tsvgr_opacity', 07:25:29 INFO - 'tart', 07:25:29 INFO - 'tscrollx', 07:25:29 INFO - 'cart']}, 07:25:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:25:29 INFO - 'tsvgr_opacity', 07:25:29 INFO - 'tart', 07:25:29 INFO - 'tscrollx', 07:25:29 INFO - 'cart']}, 07:25:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:29 INFO - 'talos_options': ['--disable-e10s'], 07:25:29 INFO - 'tests': ['tp5o']}, 07:25:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:29 INFO - 'tests': ['tp5o']}, 07:25:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:29 INFO - 'talos_options': ['--disable-e10s', 07:25:29 INFO - '--xperf_path', 07:25:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:25:29 INFO - 'tests': ['tp5n']}, 07:25:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:29 INFO - 'talos_options': ['--xperf_path', 07:25:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:25:29 INFO - 'tests': ['tp5n']}}, 07:25:29 INFO - 'talos.zip': {'path': '', 07:25:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:25:29 INFO - Running post-action listener: _resource_record_post_action 07:25:29 INFO - ##### 07:25:29 INFO - ##### Finished populate-webroot step (success) 07:25:29 INFO - ##### 07:25:29 INFO - ##### 07:25:29 INFO - ##### Running create-virtualenv step. 07:25:29 INFO - ##### 07:25:29 INFO - Running pre-action listener: _resource_record_pre_action 07:25:29 INFO - Running main action method: create_virtualenv 07:25:29 INFO - Creating virtualenv /builds/slave/test/build/venv 07:25:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:25:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:25:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:25:29 INFO - Using real prefix '/tools/python27' 07:25:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:25:30 INFO - Installing distribute.............................................................................................................................................................................................done. 07:25:33 INFO - Installing pip.................done. 07:25:33 INFO - Return code: 0 07:25:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:25:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:33 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 0x10bf6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c001618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84f959e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bed0d30>, '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 0x10bc35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec8b20>, '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.ADvqFmxrU7/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.FccnxTROU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:25:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:25:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:25:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADvqFmxrU7/Render', 07:25:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:33 INFO - 'HOME': '/Users/cltbld', 07:25:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:33 INFO - 'LOGNAME': 'cltbld', 07:25:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:33 INFO - 'MOZ_NO_REMOTE': '1', 07:25:33 INFO - 'NO_EM_RESTART': '1', 07:25:33 INFO - 'PAGER': '/bin/cat', 07:25:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:33 INFO - 'PWD': '/builds/slave/test', 07:25:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:33 INFO - 'SHELL': '/bin/bash', 07:25:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FccnxTROU1/Listeners', 07:25:33 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 07:25:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:33 INFO - 'USER': 'cltbld', 07:25:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:33 INFO - 'XPC_FLAGS': '0x0', 07:25:33 INFO - 'XPC_SERVICE_NAME': '0', 07:25:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:25:33 INFO - Cleaning up... 07:25:33 INFO - Return code: 0 07:25:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:25:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:33 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 0x10bf6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c001618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84f959e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bed0d30>, '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 0x10bc35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec8b20>, '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.ADvqFmxrU7/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.FccnxTROU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:25:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:25:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:25:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADvqFmxrU7/Render', 07:25:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:33 INFO - 'HOME': '/Users/cltbld', 07:25:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:33 INFO - 'LOGNAME': 'cltbld', 07:25:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:33 INFO - 'MOZ_NO_REMOTE': '1', 07:25:33 INFO - 'NO_EM_RESTART': '1', 07:25:33 INFO - 'PAGER': '/bin/cat', 07:25:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:33 INFO - 'PWD': '/builds/slave/test', 07:25:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:33 INFO - 'SHELL': '/bin/bash', 07:25:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FccnxTROU1/Listeners', 07:25:33 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 07:25:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:33 INFO - 'USER': 'cltbld', 07:25:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:33 INFO - 'XPC_FLAGS': '0x0', 07:25:33 INFO - 'XPC_SERVICE_NAME': '0', 07:25:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:34 INFO - Downloading/unpacking psutil>=3.1.1 07:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:25:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:25:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:25:36 INFO - Installing collected packages: psutil 07:25:36 INFO - Running setup.py install for psutil 07:25:37 INFO - building 'psutil._psutil_osx' extension 07:25:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:25:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:25:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:25:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:25:37 INFO - building 'psutil._psutil_posix' extension 07:25:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:25:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:25:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:25:37 INFO - ^ 07:25:37 INFO - 1 warning generated. 07:25:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:25:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:25:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:25:37 INFO - Successfully installed psutil 07:25:37 INFO - Cleaning up... 07:25:37 INFO - Return code: 0 07:25:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:25: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bf6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c001618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84f959e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bed0d30>, '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 0x10bc35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec8b20>, '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.ADvqFmxrU7/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.FccnxTROU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:25:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:25:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:25:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADvqFmxrU7/Render', 07:25:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:37 INFO - 'HOME': '/Users/cltbld', 07:25:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:37 INFO - 'LOGNAME': 'cltbld', 07:25:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:37 INFO - 'MOZ_NO_REMOTE': '1', 07:25:37 INFO - 'NO_EM_RESTART': '1', 07:25:37 INFO - 'PAGER': '/bin/cat', 07:25:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:37 INFO - 'PWD': '/builds/slave/test', 07:25:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:37 INFO - 'SHELL': '/bin/bash', 07:25:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FccnxTROU1/Listeners', 07:25:37 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 07:25:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:37 INFO - 'USER': 'cltbld', 07:25:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:37 INFO - 'XPC_FLAGS': '0x0', 07:25:37 INFO - 'XPC_SERVICE_NAME': '0', 07:25:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:25:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:25:40 INFO - Installing collected packages: mozsystemmonitor 07:25:40 INFO - Running setup.py install for mozsystemmonitor 07:25:40 INFO - Successfully installed mozsystemmonitor 07:25:40 INFO - Cleaning up... 07:25:40 INFO - Return code: 0 07:25:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:25: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bf6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c001618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84f959e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bed0d30>, '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 0x10bc35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec8b20>, '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.ADvqFmxrU7/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.FccnxTROU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:25:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:25:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADvqFmxrU7/Render', 07:25:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:40 INFO - 'HOME': '/Users/cltbld', 07:25:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:40 INFO - 'LOGNAME': 'cltbld', 07:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:40 INFO - 'MOZ_NO_REMOTE': '1', 07:25:40 INFO - 'NO_EM_RESTART': '1', 07:25:40 INFO - 'PAGER': '/bin/cat', 07:25:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:40 INFO - 'PWD': '/builds/slave/test', 07:25:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:40 INFO - 'SHELL': '/bin/bash', 07:25:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FccnxTROU1/Listeners', 07:25:40 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 07:25:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:40 INFO - 'USER': 'cltbld', 07:25:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:40 INFO - 'XPC_FLAGS': '0x0', 07:25:40 INFO - 'XPC_SERVICE_NAME': '0', 07:25:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:41 INFO - Downloading/unpacking jsonschema==2.5.1 07:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:25:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25:43 INFO - Downloading functools32-3.2.3-2.tar.gz 07:25:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:25:43 INFO - warning: no files found matching '*.txt' 07:25:43 INFO - no previously-included directories found matching 'build' 07:25:43 INFO - no previously-included directories found matching 'dist' 07:25:43 INFO - no previously-included directories found matching '.git*' 07:25:43 INFO - Installing collected packages: jsonschema, functools32 07:25:44 INFO - Running setup.py install for functools32 07:25:44 INFO - warning: no files found matching '*.txt' 07:25:44 INFO - no previously-included directories found matching 'build' 07:25:44 INFO - no previously-included directories found matching 'dist' 07:25:44 INFO - no previously-included directories found matching '.git*' 07:25:44 INFO - Successfully installed jsonschema functools32 07:25:44 INFO - Cleaning up... 07:25:44 INFO - Return code: 0 07:25:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:25:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:44 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 0x10bf6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c001618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84f959e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bed0d30>, '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 0x10bc35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec8b20>, '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.ADvqFmxrU7/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.FccnxTROU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:25:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:25:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:25:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADvqFmxrU7/Render', 07:25:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:44 INFO - 'HOME': '/Users/cltbld', 07:25:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:44 INFO - 'LOGNAME': 'cltbld', 07:25:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:44 INFO - 'MOZ_NO_REMOTE': '1', 07:25:44 INFO - 'NO_EM_RESTART': '1', 07:25:44 INFO - 'PAGER': '/bin/cat', 07:25:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:44 INFO - 'PWD': '/builds/slave/test', 07:25:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:44 INFO - 'SHELL': '/bin/bash', 07:25:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FccnxTROU1/Listeners', 07:25:44 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 07:25:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:44 INFO - 'USER': 'cltbld', 07:25:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:44 INFO - 'XPC_FLAGS': '0x0', 07:25:44 INFO - 'XPC_SERVICE_NAME': '0', 07:25:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:44 INFO - Downloading/unpacking blobuploader==1.2.4 07:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:46 INFO - Downloading blobuploader-1.2.4.tar.gz 07:25:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:25:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:25:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:48 INFO - Downloading docopt-0.6.1.tar.gz 07:25:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:25:48 INFO - Installing collected packages: blobuploader, requests, docopt 07:25:48 INFO - Running setup.py install for blobuploader 07:25:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:25:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:25:48 INFO - Running setup.py install for requests 07:25:48 INFO - Running setup.py install for docopt 07:25:49 INFO - Successfully installed blobuploader requests docopt 07:25:49 INFO - Cleaning up... 07:25:49 INFO - Return code: 0 07:25:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:49 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 0x10bf6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c001618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84f959e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bed0d30>, '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 0x10bc35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec8b20>, '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.ADvqFmxrU7/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.FccnxTROU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:25:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:25:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADvqFmxrU7/Render', 07:25:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:49 INFO - 'HOME': '/Users/cltbld', 07:25:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:49 INFO - 'LOGNAME': 'cltbld', 07:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:49 INFO - 'MOZ_NO_REMOTE': '1', 07:25:49 INFO - 'NO_EM_RESTART': '1', 07:25:49 INFO - 'PAGER': '/bin/cat', 07:25:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:49 INFO - 'PWD': '/builds/slave/test', 07:25:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:49 INFO - 'SHELL': '/bin/bash', 07:25:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FccnxTROU1/Listeners', 07:25:49 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 07:25:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:49 INFO - 'USER': 'cltbld', 07:25:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:49 INFO - 'XPC_FLAGS': '0x0', 07:25:49 INFO - 'XPC_SERVICE_NAME': '0', 07:25:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:25:49 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-isySgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:25:49 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-bQ2MZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:25:49 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-tLvKrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:25:49 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-wEWzAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:25:49 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-qrI59B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:25:49 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Lg9oNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:25:50 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-RP94eD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:25:50 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-HZhmxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:25:50 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-MpGq2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:25:50 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-wzch8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:25:50 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-EzWdk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:25:50 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-U6EyJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:25:50 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-quHVgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:25:50 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-XaAcic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:50 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-iM_aQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:25:50 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-fp4VkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:25:51 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-SDrISd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:25:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:25:51 INFO - Running setup.py install for manifestparser 07:25:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:25:51 INFO - Running setup.py install for mozcrash 07:25:51 INFO - Running setup.py install for mozdebug 07:25:51 INFO - Running setup.py install for mozdevice 07:25:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:25:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:25:51 INFO - Running setup.py install for mozfile 07:25:51 INFO - Running setup.py install for mozhttpd 07:25:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:25:52 INFO - Running setup.py install for mozinfo 07:25:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:25:52 INFO - Running setup.py install for mozInstall 07:25:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:25:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:25:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:25:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:25:52 INFO - Running setup.py install for mozleak 07:25:52 INFO - Running setup.py install for mozlog 07:25:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:25:52 INFO - Running setup.py install for moznetwork 07:25:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:25:52 INFO - Running setup.py install for mozprocess 07:25:52 INFO - Running setup.py install for mozprofile 07:25:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:25:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:25:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:25:53 INFO - Running setup.py install for mozrunner 07:25:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:25:53 INFO - Running setup.py install for mozscreenshot 07:25:53 INFO - Running setup.py install for moztest 07:25:53 INFO - Running setup.py install for mozversion 07:25:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:25:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:25:53 INFO - Cleaning up... 07:25:53 INFO - Return code: 0 07:25:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:53 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 0x10bf6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c001618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84f959e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bed0d30>, '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 0x10bc35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec8b20>, '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.ADvqFmxrU7/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.FccnxTROU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:25:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:25:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADvqFmxrU7/Render', 07:25:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:53 INFO - 'HOME': '/Users/cltbld', 07:25:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:53 INFO - 'LOGNAME': 'cltbld', 07:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:53 INFO - 'MOZ_NO_REMOTE': '1', 07:25:53 INFO - 'NO_EM_RESTART': '1', 07:25:53 INFO - 'PAGER': '/bin/cat', 07:25:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:53 INFO - 'PWD': '/builds/slave/test', 07:25:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:53 INFO - 'SHELL': '/bin/bash', 07:25:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FccnxTROU1/Listeners', 07:25:53 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 07:25:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:53 INFO - 'USER': 'cltbld', 07:25:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:53 INFO - 'XPC_FLAGS': '0x0', 07:25:53 INFO - 'XPC_SERVICE_NAME': '0', 07:25:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:25:53 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-U7sC6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:25:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-50ooDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:25:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-AgnsuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:25:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-8BOT2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:25:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-7QTGX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:25:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-qZazvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:25:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-spuB0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:25:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-dBR4aF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:25:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-k5NtVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:25:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-T5fcpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:25:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-7JHzcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:25:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-CMLisX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:25:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-zgT1w8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:25:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-ql6Phq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-o3FlP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:25:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-KMmJAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:25:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-vBAkXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:25:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:25:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:58 INFO - Downloading blessings-1.6.tar.gz 07:25:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:25:58 INFO - Installing collected packages: blessings 07:25:58 INFO - Running setup.py install for blessings 07:25:58 INFO - Successfully installed blessings 07:25:58 INFO - Cleaning up... 07:25:58 INFO - Return code: 0 07:25:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:25:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:25:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:25:58 INFO - Reading from file tmpfile_stdout 07:25:58 INFO - Current package versions: 07:25:58 INFO - blessings == 1.6 07:25:58 INFO - blobuploader == 1.2.4 07:25:58 INFO - docopt == 0.6.1 07:25:58 INFO - functools32 == 3.2.3-2 07:25:58 INFO - jsonschema == 2.5.1 07:25:58 INFO - manifestparser == 1.1 07:25:58 INFO - mozInstall == 1.12 07:25:58 INFO - mozcrash == 0.17 07:25:58 INFO - mozdebug == 0.1 07:25:58 INFO - mozdevice == 0.48 07:25:58 INFO - mozfile == 1.2 07:25:58 INFO - mozhttpd == 0.7 07:25:58 INFO - mozinfo == 0.9 07:25:58 INFO - mozleak == 0.1 07:25:58 INFO - mozlog == 3.2 07:25:58 INFO - moznetwork == 0.27 07:25:58 INFO - mozprocess == 0.22 07:25:58 INFO - mozprofile == 0.28 07:25:58 INFO - mozrunner == 6.11 07:25:58 INFO - mozscreenshot == 0.1 07:25:58 INFO - mozsystemmonitor == 0.3 07:25:58 INFO - moztest == 0.7 07:25:58 INFO - mozversion == 1.4 07:25:58 INFO - psutil == 3.1.1 07:25:58 INFO - requests == 1.2.3 07:25:58 INFO - wsgiref == 0.1.2 07:25:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:58 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 0x10bf6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c001618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84f959e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bed0d30>, '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 0x10bc35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec8b20>, '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.ADvqFmxrU7/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.FccnxTROU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:25:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:25:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:25:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADvqFmxrU7/Render', 07:25:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:58 INFO - 'HOME': '/Users/cltbld', 07:25:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:58 INFO - 'LOGNAME': 'cltbld', 07:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:58 INFO - 'MOZ_NO_REMOTE': '1', 07:25:58 INFO - 'NO_EM_RESTART': '1', 07:25:58 INFO - 'PAGER': '/bin/cat', 07:25:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:58 INFO - 'PWD': '/builds/slave/test', 07:25:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:58 INFO - 'SHELL': '/bin/bash', 07:25:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FccnxTROU1/Listeners', 07:25:58 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 07:25:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:58 INFO - 'USER': 'cltbld', 07:25:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:58 INFO - 'XPC_FLAGS': '0x0', 07:25:58 INFO - 'XPC_SERVICE_NAME': '0', 07:25:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:25:59 INFO - Cleaning up... 07:25:59 INFO - Return code: 0 07:25:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:25:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:59 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 0x10bf6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c001618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84f959e160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bed0d30>, '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 0x10bc35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec8b20>, '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.ADvqFmxrU7/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.FccnxTROU1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:25:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:25:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADvqFmxrU7/Render', 07:25:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:59 INFO - 'HOME': '/Users/cltbld', 07:25:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:59 INFO - 'LOGNAME': 'cltbld', 07:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:59 INFO - 'MOZ_NO_REMOTE': '1', 07:25:59 INFO - 'NO_EM_RESTART': '1', 07:25:59 INFO - 'PAGER': '/bin/cat', 07:25:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:59 INFO - 'PWD': '/builds/slave/test', 07:25:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:59 INFO - 'SHELL': '/bin/bash', 07:25:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FccnxTROU1/Listeners', 07:25:59 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 07:25:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:59 INFO - 'USER': 'cltbld', 07:25:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:59 INFO - 'XPC_FLAGS': '0x0', 07:25:59 INFO - 'XPC_SERVICE_NAME': '0', 07:25:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:25:59 INFO - Cleaning up... 07:25:59 INFO - Return code: 0 07:25:59 INFO - Running post-action listener: _resource_record_post_action 07:25:59 INFO - Running post-action listener: _start_resource_monitoring 07:25:59 INFO - Starting resource monitoring. 07:25:59 INFO - ##### 07:25:59 INFO - ##### Finished create-virtualenv step (success) 07:25:59 INFO - ##### 07:25:59 INFO - ##### 07:25:59 INFO - ##### Running install step. 07:25:59 INFO - ##### 07:25:59 INFO - Running pre-action listener: _resource_record_pre_action 07:25:59 INFO - Running main action method: install 07:25:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:25:59 INFO - Reading from file tmpfile_stdout 07:25:59 INFO - Detecting whether we're running mozinstall >=1.0... 07:25:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:25:59 INFO - Reading from file tmpfile_stdout 07:25:59 INFO - Output received: 07:25:59 INFO - Usage: mozinstall [options] installer 07:25:59 INFO - Options: 07:25:59 INFO - -h, --help show this help message and exit 07:25:59 INFO - -d DEST, --destination=DEST 07:25:59 INFO - Directory to install application into. [default: 07:25:59 INFO - "/builds/slave/test"] 07:25:59 INFO - --app=APP Application being installed. [default: firefox] 07:25:59 INFO - mkdir: /builds/slave/test/build/application 07:25:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:26:33 INFO - Reading from file tmpfile_stdout 07:26:33 INFO - Output received: 07:26:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:26:33 INFO - Running post-action listener: _resource_record_post_action 07:26:33 INFO - ##### 07:26:33 INFO - ##### Finished install step (success) 07:26:33 INFO - ##### 07:26:33 INFO - ##### 07:26:33 INFO - ##### Running run-tests step. 07:26:33 INFO - ##### 07:26:33 INFO - Running pre-action listener: _resource_record_pre_action 07:26:33 INFO - Running main action method: run_tests 07:26:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:26:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:26:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:26:34 INFO - 2016-06-30 07:26:34.012 screenresolution[2281:286763] starting screenresolution argv=screenresolution get 07:26:34 INFO - 2016-06-30 07:26:34.015 screenresolution[2281:286763] Display 0: 1600x1200x32@60 07:26:34 INFO - 2016-06-30 07:26:34.021 screenresolution[2282:286766] starting screenresolution argv=screenresolution list 07:26:34 INFO - Available Modes on Display 0 07:26:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:26:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:26:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:26:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:26:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:26:34 INFO - Intel Iris: 07:26:34 INFO - Chipset Model: Intel Iris 07:26:34 INFO - Type: GPU 07:26:34 INFO - Bus: Built-In 07:26:34 INFO - VRAM (Dynamic, Max): 1536 MB 07:26:34 INFO - Vendor: Intel (0x8086) 07:26:34 INFO - Device ID: 0x0a2e 07:26:34 INFO - Revision ID: 0x0009 07:26:34 INFO - Displays: 07:26:34 INFO - Display: 07:26:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:26:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:26:34 INFO - Display Serial Number: 1600x1200 60 07:26:34 INFO - Main Display: Yes 07:26:34 INFO - Mirror: Off 07:26:34 INFO - Online: Yes 07:26:34 INFO - Rotation: Supported 07:26:34 INFO - Return code: 0 07:26:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:26:34 INFO - Python 2.7.3 07:26:34 INFO - Return code: 0 07:26:34 INFO - grabbing minidump binary from tooltool 07:26:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:26:34 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 0x10bed0d30>, '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 0x10bc35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:26:34 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:26:34 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:26:34 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:26:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:26:34 INFO - Return code: 0 07:26:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:26:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:26:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:26:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:26:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:26:34 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/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:26:34 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/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0175 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:26:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ADvqFmxrU7/Render', 07:26:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:26:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:26:34 INFO - 'HOME': '/Users/cltbld', 07:26:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:26:34 INFO - 'LOGNAME': 'cltbld', 07:26:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:26:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:26:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:34 INFO - 'MOZ_NO_REMOTE': '1', 07:26:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:26:34 INFO - 'NO_EM_RESTART': '1', 07:26:34 INFO - 'PAGER': '/bin/cat', 07:26:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:26:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:34 INFO - 'PWD': '/builds/slave/test', 07:26:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:26:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:26:34 INFO - 'SHELL': '/bin/bash', 07:26:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FccnxTROU1/Listeners', 07:26:34 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 07:26:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:26:34 INFO - 'USER': 'cltbld', 07:26:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:26:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:26:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:34 INFO - 'XPC_FLAGS': '0x0', 07:26:34 INFO - 'XPC_SERVICE_NAME': '0', 07:26:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:26:34 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/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:26:34 INFO - mozversion application_buildid: 20160630051527 07:26:34 INFO - mozversion application_changeset: 7940be96e124a4f6b812d2b3ad6762f2921be04b 07:26:34 INFO - mozversion application_display_name: Nightly 07:26:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:26:34 INFO - mozversion application_name: Firefox 07:26:34 INFO - mozversion application_remotingname: firefox 07:26:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:26:34 INFO - mozversion application_vendor: Mozilla 07:26:34 INFO - mozversion application_version: 50.0a1 07:26:34 INFO - mozversion platform_buildid: 20160630051527 07:26:34 INFO - mozversion platform_changeset: 7940be96e124a4f6b812d2b3ad6762f2921be04b 07:26:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:26:34 INFO - mozversion platform_version: 50.0a1 07:26:34 INFO - using testdate: 1467296794 07:26:34 INFO - actual date: 1467296794 07:26:34 INFO - starting webserver on 'localhost:56188' 07:26:34 INFO - SUITE-START | Running 2 tests 07:26:34 INFO - TEST-START | dromaeo_css 07:26:34 INFO - operating with platform_type : mac_ 07:26:34 INFO - Initialising browser for dromaeo_css test... 07:26:34 INFO - TEST-INFO | started process 2292 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpXlCHCC/profile http://localhost:56188/getInfo.html) 07:26:36 INFO - PROCESS | 2292 | __metrics Screen width/height:1600/1200 07:26:36 INFO - PROCESS | 2292 | colorDepth:24 07:26:36 INFO - PROCESS | 2292 | Browser inner width/height: 1024/689 07:26:36 INFO - PROCESS | 2292 | __metrics 07:26:37 INFO - PROCESS | 2292 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:26:37 INFO - PROCESS | 2292 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:26:37 INFO - TEST-INFO | 2292: exit 0 07:26:37 INFO - Browser initialized. 07:26:37 INFO - Running cycle 1/1 for dromaeo_css test... 07:26:37 INFO - TEST-INFO | started process 2294 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpXlCHCC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:26:49 INFO - PROCESS | 2294 | RSS: Main: 243929088 07:26:49 INFO - PROCESS | 2294 | 07:27:40 INFO - PROCESS | 2294 | 1467296860782 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:29:51 INFO - PROCESS | 2294 | Cycle 1(1): loaded http://localhost:56188/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56188/tests/dromaeo/cssquery-ext.html) 07:29:52 INFO - PROCESS | 2294 | RSS: Main: 363487232 07:29:52 INFO - PROCESS | 2294 | 07:32:55 INFO - PROCESS | 2294 | Cycle 1(1): loaded http://localhost:56188/tests/dromaeo/cssquery-ext.html (next: http://localhost:56188/tests/dromaeo/cssquery-jquery.html) 07:32:56 INFO - PROCESS | 2294 | RSS: Main: 371748864 07:32:56 INFO - PROCESS | 2294 | 07:35:58 INFO - PROCESS | 2294 | Cycle 1(1): loaded http://localhost:56188/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56188/tests/dromaeo/cssquery-mootools.html) 07:35:59 INFO - PROCESS | 2294 | RSS: Main: 370499584 07:35:59 INFO - PROCESS | 2294 | 07:39:01 INFO - PROCESS | 2294 | Cycle 1(1): loaded http://localhost:56188/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56188/tests/dromaeo/cssquery-prototype.html) 07:39:02 INFO - PROCESS | 2294 | RSS: Main: 371298304 07:39:02 INFO - PROCESS | 2294 | 07:42:05 INFO - PROCESS | 2294 | Cycle 1(1): loaded http://localhost:56188/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56188/tests/dromaeo/cssquery-yui.html) 07:42:06 INFO - PROCESS | 2294 | RSS: Main: 334659584 07:42:06 INFO - PROCESS | 2294 | 07:45:09 INFO - PROCESS | 2294 | Cycle 1(1): loaded http://localhost:56188/tests/dromaeo/cssquery-yui.html (next: http://localhost:56188/tests/dromaeo/cssquery-dojo.html) 07:45:09 INFO - PROCESS | 2294 | RSS: Main: 380579840 07:45:09 INFO - PROCESS | 2294 | 07:45:09 INFO - PROCESS | 2294 | __start_tp_report 07:45:09 INFO - PROCESS | 2294 | _x_x_mozilla_page_load 07:45:09 INFO - PROCESS | 2294 | _x_x_mozilla_page_load_details 07:45:09 INFO - PROCESS | 2294 | |i|pagename|runs| 07:45:09 INFO - PROCESS | 2294 | |0;dojo.html;2721.2380533450632;2806.1862060002595;3314.508471187966;3325.8004519728834;3342.68244516771;4647.5352464753505;5583.302087239095;5650.915236271459;5735.168400581918;5766.163906233599;5638.097904335307;5647.604667673254;5704.515116215116;5799.159121927318;5831.708414579265;5065.721385323805;5145.022445735317;5175.8188463403785;5181.145111056671;5333.2533445317495;5287.41838397776;5312.946870531303;5337.813176538822;5353.705546194958;5372.355317361922;5149.922751158754;5200.089984252741;5215;5229.424763276036;5247.10799164143;5213.244079608436;5216.58267338612;5219.686818790847;5245.632805703603;5298.629095963284;5229.477052294778;5255.632105752599;5270.815521456769;5271.446498117719;5350.197470379435;5570.610057295991;5627;5728.942710572883;5733.88532229353;5748.7125643717645;6251.374862513758;6286.654234017173;6290.937090629081;6296.779612713533;6310.0850376695125;6237.6257424554215;6271.498280137624;6298.338674439164;6306.527010474233;6311.368863113697;5675.914861277084;5725.112607545879;5754.964106460793;5757;5871.060630299132;6274.498040156776;6278.277998030194;6285.779997700059;6289.0880822280515;6298.433141017331;6168.629882206992;6180.381961803873;6188.381161883775;6189.659568723673;6201.503879689614;6027.638341699512;6138.664296166039;6142.682951206299;6190.659513726789;6297.653629050446;6081.057436097412;6128.075379514901;6142.099766648211;6148.569600127948;6155;6215.254169499689;6220.315765265817;6221.502279817604;6224.253089629274;6230.501559875199;4856.8300109495995;4960.305557221992;4970.726610036482;4977.850664480072;5018.698878067327;4372.890677733082;4425.380446737459;4445.666575006822;4461.263891457938;4498.415206023196;5694.202811606296;5718.399568045378;5728.570357223225;5729.369769325371;5796.913046304308;6275.246970363585;6293.527985401021;6311.274203466568;6313.084602732578;6331.018692102732;5534.529564987021;5653.23681303027;5685.715714214272;5711.800086997018;5743.827685169452;4905.871649520559;5222.973885130626;5461.590380721462;5468.371137319259;5471.917921231185;5592.3009623797025;6312.873742525124;6320.8419789504715;6321.525885558602;6351.587146835441;5182;6149.016157414792;6172.660503672341;6196.690165491617;6262.34245404226;5313.388960269618;6271.404216599416;6274.6548939808745;6277.372262773686;6292.182016337847;6050.183225264533;6130.606853607433;6146.231721034871;6161.753529858755;6178.66017369049;6138.14066030765;6161.260648722003;6163.722632481638;6232.937670623236;6301.684915754286;6105.236845394325;6136.509079273558;6137.539684523589;6145.354737752472;6152.477039451696;5968.671723055274;6087.147799308189;6127.938720612915;6128.724207410588;6153.077038444269;5939.851503712442;5996.70016499182;6002.87994240104;6038.154658347923;6046;6150.138980542638;6155.415235552616;6160;6169.043798211284;6178.135061091541;5760.510356619567;5803.47768700819;5925.111233315038;5960.344362120077;6034.637205135791 07:45:09 INFO - PROCESS | 2294 | |1;ext.html;454.8476260452748;469.68765770762445;471.4413420097184;481.1099465987922;481.91566475866716;31060;32799.01602951912;41409.99999999996;42649;42744.9313767156;43952.34071488922;44249.77875110628;44343.556564434424;44418.7779061105;44532;9636.373635713691;9796.265280103986;9805.01949805021;9814.803703925918;9816.460094694788;8733.388662793606;8950.462972221654;11782.823257651142;12029.819552706716;12032.51869925202;11930.821037684442;12034.638960831146;12043.578474753385;12057.336846473441;12058.819117713241;8674.175953284428;11723.589674361441;11893.167478276524;12032.578859739868;12057;10647.361158330525;10952.671419857417;10973.670789876316;10975.957284058002;10981.890181098166;41025.794871025755;41376.586234137874;41438.585614143776;41464;41492;3386.8306584670668;3427.417339052367;3566.8216589170697;3653.8903832885057;3664.7617904836366;31422.842885785423;31709.682903171135;31892.521612175835;32050.878219262213;32331.353372932645;2742.890284388622;3321.983390083058;3339.282054358325;3345.8159801210923;3478.878239261639;375004;375608;375870;500967;501258;33849.830750846086;60852.78294434175;61947.83182588588;62129;62224.68887655533;33821.154471137925;35738.64261357353;35845;35879.64120358815;35904.28191436156;14840.961132720606;15060.849391505944;15063.020903641229;15067.397304107932;15095.54713358601;11543.249688770204;11805.645830625095;11811.704707382212;12626.36868156607;12718.364081795873;7450.552966822008;7533.585652789039;7684.846303073908;7707.807304817312;7739.148693643695;3226.1773247822125;3281.3765384576855;3282.2439994602773;3308.17295676081;3311.519829624739;929.972100836976;969.8933117357085;987.5259875259771;1006.3257617396362;1078.3853203673987;30627;30643.2339191522;30854.537181942287;31206.53190202149;31486.527702084488;967.2310513142069;1046.1892033673964;1082.3614067700096;1088.2545456362418;1121.556984990924;16208.51374458768;16216.918915405584;16325.737378972264;16379.918100409423;16640.583985400463;701030;778122;778331;798248;907505;60387.09419358713;60548.39451605516;61070.69464652737;61092.69453652703;61290;1112.3659514077222;1239.114033466076;1408.7112142010862;1431.0769553637845;1449.289847974506;27310.317242069108;31126.377472450877;31822.204444889063;32462.798362129608;33031.33937321288;57901.13148302863;58175;58593;58660.41339586549;58684;579448;582827;583709;584660;585355;27827.860860695568;28000.85999569989;28286;28446.004389846257;28464.573031404132;35316.823415882754;41323.380149298384;43606.34590481082;50540.24189637085;51588.74205628948;35237.64762352344;39218.62734804268;39976.801275930105;40323;40791.98020049522;40022.799886000386;40343.596564033985;41029.79485102555;41127.38308925309;41155;38710.806445968716;38907;39822;39954.00114997148;40085;34372.82813585916;35005.47491787574;35408;35647.82176089103;35783;23453.76546234516;25799.613005805302;25919.87040064863;26526.86736566382;26781.33046673771 07:45:09 INFO - PROCESS | 2294 | |2;jquery.html;377.09873402567865;382.57534137107814;382.94447305140756;393.66341777780013;400.43138742984956;18801.341953031646;20337.881416522087;21197.57604847906;21209.68185477217;21227.893860530694;11964.282143071421;11965.102617303715;14172.645683857909;20026.399208023784;20953.895230523827;5265.499777521149;5352.277442545265;5427.48438898304;5462.754176062067;5493.835184944438;5242.161254199329;5352.464753524636;5354;5378.220158077076;5380.838574842742;4320.503142138663;5378.811741589046;5388.272583201277;5388.892222155555;5398.433164517728;5542.944570554303;5895.64626122434;5903.557233207505;5935.228420305332;5947.137665038588;5661.033106269708;5713.285839270091;5739.368669446358;5744.023516002248;5745.540356771448;13360.799588006188;13366.398512066882;13370.799438008438;15499.5350139496;24962.50074998508;2148.7528934172615;2211.82305415568;2275.6017696902977;2534.2397280815867;2789.981656695292;9272.582733776997;10106.140978016878;10163.4918254087;10179.643712470102;10328.380297182119;1845.7046872500337;1860.3953715042664;1884.406411980222;1924.951876203092;2132.6267903117046;120067;125504.99999999908;126125;126198;126335;9473.431594104375;11527.48126334317;11657.591984280509;11690.824637630443;11817.645470635895;14478.420863165355;14589.343479543446;14638.268086595628;14648;14651.633709157357;8476.26236621491;8551.615177317035;8566.700165494303;8643.222110010129;8694;8455.154484551496;8479.788005299917;8485.872711909326;8582.87125693115;8584.184502472255;4458.77706114693;4551.928872363067;4558.840440584564;4630.166570017362;4685.390899183084;1530.4872867589258;1599.4401959314148;1652.4133932454047;1690.9830901690825;1744.1976690722156;382.20882772660326;788.467784245632;973.7614284500343;990.7770751580837;1033.188946676855;11418.885811141949;11909.821352679717;11992;12135.635930922086;12137.453814578188;418.31605325293367;439.90981848720935;443.7071532788344;452.59492753985;463.2056023918995;5975.760969561169;6069.605475644069;6079.300880398722;6230.688465576703;6233.0338797486465;468844;472203;473530;514401;515052;135885.73199814075;138152;142463.287683563;144303;146453;962.1484985787629;976.9755756106155;979.8971108033696;1005.3867141043958;1006.3509036671452;15056.548303550473;17891.910540447214;19033.619327613094;19122.617547649246;19190.904045479685;47705;50009.74956127056;50640.74679626578;50651;50750;78180;78187.21812781799;78639.60680196562;78779.60610196913;79241;8016.959915200387;8304.086550479322;8396.496210227406;8430.988281406271;8546.914530854612;9284.30367722437;13317.267550284827;13392.397342119822;13586.184828910296;13601.931990339985;11263.943680281545;11453.427328633368;11453.828192577284;11565.537378504765;11642.94178529102;11014.944925275322;11264.887351126385;11314.60398886035;11399.20205585601;11451.59919402817;11454.828177577176;11523.769524609294;11565.710857228636;11597.072234221074;11832.408379580815;8119.59402029908;8232.794180145544;8271.33829293643;8371.41400101987;8439.071702112886;8959;10891.400972946247;11069.446527673745;11231.26996745209;11541.826872597087 07:45:09 INFO - PROCESS | 2294 | |3;mootools.html;1492.8208614966206;1549.558375862879;1718.7335962925752;1853.7590113285287;1891.2435025989612;8430.66277348906;8716.128387161281;8887.511186884718;8904.33217508687;8915.286777057836;7804.29761321481;8029.638666260018;8531.360147988895;8720.60757265923;8742.994555626105;2338.8012018978407;2370.608849539824;2372.905083796646;2373.2642880707035;2375.1330764271;2331.5686597979343;2348.307249361434;2371.905123795046;2374.4776149247223;2376.370261880594;1703.9744403833952;2167.631502644554;2276.5105502316997;2343.8828058597;2348.248560460653;2310.8844557772045;2316.3745788637057;2339.485313231095;2341.1103780563276;2343.718753749544;2076.6573515369946;2323.5004474038165;2334.4397344637164;2341.3678306857055;2344.5896968030697;8813.427127236773;8882.600282987281;8884.11158884113;8887.15572020657;8898.86651700225;5126.615503837227;5324.4675532446445;5335.33308336458;5335.519803217729;5344.412114667383;3534.2578058607724;3557.7331700122595;3607.3687104756564;3607.549056367954;3613.259281847215;1077.49357801833;1077.9245452818307;1084.1489430796867;1100.3617901617042;1101.9779604407947;11505.539778408853;11508.942455287754;11516.712082197928;11516.942415287953;11520.02079823216;3975.284448799244;4483.596476317147;4550.635949124062;4691.601213896857;4722.574968252874;9686.806263874823;9772.951135244279;9777.755556111155;9779.755506112404;9783.853242201372;5283;5285.524302812765;5290.841274761687;5292.02097611943;5293.444188360244;4750.904981900412;4799.848036471221;4853.296272040534;4853.927191092136;4858.7570621468785;3549.881787237012;3911.5110611173604;3940.172563761585;4004.539477960072;4024.6579040781276;1177.3465726521692;1212.3695678247439;1216.8296438498617;1288.761579107868;1297.8118172864881;668.8595394967014;866.3588944181387;899.5996781432331;907.945523268606;956.6221342569646;5687.289088863892;5971.552133589911;6007.759689612367;6030.698465076729;6056.515478761688;868.7220089571402;895.4358753984948;978.4031740638205;987.1806400687412;998.1665309466676;3269.313444176702;3810.561785394715;3935.822887970048;3998.300297447993;4047.372657238133;10168.33905796121;10670.51982660782;10704.250702450754;10843.078338341329;10847.349159050482;9798.75503112428;10817.188710846718;10848.566057357617;10856.674299771019;10894.019538241439;7998.360131189491;8213.137620549876;8275.917240827515;8363.163683631588;8363.414560980795;5946.375630558902;5995.550333724901;6017.518598512196;6163.291221509584;6272.874542509033;9656.37233579815;10122.190224781853;10133.391996480235;10146;10148.391096534231;10135.898641013495;10236.590536378462;10275.640352587623;10283.94858025705;10292;7484.064491938508;7518.36093932;7596.126445458843;7642.3504002158215;7660.961695191489;8265.173482651686;8322.250997410261;8381.706640267561;8389.748307550539;8390.412671112963;7419.406447484081;7506.136794268729;7526;7637.579933103732;7661.731839385595;7242.710291588278;7366.594837284001;7422.109346878409;7444.813879652836;7458.366038886756;7076.646167691491;7091.255418181223;7136.964315178391;7144.607046612487;7151.2848715128075;4925.6454475019655;5023.397192336943;5103.87240318995;5136.512031357164;5143.948560514347;5026.974865125651;5060.544550990428;5143.9712057587885;5145.511176438233;5249.08141075318 07:45:09 INFO - PROCESS | 2294 | |4;prototype.html;257.6354458441306;297.8540515147577;312.51404066676315;314.59260257965934;315.103530455853;4447.621952134068;5113.38639363276;5245.527902488775;5346.224797404374;5475.917861232079;4672.439796641994;5011.649184557083;5096.898062038757;5259.368875734907;5280.867978300535;3454.3436747018113;4092.5498195198506;4160.06398560323;4177.979110104445;4220.978895105528;3959.168574599323;4147.709660323779;4163.958360416403;4169.457970463835;4184.916301673965;3374.2453923959647;3862.8261728222296;3990.8204130814183;4031.737937034098;4047.97976010121;3618.7285953553587;3964.9096498462864;4028.4762980812307;4110.527289361717;4141.233871733739;3063.908082757521;3551.5971191379263;3601.603823579404;3613.168971136627;3615.891523254307;4323.373110898933;4341.848035318749;4957.876537781211;5313.362396512404;5366.70483123428;3860.208657225262;4078.6125318094732;4648.325992731069;4675.054715529229;4706.082313948781;4667.369905062841;5586.720663966786;5659.6321239119325;5663.083511991328;5677;3232.288896442779;3723.1995121048835;3815.389537673959;3841.9242612068665;3894.824732887027;5218.373795144569;5408.755986123175;5488.56091512678;5925.259342582177;5935.557376721491;3920.157166209279;3923.3330333843314;3923.9019024524614;3946.605339466088;3955.92088158241;3604.585472670624;4011.117554138085;4017.393042782852;4038.333674943601;4048.4736984192455;3949.486566746362;4577.06170235101;4625.653076019312;4645.303204519281;4648.535146485325;4956.603471722254;5653.406392328829;5681.659100453986;5695.664777441312;5705.942940570541;1202.8332517458052;1309.6529419703786;1340.5442149668963;1358.3140514040347;1364.5565191312862;761.394691220481;817.9059408168018;848;910.4264313482464;913.3150137396952;5241.868953276198;5278.656887302313;5310.336207974004;5319.255304257407;5324.254604355393;4793.496682848251;5777.220075289866;5783.161441590946;5796.565257605612;5817.738201780845;4481.5070342262325;4505.459344878635;5177.508136727005;5295.523402893758;5395.163749618815;3060.6786287439945;3077.492213784745;3079;3079.337942342407;3084.640250208592;5279.973600131974;5862.368728704361;5952.503694538932;6008.918394688911;6077.328734598021;4894.681845680019;5901.498372638373;5968.612040217372;6020.367861374581;6057.545684073712;4857.878553036202;5752.9712351437975;6003.699815009232;6008.047384600022;6036.366181550874;5567.103027880419;5768.740406681625;5790.363060063474;5809.186713860147;5880.911786323294;5685.772569097356;5802.883942321046;5815.621984570989;5821.27234095738;5823.068309070529;5663.5469162467725;6036.517078633611;6039.22195789924;6089.746793686364;6127.571070025056;3921.588233235583;3926.391409331558;3951.3677811550015;3953.466282051886;3987.4816273884207;5906.645601263766;5962;5970.582059255984;6010.398960103955;6014.548908831767;5000.349954505891;5426.127985844877;5716.628419152742;5719.199312096393;5727.541796656341;4731.834385796481;5689.8008569701515;5701.914471282851;5718.25662663851;5802.564807639359;4968.428630707371;4987.376577927759;5590.413006634245;5591.930985535378;5624.550035997192;4979.626528010486;5660.632058916157;5696.829095127153;5716.79991200306;5717.914231286617;4631.652626052992;4650.5814476695905;5296.404761666832;5554.527865131509;5575.721213939206 07:45:09 INFO - PROCESS | 2294 | |5;yui.html;720.2797202797203;760.7261385901074;785.1873311122989;809.2231457800511;840.9411341206114;12013.63959081228;15345.156016419092;16134.193290335481;16146.677066458678;16438.67122657549;14515.129092254472;14597.635059123528;14673.77989330161;14674;14690.338934747935;661.6956200147938;680.880845851974;700.310194458458;837.2966707965307;851.9872201916976;837.7779888329597;841.9980223534005;842.027458285678;843.1063073142489;844.2992316377423;889.3107841422917;890.6882591093098;891.1756625121769;891.8439273127209;893.0310612984919;706.6996526476253;866.5450638414819;883.9513826739527;895.5656506594297;898.3172788680585;659.1431139518654;659.4361820643329;659.650385295794;659.7064306383661;659.7459977908492;15026.924865375604;15077;15087.24563771807;15089.170095644735;15108.622284442867;11920.761584768343;13819.170849748947;13902;14019.64950876226;14240;12768.553100641526;12800.423980920881;12902.161359511601;13106.934465327708;13249.157744254155;4020.4170395292517;4544.750038747868;4674.813007479696;4748.335233067373;4809.591184749301;103234;103736.48131759369;103982.48008760059;105192;107695;11040.558377664804;11059.059979901638;11175.608853690082;11218.439078046065;11277.097832173406;21650.02574884134;21832.017559209875;21846.453838653844;21877.12491500322;22104.2263520776;7905.169957154531;7908.644111015018;7931.84136317282;8000.119986801447;8137.186281371815;4372;4401.427814384109;4407.977960110179;4438.467383913951;4524.073255415404;3420.350133474632;3526.806025668613;3528.0826984984087;3528.8412021459094;3531.4526248431534;609.1745684597396;619.5848781316529;622.5237693164732;624.44736408279;659.2188256915571;607.1348328631682;636.2460484325995;642.9132067170872;649.6297110646885;650.612885333226;7837.216278372117;8571.828563428697;9022.954885225663;9026;9299.76750581241;601.255008394792;603.5897180927793;624.2676572511317;635.9872802543923;638.553012890969;4308.827646894152;4423.889402764892;4426.118066203219;4465.910681786346;4544.374950518519;144599;144836;147990.26004870122;153691;154392;125639.3718031404;129551;130065;131211;133562;96373.51813241029;107414.92585074206;109185.45407272913;111656;113094;8536.231739143506;8557.058723540531;8571.61427735766;8660.610272537624;8779.689591907521;51277.7436112817;61797.76404471795;63358.68320658367;63437;63690.36309636844;60588.69705651444;88795;95342;99725;101288;13272;13441.327933603476;13509.59471215865;14088.01383903117;14115.576532704035;13085.41115649817;14033.596640335885;14753.631159221106;15172.544823655307;15845.920770396075;13818.585442436743;14063.789043164565;14496.782548261996;14520.564383068524;14663.780043299146;13772.586822395344;13922.582322530341;14210.360533775798;14242.71514569682;14596;13486.86513134856;13655.726885462434;13809.86190138126;13819.864189239448;13832.723345533233;11204.943975280072;11504.309741415542;11594.478248479007;11694.649160525198;12004.39978001114;8913.598888049924;11412.731313837823;11424.82862757076;11550.364729939934;11663.650090497298 07:45:09 INFO - PROCESS | 2294 | __end_tp_report 07:45:09 INFO - PROCESS | 2294 | __start_cc_report 07:45:09 INFO - PROCESS | 2294 | _x_x_mozilla_cycle_collect,1287 07:45:09 INFO - PROCESS | 2294 | __end_cc_report 07:45:09 INFO - PROCESS | 2294 | __startTimestamp1467297909399__endTimestamp 07:45:09 INFO - PROCESS | 2294 | 07:45:09 INFO - PROCESS | 2294 | ------- Summary: start ------- 07:45:09 INFO - PROCESS | 2294 | Number of tests: 6 07:45:09 INFO - PROCESS | 2294 | 07:45:09 INFO - PROCESS | 2294 | [#0] dojo.html Cycles:165 Average:5721.96 Median:5960.34 stddev:671.48 (11.3%) stddev-sans-first:630.93 07:45:09 INFO - PROCESS | 2294 | Values: 2721.2 2806.2 3314.5 3325.8 3342.7 4647.5 5583.3 5650.9 5735.2 5766.2 5638.1 5647.6 5704.5 5799.2 5831.7 5065.7 5145.0 5175.8 5181.1 5333.3 5287.4 5312.9 5337.8 5353.7 5372.4 5149.9 5200.1 5215.0 5229.4 5247.1 5213.2 5216.6 5219.7 5245.6 5298.6 5229.5 5255.6 5270.8 5271.4 5350.2 5570.6 5627.0 5728.9 5733.9 5748.7 6251.4 6286.7 6290.9 6296.8 6310.1 6237.6 6271.5 6298.3 6306.5 6311.4 5675.9 5725.1 5755.0 5757.0 5871.1 6274.5 6278.3 6285.8 6289.1 6298.4 6168.6 6180.4 6188.4 6189.7 6201.5 6027.6 6138.7 6142.7 6190.7 6297.7 6081.1 6128.1 6142.1 6148.6 6155.0 6215.3 6220.3 6221.5 6224.3 6230.5 4856.8 4960.3 4970.7 4977.9 5018.7 4372.9 4425.4 4445.7 4461.3 4498.4 5694.2 5718.4 5728.6 5729.4 5796.9 6275.2 6293.5 6311.3 6313.1 6331.0 5534.5 5653.2 5685.7 5711.8 5743.8 4905.9 5223.0 5461.6 5468.4 5471.9 5592.3 6312.9 6320.8 6321.5 6351.6 5182.0 6149.0 6172.7 6196.7 6262.3 5313.4 6271.4 6274.7 6277.4 6292.2 6050.2 6130.6 6146.2 6161.8 6178.7 6138.1 6161.3 6163.7 6232.9 6301.7 6105.2 6136.5 6137.5 6145.4 6152.5 5968.7 6087.1 6127.9 6128.7 6153.1 5939.9 5996.7 6002.9 6038.2 6046.0 6150.1 6155.4 6160.0 6169.0 6178.1 5760.5 5803.5 5925.1 5960.3 6034.6 07:45:09 INFO - PROCESS | 2294 | 07:45:09 INFO - PROCESS | 2294 | [#1] ext.html Cycles:180 Average:72304.71 Median:19652.73 stddev:167150.59 (850.5%) stddev-sans-first:167532.44 07:45:09 INFO - PROCESS | 2294 | Values: 454.8 469.7 471.4 481.1 481.9 31060.0 32799.0 41410.0 42649.0 42744.9 43952.3 44249.8 44343.6 44418.8 44532.0 9636.4 9796.3 9805.0 9814.8 9816.5 8733.4 8950.5 11782.8 12029.8 12032.5 11930.8 12034.6 12043.6 12057.3 12058.8 8674.2 11723.6 11893.2 12032.6 12057.0 10647.4 10952.7 10973.7 10976.0 10981.9 41025.8 41376.6 41438.6 41464.0 41492.0 3386.8 3427.4 3566.8 3653.9 3664.8 31422.8 31709.7 31892.5 32050.9 32331.4 2742.9 3322.0 3339.3 3345.8 3478.9 375004.0 375608.0 375870.0 500967.0 501258.0 33849.8 60852.8 61947.8 62129.0 62224.7 33821.2 35738.6 35845.0 35879.6 35904.3 14841.0 15060.8 15063.0 15067.4 15095.5 11543.2 11805.6 11811.7 12626.4 12718.4 7450.6 7533.6 7684.8 7707.8 7739.1 3226.2 3281.4 3282.2 3308.2 3311.5 930.0 969.9 987.5 1006.3 1078.4 30627.0 30643.2 30854.5 31206.5 31486.5 967.2 1046.2 1082.4 1088.3 1121.6 16208.5 16216.9 16325.7 16379.9 16640.6 701030.0 778122.0 778331.0 798248.0 907505.0 60387.1 60548.4 61070.7 61092.7 61290.0 1112.4 1239.1 1408.7 1431.1 1449.3 27310.3 31126.4 31822.2 32462.8 33031.3 57901.1 58175.0 58593.0 58660.4 58684.0 579448.0 582827.0 583709.0 584660.0 585355.0 27827.9 28000.9 28286.0 28446.0 28464.6 35316.8 41323.4 43606.3 50540.2 51588.7 35237.6 39218.6 39976.8 40323.0 40792.0 40022.8 40343.6 41029.8 41127.4 41155.0 38710.8 38907.0 39822.0 39954.0 40085.0 34372.8 35005.5 35408.0 35647.8 35783.0 23453.8 25799.6 25919.9 26526.9 26781.3 07:45:09 INFO - PROCESS | 2294 | 07:45:09 INFO - PROCESS | 2294 | [#2] jquery.html Cycles:180 Average:31672.88 Median:382.39 stddev:83669.33 (21880.5%) stddev-sans-first:83871.05 07:45:09 INFO - PROCESS | 2294 | Values: 377.1 382.6 382.9 393.7 400.4 18801.3 20337.9 21197.6 21209.7 21227.9 11964.3 11965.1 14172.6 20026.4 20953.9 5265.5 5352.3 5427.5 5462.8 5493.8 5242.2 5352.5 5354.0 5378.2 5380.8 4320.5 5378.8 5388.3 5388.9 5398.4 5542.9 5895.6 5903.6 5935.2 5947.1 5661.0 5713.3 5739.4 5744.0 5745.5 13360.8 13366.4 13370.8 15499.5 24962.5 2148.8 2211.8 2275.6 2534.2 2790.0 9272.6 10106.1 10163.5 10179.6 10328.4 1845.7 1860.4 1884.4 1925.0 2132.6 120067.0 125505.0 126125.0 126198.0 126335.0 9473.4 11527.5 11657.6 11690.8 11817.6 14478.4 14589.3 14638.3 14648.0 14651.6 8476.3 8551.6 8566.7 8643.2 8694.0 8455.2 8479.8 8485.9 8582.9 8584.2 4458.8 4551.9 4558.8 4630.2 4685.4 1530.5 1599.4 1652.4 1691.0 1744.2 382.2 788.5 973.8 990.8 1033.2 11418.9 11909.8 11992.0 12135.6 12137.5 418.3 439.9 443.7 452.6 463.2 5975.8 6069.6 6079.3 6230.7 6233.0 468844.0 472203.0 473530.0 514401.0 515052.0 135885.7 138152.0 142463.3 144303.0 146453.0 962.1 977.0 979.9 1005.4 1006.4 15056.5 17891.9 19033.6 19122.6 19190.9 47705.0 50009.7 50640.7 50651.0 50750.0 78180.0 78187.2 78639.6 78779.6 79241.0 8017.0 8304.1 8396.5 8431.0 8546.9 9284.3 13317.3 13392.4 13586.2 13601.9 11263.9 11453.4 11453.8 11565.5 11642.9 11014.9 11264.9 11314.6 11399.2 11451.6 11454.8 11523.8 11565.7 11597.1 11832.4 8119.6 8232.8 8271.3 8371.4 8439.1 8959.0 10891.4 11069.4 11231.3 11541.8 07:45:09 INFO - PROCESS | 2294 | 07:45:09 INFO - PROCESS | 2294 | [#3] mootools.html Cycles:180 Average:5717.42 Median:5043.76 stddev:3202.87 (63.5%) stddev-sans-first:3196.12 07:45:09 INFO - PROCESS | 2294 | Values: 1492.8 1549.6 1718.7 1853.8 1891.2 8430.7 8716.1 8887.5 8904.3 8915.3 7804.3 8029.6 8531.4 8720.6 8743.0 2338.8 2370.6 2372.9 2373.3 2375.1 2331.6 2348.3 2371.9 2374.5 2376.4 1704.0 2167.6 2276.5 2343.9 2348.2 2310.9 2316.4 2339.5 2341.1 2343.7 2076.7 2323.5 2334.4 2341.4 2344.6 8813.4 8882.6 8884.1 8887.2 8898.9 5126.6 5324.5 5335.3 5335.5 5344.4 3534.3 3557.7 3607.4 3607.5 3613.3 1077.5 1077.9 1084.1 1100.4 1102.0 11505.5 11508.9 11516.7 11516.9 11520.0 3975.3 4483.6 4550.6 4691.6 4722.6 9686.8 9773.0 9777.8 9779.8 9783.9 5283.0 5285.5 5290.8 5292.0 5293.4 4750.9 4799.8 4853.3 4853.9 4858.8 3549.9 3911.5 3940.2 4004.5 4024.7 1177.3 1212.4 1216.8 1288.8 1297.8 668.9 866.4 899.6 907.9 956.6 5687.3 5971.6 6007.8 6030.7 6056.5 868.7 895.4 978.4 987.2 998.2 3269.3 3810.6 3935.8 3998.3 4047.4 10168.3 10670.5 10704.3 10843.1 10847.3 9798.8 10817.2 10848.6 10856.7 10894.0 7998.4 8213.1 8275.9 8363.2 8363.4 5946.4 5995.6 6017.5 6163.3 6272.9 9656.4 10122.2 10133.4 10146.0 10148.4 10135.9 10236.6 10275.6 10283.9 10292.0 7484.1 7518.4 7596.1 7642.4 7661.0 8265.2 8322.3 8381.7 8389.7 8390.4 7419.4 7506.1 7526.0 7637.6 7661.7 7242.7 7366.6 7422.1 7444.8 7458.4 7076.6 7091.3 7137.0 7144.6 7151.3 4925.6 5023.4 5103.9 5136.5 5143.9 5027.0 5060.5 5144.0 5145.5 5249.1 07:45:09 INFO - PROCESS | 2294 | 07:45:09 INFO - PROCESS | 2294 | [#4] prototype.html Cycles:180 Average:4579.59 Median:5105.14 stddev:1420.27 (27.8%) stddev-sans-first:1386.71 07:45:09 INFO - PROCESS | 2294 | Values: 257.6 297.9 312.5 314.6 315.1 4447.6 5113.4 5245.5 5346.2 5475.9 4672.4 5011.6 5096.9 5259.4 5280.9 3454.3 4092.5 4160.1 4178.0 4221.0 3959.2 4147.7 4164.0 4169.5 4184.9 3374.2 3862.8 3990.8 4031.7 4048.0 3618.7 3964.9 4028.5 4110.5 4141.2 3063.9 3551.6 3601.6 3613.2 3615.9 4323.4 4341.8 4957.9 5313.4 5366.7 3860.2 4078.6 4648.3 4675.1 4706.1 4667.4 5586.7 5659.6 5663.1 5677.0 3232.3 3723.2 3815.4 3841.9 3894.8 5218.4 5408.8 5488.6 5925.3 5935.6 3920.2 3923.3 3923.9 3946.6 3955.9 3604.6 4011.1 4017.4 4038.3 4048.5 3949.5 4577.1 4625.7 4645.3 4648.5 4956.6 5653.4 5681.7 5695.7 5705.9 1202.8 1309.7 1340.5 1358.3 1364.6 761.4 817.9 848.0 910.4 913.3 5241.9 5278.7 5310.3 5319.3 5324.3 4793.5 5777.2 5783.2 5796.6 5817.7 4481.5 4505.5 5177.5 5295.5 5395.2 3060.7 3077.5 3079.0 3079.3 3084.6 5280.0 5862.4 5952.5 6008.9 6077.3 4894.7 5901.5 5968.6 6020.4 6057.5 4857.9 5753.0 6003.7 6008.0 6036.4 5567.1 5768.7 5790.4 5809.2 5880.9 5685.8 5802.9 5815.6 5821.3 5823.1 5663.5 6036.5 6039.2 6089.7 6127.6 3921.6 3926.4 3951.4 3953.5 3987.5 5906.6 5962.0 5970.6 6010.4 6014.5 5000.3 5426.1 5716.6 5719.2 5727.5 4731.8 5689.8 5701.9 5718.3 5802.6 4968.4 4987.4 5590.4 5591.9 5624.6 4979.6 5660.6 5696.8 5716.8 5717.9 4631.7 4650.6 5296.4 5554.5 5575.7 07:45:09 INFO - PROCESS | 2294 | 07:45:09 INFO - PROCESS | 2294 | [#5] yui.html Cycles:180 Average:24659.48 Median:3527.44 stddev:39159.80 (1110.1%) stddev-sans-first:39228.40 07:45:09 INFO - PROCESS | 2294 | Values: 720.3 760.7 785.2 809.2 840.9 12013.6 15345.2 16134.2 16146.7 16438.7 14515.1 14597.6 14673.8 14674.0 14690.3 661.7 680.9 700.3 837.3 852.0 837.8 842.0 842.0 843.1 844.3 889.3 890.7 891.2 891.8 893.0 706.7 866.5 884.0 895.6 898.3 659.1 659.4 659.7 659.7 659.7 15026.9 15077.0 15087.2 15089.2 15108.6 11920.8 13819.2 13902.0 14019.6 14240.0 12768.6 12800.4 12902.2 13106.9 13249.2 4020.4 4544.8 4674.8 4748.3 4809.6 103234.0 103736.5 103982.5 105192.0 107695.0 11040.6 11059.1 11175.6 11218.4 11277.1 21650.0 21832.0 21846.5 21877.1 22104.2 7905.2 7908.6 7931.8 8000.1 8137.2 4372.0 4401.4 4408.0 4438.5 4524.1 3420.4 3526.8 3528.1 3528.8 3531.5 609.2 619.6 622.5 624.4 659.2 607.1 636.2 642.9 649.6 650.6 7837.2 8571.8 9023.0 9026.0 9299.8 601.3 603.6 624.3 636.0 638.6 4308.8 4423.9 4426.1 4465.9 4544.4 144599.0 144836.0 147990.3 153691.0 154392.0 125639.4 129551.0 130065.0 131211.0 133562.0 96373.5 107414.9 109185.5 111656.0 113094.0 8536.2 8557.1 8571.6 8660.6 8779.7 51277.7 61797.8 63358.7 63437.0 63690.4 60588.7 88795.0 95342.0 99725.0 101288.0 13272.0 13441.3 13509.6 14088.0 14115.6 13085.4 14033.6 14753.6 15172.5 15845.9 13818.6 14063.8 14496.8 14520.6 14663.8 13772.6 13922.6 14210.4 14242.7 14596.0 13486.9 13655.7 13809.9 13819.9 13832.7 11204.9 11504.3 11594.5 11694.6 12004.4 8913.6 11412.7 11424.8 11550.4 11663.7 07:45:09 INFO - PROCESS | 2294 | -------- Summary: end -------- 07:45:09 INFO - PROCESS | 2294 | 07:45:09 INFO - TEST-INFO | 2294: exit 0 07:45:09 INFO - TEST-OK | dromaeo_css | took 1115395ms 07:45:09 INFO - TEST-START | kraken 07:45:09 INFO - operating with platform_type : mac_ 07:45:09 INFO - Initialising browser for kraken test... 07:45:10 INFO - TEST-INFO | started process 2297 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmp8DpEB0/profile http://localhost:56188/getInfo.html) 07:45:12 INFO - PROCESS | 2297 | __metrics Screen width/height:1600/1200 07:45:12 INFO - PROCESS | 2297 | colorDepth:24 07:45:12 INFO - PROCESS | 2297 | Browser inner width/height: 1024/689 07:45:12 INFO - PROCESS | 2297 | __metrics 07:45:12 INFO - PROCESS | 2297 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:45:12 INFO - PROCESS | 2297 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:45:13 INFO - TEST-INFO | 2297: exit 0 07:45:13 INFO - Browser initialized. 07:45:13 INFO - Running cycle 1/1 for kraken test... 07:45:13 INFO - TEST-INFO | started process 2298 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmp8DpEB0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:45:25 INFO - PROCESS | 2298 | RSS: Main: 243757056 07:45:25 INFO - PROCESS | 2298 | 07:45:28 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56188/tests/kraken/driver.html?testName=audio-beat-detection) 07:45:28 INFO - PROCESS | 2298 | RSS: Main: 294473728 07:45:28 INFO - PROCESS | 2298 | 07:45:31 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56188/tests/kraken/driver.html?testName=audio-dft) 07:45:32 INFO - PROCESS | 2298 | RSS: Main: 304803840 07:45:32 INFO - PROCESS | 2298 | 07:45:35 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56188/tests/kraken/driver.html?testName=audio-fft) 07:45:36 INFO - PROCESS | 2298 | RSS: Main: 389386240 07:45:36 INFO - PROCESS | 2298 | 07:45:38 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56188/tests/kraken/driver.html?testName=audio-oscillator) 07:45:39 INFO - PROCESS | 2298 | RSS: Main: 291057664 07:45:39 INFO - PROCESS | 2298 | 07:45:41 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56188/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:45:41 INFO - PROCESS | 2298 | RSS: Main: 295231488 07:45:41 INFO - PROCESS | 2298 | 07:45:44 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56188/tests/kraken/driver.html?testName=imaging-darkroom) 07:45:45 INFO - PROCESS | 2298 | RSS: Main: 332263424 07:45:45 INFO - PROCESS | 2298 | 07:45:47 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56188/tests/kraken/driver.html?testName=imaging-desaturate) 07:45:48 INFO - PROCESS | 2298 | RSS: Main: 333934592 07:45:48 INFO - PROCESS | 2298 | 07:45:51 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56188/tests/kraken/driver.html?testName=json-parse-financial) 07:45:51 INFO - PROCESS | 2298 | RSS: Main: 333500416 07:45:51 INFO - PROCESS | 2298 | 07:45:53 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56188/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:45:54 INFO - PROCESS | 2298 | RSS: Main: 311771136 07:45:54 INFO - PROCESS | 2298 | 07:45:55 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56188/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:45:56 INFO - PROCESS | 2298 | RSS: Main: 318500864 07:45:56 INFO - PROCESS | 2298 | 07:45:58 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56188/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:45:59 INFO - PROCESS | 2298 | RSS: Main: 321703936 07:45:59 INFO - PROCESS | 2298 | 07:46:02 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56188/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:46:02 INFO - PROCESS | 2298 | RSS: Main: 325267456 07:46:02 INFO - PROCESS | 2298 | 07:46:05 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56188/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:46:06 INFO - PROCESS | 2298 | RSS: Main: 325419008 07:46:06 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | Cycle 1(1): loaded http://localhost:56188/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56188/tests/kraken/driver.html?testName=ai-astar) 07:46:08 INFO - PROCESS | 2298 | RSS: Main: 368746496 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | __start_tp_report 07:46:08 INFO - PROCESS | 2298 | _x_x_mozilla_page_load 07:46:08 INFO - PROCESS | 2298 | _x_x_mozilla_page_load_details 07:46:08 INFO - PROCESS | 2298 | |i|pagename|runs| 07:46:08 INFO - PROCESS | 2298 | |0;ai-astar;101;112;112;113;113;111;112;113;112;113 07:46:08 INFO - PROCESS | 2298 | |1;audio-beat-detection;150;116;91;185;134;134;128;106;159;122 07:46:08 INFO - PROCESS | 2298 | |2;audio-dft;184;185;186;183;183;184;182;184;181;180 07:46:08 INFO - PROCESS | 2298 | |3;audio-fft;79;73;58;89;57;62;62;64;98;60 07:46:08 INFO - PROCESS | 2298 | |4;audio-oscillator;84;93;92;71;72;69;96;69;68;102 07:46:08 INFO - PROCESS | 2298 | |5;imaging-gaussian-blur;86;84;86;83;86;83;87;84;86;83 07:46:08 INFO - PROCESS | 2298 | |6;imaging-darkroom;92;92;93;93;92;91;93;92;92;91 07:46:08 INFO - PROCESS | 2298 | |7;imaging-desaturate;88;85;85;84;86;87;86;85;85;83 07:46:08 INFO - PROCESS | 2298 | |8;json-parse-financial;56;53;54;53;54;55;53;54;53;54 07:46:08 INFO - PROCESS | 2298 | |9;json-stringify-tinderbox;65;69;76;78;73;77;71;71;70;74 07:46:08 INFO - PROCESS | 2298 | |10;stanford-crypto-aes;83;85;84;85;84;80;84;84;87;127 07:46:08 INFO - PROCESS | 2298 | |11;stanford-crypto-ccm;142;141;150;133;132;134;137;136;152;139 07:46:08 INFO - PROCESS | 2298 | |12;stanford-crypto-pbkdf2;160;165;162;161;161;226;210;161;161;215 07:46:08 INFO - PROCESS | 2298 | |13;stanford-crypto-sha256-iterative;64;67;66;62;78;81;84;75;66;65 07:46:08 INFO - PROCESS | 2298 | __end_tp_report 07:46:08 INFO - PROCESS | 2298 | __start_cc_report 07:46:08 INFO - PROCESS | 2298 | _x_x_mozilla_cycle_collect,2041 07:46:08 INFO - PROCESS | 2298 | __end_cc_report 07:46:08 INFO - PROCESS | 2298 | __startTimestamp1467297968565__endTimestamp 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | ------- Summary: start ------- 07:46:08 INFO - PROCESS | 2298 | Number of tests: 14 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.50 stddev:3.65 (3.2%) stddev-sans-first:0.71 07:46:08 INFO - PROCESS | 2298 | Values: 101.0 112.0 112.0 113.0 113.0 111.0 112.0 113.0 112.0 113.0 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:142.00 stddev:27.07 (19.1%) stddev-sans-first:27.96 07:46:08 INFO - PROCESS | 2298 | Values: 150.0 116.0 91.0 185.0 134.0 134.0 128.0 106.0 159.0 122.0 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:1.81 (1.0%) stddev-sans-first:1.90 07:46:08 INFO - PROCESS | 2298 | Values: 184.0 185.0 186.0 183.0 183.0 184.0 182.0 184.0 181.0 180.0 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#3] audio-fft Cycles:10 Average:70.20 Median:68.50 stddev:14.19 (20.7%) stddev-sans-first:14.69 07:46:08 INFO - PROCESS | 2298 | Values: 79.0 73.0 58.0 89.0 57.0 62.0 62.0 64.0 98.0 60.0 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#4] audio-oscillator Cycles:10 Average:81.60 Median:78.00 stddev:13.23 (17.0%) stddev-sans-first:14.00 07:46:08 INFO - PROCESS | 2298 | Values: 84.0 93.0 92.0 71.0 72.0 69.0 96.0 69.0 68.0 102.0 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#5] imaging-gaussian-blur Cycles:10 Average:84.80 Median:86.00 stddev:1.55 (1.8%) stddev-sans-first:1.58 07:46:08 INFO - PROCESS | 2298 | Values: 86.0 84.0 86.0 83.0 86.0 83.0 87.0 84.0 86.0 83.0 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#6] imaging-darkroom Cycles:10 Average:92.10 Median:92.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 07:46:08 INFO - PROCESS | 2298 | Values: 92.0 92.0 93.0 93.0 92.0 91.0 93.0 92.0 92.0 91.0 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:85.50 stddev:1.43 (1.7%) stddev-sans-first:1.17 07:46:08 INFO - PROCESS | 2298 | Values: 88.0 85.0 85.0 84.0 86.0 87.0 86.0 85.0 85.0 83.0 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:0.99 (1.8%) stddev-sans-first:0.71 07:46:08 INFO - PROCESS | 2298 | Values: 56.0 53.0 54.0 53.0 54.0 55.0 53.0 54.0 53.0 54.0 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:73.50 stddev:4.01 (5.4%) stddev-sans-first:3.23 07:46:08 INFO - PROCESS | 2298 | Values: 65.0 69.0 76.0 78.0 73.0 77.0 71.0 71.0 70.0 74.0 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:84.00 stddev:13.71 (16.3%) stddev-sans-first:14.41 07:46:08 INFO - PROCESS | 2298 | Values: 83.0 85.0 84.0 85.0 84.0 80.0 84.0 84.0 87.0 127.0 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#11] stanford-crypto-ccm Cycles:10 Average:139.60 Median:140.00 stddev:6.85 (4.9%) stddev-sans-first:7.21 07:46:08 INFO - PROCESS | 2298 | Values: 142.0 141.0 150.0 133.0 132.0 134.0 137.0 136.0 152.0 139.0 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.20 Median:163.50 stddev:27.08 (16.6%) stddev-sans-first:27.91 07:46:08 INFO - PROCESS | 2298 | Values: 160.0 165.0 162.0 161.0 161.0 226.0 210.0 161.0 161.0 215.0 07:46:08 INFO - PROCESS | 2298 | 07:46:08 INFO - PROCESS | 2298 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:71.00 stddev:7.93 (11.2%) stddev-sans-first:8.02 07:46:08 INFO - PROCESS | 2298 | Values: 64.0 67.0 66.0 62.0 78.0 81.0 84.0 75.0 66.0 65.0 07:46:08 INFO - PROCESS | 2298 | -------- Summary: end -------- 07:46:08 INFO - PROCESS | 2298 | 07:46:09 INFO - TEST-INFO | 2298: exit 0 07:46:09 INFO - TEST-OK | kraken | took 59396ms 07:46:09 INFO - SUITE-END | took 1174s 07:46:09 INFO - Completed test suite (00:19:35) 07:46:09 INFO - javascript benchmark 07:46:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2721.2380533450632, 2806.1862060002595, 3314.508471187966, 3325.8004519728834, 3342.68244516771, 4647.5352464753505, 5583.302087239095, 5650.915236271459, 5735.168400581918, 5766.163906233599, 5638.097904335307, 5647.604667673254, 5704.515116215116, 5799.159121927318, 5831.708414579265, 5065.721385323805, 5145.022445735317, 5175.8188463403785, 5181.145111056671, 5333.2533445317495, 5287.41838397776, 5312.946870531303, 5337.813176538822, 5353.705546194958, 5372.355317361922, 5149.922751158754, 5200.089984252741, 5215.0, 5229.424763276036, 5247.10799164143, 5213.244079608436, 5216.58267338612, 5219.686818790847, 5245.632805703603, 5298.629095963284, 5229.477052294778, 5255.632105752599, 5270.815521456769, 5271.446498117719, 5350.197470379435, 5570.610057295991, 5627.0, 5728.942710572883, 5733.88532229353, 5748.7125643717645, 6251.374862513758, 6286.654234017173, 6290.937090629081, 6296.779612713533, 6310.0850376695125, 6237.6257424554215, 6271.498280137624, 6298.338674439164, 6306.527010474233, 6311.368863113697, 5675.914861277084, 5725.112607545879, 5754.964106460793, 5757.0, 5871.060630299132, 6274.498040156776, 6278.277998030194, 6285.779997700059, 6289.0880822280515, 6298.433141017331, 6168.629882206992, 6180.381961803873, 6188.381161883775, 6189.659568723673, 6201.503879689614, 6027.638341699512, 6138.664296166039, 6142.682951206299, 6190.659513726789, 6297.653629050446, 6081.057436097412, 6128.075379514901, 6142.099766648211, 6148.569600127948, 6155.0, 6215.254169499689, 6220.315765265817, 6221.502279817604, 6224.253089629274, 6230.501559875199, 4856.8300109495995, 4960.305557221992, 4970.726610036482, 4977.850664480072, 5018.698878067327, 4372.890677733082, 4425.380446737459, 4445.666575006822, 4461.263891457938, 4498.415206023196, 5694.202811606296, 5718.399568045378, 5728.570357223225, 5729.369769325371, 5796.913046304308, 6275.246970363585, 6293.527985401021, 6311.274203466568, 6313.084602732578, 6331.018692102732, 5534.529564987021, 5653.23681303027, 5685.715714214272, 5711.800086997018, 5743.827685169452, 4905.871649520559, 5222.973885130626, 5461.590380721462, 5468.371137319259, 5471.917921231185, 5592.3009623797025, 6312.873742525124, 6320.8419789504715, 6321.525885558602, 6351.587146835441, 5182.0, 6149.016157414792, 6172.660503672341, 6196.690165491617, 6262.34245404226, 5313.388960269618, 6271.404216599416, 6274.6548939808745, 6277.372262773686, 6292.182016337847, 6050.183225264533, 6130.606853607433, 6146.231721034871, 6161.753529858755, 6178.66017369049, 6138.14066030765, 6161.260648722003, 6163.722632481638, 6232.937670623236, 6301.684915754286, 6105.236845394325, 6136.509079273558, 6137.539684523589, 6145.354737752472, 6152.477039451696, 5968.671723055274, 6087.147799308189, 6127.938720612915, 6128.724207410588, 6153.077038444269, 5939.851503712442, 5996.70016499182, 6002.87994240104, 6038.154658347923, 6046.0, 6150.138980542638, 6155.415235552616, 6160.0, 6169.043798211284, 6178.135061091541, 5760.510356619567, 5803.47768700819, 5925.111233315038, 5960.344362120077, 6034.637205135791], "value": 5675.801867990709, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.8476260452748, 469.68765770762445, 471.4413420097184, 481.1099465987922, 481.91566475866716, 31060.0, 32799.01602951912, 41409.99999999996, 42649.0, 42744.9313767156, 43952.34071488922, 44249.77875110628, 44343.556564434424, 44418.7779061105, 44532.0, 9636.373635713691, 9796.265280103986, 9805.01949805021, 9814.803703925918, 9816.460094694788, 8733.388662793606, 8950.462972221654, 11782.823257651142, 12029.819552706716, 12032.51869925202, 11930.821037684442, 12034.638960831146, 12043.578474753385, 12057.336846473441, 12058.819117713241, 8674.175953284428, 11723.589674361441, 11893.167478276524, 12032.578859739868, 12057.0, 10647.361158330525, 10952.671419857417, 10973.670789876316, 10975.957284058002, 10981.890181098166, 41025.794871025755, 41376.586234137874, 41438.585614143776, 41464.0, 41492.0, 3386.8306584670668, 3427.417339052367, 3566.8216589170697, 3653.8903832885057, 3664.7617904836366, 31422.842885785423, 31709.682903171135, 31892.521612175835, 32050.878219262213, 32331.353372932645, 2742.890284388622, 3321.983390083058, 3339.282054358325, 3345.8159801210923, 3478.878239261639, 375004.0, 375608.0, 375870.0, 500967.0, 501258.0, 33849.830750846086, 60852.78294434175, 61947.83182588588, 62129.0, 62224.68887655533, 33821.154471137925, 35738.64261357353, 35845.0, 35879.64120358815, 35904.28191436156, 14840.961132720606, 15060.849391505944, 15063.020903641229, 15067.397304107932, 15095.54713358601, 11543.249688770204, 11805.645830625095, 11811.704707382212, 12626.36868156607, 12718.364081795873, 7450.552966822008, 7533.585652789039, 7684.846303073908, 7707.807304817312, 7739.148693643695, 3226.1773247822125, 3281.3765384576855, 3282.2439994602773, 3308.17295676081, 3311.519829624739, 929.972100836976, 969.8933117357085, 987.5259875259771, 1006.3257617396362, 1078.3853203673987, 30627.0, 30643.2339191522, 30854.537181942287, 31206.53190202149, 31486.527702084488, 967.2310513142069, 1046.1892033673964, 1082.3614067700096, 1088.2545456362418, 1121.556984990924, 16208.51374458768, 16216.918915405584, 16325.737378972264, 16379.918100409423, 16640.583985400463, 701030.0, 778122.0, 778331.0, 798248.0, 907505.0, 60387.09419358713, 60548.39451605516, 61070.69464652737, 61092.69453652703, 61290.0, 1112.3659514077222, 1239.114033466076, 1408.7112142010862, 1431.0769553637845, 1449.289847974506, 27310.317242069108, 31126.377472450877, 31822.204444889063, 32462.798362129608, 33031.33937321288, 57901.13148302863, 58175.0, 58593.0, 58660.41339586549, 58684.0, 579448.0, 582827.0, 583709.0, 584660.0, 585355.0, 27827.860860695568, 28000.85999569989, 28286.0, 28446.004389846257, 28464.573031404132, 35316.823415882754, 41323.380149298384, 43606.34590481082, 50540.24189637085, 51588.74205628948, 35237.64762352344, 39218.62734804268, 39976.801275930105, 40323.0, 40791.98020049522, 40022.799886000386, 40343.596564033985, 41029.79485102555, 41127.38308925309, 41155.0, 38710.806445968716, 38907.0, 39822.0, 39954.00114997148, 40085.0, 34372.82813585916, 35005.47491787574, 35408.0, 35647.82176089103, 35783.0, 23453.76546234516, 25799.613005805302, 25919.87040064863, 26526.86736566382, 26781.33046673771], "value": 19293.108149850785, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.09873402567865, 382.57534137107814, 382.94447305140756, 393.66341777780013, 400.43138742984956, 18801.341953031646, 20337.881416522087, 21197.57604847906, 21209.68185477217, 21227.893860530694, 11964.282143071421, 11965.102617303715, 14172.645683857909, 20026.399208023784, 20953.895230523827, 5265.499777521149, 5352.277442545265, 5427.48438898304, 5462.754176062067, 5493.835184944438, 5242.161254199329, 5352.464753524636, 5354.0, 5378.220158077076, 5380.838574842742, 4320.503142138663, 5378.811741589046, 5388.272583201277, 5388.892222155555, 5398.433164517728, 5542.944570554303, 5895.64626122434, 5903.557233207505, 5935.228420305332, 5947.137665038588, 5661.033106269708, 5713.285839270091, 5739.368669446358, 5744.023516002248, 5745.540356771448, 13360.799588006188, 13366.398512066882, 13370.799438008438, 15499.5350139496, 24962.50074998508, 2148.7528934172615, 2211.82305415568, 2275.6017696902977, 2534.2397280815867, 2789.981656695292, 9272.582733776997, 10106.140978016878, 10163.4918254087, 10179.643712470102, 10328.380297182119, 1845.7046872500337, 1860.3953715042664, 1884.406411980222, 1924.951876203092, 2132.6267903117046, 120067.0, 125504.99999999908, 126125.0, 126198.0, 126335.0, 9473.431594104375, 11527.48126334317, 11657.591984280509, 11690.824637630443, 11817.645470635895, 14478.420863165355, 14589.343479543446, 14638.268086595628, 14648.0, 14651.633709157357, 8476.26236621491, 8551.615177317035, 8566.700165494303, 8643.222110010129, 8694.0, 8455.154484551496, 8479.788005299917, 8485.872711909326, 8582.87125693115, 8584.184502472255, 4458.77706114693, 4551.928872363067, 4558.840440584564, 4630.166570017362, 4685.390899183084, 1530.4872867589258, 1599.4401959314148, 1652.4133932454047, 1690.9830901690825, 1744.1976690722156, 382.20882772660326, 788.467784245632, 973.7614284500343, 990.7770751580837, 1033.188946676855, 11418.885811141949, 11909.821352679717, 11992.0, 12135.635930922086, 12137.453814578188, 418.31605325293367, 439.90981848720935, 443.7071532788344, 452.59492753985, 463.2056023918995, 5975.760969561169, 6069.605475644069, 6079.300880398722, 6230.688465576703, 6233.0338797486465, 468844.0, 472203.0, 473530.0, 514401.0, 515052.0, 135885.73199814075, 138152.0, 142463.287683563, 144303.0, 146453.0, 962.1484985787629, 976.9755756106155, 979.8971108033696, 1005.3867141043958, 1006.3509036671452, 15056.548303550473, 17891.910540447214, 19033.619327613094, 19122.617547649246, 19190.904045479685, 47705.0, 50009.74956127056, 50640.74679626578, 50651.0, 50750.0, 78180.0, 78187.21812781799, 78639.60680196562, 78779.60610196913, 79241.0, 8016.959915200387, 8304.086550479322, 8396.496210227406, 8430.988281406271, 8546.914530854612, 9284.30367722437, 13317.267550284827, 13392.397342119822, 13586.184828910296, 13601.931990339985, 11263.943680281545, 11453.427328633368, 11453.828192577284, 11565.537378504765, 11642.94178529102, 11014.944925275322, 11264.887351126385, 11314.60398886035, 11399.20205585601, 11451.59919402817, 11454.828177577176, 11523.769524609294, 11565.710857228636, 11597.072234221074, 11832.408379580815, 8119.59402029908, 8232.794180145544, 8271.33829293643, 8371.41400101987, 8439.071702112886, 8959.0, 10891.400972946247, 11069.446527673745, 11231.26996745209, 11541.826872597087], "value": 8793.052613703107, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1492.8208614966206, 1549.558375862879, 1718.7335962925752, 1853.7590113285287, 1891.2435025989612, 8430.66277348906, 8716.128387161281, 8887.511186884718, 8904.33217508687, 8915.286777057836, 7804.29761321481, 8029.638666260018, 8531.360147988895, 8720.60757265923, 8742.994555626105, 2338.8012018978407, 2370.608849539824, 2372.905083796646, 2373.2642880707035, 2375.1330764271, 2331.5686597979343, 2348.307249361434, 2371.905123795046, 2374.4776149247223, 2376.370261880594, 1703.9744403833952, 2167.631502644554, 2276.5105502316997, 2343.8828058597, 2348.248560460653, 2310.8844557772045, 2316.3745788637057, 2339.485313231095, 2341.1103780563276, 2343.718753749544, 2076.6573515369946, 2323.5004474038165, 2334.4397344637164, 2341.3678306857055, 2344.5896968030697, 8813.427127236773, 8882.600282987281, 8884.11158884113, 8887.15572020657, 8898.86651700225, 5126.615503837227, 5324.4675532446445, 5335.33308336458, 5335.519803217729, 5344.412114667383, 3534.2578058607724, 3557.7331700122595, 3607.3687104756564, 3607.549056367954, 3613.259281847215, 1077.49357801833, 1077.9245452818307, 1084.1489430796867, 1100.3617901617042, 1101.9779604407947, 11505.539778408853, 11508.942455287754, 11516.712082197928, 11516.942415287953, 11520.02079823216, 3975.284448799244, 4483.596476317147, 4550.635949124062, 4691.601213896857, 4722.574968252874, 9686.806263874823, 9772.951135244279, 9777.755556111155, 9779.755506112404, 9783.853242201372, 5283.0, 5285.524302812765, 5290.841274761687, 5292.02097611943, 5293.444188360244, 4750.904981900412, 4799.848036471221, 4853.296272040534, 4853.927191092136, 4858.7570621468785, 3549.881787237012, 3911.5110611173604, 3940.172563761585, 4004.539477960072, 4024.6579040781276, 1177.3465726521692, 1212.3695678247439, 1216.8296438498617, 1288.761579107868, 1297.8118172864881, 668.8595394967014, 866.3588944181387, 899.5996781432331, 907.945523268606, 956.6221342569646, 5687.289088863892, 5971.552133589911, 6007.759689612367, 6030.698465076729, 6056.515478761688, 868.7220089571402, 895.4358753984948, 978.4031740638205, 987.1806400687412, 998.1665309466676, 3269.313444176702, 3810.561785394715, 3935.822887970048, 3998.300297447993, 4047.372657238133, 10168.33905796121, 10670.51982660782, 10704.250702450754, 10843.078338341329, 10847.349159050482, 9798.75503112428, 10817.188710846718, 10848.566057357617, 10856.674299771019, 10894.019538241439, 7998.360131189491, 8213.137620549876, 8275.917240827515, 8363.163683631588, 8363.414560980795, 5946.375630558902, 5995.550333724901, 6017.518598512196, 6163.291221509584, 6272.874542509033, 9656.37233579815, 10122.190224781853, 10133.391996480235, 10146.0, 10148.391096534231, 10135.898641013495, 10236.590536378462, 10275.640352587623, 10283.94858025705, 10292.0, 7484.064491938508, 7518.36093932, 7596.126445458843, 7642.3504002158215, 7660.961695191489, 8265.173482651686, 8322.250997410261, 8381.706640267561, 8389.748307550539, 8390.412671112963, 7419.406447484081, 7506.136794268729, 7526.0, 7637.579933103732, 7661.731839385595, 7242.710291588278, 7366.594837284001, 7422.109346878409, 7444.813879652836, 7458.366038886756, 7076.646167691491, 7091.255418181223, 7136.964315178391, 7144.607046612487, 7151.2848715128075, 4925.6454475019655, 5023.397192336943, 5103.87240318995, 5136.512031357164, 5143.948560514347, 5026.974865125651, 5060.544550990428, 5143.9712057587885, 5145.511176438233, 5249.08141075318], "value": 4585.069786388749, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [257.6354458441306, 297.8540515147577, 312.51404066676315, 314.59260257965934, 315.103530455853, 4447.621952134068, 5113.38639363276, 5245.527902488775, 5346.224797404374, 5475.917861232079, 4672.439796641994, 5011.649184557083, 5096.898062038757, 5259.368875734907, 5280.867978300535, 3454.3436747018113, 4092.5498195198506, 4160.06398560323, 4177.979110104445, 4220.978895105528, 3959.168574599323, 4147.709660323779, 4163.958360416403, 4169.457970463835, 4184.916301673965, 3374.2453923959647, 3862.8261728222296, 3990.8204130814183, 4031.737937034098, 4047.97976010121, 3618.7285953553587, 3964.9096498462864, 4028.4762980812307, 4110.527289361717, 4141.233871733739, 3063.908082757521, 3551.5971191379263, 3601.603823579404, 3613.168971136627, 3615.891523254307, 4323.373110898933, 4341.848035318749, 4957.876537781211, 5313.362396512404, 5366.70483123428, 3860.208657225262, 4078.6125318094732, 4648.325992731069, 4675.054715529229, 4706.082313948781, 4667.369905062841, 5586.720663966786, 5659.6321239119325, 5663.083511991328, 5677.0, 3232.288896442779, 3723.1995121048835, 3815.389537673959, 3841.9242612068665, 3894.824732887027, 5218.373795144569, 5408.755986123175, 5488.56091512678, 5925.259342582177, 5935.557376721491, 3920.157166209279, 3923.3330333843314, 3923.9019024524614, 3946.605339466088, 3955.92088158241, 3604.585472670624, 4011.117554138085, 4017.393042782852, 4038.333674943601, 4048.4736984192455, 3949.486566746362, 4577.06170235101, 4625.653076019312, 4645.303204519281, 4648.535146485325, 4956.603471722254, 5653.406392328829, 5681.659100453986, 5695.664777441312, 5705.942940570541, 1202.8332517458052, 1309.6529419703786, 1340.5442149668963, 1358.3140514040347, 1364.5565191312862, 761.394691220481, 817.9059408168018, 848.0, 910.4264313482464, 913.3150137396952, 5241.868953276198, 5278.656887302313, 5310.336207974004, 5319.255304257407, 5324.254604355393, 4793.496682848251, 5777.220075289866, 5783.161441590946, 5796.565257605612, 5817.738201780845, 4481.5070342262325, 4505.459344878635, 5177.508136727005, 5295.523402893758, 5395.163749618815, 3060.6786287439945, 3077.492213784745, 3079.0, 3079.337942342407, 3084.640250208592, 5279.973600131974, 5862.368728704361, 5952.503694538932, 6008.918394688911, 6077.328734598021, 4894.681845680019, 5901.498372638373, 5968.612040217372, 6020.367861374581, 6057.545684073712, 4857.878553036202, 5752.9712351437975, 6003.699815009232, 6008.047384600022, 6036.366181550874, 5567.103027880419, 5768.740406681625, 5790.363060063474, 5809.186713860147, 5880.911786323294, 5685.772569097356, 5802.883942321046, 5815.621984570989, 5821.27234095738, 5823.068309070529, 5663.5469162467725, 6036.517078633611, 6039.22195789924, 6089.746793686364, 6127.571070025056, 3921.588233235583, 3926.391409331558, 3951.3677811550015, 3953.466282051886, 3987.4816273884207, 5906.645601263766, 5962.0, 5970.582059255984, 6010.398960103955, 6014.548908831767, 5000.349954505891, 5426.127985844877, 5716.628419152742, 5719.199312096393, 5727.541796656341, 4731.834385796481, 5689.8008569701515, 5701.914471282851, 5718.25662663851, 5802.564807639359, 4968.428630707371, 4987.376577927759, 5590.413006634245, 5591.930985535378, 5624.550035997192, 4979.626528010486, 5660.632058916157, 5696.829095127153, 5716.79991200306, 5717.914231286617, 4631.652626052992, 4650.5814476695905, 5296.404761666832, 5554.527865131509, 5575.721213939206], "value": 4121.308801344195, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.2797202797203, 760.7261385901074, 785.1873311122989, 809.2231457800511, 840.9411341206114, 12013.63959081228, 15345.156016419092, 16134.193290335481, 16146.677066458678, 16438.67122657549, 14515.129092254472, 14597.635059123528, 14673.77989330161, 14674.0, 14690.338934747935, 661.6956200147938, 680.880845851974, 700.310194458458, 837.2966707965307, 851.9872201916976, 837.7779888329597, 841.9980223534005, 842.027458285678, 843.1063073142489, 844.2992316377423, 889.3107841422917, 890.6882591093098, 891.1756625121769, 891.8439273127209, 893.0310612984919, 706.6996526476253, 866.5450638414819, 883.9513826739527, 895.5656506594297, 898.3172788680585, 659.1431139518654, 659.4361820643329, 659.650385295794, 659.7064306383661, 659.7459977908492, 15026.924865375604, 15077.0, 15087.24563771807, 15089.170095644735, 15108.622284442867, 11920.761584768343, 13819.170849748947, 13902.0, 14019.64950876226, 14240.0, 12768.553100641526, 12800.423980920881, 12902.161359511601, 13106.934465327708, 13249.157744254155, 4020.4170395292517, 4544.750038747868, 4674.813007479696, 4748.335233067373, 4809.591184749301, 103234.0, 103736.48131759369, 103982.48008760059, 105192.0, 107695.0, 11040.558377664804, 11059.059979901638, 11175.608853690082, 11218.439078046065, 11277.097832173406, 21650.02574884134, 21832.017559209875, 21846.453838653844, 21877.12491500322, 22104.2263520776, 7905.169957154531, 7908.644111015018, 7931.84136317282, 8000.119986801447, 8137.186281371815, 4372.0, 4401.427814384109, 4407.977960110179, 4438.467383913951, 4524.073255415404, 3420.350133474632, 3526.806025668613, 3528.0826984984087, 3528.8412021459094, 3531.4526248431534, 609.1745684597396, 619.5848781316529, 622.5237693164732, 624.44736408279, 659.2188256915571, 607.1348328631682, 636.2460484325995, 642.9132067170872, 649.6297110646885, 650.612885333226, 7837.216278372117, 8571.828563428697, 9022.954885225663, 9026.0, 9299.76750581241, 601.255008394792, 603.5897180927793, 624.2676572511317, 635.9872802543923, 638.553012890969, 4308.827646894152, 4423.889402764892, 4426.118066203219, 4465.910681786346, 4544.374950518519, 144599.0, 144836.0, 147990.26004870122, 153691.0, 154392.0, 125639.3718031404, 129551.0, 130065.0, 131211.0, 133562.0, 96373.51813241029, 107414.92585074206, 109185.45407272913, 111656.0, 113094.0, 8536.231739143506, 8557.058723540531, 8571.61427735766, 8660.610272537624, 8779.689591907521, 51277.7436112817, 61797.76404471795, 63358.68320658367, 63437.0, 63690.36309636844, 60588.69705651444, 88795.0, 95342.0, 99725.0, 101288.0, 13272.0, 13441.327933603476, 13509.59471215865, 14088.01383903117, 14115.576532704035, 13085.41115649817, 14033.596640335885, 14753.631159221106, 15172.544823655307, 15845.920770396075, 13818.585442436743, 14063.789043164565, 14496.782548261996, 14520.564383068524, 14663.780043299146, 13772.586822395344, 13922.582322530341, 14210.360533775798, 14242.71514569682, 14596.0, 13486.86513134856, 13655.726885462434, 13809.86190138126, 13819.864189239448, 13832.723345533233, 11204.943975280072, 11504.309741415542, 11594.478248479007, 11694.649160525198, 12004.39978001114, 8913.598888049924, 11412.731313837823, 11424.82862757076, 11550.364729939934, 11663.650090497298], "value": 7815.751544979212, "unit": "score"}], "name": "dromaeo_css", "value": 7224.825992313633, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 112.0, 113.0, 113.0, 111.0, 112.0, 113.0, 112.0, 113.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 116.0, 91.0, 185.0, 134.0, 134.0, 128.0, 106.0, 159.0, 122.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 186.0, 183.0, 183.0, 184.0, 182.0, 184.0, 181.0, 180.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 58.0, 89.0, 57.0, 62.0, 62.0, 64.0, 98.0, 60.0], "value": 70.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 93.0, 92.0, 71.0, 72.0, 69.0, 96.0, 69.0, 68.0, 102.0], "value": 81.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 83.0, 86.0, 83.0, 87.0, 84.0, 86.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 93.0, 92.0, 91.0, 93.0, 92.0, 92.0, 91.0], "value": 92.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 85.0, 84.0, 86.0, 87.0, 86.0, 85.0, 85.0, 83.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 54.0, 53.0, 54.0, 55.0, 53.0, 54.0, 53.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 76.0, 78.0, 73.0, 77.0, 71.0, 71.0, 70.0, 74.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 84.0, 85.0, 84.0, 80.0, 84.0, 84.0, 87.0, 127.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 150.0, 133.0, 132.0, 134.0, 137.0, 136.0, 152.0, 139.0], "value": 139.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 165.0, 162.0, 161.0, 161.0, 226.0, 210.0, 161.0, 161.0, 215.0], "value": 178.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 66.0, 62.0, 78.0, 81.0, 84.0, 75.0, 66.0, 65.0], "value": 70.8, "unit": "score"}], "name": "kraken", "value": 1444.1999999999998, "alertThreshold": 2.0}]} 07:46:09 INFO - Return code: 0 07:46:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:46:09 INFO - # TBPL SUCCESS # 07:46:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:46:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:46:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:46:09 INFO - 2016-06-30 07:46:09.877 screenresolution[2302:288627] starting screenresolution argv=screenresolution get 07:46:09 INFO - 2016-06-30 07:46:09.880 screenresolution[2302:288627] Display 0: 1600x1200x32@60 07:46:09 INFO - 2016-06-30 07:46:09.886 screenresolution[2303:288630] starting screenresolution argv=screenresolution list 07:46:09 INFO - Available Modes on Display 0 07:46:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:46:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:46:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:46:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:46:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:46:10 INFO - Intel Iris: 07:46:10 INFO - Chipset Model: Intel Iris 07:46:10 INFO - Type: GPU 07:46:10 INFO - Bus: Built-In 07:46:10 INFO - VRAM (Dynamic, Max): 1536 MB 07:46:10 INFO - Vendor: Intel (0x8086) 07:46:10 INFO - Device ID: 0x0a2e 07:46:10 INFO - Revision ID: 0x0009 07:46:10 INFO - Displays: 07:46:10 INFO - Display: 07:46:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:46:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:46:10 INFO - Display Serial Number: 1600x1200 60 07:46:10 INFO - Main Display: Yes 07:46:10 INFO - Mirror: Off 07:46:10 INFO - Online: Yes 07:46:10 INFO - Rotation: Supported 07:46:10 INFO - Return code: 0 07:46:10 INFO - Running post-action listener: _resource_record_post_action 07:46:10 INFO - ##### 07:46:10 INFO - ##### Finished run-tests step (success) 07:46:10 INFO - ##### 07:46:10 INFO - Running post-run listener: _resource_record_post_run 07:46:10 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 36864; Write bytes: 704166912; Read time: 2; Write time: 1936 07:46:10 INFO - TinderboxPrint: CPU usage
53.3% 07:46:10 INFO - TinderboxPrint: I/O read bytes / time
36,864 / 2 07:46:10 INFO - TinderboxPrint: I/O write bytes / time
704,166,912 / 1,936 07:46:10 INFO - TinderboxPrint: CPU idle
2,261.4 (46.7%) 07:46:10 INFO - TinderboxPrint: CPU system
963.0 (19.9%) 07:46:10 INFO - TinderboxPrint: CPU user
1,617.3 (33.4%) 07:46:10 INFO - TinderboxPrint: Swap in / out
406,093,824 / 0 07:46:10 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 398123520; Write bytes: 392382464; Read time: 29602; Write time: 761 07:46:10 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 36864; Write bytes: 294028288; Read time: 2; Write time: 1133 07:46:10 INFO - Running post-run listener: _upload_blobber_files 07:46:10 INFO - Blob upload gear active. 07:46:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:46:10 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:46:10 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'] 07:46:10 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 07:46:10 INFO - (blobuploader) - INFO - Open directory for files ... 07:46:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:46:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:46:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:46:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:46:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:46:11 INFO - (blobuploader) - INFO - Done attempting. 07:46:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:46:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:46:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:46:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:46:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:46:12 INFO - (blobuploader) - INFO - Done attempting. 07:46:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:46:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:46:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:46:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:46:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:46:14 INFO - (blobuploader) - INFO - Done attempting. 07:46:14 INFO - (blobuploader) - INFO - Iteration through files over. 07:46:14 INFO - Return code: 0 07:46:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:46:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:46:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d76edb0abce1b6de78ae6ac544a9be42a683f5382f520800ee0490feafaf06b27ee8d13a9ebd1bf59245cfb7ccec048a3c483de74bc25d7141939dee3ad3f07a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7ac827c2c0b7fea9835c15b44cffed8f7c0d38c889f57a2d77cdc3f270317d829f77f41a10f111b2488c908ddb8be5ca9ab4cab1d60796c70ab1154e03a164f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c89aee91c5cfa5af0be6fe3ddcd0f49a4d5ed5824b29ab094834055314abcfd1c7d82d15e603b524a40048066cd50aba608616b1c4ff1c3447f35e7255c52df"} 07:46:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:46:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:46:14 INFO - Contents: 07:46:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d76edb0abce1b6de78ae6ac544a9be42a683f5382f520800ee0490feafaf06b27ee8d13a9ebd1bf59245cfb7ccec048a3c483de74bc25d7141939dee3ad3f07a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7ac827c2c0b7fea9835c15b44cffed8f7c0d38c889f57a2d77cdc3f270317d829f77f41a10f111b2488c908ddb8be5ca9ab4cab1d60796c70ab1154e03a164f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c89aee91c5cfa5af0be6fe3ddcd0f49a4d5ed5824b29ab094834055314abcfd1c7d82d15e603b524a40048066cd50aba608616b1c4ff1c3447f35e7255c52df"} 07:46:14 INFO - Running post-run listener: copy_logs_to_upload_dir 07:46:14 INFO - Copying logs to upload dir... 07:46:14 INFO - mkdir: /builds/slave/test/build/upload/logs 07:46:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.869770 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-06-30 07:46:14.919589) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 07:46:14.923676) ========= 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.ADvqFmxrU7/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.FccnxTROU1/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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/d76edb0abce1b6de78ae6ac544a9be42a683f5382f520800ee0490feafaf06b27ee8d13a9ebd1bf59245cfb7ccec048a3c483de74bc25d7141939dee3ad3f07a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7ac827c2c0b7fea9835c15b44cffed8f7c0d38c889f57a2d77cdc3f270317d829f77f41a10f111b2488c908ddb8be5ca9ab4cab1d60796c70ab1154e03a164f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c89aee91c5cfa5af0be6fe3ddcd0f49a4d5ed5824b29ab094834055314abcfd1c7d82d15e603b524a40048066cd50aba608616b1c4ff1c3447f35e7255c52df"} build_url:https://queue.taskcluster.net/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011262 build_url: 'https://queue.taskcluster.net/v1/task/VjAb4nTBRIeozoemQ8Vzpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d76edb0abce1b6de78ae6ac544a9be42a683f5382f520800ee0490feafaf06b27ee8d13a9ebd1bf59245cfb7ccec048a3c483de74bc25d7141939dee3ad3f07a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7ac827c2c0b7fea9835c15b44cffed8f7c0d38c889f57a2d77cdc3f270317d829f77f41a10f111b2488c908ddb8be5ca9ab4cab1d60796c70ab1154e03a164f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c89aee91c5cfa5af0be6fe3ddcd0f49a4d5ed5824b29ab094834055314abcfd1c7d82d15e603b524a40048066cd50aba608616b1c4ff1c3447f35e7255c52df"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 07:46:14.958854) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 07:46:14.959196) ========= 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.ADvqFmxrU7/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.FccnxTROU1/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.005220 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 07:46:15.008245) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 07:46:15.008696) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 07:46:15.009053) ========= ========= Total master_lag: 0.19 =========