builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0107 starttime: 1473451488.38 results: success (0) buildid: 20160909105620 builduid: 1da0dc7289d94b90b158b20fb182bb6d revision: 184ffdb1a3acb3aed6c099068cb69758569111e9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 13:04:48.381556) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 13:04:48.382018) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 13:04:48.382305) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 13:04:49.108069) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 13:04:49.108415) ========= 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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-09 13:04:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.01M=0.001s 2016-09-09 13:04:49 (8.01 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095319 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 13:04:49.231297) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 13:04:49.231596) ========= 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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.021082 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 13:04:49.282788) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-09 13:04:49.285169) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 184ffdb1a3acb3aed6c099068cb69758569111e9 --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 184ffdb1a3acb3aed6c099068cb69758569111e9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sBBm6JbHZx/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.l0F9pO0QIB/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-09 13:04:49,361 truncating revision to first 12 chars 2016-09-09 13:04:49,361 Setting DEBUG logging. 2016-09-09 13:04:49,361 attempt 1/10 2016-09-09 13:04:49,361 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/184ffdb1a3ac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-09 13:04:50,930 unpacking tar archive at: fx-team-184ffdb1a3ac/testing/mozharness/ program finished with exit code 0 elapsedTime=1.849980 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-09 13:04:51.160808) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 13:04:51.161170) ========= script_repo_revision: 184ffdb1a3acb3aed6c099068cb69758569111e9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 13:04:51.161638) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 13:04:51.161972) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 13:04:51.180805) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-09 13:04:51.181132) ========= /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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 13:04:51 INFO - MultiFileLogger online at 20160909 13:04:51 in /builds/slave/test 13:04:51 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 13:04:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:04:51 INFO - {'append_to_log': False, 13:04:51 INFO - 'base_work_dir': '/builds/slave/test', 13:04:51 INFO - 'blob_upload_branch': 'Fx-Team', 13:04:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:04:51 INFO - 'branch': 'Fx-Team', 13:04:51 INFO - 'buildbot_json_path': 'buildprops.json', 13:04:51 INFO - 'config_files': ('talos/mac_config.py',), 13:04:51 INFO - 'default_actions': ('clobber', 13:04:51 INFO - 'read-buildbot-config', 13:04:51 INFO - 'download-and-extract', 13:04:51 INFO - 'populate-webroot', 13:04:51 INFO - 'create-virtualenv', 13:04:51 INFO - 'install', 13:04:51 INFO - 'run-tests'), 13:04:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:04:51 INFO - 'download_minidump_stackwalk': True, 13:04:51 INFO - 'download_symbols': 'ondemand', 13:04:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:04:51 INFO - 'tooltool.py': '/tools/tooltool.py', 13:04:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:04:51 INFO - '/tools/misc-python/virtualenv.py')}, 13:04:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:04:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:04:51 INFO - 'installer_path': 'installer.exe', 13:04:51 INFO - 'log_level': 'info', 13:04:51 INFO - 'log_name': 'talos', 13:04:51 INFO - 'log_to_console': True, 13:04:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:04:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:04:51 INFO - 'opt_config_files': (), 13:04:51 INFO - 'pip_index': False, 13:04:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:04:51 INFO - 'cmd': ('bash', 13:04:51 INFO - '-c', 13:04:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:04:51 INFO - 'enabled': True, 13:04:51 INFO - 'halt_on_failure': False, 13:04:51 INFO - 'name': 'check_screen_resolution'},), 13:04:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:04:51 INFO - 'cmd': ('bash', 13:04:51 INFO - '-c', 13:04:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:04:51 INFO - 'enabled': True, 13:04:51 INFO - 'halt_on_failure': False, 13:04:51 INFO - 'name': 'check_screen_resolution'},), 13:04:51 INFO - 'run_cmd_checks_enabled': True, 13:04:51 INFO - 'sps_profile': False, 13:04:51 INFO - 'sps_profile_interval': 0, 13:04:51 INFO - 'suite': 'dromaeojs', 13:04:51 INFO - 'system_bits': '32', 13:04:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:04:51 INFO - 'title': 't-yosemite-r7-0107', 13:04:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:04:51 INFO - 'use_talos_json': True, 13:04:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:04:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:04:51 INFO - 'work_dir': 'build'} 13:04:51 INFO - [mozharness: 2016-09-09 20:04:51.377229Z] Running clobber step. 13:04:51 INFO - Running pre-action listener: _resource_record_pre_action 13:04:51 INFO - Running main action method: clobber 13:04:51 INFO - rmtree: /builds/slave/test/build 13:04:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:04:53 INFO - Running post-action listener: _resource_record_post_action 13:04:53 INFO - [mozharness: 2016-09-09 20:04:53.619575Z] Finished clobber step (success) 13:04:53 INFO - [mozharness: 2016-09-09 20:04:53.619729Z] Running read-buildbot-config step. 13:04:53 INFO - Running pre-action listener: _resource_record_pre_action 13:04:53 INFO - Running main action method: read_buildbot_config 13:04:53 INFO - Using buildbot properties: 13:04:53 INFO - { 13:04:53 INFO - "project": "", 13:04:53 INFO - "product": "firefox", 13:04:53 INFO - "script_repo_revision": "production", 13:04:53 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 13:04:53 INFO - "repository": "", 13:04:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 13:04:53 INFO - "buildid": "20160909105620", 13:04:53 INFO - "pgo_build": "False", 13:04:53 INFO - "basedir": "/builds/slave/test", 13:04:53 INFO - "buildnumber": 934, 13:04:53 INFO - "slavename": "t-yosemite-r7-0107", 13:04:53 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:04:53 INFO - "platform": "yosemite_r7", 13:04:53 INFO - "scheduler": "tests-fx-team-macosx64-talos", 13:04:53 INFO - "branch": "fx-team", 13:04:53 INFO - "repo_path": "integration/fx-team", 13:04:53 INFO - "revision": "184ffdb1a3acb3aed6c099068cb69758569111e9", 13:04:53 INFO - "stage_platform": "macosx64", 13:04:53 INFO - "builduid": "1da0dc7289d94b90b158b20fb182bb6d", 13:04:53 INFO - "slavebuilddir": "test" 13:04:53 INFO - } 13:04:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 13:04:53 INFO - Running post-action listener: _resource_record_post_action 13:04:53 INFO - [mozharness: 2016-09-09 20:04:53.623120Z] Finished read-buildbot-config step (success) 13:04:53 INFO - [mozharness: 2016-09-09 20:04:53.623205Z] Running download-and-extract step. 13:04:53 INFO - Running pre-action listener: _resource_record_pre_action 13:04:53 INFO - Running main action method: download_and_extract 13:04:53 INFO - mkdir: /builds/slave/test/build/tests 13:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:04:53 INFO - trying https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 13:04:53 INFO - Downloading https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 13:04:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 13:04:54 INFO - Downloaded 1199 bytes. 13:04:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 13:04:54 INFO - Using the following test package requirements: 13:04:54 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 13:04:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:04:54 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 13:04:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:04:54 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 13:04:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:04:54 INFO - u'jsshell-mac.zip'], 13:04:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:04:54 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 13:04:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 13:04:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:04:54 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 13:04:54 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:04:54 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 13:04:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:04:54 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 13:04:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:04:54 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 13:04:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:04:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:04:55 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 13:05:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:05:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:05:02 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 13:05:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 13:05:10 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 13:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:11 INFO - trying https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:05:11 INFO - Downloading https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 13:05:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 13:05:13 INFO - Downloaded 111585329 bytes. 13:05:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:05:13 INFO - mkdir: /builds/slave/test/properties 13:05:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:05:13 INFO - Writing to file /builds/slave/test/properties/build_url 13:05:13 INFO - Contents: 13:05:13 INFO - build_url:https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:05:14 INFO - Running post-action listener: _resource_record_post_action 13:05:14 INFO - Running post-action listener: set_extra_try_arguments 13:05:14 INFO - [mozharness: 2016-09-09 20:05:14.608564Z] Finished download-and-extract step (success) 13:05:14 INFO - [mozharness: 2016-09-09 20:05:14.608717Z] Running populate-webroot step. 13:05:14 INFO - Running pre-action listener: _resource_record_pre_action 13:05:14 INFO - Running main action method: populate_webroot 13:05:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:05:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:05:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:05:14 INFO - '--tppagecycles', 13:05:14 INFO - '7'], 13:05:14 INFO - 'tests': ['tsvgm']}}, 13:05:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:05:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:05:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:05:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:05:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:05:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:05:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:14 INFO - 'talos_options': ['--disable-e10s'], 13:05:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:05:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:05:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:14 INFO - 'talos_options': ['--disable-e10s'], 13:05:14 INFO - 'tests': ['damp', 'tps']}, 13:05:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:14 INFO - 'tests': ['damp', 'tps']}, 13:05:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:05:14 INFO - 'tests': ['dromaeo_dom']}, 13:05:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:05:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:05:14 INFO - 'tests': ['basic_compositor_video']}, 13:05:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:05:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:05:14 INFO - 'tests': ['a11yr', 13:05:14 INFO - 'ts_paint', 13:05:14 INFO - 'tpaint', 13:05:14 INFO - 'sessionrestore', 13:05:14 INFO - 'sessionrestore_no_auto_restore', 13:05:14 INFO - 'tabpaint']}, 13:05:14 INFO - 'other-e10s': {'tests': ['a11yr', 13:05:14 INFO - 'ts_paint', 13:05:14 INFO - 'tpaint', 13:05:14 INFO - 'sessionrestore', 13:05:14 INFO - 'sessionrestore_no_auto_restore', 13:05:14 INFO - 'tabpaint']}, 13:05:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:05:14 INFO - 'ts_paint', 13:05:14 INFO - 'tpaint', 13:05:14 INFO - 'sessionrestore', 13:05:14 INFO - 'sessionrestore_no_auto_restore', 13:05:14 INFO - 'tabpaint']}, 13:05:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:05:14 INFO - 'ts_paint', 13:05:14 INFO - 'tpaint', 13:05:14 INFO - 'sessionrestore', 13:05:14 INFO - 'sessionrestore_no_auto_restore', 13:05:14 INFO - 'tabpaint']}, 13:05:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:05:14 INFO - 'tests': ['a11yr', 13:05:14 INFO - 'ts_paint', 13:05:14 INFO - 'tpaint', 13:05:14 INFO - 'sessionrestore', 13:05:14 INFO - 'sessionrestore_no_auto_restore', 13:05:14 INFO - 'tabpaint']}, 13:05:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:05:14 INFO - 'tests': ['a11yr', 13:05:14 INFO - 'ts_paint', 13:05:14 INFO - 'tpaint', 13:05:14 INFO - 'sessionrestore', 13:05:14 INFO - 'sessionrestore_no_auto_restore', 13:05:14 INFO - 'tabpaint']}, 13:05:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:05:14 INFO - 'tests': ['tsvgx', 13:05:14 INFO - 'tsvgr_opacity', 13:05:14 INFO - 'tart', 13:05:14 INFO - 'tscrollx', 13:05:14 INFO - 'cart']}, 13:05:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:05:14 INFO - 'tsvgr_opacity', 13:05:14 INFO - 'tart', 13:05:14 INFO - 'tscrollx', 13:05:14 INFO - 'cart']}, 13:05:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:14 INFO - 'talos_options': ['--disable-e10s'], 13:05:14 INFO - 'tests': ['tp5o']}, 13:05:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:14 INFO - 'tests': ['tp5o']}, 13:05:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:14 INFO - 'talos_options': ['--disable-e10s', 13:05:14 INFO - '--xperf_path', 13:05:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:05:14 INFO - 'tests': ['tp5n']}, 13:05:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:14 INFO - 'talos_options': ['--xperf_path', 13:05:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:05:14 INFO - 'tests': ['tp5n']}}, 13:05:14 INFO - 'talos.zip': {'path': '', 13:05:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:05:14 INFO - Running post-action listener: _resource_record_post_action 13:05:14 INFO - [mozharness: 2016-09-09 20:05:14.627600Z] Finished populate-webroot step (success) 13:05:14 INFO - [mozharness: 2016-09-09 20:05:14.627699Z] Running create-virtualenv step. 13:05:14 INFO - Running pre-action listener: _resource_record_pre_action 13:05:14 INFO - Running main action method: create_virtualenv 13:05:14 INFO - Creating virtualenv /builds/slave/test/build/venv 13:05:14 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 13:05:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:05:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:05:14 INFO - Using real prefix '/tools/python27' 13:05:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:05:15 INFO - Installing distribute.............................................................................................................................................................................................done. 13:05:18 INFO - Installing pip.................done. 13:05:18 INFO - Return code: 0 13:05:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108941a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089d6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed204bfec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a5d30>, '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 0x10860adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889db20>, '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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 13:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:05:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBBm6JbHZx/Render', 13:05:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:18 INFO - 'HOME': '/Users/cltbld', 13:05:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:18 INFO - 'LOGNAME': 'cltbld', 13:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:18 INFO - 'MOZ_NO_REMOTE': '1', 13:05:18 INFO - 'NO_EM_RESTART': '1', 13:05:18 INFO - 'PAGER': '/bin/cat', 13:05:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:18 INFO - 'PWD': '/builds/slave/test', 13:05:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:18 INFO - 'SHELL': '/bin/bash', 13:05:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0F9pO0QIB/Listeners', 13:05:18 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 13:05:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:18 INFO - 'USER': 'cltbld', 13:05:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:18 INFO - 'XPC_FLAGS': '0x0', 13:05:18 INFO - 'XPC_SERVICE_NAME': '0', 13:05:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:18 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 13:05:18 INFO - Cleaning up... 13:05:18 INFO - Return code: 0 13:05:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108941a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089d6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed204bfec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a5d30>, '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 0x10860adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889db20>, '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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 13:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:05:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBBm6JbHZx/Render', 13:05:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:18 INFO - 'HOME': '/Users/cltbld', 13:05:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:18 INFO - 'LOGNAME': 'cltbld', 13:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:18 INFO - 'MOZ_NO_REMOTE': '1', 13:05:18 INFO - 'NO_EM_RESTART': '1', 13:05:18 INFO - 'PAGER': '/bin/cat', 13:05:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:18 INFO - 'PWD': '/builds/slave/test', 13:05:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:18 INFO - 'SHELL': '/bin/bash', 13:05:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0F9pO0QIB/Listeners', 13:05:18 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 13:05:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:18 INFO - 'USER': 'cltbld', 13:05:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:18 INFO - 'XPC_FLAGS': '0x0', 13:05:18 INFO - 'XPC_SERVICE_NAME': '0', 13:05:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:19 INFO - Downloading/unpacking psutil>=3.1.1 13:05:19 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 13:05:19 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 13:05:19 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 13:05:19 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 13:05:19 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 13:05:19 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 13:05:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:05:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:05:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:05:22 INFO - Installing collected packages: psutil 13:05:22 INFO - Running setup.py install for psutil 13:05:22 INFO - building 'psutil._psutil_osx' extension 13:05:22 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 13:05:22 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 13:05:22 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 13:05:22 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 13:05:22 INFO - building 'psutil._psutil_posix' extension 13:05:22 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 13:05:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:05:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:05:22 INFO - ^ 13:05:22 INFO - 1 warning generated. 13:05:22 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 13:05:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:05:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:05:23 INFO - Successfully installed psutil 13:05:23 INFO - Cleaning up... 13:05:23 INFO - Return code: 0 13:05:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108941a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089d6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed204bfec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a5d30>, '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 0x10860adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889db20>, '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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 13:05:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:05:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBBm6JbHZx/Render', 13:05:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:23 INFO - 'HOME': '/Users/cltbld', 13:05:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:23 INFO - 'LOGNAME': 'cltbld', 13:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:23 INFO - 'MOZ_NO_REMOTE': '1', 13:05:23 INFO - 'NO_EM_RESTART': '1', 13:05:23 INFO - 'PAGER': '/bin/cat', 13:05:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:23 INFO - 'PWD': '/builds/slave/test', 13:05:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:23 INFO - 'SHELL': '/bin/bash', 13:05:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0F9pO0QIB/Listeners', 13:05:23 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 13:05:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:23 INFO - 'USER': 'cltbld', 13:05:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:23 INFO - 'XPC_FLAGS': '0x0', 13:05:23 INFO - 'XPC_SERVICE_NAME': '0', 13:05:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:05:23 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 13:05:23 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 13:05:23 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 13:05:23 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 13:05:23 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 13:05:23 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 13:05:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:05:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:05:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:05:25 INFO - Installing collected packages: mozsystemmonitor 13:05:25 INFO - Running setup.py install for mozsystemmonitor 13:05:26 INFO - Successfully installed mozsystemmonitor 13:05:26 INFO - Cleaning up... 13:05:26 INFO - Return code: 0 13:05:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:26 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 0x108941a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089d6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed204bfec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a5d30>, '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 0x10860adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889db20>, '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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 13:05:26 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 13:05:26 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 13:05:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBBm6JbHZx/Render', 13:05:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:26 INFO - 'HOME': '/Users/cltbld', 13:05:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:26 INFO - 'LOGNAME': 'cltbld', 13:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:26 INFO - 'MOZ_NO_REMOTE': '1', 13:05:26 INFO - 'NO_EM_RESTART': '1', 13:05:26 INFO - 'PAGER': '/bin/cat', 13:05:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:26 INFO - 'PWD': '/builds/slave/test', 13:05:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:26 INFO - 'SHELL': '/bin/bash', 13:05:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0F9pO0QIB/Listeners', 13:05:26 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 13:05:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:26 INFO - 'USER': 'cltbld', 13:05:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:26 INFO - 'XPC_FLAGS': '0x0', 13:05:26 INFO - 'XPC_SERVICE_NAME': '0', 13:05:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:26 INFO - Downloading/unpacking jsonschema==2.5.1 13:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:05:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:05:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:05:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:05:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:05:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:05:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:05:29 INFO - Downloading functools32-3.2.3-2.tar.gz 13:05:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:05:29 INFO - warning: no files found matching '*.txt' 13:05:29 INFO - no previously-included directories found matching 'build' 13:05:29 INFO - no previously-included directories found matching 'dist' 13:05:29 INFO - no previously-included directories found matching '.git*' 13:05:29 INFO - Installing collected packages: jsonschema, functools32 13:05:29 INFO - Running setup.py install for functools32 13:05:29 INFO - warning: no files found matching '*.txt' 13:05:29 INFO - no previously-included directories found matching 'build' 13:05:29 INFO - no previously-included directories found matching 'dist' 13:05:29 INFO - no previously-included directories found matching '.git*' 13:05:29 INFO - Successfully installed jsonschema functools32 13:05:29 INFO - Cleaning up... 13:05:29 INFO - Return code: 0 13:05:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108941a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089d6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed204bfec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a5d30>, '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 0x10860adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889db20>, '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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 13:05:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:05:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:05:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBBm6JbHZx/Render', 13:05:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:29 INFO - 'HOME': '/Users/cltbld', 13:05:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:29 INFO - 'LOGNAME': 'cltbld', 13:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:29 INFO - 'MOZ_NO_REMOTE': '1', 13:05:29 INFO - 'NO_EM_RESTART': '1', 13:05:29 INFO - 'PAGER': '/bin/cat', 13:05:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:29 INFO - 'PWD': '/builds/slave/test', 13:05:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:29 INFO - 'SHELL': '/bin/bash', 13:05:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0F9pO0QIB/Listeners', 13:05:29 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 13:05:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:29 INFO - 'USER': 'cltbld', 13:05:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:29 INFO - 'XPC_FLAGS': '0x0', 13:05:29 INFO - 'XPC_SERVICE_NAME': '0', 13:05:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:05:29 INFO - Cleaning up... 13:05:29 INFO - Return code: 0 13:05:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:29 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 0x108941a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089d6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed204bfec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a5d30>, '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 0x10860adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889db20>, '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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 13:05:29 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 13:05:29 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 13:05:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBBm6JbHZx/Render', 13:05:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:29 INFO - 'HOME': '/Users/cltbld', 13:05:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:29 INFO - 'LOGNAME': 'cltbld', 13:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:29 INFO - 'MOZ_NO_REMOTE': '1', 13:05:29 INFO - 'NO_EM_RESTART': '1', 13:05:29 INFO - 'PAGER': '/bin/cat', 13:05:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:29 INFO - 'PWD': '/builds/slave/test', 13:05:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:29 INFO - 'SHELL': '/bin/bash', 13:05:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0F9pO0QIB/Listeners', 13:05:29 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 13:05:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:29 INFO - 'USER': 'cltbld', 13:05:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:29 INFO - 'XPC_FLAGS': '0x0', 13:05:29 INFO - 'XPC_SERVICE_NAME': '0', 13:05:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:30 INFO - Downloading/unpacking blobuploader==1.2.4 13:05:30 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 13:05:30 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 13:05:30 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 13:05:30 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 13:05:30 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 13:05:30 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 13:05:32 INFO - Downloading blobuploader-1.2.4.tar.gz 13:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:05:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:05:32 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 13:05:32 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 13:05:32 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 13:05:32 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 13:05:32 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 13:05:32 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 13:05:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:05:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:05:33 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 13:05:33 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 13:05:33 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 13:05:33 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 13:05:33 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 13:05:33 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 13:05:33 INFO - Downloading docopt-0.6.1.tar.gz 13:05:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:05:33 INFO - Installing collected packages: blobuploader, requests, docopt 13:05:33 INFO - Running setup.py install for blobuploader 13:05:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:05:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:05:34 INFO - Running setup.py install for requests 13:05:34 INFO - Running setup.py install for docopt 13:05:34 INFO - Successfully installed blobuploader requests docopt 13:05:34 INFO - Cleaning up... 13:05:34 INFO - Return code: 0 13:05:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:34 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 0x108941a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089d6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed204bfec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a5d30>, '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 0x10860adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889db20>, '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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 13:05:34 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 13:05:34 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 13:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBBm6JbHZx/Render', 13:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:34 INFO - 'HOME': '/Users/cltbld', 13:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:34 INFO - 'LOGNAME': 'cltbld', 13:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:34 INFO - 'MOZ_NO_REMOTE': '1', 13:05:34 INFO - 'NO_EM_RESTART': '1', 13:05:34 INFO - 'PAGER': '/bin/cat', 13:05:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:34 INFO - 'PWD': '/builds/slave/test', 13:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:34 INFO - 'SHELL': '/bin/bash', 13:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0F9pO0QIB/Listeners', 13:05:34 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 13:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:34 INFO - 'USER': 'cltbld', 13:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:34 INFO - 'XPC_FLAGS': '0x0', 13:05:34 INFO - 'XPC_SERVICE_NAME': '0', 13:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:05:35 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-YSvqGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:05:35 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-DdgoKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:05:35 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-VJGOQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:05:35 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-2TIUaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:05:35 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-cGyk_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:05:35 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-MtwP5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:05:35 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-06eCV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:05:35 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-Y15s16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:05:35 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-Dn4FFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:05:36 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-N0taDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:05:36 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-ypcIbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:05:36 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-DqG9ht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:05:36 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-o_Sw4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:05:36 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-GM8PXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:05:36 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-2wQM1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:05:36 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-WCurai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:05:36 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-UtOd26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:05:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:05:36 INFO - Running setup.py install for manifestparser 13:05:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:05:36 INFO - Running setup.py install for mozcrash 13:05:37 INFO - Running setup.py install for mozdebug 13:05:37 INFO - Running setup.py install for mozdevice 13:05:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:05:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:05:37 INFO - Running setup.py install for mozfile 13:05:37 INFO - Running setup.py install for mozhttpd 13:05:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:05:37 INFO - Running setup.py install for mozinfo 13:05:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:05:37 INFO - Running setup.py install for mozInstall 13:05:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:05:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:05:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:05:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:05:38 INFO - Running setup.py install for mozleak 13:05:38 INFO - Running setup.py install for mozlog 13:05:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:05:38 INFO - Running setup.py install for moznetwork 13:05:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:05:38 INFO - Running setup.py install for mozprocess 13:05:38 INFO - Running setup.py install for mozprofile 13:05:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:05:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:05:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:05:38 INFO - Running setup.py install for mozrunner 13:05:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:05:39 INFO - Running setup.py install for mozscreenshot 13:05:39 INFO - Running setup.py install for moztest 13:05:39 INFO - Running setup.py install for mozversion 13:05:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:05:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:05:39 INFO - Cleaning up... 13:05:39 INFO - Return code: 0 13:05:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:05:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:39 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 0x108941a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089d6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed204bfec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a5d30>, '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 0x10860adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889db20>, '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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 13:05:39 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 13:05:39 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 13:05:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBBm6JbHZx/Render', 13:05:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:39 INFO - 'HOME': '/Users/cltbld', 13:05:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:39 INFO - 'LOGNAME': 'cltbld', 13:05:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:39 INFO - 'MOZ_NO_REMOTE': '1', 13:05:39 INFO - 'NO_EM_RESTART': '1', 13:05:39 INFO - 'PAGER': '/bin/cat', 13:05:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:39 INFO - 'PWD': '/builds/slave/test', 13:05:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:39 INFO - 'SHELL': '/bin/bash', 13:05:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0F9pO0QIB/Listeners', 13:05:39 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 13:05:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:39 INFO - 'USER': 'cltbld', 13:05:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:39 INFO - 'XPC_FLAGS': '0x0', 13:05:39 INFO - 'XPC_SERVICE_NAME': '0', 13:05:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:05:39 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-IT1FGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:05:39 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)) 13:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:05:39 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-4vdzCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:05:39 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)) 13:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:05:39 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-eUh8om-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:05:40 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)) 13:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:05:40 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-C7Wso9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:05:40 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)) 13:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:05:40 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-cjhM2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:05:40 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)) 13:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:05:40 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-XKeIYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:05:40 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)) 13:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:05:40 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-ufEXCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:05:40 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)) 13:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:05:40 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-ybMQV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:05:40 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)) 13:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:05:40 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-kfkHCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:05:40 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)) 13:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:05:40 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-t4mbe5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:05:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:05:40 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-v_EzRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:05:40 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)) 13:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:05:40 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-iEDWSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:05:41 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)) 13:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:05:41 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-nJG65z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:05:41 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)) 13:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:05:41 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-onTKMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:05:41 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)) 13:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:05:41 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-QhLlG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:05:41 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)) 13:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:05:41 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-wxmX0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:05:41 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)) 13:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:05:41 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-nPoaF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:05:41 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)) 13:05:41 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)) 13:05:41 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)) 13:05:41 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)) 13:05:41 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)) 13:05:41 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)) 13:05:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:05: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 13:05: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 13:05: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 13:05: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 13:05: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 13:05: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 13:05:43 INFO - Downloading blessings-1.6.tar.gz 13:05:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:05:44 INFO - Installing collected packages: blessings 13:05:44 INFO - Running setup.py install for blessings 13:05:44 INFO - Successfully installed blessings 13:05:44 INFO - Cleaning up... 13:05:44 INFO - Return code: 0 13:05:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:05:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:05:44 INFO - Reading from file tmpfile_stdout 13:05:44 INFO - Current package versions: 13:05:44 INFO - blessings == 1.6 13:05:44 INFO - blobuploader == 1.2.4 13:05:44 INFO - docopt == 0.6.1 13:05:44 INFO - functools32 == 3.2.3-2 13:05:44 INFO - jsonschema == 2.5.1 13:05:44 INFO - manifestparser == 1.1 13:05:44 INFO - mozInstall == 1.12 13:05:44 INFO - mozcrash == 0.17 13:05:44 INFO - mozdebug == 0.1 13:05:44 INFO - mozdevice == 0.48 13:05:44 INFO - mozfile == 1.2 13:05:44 INFO - mozhttpd == 0.7 13:05:44 INFO - mozinfo == 0.9 13:05:44 INFO - mozleak == 0.1 13:05:44 INFO - mozlog == 3.3 13:05:44 INFO - moznetwork == 0.27 13:05:44 INFO - mozprocess == 0.23 13:05:44 INFO - mozprofile == 0.28 13:05:44 INFO - mozrunner == 6.12 13:05:44 INFO - mozscreenshot == 0.1 13:05:44 INFO - mozsystemmonitor == 0.3 13:05:44 INFO - moztest == 0.7 13:05:44 INFO - mozversion == 1.4 13:05:44 INFO - psutil == 3.1.1 13:05:44 INFO - requests == 1.2.3 13:05:44 INFO - wsgiref == 0.1.2 13:05:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:44 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 0x108941a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089d6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed204bfec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a5d30>, '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 0x10860adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889db20>, '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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 13:05:44 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 13:05:44 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 13:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBBm6JbHZx/Render', 13:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:44 INFO - 'HOME': '/Users/cltbld', 13:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:44 INFO - 'LOGNAME': 'cltbld', 13:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:44 INFO - 'MOZ_NO_REMOTE': '1', 13:05:44 INFO - 'NO_EM_RESTART': '1', 13:05:44 INFO - 'PAGER': '/bin/cat', 13:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:44 INFO - 'PWD': '/builds/slave/test', 13:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:44 INFO - 'SHELL': '/bin/bash', 13:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0F9pO0QIB/Listeners', 13:05:44 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 13:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:44 INFO - 'USER': 'cltbld', 13:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:44 INFO - 'XPC_FLAGS': '0x0', 13:05:44 INFO - 'XPC_SERVICE_NAME': '0', 13:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:44 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)) 13:05:44 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)) 13:05:44 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)) 13:05:44 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)) 13:05:44 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)) 13:05:44 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)) 13:05:44 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)) 13:05:44 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)) 13:05:44 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)) 13:05:44 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)) 13:05:44 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)) 13:05:44 INFO - Cleaning up... 13:05:44 INFO - Return code: 0 13:05:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108941a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089d6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed204bfec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a5d30>, '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 0x10860adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889db20>, '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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 13:05: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'] in /builds/slave/test/build 13:05: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 13:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBBm6JbHZx/Render', 13:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:44 INFO - 'HOME': '/Users/cltbld', 13:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:44 INFO - 'LOGNAME': 'cltbld', 13:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:44 INFO - 'MOZ_NO_REMOTE': '1', 13:05:44 INFO - 'NO_EM_RESTART': '1', 13:05:44 INFO - 'PAGER': '/bin/cat', 13:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:44 INFO - 'PWD': '/builds/slave/test', 13:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:44 INFO - 'SHELL': '/bin/bash', 13:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0F9pO0QIB/Listeners', 13:05:44 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 13:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:44 INFO - 'USER': 'cltbld', 13:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:44 INFO - 'XPC_FLAGS': '0x0', 13:05:44 INFO - 'XPC_SERVICE_NAME': '0', 13:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:05:45 INFO - Cleaning up... 13:05:45 INFO - Return code: 0 13:05:45 INFO - Running post-action listener: _resource_record_post_action 13:05:45 INFO - Running post-action listener: _start_resource_monitoring 13:05:45 INFO - Starting resource monitoring. 13:05:45 INFO - [mozharness: 2016-09-09 20:05:45.236922Z] Finished create-virtualenv step (success) 13:05:45 INFO - [mozharness: 2016-09-09 20:05:45.238001Z] Running install step. 13:05:45 INFO - Running pre-action listener: _resource_record_pre_action 13:05:45 INFO - Running main action method: install 13:05:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:05:45 INFO - Reading from file tmpfile_stdout 13:05:45 INFO - Detecting whether we're running mozinstall >=1.0... 13:05:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:05:45 INFO - Reading from file tmpfile_stdout 13:05:45 INFO - Output received: 13:05:45 INFO - Usage: mozinstall [options] installer 13:05:45 INFO - Options: 13:05:45 INFO - -h, --help show this help message and exit 13:05:45 INFO - -d DEST, --destination=DEST 13:05:45 INFO - Directory to install application into. [default: 13:05:45 INFO - "/builds/slave/test"] 13:05:45 INFO - --app=APP Application being installed. [default: firefox] 13:05:45 INFO - mkdir: /builds/slave/test/build/application 13:05:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:06:19 INFO - Reading from file tmpfile_stdout 13:06:19 INFO - Output received: 13:06:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:06:19 INFO - Running post-action listener: _resource_record_post_action 13:06:19 INFO - [mozharness: 2016-09-09 20:06:19.688186Z] Finished install step (success) 13:06:19 INFO - [mozharness: 2016-09-09 20:06:19.688333Z] Running run-tests step. 13:06:19 INFO - Running pre-action listener: _resource_record_pre_action 13:06:19 INFO - Running main action method: run_tests 13:06:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:06:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:06:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:06:19 INFO - 2016-09-09 13:06:19.703 screenresolution[1917:18333] starting screenresolution argv=screenresolution get 13:06:19 INFO - 2016-09-09 13:06:19.705 screenresolution[1917:18333] Display 0: 1600x1200x32@60 13:06:19 INFO - 2016-09-09 13:06:19.711 screenresolution[1918:18335] starting screenresolution argv=screenresolution list 13:06:19 INFO - Available Modes on Display 0 13:06:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:06:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:06:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:06:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:06:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:06:19 INFO - Intel Iris: 13:06:19 INFO - Chipset Model: Intel Iris 13:06:19 INFO - Type: GPU 13:06:19 INFO - Bus: Built-In 13:06:19 INFO - VRAM (Dynamic, Max): 1536 MB 13:06:19 INFO - Vendor: Intel (0x8086) 13:06:19 INFO - Device ID: 0x0a2e 13:06:19 INFO - Revision ID: 0x0009 13:06:19 INFO - Displays: 13:06:19 INFO - Display: 13:06:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:06:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:06:19 INFO - Display Serial Number: 1600x1200 60 13:06:19 INFO - Main Display: Yes 13:06:19 INFO - Mirror: Off 13:06:19 INFO - Online: Yes 13:06:19 INFO - Rotation: Supported 13:06:19 INFO - Return code: 0 13:06:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:06:19 INFO - Python 2.7.3 13:06:19 INFO - Return code: 0 13:06:19 INFO - grabbing minidump binary from tooltool 13:06:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:19 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 0x1088a5d30>, '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 0x10860adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:06:19 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 13:06:19 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 13:06:19 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 13:06:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:06:20 INFO - Return code: 0 13:06:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:06:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:06:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:06:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:06:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:06:20 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/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--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 13:06:20 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/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0107 --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 13:06:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBBm6JbHZx/Render', 13:06:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:20 INFO - 'HOME': '/Users/cltbld', 13:06:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:20 INFO - 'LOGNAME': 'cltbld', 13:06:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:06:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:20 INFO - 'MOZ_NO_REMOTE': '1', 13:06:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:06:20 INFO - 'NO_EM_RESTART': '1', 13:06:20 INFO - 'PAGER': '/bin/cat', 13:06:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:20 INFO - 'PWD': '/builds/slave/test', 13:06:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:06:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:20 INFO - 'SHELL': '/bin/bash', 13:06:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l0F9pO0QIB/Listeners', 13:06:20 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 13:06:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:20 INFO - 'USER': 'cltbld', 13:06:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:20 INFO - 'XPC_FLAGS': '0x0', 13:06:20 INFO - 'XPC_SERVICE_NAME': '0', 13:06:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:20 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/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--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 13:06:20 INFO - mozversion application_buildid: 20160909105620 13:06:20 INFO - mozversion application_changeset: 184ffdb1a3acb3aed6c099068cb69758569111e9 13:06:20 INFO - mozversion application_display_name: Nightly 13:06:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:06:20 INFO - mozversion application_name: Firefox 13:06:20 INFO - mozversion application_remotingname: firefox 13:06:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:06:20 INFO - mozversion application_vendor: Mozilla 13:06:20 INFO - mozversion application_version: 51.0a1 13:06:20 INFO - mozversion platform_buildid: 20160909105620 13:06:20 INFO - mozversion platform_changeset: 184ffdb1a3acb3aed6c099068cb69758569111e9 13:06:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:06:20 INFO - mozversion platform_version: 51.0a1 13:06:20 INFO - using testdate: 1473451580 13:06:20 INFO - actual date: 1473451580 13:06:20 INFO - starting webserver on 'localhost:57262' 13:06:20 INFO - SUITE-START | Running 2 tests 13:06:20 INFO - TEST-START | dromaeo_css 13:06:20 INFO - operating with platform_type : mac_ 13:06:20 INFO - Initialising browser for dromaeo_css test... 13:06:20 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpoqKRpd/profile http://localhost:57262/getInfo.html) 13:06:22 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 13:06:22 INFO - PROCESS | 1928 | colorDepth:24 13:06:22 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 13:06:22 INFO - PROCESS | 1928 | __metrics 13:06:22 INFO - PROCESS | 1928 | 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" 13:06:22 INFO - PROCESS | 1928 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:06:23 INFO - TEST-INFO | 1928: exit 0 13:06:23 INFO - Browser initialized. 13:06:23 INFO - Running cycle 1/1 for dromaeo_css test... 13:06:23 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpoqKRpd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:06:35 INFO - PROCESS | 1930 | RSS: Main: 232390656 13:06:35 INFO - PROCESS | 1930 | 13:07:26 INFO - PROCESS | 1930 | 1473451646934 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] 13:09:38 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57262/tests/dromaeo/cssquery-ext.html) 13:09:39 INFO - PROCESS | 1930 | RSS: Main: 367665152 13:09:39 INFO - PROCESS | 1930 | 13:12:42 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-ext.html (next: http://localhost:57262/tests/dromaeo/cssquery-jquery.html) 13:12:43 INFO - PROCESS | 1930 | RSS: Main: 367558656 13:12:43 INFO - PROCESS | 1930 | 13:15:45 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57262/tests/dromaeo/cssquery-mootools.html) 13:15:46 INFO - PROCESS | 1930 | RSS: Main: 359636992 13:15:46 INFO - PROCESS | 1930 | 13:18:48 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57262/tests/dromaeo/cssquery-prototype.html) 13:18:48 INFO - PROCESS | 1930 | RSS: Main: 347471872 13:18:48 INFO - PROCESS | 1930 | 13:21:52 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57262/tests/dromaeo/cssquery-yui.html) 13:21:52 INFO - PROCESS | 1930 | RSS: Main: 327839744 13:21:52 INFO - PROCESS | 1930 | 13:24:55 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-yui.html (next: http://localhost:57262/tests/dromaeo/cssquery-dojo.html) 13:24:56 INFO - PROCESS | 1930 | RSS: Main: 434298880 13:24:56 INFO - PROCESS | 1930 | 13:24:56 INFO - PROCESS | 1930 | __start_tp_report 13:24:56 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 13:24:56 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 13:24:56 INFO - PROCESS | 1930 | |i|pagename|runs| 13:24:56 INFO - PROCESS | 1930 | |0;dojo.html;3105.441020616289;3246.4643333849917;3289.4079065768183;3306.2891478332153;3351.899443016709;5423.620346575742;5590.832275031746;5620.718964051796;5713.028785106526;5728.541716662668;5538.9169162462595;5698.715064246781;5777;5779.190913272135;5837.854053648661;5112.616553758465;5131.025105230013;5137.897242055156;5227.581793456533;5227.86930326743;5276.577873770089;5283.63014588979;5294.867628309304;5306.018386598473;5309.654872433299;5099.5410413063;5226.973865130688;5235.057689615867;5248.239005344223;5326.467353264681;5099.643024988252;5124.359455068116;5173.275741396207;5173.922391164136;5176.120059589879;5181.740912954338;5194.71429071401;5203.557697595709;5224.73876306187;5265.3681558212875;4684.4378674559275;5673.744681489302;5679.829605111853;5703.429657034305;5718.799842005551;6097.706167156134;6117.480014198754;6135.477655820289;6188.81433556994;6270.78052268173;5022.497750224985;6232.252129744415;6344.111824344595;6347.492200623985;6357.141785858896;5819.0689489681445;5827.592068555203;5842.941570584282;5849.034909239927;5852.209951656557;6131.662758548234;6255.843603909848;6261.655608941552;6318.241810982711;6348.4286414222015;6088.878222435526;6153.107799369066;6185.288691800411;6191.288001879751;6211.937880621226;5691.747815480588;5732.828015159552;6315.431611155018;6320.146780184617;6329.841753956096;5290.338707661543;6009.759609615654;6098.0147959968335;6230.501559875199;6243.500519958392;5194.532492075731;6076.696165191723;6153.98459254227;6190.566660333823;6263.592866463665;4944.554990050912;4944.579710724557;4944.703317800871;4957.281194226817;5015.14742493777;4463.843765468193;4467.30756732707;4494.842680506166;4506.076254367847;4546.02260513991;5558.388577256498;5624.3813180550105;5633.070543360382;5633.549316054706;5724.427557244242;6197.752089916353;6284.654344011032;6295.811125666238;6303.590266632654;6322.178116844781;5683.914741278884;5691.857703557444;5694.259746233046;5706.828795136153;5732.111522713903;5309.761060752276;5349.946500535023;5363.356397232357;5434.673919564839;5442.809501667422;6219.346968568235;6296.5592408530965;6307.810765677037;6312.968435157794;6328.272248691459;6301.558890877594;6332.936670633235;6344.524160687874;6347.270063942706;6363.427291543783;6226.066090086342;6303.180586523723;6340.397662222082;6342.841428964312;6348.904766428415;6075.058365953284;6101.237345331833;6112.633242005316;6124.319907527303;6232.002879539252;6236.251649802053;6319.115323854572;6322.715477803418;6340.714667839866;6345.714442850175;4740.431148262231;6135.478484328882;6192.195014648067;6199.442050215503;6203.689815509116;5971.58198926071;5988.221531200916;6131.417515336036;6147.354527774698;6188.597741146811;5892.145638882253;5917.3490915998345;5942.405759424023;5946.583739138218;6093.086037094471;6193.907091393543;6194.81415557534;6195.2255968004;6196.845078873064;6212.72042758086;5971.640203183596;6019.608725432833;6020.247469066367;6036.245469316335;6044.184035155374 13:24:56 INFO - PROCESS | 1930 | |1;ext.html;461.68374663355604;507.852722710414;517.8524120625622;523.2099529710138;525.8212207849331;28469.715302846966;40911.38632920505;42053.36919946203;42083.99999999996;42130.7893460533;43210.351844722354;43626.78186609071;43691.12617747643;43729.344059839124;43821.34267985983;9707.951460242706;9886.604535818558;9901;9901.059399357086;9910.157636600898;8777.42946708465;8954.19412252897;12008.879911200864;12221.388930553481;12238;10787.244892857503;11785.705357366049;12125.514979400814;12202.816957745597;12219;12251.448684809206;12330.753384932252;12340.197887137307;12349.938250308736;12353;11902.285862848257;12102.81845772314;12104.515819367214;12105;12109.878901211052;40738.38892416616;41078;41093.972650683674;41097;41118.588814111776;3238.5304130901122;3310.7682462227654;3364.310316385135;3386.8306584670668;3500.562429696288;30410.69589304123;31568.684313157035;31659.683403166135;31735.682643173735;31769.6823031769;3313.2379552702773;3321.2527181384235;3382.830858457092;3418.9658103418897;3419.8803041893416;373861;378433;378451.10774446226;379335;384574;48775.756121219165;58538.7073064632;58886;58888.7055564728;58901.70549147313;64173.67913160404;64175;64221.35778642154;64295.67852160709;64463;16124.193790310437;16362.590935226715;16382.672346553003;16393.180340982904;16397.91801040987;11328.773424531464;11600.18798684084;12634;12678.239305641691;12699.238045717102;15327.923360383349;15433.074015559103;15455;15493.380264789508;16977.49067527976;5726.914096288559;6887.621180835102;6916.956539122871;6939.6877140528795;6948.374646281859;820.8016296207604;844.6452489954111;854.6581367453068;875.5840975536569;987.8666380038746;27945;28797.536381860846;29009.56485652717;29735.25661858471;29833.701662983527;1031.896810318962;1057.3021805608262;1061.3472714280815;1072.6781965410346;1125.2517076144436;14251.928740356232;14904;14956.626084347761;15120.319585618674;15321.233938303263;623060;627535;627779;627987;847975;46590;55970.44029559652;55976.44023559794;55987.44012559822;60105;1194.3013337197663;1198.748262864808;1334.5129027904939;1383.1355402873205;1391.7007843313736;30068.332595095224;30713.232169195948;30912.845435772677;31406.214844629067;31739.523907141873;50065.74967125141;50650;50745;50797;50943.490565093874;420350;511466;511769;512408;512999;25157.11950081738;25390.492190156463;25425.11012114567;25744.356391090372;25764.484710306064;45578.86052848705;45895.770521147184;46106.7694661538;46400;46437.76781116208;36565.634343656224;37463.43804842984;38561.421578676905;38688.41967370435;40007.39988900227;31406.900758473344;34752.78365257305;35268;37748.81125594355;38883;36302.45546316754;36470.63529364672;37321.440178396806;38458;38550;33015.33969320648;33619.49570756392;33894.049961395925;34383;34670;24964.37589060288;25001.374965626004;25041;25464.872675636503;25542.361440964123 13:24:56 INFO - PROCESS | 1930 | |2;jquery.html;350.43055035567204;369.9223163135742;372.62365011338557;379.6981399787168;401.3598310694441;15046.623834404132;15248.771268430983;16610.16949152542;20808.37574872756;21210.57578848422;16272.43046493373;19066.809331906676;19514.414567562995;20145.19419223233;20822.479438014056;5465.988792073479;5955.910661340084;6221.688915554227;6222.595531290452;6225.719842607071;6024.427679370453;6162.876742465148;6228.626282423046;6229.781957631484;6234.625922444645;5827.35899051104;6139.508839292846;6210.968945155268;6213.937860621381;6218.31598524162;6168.198134242542;6199.256089269294;6210.78262260821;6211.9068213976825;6216.160818289563;5817.825465236049;5915.349311575723;6037.7584896604085;6044.546659000593;6063.514918806529;12862.099653024292;13104.213747175105;13107.803382949263;23704.525909481712;24830.5033899321;2309.9653505197434;2377.7741114594087;2504.5742223821812;2617.450335429562;2847.8433686147255;9863.654772083015;10356.17150627948;10449.425281609509;12302.569410070604;14723.786597718652;1766.0904634113442;1855.879367841086;1857.888526688403;1916.2718167096414;1923.5287354598074;103311.48344258306;103476.41308967261;105327;117499.23751143824;119119;11280.830787538194;11288.717782055513;11452.141089418326;11513.597024104283;11645.767084658259;14462.05996610217;14463.855361446462;14501.927490362481;14506.202158881362;14508.709825803637;8044.31623312025;8408.453450525696;8409.075001749925;8488.915110848937;8494.702685406104;8220.58897055145;8222.712205072914;8223.835523289621;8374.330053595697;8416.116307787717;3821.7197238925273;4431.468223813099;4480.4623445186135;4483.30508771141;4490.7754612269255;1565.6085978505373;1637.287779815784;1884.632496663165;1893.8390236829748;1929.9710504342447;671.77495538994;722.7795522365624;822.7943014246439;823.47297729452;962.43216502264;20282.188712451338;21585.460363490725;21608.354203845935;21638.32148534753;21798.237061702763;892.6295587331185;927.7634203278121;975.1662328708993;984.8128855517429;1004.8693669822876;9309.30180236485;11021.338719676845;11214.719632009102;11403.42982850854;11495.827562586568;565289;566030;570270;600263;606789;79994.40011199743;82088;126472.73527264793;129861;149758.78738795503;963.9469829159324;981.4651015196735;991.4745185051934;996.0885789502524;1011.5245834457793;17753.644927101643;19496.220151193793;20119;20141;20236;41230.38154427746;41658.12538435717;43003.784981074896;52211.95576088533;55001;65356.019659704194;79759.2024079775;82429.76355354795;82900;83113;7708.190639982721;7845.411594130349;7880.881786773089;7993.280604745601;8020.398470114881;8928.598213080557;9941.801163976825;12071.758564828478;12374.071944604011;12416.255024698547;10461.529231184462;10655.147588193082;10770.407627580245;10879.945600271947;10914.181436392144;8583.18459746323;10316.948415257875;10442.582296708046;10485.580576776843;10608.363498190134;8050.6779728812535;10818.972197641211;11161.888381116085;11162.888371116509;11224.831627525757;6964.129483814523;7190.460715446257;7283.599402032727;7398.741044063432;8098.825670277659;9411.952940235255;10721.624743133953;10994.780104397707;11069.5572177112;11080.667579972614 13:24:56 INFO - PROCESS | 1930 | |3;mootools.html;1496.8877334199938;1526.00809473842;1725.6980028495018;1804.1340156724764;1903.2482169543039;8513.276371508422;9043.457392556451;9044.05037471066;9046.547672616367;9048;7894.486858354203;8040.678372865093;8559.65761369546;8742.388032837704;8806.91193088069;1977.71323158142;2402.1352313167245;2402.7356990731005;2404.4589967257402;2411.252511721372;2362.681038059857;2377.8811059447044;2398.7721166489155;2407.879606019701;2408.5303365843665;2386.3914701751114;2394.1500767227644;2408.217329367954;2412.7707867752533;2419.6491508731224;2372.406898275431;2379.3813608461837;2385.1175065225975;2386.1886958434216;2388.259639511757;2370.3955491349766;2373.905043798246;2374.82188835838;2377.3818807110274;2377.8216633752363;8786.253168480624;9024.278057755364;9045.547722613843;9049.728508144766;9058.365914385995;5206.869828254286;5369.060414427459;5374.381946076212;5377.516023557899;5388.407275199725;3492.668196521352;3558.7508874378805;3681.1533347329996;3715.6284371562897;3716.0709822544363;1067.7330667333167;1106.7731115121462;1107.894749998749;1131.9886801132047;1133.2633788037758;11719.355435451047;11809.881901180965;11811.645650630495;11825.290482570987;11831.940840295829;4484.932726009112;4589.977050114728;4763;4774.212254977959;4781.665283430196;9995.400275983464;10021.899781002097;10038.64864729731;10043.246756493292;10045.849312260176;5363;5366.141417373202;5368.624196306299;5375.3549574050585;5386.111291636836;4385.254506733863;4408.4930233023615;4590.793414296365;4934.925976110361;4939.407271127488;3591.048372181374;3914.941275880864;4018.557958624549;4046.959530404717;4058.1477889643506;1293.6636474516692;1302.9934850325687;1304.547450792504;1316.7629826631298;1317.0648839323997;828.9419740618099;886.1404437695425;895.7178488776015;899.8785164002907;916.6791622932054;5765.394633563499;5811.709414529257;5970.49250813677;6039.1545183674325;6120.387961203933;897.1387468030633;928.3547934185812;936.9953150234205;949.7055912667096;988.0564061321421;3744.1762812181278;3845.2117315950163;3854.3062248795486;3916.412538119248;3977.463042489227;10615.31000484966;10895.83656245157;10972.177086718528;10976.012158905736;11030.952059554329;10915.235933484739;10919.836202456969;10934.726631834268;10944.726381840359;10964.28732132409;7883.7240696575345;8175.79560510992;8227.8354432911;8381.58092095405;8390.454620449651;5888.4700376966275;5932.080527518242;6033.75864965414;6085.847853803691;6116.143739876332;10143.28996970205;10230.28388012832;10299.691009269734;10319.329243599143;10338.844917326096;8217.95891020565;10285.434301113211;10289.74275643085;10428.374297542172;10467.267291289536;6990.405815505535;7189.209186989322;7409.073865766779;7513.586752728653;7675.078990520949;8378.748637540884;8456.619452124545;8577;8581.828363432822;8591.785205369666;7446.478746487694;7457.813554661177;7512.173660897406;7603.543787372775;7702.730404435818;7253.673584688807;7428.6285685716575;7466.552006879605;7497.2877576630135;7610.010698609145;6990.580565166107;7112.964435177998;7119.644017798986;7120.537165084253;7121.715131394893;4865.829695960624;4998.225275082368;4999.725015124058;5034.899302014012;5101.362329708786;4654.813807447664;4935.555799978019;5030.119729047476;5144.0997825381155;5227.163653815372 13:24:56 INFO - PROCESS | 1930 | |4;prototype.html;251.81869054280918;298.70875896001417;307.49877699350066;310.8601129491729;315.52355942526776;4295.59191876772;5330.253764472972;5377.11277639189;5418.241446197536;5489.2040654105185;4027.972027972028;4980.576650984662;5196.532312091911;5212.687238765668;5361.303030606015;3563.055790215595;3598.352296586613;4071.7988193482847;4096.406021126935;4101.856435024774;4070.1859628074358;4090.6727461802984;4097.344424892018;4107.055377263231;4133.648639865615;3850.4609354690365;4061.776602286873;4067.983004248938;4081.2449696806184;4101.938470922939;4048.9190216195807;4062.5531191568903;4079.4900637420324;4088.7751173685438;4116.444280022189;3098.9690103099133;3547.4324108142573;3550.289942011608;3564.7326450516057;3568.3050550988332;4827.420709514846;5130.538251557377;5162.535371816554;5174.3790745110455;5236.738163091868;4594.448666160082;4649.372334734835;4660.370849935283;4664.930026049612;4681.368015317923;4871.075925009602;4874.342723474036;5419.078756611386;5603.599100224944;5622.0041885131;3316.6185888622877;3611.880317101707;3905.5313362396696;3906.8437262509465;3922.5096862892137;4960.33035540201;5467.507924286833;5507.614466987313;5828.388019257959;5864.560157988168;3964.583718709494;3965.2862484752445;3972.960270397317;3975.2447035063246;3975.8210880510446;3542.3977923753023;4047.9392809107885;4073.5315438724924;4073.816678249486;4093.9590604094174;4021.698372622065;4535.206338890747;4636.482585723046;4705.482396936334;4715.575598196178;4681.531846815359;4886.926696099561;5611.410801865745;5614.831555053355;5647.971760141173;1303.1138825598493;1348.8467360406712;1384.439302082658;1386.2167875150508;1391.4573316406609;761.5302466240099;820.2084987986622;918.2195134135931;943.7778077389796;946.8059047895165;4431.490378606437;4499.59503644667;5208.8958220835375;5215.58275337972;5277.759726464278;5095.770690318945;5755.798547050833;5755.8273251801675;5821.825345239649;5860.2381690380835;4466.3523789050405;5012.323336349619;5075.289459475676;5115.820946266863;5171.689698618095;2723.597347366108;2751.8442254253264;2772.8890844366;2988.9103326899794;3033.090072978142;5546.584006199551;5895.263092113486;5906.40935906406;5922.585419020714;5927.911081333784;5539.390667026624;5789.710514474259;5818.912716309259;5835.941640583625;5837.678927658934;4908.1287446844;4964.702117872939;5384.32695913003;5609.467100625598;5881.852953676193;4515.909681806411;4747.881302967316;5391.730413479232;5675.943240567707;5717.656940583578;4676.643773305704;4790.928136077892;5107.033791990185;5329.653572517774;5593.328800543961;5012.8496145115705;5396.1366181410785;5672.978863804555;5703.63112852904;5844.590878638454;3474.3919814032947;3755.6479667319195;3902.200048990007;3936.980315098406;3947.703922205789;5604;5623.609965764452;5640.689762063126;5654.519365853948;5654.943450565442;5045.697258164522;5350.8127215547265;5582.385937546947;5593.272874526286;5624.437556244507;5473.877855039627;5526.640768350045;5540.529055030368;5547.389787123332;5577.44225577439;4510.526394728507;5068.884845334021;5355.169948657965;5378.865528361822;5405.945840561053;4913.385826771653;4958.049960273632;5025.39695236574;5490.121580547093;5643.576731745054;4553.408056952575;4964.702117872939;5156.56169225605;5181.06740786662;5508.366537848038 13:24:56 INFO - PROCESS | 1930 | |5;yui.html;679.7620832708552;700.7022015643352;721.0302143616836;733.538803607145;770.418334157711;15088.245587720612;15936.84063159368;15949.760753588691;15953.282102305395;16054.197290135506;14384.71230575388;14511.346989385476;14524.491642792504;14531.273436328194;14542;683.9726410943556;704.1303989572873;705.3651713457904;841.0706169682504;863.0204717645455;848.0417128644623;866.1165611076698;874.4359887872318;876.3471213945619;877.057163549184;895.494045864088;895.7133717210495;895.9830592277779;898.7213963671251;901.7745563609097;717.3938022371088;891.4383938118943;891.6121487153044;892.482360231071;897.6409436225497;545.6098889294134;546.641949523063;547.4115326024499;549.6729445979636;659.6635715784936;14391.2084835334;14451.421943122263;14483.782743258755;14511.927440362731;14557;13321.53374631893;13559.25424101674;13683.04218704691;13688.110272832235;14014.509492167725;12004.219725717887;12270.754584908253;12330.753384932252;12801.231926084465;12834.614961551168;4401.779911004437;4423.601875831158;4450.866474005753;4481.820727170909;4517.525659805706;118257.40871295589;118557.40721296339;124594.13108803376;125183.37408312991;125238;10449.268551201494;10638.255322127377;10672.946635266773;10969.83545246822;11136.443177841076;21102.155913763276;21156.365309040753;21208.681869771655;21229;21265.78734212638;7640.274173953466;7673.654685539164;7710.845783084419;7779.8833017504785;7885.605719714106;4309.07354918694;4324.762138082373;4377.584129507692;4393.6704747144095;4477.3731677565165;3301.6863397977154;3403.3533628610485;3407.727381809449;3409.7442691798215;3414.8463319150696;530.7823792245238;627.9120923070774;640.1326202994968;647.8412788866758;650.7331993882485;554.5397320224204;587.1926101610286;616.5868867858545;627.8461776864698;629.9307076221612;8561.400701950804;8673.696420625249;8805.339599530062;8915.197632213003;9094.571808021201;569.682224134352;608.7186472475464;615.4276522833718;622.5859803230892;624.0452108274347;4164.6251837334785;4358.128374325148;4371.475422949266;4432.4237849080055;4475.26158183896;118401.40799295949;130725.69274307326;141883.29058354642;153142;164641.17679411525;136586;139520;142648;143950;145027.82458263135;118372.22441663382;121134;122379;123503.3824830888;124804.37597811953;8370;8436.282915951968;8443.535605541754;8463.527368200446;8682.826343473222;61266.38733612785;62428.06357904544;62904;63544.41138971563;66833.66583167053;92567;96617.03382966081;99527.5070873923;101834;104221;9507.619695212114;10990.560377585127;11208.83186752183;11216.270942388719;11731.530738770354;11717.589884354007;12152.878471215528;12271.75456490883;12323.9383803084;12453.439595218419;11226.326420414802;11559.537618495166;11723;11749.823752643546;11872;11360.03439707634;11452.526423761277;11638.185327027028;11642.825357619473;11744.58893938708;10904.61833835812;11091.778164436504;11148.275362101438;11208.66374008781;11257.887421125684;9116.544172791466;9396.295277854326;9429.245660347293;9654.469004204837;9773.218142548436;9278;9555.04449555039;9639.138764982185;9670.95164524201;9756.658516952157 13:24:56 INFO - PROCESS | 1930 | __end_tp_report 13:24:56 INFO - PROCESS | 1930 | __start_cc_report 13:24:56 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,1285 13:24:56 INFO - PROCESS | 1930 | __end_cc_report 13:24:56 INFO - PROCESS | 1930 | __startTimestamp1473452696216__endTimestamp 13:24:56 INFO - PROCESS | 1930 | 13:24:56 INFO - PROCESS | 1930 | ------- Summary: start ------- 13:24:56 INFO - PROCESS | 1930 | Number of tests: 6 13:24:56 INFO - PROCESS | 1930 | 13:24:56 INFO - PROCESS | 1930 | [#0] dojo.html Cycles:165 Average:5716.94 Median:5917.35 stddev:661.56 (11.2%) stddev-sans-first:631.07 13:24:56 INFO - PROCESS | 1930 | Values: 3105.4 3246.5 3289.4 3306.3 3351.9 5423.6 5590.8 5620.7 5713.0 5728.5 5538.9 5698.7 5777.0 5779.2 5837.9 5112.6 5131.0 5137.9 5227.6 5227.9 5276.6 5283.6 5294.9 5306.0 5309.7 5099.5 5227.0 5235.1 5248.2 5326.5 5099.6 5124.4 5173.3 5173.9 5176.1 5181.7 5194.7 5203.6 5224.7 5265.4 4684.4 5673.7 5679.8 5703.4 5718.8 6097.7 6117.5 6135.5 6188.8 6270.8 5022.5 6232.3 6344.1 6347.5 6357.1 5819.1 5827.6 5842.9 5849.0 5852.2 6131.7 6255.8 6261.7 6318.2 6348.4 6088.9 6153.1 6185.3 6191.3 6211.9 5691.7 5732.8 6315.4 6320.1 6329.8 5290.3 6009.8 6098.0 6230.5 6243.5 5194.5 6076.7 6154.0 6190.6 6263.6 4944.6 4944.6 4944.7 4957.3 5015.1 4463.8 4467.3 4494.8 4506.1 4546.0 5558.4 5624.4 5633.1 5633.5 5724.4 6197.8 6284.7 6295.8 6303.6 6322.2 5683.9 5691.9 5694.3 5706.8 5732.1 5309.8 5349.9 5363.4 5434.7 5442.8 6219.3 6296.6 6307.8 6313.0 6328.3 6301.6 6332.9 6344.5 6347.3 6363.4 6226.1 6303.2 6340.4 6342.8 6348.9 6075.1 6101.2 6112.6 6124.3 6232.0 6236.3 6319.1 6322.7 6340.7 6345.7 4740.4 6135.5 6192.2 6199.4 6203.7 5971.6 5988.2 6131.4 6147.4 6188.6 5892.1 5917.3 5942.4 5946.6 6093.1 6193.9 6194.8 6195.2 6196.8 6212.7 5971.6 6019.6 6020.2 6036.2 6044.2 13:24:56 INFO - PROCESS | 1930 | 13:24:56 INFO - PROCESS | 1930 | [#1] ext.html Cycles:180 Average:65575.02 Median:19384.28 stddev:142122.37 (733.2%) stddev-sans-first:142436.97 13:24:56 INFO - PROCESS | 1930 | Values: 461.7 507.9 517.9 523.2 525.8 28469.7 40911.4 42053.4 42084.0 42130.8 43210.4 43626.8 43691.1 43729.3 43821.3 9708.0 9886.6 9901.0 9901.1 9910.2 8777.4 8954.2 12008.9 12221.4 12238.0 10787.2 11785.7 12125.5 12202.8 12219.0 12251.4 12330.8 12340.2 12349.9 12353.0 11902.3 12102.8 12104.5 12105.0 12109.9 40738.4 41078.0 41094.0 41097.0 41118.6 3238.5 3310.8 3364.3 3386.8 3500.6 30410.7 31568.7 31659.7 31735.7 31769.7 3313.2 3321.3 3382.8 3419.0 3419.9 373861.0 378433.0 378451.1 379335.0 384574.0 48775.8 58538.7 58886.0 58888.7 58901.7 64173.7 64175.0 64221.4 64295.7 64463.0 16124.2 16362.6 16382.7 16393.2 16397.9 11328.8 11600.2 12634.0 12678.2 12699.2 15327.9 15433.1 15455.0 15493.4 16977.5 5726.9 6887.6 6917.0 6939.7 6948.4 820.8 844.6 854.7 875.6 987.9 27945.0 28797.5 29009.6 29735.3 29833.7 1031.9 1057.3 1061.3 1072.7 1125.3 14251.9 14904.0 14956.6 15120.3 15321.2 623060.0 627535.0 627779.0 627987.0 847975.0 46590.0 55970.4 55976.4 55987.4 60105.0 1194.3 1198.7 1334.5 1383.1 1391.7 30068.3 30713.2 30912.8 31406.2 31739.5 50065.7 50650.0 50745.0 50797.0 50943.5 420350.0 511466.0 511769.0 512408.0 512999.0 25157.1 25390.5 25425.1 25744.4 25764.5 45578.9 45895.8 46106.8 46400.0 46437.8 36565.6 37463.4 38561.4 38688.4 40007.4 31406.9 34752.8 35268.0 37748.8 38883.0 36302.5 36470.6 37321.4 38458.0 38550.0 33015.3 33619.5 33894.0 34383.0 34670.0 24964.4 25001.4 25041.0 25464.9 25542.4 13:24:56 INFO - PROCESS | 1930 | 13:24:56 INFO - PROCESS | 1930 | [#2] jquery.html Cycles:180 Average:33427.20 Median:376.16 stddev:96914.29 (25764.1%) stddev-sans-first:97154.33 13:24:56 INFO - PROCESS | 1930 | Values: 350.4 369.9 372.6 379.7 401.4 15046.6 15248.8 16610.2 20808.4 21210.6 16272.4 19066.8 19514.4 20145.2 20822.5 5466.0 5955.9 6221.7 6222.6 6225.7 6024.4 6162.9 6228.6 6229.8 6234.6 5827.4 6139.5 6211.0 6213.9 6218.3 6168.2 6199.3 6210.8 6211.9 6216.2 5817.8 5915.3 6037.8 6044.5 6063.5 12862.1 13104.2 13107.8 23704.5 24830.5 2310.0 2377.8 2504.6 2617.5 2847.8 9863.7 10356.2 10449.4 12302.6 14723.8 1766.1 1855.9 1857.9 1916.3 1923.5 103311.5 103476.4 105327.0 117499.2 119119.0 11280.8 11288.7 11452.1 11513.6 11645.8 14462.1 14463.9 14501.9 14506.2 14508.7 8044.3 8408.5 8409.1 8488.9 8494.7 8220.6 8222.7 8223.8 8374.3 8416.1 3821.7 4431.5 4480.5 4483.3 4490.8 1565.6 1637.3 1884.6 1893.8 1930.0 671.8 722.8 822.8 823.5 962.4 20282.2 21585.5 21608.4 21638.3 21798.2 892.6 927.8 975.2 984.8 1004.9 9309.3 11021.3 11214.7 11403.4 11495.8 565289.0 566030.0 570270.0 600263.0 606789.0 79994.4 82088.0 126472.7 129861.0 149758.8 963.9 981.5 991.5 996.1 1011.5 17753.6 19496.2 20119.0 20141.0 20236.0 41230.4 41658.1 43003.8 52212.0 55001.0 65356.0 79759.2 82429.8 82900.0 83113.0 7708.2 7845.4 7880.9 7993.3 8020.4 8928.6 9941.8 12071.8 12374.1 12416.3 10461.5 10655.1 10770.4 10879.9 10914.2 8583.2 10316.9 10442.6 10485.6 10608.4 8050.7 10819.0 11161.9 11162.9 11224.8 6964.1 7190.5 7283.6 7398.7 8098.8 9412.0 10721.6 10994.8 11069.6 11080.7 13:24:56 INFO - PROCESS | 1930 | 13:24:56 INFO - PROCESS | 1930 | [#3] mootools.html Cycles:180 Average:5766.92 Median:4777.94 stddev:3242.15 (67.9%) stddev-sans-first:3235.36 13:24:56 INFO - PROCESS | 1930 | Values: 1496.9 1526.0 1725.7 1804.1 1903.2 8513.3 9043.5 9044.1 9046.5 9048.0 7894.5 8040.7 8559.7 8742.4 8806.9 1977.7 2402.1 2402.7 2404.5 2411.3 2362.7 2377.9 2398.8 2407.9 2408.5 2386.4 2394.2 2408.2 2412.8 2419.6 2372.4 2379.4 2385.1 2386.2 2388.3 2370.4 2373.9 2374.8 2377.4 2377.8 8786.3 9024.3 9045.5 9049.7 9058.4 5206.9 5369.1 5374.4 5377.5 5388.4 3492.7 3558.8 3681.2 3715.6 3716.1 1067.7 1106.8 1107.9 1132.0 1133.3 11719.4 11809.9 11811.6 11825.3 11831.9 4484.9 4590.0 4763.0 4774.2 4781.7 9995.4 10021.9 10038.6 10043.2 10045.8 5363.0 5366.1 5368.6 5375.4 5386.1 4385.3 4408.5 4590.8 4934.9 4939.4 3591.0 3914.9 4018.6 4047.0 4058.1 1293.7 1303.0 1304.5 1316.8 1317.1 828.9 886.1 895.7 899.9 916.7 5765.4 5811.7 5970.5 6039.2 6120.4 897.1 928.4 937.0 949.7 988.1 3744.2 3845.2 3854.3 3916.4 3977.5 10615.3 10895.8 10972.2 10976.0 11031.0 10915.2 10919.8 10934.7 10944.7 10964.3 7883.7 8175.8 8227.8 8381.6 8390.5 5888.5 5932.1 6033.8 6085.8 6116.1 10143.3 10230.3 10299.7 10319.3 10338.8 8218.0 10285.4 10289.7 10428.4 10467.3 6990.4 7189.2 7409.1 7513.6 7675.1 8378.7 8456.6 8577.0 8581.8 8591.8 7446.5 7457.8 7512.2 7603.5 7702.7 7253.7 7428.6 7466.6 7497.3 7610.0 6990.6 7113.0 7119.6 7120.5 7121.7 4865.8 4998.2 4999.7 5034.9 5101.4 4654.8 4935.6 5030.1 5144.1 5227.2 13:24:56 INFO - PROCESS | 1930 | 13:24:56 INFO - PROCESS | 1930 | [#4] prototype.html Cycles:180 Average:4480.87 Median:4964.70 stddev:1347.38 (27.1%) stddev-sans-first:1313.24 13:24:56 INFO - PROCESS | 1930 | Values: 251.8 298.7 307.5 310.9 315.5 4295.6 5330.3 5377.1 5418.2 5489.2 4028.0 4980.6 5196.5 5212.7 5361.3 3563.1 3598.4 4071.8 4096.4 4101.9 4070.2 4090.7 4097.3 4107.1 4133.6 3850.5 4061.8 4068.0 4081.2 4101.9 4048.9 4062.6 4079.5 4088.8 4116.4 3099.0 3547.4 3550.3 3564.7 3568.3 4827.4 5130.5 5162.5 5174.4 5236.7 4594.4 4649.4 4660.4 4664.9 4681.4 4871.1 4874.3 5419.1 5603.6 5622.0 3316.6 3611.9 3905.5 3906.8 3922.5 4960.3 5467.5 5507.6 5828.4 5864.6 3964.6 3965.3 3973.0 3975.2 3975.8 3542.4 4047.9 4073.5 4073.8 4094.0 4021.7 4535.2 4636.5 4705.5 4715.6 4681.5 4886.9 5611.4 5614.8 5648.0 1303.1 1348.8 1384.4 1386.2 1391.5 761.5 820.2 918.2 943.8 946.8 4431.5 4499.6 5208.9 5215.6 5277.8 5095.8 5755.8 5755.8 5821.8 5860.2 4466.4 5012.3 5075.3 5115.8 5171.7 2723.6 2751.8 2772.9 2988.9 3033.1 5546.6 5895.3 5906.4 5922.6 5927.9 5539.4 5789.7 5818.9 5835.9 5837.7 4908.1 4964.7 5384.3 5609.5 5881.9 4515.9 4747.9 5391.7 5675.9 5717.7 4676.6 4790.9 5107.0 5329.7 5593.3 5012.8 5396.1 5673.0 5703.6 5844.6 3474.4 3755.6 3902.2 3937.0 3947.7 5604.0 5623.6 5640.7 5654.5 5654.9 5045.7 5350.8 5582.4 5593.3 5624.4 5473.9 5526.6 5540.5 5547.4 5577.4 4510.5 5068.9 5355.2 5378.9 5405.9 4913.4 4958.0 5025.4 5490.1 5643.6 4553.4 4964.7 5156.6 5181.1 5508.4 13:24:56 INFO - PROCESS | 1930 | 13:24:56 INFO - PROCESS | 1930 | [#5] yui.html Cycles:180 Average:25486.02 Median:4374.53 stddev:42095.01 (962.3%) stddev-sans-first:42171.89 13:24:56 INFO - PROCESS | 1930 | Values: 679.8 700.7 721.0 733.5 770.4 15088.2 15936.8 15949.8 15953.3 16054.2 14384.7 14511.3 14524.5 14531.3 14542.0 684.0 704.1 705.4 841.1 863.0 848.0 866.1 874.4 876.3 877.1 895.5 895.7 896.0 898.7 901.8 717.4 891.4 891.6 892.5 897.6 545.6 546.6 547.4 549.7 659.7 14391.2 14451.4 14483.8 14511.9 14557.0 13321.5 13559.3 13683.0 13688.1 14014.5 12004.2 12270.8 12330.8 12801.2 12834.6 4401.8 4423.6 4450.9 4481.8 4517.5 118257.4 118557.4 124594.1 125183.4 125238.0 10449.3 10638.3 10672.9 10969.8 11136.4 21102.2 21156.4 21208.7 21229.0 21265.8 7640.3 7673.7 7710.8 7779.9 7885.6 4309.1 4324.8 4377.6 4393.7 4477.4 3301.7 3403.4 3407.7 3409.7 3414.8 530.8 627.9 640.1 647.8 650.7 554.5 587.2 616.6 627.8 629.9 8561.4 8673.7 8805.3 8915.2 9094.6 569.7 608.7 615.4 622.6 624.0 4164.6 4358.1 4371.5 4432.4 4475.3 118401.4 130725.7 141883.3 153142.0 164641.2 136586.0 139520.0 142648.0 143950.0 145027.8 118372.2 121134.0 122379.0 123503.4 124804.4 8370.0 8436.3 8443.5 8463.5 8682.8 61266.4 62428.1 62904.0 63544.4 66833.7 92567.0 96617.0 99527.5 101834.0 104221.0 9507.6 10990.6 11208.8 11216.3 11731.5 11717.6 12152.9 12271.8 12323.9 12453.4 11226.3 11559.5 11723.0 11749.8 11872.0 11360.0 11452.5 11638.2 11642.8 11744.6 10904.6 11091.8 11148.3 11208.7 11257.9 9116.5 9396.3 9429.2 9654.5 9773.2 9278.0 9555.0 9639.1 9671.0 9756.7 13:24:56 INFO - PROCESS | 1930 | -------- Summary: end -------- 13:24:56 INFO - PROCESS | 1930 | 13:24:56 INFO - TEST-INFO | 1930: exit 0 13:24:56 INFO - TEST-OK | dromaeo_css | took 1116576ms 13:24:56 INFO - TEST-START | kraken 13:24:56 INFO - operating with platform_type : mac_ 13:24:56 INFO - Initialising browser for kraken test... 13:24:56 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmp_TByB3/profile http://localhost:57262/getInfo.html) 13:24:59 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 13:24:59 INFO - PROCESS | 1936 | colorDepth:24 13:24:59 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 13:24:59 INFO - PROCESS | 1936 | __metrics 13:24:59 INFO - PROCESS | 1936 | 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" 13:24:59 INFO - PROCESS | 1936 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:24:59 INFO - TEST-INFO | 1936: exit 0 13:24:59 INFO - Browser initialized. 13:24:59 INFO - Running cycle 1/1 for kraken test... 13:24:59 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmp_TByB3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:25:12 INFO - PROCESS | 1937 | RSS: Main: 232468480 13:25:12 INFO - PROCESS | 1937 | 13:25:14 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57262/tests/kraken/driver.html?testName=audio-beat-detection) 13:25:15 INFO - PROCESS | 1937 | RSS: Main: 290885632 13:25:15 INFO - PROCESS | 1937 | 13:25:18 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57262/tests/kraken/driver.html?testName=audio-dft) 13:25:18 INFO - PROCESS | 1937 | RSS: Main: 287735808 13:25:18 INFO - PROCESS | 1937 | 13:25:22 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57262/tests/kraken/driver.html?testName=audio-fft) 13:25:23 INFO - PROCESS | 1937 | RSS: Main: 375590912 13:25:23 INFO - PROCESS | 1937 | 13:25:25 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57262/tests/kraken/driver.html?testName=audio-oscillator) 13:25:25 INFO - PROCESS | 1937 | RSS: Main: 279863296 13:25:25 INFO - PROCESS | 1937 | 13:25:27 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57262/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:25:28 INFO - PROCESS | 1937 | RSS: Main: 283430912 13:25:28 INFO - PROCESS | 1937 | 13:25:31 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57262/tests/kraken/driver.html?testName=imaging-darkroom) 13:25:31 INFO - PROCESS | 1937 | RSS: Main: 321273856 13:25:31 INFO - PROCESS | 1937 | 13:25:34 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57262/tests/kraken/driver.html?testName=imaging-desaturate) 13:25:35 INFO - PROCESS | 1937 | RSS: Main: 322154496 13:25:35 INFO - PROCESS | 1937 | 13:25:38 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57262/tests/kraken/driver.html?testName=json-parse-financial) 13:25:38 INFO - PROCESS | 1937 | RSS: Main: 321429504 13:25:38 INFO - PROCESS | 1937 | 13:25:40 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57262/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:25:40 INFO - PROCESS | 1937 | RSS: Main: 298733568 13:25:40 INFO - PROCESS | 1937 | 13:25:42 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:25:43 INFO - PROCESS | 1937 | RSS: Main: 305315840 13:25:43 INFO - PROCESS | 1937 | 13:25:45 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:25:46 INFO - PROCESS | 1937 | RSS: Main: 306888704 13:25:46 INFO - PROCESS | 1937 | 13:25:49 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:25:49 INFO - PROCESS | 1937 | RSS: Main: 321028096 13:25:49 INFO - PROCESS | 1937 | 13:25:52 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:25:53 INFO - PROCESS | 1937 | RSS: Main: 317132800 13:25:53 INFO - PROCESS | 1937 | 13:25:54 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57262/tests/kraken/driver.html?testName=ai-astar) 13:25:55 INFO - PROCESS | 1937 | RSS: Main: 361910272 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | __start_tp_report 13:25:55 INFO - PROCESS | 1937 | _x_x_mozilla_page_load 13:25:55 INFO - PROCESS | 1937 | _x_x_mozilla_page_load_details 13:25:55 INFO - PROCESS | 1937 | |i|pagename|runs| 13:25:55 INFO - PROCESS | 1937 | |0;ai-astar;113;112;117;112;114;113;111;113;111;123 13:25:55 INFO - PROCESS | 1937 | |1;audio-beat-detection;152;113;129;166;131;162;160;107;156;108 13:25:55 INFO - PROCESS | 1937 | |2;audio-dft;188;186;185;184;186;183;185;183;184;183 13:25:55 INFO - PROCESS | 1937 | |3;audio-fft;81;75;77;158;57;58;57;58;144;59 13:25:55 INFO - PROCESS | 1937 | |4;audio-oscillator;83;84;136;71;68;79;119;72;69;120 13:25:55 INFO - PROCESS | 1937 | |5;imaging-gaussian-blur;88;85;89;86;87;85;90;86;90;87 13:25:55 INFO - PROCESS | 1937 | |6;imaging-darkroom;94;94;95;95;95;95;95;95;93;93 13:25:55 INFO - PROCESS | 1937 | |7;imaging-desaturate;84;87;86;86;84;103;141;109;83;83 13:25:55 INFO - PROCESS | 1937 | |8;json-parse-financial;55;56;57;54;54;55;54;54;53;54 13:25:55 INFO - PROCESS | 1937 | |9;json-stringify-tinderbox;66;65;69;68;74;78;71;73;73;71 13:25:55 INFO - PROCESS | 1937 | |10;stanford-crypto-aes;88;88;84;87;85;84;85;87;86;85 13:25:55 INFO - PROCESS | 1937 | |11;stanford-crypto-ccm;153;137;133;139;138;138;134;129;187;147 13:25:55 INFO - PROCESS | 1937 | |12;stanford-crypto-pbkdf2;167;166;167;160;167;167;165;166;169;167 13:25:55 INFO - PROCESS | 1937 | |13;stanford-crypto-sha256-iterative;67;68;68;68;68;65;67;67;66;65 13:25:55 INFO - PROCESS | 1937 | __end_tp_report 13:25:55 INFO - PROCESS | 1937 | __start_cc_report 13:25:55 INFO - PROCESS | 1937 | _x_x_mozilla_cycle_collect,2047 13:25:55 INFO - PROCESS | 1937 | __end_cc_report 13:25:55 INFO - PROCESS | 1937 | __startTimestamp1473452755201__endTimestamp 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | ------- Summary: start ------- 13:25:55 INFO - PROCESS | 1937 | Number of tests: 14 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#0] ai-astar Cycles:10 Average:113.90 Median:113.00 stddev:3.63 (3.2%) stddev-sans-first:3.84 13:25:55 INFO - PROCESS | 1937 | Values: 113.0 112.0 117.0 112.0 114.0 113.0 111.0 113.0 111.0 123.0 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:154.00 stddev:23.52 (15.3%) stddev-sans-first:24.43 13:25:55 INFO - PROCESS | 1937 | Values: 152.0 113.0 129.0 166.0 131.0 162.0 160.0 107.0 156.0 108.0 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:1.64 (0.9%) stddev-sans-first:1.22 13:25:55 INFO - PROCESS | 1937 | Values: 188.0 186.0 185.0 184.0 186.0 183.0 185.0 183.0 184.0 183.0 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#3] audio-fft Cycles:10 Average:82.40 Median:58.50 stddev:37.45 (64.0%) stddev-sans-first:39.72 13:25:55 INFO - PROCESS | 1937 | Values: 81.0 75.0 77.0 158.0 57.0 58.0 57.0 58.0 144.0 59.0 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#4] audio-oscillator Cycles:10 Average:90.10 Median:71.50 stddev:25.11 (35.1%) stddev-sans-first:26.50 13:25:55 INFO - PROCESS | 1937 | Values: 83.0 84.0 136.0 71.0 68.0 79.0 119.0 72.0 69.0 120.0 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:87.50 stddev:1.89 (2.2%) stddev-sans-first:1.99 13:25:55 INFO - PROCESS | 1937 | Values: 88.0 85.0 89.0 86.0 87.0 85.0 90.0 86.0 90.0 87.0 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:95.00 stddev:0.84 (0.9%) stddev-sans-first:0.88 13:25:55 INFO - PROCESS | 1937 | Values: 94.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 93.0 93.0 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:84.00 stddev:18.65 (22.2%) stddev-sans-first:19.38 13:25:55 INFO - PROCESS | 1937 | Values: 84.0 87.0 86.0 86.0 84.0 103.0 141.0 109.0 83.0 83.0 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:54.50 stddev:1.17 (2.2%) stddev-sans-first:1.24 13:25:55 INFO - PROCESS | 1937 | Values: 55.0 56.0 57.0 54.0 54.0 55.0 54.0 54.0 53.0 54.0 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:72.00 stddev:3.94 (5.5%) stddev-sans-first:3.77 13:25:55 INFO - PROCESS | 1937 | Values: 66.0 65.0 69.0 68.0 74.0 78.0 71.0 73.0 73.0 71.0 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:86.50 stddev:1.52 (1.8%) stddev-sans-first:1.41 13:25:55 INFO - PROCESS | 1937 | Values: 88.0 88.0 84.0 87.0 85.0 84.0 85.0 87.0 86.0 85.0 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#11] stanford-crypto-ccm Cycles:10 Average:143.50 Median:138.50 stddev:16.76 (12.1%) stddev-sans-first:17.42 13:25:55 INFO - PROCESS | 1937 | Values: 153.0 137.0 133.0 139.0 138.0 138.0 134.0 129.0 187.0 147.0 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:167.00 stddev:2.38 (1.4%) stddev-sans-first:2.50 13:25:55 INFO - PROCESS | 1937 | Values: 167.0 166.0 167.0 160.0 167.0 167.0 165.0 166.0 169.0 167.0 13:25:55 INFO - PROCESS | 1937 | 13:25:55 INFO - PROCESS | 1937 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.50 stddev:1.20 (1.8%) stddev-sans-first:1.27 13:25:55 INFO - PROCESS | 1937 | Values: 67.0 68.0 68.0 68.0 68.0 65.0 67.0 67.0 66.0 65.0 13:25:55 INFO - PROCESS | 1937 | -------- Summary: end -------- 13:25:55 INFO - PROCESS | 1937 | 13:25:56 INFO - TEST-INFO | 1937: exit 0 13:25:56 INFO - TEST-OK | kraken | took 59198ms 13:25:56 INFO - SUITE-END | took 1175s 13:25:56 INFO - Completed test suite (00:19:36) 13:25:56 INFO - javascript benchmark 13:25:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3105.441020616289, 3246.4643333849917, 3289.4079065768183, 3306.2891478332153, 3351.899443016709, 5423.620346575742, 5590.832275031746, 5620.718964051796, 5713.028785106526, 5728.541716662668, 5538.9169162462595, 5698.715064246781, 5777.0, 5779.190913272135, 5837.854053648661, 5112.616553758465, 5131.025105230013, 5137.897242055156, 5227.581793456533, 5227.86930326743, 5276.577873770089, 5283.63014588979, 5294.867628309304, 5306.018386598473, 5309.654872433299, 5099.5410413063, 5226.973865130688, 5235.057689615867, 5248.239005344223, 5326.467353264681, 5099.643024988252, 5124.359455068116, 5173.275741396207, 5173.922391164136, 5176.120059589879, 5181.740912954338, 5194.71429071401, 5203.557697595709, 5224.73876306187, 5265.3681558212875, 4684.4378674559275, 5673.744681489302, 5679.829605111853, 5703.429657034305, 5718.799842005551, 6097.706167156134, 6117.480014198754, 6135.477655820289, 6188.81433556994, 6270.78052268173, 5022.497750224985, 6232.252129744415, 6344.111824344595, 6347.492200623985, 6357.141785858896, 5819.0689489681445, 5827.592068555203, 5842.941570584282, 5849.034909239927, 5852.209951656557, 6131.662758548234, 6255.843603909848, 6261.655608941552, 6318.241810982711, 6348.4286414222015, 6088.878222435526, 6153.107799369066, 6185.288691800411, 6191.288001879751, 6211.937880621226, 5691.747815480588, 5732.828015159552, 6315.431611155018, 6320.146780184617, 6329.841753956096, 5290.338707661543, 6009.759609615654, 6098.0147959968335, 6230.501559875199, 6243.500519958392, 5194.532492075731, 6076.696165191723, 6153.98459254227, 6190.566660333823, 6263.592866463665, 4944.554990050912, 4944.579710724557, 4944.703317800871, 4957.281194226817, 5015.14742493777, 4463.843765468193, 4467.30756732707, 4494.842680506166, 4506.076254367847, 4546.02260513991, 5558.388577256498, 5624.3813180550105, 5633.070543360382, 5633.549316054706, 5724.427557244242, 6197.752089916353, 6284.654344011032, 6295.811125666238, 6303.590266632654, 6322.178116844781, 5683.914741278884, 5691.857703557444, 5694.259746233046, 5706.828795136153, 5732.111522713903, 5309.761060752276, 5349.946500535023, 5363.356397232357, 5434.673919564839, 5442.809501667422, 6219.346968568235, 6296.5592408530965, 6307.810765677037, 6312.968435157794, 6328.272248691459, 6301.558890877594, 6332.936670633235, 6344.524160687874, 6347.270063942706, 6363.427291543783, 6226.066090086342, 6303.180586523723, 6340.397662222082, 6342.841428964312, 6348.904766428415, 6075.058365953284, 6101.237345331833, 6112.633242005316, 6124.319907527303, 6232.002879539252, 6236.251649802053, 6319.115323854572, 6322.715477803418, 6340.714667839866, 6345.714442850175, 4740.431148262231, 6135.478484328882, 6192.195014648067, 6199.442050215503, 6203.689815509116, 5971.58198926071, 5988.221531200916, 6131.417515336036, 6147.354527774698, 6188.597741146811, 5892.145638882253, 5917.3490915998345, 5942.405759424023, 5946.583739138218, 6093.086037094471, 6193.907091393543, 6194.81415557534, 6195.2255968004, 6196.845078873064, 6212.72042758086, 5971.640203183596, 6019.608725432833, 6020.247469066367, 6036.245469316335, 6044.184035155374], "value": 5674.88843154236, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.68374663355604, 507.852722710414, 517.8524120625622, 523.2099529710138, 525.8212207849331, 28469.715302846966, 40911.38632920505, 42053.36919946203, 42083.99999999996, 42130.7893460533, 43210.351844722354, 43626.78186609071, 43691.12617747643, 43729.344059839124, 43821.34267985983, 9707.951460242706, 9886.604535818558, 9901.0, 9901.059399357086, 9910.157636600898, 8777.42946708465, 8954.19412252897, 12008.879911200864, 12221.388930553481, 12238.0, 10787.244892857503, 11785.705357366049, 12125.514979400814, 12202.816957745597, 12219.0, 12251.448684809206, 12330.753384932252, 12340.197887137307, 12349.938250308736, 12353.0, 11902.285862848257, 12102.81845772314, 12104.515819367214, 12105.0, 12109.878901211052, 40738.38892416616, 41078.0, 41093.972650683674, 41097.0, 41118.588814111776, 3238.5304130901122, 3310.7682462227654, 3364.310316385135, 3386.8306584670668, 3500.562429696288, 30410.69589304123, 31568.684313157035, 31659.683403166135, 31735.682643173735, 31769.6823031769, 3313.2379552702773, 3321.2527181384235, 3382.830858457092, 3418.9658103418897, 3419.8803041893416, 373861.0, 378433.0, 378451.10774446226, 379335.0, 384574.0, 48775.756121219165, 58538.7073064632, 58886.0, 58888.7055564728, 58901.70549147313, 64173.67913160404, 64175.0, 64221.35778642154, 64295.67852160709, 64463.0, 16124.193790310437, 16362.590935226715, 16382.672346553003, 16393.180340982904, 16397.91801040987, 11328.773424531464, 11600.18798684084, 12634.0, 12678.239305641691, 12699.238045717102, 15327.923360383349, 15433.074015559103, 15455.0, 15493.380264789508, 16977.49067527976, 5726.914096288559, 6887.621180835102, 6916.956539122871, 6939.6877140528795, 6948.374646281859, 820.8016296207604, 844.6452489954111, 854.6581367453068, 875.5840975536569, 987.8666380038746, 27945.0, 28797.536381860846, 29009.56485652717, 29735.25661858471, 29833.701662983527, 1031.896810318962, 1057.3021805608262, 1061.3472714280815, 1072.6781965410346, 1125.2517076144436, 14251.928740356232, 14904.0, 14956.626084347761, 15120.319585618674, 15321.233938303263, 623060.0, 627535.0, 627779.0, 627987.0, 847975.0, 46590.0, 55970.44029559652, 55976.44023559794, 55987.44012559822, 60105.0, 1194.3013337197663, 1198.748262864808, 1334.5129027904939, 1383.1355402873205, 1391.7007843313736, 30068.332595095224, 30713.232169195948, 30912.845435772677, 31406.214844629067, 31739.523907141873, 50065.74967125141, 50650.0, 50745.0, 50797.0, 50943.490565093874, 420350.0, 511466.0, 511769.0, 512408.0, 512999.0, 25157.11950081738, 25390.492190156463, 25425.11012114567, 25744.356391090372, 25764.484710306064, 45578.86052848705, 45895.770521147184, 46106.7694661538, 46400.0, 46437.76781116208, 36565.634343656224, 37463.43804842984, 38561.421578676905, 38688.41967370435, 40007.39988900227, 31406.900758473344, 34752.78365257305, 35268.0, 37748.81125594355, 38883.0, 36302.45546316754, 36470.63529364672, 37321.440178396806, 38458.0, 38550.0, 33015.33969320648, 33619.49570756392, 33894.049961395925, 34383.0, 34670.0, 24964.37589060288, 25001.374965626004, 25041.0, 25464.872675636503, 25542.361440964123], "value": 19860.552870604686, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.43055035567204, 369.9223163135742, 372.62365011338557, 379.6981399787168, 401.3598310694441, 15046.623834404132, 15248.771268430983, 16610.16949152542, 20808.37574872756, 21210.57578848422, 16272.43046493373, 19066.809331906676, 19514.414567562995, 20145.19419223233, 20822.479438014056, 5465.988792073479, 5955.910661340084, 6221.688915554227, 6222.595531290452, 6225.719842607071, 6024.427679370453, 6162.876742465148, 6228.626282423046, 6229.781957631484, 6234.625922444645, 5827.35899051104, 6139.508839292846, 6210.968945155268, 6213.937860621381, 6218.31598524162, 6168.198134242542, 6199.256089269294, 6210.78262260821, 6211.9068213976825, 6216.160818289563, 5817.825465236049, 5915.349311575723, 6037.7584896604085, 6044.546659000593, 6063.514918806529, 12862.099653024292, 13104.213747175105, 13107.803382949263, 23704.525909481712, 24830.5033899321, 2309.9653505197434, 2377.7741114594087, 2504.5742223821812, 2617.450335429562, 2847.8433686147255, 9863.654772083015, 10356.17150627948, 10449.425281609509, 12302.569410070604, 14723.786597718652, 1766.0904634113442, 1855.879367841086, 1857.888526688403, 1916.2718167096414, 1923.5287354598074, 103311.48344258306, 103476.41308967261, 105327.0, 117499.23751143824, 119119.0, 11280.830787538194, 11288.717782055513, 11452.141089418326, 11513.597024104283, 11645.767084658259, 14462.05996610217, 14463.855361446462, 14501.927490362481, 14506.202158881362, 14508.709825803637, 8044.31623312025, 8408.453450525696, 8409.075001749925, 8488.915110848937, 8494.702685406104, 8220.58897055145, 8222.712205072914, 8223.835523289621, 8374.330053595697, 8416.116307787717, 3821.7197238925273, 4431.468223813099, 4480.4623445186135, 4483.30508771141, 4490.7754612269255, 1565.6085978505373, 1637.287779815784, 1884.632496663165, 1893.8390236829748, 1929.9710504342447, 671.77495538994, 722.7795522365624, 822.7943014246439, 823.47297729452, 962.43216502264, 20282.188712451338, 21585.460363490725, 21608.354203845935, 21638.32148534753, 21798.237061702763, 892.6295587331185, 927.7634203278121, 975.1662328708993, 984.8128855517429, 1004.8693669822876, 9309.30180236485, 11021.338719676845, 11214.719632009102, 11403.42982850854, 11495.827562586568, 565289.0, 566030.0, 570270.0, 600263.0, 606789.0, 79994.40011199743, 82088.0, 126472.73527264793, 129861.0, 149758.78738795503, 963.9469829159324, 981.4651015196735, 991.4745185051934, 996.0885789502524, 1011.5245834457793, 17753.644927101643, 19496.220151193793, 20119.0, 20141.0, 20236.0, 41230.38154427746, 41658.12538435717, 43003.784981074896, 52211.95576088533, 55001.0, 65356.019659704194, 79759.2024079775, 82429.76355354795, 82900.0, 83113.0, 7708.190639982721, 7845.411594130349, 7880.881786773089, 7993.280604745601, 8020.398470114881, 8928.598213080557, 9941.801163976825, 12071.758564828478, 12374.071944604011, 12416.255024698547, 10461.529231184462, 10655.147588193082, 10770.407627580245, 10879.945600271947, 10914.181436392144, 8583.18459746323, 10316.948415257875, 10442.582296708046, 10485.580576776843, 10608.363498190134, 8050.6779728812535, 10818.972197641211, 11161.888381116085, 11162.888371116509, 11224.831627525757, 6964.129483814523, 7190.460715446257, 7283.599402032727, 7398.741044063432, 8098.825670277659, 9411.952940235255, 10721.624743133953, 10994.780104397707, 11069.5572177112, 11080.667579972614], "value": 9274.335124075713, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1496.8877334199938, 1526.00809473842, 1725.6980028495018, 1804.1340156724764, 1903.2482169543039, 8513.276371508422, 9043.457392556451, 9044.05037471066, 9046.547672616367, 9048.0, 7894.486858354203, 8040.678372865093, 8559.65761369546, 8742.388032837704, 8806.91193088069, 1977.71323158142, 2402.1352313167245, 2402.7356990731005, 2404.4589967257402, 2411.252511721372, 2362.681038059857, 2377.8811059447044, 2398.7721166489155, 2407.879606019701, 2408.5303365843665, 2386.3914701751114, 2394.1500767227644, 2408.217329367954, 2412.7707867752533, 2419.6491508731224, 2372.406898275431, 2379.3813608461837, 2385.1175065225975, 2386.1886958434216, 2388.259639511757, 2370.3955491349766, 2373.905043798246, 2374.82188835838, 2377.3818807110274, 2377.8216633752363, 8786.253168480624, 9024.278057755364, 9045.547722613843, 9049.728508144766, 9058.365914385995, 5206.869828254286, 5369.060414427459, 5374.381946076212, 5377.516023557899, 5388.407275199725, 3492.668196521352, 3558.7508874378805, 3681.1533347329996, 3715.6284371562897, 3716.0709822544363, 1067.7330667333167, 1106.7731115121462, 1107.894749998749, 1131.9886801132047, 1133.2633788037758, 11719.355435451047, 11809.881901180965, 11811.645650630495, 11825.290482570987, 11831.940840295829, 4484.932726009112, 4589.977050114728, 4763.0, 4774.212254977959, 4781.665283430196, 9995.400275983464, 10021.899781002097, 10038.64864729731, 10043.246756493292, 10045.849312260176, 5363.0, 5366.141417373202, 5368.624196306299, 5375.3549574050585, 5386.111291636836, 4385.254506733863, 4408.4930233023615, 4590.793414296365, 4934.925976110361, 4939.407271127488, 3591.048372181374, 3914.941275880864, 4018.557958624549, 4046.959530404717, 4058.1477889643506, 1293.6636474516692, 1302.9934850325687, 1304.547450792504, 1316.7629826631298, 1317.0648839323997, 828.9419740618099, 886.1404437695425, 895.7178488776015, 899.8785164002907, 916.6791622932054, 5765.394633563499, 5811.709414529257, 5970.49250813677, 6039.1545183674325, 6120.387961203933, 897.1387468030633, 928.3547934185812, 936.9953150234205, 949.7055912667096, 988.0564061321421, 3744.1762812181278, 3845.2117315950163, 3854.3062248795486, 3916.412538119248, 3977.463042489227, 10615.31000484966, 10895.83656245157, 10972.177086718528, 10976.012158905736, 11030.952059554329, 10915.235933484739, 10919.836202456969, 10934.726631834268, 10944.726381840359, 10964.28732132409, 7883.7240696575345, 8175.79560510992, 8227.8354432911, 8381.58092095405, 8390.454620449651, 5888.4700376966275, 5932.080527518242, 6033.75864965414, 6085.847853803691, 6116.143739876332, 10143.28996970205, 10230.28388012832, 10299.691009269734, 10319.329243599143, 10338.844917326096, 8217.95891020565, 10285.434301113211, 10289.74275643085, 10428.374297542172, 10467.267291289536, 6990.405815505535, 7189.209186989322, 7409.073865766779, 7513.586752728653, 7675.078990520949, 8378.748637540884, 8456.619452124545, 8577.0, 8581.828363432822, 8591.785205369666, 7446.478746487694, 7457.813554661177, 7512.173660897406, 7603.543787372775, 7702.730404435818, 7253.673584688807, 7428.6285685716575, 7466.552006879605, 7497.2877576630135, 7610.010698609145, 6990.580565166107, 7112.964435177998, 7119.644017798986, 7120.537165084253, 7121.715131394893, 4865.829695960624, 4998.225275082368, 4999.725015124058, 5034.899302014012, 5101.362329708786, 4654.813807447664, 4935.555799978019, 5030.119729047476, 5144.0997825381155, 5227.163653815372], "value": 4634.6892990617, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [251.81869054280918, 298.70875896001417, 307.49877699350066, 310.8601129491729, 315.52355942526776, 4295.59191876772, 5330.253764472972, 5377.11277639189, 5418.241446197536, 5489.2040654105185, 4027.972027972028, 4980.576650984662, 5196.532312091911, 5212.687238765668, 5361.303030606015, 3563.055790215595, 3598.352296586613, 4071.7988193482847, 4096.406021126935, 4101.856435024774, 4070.1859628074358, 4090.6727461802984, 4097.344424892018, 4107.055377263231, 4133.648639865615, 3850.4609354690365, 4061.776602286873, 4067.983004248938, 4081.2449696806184, 4101.938470922939, 4048.9190216195807, 4062.5531191568903, 4079.4900637420324, 4088.7751173685438, 4116.444280022189, 3098.9690103099133, 3547.4324108142573, 3550.289942011608, 3564.7326450516057, 3568.3050550988332, 4827.420709514846, 5130.538251557377, 5162.535371816554, 5174.3790745110455, 5236.738163091868, 4594.448666160082, 4649.372334734835, 4660.370849935283, 4664.930026049612, 4681.368015317923, 4871.075925009602, 4874.342723474036, 5419.078756611386, 5603.599100224944, 5622.0041885131, 3316.6185888622877, 3611.880317101707, 3905.5313362396696, 3906.8437262509465, 3922.5096862892137, 4960.33035540201, 5467.507924286833, 5507.614466987313, 5828.388019257959, 5864.560157988168, 3964.583718709494, 3965.2862484752445, 3972.960270397317, 3975.2447035063246, 3975.8210880510446, 3542.3977923753023, 4047.9392809107885, 4073.5315438724924, 4073.816678249486, 4093.9590604094174, 4021.698372622065, 4535.206338890747, 4636.482585723046, 4705.482396936334, 4715.575598196178, 4681.531846815359, 4886.926696099561, 5611.410801865745, 5614.831555053355, 5647.971760141173, 1303.1138825598493, 1348.8467360406712, 1384.439302082658, 1386.2167875150508, 1391.4573316406609, 761.5302466240099, 820.2084987986622, 918.2195134135931, 943.7778077389796, 946.8059047895165, 4431.490378606437, 4499.59503644667, 5208.8958220835375, 5215.58275337972, 5277.759726464278, 5095.770690318945, 5755.798547050833, 5755.8273251801675, 5821.825345239649, 5860.2381690380835, 4466.3523789050405, 5012.323336349619, 5075.289459475676, 5115.820946266863, 5171.689698618095, 2723.597347366108, 2751.8442254253264, 2772.8890844366, 2988.9103326899794, 3033.090072978142, 5546.584006199551, 5895.263092113486, 5906.40935906406, 5922.585419020714, 5927.911081333784, 5539.390667026624, 5789.710514474259, 5818.912716309259, 5835.941640583625, 5837.678927658934, 4908.1287446844, 4964.702117872939, 5384.32695913003, 5609.467100625598, 5881.852953676193, 4515.909681806411, 4747.881302967316, 5391.730413479232, 5675.943240567707, 5717.656940583578, 4676.643773305704, 4790.928136077892, 5107.033791990185, 5329.653572517774, 5593.328800543961, 5012.8496145115705, 5396.1366181410785, 5672.978863804555, 5703.63112852904, 5844.590878638454, 3474.3919814032947, 3755.6479667319195, 3902.200048990007, 3936.980315098406, 3947.703922205789, 5604.0, 5623.609965764452, 5640.689762063126, 5654.519365853948, 5654.943450565442, 5045.697258164522, 5350.8127215547265, 5582.385937546947, 5593.272874526286, 5624.437556244507, 5473.877855039627, 5526.640768350045, 5540.529055030368, 5547.389787123332, 5577.44225577439, 4510.526394728507, 5068.884845334021, 5355.169948657965, 5378.865528361822, 5405.945840561053, 4913.385826771653, 4958.049960273632, 5025.39695236574, 5490.121580547093, 5643.576731745054, 4553.408056952575, 4964.702117872939, 5156.56169225605, 5181.06740786662, 5508.366537848038], "value": 4050.476091286944, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.7620832708552, 700.7022015643352, 721.0302143616836, 733.538803607145, 770.418334157711, 15088.245587720612, 15936.84063159368, 15949.760753588691, 15953.282102305395, 16054.197290135506, 14384.71230575388, 14511.346989385476, 14524.491642792504, 14531.273436328194, 14542.0, 683.9726410943556, 704.1303989572873, 705.3651713457904, 841.0706169682504, 863.0204717645455, 848.0417128644623, 866.1165611076698, 874.4359887872318, 876.3471213945619, 877.057163549184, 895.494045864088, 895.7133717210495, 895.9830592277779, 898.7213963671251, 901.7745563609097, 717.3938022371088, 891.4383938118943, 891.6121487153044, 892.482360231071, 897.6409436225497, 545.6098889294134, 546.641949523063, 547.4115326024499, 549.6729445979636, 659.6635715784936, 14391.2084835334, 14451.421943122263, 14483.782743258755, 14511.927440362731, 14557.0, 13321.53374631893, 13559.25424101674, 13683.04218704691, 13688.110272832235, 14014.509492167725, 12004.219725717887, 12270.754584908253, 12330.753384932252, 12801.231926084465, 12834.614961551168, 4401.779911004437, 4423.601875831158, 4450.866474005753, 4481.820727170909, 4517.525659805706, 118257.40871295589, 118557.40721296339, 124594.13108803376, 125183.37408312991, 125238.0, 10449.268551201494, 10638.255322127377, 10672.946635266773, 10969.83545246822, 11136.443177841076, 21102.155913763276, 21156.365309040753, 21208.681869771655, 21229.0, 21265.78734212638, 7640.274173953466, 7673.654685539164, 7710.845783084419, 7779.8833017504785, 7885.605719714106, 4309.07354918694, 4324.762138082373, 4377.584129507692, 4393.6704747144095, 4477.3731677565165, 3301.6863397977154, 3403.3533628610485, 3407.727381809449, 3409.7442691798215, 3414.8463319150696, 530.7823792245238, 627.9120923070774, 640.1326202994968, 647.8412788866758, 650.7331993882485, 554.5397320224204, 587.1926101610286, 616.5868867858545, 627.8461776864698, 629.9307076221612, 8561.400701950804, 8673.696420625249, 8805.339599530062, 8915.197632213003, 9094.571808021201, 569.682224134352, 608.7186472475464, 615.4276522833718, 622.5859803230892, 624.0452108274347, 4164.6251837334785, 4358.128374325148, 4371.475422949266, 4432.4237849080055, 4475.26158183896, 118401.40799295949, 130725.69274307326, 141883.29058354642, 153142.0, 164641.17679411525, 136586.0, 139520.0, 142648.0, 143950.0, 145027.82458263135, 118372.22441663382, 121134.0, 122379.0, 123503.3824830888, 124804.37597811953, 8370.0, 8436.282915951968, 8443.535605541754, 8463.527368200446, 8682.826343473222, 61266.38733612785, 62428.06357904544, 62904.0, 63544.41138971563, 66833.66583167053, 92567.0, 96617.03382966081, 99527.5070873923, 101834.0, 104221.0, 9507.619695212114, 10990.560377585127, 11208.83186752183, 11216.270942388719, 11731.530738770354, 11717.589884354007, 12152.878471215528, 12271.75456490883, 12323.9383803084, 12453.439595218419, 11226.326420414802, 11559.537618495166, 11723.0, 11749.823752643546, 11872.0, 11360.03439707634, 11452.526423761277, 11638.185327027028, 11642.825357619473, 11744.58893938708, 10904.61833835812, 11091.778164436504, 11148.275362101438, 11208.66374008781, 11257.887421125684, 9116.544172791466, 9396.295277854326, 9429.245660347293, 9654.469004204837, 9773.218142548436, 9278.0, 9555.04449555039, 9639.138764982185, 9670.95164524201, 9756.658516952157], "value": 7519.491075098378, "unit": "score"}], "name": "dromaeo_css", "value": 7269.401756166974, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 117.0, 112.0, 114.0, 113.0, 111.0, 113.0, 111.0, 123.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 113.0, 129.0, 166.0, 131.0, 162.0, 160.0, 107.0, 156.0, 108.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 186.0, 185.0, 184.0, 186.0, 183.0, 185.0, 183.0, 184.0, 183.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 75.0, 77.0, 158.0, 57.0, 58.0, 57.0, 58.0, 144.0, 59.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 136.0, 71.0, 68.0, 79.0, 119.0, 72.0, 69.0, 120.0], "value": 90.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 86.0, 87.0, 85.0, 90.0, 86.0, 90.0, 87.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 93.0, 93.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 86.0, 86.0, 84.0, 103.0, 141.0, 109.0, 83.0, 83.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 54.0, 54.0, 55.0, 54.0, 54.0, 53.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 69.0, 68.0, 74.0, 78.0, 71.0, 73.0, 73.0, 71.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 84.0, 87.0, 85.0, 84.0, 85.0, 87.0, 86.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 137.0, 133.0, 139.0, 138.0, 138.0, 134.0, 129.0, 187.0, 147.0], "value": 143.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 167.0, 160.0, 167.0, 167.0, 165.0, 166.0, 169.0, 167.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 68.0, 68.0, 65.0, 67.0, 67.0, 66.0, 65.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1473.6, "alertThreshold": 2.0}]} 13:25:56 INFO - Return code: 0 13:25:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:25:56 INFO - # TBPL SUCCESS # 13:25:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:25:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:25:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:25:56 INFO - 2016-09-09 13:25:56.514 screenresolution[1941:20613] starting screenresolution argv=screenresolution get 13:25:56 INFO - 2016-09-09 13:25:56.516 screenresolution[1941:20613] Display 0: 1600x1200x32@60 13:25:56 INFO - 2016-09-09 13:25:56.522 screenresolution[1942:20616] starting screenresolution argv=screenresolution list 13:25:56 INFO - Available Modes on Display 0 13:25:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:25:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:25:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:25:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:25:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:25:56 INFO - Intel Iris: 13:25:56 INFO - Chipset Model: Intel Iris 13:25:56 INFO - Type: GPU 13:25:56 INFO - Bus: Built-In 13:25:56 INFO - VRAM (Dynamic, Max): 1536 MB 13:25:56 INFO - Vendor: Intel (0x8086) 13:25:56 INFO - Device ID: 0x0a2e 13:25:56 INFO - Revision ID: 0x0009 13:25:56 INFO - Displays: 13:25:56 INFO - Display: 13:25:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:25:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:25:56 INFO - Display Serial Number: 1600x1200 60 13:25:56 INFO - Main Display: Yes 13:25:56 INFO - Mirror: Off 13:25:56 INFO - Online: Yes 13:25:56 INFO - Rotation: Supported 13:25:56 INFO - Return code: 0 13:25:56 INFO - Running post-action listener: _resource_record_post_action 13:25:56 INFO - [mozharness: 2016-09-09 20:25:56.758509Z] Finished run-tests step (success) 13:25:56 INFO - Running post-run listener: _resource_record_post_run 13:25:56 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 1884160; Write bytes: 705121792; Read time: 14; Write time: 1904 13:25:56 INFO - TinderboxPrint: CPU usage
53.7% 13:25:56 INFO - TinderboxPrint: I/O read bytes / time
1,884,160 / 14 13:25:56 INFO - TinderboxPrint: I/O write bytes / time
705,121,792 / 1,904 13:25:56 INFO - TinderboxPrint: CPU idle
2,244.4 (46.3%) 13:25:56 INFO - TinderboxPrint: CPU system
972.9 (20.1%) 13:25:56 INFO - TinderboxPrint: CPU user
1,628.3 (33.6%) 13:25:56 INFO - TinderboxPrint: Swap in / out
407,928,832 / 0 13:25:56 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 398361088; Write bytes: 392267264; Read time: 29383; Write time: 728 13:25:56 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1884160; Write bytes: 296192000; Read time: 14; Write time: 1106 13:25:57 INFO - Running post-run listener: _upload_blobber_files 13:25:57 INFO - Blob upload gear active. 13:25:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:25:57 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 13:25:57 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'] 13:25:57 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 13:25:57 INFO - (blobuploader) - INFO - Open directory for files ... 13:25:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:25:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:25:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:25:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:25:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:25:58 INFO - (blobuploader) - INFO - Done attempting. 13:25:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:25:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:25:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:25:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:25:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:25:59 INFO - (blobuploader) - INFO - Done attempting. 13:25:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:25:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:25:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:26:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:26:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:26:02 INFO - (blobuploader) - INFO - Done attempting. 13:26:02 INFO - (blobuploader) - INFO - Iteration through files over. 13:26:02 INFO - Return code: 0 13:26:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:26:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:26:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ac9d4096d133324bedf36ec4ef9466d4b2999e26191c02de164c5ec2c2de5dc2a302fae2984b303adec8eae29d396446dccedfaab0271ddc9704a61316e19b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0141efc0f2d277b65f3699c36907ce92d3f4729e976e7d573dba43b4ed9114c4e0f4422fc04ef55e75837a11a597275aeb4a2d45c467ca0597b33b265deeba80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f72a72ab96c7d4c4b06646b9ee31ff59a72c2af0da158338a7792d1968f3015f54062c88a24debdef4e675859a17080c7fc3844f2405786bf122025a7a016d80"} 13:26:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:26:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:26:02 INFO - Contents: 13:26:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ac9d4096d133324bedf36ec4ef9466d4b2999e26191c02de164c5ec2c2de5dc2a302fae2984b303adec8eae29d396446dccedfaab0271ddc9704a61316e19b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0141efc0f2d277b65f3699c36907ce92d3f4729e976e7d573dba43b4ed9114c4e0f4422fc04ef55e75837a11a597275aeb4a2d45c467ca0597b33b265deeba80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f72a72ab96c7d4c4b06646b9ee31ff59a72c2af0da158338a7792d1968f3015f54062c88a24debdef4e675859a17080c7fc3844f2405786bf122025a7a016d80"} 13:26:02 INFO - Running post-run listener: copy_logs_to_upload_dir 13:26:02 INFO - Copying logs to upload dir... 13:26:02 INFO - mkdir: /builds/slave/test/build/upload/logs 13:26:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.314390 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-09 13:26:02.542040) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 13:26:02.545521) ========= 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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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/1ac9d4096d133324bedf36ec4ef9466d4b2999e26191c02de164c5ec2c2de5dc2a302fae2984b303adec8eae29d396446dccedfaab0271ddc9704a61316e19b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0141efc0f2d277b65f3699c36907ce92d3f4729e976e7d573dba43b4ed9114c4e0f4422fc04ef55e75837a11a597275aeb4a2d45c467ca0597b33b265deeba80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f72a72ab96c7d4c4b06646b9ee31ff59a72c2af0da158338a7792d1968f3015f54062c88a24debdef4e675859a17080c7fc3844f2405786bf122025a7a016d80"} build_url:https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011431 build_url: 'https://queue.taskcluster.net/v1/task/ANKPVJGdRLKi7GWe6DWeRw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ac9d4096d133324bedf36ec4ef9466d4b2999e26191c02de164c5ec2c2de5dc2a302fae2984b303adec8eae29d396446dccedfaab0271ddc9704a61316e19b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0141efc0f2d277b65f3699c36907ce92d3f4729e976e7d573dba43b4ed9114c4e0f4422fc04ef55e75837a11a597275aeb4a2d45c467ca0597b33b265deeba80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f72a72ab96c7d4c4b06646b9ee31ff59a72c2af0da158338a7792d1968f3015f54062c88a24debdef4e675859a17080c7fc3844f2405786bf122025a7a016d80"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 13:26:02.580384) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 13:26:02.581057) ========= 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.sBBm6JbHZx/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.l0F9pO0QIB/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.005115 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 13:26:02.631566) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 13:26:02.632151) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 13:26:02.632807) ========= ========= Total master_lag: 0.20 =========