builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0256 starttime: 1468939092.39 results: success (0) buildid: 20160719052920 builduid: 09943e17049c4d5b91b74cba7d1254eb revision: efa58477381d54e2d0bd1d89c2d9210a3714e143 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 07:38:12.389144) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 07:38:12.389552) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 07:38:12.389835) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 07:38:12.425064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 07:38:12.425328) ========= 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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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-07-19 07:38:12-- 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% 159M=0s 2016-07-19 07:38:12 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093883 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 07:38:12.543565) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 07:38:12.544255) ========= 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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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.020566 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 07:38:12.596802) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-19 07:38:12.597548) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev efa58477381d54e2d0bd1d89c2d9210a3714e143 --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 efa58477381d54e2d0bd1d89c2d9210a3714e143 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NXaeRzbRIE/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.NXGKvbWw8P/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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-07-19 07:38:12,679 truncating revision to first 12 chars 2016-07-19 07:38:12,679 Setting DEBUG logging. 2016-07-19 07:38:12,679 attempt 1/10 2016-07-19 07:38:12,679 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/efa58477381d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 07:38:13,902 unpacking tar archive at: fx-team-efa58477381d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.497705 ========= master_lag: 0.65 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-19 07:38:14.750145) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 07:38:14.750438) ========= script_repo_revision: efa58477381d54e2d0bd1d89c2d9210a3714e143 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 07:38:14.750820) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 07:38:14.751077) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 07:38:14.775005) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-19 07:38:14.775402) ========= /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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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:38:14 INFO - MultiFileLogger online at 20160719 07:38:14 in /builds/slave/test 07:38:14 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:38:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:38:14 INFO - {'append_to_log': False, 07:38:14 INFO - 'base_work_dir': '/builds/slave/test', 07:38:14 INFO - 'blob_upload_branch': 'Fx-Team', 07:38:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:38:14 INFO - 'branch': 'Fx-Team', 07:38:14 INFO - 'buildbot_json_path': 'buildprops.json', 07:38:14 INFO - 'config_files': ('talos/mac_config.py',), 07:38:14 INFO - 'default_actions': ('clobber', 07:38:14 INFO - 'read-buildbot-config', 07:38:14 INFO - 'download-and-extract', 07:38:14 INFO - 'populate-webroot', 07:38:14 INFO - 'create-virtualenv', 07:38:14 INFO - 'install', 07:38:14 INFO - 'run-tests'), 07:38:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:38:14 INFO - 'download_minidump_stackwalk': True, 07:38:14 INFO - 'download_symbols': 'ondemand', 07:38:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:38:14 INFO - 'tooltool.py': '/tools/tooltool.py', 07:38:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:38:14 INFO - '/tools/misc-python/virtualenv.py')}, 07:38:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:38:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:38:14 INFO - 'installer_path': 'installer.exe', 07:38:14 INFO - 'log_level': 'info', 07:38:14 INFO - 'log_name': 'talos', 07:38:14 INFO - 'log_to_console': True, 07:38:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:38:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:38:14 INFO - 'opt_config_files': (), 07:38:14 INFO - 'pip_index': False, 07:38:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:38:14 INFO - 'cmd': ('bash', 07:38:14 INFO - '-c', 07:38:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:38:14 INFO - 'enabled': True, 07:38:14 INFO - 'halt_on_failure': False, 07:38:14 INFO - 'name': 'check_screen_resolution'},), 07:38:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:38:14 INFO - 'cmd': ('bash', 07:38:14 INFO - '-c', 07:38:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:38:14 INFO - 'enabled': True, 07:38:14 INFO - 'halt_on_failure': False, 07:38:14 INFO - 'name': 'check_screen_resolution'},), 07:38:14 INFO - 'run_cmd_checks_enabled': True, 07:38:14 INFO - 'sps_profile': False, 07:38:14 INFO - 'sps_profile_interval': 0, 07:38:14 INFO - 'suite': 'dromaeojs', 07:38:14 INFO - 'system_bits': '32', 07:38:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:38:14 INFO - 'title': 't-yosemite-r7-0256', 07:38:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:38:14 INFO - 'use_talos_json': True, 07:38:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:38:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:38:14 INFO - 'work_dir': 'build'} 07:38:14 INFO - ##### 07:38:14 INFO - ##### Running clobber step. 07:38:14 INFO - ##### 07:38:14 INFO - Running pre-action listener: _resource_record_pre_action 07:38:14 INFO - Running main action method: clobber 07:38:14 INFO - rmtree: /builds/slave/test/build 07:38:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:38:15 INFO - Running post-action listener: _resource_record_post_action 07:38:15 INFO - ##### 07:38:15 INFO - ##### Finished clobber step (success) 07:38:15 INFO - ##### 07:38:15 INFO - ##### 07:38:15 INFO - ##### Running read-buildbot-config step. 07:38:15 INFO - ##### 07:38:15 INFO - Running pre-action listener: _resource_record_pre_action 07:38:15 INFO - Running main action method: read_buildbot_config 07:38:15 INFO - Using buildbot properties: 07:38:15 INFO - { 07:38:15 INFO - "project": "", 07:38:15 INFO - "product": "firefox", 07:38:15 INFO - "script_repo_revision": "production", 07:38:15 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:38:15 INFO - "repository": "", 07:38:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:38:15 INFO - "buildid": "20160719052920", 07:38:15 INFO - "pgo_build": "False", 07:38:15 INFO - "basedir": "/builds/slave/test", 07:38:15 INFO - "buildnumber": 95, 07:38:15 INFO - "slavename": "t-yosemite-r7-0256", 07:38:15 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:38:15 INFO - "platform": "yosemite_r7", 07:38:15 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 07:38:15 INFO - "branch": "fx-team", 07:38:15 INFO - "repo_path": "integration/fx-team", 07:38:15 INFO - "revision": "efa58477381d54e2d0bd1d89c2d9210a3714e143", 07:38:15 INFO - "stage_platform": "macosx64", 07:38:15 INFO - "builduid": "09943e17049c4d5b91b74cba7d1254eb", 07:38:15 INFO - "slavebuilddir": "test" 07:38:15 INFO - } 07:38:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 07:38:15 INFO - Running post-action listener: _resource_record_post_action 07:38:15 INFO - ##### 07:38:15 INFO - ##### Finished read-buildbot-config step (success) 07:38:15 INFO - ##### 07:38:15 INFO - ##### 07:38:15 INFO - ##### Running download-and-extract step. 07:38:15 INFO - ##### 07:38:15 INFO - Running pre-action listener: _resource_record_pre_action 07:38:15 INFO - Running main action method: download_and_extract 07:38:15 INFO - mkdir: /builds/slave/test/build/tests 07:38:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:38:15 INFO - https://queue.taskcluster.net/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:38:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 07:38:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 07:38:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/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:38:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/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:38:17 INFO - Downloaded 1199 bytes. 07:38:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 07:38:17 INFO - Using the following test package requirements: 07:38:17 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 07:38:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:38:17 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 07:38:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:38:17 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 07:38:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:38:17 INFO - u'jsshell-mac.zip'], 07:38:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:38:17 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 07:38:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 07:38:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:38:17 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 07:38:17 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:38:17 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 07:38:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:38:17 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 07:38:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:38:17 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 07:38:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:38: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:38:17 INFO - https://queue.taskcluster.net/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:38:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:38:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:38:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/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:38:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/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:38:18 INFO - Downloaded 18835329 bytes. 07:38: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:38: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:38:21 INFO - Return code: 0 07:38:21 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:38:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:38:21 INFO - https://queue.taskcluster.net/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:38:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:38:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:38:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/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:38:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/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:38:24 INFO - Downloaded 18835329 bytes. 07:38:24 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:38:24 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:38:27 INFO - Return code: 0 07:38:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:38:27 INFO - https://queue.taskcluster.net/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:38:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 07:38:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 07:38:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/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:38:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/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:38:28 INFO - Downloaded 14403317 bytes. 07:38:28 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:38:28 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:38:28 INFO - Return code: 0 07:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:38:28 INFO - https://queue.taskcluster.net/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:38:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:38:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:38:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 07:38:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDku86KXQNaXo5NFzmQO7w/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:38:31 INFO - Downloaded 112873687 bytes. 07:38:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:38:31 INFO - mkdir: /builds/slave/test/properties 07:38:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:38:31 INFO - Writing to file /builds/slave/test/properties/build_url 07:38:31 INFO - Contents: 07:38:31 INFO - build_url:https://queue.taskcluster.net/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:38:33 INFO - Running post-action listener: _resource_record_post_action 07:38:33 INFO - Running post-action listener: set_extra_try_arguments 07:38:33 INFO - ##### 07:38:33 INFO - ##### Finished download-and-extract step (success) 07:38:33 INFO - ##### 07:38:33 INFO - ##### 07:38:33 INFO - ##### Running populate-webroot step. 07:38:33 INFO - ##### 07:38:33 INFO - Running pre-action listener: _resource_record_pre_action 07:38:33 INFO - Running main action method: populate_webroot 07:38:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:38:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:38:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:38:33 INFO - '--tppagecycles', 07:38:33 INFO - '7'], 07:38:33 INFO - 'tests': ['tsvgm']}}, 07:38:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:38:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:38:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:38:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:38:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:38:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:38:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:33 INFO - 'talos_options': ['--disable-e10s'], 07:38:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:38:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:38:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:33 INFO - 'talos_options': ['--disable-e10s'], 07:38:33 INFO - 'tests': ['damp', 'tps']}, 07:38:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:33 INFO - 'tests': ['damp', 'tps']}, 07:38:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:38:33 INFO - 'tests': ['dromaeo_dom']}, 07:38:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:38:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:38:33 INFO - 'tests': ['basic_compositor_video']}, 07:38:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:38:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:38:33 INFO - 'tests': ['a11yr', 07:38:33 INFO - 'ts_paint', 07:38:33 INFO - 'tpaint', 07:38:33 INFO - 'sessionrestore', 07:38:33 INFO - 'sessionrestore_no_auto_restore', 07:38:33 INFO - 'tabpaint']}, 07:38:33 INFO - 'other-e10s': {'tests': ['a11yr', 07:38:33 INFO - 'ts_paint', 07:38:33 INFO - 'tpaint', 07:38:33 INFO - 'sessionrestore', 07:38:33 INFO - 'sessionrestore_no_auto_restore', 07:38:33 INFO - 'tabpaint']}, 07:38:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:38:33 INFO - 'ts_paint', 07:38:33 INFO - 'tpaint', 07:38:33 INFO - 'sessionrestore', 07:38:33 INFO - 'sessionrestore_no_auto_restore', 07:38:33 INFO - 'tabpaint']}, 07:38:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:38:33 INFO - 'ts_paint', 07:38:33 INFO - 'tpaint', 07:38:33 INFO - 'sessionrestore', 07:38:33 INFO - 'sessionrestore_no_auto_restore', 07:38:33 INFO - 'tabpaint']}, 07:38:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:38:33 INFO - 'tests': ['a11yr', 07:38:33 INFO - 'ts_paint', 07:38:33 INFO - 'tpaint', 07:38:33 INFO - 'sessionrestore', 07:38:33 INFO - 'sessionrestore_no_auto_restore', 07:38:33 INFO - 'tabpaint']}, 07:38:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:38:33 INFO - 'tests': ['a11yr', 07:38:33 INFO - 'ts_paint', 07:38:33 INFO - 'tpaint', 07:38:33 INFO - 'sessionrestore', 07:38:33 INFO - 'sessionrestore_no_auto_restore', 07:38:33 INFO - 'tabpaint']}, 07:38:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:38:33 INFO - 'tests': ['tsvgx', 07:38:33 INFO - 'tsvgr_opacity', 07:38:33 INFO - 'tart', 07:38:33 INFO - 'tscrollx', 07:38:33 INFO - 'cart']}, 07:38:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:38:33 INFO - 'tsvgr_opacity', 07:38:33 INFO - 'tart', 07:38:33 INFO - 'tscrollx', 07:38:33 INFO - 'cart']}, 07:38:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:33 INFO - 'talos_options': ['--disable-e10s'], 07:38:33 INFO - 'tests': ['tp5o']}, 07:38:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:33 INFO - 'tests': ['tp5o']}, 07:38:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:33 INFO - 'talos_options': ['--disable-e10s', 07:38:33 INFO - '--xperf_path', 07:38:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:38:33 INFO - 'tests': ['tp5n']}, 07:38:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:33 INFO - 'talos_options': ['--xperf_path', 07:38:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:38:33 INFO - 'tests': ['tp5n']}}, 07:38:33 INFO - 'talos.zip': {'path': '', 07:38:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:38:33 INFO - Running post-action listener: _resource_record_post_action 07:38:33 INFO - ##### 07:38:33 INFO - ##### Finished populate-webroot step (success) 07:38:33 INFO - ##### 07:38:33 INFO - ##### 07:38:33 INFO - ##### Running create-virtualenv step. 07:38:33 INFO - ##### 07:38:33 INFO - Running pre-action listener: _resource_record_pre_action 07:38:33 INFO - Running main action method: create_virtualenv 07:38:33 INFO - Creating virtualenv /builds/slave/test/build/venv 07:38:33 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:38:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:38:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:38:33 INFO - Using real prefix '/tools/python27' 07:38:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:38:33 INFO - Installing distribute.............................................................................................................................................................................................done. 07:38:36 INFO - Installing pip.................done. 07:38:36 INFO - Return code: 0 07:38:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:38:36 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:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:36 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:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:36 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 0x1084c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe913488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10842ad30>, '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 0x10818fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108422b20>, '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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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:38:36 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:38:36 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:38:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXaeRzbRIE/Render', 07:38:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:36 INFO - 'HOME': '/Users/cltbld', 07:38:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:36 INFO - 'LOGNAME': 'cltbld', 07:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:36 INFO - 'MOZ_NO_REMOTE': '1', 07:38:36 INFO - 'NO_EM_RESTART': '1', 07:38:36 INFO - 'PAGER': '/bin/cat', 07:38:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:36 INFO - 'PWD': '/builds/slave/test', 07:38:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:36 INFO - 'SHELL': '/bin/bash', 07:38:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NXGKvbWw8P/Listeners', 07:38:36 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 07:38:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:36 INFO - 'USER': 'cltbld', 07:38:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:36 INFO - 'XPC_FLAGS': '0x0', 07:38:36 INFO - 'XPC_SERVICE_NAME': '0', 07:38:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:37 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:38:37 INFO - Cleaning up... 07:38:37 INFO - Return code: 0 07:38:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:38: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:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38: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:38:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38: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', '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 0x1084c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe913488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10842ad30>, '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 0x10818fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108422b20>, '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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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:38: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', 'psutil>=3.1.1'] in /builds/slave/test/build 07:38: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 psutil>=3.1.1 07:38:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXaeRzbRIE/Render', 07:38:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:37 INFO - 'HOME': '/Users/cltbld', 07:38:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:37 INFO - 'LOGNAME': 'cltbld', 07:38:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:37 INFO - 'MOZ_NO_REMOTE': '1', 07:38:37 INFO - 'NO_EM_RESTART': '1', 07:38:37 INFO - 'PAGER': '/bin/cat', 07:38:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:37 INFO - 'PWD': '/builds/slave/test', 07:38:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:37 INFO - 'SHELL': '/bin/bash', 07:38:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NXGKvbWw8P/Listeners', 07:38:37 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 07:38:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:37 INFO - 'USER': 'cltbld', 07:38:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:37 INFO - 'XPC_FLAGS': '0x0', 07:38:37 INFO - 'XPC_SERVICE_NAME': '0', 07:38:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:37 INFO - Downloading/unpacking psutil>=3.1.1 07:38:37 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:38:37 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:38:37 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:38:37 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:38:37 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:38:37 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:38:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:38:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:38:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:38:40 INFO - Installing collected packages: psutil 07:38:40 INFO - Running setup.py install for psutil 07:38:40 INFO - building 'psutil._psutil_osx' extension 07:38:40 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:38:40 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:38:40 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:38:40 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:38:40 INFO - building 'psutil._psutil_posix' extension 07:38:40 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:38:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:38:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:38:40 INFO - ^ 07:38:41 INFO - 1 warning generated. 07:38:41 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:38:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:38:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:38:41 INFO - Successfully installed psutil 07:38:41 INFO - Cleaning up... 07:38:41 INFO - Return code: 0 07:38:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:38:41 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:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:41 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:38:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:41 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 0x1084c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe913488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10842ad30>, '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 0x10818fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108422b20>, '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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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:38:41 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:38:41 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:38:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXaeRzbRIE/Render', 07:38:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:41 INFO - 'HOME': '/Users/cltbld', 07:38:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:41 INFO - 'LOGNAME': 'cltbld', 07:38:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:41 INFO - 'MOZ_NO_REMOTE': '1', 07:38:41 INFO - 'NO_EM_RESTART': '1', 07:38:41 INFO - 'PAGER': '/bin/cat', 07:38:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:41 INFO - 'PWD': '/builds/slave/test', 07:38:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:41 INFO - 'SHELL': '/bin/bash', 07:38:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NXGKvbWw8P/Listeners', 07:38:41 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 07:38:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:41 INFO - 'USER': 'cltbld', 07:38:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:41 INFO - 'XPC_FLAGS': '0x0', 07:38:41 INFO - 'XPC_SERVICE_NAME': '0', 07:38:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:38: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:38: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:38: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:38: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:38: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:38: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:38:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:38:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:38:44 INFO - Installing collected packages: mozsystemmonitor 07:38:44 INFO - Running setup.py install for mozsystemmonitor 07:38:44 INFO - Successfully installed mozsystemmonitor 07:38:44 INFO - Cleaning up... 07:38:44 INFO - Return code: 0 07:38:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:38: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:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38: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:38:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38: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', '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 0x1084c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe913488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10842ad30>, '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 0x10818fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108422b20>, '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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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:38: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:38: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 jsonschema==2.5.1 07:38:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXaeRzbRIE/Render', 07:38:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:44 INFO - 'HOME': '/Users/cltbld', 07:38:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:44 INFO - 'LOGNAME': 'cltbld', 07:38:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:44 INFO - 'MOZ_NO_REMOTE': '1', 07:38:44 INFO - 'NO_EM_RESTART': '1', 07:38:44 INFO - 'PAGER': '/bin/cat', 07:38:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:44 INFO - 'PWD': '/builds/slave/test', 07:38:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:44 INFO - 'SHELL': '/bin/bash', 07:38:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NXGKvbWw8P/Listeners', 07:38:44 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 07:38:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:44 INFO - 'USER': 'cltbld', 07:38:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:44 INFO - 'XPC_FLAGS': '0x0', 07:38:44 INFO - 'XPC_SERVICE_NAME': '0', 07:38:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:44 INFO - Downloading/unpacking jsonschema==2.5.1 07:38: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:38: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:38: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:38: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:38: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:38: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:38:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:38:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:38: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:38: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:38: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:38: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:38: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:38: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:38:47 INFO - Downloading functools32-3.2.3-2.tar.gz 07:38:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:38:47 INFO - warning: no files found matching '*.txt' 07:38:47 INFO - no previously-included directories found matching 'build' 07:38:47 INFO - no previously-included directories found matching 'dist' 07:38:47 INFO - no previously-included directories found matching '.git*' 07:38:47 INFO - Installing collected packages: jsonschema, functools32 07:38:47 INFO - Running setup.py install for functools32 07:38:47 INFO - warning: no files found matching '*.txt' 07:38:47 INFO - no previously-included directories found matching 'build' 07:38:47 INFO - no previously-included directories found matching 'dist' 07:38:47 INFO - no previously-included directories found matching '.git*' 07:38:47 INFO - Successfully installed jsonschema functools32 07:38:47 INFO - Cleaning up... 07:38:47 INFO - Return code: 0 07:38:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:38:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:38:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:47 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 0x1084c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe913488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10842ad30>, '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 0x10818fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108422b20>, '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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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:38:47 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:38:47 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:38:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXaeRzbRIE/Render', 07:38:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:47 INFO - 'HOME': '/Users/cltbld', 07:38:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:47 INFO - 'LOGNAME': 'cltbld', 07:38:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:47 INFO - 'MOZ_NO_REMOTE': '1', 07:38:47 INFO - 'NO_EM_RESTART': '1', 07:38:47 INFO - 'PAGER': '/bin/cat', 07:38:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:47 INFO - 'PWD': '/builds/slave/test', 07:38:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:47 INFO - 'SHELL': '/bin/bash', 07:38:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NXGKvbWw8P/Listeners', 07:38:47 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 07:38:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:47 INFO - 'USER': 'cltbld', 07:38:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:47 INFO - 'XPC_FLAGS': '0x0', 07:38:47 INFO - 'XPC_SERVICE_NAME': '0', 07:38:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:48 INFO - Downloading/unpacking blobuploader==1.2.4 07:38:48 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:38:48 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:38:48 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:38:48 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:38:48 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:38:48 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:38:50 INFO - Downloading blobuploader-1.2.4.tar.gz 07:38:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:38:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:38:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:38:51 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:38:51 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:38:51 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:38:51 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:38:51 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:38:51 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:38:51 INFO - Downloading docopt-0.6.1.tar.gz 07:38:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:38:51 INFO - Installing collected packages: blobuploader, requests, docopt 07:38:51 INFO - Running setup.py install for blobuploader 07:38:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:38:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:38:51 INFO - Running setup.py install for requests 07:38:52 INFO - Running setup.py install for docopt 07:38:52 INFO - Successfully installed blobuploader requests docopt 07:38:52 INFO - Cleaning up... 07:38:52 INFO - Return code: 0 07:38:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:38:52 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:38:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:52 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:38:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:52 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 0x1084c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe913488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10842ad30>, '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 0x10818fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108422b20>, '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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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:38:52 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:38:52 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:38:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXaeRzbRIE/Render', 07:38:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:52 INFO - 'HOME': '/Users/cltbld', 07:38:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:52 INFO - 'LOGNAME': 'cltbld', 07:38:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:52 INFO - 'MOZ_NO_REMOTE': '1', 07:38:52 INFO - 'NO_EM_RESTART': '1', 07:38:52 INFO - 'PAGER': '/bin/cat', 07:38:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:52 INFO - 'PWD': '/builds/slave/test', 07:38:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:52 INFO - 'SHELL': '/bin/bash', 07:38:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NXGKvbWw8P/Listeners', 07:38:52 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 07:38:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:52 INFO - 'USER': 'cltbld', 07:38:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:52 INFO - 'XPC_FLAGS': '0x0', 07:38:52 INFO - 'XPC_SERVICE_NAME': '0', 07:38:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:38:52 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-UYsafk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:38:53 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-yZ3mJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:38:53 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-trEQ1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:38:53 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-oZ6im0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:38:53 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-BS2goB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:38:53 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-3K_Lb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:38:53 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-lZAdlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:38:53 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-TalMgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:38:53 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-InO6Yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:38:53 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-bMzzH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:38:53 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-LPctY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:38:54 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-ohwkVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:38:54 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-9sstH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:38:54 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-C7Gouo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:38:54 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-15x8Od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:38:54 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-pJzngF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:38:54 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-kfr1Xh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:38:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:38:54 INFO - Running setup.py install for manifestparser 07:38:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:38:54 INFO - Running setup.py install for mozcrash 07:38:54 INFO - Running setup.py install for mozdebug 07:38:55 INFO - Running setup.py install for mozdevice 07:38:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:38:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:38:55 INFO - Running setup.py install for mozfile 07:38:55 INFO - Running setup.py install for mozhttpd 07:38:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:38:55 INFO - Running setup.py install for mozinfo 07:38:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:38:55 INFO - Running setup.py install for mozInstall 07:38:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:38:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:38:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:38:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:38:55 INFO - Running setup.py install for mozleak 07:38:55 INFO - Running setup.py install for mozlog 07:38:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:38:56 INFO - Running setup.py install for moznetwork 07:38:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:38:56 INFO - Running setup.py install for mozprocess 07:38:56 INFO - Running setup.py install for mozprofile 07:38:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:38:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:38:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:38:56 INFO - Running setup.py install for mozrunner 07:38:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:38:56 INFO - Running setup.py install for mozscreenshot 07:38:56 INFO - Running setup.py install for moztest 07:38:57 INFO - Running setup.py install for mozversion 07:38:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:38:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:38:57 INFO - Cleaning up... 07:38:57 INFO - Return code: 0 07:38:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:38:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:38:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:57 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 0x1084c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe913488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10842ad30>, '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 0x10818fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108422b20>, '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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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:38:57 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:38:57 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:38:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXaeRzbRIE/Render', 07:38:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:57 INFO - 'HOME': '/Users/cltbld', 07:38:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:57 INFO - 'LOGNAME': 'cltbld', 07:38:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:57 INFO - 'MOZ_NO_REMOTE': '1', 07:38:57 INFO - 'NO_EM_RESTART': '1', 07:38:57 INFO - 'PAGER': '/bin/cat', 07:38:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:57 INFO - 'PWD': '/builds/slave/test', 07:38:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:57 INFO - 'SHELL': '/bin/bash', 07:38:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NXGKvbWw8P/Listeners', 07:38:57 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 07:38:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:57 INFO - 'USER': 'cltbld', 07:38:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:57 INFO - 'XPC_FLAGS': '0x0', 07:38:57 INFO - 'XPC_SERVICE_NAME': '0', 07:38:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:38:57 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-ia1l75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:38:57 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:38:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:38:57 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-WMfi56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:38:57 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:38:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:38:57 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-j8s7Op-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:38:57 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:38:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:38:57 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-9bGgkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:38:57 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:38:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:38:57 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-s3qnOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:38:58 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:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:38:58 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-aOIh0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:38:58 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:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:38:58 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-quUupP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:38:58 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:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:38:58 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-8PlrNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:38:58 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:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:38:58 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-gTimrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:38:58 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:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:38:58 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-pydQG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:38:58 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:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:38:58 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-suROEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:38:58 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:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:38:58 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-pBVAUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:38:58 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-aXvpnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:38:58 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:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:38:58 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-PYL3JH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:38:58 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-dGDdQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:38:59 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:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:38:59 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-UrelUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:38:59 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:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:38:59 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-cOi0lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:38:59 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:38:59 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:38:59 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:38:59 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:38:59 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:38:59 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:38:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:38:59 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:38:59 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:38:59 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:38:59 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:38:59 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:38:59 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:39:01 INFO - Downloading blessings-1.6.tar.gz 07:39:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:39:01 INFO - Installing collected packages: blessings 07:39:01 INFO - Running setup.py install for blessings 07:39:02 INFO - Successfully installed blessings 07:39:02 INFO - Cleaning up... 07:39:02 INFO - Return code: 0 07:39:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:39:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:39:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:39:02 INFO - Reading from file tmpfile_stdout 07:39:02 INFO - Current package versions: 07:39:02 INFO - blessings == 1.6 07:39:02 INFO - blobuploader == 1.2.4 07:39:02 INFO - docopt == 0.6.1 07:39:02 INFO - functools32 == 3.2.3-2 07:39:02 INFO - jsonschema == 2.5.1 07:39:02 INFO - manifestparser == 1.1 07:39:02 INFO - mozInstall == 1.12 07:39:02 INFO - mozcrash == 0.17 07:39:02 INFO - mozdebug == 0.1 07:39:02 INFO - mozdevice == 0.48 07:39:02 INFO - mozfile == 1.2 07:39:02 INFO - mozhttpd == 0.7 07:39:02 INFO - mozinfo == 0.9 07:39:02 INFO - mozleak == 0.1 07:39:02 INFO - mozlog == 3.2 07:39:02 INFO - moznetwork == 0.27 07:39:02 INFO - mozprocess == 0.23 07:39:02 INFO - mozprofile == 0.28 07:39:02 INFO - mozrunner == 6.12 07:39:02 INFO - mozscreenshot == 0.1 07:39:02 INFO - mozsystemmonitor == 0.3 07:39:02 INFO - moztest == 0.7 07:39:02 INFO - mozversion == 1.4 07:39:02 INFO - psutil == 3.1.1 07:39:02 INFO - requests == 1.2.3 07:39:02 INFO - wsgiref == 0.1.2 07:39:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:39:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:39:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:39:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:39:02 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 0x1084c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe913488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10842ad30>, '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 0x10818fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108422b20>, '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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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:39:02 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:39:02 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:39:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXaeRzbRIE/Render', 07:39:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:39:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:39:02 INFO - 'HOME': '/Users/cltbld', 07:39:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:39:02 INFO - 'LOGNAME': 'cltbld', 07:39:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:39:02 INFO - 'MOZ_NO_REMOTE': '1', 07:39:02 INFO - 'NO_EM_RESTART': '1', 07:39:02 INFO - 'PAGER': '/bin/cat', 07:39:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:39:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:39:02 INFO - 'PWD': '/builds/slave/test', 07:39:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:39:02 INFO - 'SHELL': '/bin/bash', 07:39:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NXGKvbWw8P/Listeners', 07:39:02 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 07:39:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:39:02 INFO - 'USER': 'cltbld', 07:39:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:39:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:39:02 INFO - 'XPC_FLAGS': '0x0', 07:39:02 INFO - 'XPC_SERVICE_NAME': '0', 07:39:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:39:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:39:02 INFO - Cleaning up... 07:39:02 INFO - Return code: 0 07:39:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:39:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:39:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:39:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:39:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1084c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe913488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10842ad30>, '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 0x10818fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108422b20>, '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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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:39:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:39:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:39:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXaeRzbRIE/Render', 07:39:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:39:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:39:02 INFO - 'HOME': '/Users/cltbld', 07:39:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:39:02 INFO - 'LOGNAME': 'cltbld', 07:39:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:39:02 INFO - 'MOZ_NO_REMOTE': '1', 07:39:02 INFO - 'NO_EM_RESTART': '1', 07:39:02 INFO - 'PAGER': '/bin/cat', 07:39:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:39:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:39:02 INFO - 'PWD': '/builds/slave/test', 07:39:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:39:02 INFO - 'SHELL': '/bin/bash', 07:39:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NXGKvbWw8P/Listeners', 07:39:02 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 07:39:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:39:02 INFO - 'USER': 'cltbld', 07:39:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:39:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:39:02 INFO - 'XPC_FLAGS': '0x0', 07:39:02 INFO - 'XPC_SERVICE_NAME': '0', 07:39:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:39:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:39:02 INFO - Cleaning up... 07:39:02 INFO - Return code: 0 07:39:02 INFO - Running post-action listener: _resource_record_post_action 07:39:02 INFO - Running post-action listener: _start_resource_monitoring 07:39:02 INFO - Starting resource monitoring. 07:39:02 INFO - ##### 07:39:02 INFO - ##### Finished create-virtualenv step (success) 07:39:02 INFO - ##### 07:39:02 INFO - ##### 07:39:02 INFO - ##### Running install step. 07:39:02 INFO - ##### 07:39:02 INFO - Running pre-action listener: _resource_record_pre_action 07:39:02 INFO - Running main action method: install 07:39:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:39:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:39:03 INFO - Reading from file tmpfile_stdout 07:39:03 INFO - Detecting whether we're running mozinstall >=1.0... 07:39:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:39:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:39:03 INFO - Reading from file tmpfile_stdout 07:39:03 INFO - Output received: 07:39:03 INFO - Usage: mozinstall [options] installer 07:39:03 INFO - Options: 07:39:03 INFO - -h, --help show this help message and exit 07:39:03 INFO - -d DEST, --destination=DEST 07:39:03 INFO - Directory to install application into. [default: 07:39:03 INFO - "/builds/slave/test"] 07:39:03 INFO - --app=APP Application being installed. [default: firefox] 07:39:03 INFO - mkdir: /builds/slave/test/build/application 07:39:03 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:39:03 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:39:37 INFO - Reading from file tmpfile_stdout 07:39:37 INFO - Output received: 07:39:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:39:37 INFO - Running post-action listener: _resource_record_post_action 07:39:37 INFO - ##### 07:39:37 INFO - ##### Finished install step (success) 07:39:37 INFO - ##### 07:39:37 INFO - ##### 07:39:37 INFO - ##### Running run-tests step. 07:39:37 INFO - ##### 07:39:37 INFO - Running pre-action listener: _resource_record_pre_action 07:39:37 INFO - Running main action method: run_tests 07:39:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:39:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:39:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:39:37 INFO - 2016-07-19 07:39:37.837 screenresolution[2192:14021] starting screenresolution argv=screenresolution get 07:39:37 INFO - 2016-07-19 07:39:37.840 screenresolution[2192:14021] Display 0: 1600x1200x32@60 07:39:37 INFO - 2016-07-19 07:39:37.846 screenresolution[2193:14023] starting screenresolution argv=screenresolution list 07:39:37 INFO - Available Modes on Display 0 07:39:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:39:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:39:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:39:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:39:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:39:38 INFO - Intel Iris: 07:39:38 INFO - Chipset Model: Intel Iris 07:39:38 INFO - Type: GPU 07:39:38 INFO - Bus: Built-In 07:39:38 INFO - VRAM (Dynamic, Max): 1536 MB 07:39:38 INFO - Vendor: Intel (0x8086) 07:39:38 INFO - Device ID: 0x0a2e 07:39:38 INFO - Revision ID: 0x0009 07:39:38 INFO - Displays: 07:39:38 INFO - Display: 07:39:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:39:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:39:38 INFO - Display Serial Number: 1600x1200 60 07:39:38 INFO - Main Display: Yes 07:39:38 INFO - Mirror: Off 07:39:38 INFO - Online: Yes 07:39:38 INFO - Rotation: Supported 07:39:38 INFO - Return code: 0 07:39:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:39:38 INFO - Python 2.7.3 07:39:38 INFO - Return code: 0 07:39:38 INFO - grabbing minidump binary from tooltool 07:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:39:38 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 0x10842ad30>, '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 0x10818fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108422b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:39:38 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:39:38 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:39:38 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:39:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:39:38 INFO - Return code: 0 07:39:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:39:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:39:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:39:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:39:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:39:38 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/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0256', '--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:39:38 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/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0256 --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:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXaeRzbRIE/Render', 07:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:39:38 INFO - 'HOME': '/Users/cltbld', 07:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:39:38 INFO - 'LOGNAME': 'cltbld', 07:39:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:39:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:39:38 INFO - 'MOZ_NO_REMOTE': '1', 07:39:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:39:38 INFO - 'NO_EM_RESTART': '1', 07:39:38 INFO - 'PAGER': '/bin/cat', 07:39:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:39:38 INFO - 'PWD': '/builds/slave/test', 07:39:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:39:38 INFO - 'SHELL': '/bin/bash', 07:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NXGKvbWw8P/Listeners', 07:39:38 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 07:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:39:38 INFO - 'USER': 'cltbld', 07:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:39:38 INFO - 'XPC_FLAGS': '0x0', 07:39:38 INFO - 'XPC_SERVICE_NAME': '0', 07:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:39:38 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/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0256', '--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:39:38 INFO - mozversion application_buildid: 20160719052920 07:39:38 INFO - mozversion application_changeset: efa58477381d54e2d0bd1d89c2d9210a3714e143 07:39:38 INFO - mozversion application_display_name: Nightly 07:39:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:39:38 INFO - mozversion application_name: Firefox 07:39:38 INFO - mozversion application_remotingname: firefox 07:39:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:39:38 INFO - mozversion application_vendor: Mozilla 07:39:38 INFO - mozversion application_version: 50.0a1 07:39:38 INFO - mozversion platform_buildid: 20160719052920 07:39:38 INFO - mozversion platform_changeset: efa58477381d54e2d0bd1d89c2d9210a3714e143 07:39:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:39:38 INFO - mozversion platform_version: 50.0a1 07:39:38 INFO - using testdate: 1468939178 07:39:38 INFO - actual date: 1468939178 07:39:38 INFO - starting webserver on 'localhost:51302' 07:39:38 INFO - SUITE-START | Running 2 tests 07:39:38 INFO - TEST-START | dromaeo_css 07:39:38 INFO - operating with platform_type : mac_ 07:39:38 INFO - Initialising browser for dromaeo_css test... 07:39:38 INFO - TEST-INFO | started process 2203 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpu4gRUw/profile http://localhost:51302/getInfo.html) 07:39:40 INFO - PROCESS | 2203 | __metrics Screen width/height:1600/1200 07:39:40 INFO - PROCESS | 2203 | colorDepth:24 07:39:40 INFO - PROCESS | 2203 | Browser inner width/height: 1024/689 07:39:40 INFO - PROCESS | 2203 | __metrics 07:39:41 INFO - PROCESS | 2203 | 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:39:41 INFO - PROCESS | 2203 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:39:41 INFO - TEST-INFO | 2203: exit 0 07:39:41 INFO - Browser initialized. 07:39:41 INFO - Running cycle 1/1 for dromaeo_css test... 07:39:41 INFO - TEST-INFO | started process 2205 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpu4gRUw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:39:53 INFO - PROCESS | 2205 | RSS: Main: 245231616 07:39:53 INFO - PROCESS | 2205 | 07:40:45 INFO - PROCESS | 2205 | 1468939245865 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:42:56 INFO - PROCESS | 2205 | Cycle 1(1): loaded http://localhost:51302/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51302/tests/dromaeo/cssquery-ext.html) 07:42:57 INFO - PROCESS | 2205 | RSS: Main: 371191808 07:42:57 INFO - PROCESS | 2205 | 07:46:01 INFO - PROCESS | 2205 | Cycle 1(1): loaded http://localhost:51302/tests/dromaeo/cssquery-ext.html (next: http://localhost:51302/tests/dromaeo/cssquery-jquery.html) 07:46:01 INFO - PROCESS | 2205 | RSS: Main: 366161920 07:46:01 INFO - PROCESS | 2205 | 07:49:03 INFO - PROCESS | 2205 | Cycle 1(1): loaded http://localhost:51302/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51302/tests/dromaeo/cssquery-mootools.html) 07:49:04 INFO - PROCESS | 2205 | RSS: Main: 372109312 07:49:04 INFO - PROCESS | 2205 | 07:52:06 INFO - PROCESS | 2205 | Cycle 1(1): loaded http://localhost:51302/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51302/tests/dromaeo/cssquery-prototype.html) 07:52:07 INFO - PROCESS | 2205 | RSS: Main: 371445760 07:52:07 INFO - PROCESS | 2205 | 07:55:10 INFO - PROCESS | 2205 | Cycle 1(1): loaded http://localhost:51302/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51302/tests/dromaeo/cssquery-yui.html) 07:55:11 INFO - PROCESS | 2205 | RSS: Main: 332836864 07:55:11 INFO - PROCESS | 2205 | 07:58:14 INFO - PROCESS | 2205 | Cycle 1(1): loaded http://localhost:51302/tests/dromaeo/cssquery-yui.html (next: http://localhost:51302/tests/dromaeo/cssquery-dojo.html) 07:58:15 INFO - PROCESS | 2205 | RSS: Main: 396800000 07:58:15 INFO - PROCESS | 2205 | 07:58:15 INFO - PROCESS | 2205 | __start_tp_report 07:58:15 INFO - PROCESS | 2205 | _x_x_mozilla_page_load 07:58:15 INFO - PROCESS | 2205 | _x_x_mozilla_page_load_details 07:58:15 INFO - PROCESS | 2205 | |i|pagename|runs| 07:58:15 INFO - PROCESS | 2205 | |0;dojo.html;3330.9999999999995;3340.7995520268787;3352.463605823069;3383.5093911382833;3414.829258537074;5659.8585035374135;5661.433856614347;5742.310922689283;5752.913706294409;5792.304923409197;5748.5688573357065;5772.3361813391475;5876.559258055652;5882.5588080893895;5937.139114828358;4238;5186.2220666900075;5256.448072952342;5275.235090911816;5354.303940487731;5320.600954928376;5354.491323324278;5374.6237763356585;5395.73021348933;5407.810726624569;5168.431472538036;5226.686398816064;5226.712530810804;5243.895122097537;5260.053190425722;4413.29387298034;5189.2475591039465;5195.298634684307;5281.524662780368;5282.841514754564;4969.881776600236;5161.66449180802;5168.508991645787;5184.455632158644;5273.499017593323;5292.92060619091;5694.971525142348;5806.709664516799;5867.442592953663;5881.470667639933;6174.259088909314;6179.351168127327;6194.256689197326;6236.345183755731;6247.4377306042215;6216.751329946796;6222.160008398899;6231.096491008823;6240.781572644935;6241.65670888101;5998.910016349758;6008.369121242206;6017.819465416044;6023.698815059273;6032.276126864805;6177.752889884444;6248.593841400385;6314.431701146918;6328.557001009977;6407.13503677007;6145.385461453909;6173.351798061229;6177.505799536027;6181.752729890754;6194.0708893666315;6142.447179753844;6174.5060395168275;6259.8419292431045;6324.9051264231075;6327.114204011442;6200.844978875474;6249.531285153541;6262.248530176317;6263.686815659199;6313.873722525524;6287.434130928238;6314.968425157845;6318.304986451487;6329.620222786555;6335.588186767845;4856.68431551948;4963.751812409365;4973.0204167123775;4976.527229913152;5025.120603894377;3719.423489359154;4415.735055896592;4426.468823741107;4452.376667266585;4475.48531918827;4945.282933974553;5882.294124705063;5893.14549390336;5900.232969713909;5976.880462390728;6242.750289988349;6275.4038366356035;6286.622802631857;6329.556931014785;6333.524985626096;5775.94224057754;5811.4479124483105;5854.326752423526;5858.47273745365;5888.882222355529;5439.048166570834;5440.347158341025;5455.890882182334;5465.152901300305;5482.616216864782;6243.750249989949;6269.373062693877;6284.057391391328;6354.3645635437015;6363.109164716943;6322.0200868865395;6325.841853953688;6328.525360598066;6343.333949935375;6347.270063942706;6365.395287447685;6366.108744775643;6377.617342959437;6383.585066970632;6389.8722025560155;6129.114290130661;6198.566100372931;6236;6269.373062693694;6283.277423096402;6257.812265632038;6296.527760418078;6296.559240853279;6303.621782693053;6314;6146.139540464428;6201.2868520118955;6227.06594010902;6230.376962303733;6237.5321850860455;6021.939780602138;6132.08018797184;6150.538709596887;6175.722092505758;6231.813045608639;5897.528197744256;6053.273607167168;6057.57596968208;6059.1030384794285;6105.389461053859;6089.086637004485;6231.22109736283;6237.5009999200865;6252.062190671436;6261.3112557617715;5941.58408911372;5946.732397042057;6059.757609695563;6062.484688801501;6076.149339092619 07:58:15 INFO - PROCESS | 2205 | |1;ext.html;488.27247401371955;513.8355726167625;516.7546213625164;520.3677531798863;520.8255234496444;29583.26041848955;32386.83806580967;32488.837555812217;41308.380374294335;42586;38824.80587597058;42304.461732296084;44483;44917;44980.775096124555;9721.465319407413;9890.90109098907;9901.851472227921;9903.45530995795;9914.851277230848;8285.668573257062;8829;9796.26528010402;11188.888111118908;11879.821802672966;11764.647060588153;11803.645890623253;11818.704532386717;11826.11304152192;11844.526218951274;8843.557822108869;12060.155789094766;12100.81848772269;12120.45457954394;12134.514619415213;11606.129540284512;11850.051995840313;11856;12210.389480526028;12213.633590992284;42561.36157957602;42745;42765.57234427647;42767;42861.78569107166;3557.6442355764216;3567.2645183696964;3597.4244120940525;3615.2769446110883;3643.508126402928;32444;32911;33689.831550842086;33697;33712;3194.5498861229817;3429.3998550253855;3440.2259491614436;3459.2735525539665;3475.791452512832;323224.9999999977;376738;380566;381202;381592;62556.37443625597;63048.36951630517;63107;63108;63195.36804631987;64979.02531462032;65508;65508.01737973934;65523.672381637785;65717;16545.172741362883;16582;16813.15934203285;16814.83185168157;16820;11666.125040621988;11833.230839995373;12964.22214667123;13033.869661303455;13058.738825223443;16652.58368540796;16807.831921680627;16831.158442077845;16865.747013794804;16921.576960576083;6295.5907865988565;6363.968180159162;7307.561546307238;7352.77941661751;7384.4830861839155;745.414849343263;985.1924389447747;1047.418682631147;1048.501961568249;1088.1947358954317;30620.38759224803;30865.537016944763;31049.223769405944;31619.367612647617;31628.367432651678;975.512243878061;1069.6844430892904;1136.6192325570862;1183.526589364261;1184.6564496295978;13961.232132232622;14857.108573485626;16299.429519966745;16394.016359018497;16582.66834663324;672062;676342;678416;678940.2635841866;679270;45910.77044614756;61601.69199153975;61612.38387616156;61673.69163154156;61863.6906815463;1263.3241215949422;1305.556110922272;1387.7987691784836;1409.041851540962;1446.2118145610668;31290.530642039932;32209.35581288408;32230.19424514406;32942.67057329396;33039.50440743439;51636;52154.739226305144;52288.431347059646;52291;52436.95126097533;557792;561330;564225;566431;566815;35858;41611.375829361976;42211.934567852055;42356.15287694167;43307.783461082494;48768.2684759736;48928.75535622299;50151.74620634513;50497.495025050746;50654;34489.65510344865;40832.1833563333;40955.59044409518;41700.95336176298;42378.364324535774;40845.774626761246;40978.38532421956;41057;41323.793381032905;42266.73199804011;39862.00344991399;39971;40088.799556002035;40773.592264078165;41514.1697166049;30886;35601;36662.63337366699;42417.787911060244;45497.772511137235;31519.52720709237;31600.841995789873;33487.49768753519;33526.16184595405;34240.65759342375 07:58:15 INFO - PROCESS | 2205 | |2;jquery.html;352.3323302342062;359.12015561873415;379.6658940132683;388.4930166133196;390.66988394806395;15528.611784705374;15805.972611780226;20628.8968555157;22569.322920312377;22610.321690349316;20954.26660066898;21460.57078858422;21557.353279401603;21574.676379854314;21875.9206091624;5379.865503362427;6004.27948646162;6203.007518796993;6256;6258.499320054385;5836.620619659709;6010.489108425789;6169.2596888373455;6178.07328900664;6189.195404597396;6100.572959892809;6174.320824709278;6189.845253868644;6190.380961903797;6202.875942481148;6831.965840170792;6845.383915447634;6848.14398200225;6864.9656751716175;6880.724771009153;5940.762369505258;5952.690408110208;6060.30306514752;6060.515158787331;6097.7865774697675;13608.795868061987;13641.590752277447;13788.586342409744;13819.861801381958;13874.098183618033;2149.623815832223;2278.6354183330754;2448.5837407640743;2732.2076597786618;3145.1350878508592;10321.793564128751;10599;10683;12990.350482475838;14613.342399592044;1865.3098353609116;1870.2145099058428;1905.5236190952262;1932.9697580544582;1958.569114794743;117651.4117429416;118094;120150.39924800408;120337.79662203354;123386;10426.791464170827;11558.248713833575;11644.126690498246;11708.824367634492;11733.237339572901;13715.45138194481;13825.930870345584;14661.48684796027;14670.853291466949;14908.403663853325;8284.212999765012;8550.914490855137;8723.694670686495;8794.34042446819;8872.24585910205;8659.437136586102;8664.566771661392;8693.217610415093;8770.780730481789;8811.20699137068;4536.455625324916;4603.562661547147;4643.808421703745;4655.883602909887;4659.464161621457;1867.327762005677;1902.9238830446627;1926.9903650481658;1951.1317463728506;1957.2171131547495;843.8607629741147;902.0458000049889;927.758782716485;1033.4832583708146;1049.8425236214475;22208.00063997124;22783.430414239778;22803.657945130835;22848.885755571115;22866.885665571564;888.8800011998427;957.1242313283268;987.2496902354262;993.3096497933867;1007.254631572631;9830.75423114428;10766.461676916122;11736.941315293368;11778.52885884555;11842.526298948118;600191;604670;610828.9458552679;617068.8293117101;617625;137782;139293.3035334837;140408.59591403956;141483;142190;983.5623147699348;999.8202121496558;1034.2656713733184;1046.6284469013542;1050.695298363466;20218.393448196577;20797.37607871766;20801.751894886354;20818;20911.8954405227;52299;55093.72453137708;55722.72138639281;55878.72060639671;56399;72211.75047122788;82075.58962205151;82675.5866220665;82944;84121.57939210265;8266.586670666322;8407.579621019047;8434.620442079999;8453.915460845314;8681.913180868363;10500;12707.745845083231;13203.735925281248;13496.46014159462;13531.323433828466;11518.654440366803;11634.418279086181;11664.475098620413;11766.352850593299;11894.881051189723;11370.431478426211;11385.203035787416;11447.942760286478;11480.081593472669;11774.94112529432;8999.955000224956;10412.791744165226;11834;11952.76094478123;11997.580084696994;8366.707165249187;8369.49783013021;8416.537090460084;8445.493270403795;8628.180322869317;8377.539235342114;11517.827232591686;11644.592439264585;11683.941580292385;11911.880881191077 07:58:15 INFO - PROCESS | 2205 | |3;mootools.html;1351.2027903536912;1500.7448733715266;1544.876409887209;1702.9914850425746;1853.1845987765382;7952.165022672622;8452.492850428978;8472.19514146155;8645.65417383304;8891.155340242667;8706.695265665703;8815.250703690193;8838.91161088389;8862.423942443736;8870.15733505316;2334.7781960713746;2357.634566642165;2360.9409764755846;2363.4918492524102;2365.4914193448403;2313.90744370225;2335.988320058397;2348.1429278313376;2352.752960939102;2357.740648528669;2303.965440518385;2321.4892723600865;2334.719833619968;2346.671465994762;2349.577076126305;2294.7246330440453;2314.1206341590087;2317.4785673223473;2322.129201549419;2324.6048171810835;2296.207808306132;2298.138198175684;2302.2172461363048;2307.111761971636;2307.8038366738847;8793.956030219872;8828;8880.555972201364;8907.421017633833;8913.064128266567;5114.948850511521;5296.3379577552805;5297.152455607084;5326.121190003645;5330.200469929503;3483.111806489329;3599.7120230381506;3640.8179591020344;3655.3054919565197;3672.173760903802;1070.1545778834789;1088.4720910358549;1099.1261946752352;1108.1356541897333;1115.9497822598003;11275.43622818864;11345.716357091056;11353.602623908206;11357.829632555518;11383.430828458544;3693.8918324502542;4283.785810709452;4456.710313829591;4470.351798989128;4525.954740452619;8909.955450222837;8968.417052891542;8970.147835955573;8974.237189838937;8988.59551320192;5094.414142373675;5104.05574968632;5105.902231020348;5108.56577190943;5112.079825631422;4369.934450983238;4531.524189960072;4670.953290467051;4799.496052914464;4839;3695.870644527429;3724.156542511474;3797.1646237827636;3820.617938206213;3839.4816699745734;1103.7351035751362;1106.3085571517802;1107.855978722777;1114.1421105748527;1117.284937639911;627.1564745417406;764.4190415284423;919.8896132464014;932.6828878181316;941.3645789092335;5811.27359080115;6004.189434426384;6027.21646185993;6111.113888486234;6227.6263424193785;918.6738707758783;919.4850883505259;972.3825370889537;981.1316984468654;988.4020167798523;3947.802609869495;4077.89805254871;4133.6693064554765;4138.544760076389;4139.048018955657;10738.463076846127;10910;10978.341299522055;11061.225714200089;11064.336139831636;11009.724756881142;11021.173411994132;11026.117910567136;11042;11069;7982.361411087099;8437;8442.957785211034;8445.071042185355;8445.142068744866;5371.973140134274;5440.319960004999;5933.2880054393745;6055.819115272479;6094.329623741296;9118.95440522793;9985.051420115078;10233.74415639615;10254.384736915808;10303.793924121626;8720.909886264217;9337.626494940127;9752.756181095529;9760.755981100529;10446.112080473245;6056.3035250946705;7579.279968402993;7579.393648508217;7581.052368453943;7584.772456826304;8127.349812015143;8202.589870506332;8282.337413007066;8292.295154911659;8300.501969881827;7444.627768611656;7477.476576639583;7485.66314515859;7630.8092297693;7639.083310002835;7283.890741638986;7326.377257933135;7445.4415918805225;7503.212162723054;7514.887276690744;6925.168979722264;6929.376356127973;6997.895031574429;7001.719931202899;7005.054317667254;4843.104025755247;4953.702777833341;4997.550220480029;5025.7989680412375;5033.119204139334;3831.2076524512645;4987.551120399036;5074.771635276347;5134.5635620972635;5202.479752024768 07:58:15 INFO - PROCESS | 2205 | |4;prototype.html;304.4413501225252;307.6723289696473;312.95462157987095;318.53016800219666;320.66490517409306;4091.3862920561933;4537.774800803785;4593.196190666628;4880.414350277967;4904.975475122628;4331.350297455383;4907.8773030674165;4975.228839529869;5215.295935048777;5226.5818734501245;3702.962970370302;3818.8281527331337;3853.49904512413;3978.1446988897383;4012.779297138656;3315.950260746091;3685.806944867813;3968.702347323948;4003.7998100095024;4025.9798701006453;3295.6210035845825;3764.5482542094987;3917.373220284755;3923.8430462781453;3941.2314598653275;3510.596281427643;3722.683571896392;3804.9809750951345;3884;3948.6643635291034;3444.3111377724554;3445.4831775233665;3463.9653603463894;3465.324261768956;3491.3540994915766;4962.454130045692;4985.925211121836;4993.700377977297;5013.448520662724;5073.518015788531;3780.6597406233573;3782.602826703184;3793.9810300948593;4280.850170244058;4560.377782774917;4487.41663583732;5327.46725327468;5364.302640656729;5378.946210537883;5400.972995135038;3223.871644924281;3836.9424458633143;3839.0786211309087;3854.053702880056;3863.517060367454;4264.7654379009455;4735.431748190204;4941.901161976741;5635.098384258499;5675.49599356171;3164.939745185343;3736.3834967230105;3746.2882052409955;3750.868719594801;3778.981105094512;3792.0140763401714;3819.3316169670197;3820.465134881119;3861.4787003754695;3871.9806400967814;4452.9109417812115;4468.575485328888;4476.485204201558;4489.820407183676;4494.820207191741;4309.719868208556;5238.397584277781;5443.374011988592;5454.890902182014;5470.53500452466;1326.9867301327056;1347.8382594088575;1349.743548725739;1354.6951935814363;1356.4302992743071;739.9519031262951;823.4606332851992;840.5251033166242;853.3130829682166;862.9654813807502;4855.854324370204;5041.470645582161;5054.166062599704;5055.865363474596;5056.216286475528;5355.866103347447;5535.391106978229;5557.749901254453;5572.631631556572;5588.664680119125;3786.905327366838;4924.941137655422;5038.5969122470115;5079.085764562415;5092.465291144451;2494.787943024863;2920.1093666431943;2921.0429012561635;2956.1574951138823;2966.5995090662914;5463.2078348639225;5583.106702927512;5780.988327042751;5820.621659592113;5832.533397328203;4702.388689470416;5051.343325367752;5423.783048678088;5746.9425305747245;5807.651540907559;5132.896427268155;5452.509274165344;5766.625169363978;5780.797672081541;5787.247657804459;5363.168708850135;5552.69460179694;5596.0206963780365;5600.85997850057;5624.509504981265;4743.45450273223;5102.69383836971;5219.660722053054;5514.834554963358;5603.943960560342;5345.305110335631;5466.562674986071;5534.142207957612;5640.971795140998;5683.8010669626365;2824.60455536221;3245.6955561551563;3273.9836300818342;3274.5251938469264;3288.1943923738836;4723.551262630044;4911.386076740408;5119.950410165832;5615.747291371817;5639.915401269066;4733.171694953438;5367.927308693247;5447.237386765777;5463.672179669233;5474.233607295061;4268.295731204316;5450.721183870643;5513.972430137824;5535.8892822144135;5556.527695145796;4292.678049146389;4932.556069953722;5335.67985920846;5363.892722145613;5366.309612472059;4366.97376116606;4610.677252592286;5386.326709161355;5411.188321751769;5490.0941344677685;5358.035553600389;5405.918911216257;5419.861829015951;5423.810166644149;5442.537384322219 07:58:15 INFO - PROCESS | 2205 | |5;yui.html;715.2454160860291;749.3143773447296;772.1313522287427;784.0160598448947;841.490898006706;15103.697926041472;16326.020438773667;16332.510024699279;16337.591560211;16362.918185409071;14860.034097783637;14990.250487475638;15005.62485937852;15036.24818759063;15104;880.1462581296148;894.061235702513;894.1594900793266;894.5482531321674;895.7133717210495;869.8651708985118;872.3370238618633;873.1530415496958;875.5709702245886;877.7279043496504;734.2950767263434;755.5202446446518;763.0423818108267;921.5346250143701;926.2034650200821;919.6965001549506;924.6301479408223;935.7707361696359;937.5921474158695;940.2384068904175;688.0230073295932;688.9138857642795;689.1661090081008;689.9241083480813;690.623610132479;12085.758284834343;15165.317560709684;15181.240937953058;15207.771883421758;15218.239088045664;12062.396880155957;12973.286469244187;13456.990725695612;15001.924990374979;15458.613534661723;11543.884561154366;12020.158588898781;12029.639110826689;12197.329146896916;12503.239439427532;3921.705872059607;3923.195744872294;3925.9585015791627;4058.492688413948;4088.713790034699;107674.46162769136;109094.90905090848;113564;114932;115442;10439.37363758177;10490.947545262377;10496.974977968448;10585.576576936835;10662.360258384522;21940.902954852514;23493.7650623495;23500;23541.0583576655;23542.411439714146;8114.675412983533;8210.630521626541;8224.424290299741;8312.25189732927;8376.58117094155;4459.420275364247;4574.88562785933;4612.930806037912;4622.86131416058;4673.065386922561;3161.367726454718;3575.874844380486;3708.2027364116843;3711.4247291669835;3712.7401081924545;685.9519833611599;686.2828344380035;686.4234043403565;692.5567636712508;693.445243804954;557.2338035201598;571.8427432456108;669.6116252573496;684.866451042052;694.836713372357;8443.788905277417;9053.411528250643;9495.050494950588;9599.760005999766;9706.70879873605;531.3915566676134;631.8294060603612;646.9644169570719;699.2587856871733;710.8933659950945;3723.3670276052594;4723.102610503994;4731.929021064687;4765.69023013503;4874.707517548958;91742.08257917335;126464;144255;154664;156716.21641891717;91728.54135729278;109293;133000;135156;139374;106105;109224.81550368948;110477.02853371778;112164;112520.43739781412;8650.740477785677;9648.903510964801;9880.061394167542;9896.851547226941;10122.493875306058;57705.42294577169;57747;58424;59278.40721592729;60630.090548642685;95561.5221923886;99524.50237748765;100355.49822250842;102287;102592;11060.668179954615;13263.933680331536;13468;13538.390772415069;14053.297741965684;13740.862591373958;14333;15208.239588020333;15958.60103497422;16341.346346146496;13571.932140339235;14172.858271417153;14727.410903564167;14870.40518379296;15172.696546069237;14188.148711077369;14285.214313212457;14398.640033998814;14813.03787220005;14992.850071499146;13488.258145801683;13691.657708557366;13963.371648275648;14148.717025659224;14702.55892323232;9707.854382184132;11558.992267398353;11606.361650108987;11613.245139065933;11863;11302.135147700867;11559.595414160463;11775.470103845519;11775.76448471043;11854.585089521825 07:58:15 INFO - PROCESS | 2205 | __end_tp_report 07:58:15 INFO - PROCESS | 2205 | __start_cc_report 07:58:15 INFO - PROCESS | 2205 | _x_x_mozilla_cycle_collect,1372 07:58:15 INFO - PROCESS | 2205 | __end_cc_report 07:58:15 INFO - PROCESS | 2205 | __startTimestamp1468940295100__endTimestamp 07:58:15 INFO - PROCESS | 2205 | 07:58:15 INFO - PROCESS | 2205 | ------- Summary: start ------- 07:58:15 INFO - PROCESS | 2205 | Number of tests: 6 07:58:15 INFO - PROCESS | 2205 | 07:58:15 INFO - PROCESS | 2205 | [#0] dojo.html Cycles:165 Average:5787.13 Median:6059.76 stddev:681.31 (11.2%) stddev-sans-first:655.59 07:58:15 INFO - PROCESS | 2205 | Values: 3331.0 3340.8 3352.5 3383.5 3414.8 5659.9 5661.4 5742.3 5752.9 5792.3 5748.6 5772.3 5876.6 5882.6 5937.1 4238.0 5186.2 5256.4 5275.2 5354.3 5320.6 5354.5 5374.6 5395.7 5407.8 5168.4 5226.7 5226.7 5243.9 5260.1 4413.3 5189.2 5195.3 5281.5 5282.8 4969.9 5161.7 5168.5 5184.5 5273.5 5292.9 5695.0 5806.7 5867.4 5881.5 6174.3 6179.4 6194.3 6236.3 6247.4 6216.8 6222.2 6231.1 6240.8 6241.7 5998.9 6008.4 6017.8 6023.7 6032.3 6177.8 6248.6 6314.4 6328.6 6407.1 6145.4 6173.4 6177.5 6181.8 6194.1 6142.4 6174.5 6259.8 6324.9 6327.1 6200.8 6249.5 6262.2 6263.7 6313.9 6287.4 6315.0 6318.3 6329.6 6335.6 4856.7 4963.8 4973.0 4976.5 5025.1 3719.4 4415.7 4426.5 4452.4 4475.5 4945.3 5882.3 5893.1 5900.2 5976.9 6242.8 6275.4 6286.6 6329.6 6333.5 5775.9 5811.4 5854.3 5858.5 5888.9 5439.0 5440.3 5455.9 5465.2 5482.6 6243.8 6269.4 6284.1 6354.4 6363.1 6322.0 6325.8 6328.5 6343.3 6347.3 6365.4 6366.1 6377.6 6383.6 6389.9 6129.1 6198.6 6236.0 6269.4 6283.3 6257.8 6296.5 6296.6 6303.6 6314.0 6146.1 6201.3 6227.1 6230.4 6237.5 6021.9 6132.1 6150.5 6175.7 6231.8 5897.5 6053.3 6057.6 6059.1 6105.4 6089.1 6231.2 6237.5 6252.1 6261.3 5941.6 5946.7 6059.8 6062.5 6076.1 07:58:15 INFO - PROCESS | 2205 | 07:58:15 INFO - PROCESS | 2205 | [#1] ext.html Cycles:180 Average:69023.30 Median:210208.40 stddev:147276.42 (70.1%) stddev-sans-first:147599.68 07:58:15 INFO - PROCESS | 2205 | Values: 488.3 513.8 516.8 520.4 520.8 29583.3 32386.8 32488.8 41308.4 42586.0 38824.8 42304.5 44483.0 44917.0 44980.8 9721.5 9890.9 9901.9 9903.5 9914.9 8285.7 8829.0 9796.3 11188.9 11879.8 11764.6 11803.6 11818.7 11826.1 11844.5 8843.6 12060.2 12100.8 12120.5 12134.5 11606.1 11850.1 11856.0 12210.4 12213.6 42561.4 42745.0 42765.6 42767.0 42861.8 3557.6 3567.3 3597.4 3615.3 3643.5 32444.0 32911.0 33689.8 33697.0 33712.0 3194.5 3429.4 3440.2 3459.3 3475.8 323225.0 376738.0 380566.0 381202.0 381592.0 62556.4 63048.4 63107.0 63108.0 63195.4 64979.0 65508.0 65508.0 65523.7 65717.0 16545.2 16582.0 16813.2 16814.8 16820.0 11666.1 11833.2 12964.2 13033.9 13058.7 16652.6 16807.8 16831.2 16865.7 16921.6 6295.6 6364.0 7307.6 7352.8 7384.5 745.4 985.2 1047.4 1048.5 1088.2 30620.4 30865.5 31049.2 31619.4 31628.4 975.5 1069.7 1136.6 1183.5 1184.7 13961.2 14857.1 16299.4 16394.0 16582.7 672062.0 676342.0 678416.0 678940.3 679270.0 45910.8 61601.7 61612.4 61673.7 61863.7 1263.3 1305.6 1387.8 1409.0 1446.2 31290.5 32209.4 32230.2 32942.7 33039.5 51636.0 52154.7 52288.4 52291.0 52437.0 557792.0 561330.0 564225.0 566431.0 566815.0 35858.0 41611.4 42211.9 42356.2 43307.8 48768.3 48928.8 50151.7 50497.5 50654.0 34489.7 40832.2 40955.6 41701.0 42378.4 40845.8 40978.4 41057.0 41323.8 42266.7 39862.0 39971.0 40088.8 40773.6 41514.2 30886.0 35601.0 36662.6 42417.8 45497.8 31519.5 31600.8 33487.5 33526.2 34240.7 07:58:15 INFO - PROCESS | 2205 | 07:58:15 INFO - PROCESS | 2205 | [#2] jquery.html Cycles:180 Average:35851.41 Median:12531.03 stddev:102128.01 (815.0%) stddev-sans-first:102379.72 07:58:15 INFO - PROCESS | 2205 | Values: 352.3 359.1 379.7 388.5 390.7 15528.6 15806.0 20628.9 22569.3 22610.3 20954.3 21460.6 21557.4 21574.7 21875.9 5379.9 6004.3 6203.0 6256.0 6258.5 5836.6 6010.5 6169.3 6178.1 6189.2 6100.6 6174.3 6189.8 6190.4 6202.9 6832.0 6845.4 6848.1 6865.0 6880.7 5940.8 5952.7 6060.3 6060.5 6097.8 13608.8 13641.6 13788.6 13819.9 13874.1 2149.6 2278.6 2448.6 2732.2 3145.1 10321.8 10599.0 10683.0 12990.4 14613.3 1865.3 1870.2 1905.5 1933.0 1958.6 117651.4 118094.0 120150.4 120337.8 123386.0 10426.8 11558.2 11644.1 11708.8 11733.2 13715.5 13825.9 14661.5 14670.9 14908.4 8284.2 8550.9 8723.7 8794.3 8872.2 8659.4 8664.6 8693.2 8770.8 8811.2 4536.5 4603.6 4643.8 4655.9 4659.5 1867.3 1902.9 1927.0 1951.1 1957.2 843.9 902.0 927.8 1033.5 1049.8 22208.0 22783.4 22803.7 22848.9 22866.9 888.9 957.1 987.2 993.3 1007.3 9830.8 10766.5 11736.9 11778.5 11842.5 600191.0 604670.0 610828.9 617068.8 617625.0 137782.0 139293.3 140408.6 141483.0 142190.0 983.6 999.8 1034.3 1046.6 1050.7 20218.4 20797.4 20801.8 20818.0 20911.9 52299.0 55093.7 55722.7 55878.7 56399.0 72211.8 82075.6 82675.6 82944.0 84121.6 8266.6 8407.6 8434.6 8453.9 8681.9 10500.0 12707.7 13203.7 13496.5 13531.3 11518.7 11634.4 11664.5 11766.4 11894.9 11370.4 11385.2 11447.9 11480.1 11774.9 9000.0 10412.8 11834.0 11952.8 11997.6 8366.7 8369.5 8416.5 8445.5 8628.2 8377.5 11517.8 11644.6 11683.9 11911.9 07:58:15 INFO - PROCESS | 2205 | 07:58:15 INFO - PROCESS | 2205 | [#3] mootools.html Cycles:180 Average:5652.11 Median:5053.95 stddev:3183.43 (63.0%) stddev-sans-first:3175.95 07:58:15 INFO - PROCESS | 2205 | Values: 1351.2 1500.7 1544.9 1703.0 1853.2 7952.2 8452.5 8472.2 8645.7 8891.2 8706.7 8815.3 8838.9 8862.4 8870.2 2334.8 2357.6 2360.9 2363.5 2365.5 2313.9 2336.0 2348.1 2352.8 2357.7 2304.0 2321.5 2334.7 2346.7 2349.6 2294.7 2314.1 2317.5 2322.1 2324.6 2296.2 2298.1 2302.2 2307.1 2307.8 8794.0 8828.0 8880.6 8907.4 8913.1 5114.9 5296.3 5297.2 5326.1 5330.2 3483.1 3599.7 3640.8 3655.3 3672.2 1070.2 1088.5 1099.1 1108.1 1115.9 11275.4 11345.7 11353.6 11357.8 11383.4 3693.9 4283.8 4456.7 4470.4 4526.0 8910.0 8968.4 8970.1 8974.2 8988.6 5094.4 5104.1 5105.9 5108.6 5112.1 4369.9 4531.5 4671.0 4799.5 4839.0 3695.9 3724.2 3797.2 3820.6 3839.5 1103.7 1106.3 1107.9 1114.1 1117.3 627.2 764.4 919.9 932.7 941.4 5811.3 6004.2 6027.2 6111.1 6227.6 918.7 919.5 972.4 981.1 988.4 3947.8 4077.9 4133.7 4138.5 4139.0 10738.5 10910.0 10978.3 11061.2 11064.3 11009.7 11021.2 11026.1 11042.0 11069.0 7982.4 8437.0 8443.0 8445.1 8445.1 5372.0 5440.3 5933.3 6055.8 6094.3 9119.0 9985.1 10233.7 10254.4 10303.8 8720.9 9337.6 9752.8 9760.8 10446.1 6056.3 7579.3 7579.4 7581.1 7584.8 8127.3 8202.6 8282.3 8292.3 8300.5 7444.6 7477.5 7485.7 7630.8 7639.1 7283.9 7326.4 7445.4 7503.2 7514.9 6925.2 6929.4 6997.9 7001.7 7005.1 4843.1 4953.7 4997.6 5025.8 5033.1 3831.2 4987.6 5074.8 5134.6 5202.5 07:58:15 INFO - PROCESS | 2205 | 07:58:15 INFO - PROCESS | 2205 | [#4] prototype.html Cycles:180 Average:4344.94 Median:4909.63 stddev:1343.97 (27.4%) stddev-sans-first:1313.08 07:58:15 INFO - PROCESS | 2205 | Values: 304.4 307.7 313.0 318.5 320.7 4091.4 4537.8 4593.2 4880.4 4905.0 4331.4 4907.9 4975.2 5215.3 5226.6 3703.0 3818.8 3853.5 3978.1 4012.8 3316.0 3685.8 3968.7 4003.8 4026.0 3295.6 3764.5 3917.4 3923.8 3941.2 3510.6 3722.7 3805.0 3884.0 3948.7 3444.3 3445.5 3464.0 3465.3 3491.4 4962.5 4985.9 4993.7 5013.4 5073.5 3780.7 3782.6 3794.0 4280.9 4560.4 4487.4 5327.5 5364.3 5378.9 5401.0 3223.9 3836.9 3839.1 3854.1 3863.5 4264.8 4735.4 4941.9 5635.1 5675.5 3164.9 3736.4 3746.3 3750.9 3779.0 3792.0 3819.3 3820.5 3861.5 3872.0 4452.9 4468.6 4476.5 4489.8 4494.8 4309.7 5238.4 5443.4 5454.9 5470.5 1327.0 1347.8 1349.7 1354.7 1356.4 740.0 823.5 840.5 853.3 863.0 4855.9 5041.5 5054.2 5055.9 5056.2 5355.9 5535.4 5557.7 5572.6 5588.7 3786.9 4924.9 5038.6 5079.1 5092.5 2494.8 2920.1 2921.0 2956.2 2966.6 5463.2 5583.1 5781.0 5820.6 5832.5 4702.4 5051.3 5423.8 5746.9 5807.7 5132.9 5452.5 5766.6 5780.8 5787.2 5363.2 5552.7 5596.0 5600.9 5624.5 4743.5 5102.7 5219.7 5514.8 5603.9 5345.3 5466.6 5534.1 5641.0 5683.8 2824.6 3245.7 3274.0 3274.5 3288.2 4723.6 4911.4 5120.0 5615.7 5639.9 4733.2 5367.9 5447.2 5463.7 5474.2 4268.3 5450.7 5514.0 5535.9 5556.5 4292.7 4932.6 5335.7 5363.9 5366.3 4367.0 4610.7 5386.3 5411.2 5490.1 5358.0 5405.9 5419.9 5423.8 5442.5 07:58:15 INFO - PROCESS | 2205 | 07:58:15 INFO - PROCESS | 2205 | [#5] yui.html Cycles:180 Average:24659.72 Median:13351.89 stddev:38648.17 (289.5%) stddev-sans-first:38714.77 07:58:15 INFO - PROCESS | 2205 | Values: 715.2 749.3 772.1 784.0 841.5 15103.7 16326.0 16332.5 16337.6 16362.9 14860.0 14990.3 15005.6 15036.2 15104.0 880.1 894.1 894.2 894.5 895.7 869.9 872.3 873.2 875.6 877.7 734.3 755.5 763.0 921.5 926.2 919.7 924.6 935.8 937.6 940.2 688.0 688.9 689.2 689.9 690.6 12085.8 15165.3 15181.2 15207.8 15218.2 12062.4 12973.3 13457.0 15001.9 15458.6 11543.9 12020.2 12029.6 12197.3 12503.2 3921.7 3923.2 3926.0 4058.5 4088.7 107674.5 109094.9 113564.0 114932.0 115442.0 10439.4 10490.9 10497.0 10585.6 10662.4 21940.9 23493.8 23500.0 23541.1 23542.4 8114.7 8210.6 8224.4 8312.3 8376.6 4459.4 4574.9 4612.9 4622.9 4673.1 3161.4 3575.9 3708.2 3711.4 3712.7 686.0 686.3 686.4 692.6 693.4 557.2 571.8 669.6 684.9 694.8 8443.8 9053.4 9495.1 9599.8 9706.7 531.4 631.8 647.0 699.3 710.9 3723.4 4723.1 4731.9 4765.7 4874.7 91742.1 126464.0 144255.0 154664.0 156716.2 91728.5 109293.0 133000.0 135156.0 139374.0 106105.0 109224.8 110477.0 112164.0 112520.4 8650.7 9648.9 9880.1 9896.9 10122.5 57705.4 57747.0 58424.0 59278.4 60630.1 95561.5 99524.5 100355.5 102287.0 102592.0 11060.7 13263.9 13468.0 13538.4 14053.3 13740.9 14333.0 15208.2 15958.6 16341.3 13571.9 14172.9 14727.4 14870.4 15172.7 14188.1 14285.2 14398.6 14813.0 14992.9 13488.3 13691.7 13963.4 14148.7 14702.6 9707.9 11559.0 11606.4 11613.2 11863.0 11302.1 11559.6 11775.5 11775.8 11854.6 07:58:15 INFO - PROCESS | 2205 | -------- Summary: end -------- 07:58:15 INFO - PROCESS | 2205 | 07:58:15 INFO - TEST-INFO | 2205: exit 0 07:58:15 INFO - TEST-OK | dromaeo_css | took 1117320ms 07:58:15 INFO - TEST-START | kraken 07:58:15 INFO - operating with platform_type : mac_ 07:58:15 INFO - Initialising browser for kraken test... 07:58:15 INFO - TEST-INFO | started process 2208 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmp_D3FPO/profile http://localhost:51302/getInfo.html) 07:58:18 INFO - PROCESS | 2208 | __metrics Screen width/height:1600/1200 07:58:18 INFO - PROCESS | 2208 | colorDepth:24 07:58:18 INFO - PROCESS | 2208 | Browser inner width/height: 1024/689 07:58:18 INFO - PROCESS | 2208 | __metrics 07:58:18 INFO - PROCESS | 2208 | 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:58:18 INFO - PROCESS | 2208 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:58:19 INFO - TEST-INFO | 2208: exit 0 07:58:19 INFO - Browser initialized. 07:58:19 INFO - Running cycle 1/1 for kraken test... 07:58:19 INFO - TEST-INFO | started process 2209 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmp_D3FPO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:58:30 INFO - PROCESS | 2209 | RSS: Main: 245260288 07:58:30 INFO - PROCESS | 2209 | 07:58:33 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51302/tests/kraken/driver.html?testName=audio-beat-detection) 07:58:34 INFO - PROCESS | 2209 | RSS: Main: 295657472 07:58:34 INFO - PROCESS | 2209 | 07:58:37 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51302/tests/kraken/driver.html?testName=audio-dft) 07:58:37 INFO - PROCESS | 2209 | RSS: Main: 304664576 07:58:37 INFO - PROCESS | 2209 | 07:58:41 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51302/tests/kraken/driver.html?testName=audio-fft) 07:58:41 INFO - PROCESS | 2209 | RSS: Main: 389980160 07:58:41 INFO - PROCESS | 2209 | 07:58:43 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51302/tests/kraken/driver.html?testName=audio-oscillator) 07:58:44 INFO - PROCESS | 2209 | RSS: Main: 292085760 07:58:44 INFO - PROCESS | 2209 | 07:58:46 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51302/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:58:47 INFO - PROCESS | 2209 | RSS: Main: 296751104 07:58:47 INFO - PROCESS | 2209 | 07:58:49 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51302/tests/kraken/driver.html?testName=imaging-darkroom) 07:58:50 INFO - PROCESS | 2209 | RSS: Main: 332996608 07:58:50 INFO - PROCESS | 2209 | 07:58:53 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51302/tests/kraken/driver.html?testName=imaging-desaturate) 07:58:54 INFO - PROCESS | 2209 | RSS: Main: 335732736 07:58:54 INFO - PROCESS | 2209 | 07:58:56 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51302/tests/kraken/driver.html?testName=json-parse-financial) 07:58:57 INFO - PROCESS | 2209 | RSS: Main: 334233600 07:58:57 INFO - PROCESS | 2209 | 07:58:59 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51302/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:58:59 INFO - PROCESS | 2209 | RSS: Main: 312049664 07:58:59 INFO - PROCESS | 2209 | 07:59:01 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51302/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:59:02 INFO - PROCESS | 2209 | RSS: Main: 318029824 07:59:02 INFO - PROCESS | 2209 | 07:59:04 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51302/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:59:05 INFO - PROCESS | 2209 | RSS: Main: 318693376 07:59:05 INFO - PROCESS | 2209 | 07:59:07 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51302/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:59:08 INFO - PROCESS | 2209 | RSS: Main: 325824512 07:59:08 INFO - PROCESS | 2209 | 07:59:11 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51302/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:59:12 INFO - PROCESS | 2209 | RSS: Main: 328527872 07:59:12 INFO - PROCESS | 2209 | 07:59:13 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:51302/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51302/tests/kraken/driver.html?testName=ai-astar) 07:59:14 INFO - PROCESS | 2209 | RSS: Main: 371834880 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | __start_tp_report 07:59:14 INFO - PROCESS | 2209 | _x_x_mozilla_page_load 07:59:14 INFO - PROCESS | 2209 | _x_x_mozilla_page_load_details 07:59:14 INFO - PROCESS | 2209 | |i|pagename|runs| 07:59:14 INFO - PROCESS | 2209 | |0;ai-astar;102;114;113;113;114;113;112;112;112;112 07:59:14 INFO - PROCESS | 2209 | |1;audio-beat-detection;144;107;106;161;108;106;127;104;139;107 07:59:14 INFO - PROCESS | 2209 | |2;audio-dft;183;186;184;184;183;184;181;181;183;183 07:59:14 INFO - PROCESS | 2209 | |3;audio-fft;84;75;58;90;60;58;59;67;82;70 07:59:14 INFO - PROCESS | 2209 | |4;audio-oscillator;85;87;92;72;75;68;98;71;67;101 07:59:14 INFO - PROCESS | 2209 | |5;imaging-gaussian-blur;88;87;89;86;87;85;88;87;88;84 07:59:14 INFO - PROCESS | 2209 | |6;imaging-darkroom;94;93;103;138;150;101;93;95;94;94 07:59:14 INFO - PROCESS | 2209 | |7;imaging-desaturate;84;86;83;85;83;84;84;85;86;84 07:59:14 INFO - PROCESS | 2209 | |8;json-parse-financial;55;53;54;55;54;52;52;52;53;53 07:59:14 INFO - PROCESS | 2209 | |9;json-stringify-tinderbox;67;69;73;78;74;70;72;71;71;75 07:59:14 INFO - PROCESS | 2209 | |10;stanford-crypto-aes;81;84;85;85;83;84;83;85;83;89 07:59:14 INFO - PROCESS | 2209 | |11;stanford-crypto-ccm;153;150;132;138;134;135;131;139;237;130 07:59:14 INFO - PROCESS | 2209 | |12;stanford-crypto-pbkdf2;164;168;231;182;164;167;166;165;167;166 07:59:14 INFO - PROCESS | 2209 | |13;stanford-crypto-sha256-iterative;69;68;68;67;67;66;67;78;82;89 07:59:14 INFO - PROCESS | 2209 | __end_tp_report 07:59:14 INFO - PROCESS | 2209 | __start_cc_report 07:59:14 INFO - PROCESS | 2209 | _x_x_mozilla_cycle_collect,2098 07:59:14 INFO - PROCESS | 2209 | __end_cc_report 07:59:14 INFO - PROCESS | 2209 | __startTimestamp1468940354203__endTimestamp 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | ------- Summary: start ------- 07:59:14 INFO - PROCESS | 2209 | Number of tests: 14 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.50 (3.1%) stddev-sans-first:0.83 07:59:14 INFO - PROCESS | 2209 | Values: 102.0 114.0 113.0 113.0 114.0 113.0 112.0 112.0 112.0 112.0 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#1] audio-beat-detection Cycles:10 Average:120.90 Median:117.50 stddev:20.52 (17.5%) stddev-sans-first:19.99 07:59:14 INFO - PROCESS | 2209 | Values: 144.0 107.0 106.0 161.0 108.0 106.0 127.0 104.0 139.0 107.0 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.50 stddev:1.48 (0.8%) stddev-sans-first:1.56 07:59:14 INFO - PROCESS | 2209 | Values: 183.0 186.0 184.0 184.0 183.0 184.0 181.0 181.0 183.0 183.0 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#3] audio-fft Cycles:10 Average:70.30 Median:72.50 stddev:11.94 (16.5%) stddev-sans-first:11.58 07:59:14 INFO - PROCESS | 2209 | Values: 84.0 75.0 58.0 90.0 60.0 58.0 59.0 67.0 82.0 70.0 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#4] audio-oscillator Cycles:10 Average:81.60 Median:80.00 stddev:12.65 (15.8%) stddev-sans-first:13.36 07:59:14 INFO - PROCESS | 2209 | Values: 85.0 87.0 92.0 72.0 75.0 68.0 98.0 71.0 67.0 101.0 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:87.50 stddev:1.52 (1.7%) stddev-sans-first:1.56 07:59:14 INFO - PROCESS | 2209 | Values: 88.0 87.0 89.0 86.0 87.0 85.0 88.0 87.0 88.0 84.0 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#6] imaging-darkroom Cycles:10 Average:105.50 Median:93.50 stddev:20.77 (22.2%) stddev-sans-first:21.61 07:59:14 INFO - PROCESS | 2209 | Values: 94.0 93.0 103.0 138.0 150.0 101.0 93.0 95.0 94.0 94.0 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:1.07 (1.3%) stddev-sans-first:1.13 07:59:14 INFO - PROCESS | 2209 | Values: 84.0 86.0 83.0 85.0 83.0 84.0 84.0 85.0 86.0 84.0 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.50 stddev:1.16 (2.2%) stddev-sans-first:1.05 07:59:14 INFO - PROCESS | 2209 | Values: 55.0 53.0 54.0 55.0 54.0 52.0 52.0 52.0 53.0 53.0 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.50 stddev:3.16 (4.4%) stddev-sans-first:2.79 07:59:14 INFO - PROCESS | 2209 | Values: 67.0 69.0 73.0 78.0 74.0 70.0 72.0 71.0 71.0 75.0 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.50 stddev:2.10 (2.5%) stddev-sans-first:1.88 07:59:14 INFO - PROCESS | 2209 | Values: 81.0 84.0 85.0 85.0 83.0 84.0 83.0 85.0 83.0 89.0 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:138.50 stddev:32.26 (23.3%) stddev-sans-first:34.16 07:59:14 INFO - PROCESS | 2209 | Values: 153.0 150.0 132.0 138.0 134.0 135.0 131.0 139.0 237.0 130.0 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.00 Median:167.00 stddev:20.70 (12.4%) stddev-sans-first:21.64 07:59:14 INFO - PROCESS | 2209 | Values: 164.0 168.0 231.0 182.0 164.0 167.0 166.0 165.0 167.0 166.0 07:59:14 INFO - PROCESS | 2209 | 07:59:14 INFO - PROCESS | 2209 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:68.50 stddev:8.01 (11.7%) stddev-sans-first:8.41 07:59:14 INFO - PROCESS | 2209 | Values: 69.0 68.0 68.0 67.0 67.0 66.0 67.0 78.0 82.0 89.0 07:59:14 INFO - PROCESS | 2209 | -------- Summary: end -------- 07:59:14 INFO - PROCESS | 2209 | 07:59:15 INFO - TEST-INFO | 2209: exit 0 07:59:15 INFO - TEST-OK | kraken | took 59415ms 07:59:15 INFO - SUITE-END | took 1176s 07:59:15 INFO - Completed test suite (00:19:36) 07:59:15 INFO - javascript benchmark 07:59:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.9999999999995, 3340.7995520268787, 3352.463605823069, 3383.5093911382833, 3414.829258537074, 5659.8585035374135, 5661.433856614347, 5742.310922689283, 5752.913706294409, 5792.304923409197, 5748.5688573357065, 5772.3361813391475, 5876.559258055652, 5882.5588080893895, 5937.139114828358, 4238.0, 5186.2220666900075, 5256.448072952342, 5275.235090911816, 5354.303940487731, 5320.600954928376, 5354.491323324278, 5374.6237763356585, 5395.73021348933, 5407.810726624569, 5168.431472538036, 5226.686398816064, 5226.712530810804, 5243.895122097537, 5260.053190425722, 4413.29387298034, 5189.2475591039465, 5195.298634684307, 5281.524662780368, 5282.841514754564, 4969.881776600236, 5161.66449180802, 5168.508991645787, 5184.455632158644, 5273.499017593323, 5292.92060619091, 5694.971525142348, 5806.709664516799, 5867.442592953663, 5881.470667639933, 6174.259088909314, 6179.351168127327, 6194.256689197326, 6236.345183755731, 6247.4377306042215, 6216.751329946796, 6222.160008398899, 6231.096491008823, 6240.781572644935, 6241.65670888101, 5998.910016349758, 6008.369121242206, 6017.819465416044, 6023.698815059273, 6032.276126864805, 6177.752889884444, 6248.593841400385, 6314.431701146918, 6328.557001009977, 6407.13503677007, 6145.385461453909, 6173.351798061229, 6177.505799536027, 6181.752729890754, 6194.0708893666315, 6142.447179753844, 6174.5060395168275, 6259.8419292431045, 6324.9051264231075, 6327.114204011442, 6200.844978875474, 6249.531285153541, 6262.248530176317, 6263.686815659199, 6313.873722525524, 6287.434130928238, 6314.968425157845, 6318.304986451487, 6329.620222786555, 6335.588186767845, 4856.68431551948, 4963.751812409365, 4973.0204167123775, 4976.527229913152, 5025.120603894377, 3719.423489359154, 4415.735055896592, 4426.468823741107, 4452.376667266585, 4475.48531918827, 4945.282933974553, 5882.294124705063, 5893.14549390336, 5900.232969713909, 5976.880462390728, 6242.750289988349, 6275.4038366356035, 6286.622802631857, 6329.556931014785, 6333.524985626096, 5775.94224057754, 5811.4479124483105, 5854.326752423526, 5858.47273745365, 5888.882222355529, 5439.048166570834, 5440.347158341025, 5455.890882182334, 5465.152901300305, 5482.616216864782, 6243.750249989949, 6269.373062693877, 6284.057391391328, 6354.3645635437015, 6363.109164716943, 6322.0200868865395, 6325.841853953688, 6328.525360598066, 6343.333949935375, 6347.270063942706, 6365.395287447685, 6366.108744775643, 6377.617342959437, 6383.585066970632, 6389.8722025560155, 6129.114290130661, 6198.566100372931, 6236.0, 6269.373062693694, 6283.277423096402, 6257.812265632038, 6296.527760418078, 6296.559240853279, 6303.621782693053, 6314.0, 6146.139540464428, 6201.2868520118955, 6227.06594010902, 6230.376962303733, 6237.5321850860455, 6021.939780602138, 6132.08018797184, 6150.538709596887, 6175.722092505758, 6231.813045608639, 5897.528197744256, 6053.273607167168, 6057.57596968208, 6059.1030384794285, 6105.389461053859, 6089.086637004485, 6231.22109736283, 6237.5009999200865, 6252.062190671436, 6261.3112557617715, 5941.58408911372, 5946.732397042057, 6059.757609695563, 6062.484688801501, 6076.149339092619], "value": 5741.433152282915, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.27247401371955, 513.8355726167625, 516.7546213625164, 520.3677531798863, 520.8255234496444, 29583.26041848955, 32386.83806580967, 32488.837555812217, 41308.380374294335, 42586.0, 38824.80587597058, 42304.461732296084, 44483.0, 44917.0, 44980.775096124555, 9721.465319407413, 9890.90109098907, 9901.851472227921, 9903.45530995795, 9914.851277230848, 8285.668573257062, 8829.0, 9796.26528010402, 11188.888111118908, 11879.821802672966, 11764.647060588153, 11803.645890623253, 11818.704532386717, 11826.11304152192, 11844.526218951274, 8843.557822108869, 12060.155789094766, 12100.81848772269, 12120.45457954394, 12134.514619415213, 11606.129540284512, 11850.051995840313, 11856.0, 12210.389480526028, 12213.633590992284, 42561.36157957602, 42745.0, 42765.57234427647, 42767.0, 42861.78569107166, 3557.6442355764216, 3567.2645183696964, 3597.4244120940525, 3615.2769446110883, 3643.508126402928, 32444.0, 32911.0, 33689.831550842086, 33697.0, 33712.0, 3194.5498861229817, 3429.3998550253855, 3440.2259491614436, 3459.2735525539665, 3475.791452512832, 323224.9999999977, 376738.0, 380566.0, 381202.0, 381592.0, 62556.37443625597, 63048.36951630517, 63107.0, 63108.0, 63195.36804631987, 64979.02531462032, 65508.0, 65508.01737973934, 65523.672381637785, 65717.0, 16545.172741362883, 16582.0, 16813.15934203285, 16814.83185168157, 16820.0, 11666.125040621988, 11833.230839995373, 12964.22214667123, 13033.869661303455, 13058.738825223443, 16652.58368540796, 16807.831921680627, 16831.158442077845, 16865.747013794804, 16921.576960576083, 6295.5907865988565, 6363.968180159162, 7307.561546307238, 7352.77941661751, 7384.4830861839155, 745.414849343263, 985.1924389447747, 1047.418682631147, 1048.501961568249, 1088.1947358954317, 30620.38759224803, 30865.537016944763, 31049.223769405944, 31619.367612647617, 31628.367432651678, 975.512243878061, 1069.6844430892904, 1136.6192325570862, 1183.526589364261, 1184.6564496295978, 13961.232132232622, 14857.108573485626, 16299.429519966745, 16394.016359018497, 16582.66834663324, 672062.0, 676342.0, 678416.0, 678940.2635841866, 679270.0, 45910.77044614756, 61601.69199153975, 61612.38387616156, 61673.69163154156, 61863.6906815463, 1263.3241215949422, 1305.556110922272, 1387.7987691784836, 1409.041851540962, 1446.2118145610668, 31290.530642039932, 32209.35581288408, 32230.19424514406, 32942.67057329396, 33039.50440743439, 51636.0, 52154.739226305144, 52288.431347059646, 52291.0, 52436.95126097533, 557792.0, 561330.0, 564225.0, 566431.0, 566815.0, 35858.0, 41611.375829361976, 42211.934567852055, 42356.15287694167, 43307.783461082494, 48768.2684759736, 48928.75535622299, 50151.74620634513, 50497.495025050746, 50654.0, 34489.65510344865, 40832.1833563333, 40955.59044409518, 41700.95336176298, 42378.364324535774, 40845.774626761246, 40978.38532421956, 41057.0, 41323.793381032905, 42266.73199804011, 39862.00344991399, 39971.0, 40088.799556002035, 40773.592264078165, 41514.1697166049, 30886.0, 35601.0, 36662.63337366699, 42417.787911060244, 45497.772511137235, 31519.52720709237, 31600.841995789873, 33487.49768753519, 33526.16184595405, 34240.65759342375], "value": 20974.72649301254, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.3323302342062, 359.12015561873415, 379.6658940132683, 388.4930166133196, 390.66988394806395, 15528.611784705374, 15805.972611780226, 20628.8968555157, 22569.322920312377, 22610.321690349316, 20954.26660066898, 21460.57078858422, 21557.353279401603, 21574.676379854314, 21875.9206091624, 5379.865503362427, 6004.27948646162, 6203.007518796993, 6256.0, 6258.499320054385, 5836.620619659709, 6010.489108425789, 6169.2596888373455, 6178.07328900664, 6189.195404597396, 6100.572959892809, 6174.320824709278, 6189.845253868644, 6190.380961903797, 6202.875942481148, 6831.965840170792, 6845.383915447634, 6848.14398200225, 6864.9656751716175, 6880.724771009153, 5940.762369505258, 5952.690408110208, 6060.30306514752, 6060.515158787331, 6097.7865774697675, 13608.795868061987, 13641.590752277447, 13788.586342409744, 13819.861801381958, 13874.098183618033, 2149.623815832223, 2278.6354183330754, 2448.5837407640743, 2732.2076597786618, 3145.1350878508592, 10321.793564128751, 10599.0, 10683.0, 12990.350482475838, 14613.342399592044, 1865.3098353609116, 1870.2145099058428, 1905.5236190952262, 1932.9697580544582, 1958.569114794743, 117651.4117429416, 118094.0, 120150.39924800408, 120337.79662203354, 123386.0, 10426.791464170827, 11558.248713833575, 11644.126690498246, 11708.824367634492, 11733.237339572901, 13715.45138194481, 13825.930870345584, 14661.48684796027, 14670.853291466949, 14908.403663853325, 8284.212999765012, 8550.914490855137, 8723.694670686495, 8794.34042446819, 8872.24585910205, 8659.437136586102, 8664.566771661392, 8693.217610415093, 8770.780730481789, 8811.20699137068, 4536.455625324916, 4603.562661547147, 4643.808421703745, 4655.883602909887, 4659.464161621457, 1867.327762005677, 1902.9238830446627, 1926.9903650481658, 1951.1317463728506, 1957.2171131547495, 843.8607629741147, 902.0458000049889, 927.758782716485, 1033.4832583708146, 1049.8425236214475, 22208.00063997124, 22783.430414239778, 22803.657945130835, 22848.885755571115, 22866.885665571564, 888.8800011998427, 957.1242313283268, 987.2496902354262, 993.3096497933867, 1007.254631572631, 9830.75423114428, 10766.461676916122, 11736.941315293368, 11778.52885884555, 11842.526298948118, 600191.0, 604670.0, 610828.9458552679, 617068.8293117101, 617625.0, 137782.0, 139293.3035334837, 140408.59591403956, 141483.0, 142190.0, 983.5623147699348, 999.8202121496558, 1034.2656713733184, 1046.6284469013542, 1050.695298363466, 20218.393448196577, 20797.37607871766, 20801.751894886354, 20818.0, 20911.8954405227, 52299.0, 55093.72453137708, 55722.72138639281, 55878.72060639671, 56399.0, 72211.75047122788, 82075.58962205151, 82675.5866220665, 82944.0, 84121.57939210265, 8266.586670666322, 8407.579621019047, 8434.620442079999, 8453.915460845314, 8681.913180868363, 10500.0, 12707.745845083231, 13203.735925281248, 13496.46014159462, 13531.323433828466, 11518.654440366803, 11634.418279086181, 11664.475098620413, 11766.352850593299, 11894.881051189723, 11370.431478426211, 11385.203035787416, 11447.942760286478, 11480.081593472669, 11774.94112529432, 8999.955000224956, 10412.791744165226, 11834.0, 11952.76094478123, 11997.580084696994, 8366.707165249187, 8369.49783013021, 8416.537090460084, 8445.493270403795, 8628.180322869317, 8377.539235342114, 11517.827232591686, 11644.592439264585, 11683.941580292385, 11911.880881191077], "value": 9765.421022034645, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.2027903536912, 1500.7448733715266, 1544.876409887209, 1702.9914850425746, 1853.1845987765382, 7952.165022672622, 8452.492850428978, 8472.19514146155, 8645.65417383304, 8891.155340242667, 8706.695265665703, 8815.250703690193, 8838.91161088389, 8862.423942443736, 8870.15733505316, 2334.7781960713746, 2357.634566642165, 2360.9409764755846, 2363.4918492524102, 2365.4914193448403, 2313.90744370225, 2335.988320058397, 2348.1429278313376, 2352.752960939102, 2357.740648528669, 2303.965440518385, 2321.4892723600865, 2334.719833619968, 2346.671465994762, 2349.577076126305, 2294.7246330440453, 2314.1206341590087, 2317.4785673223473, 2322.129201549419, 2324.6048171810835, 2296.207808306132, 2298.138198175684, 2302.2172461363048, 2307.111761971636, 2307.8038366738847, 8793.956030219872, 8828.0, 8880.555972201364, 8907.421017633833, 8913.064128266567, 5114.948850511521, 5296.3379577552805, 5297.152455607084, 5326.121190003645, 5330.200469929503, 3483.111806489329, 3599.7120230381506, 3640.8179591020344, 3655.3054919565197, 3672.173760903802, 1070.1545778834789, 1088.4720910358549, 1099.1261946752352, 1108.1356541897333, 1115.9497822598003, 11275.43622818864, 11345.716357091056, 11353.602623908206, 11357.829632555518, 11383.430828458544, 3693.8918324502542, 4283.785810709452, 4456.710313829591, 4470.351798989128, 4525.954740452619, 8909.955450222837, 8968.417052891542, 8970.147835955573, 8974.237189838937, 8988.59551320192, 5094.414142373675, 5104.05574968632, 5105.902231020348, 5108.56577190943, 5112.079825631422, 4369.934450983238, 4531.524189960072, 4670.953290467051, 4799.496052914464, 4839.0, 3695.870644527429, 3724.156542511474, 3797.1646237827636, 3820.617938206213, 3839.4816699745734, 1103.7351035751362, 1106.3085571517802, 1107.855978722777, 1114.1421105748527, 1117.284937639911, 627.1564745417406, 764.4190415284423, 919.8896132464014, 932.6828878181316, 941.3645789092335, 5811.27359080115, 6004.189434426384, 6027.21646185993, 6111.113888486234, 6227.6263424193785, 918.6738707758783, 919.4850883505259, 972.3825370889537, 981.1316984468654, 988.4020167798523, 3947.802609869495, 4077.89805254871, 4133.6693064554765, 4138.544760076389, 4139.048018955657, 10738.463076846127, 10910.0, 10978.341299522055, 11061.225714200089, 11064.336139831636, 11009.724756881142, 11021.173411994132, 11026.117910567136, 11042.0, 11069.0, 7982.361411087099, 8437.0, 8442.957785211034, 8445.071042185355, 8445.142068744866, 5371.973140134274, 5440.319960004999, 5933.2880054393745, 6055.819115272479, 6094.329623741296, 9118.95440522793, 9985.051420115078, 10233.74415639615, 10254.384736915808, 10303.793924121626, 8720.909886264217, 9337.626494940127, 9752.756181095529, 9760.755981100529, 10446.112080473245, 6056.3035250946705, 7579.279968402993, 7579.393648508217, 7581.052368453943, 7584.772456826304, 8127.349812015143, 8202.589870506332, 8282.337413007066, 8292.295154911659, 8300.501969881827, 7444.627768611656, 7477.476576639583, 7485.66314515859, 7630.8092297693, 7639.083310002835, 7283.890741638986, 7326.377257933135, 7445.4415918805225, 7503.212162723054, 7514.887276690744, 6925.168979722264, 6929.376356127973, 6997.895031574429, 7001.719931202899, 7005.054317667254, 4843.104025755247, 4953.702777833341, 4997.550220480029, 5025.7989680412375, 5033.119204139334, 3831.2076524512645, 4987.551120399036, 5074.771635276347, 5134.5635620972635, 5202.479752024768], "value": 4526.995550111698, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.4413501225252, 307.6723289696473, 312.95462157987095, 318.53016800219666, 320.66490517409306, 4091.3862920561933, 4537.774800803785, 4593.196190666628, 4880.414350277967, 4904.975475122628, 4331.350297455383, 4907.8773030674165, 4975.228839529869, 5215.295935048777, 5226.5818734501245, 3702.962970370302, 3818.8281527331337, 3853.49904512413, 3978.1446988897383, 4012.779297138656, 3315.950260746091, 3685.806944867813, 3968.702347323948, 4003.7998100095024, 4025.9798701006453, 3295.6210035845825, 3764.5482542094987, 3917.373220284755, 3923.8430462781453, 3941.2314598653275, 3510.596281427643, 3722.683571896392, 3804.9809750951345, 3884.0, 3948.6643635291034, 3444.3111377724554, 3445.4831775233665, 3463.9653603463894, 3465.324261768956, 3491.3540994915766, 4962.454130045692, 4985.925211121836, 4993.700377977297, 5013.448520662724, 5073.518015788531, 3780.6597406233573, 3782.602826703184, 3793.9810300948593, 4280.850170244058, 4560.377782774917, 4487.41663583732, 5327.46725327468, 5364.302640656729, 5378.946210537883, 5400.972995135038, 3223.871644924281, 3836.9424458633143, 3839.0786211309087, 3854.053702880056, 3863.517060367454, 4264.7654379009455, 4735.431748190204, 4941.901161976741, 5635.098384258499, 5675.49599356171, 3164.939745185343, 3736.3834967230105, 3746.2882052409955, 3750.868719594801, 3778.981105094512, 3792.0140763401714, 3819.3316169670197, 3820.465134881119, 3861.4787003754695, 3871.9806400967814, 4452.9109417812115, 4468.575485328888, 4476.485204201558, 4489.820407183676, 4494.820207191741, 4309.719868208556, 5238.397584277781, 5443.374011988592, 5454.890902182014, 5470.53500452466, 1326.9867301327056, 1347.8382594088575, 1349.743548725739, 1354.6951935814363, 1356.4302992743071, 739.9519031262951, 823.4606332851992, 840.5251033166242, 853.3130829682166, 862.9654813807502, 4855.854324370204, 5041.470645582161, 5054.166062599704, 5055.865363474596, 5056.216286475528, 5355.866103347447, 5535.391106978229, 5557.749901254453, 5572.631631556572, 5588.664680119125, 3786.905327366838, 4924.941137655422, 5038.5969122470115, 5079.085764562415, 5092.465291144451, 2494.787943024863, 2920.1093666431943, 2921.0429012561635, 2956.1574951138823, 2966.5995090662914, 5463.2078348639225, 5583.106702927512, 5780.988327042751, 5820.621659592113, 5832.533397328203, 4702.388689470416, 5051.343325367752, 5423.783048678088, 5746.9425305747245, 5807.651540907559, 5132.896427268155, 5452.509274165344, 5766.625169363978, 5780.797672081541, 5787.247657804459, 5363.168708850135, 5552.69460179694, 5596.0206963780365, 5600.85997850057, 5624.509504981265, 4743.45450273223, 5102.69383836971, 5219.660722053054, 5514.834554963358, 5603.943960560342, 5345.305110335631, 5466.562674986071, 5534.142207957612, 5640.971795140998, 5683.8010669626365, 2824.60455536221, 3245.6955561551563, 3273.9836300818342, 3274.5251938469264, 3288.1943923738836, 4723.551262630044, 4911.386076740408, 5119.950410165832, 5615.747291371817, 5639.915401269066, 4733.171694953438, 5367.927308693247, 5447.237386765777, 5463.672179669233, 5474.233607295061, 4268.295731204316, 5450.721183870643, 5513.972430137824, 5535.8892822144135, 5556.527695145796, 4292.678049146389, 4932.556069953722, 5335.67985920846, 5363.892722145613, 5366.309612472059, 4366.97376116606, 4610.677252592286, 5386.326709161355, 5411.188321751769, 5490.0941344677685, 5358.035553600389, 5405.918911216257, 5419.861829015951, 5423.810166644149, 5442.537384322219], "value": 3925.9996241372105, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.2454160860291, 749.3143773447296, 772.1313522287427, 784.0160598448947, 841.490898006706, 15103.697926041472, 16326.020438773667, 16332.510024699279, 16337.591560211, 16362.918185409071, 14860.034097783637, 14990.250487475638, 15005.62485937852, 15036.24818759063, 15104.0, 880.1462581296148, 894.061235702513, 894.1594900793266, 894.5482531321674, 895.7133717210495, 869.8651708985118, 872.3370238618633, 873.1530415496958, 875.5709702245886, 877.7279043496504, 734.2950767263434, 755.5202446446518, 763.0423818108267, 921.5346250143701, 926.2034650200821, 919.6965001549506, 924.6301479408223, 935.7707361696359, 937.5921474158695, 940.2384068904175, 688.0230073295932, 688.9138857642795, 689.1661090081008, 689.9241083480813, 690.623610132479, 12085.758284834343, 15165.317560709684, 15181.240937953058, 15207.771883421758, 15218.239088045664, 12062.396880155957, 12973.286469244187, 13456.990725695612, 15001.924990374979, 15458.613534661723, 11543.884561154366, 12020.158588898781, 12029.639110826689, 12197.329146896916, 12503.239439427532, 3921.705872059607, 3923.195744872294, 3925.9585015791627, 4058.492688413948, 4088.713790034699, 107674.46162769136, 109094.90905090848, 113564.0, 114932.0, 115442.0, 10439.37363758177, 10490.947545262377, 10496.974977968448, 10585.576576936835, 10662.360258384522, 21940.902954852514, 23493.7650623495, 23500.0, 23541.0583576655, 23542.411439714146, 8114.675412983533, 8210.630521626541, 8224.424290299741, 8312.25189732927, 8376.58117094155, 4459.420275364247, 4574.88562785933, 4612.930806037912, 4622.86131416058, 4673.065386922561, 3161.367726454718, 3575.874844380486, 3708.2027364116843, 3711.4247291669835, 3712.7401081924545, 685.9519833611599, 686.2828344380035, 686.4234043403565, 692.5567636712508, 693.445243804954, 557.2338035201598, 571.8427432456108, 669.6116252573496, 684.866451042052, 694.836713372357, 8443.788905277417, 9053.411528250643, 9495.050494950588, 9599.760005999766, 9706.70879873605, 531.3915566676134, 631.8294060603612, 646.9644169570719, 699.2587856871733, 710.8933659950945, 3723.3670276052594, 4723.102610503994, 4731.929021064687, 4765.69023013503, 4874.707517548958, 91742.08257917335, 126464.0, 144255.0, 154664.0, 156716.21641891717, 91728.54135729278, 109293.0, 133000.0, 135156.0, 139374.0, 106105.0, 109224.81550368948, 110477.02853371778, 112164.0, 112520.43739781412, 8650.740477785677, 9648.903510964801, 9880.061394167542, 9896.851547226941, 10122.493875306058, 57705.42294577169, 57747.0, 58424.0, 59278.40721592729, 60630.090548642685, 95561.5221923886, 99524.50237748765, 100355.49822250842, 102287.0, 102592.0, 11060.668179954615, 13263.933680331536, 13468.0, 13538.390772415069, 14053.297741965684, 13740.862591373958, 14333.0, 15208.239588020333, 15958.60103497422, 16341.346346146496, 13571.932140339235, 14172.858271417153, 14727.410903564167, 14870.40518379296, 15172.696546069237, 14188.148711077369, 14285.214313212457, 14398.640033998814, 14813.03787220005, 14992.850071499146, 13488.258145801683, 13691.657708557366, 13963.371648275648, 14148.717025659224, 14702.55892323232, 9707.854382184132, 11558.992267398353, 11606.361650108987, 11613.245139065933, 11863.0, 11302.135147700867, 11559.595414160463, 11775.470103845519, 11775.76448471043, 11854.585089521825], "value": 7949.583665909277, "unit": "score"}], "name": "dromaeo_css", "value": 7414.70384766085, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 113.0, 113.0, 114.0, 113.0, 112.0, 112.0, 112.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 107.0, 106.0, 161.0, 108.0, 106.0, 127.0, 104.0, 139.0, 107.0], "value": 120.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 184.0, 184.0, 183.0, 184.0, 181.0, 181.0, 183.0, 183.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 75.0, 58.0, 90.0, 60.0, 58.0, 59.0, 67.0, 82.0, 70.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 92.0, 72.0, 75.0, 68.0, 98.0, 71.0, 67.0, 101.0], "value": 81.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 86.0, 87.0, 85.0, 88.0, 87.0, 88.0, 84.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 103.0, 138.0, 150.0, 101.0, 93.0, 95.0, 94.0, 94.0], "value": 105.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 83.0, 85.0, 83.0, 84.0, 84.0, 85.0, 86.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 54.0, 55.0, 54.0, 52.0, 52.0, 52.0, 53.0, 53.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 73.0, 78.0, 74.0, 70.0, 72.0, 71.0, 71.0, 75.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 85.0, 85.0, 83.0, 84.0, 83.0, 85.0, 83.0, 89.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 150.0, 132.0, 138.0, 134.0, 135.0, 131.0, 139.0, 237.0, 130.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 168.0, 231.0, 182.0, 164.0, 167.0, 166.0, 165.0, 167.0, 166.0], "value": 174.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 67.0, 67.0, 66.0, 67.0, 78.0, 82.0, 89.0], "value": 72.1, "unit": "score"}], "name": "kraken", "value": 1448.0, "alertThreshold": 2.0}]} 07:59:15 INFO - Return code: 0 07:59:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:59:15 INFO - # TBPL SUCCESS # 07:59:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:59:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:59:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:59:15 INFO - 2016-07-19 07:59:15.478 screenresolution[2213:15926] starting screenresolution argv=screenresolution get 07:59:15 INFO - 2016-07-19 07:59:15.481 screenresolution[2213:15926] Display 0: 1600x1200x32@60 07:59:15 INFO - 2016-07-19 07:59:15.487 screenresolution[2214:15929] starting screenresolution argv=screenresolution list 07:59:15 INFO - Available Modes on Display 0 07:59:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:59:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:59:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:59:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:59:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:59:15 INFO - Intel Iris: 07:59:15 INFO - Chipset Model: Intel Iris 07:59:15 INFO - Type: GPU 07:59:15 INFO - Bus: Built-In 07:59:15 INFO - VRAM (Dynamic, Max): 1536 MB 07:59:15 INFO - Vendor: Intel (0x8086) 07:59:15 INFO - Device ID: 0x0a2e 07:59:15 INFO - Revision ID: 0x0009 07:59:15 INFO - Displays: 07:59:15 INFO - Display: 07:59:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:59:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:59:15 INFO - Display Serial Number: 1600x1200 60 07:59:15 INFO - Main Display: Yes 07:59:15 INFO - Mirror: Off 07:59:15 INFO - Online: Yes 07:59:15 INFO - Rotation: Supported 07:59:15 INFO - Return code: 0 07:59:15 INFO - Running post-action listener: _resource_record_post_action 07:59:15 INFO - ##### 07:59:15 INFO - ##### Finished run-tests step (success) 07:59:15 INFO - ##### 07:59:15 INFO - Running post-run listener: _resource_record_post_run 07:59:15 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 758272; Write bytes: 707330560; Read time: 8; Write time: 1980 07:59:15 INFO - TinderboxPrint: CPU usage
53.3% 07:59:15 INFO - TinderboxPrint: I/O read bytes / time
758,272 / 8 07:59:15 INFO - TinderboxPrint: I/O write bytes / time
707,330,560 / 1,980 07:59:15 INFO - TinderboxPrint: CPU idle
2,262.9 (46.7%) 07:59:15 INFO - TinderboxPrint: CPU system
969.1 (20.0%) 07:59:15 INFO - TinderboxPrint: CPU user
1,617.7 (33.4%) 07:59:15 INFO - TinderboxPrint: Swap in / out
408,412,160 / 0 07:59:15 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 388649472; Write bytes: 387214848; Read time: 29149; Write time: 839 07:59:15 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 758272; Write bytes: 292840448; Read time: 8; Write time: 1077 07:59:16 INFO - Running post-run listener: _upload_blobber_files 07:59:16 INFO - Blob upload gear active. 07:59:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:59:16 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:59:16 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:59:16 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:59:16 INFO - (blobuploader) - INFO - Open directory for files ... 07:59:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:59:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:59:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:59:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:59:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:59:17 INFO - (blobuploader) - INFO - Done attempting. 07:59:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:59:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:59:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:59:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:59:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:59:18 INFO - (blobuploader) - INFO - Done attempting. 07:59:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:59:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:59:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:59:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:59:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:59:22 INFO - (blobuploader) - INFO - Done attempting. 07:59:22 INFO - (blobuploader) - INFO - Iteration through files over. 07:59:22 INFO - Return code: 0 07:59:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:59:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:59:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/26cbedc8c5a0c8a88fabf9f9e6efafa577e1e038b7a2c1d4667d5d38b387a7820d2047a28cfebe245e6041a659977ac08c3918d1508a10e8b237213a034e3629", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6e36fc4018b9188eccbd89423d0821db91436c40bc8636f6109454ca9b621f30caac2b1e15df98afe8036f54c93027fb3c3b027eed62095d45345c5d843240a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/17503237ba1d408495cec8e839d817323baa1a634a0bb33844786eae900a9df2cf89eb3f22d96384991523bb731286bf9395ae5efd45777bf3a6fb518e9608c6"} 07:59:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:59:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:59:22 INFO - Contents: 07:59:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/26cbedc8c5a0c8a88fabf9f9e6efafa577e1e038b7a2c1d4667d5d38b387a7820d2047a28cfebe245e6041a659977ac08c3918d1508a10e8b237213a034e3629", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6e36fc4018b9188eccbd89423d0821db91436c40bc8636f6109454ca9b621f30caac2b1e15df98afe8036f54c93027fb3c3b027eed62095d45345c5d843240a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/17503237ba1d408495cec8e839d817323baa1a634a0bb33844786eae900a9df2cf89eb3f22d96384991523bb731286bf9395ae5efd45777bf3a6fb518e9608c6"} 07:59:22 INFO - Running post-run listener: copy_logs_to_upload_dir 07:59:22 INFO - Copying logs to upload dir... 07:59:22 INFO - mkdir: /builds/slave/test/build/upload/logs 07:59:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.481013 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-19 07:59:22.303110) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 07:59:22.309003) ========= 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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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/26cbedc8c5a0c8a88fabf9f9e6efafa577e1e038b7a2c1d4667d5d38b387a7820d2047a28cfebe245e6041a659977ac08c3918d1508a10e8b237213a034e3629", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6e36fc4018b9188eccbd89423d0821db91436c40bc8636f6109454ca9b621f30caac2b1e15df98afe8036f54c93027fb3c3b027eed62095d45345c5d843240a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/17503237ba1d408495cec8e839d817323baa1a634a0bb33844786eae900a9df2cf89eb3f22d96384991523bb731286bf9395ae5efd45777bf3a6fb518e9608c6"} build_url:https://queue.taskcluster.net/v1/task/EDku86KXQNaXo5NFzmQO7w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010444 build_url: 'https://queue.taskcluster.net/v1/task/EDku86KXQNaXo5NFzmQO7w/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/26cbedc8c5a0c8a88fabf9f9e6efafa577e1e038b7a2c1d4667d5d38b387a7820d2047a28cfebe245e6041a659977ac08c3918d1508a10e8b237213a034e3629", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6e36fc4018b9188eccbd89423d0821db91436c40bc8636f6109454ca9b621f30caac2b1e15df98afe8036f54c93027fb3c3b027eed62095d45345c5d843240a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/17503237ba1d408495cec8e839d817323baa1a634a0bb33844786eae900a9df2cf89eb3f22d96384991523bb731286bf9395ae5efd45777bf3a6fb518e9608c6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 07:59:22.343851) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 07:59:22.344128) ========= 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.NXaeRzbRIE/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.NXGKvbWw8P/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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.005018 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 07:59:22.395237) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 07:59:22.395914) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 07:59:22.396710) ========= ========= Total master_lag: 0.83 =========