builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0194 starttime: 1469206477.56 results: success (0) buildid: 20160722080026 builduid: c29f9f9830d3452da61197b155ab2c91 revision: ff29f08b72c466b65dea5efa6f82f99add8f17ef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 09:54:37.555402) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 09:54:37.555816) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 09:54:37.556106) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 09:54:37.614175) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 09:54:37.614562) ========= 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.5szOLqZRff/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.zMzLrogGUm/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-22 09:54:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.9M=0.001s 2016-07-22 09:54:37 (12.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125276 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 09:54:37.798030) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 09:54:37.798367) ========= 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.5szOLqZRff/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.zMzLrogGUm/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.027667 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 09:54:37.878857) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-22 09:54:37.879253) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ff29f08b72c466b65dea5efa6f82f99add8f17ef --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 ff29f08b72c466b65dea5efa6f82f99add8f17ef --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5szOLqZRff/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.zMzLrogGUm/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-22 09:54:37,954 truncating revision to first 12 chars 2016-07-22 09:54:37,954 Setting DEBUG logging. 2016-07-22 09:54:37,954 attempt 1/10 2016-07-22 09:54:37,954 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ff29f08b72c4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 09:54:39,042 unpacking tar archive at: fx-team-ff29f08b72c4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.349911 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-22 09:54:39.254174) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 09:54:39.254571) ========= script_repo_revision: ff29f08b72c466b65dea5efa6f82f99add8f17ef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 09:54:39.254974) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 09:54:39.255273) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 09:54:39.274001) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-22 09:54:39.274441) ========= /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.5szOLqZRff/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.zMzLrogGUm/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:54:39 INFO - MultiFileLogger online at 20160722 09:54:39 in /builds/slave/test 09:54:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:54:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:54:39 INFO - {'append_to_log': False, 09:54:39 INFO - 'base_work_dir': '/builds/slave/test', 09:54:39 INFO - 'blob_upload_branch': 'Fx-Team', 09:54:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:54:39 INFO - 'branch': 'Fx-Team', 09:54:39 INFO - 'buildbot_json_path': 'buildprops.json', 09:54:39 INFO - 'config_files': ('talos/mac_config.py',), 09:54:39 INFO - 'default_actions': ('clobber', 09:54:39 INFO - 'read-buildbot-config', 09:54:39 INFO - 'download-and-extract', 09:54:39 INFO - 'populate-webroot', 09:54:39 INFO - 'create-virtualenv', 09:54:39 INFO - 'install', 09:54:39 INFO - 'run-tests'), 09:54:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:54:39 INFO - 'download_minidump_stackwalk': True, 09:54:39 INFO - 'download_symbols': 'ondemand', 09:54:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:54:39 INFO - 'tooltool.py': '/tools/tooltool.py', 09:54:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:54:39 INFO - '/tools/misc-python/virtualenv.py')}, 09:54:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:54:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:54:39 INFO - 'installer_path': 'installer.exe', 09:54:39 INFO - 'log_level': 'info', 09:54:39 INFO - 'log_name': 'talos', 09:54:39 INFO - 'log_to_console': True, 09:54:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:54:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:54:39 INFO - 'opt_config_files': (), 09:54:39 INFO - 'pip_index': False, 09:54:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:54:39 INFO - 'cmd': ('bash', 09:54:39 INFO - '-c', 09:54:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:54:39 INFO - 'enabled': True, 09:54:39 INFO - 'halt_on_failure': False, 09:54:39 INFO - 'name': 'check_screen_resolution'},), 09:54:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:54:39 INFO - 'cmd': ('bash', 09:54:39 INFO - '-c', 09:54:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:54:39 INFO - 'enabled': True, 09:54:39 INFO - 'halt_on_failure': False, 09:54:39 INFO - 'name': 'check_screen_resolution'},), 09:54:39 INFO - 'run_cmd_checks_enabled': True, 09:54:39 INFO - 'sps_profile': False, 09:54:39 INFO - 'sps_profile_interval': 0, 09:54:39 INFO - 'suite': 'dromaeojs', 09:54:39 INFO - 'system_bits': '32', 09:54:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:54:39 INFO - 'title': 't-yosemite-r7-0194', 09:54:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:54:39 INFO - 'use_talos_json': True, 09:54:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:54:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:54:39 INFO - 'work_dir': 'build'} 09:54:39 INFO - [mozharness: 2016-07-22 16:54:39.474891Z] Running clobber step. 09:54:39 INFO - Running pre-action listener: _resource_record_pre_action 09:54:39 INFO - Running main action method: clobber 09:54:39 INFO - rmtree: /builds/slave/test/build 09:54:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:54:41 INFO - Running post-action listener: _resource_record_post_action 09:54:41 INFO - [mozharness: 2016-07-22 16:54:41.633514Z] Finished clobber step (success) 09:54:41 INFO - [mozharness: 2016-07-22 16:54:41.633648Z] Running read-buildbot-config step. 09:54:41 INFO - Running pre-action listener: _resource_record_pre_action 09:54:41 INFO - Running main action method: read_buildbot_config 09:54:41 INFO - Using buildbot properties: 09:54:41 INFO - { 09:54:41 INFO - "project": "", 09:54:41 INFO - "product": "firefox", 09:54:41 INFO - "script_repo_revision": "production", 09:54:41 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:54:41 INFO - "repository": "", 09:54:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:54:41 INFO - "buildid": "20160722080026", 09:54:41 INFO - "pgo_build": "False", 09:54:41 INFO - "basedir": "/builds/slave/test", 09:54:41 INFO - "buildnumber": 72, 09:54:41 INFO - "slavename": "t-yosemite-r7-0194", 09:54:41 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:54:41 INFO - "platform": "yosemite_r7", 09:54:41 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 09:54:41 INFO - "branch": "fx-team", 09:54:41 INFO - "repo_path": "integration/fx-team", 09:54:41 INFO - "revision": "ff29f08b72c466b65dea5efa6f82f99add8f17ef", 09:54:41 INFO - "stage_platform": "macosx64", 09:54:41 INFO - "builduid": "c29f9f9830d3452da61197b155ab2c91", 09:54:41 INFO - "slavebuilddir": "test" 09:54:41 INFO - } 09:54:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:54:41 INFO - Running post-action listener: _resource_record_post_action 09:54:41 INFO - [mozharness: 2016-07-22 16:54:41.636900Z] Finished read-buildbot-config step (success) 09:54:41 INFO - [mozharness: 2016-07-22 16:54:41.637001Z] Running download-and-extract step. 09:54:41 INFO - Running pre-action listener: _resource_record_pre_action 09:54:41 INFO - Running main action method: download_and_extract 09:54:41 INFO - mkdir: /builds/slave/test/build/tests 09:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:41 INFO - https://queue.taskcluster.net/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:54:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:54:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:54:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:54:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:54:42 INFO - Downloaded 1199 bytes. 09:54:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:54:42 INFO - Using the following test package requirements: 09:54:42 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:54:42 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:54:42 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:54:42 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'jsshell-mac.zip'], 09:54:42 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:54:42 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:54:42 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:54:42 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:54:42 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:54:42 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:54:42 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:42 INFO - https://queue.taskcluster.net/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:54:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:54:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:54:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:54:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:54:44 INFO - Downloaded 18808563 bytes. 09:54:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:54:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:54:47 INFO - Return code: 0 09:54:47 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:47 INFO - https://queue.taskcluster.net/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:54:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:54:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:54:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:54:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:54:49 INFO - Downloaded 18808563 bytes. 09:54:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:54:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:54:53 INFO - Return code: 0 09:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:53 INFO - https://queue.taskcluster.net/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:54:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:54:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:54:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:54:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:54:54 INFO - Downloaded 14403289 bytes. 09:54:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:54:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:54:54 INFO - Return code: 0 09:54:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:54 INFO - https://queue.taskcluster.net/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:54:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:54:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:54:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:54:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:54:57 INFO - Downloaded 112896230 bytes. 09:54:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:54:57 INFO - mkdir: /builds/slave/test/properties 09:54:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:54:57 INFO - Writing to file /builds/slave/test/properties/build_url 09:54:57 INFO - Contents: 09:54:57 INFO - build_url:https://queue.taskcluster.net/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:54:58 INFO - Running post-action listener: _resource_record_post_action 09:54:58 INFO - Running post-action listener: set_extra_try_arguments 09:54:58 INFO - [mozharness: 2016-07-22 16:54:58.465097Z] Finished download-and-extract step (success) 09:54:58 INFO - [mozharness: 2016-07-22 16:54:58.465274Z] Running populate-webroot step. 09:54:58 INFO - Running pre-action listener: _resource_record_pre_action 09:54:58 INFO - Running main action method: populate_webroot 09:54:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:54:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:54:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:54:58 INFO - '--tppagecycles', 09:54:58 INFO - '7'], 09:54:58 INFO - 'tests': ['tsvgm']}}, 09:54:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:54:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:54:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:54:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:54:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:54:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:54:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:58 INFO - 'talos_options': ['--disable-e10s'], 09:54:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:54:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:54:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:58 INFO - 'talos_options': ['--disable-e10s'], 09:54:58 INFO - 'tests': ['damp', 'tps']}, 09:54:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:58 INFO - 'tests': ['damp', 'tps']}, 09:54:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:54:58 INFO - 'tests': ['dromaeo_dom']}, 09:54:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:54:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:54:58 INFO - 'tests': ['basic_compositor_video']}, 09:54:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:54:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:54:58 INFO - 'tests': ['a11yr', 09:54:58 INFO - 'ts_paint', 09:54:58 INFO - 'tpaint', 09:54:58 INFO - 'sessionrestore', 09:54:58 INFO - 'sessionrestore_no_auto_restore', 09:54:58 INFO - 'tabpaint']}, 09:54:58 INFO - 'other-e10s': {'tests': ['a11yr', 09:54:58 INFO - 'ts_paint', 09:54:58 INFO - 'tpaint', 09:54:58 INFO - 'sessionrestore', 09:54:58 INFO - 'sessionrestore_no_auto_restore', 09:54:58 INFO - 'tabpaint']}, 09:54:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:54:58 INFO - 'ts_paint', 09:54:58 INFO - 'tpaint', 09:54:58 INFO - 'sessionrestore', 09:54:58 INFO - 'sessionrestore_no_auto_restore', 09:54:58 INFO - 'tabpaint']}, 09:54:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:54:58 INFO - 'ts_paint', 09:54:58 INFO - 'tpaint', 09:54:58 INFO - 'sessionrestore', 09:54:58 INFO - 'sessionrestore_no_auto_restore', 09:54:58 INFO - 'tabpaint']}, 09:54:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:54:58 INFO - 'tests': ['a11yr', 09:54:58 INFO - 'ts_paint', 09:54:58 INFO - 'tpaint', 09:54:58 INFO - 'sessionrestore', 09:54:58 INFO - 'sessionrestore_no_auto_restore', 09:54:58 INFO - 'tabpaint']}, 09:54:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:54:58 INFO - 'tests': ['a11yr', 09:54:58 INFO - 'ts_paint', 09:54:58 INFO - 'tpaint', 09:54:58 INFO - 'sessionrestore', 09:54:58 INFO - 'sessionrestore_no_auto_restore', 09:54:58 INFO - 'tabpaint']}, 09:54:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:54:58 INFO - 'tests': ['tsvgx', 09:54:58 INFO - 'tsvgr_opacity', 09:54:58 INFO - 'tart', 09:54:58 INFO - 'tscrollx', 09:54:58 INFO - 'cart']}, 09:54:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:54:58 INFO - 'tsvgr_opacity', 09:54:58 INFO - 'tart', 09:54:58 INFO - 'tscrollx', 09:54:58 INFO - 'cart']}, 09:54:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:58 INFO - 'talos_options': ['--disable-e10s'], 09:54:58 INFO - 'tests': ['tp5o']}, 09:54:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:58 INFO - 'tests': ['tp5o']}, 09:54:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:58 INFO - 'talos_options': ['--disable-e10s', 09:54:58 INFO - '--xperf_path', 09:54:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:54:58 INFO - 'tests': ['tp5n']}, 09:54:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:58 INFO - 'talos_options': ['--xperf_path', 09:54:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:54:58 INFO - 'tests': ['tp5n']}}, 09:54:58 INFO - 'talos.zip': {'path': '', 09:54:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:54:58 INFO - Running post-action listener: _resource_record_post_action 09:54:58 INFO - [mozharness: 2016-07-22 16:54:58.484364Z] Finished populate-webroot step (success) 09:54:58 INFO - [mozharness: 2016-07-22 16:54:58.484496Z] Running create-virtualenv step. 09:54:58 INFO - Running pre-action listener: _resource_record_pre_action 09:54:58 INFO - Running main action method: create_virtualenv 09:54:58 INFO - Creating virtualenv /builds/slave/test/build/venv 09:54:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:54:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:54:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:54:58 INFO - Using real prefix '/tools/python27' 09:54:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:54:59 INFO - Installing distribute.............................................................................................................................................................................................done. 09:55:02 INFO - Installing pip.................done. 09:55:02 INFO - Return code: 0 09:55:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10672ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9670d9f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10668fd30>, '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 0x1063f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106687b20>, '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.5szOLqZRff/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.zMzLrogGUm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:55:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:55:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5szOLqZRff/Render', 09:55:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:02 INFO - 'HOME': '/Users/cltbld', 09:55:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:02 INFO - 'LOGNAME': 'cltbld', 09:55:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:02 INFO - 'MOZ_NO_REMOTE': '1', 09:55:02 INFO - 'NO_EM_RESTART': '1', 09:55:02 INFO - 'PAGER': '/bin/cat', 09:55:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:02 INFO - 'PWD': '/builds/slave/test', 09:55:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:02 INFO - 'SHELL': '/bin/bash', 09:55:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMzLrogGUm/Listeners', 09:55:02 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 09:55:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:02 INFO - 'USER': 'cltbld', 09:55:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:02 INFO - 'XPC_FLAGS': '0x0', 09:55:02 INFO - 'XPC_SERVICE_NAME': '0', 09:55:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:55:02 INFO - Cleaning up... 09:55:02 INFO - Return code: 0 09:55:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10672ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9670d9f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10668fd30>, '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 0x1063f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106687b20>, '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.5szOLqZRff/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.zMzLrogGUm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:55:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:55:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5szOLqZRff/Render', 09:55:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:02 INFO - 'HOME': '/Users/cltbld', 09:55:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:02 INFO - 'LOGNAME': 'cltbld', 09:55:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:02 INFO - 'MOZ_NO_REMOTE': '1', 09:55:02 INFO - 'NO_EM_RESTART': '1', 09:55:02 INFO - 'PAGER': '/bin/cat', 09:55:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:02 INFO - 'PWD': '/builds/slave/test', 09:55:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:02 INFO - 'SHELL': '/bin/bash', 09:55:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMzLrogGUm/Listeners', 09:55:02 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 09:55:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:02 INFO - 'USER': 'cltbld', 09:55:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:02 INFO - 'XPC_FLAGS': '0x0', 09:55:02 INFO - 'XPC_SERVICE_NAME': '0', 09:55:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:02 INFO - Downloading/unpacking psutil>=3.1.1 09:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:55:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:55:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:55:05 INFO - Installing collected packages: psutil 09:55:05 INFO - Running setup.py install for psutil 09:55:05 INFO - building 'psutil._psutil_osx' extension 09:55:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:55:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:55:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:55:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:55:06 INFO - building 'psutil._psutil_posix' extension 09:55:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:55:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:55:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:55:06 INFO - ^ 09:55:06 INFO - 1 warning generated. 09:55:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:55:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:55:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:55:06 INFO - Successfully installed psutil 09:55:06 INFO - Cleaning up... 09:55:06 INFO - Return code: 0 09:55:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:06 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 0x10672ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9670d9f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10668fd30>, '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 0x1063f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106687b20>, '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.5szOLqZRff/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.zMzLrogGUm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:55:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:55:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5szOLqZRff/Render', 09:55:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:06 INFO - 'HOME': '/Users/cltbld', 09:55:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:06 INFO - 'LOGNAME': 'cltbld', 09:55:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:06 INFO - 'MOZ_NO_REMOTE': '1', 09:55:06 INFO - 'NO_EM_RESTART': '1', 09:55:06 INFO - 'PAGER': '/bin/cat', 09:55:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:06 INFO - 'PWD': '/builds/slave/test', 09:55:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:06 INFO - 'SHELL': '/bin/bash', 09:55:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMzLrogGUm/Listeners', 09:55:06 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 09:55:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:06 INFO - 'USER': 'cltbld', 09:55:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:06 INFO - 'XPC_FLAGS': '0x0', 09:55:06 INFO - 'XPC_SERVICE_NAME': '0', 09:55:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:55:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:55:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:55:09 INFO - Installing collected packages: mozsystemmonitor 09:55:09 INFO - Running setup.py install for mozsystemmonitor 09:55:09 INFO - Successfully installed mozsystemmonitor 09:55:09 INFO - Cleaning up... 09:55:09 INFO - Return code: 0 09:55:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:55:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:09 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 0x10672ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9670d9f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10668fd30>, '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 0x1063f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106687b20>, '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.5szOLqZRff/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.zMzLrogGUm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:55:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:55:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5szOLqZRff/Render', 09:55:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:09 INFO - 'HOME': '/Users/cltbld', 09:55:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:09 INFO - 'LOGNAME': 'cltbld', 09:55:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:09 INFO - 'MOZ_NO_REMOTE': '1', 09:55:09 INFO - 'NO_EM_RESTART': '1', 09:55:09 INFO - 'PAGER': '/bin/cat', 09:55:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:09 INFO - 'PWD': '/builds/slave/test', 09:55:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:09 INFO - 'SHELL': '/bin/bash', 09:55:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMzLrogGUm/Listeners', 09:55:09 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 09:55:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:09 INFO - 'USER': 'cltbld', 09:55:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:09 INFO - 'XPC_FLAGS': '0x0', 09:55:09 INFO - 'XPC_SERVICE_NAME': '0', 09:55:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:09 INFO - Downloading/unpacking jsonschema==2.5.1 09:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:55:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:12 INFO - Downloading functools32-3.2.3-2.tar.gz 09:55:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:55:12 INFO - warning: no files found matching '*.txt' 09:55:12 INFO - no previously-included directories found matching 'build' 09:55:12 INFO - no previously-included directories found matching 'dist' 09:55:12 INFO - no previously-included directories found matching '.git*' 09:55:12 INFO - Installing collected packages: jsonschema, functools32 09:55:12 INFO - Running setup.py install for functools32 09:55:13 INFO - warning: no files found matching '*.txt' 09:55:13 INFO - no previously-included directories found matching 'build' 09:55:13 INFO - no previously-included directories found matching 'dist' 09:55:13 INFO - no previously-included directories found matching '.git*' 09:55:13 INFO - Successfully installed jsonschema functools32 09:55:13 INFO - Cleaning up... 09:55:13 INFO - Return code: 0 09:55:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:55:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:13 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 0x10672ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9670d9f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10668fd30>, '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 0x1063f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106687b20>, '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.5szOLqZRff/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.zMzLrogGUm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:55:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:55:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5szOLqZRff/Render', 09:55:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:13 INFO - 'HOME': '/Users/cltbld', 09:55:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:13 INFO - 'LOGNAME': 'cltbld', 09:55:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:13 INFO - 'MOZ_NO_REMOTE': '1', 09:55:13 INFO - 'NO_EM_RESTART': '1', 09:55:13 INFO - 'PAGER': '/bin/cat', 09:55:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:13 INFO - 'PWD': '/builds/slave/test', 09:55:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:13 INFO - 'SHELL': '/bin/bash', 09:55:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMzLrogGUm/Listeners', 09:55:13 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 09:55:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:13 INFO - 'USER': 'cltbld', 09:55:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:13 INFO - 'XPC_FLAGS': '0x0', 09:55:13 INFO - 'XPC_SERVICE_NAME': '0', 09:55:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:13 INFO - Downloading/unpacking blobuploader==1.2.4 09:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:15 INFO - Downloading blobuploader-1.2.4.tar.gz 09:55:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:55:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:55:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:17 INFO - Downloading docopt-0.6.1.tar.gz 09:55:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:55:17 INFO - Installing collected packages: blobuploader, requests, docopt 09:55:17 INFO - Running setup.py install for blobuploader 09:55:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:55:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:55:17 INFO - Running setup.py install for requests 09:55:17 INFO - Running setup.py install for docopt 09:55:17 INFO - Successfully installed blobuploader requests docopt 09:55:17 INFO - Cleaning up... 09:55:17 INFO - Return code: 0 09:55:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:55:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:17 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 0x10672ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9670d9f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10668fd30>, '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 0x1063f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106687b20>, '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.5szOLqZRff/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.zMzLrogGUm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:55:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:55:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5szOLqZRff/Render', 09:55:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:17 INFO - 'HOME': '/Users/cltbld', 09:55:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:17 INFO - 'LOGNAME': 'cltbld', 09:55:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:17 INFO - 'MOZ_NO_REMOTE': '1', 09:55:17 INFO - 'NO_EM_RESTART': '1', 09:55:17 INFO - 'PAGER': '/bin/cat', 09:55:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:17 INFO - 'PWD': '/builds/slave/test', 09:55:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:17 INFO - 'SHELL': '/bin/bash', 09:55:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMzLrogGUm/Listeners', 09:55:17 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 09:55:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:17 INFO - 'USER': 'cltbld', 09:55:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:18 INFO - 'XPC_FLAGS': '0x0', 09:55:18 INFO - 'XPC_SERVICE_NAME': '0', 09:55:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:55:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-qiD0UQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:55:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-2b7CAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:55:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-5Giap4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:55:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-V4FJ3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:55:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-rqNkBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:55:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-dvztrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:55:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-mtUAAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:55:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-FZ5dkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:55:19 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-twvDnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:55:19 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-IJDVYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:55:19 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-GDjMeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:55:19 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-mKMSD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:55:19 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-2Ynodu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:55:19 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-kZ57Kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:55:19 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-JTYQRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:55:19 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-r6Zcjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:55:19 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-w2Qfwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:55:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:55:20 INFO - Running setup.py install for manifestparser 09:55:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:55:20 INFO - Running setup.py install for mozcrash 09:55:20 INFO - Running setup.py install for mozdebug 09:55:20 INFO - Running setup.py install for mozdevice 09:55:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:55:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:55:20 INFO - Running setup.py install for mozfile 09:55:20 INFO - Running setup.py install for mozhttpd 09:55:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:55:20 INFO - Running setup.py install for mozinfo 09:55:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:55:21 INFO - Running setup.py install for mozInstall 09:55:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:55:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:55:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:55:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:55:21 INFO - Running setup.py install for mozleak 09:55:21 INFO - Running setup.py install for mozlog 09:55:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:55:21 INFO - Running setup.py install for moznetwork 09:55:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:55:21 INFO - Running setup.py install for mozprocess 09:55:21 INFO - Running setup.py install for mozprofile 09:55:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:55:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:55:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:55:22 INFO - Running setup.py install for mozrunner 09:55:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:55:22 INFO - Running setup.py install for mozscreenshot 09:55:22 INFO - Running setup.py install for moztest 09:55:22 INFO - Running setup.py install for mozversion 09:55:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:55:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:55:22 INFO - Cleaning up... 09:55:22 INFO - Return code: 0 09:55:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:22 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 0x10672ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9670d9f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10668fd30>, '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 0x1063f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106687b20>, '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.5szOLqZRff/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.zMzLrogGUm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:55:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5szOLqZRff/Render', 09:55:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:22 INFO - 'HOME': '/Users/cltbld', 09:55:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:22 INFO - 'LOGNAME': 'cltbld', 09:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:22 INFO - 'MOZ_NO_REMOTE': '1', 09:55:22 INFO - 'NO_EM_RESTART': '1', 09:55:22 INFO - 'PAGER': '/bin/cat', 09:55:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:22 INFO - 'PWD': '/builds/slave/test', 09:55:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:22 INFO - 'SHELL': '/bin/bash', 09:55:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMzLrogGUm/Listeners', 09:55:22 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 09:55:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:22 INFO - 'USER': 'cltbld', 09:55:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:22 INFO - 'XPC_FLAGS': '0x0', 09:55:22 INFO - 'XPC_SERVICE_NAME': '0', 09:55:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:55:22 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-6X7x97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:55:23 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-tqS1yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:55:23 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-N_8jFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:55:23 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-jI8Vlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:55:23 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-_X3hJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:55:23 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-_e7jY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:55:23 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-BJ9EDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:55:23 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-tCSwY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:55:23 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-wblOEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:55:23 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-297XjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:55:23 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-wLsx5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:55:24 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-ul3OcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:55:24 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-hTeRB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:55:24 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-K39bdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:55:24 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-4pu8tZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:55:24 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Lscoaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:55:24 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-8czTdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:55:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:27 INFO - Downloading blessings-1.6.tar.gz 09:55:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:55:27 INFO - Installing collected packages: blessings 09:55:27 INFO - Running setup.py install for blessings 09:55:27 INFO - Successfully installed blessings 09:55:27 INFO - Cleaning up... 09:55:27 INFO - Return code: 0 09:55:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:55:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:55:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:55:27 INFO - Reading from file tmpfile_stdout 09:55:27 INFO - Current package versions: 09:55:27 INFO - blessings == 1.6 09:55:27 INFO - blobuploader == 1.2.4 09:55:27 INFO - docopt == 0.6.1 09:55:27 INFO - functools32 == 3.2.3-2 09:55:27 INFO - jsonschema == 2.5.1 09:55:27 INFO - manifestparser == 1.1 09:55:27 INFO - mozInstall == 1.12 09:55:27 INFO - mozcrash == 0.17 09:55:27 INFO - mozdebug == 0.1 09:55:27 INFO - mozdevice == 0.48 09:55:27 INFO - mozfile == 1.2 09:55:27 INFO - mozhttpd == 0.7 09:55:27 INFO - mozinfo == 0.9 09:55:27 INFO - mozleak == 0.1 09:55:27 INFO - mozlog == 3.3 09:55:27 INFO - moznetwork == 0.27 09:55:27 INFO - mozprocess == 0.23 09:55:27 INFO - mozprofile == 0.28 09:55:27 INFO - mozrunner == 6.12 09:55:27 INFO - mozscreenshot == 0.1 09:55:27 INFO - mozsystemmonitor == 0.3 09:55:27 INFO - moztest == 0.7 09:55:27 INFO - mozversion == 1.4 09:55:27 INFO - psutil == 3.1.1 09:55:27 INFO - requests == 1.2.3 09:55:27 INFO - wsgiref == 0.1.2 09:55:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:27 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 0x10672ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9670d9f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10668fd30>, '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 0x1063f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106687b20>, '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.5szOLqZRff/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.zMzLrogGUm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:55:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:55:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5szOLqZRff/Render', 09:55:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:27 INFO - 'HOME': '/Users/cltbld', 09:55:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:27 INFO - 'LOGNAME': 'cltbld', 09:55:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:27 INFO - 'MOZ_NO_REMOTE': '1', 09:55:27 INFO - 'NO_EM_RESTART': '1', 09:55:27 INFO - 'PAGER': '/bin/cat', 09:55:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:27 INFO - 'PWD': '/builds/slave/test', 09:55:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:27 INFO - 'SHELL': '/bin/bash', 09:55:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMzLrogGUm/Listeners', 09:55:27 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 09:55:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:27 INFO - 'USER': 'cltbld', 09:55:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:27 INFO - 'XPC_FLAGS': '0x0', 09:55:27 INFO - 'XPC_SERVICE_NAME': '0', 09:55:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:55:28 INFO - Cleaning up... 09:55:28 INFO - Return code: 0 09:55:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:28 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 0x10672ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9670d9f7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10668fd30>, '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 0x1063f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106687b20>, '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.5szOLqZRff/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.zMzLrogGUm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:55:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:55:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5szOLqZRff/Render', 09:55:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:28 INFO - 'HOME': '/Users/cltbld', 09:55:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:28 INFO - 'LOGNAME': 'cltbld', 09:55:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:28 INFO - 'MOZ_NO_REMOTE': '1', 09:55:28 INFO - 'NO_EM_RESTART': '1', 09:55:28 INFO - 'PAGER': '/bin/cat', 09:55:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:28 INFO - 'PWD': '/builds/slave/test', 09:55:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:28 INFO - 'SHELL': '/bin/bash', 09:55:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMzLrogGUm/Listeners', 09:55:28 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 09:55:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:28 INFO - 'USER': 'cltbld', 09:55:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:28 INFO - 'XPC_FLAGS': '0x0', 09:55:28 INFO - 'XPC_SERVICE_NAME': '0', 09:55:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:55:28 INFO - Cleaning up... 09:55:28 INFO - Return code: 0 09:55:28 INFO - Running post-action listener: _resource_record_post_action 09:55:28 INFO - Running post-action listener: _start_resource_monitoring 09:55:28 INFO - Starting resource monitoring. 09:55:28 INFO - [mozharness: 2016-07-22 16:55:28.334320Z] Finished create-virtualenv step (success) 09:55:28 INFO - [mozharness: 2016-07-22 16:55:28.335304Z] Running install step. 09:55:28 INFO - Running pre-action listener: _resource_record_pre_action 09:55:28 INFO - Running main action method: install 09:55:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:55:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:55:28 INFO - Reading from file tmpfile_stdout 09:55:28 INFO - Detecting whether we're running mozinstall >=1.0... 09:55:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:55:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:55:28 INFO - Reading from file tmpfile_stdout 09:55:28 INFO - Output received: 09:55:28 INFO - Usage: mozinstall [options] installer 09:55:28 INFO - Options: 09:55:28 INFO - -h, --help show this help message and exit 09:55:28 INFO - -d DEST, --destination=DEST 09:55:28 INFO - Directory to install application into. [default: 09:55:28 INFO - "/builds/slave/test"] 09:55:28 INFO - --app=APP Application being installed. [default: firefox] 09:55:28 INFO - mkdir: /builds/slave/test/build/application 09:55:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:55:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:56:03 INFO - Reading from file tmpfile_stdout 09:56:03 INFO - Output received: 09:56:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:56:03 INFO - Running post-action listener: _resource_record_post_action 09:56:03 INFO - [mozharness: 2016-07-22 16:56:03.269906Z] Finished install step (success) 09:56:03 INFO - [mozharness: 2016-07-22 16:56:03.270029Z] Running run-tests step. 09:56:03 INFO - Running pre-action listener: _resource_record_pre_action 09:56:03 INFO - Running main action method: run_tests 09:56:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:56:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:56:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:56:03 INFO - 2016-07-22 09:56:03.284 screenresolution[1640:8348] starting screenresolution argv=screenresolution get 09:56:03 INFO - 2016-07-22 09:56:03.287 screenresolution[1640:8348] Display 0: 1600x1200x32@60 09:56:03 INFO - 2016-07-22 09:56:03.292 screenresolution[1641:8350] starting screenresolution argv=screenresolution list 09:56:03 INFO - Available Modes on Display 0 09:56:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:56:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:56:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:56:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:56:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:56:03 INFO - Intel Iris: 09:56:03 INFO - Chipset Model: Intel Iris 09:56:03 INFO - Type: GPU 09:56:03 INFO - Bus: Built-In 09:56:03 INFO - VRAM (Dynamic, Max): 1536 MB 09:56:03 INFO - Vendor: Intel (0x8086) 09:56:03 INFO - Device ID: 0x0a2e 09:56:03 INFO - Revision ID: 0x0009 09:56:03 INFO - Displays: 09:56:03 INFO - Display: 09:56:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:56:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:56:03 INFO - Display Serial Number: 1600x1200 60 09:56:03 INFO - Main Display: Yes 09:56:03 INFO - Mirror: Off 09:56:03 INFO - Online: Yes 09:56:03 INFO - Rotation: Supported 09:56:03 INFO - Return code: 0 09:56:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:56:03 INFO - Python 2.7.3 09:56:03 INFO - Return code: 0 09:56:03 INFO - grabbing minidump binary from tooltool 09:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:03 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 0x10668fd30>, '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 0x1063f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106687b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:56:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:56:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:56:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:56:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:56:03 INFO - Return code: 0 09:56:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:56:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:56:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:56:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:56:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:56:03 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/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:56:03 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/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0194 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:56:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5szOLqZRff/Render', 09:56:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:03 INFO - 'HOME': '/Users/cltbld', 09:56:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:03 INFO - 'LOGNAME': 'cltbld', 09:56:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:56:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:03 INFO - 'MOZ_NO_REMOTE': '1', 09:56:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:56:03 INFO - 'NO_EM_RESTART': '1', 09:56:03 INFO - 'PAGER': '/bin/cat', 09:56:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:03 INFO - 'PWD': '/builds/slave/test', 09:56:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:56:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:03 INFO - 'SHELL': '/bin/bash', 09:56:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zMzLrogGUm/Listeners', 09:56:03 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 09:56:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:03 INFO - 'USER': 'cltbld', 09:56:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:03 INFO - 'XPC_FLAGS': '0x0', 09:56:03 INFO - 'XPC_SERVICE_NAME': '0', 09:56:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:03 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/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:56:03 INFO - mozversion application_buildid: 20160722080026 09:56:03 INFO - mozversion application_changeset: ff29f08b72c466b65dea5efa6f82f99add8f17ef 09:56:03 INFO - mozversion application_display_name: Nightly 09:56:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:56:03 INFO - mozversion application_name: Firefox 09:56:03 INFO - mozversion application_remotingname: firefox 09:56:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:56:03 INFO - mozversion application_vendor: Mozilla 09:56:03 INFO - mozversion application_version: 50.0a1 09:56:03 INFO - mozversion platform_buildid: 20160722080026 09:56:03 INFO - mozversion platform_changeset: ff29f08b72c466b65dea5efa6f82f99add8f17ef 09:56:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:56:03 INFO - mozversion platform_version: 50.0a1 09:56:03 INFO - using testdate: 1469206563 09:56:03 INFO - actual date: 1469206563 09:56:03 INFO - starting webserver on 'localhost:49266' 09:56:03 INFO - SUITE-START | Running 2 tests 09:56:03 INFO - TEST-START | dromaeo_css 09:56:03 INFO - operating with platform_type : mac_ 09:56:03 INFO - Initialising browser for dromaeo_css test... 09:56:03 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmplN1ba1/profile http://localhost:49266/getInfo.html) 09:56:06 INFO - PROCESS | 1651 | JavaScript error: , line 0: uncaught exception: undefined 09:56:06 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 09:56:06 INFO - PROCESS | 1651 | colorDepth:24 09:56:06 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 09:56:06 INFO - PROCESS | 1651 | __metrics 09:56:06 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:56:06 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:56:07 INFO - TEST-INFO | 1651: exit 0 09:56:07 INFO - Browser initialized. 09:56:07 INFO - Running cycle 1/1 for dromaeo_css test... 09:56:07 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmplN1ba1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:19 INFO - PROCESS | 1654 | RSS: Main: 245714944 09:56:19 INFO - PROCESS | 1654 | 09:57:10 INFO - PROCESS | 1654 | 1469206630841 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:59:21 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 09:59:22 INFO - PROCESS | 1654 | RSS: Main: 351629312 09:59:22 INFO - PROCESS | 1654 | 10:02:25 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 10:02:26 INFO - PROCESS | 1654 | RSS: Main: 389615616 10:02:26 INFO - PROCESS | 1654 | 10:05:28 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 10:05:29 INFO - PROCESS | 1654 | RSS: Main: 372482048 10:05:29 INFO - PROCESS | 1654 | 10:08:31 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 10:08:32 INFO - PROCESS | 1654 | RSS: Main: 362065920 10:08:32 INFO - PROCESS | 1654 | 10:11:35 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 10:11:36 INFO - PROCESS | 1654 | RSS: Main: 355037184 10:11:36 INFO - PROCESS | 1654 | 10:14:39 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 10:14:39 INFO - PROCESS | 1654 | RSS: Main: 433975296 10:14:39 INFO - PROCESS | 1654 | 10:14:39 INFO - PROCESS | 1654 | __start_tp_report 10:14:39 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 10:14:39 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 10:14:39 INFO - PROCESS | 1654 | |i|pagename|runs| 10:14:39 INFO - PROCESS | 1654 | |0;dojo.html;2816.0284701777887;3349.4640857462796;3364.8822291219803;3366.4613661814114;3420.6750358715913;5541.501264886159;5630.211770352149;5671.6597004179675;5680.460356266153;5733.799317023905;5651.208830763697;5674.858128546788;5677.886442271162;5694.77220911163;5711.914321285184;5126.256692779545;5178.249153872686;5192.324997750287;5220.738963051851;5272.367315922094;5273.050850846846;5276.314079169702;5279.155335146377;5285.365756109272;5292.338457692788;5161.329027226455;5192.532672059514;5194.194899790538;5214.113600687873;5270.789168433277;5268.657537260085;5285.445028272053;5296.364436267672;5305.575553955674;5353.38436079848;5132.589392848563;5147.202183661539;5147.588192944556;5220.738963051832;5235.921461178047;5630.493255607015;5686.658800471943;5689.9146512802345;5708.029634962067;5748.511376533;5334.306540149794;6263.874722505524;6266.717997690115;6273.686315684197;6293.056041593752;5666.773329066832;6171.413715697002;6237.469815065725;6302.40127187921;6340.492760579143;5867.061270196748;5879.118132280149;5902.734376953047;5903.940960590339;5904.704764761745;6288.937110628926;6296.622202667763;6313.2424109107205;6316.30520642729;6321.589096708699;5993.520518358521;6043.516518678495;6050.727717252734;6100.359462256488;6155.4767844732805;6256.374362563707;6258.311585725566;6277.121203031579;6277.435030847246;6277.466415354655;6155.569110162246;6192.164057852132;6195.473384762256;6201.565890387629;6220.222472190976;6044.969775151096;6135.447809697149;6140.17107690465;6221.502279817604;6234.501239900797;4918.95081049185;4948.628852836052;4951.158303088484;4960.603151747924;4964.453910069889;4445.133199026159;4479.440069991251;4499.662525310615;4521.570450807168;4531.320301954733;5687.203791469197;5706.9714651426475;5731.598788084794;5767.134929760486;5768.596198266081;6259.217597800275;6289.874202516015;6306.621602703853;6309.873802523924;6342.778002769881;5581.441855814467;5649.717514124277;5661.207430959669;5696.857578560569;5725.456081672318;5390.946090539122;5399.541039011649;5405.162199859028;5442.8911421771345;5456.809011684572;6231.563790534619;6268.216472940882;6291.7797877075;6301.4643755279485;6303.180586523814;6132.264128304632;6146.016637338004;6194.256689197326;6291.622502649856;6297.96851015742;6234.096056071753;6283.465905397909;6288.276848162337;6293.118963345227;6295.307516173126;6185.567010309415;6188.381161883775;6196.039613859859;6205.131281620667;6206.596571222856;5753.424657534213;6179.28938172098;6286.214223222098;6309.148264984168;6310.936890631035;6089.299730530868;6116.938830611637;6122.204113465221;6123.050927106306;6124.228368151265;6098.359672234352;6098.75604975796;6101.420365065312;6106.603070800384;6113.419225173601;5047.570956468741;5767.192593036894;5774.538036957118;5966.313873904556;5990.7903223386975;5927.7036148191555;6004.609700369462;6032.909506357496;6059.969700151471;6074.392560744067;5767.625104368202;5790.478856902899;5832.300123985149;5863.501602363847;5868.149118377726 10:14:39 INFO - PROCESS | 1654 | |1;ext.html;477.3388856433839;519.8258583374569;519.9808375583856;543.912973924172;571.3172758553527;40159.59840401595;44222.7788861056;44270.11459770802;44302.77848610753;46313.7684311578;45631.54368456314;45801.77099114508;45802.77098614511;45860.54139458604;45899.770501147534;8993.910060899372;9736.756581085476;9921.305508614363;9922.553485093153;9923.90076099237;8786.209241168293;11981.880181198207;12152.756944861098;12203.694907627292;12376.62870113898;12123.33321667308;12177.964872985762;12185.02519798414;12185.634430967086;12197.14619976602;8865.822683546357;9056.184943355063;11961.820572691417;12011.65772674329;12031.759364812742;8950.955245223797;8971.596278167499;11513.194076414655;11800.350980696057;11807.173497855154;42463.57536424627;42730.78634606807;42734.57265427368;42880.356794648105;42898.785506072585;2974.672785993539;3466.8959931201853;3647.963520364789;3675.9081022974638;3882.864099756495;31787.82121787826;32129.51805722916;33662.83168584141;33677.15807104817;33695.83152084248;3061.3265081681993;3194.520821876714;3223.2425380035675;3228.580284563015;3233.3048394595276;544296;546227;550291;551948;553319.9999999959;66409.66795165994;66720.6663966677;66735.99896001564;66883;66987.99999999903;58896.41103588909;69730.65134674295;69774.9067527975;69790.65104674445;69961;13608.863911360957;16819.495415137564;16851;16851.831481685273;16854.494365169063;11547.191696581327;11836.23064500822;11861.229020113666;12834.55079072228;12873.485060597472;14235.715285694436;16760.73756146;17137.742933856003;17356.218970146372;20311.18755249803;6802.047713320139;7689;7729.304362607392;7792.103908050533;7867.724629637935;927.1655510040882;995.6963126246419;1020.1532727835878;1066.1470823341297;1090.2695194219891;32088.197795054843;32157.51763723546;32169.195770105467;32563;33020.83489582537;1009.9446078847621;1039.4023436524028;1055.0926203465015;1089.242976131581;1109.517359948425;14135.222562758941;15456.768148477782;15594.454194103151;16201.756973645404;16963.406280780113;727377;728826;731627;731803.0229546562;738656;63149.368506315266;63584.682076590245;63662;63731.36268637347;63799.6810015947;1273.2233337664018;1313.8817506424468;1371.3897315694421;1409.2108419285028;1430.9427622894968;28110.120078798263;31561.526577100904;32428.351432970736;33156.50265246072;33196.33607327792;53330.73334633302;53975.99999999843;53988.730056349465;54117.647058823844;54426;589974;592980;594158;595027;596719;27682.86158569275;28096;28153.57769633498;28688.856555717088;29246;49924.00151997012;50096.74951625219;50180;50797.74601126971;50930.633670791154;41420.1715965685;41499;41827;42076.527321543595;43179.78410107929;41969.79015104905;42510.7246782597;42635.14729705451;43264;43603.56396435995;41032;41469.170616588104;41658.58341416547;41705.165896682505;41790.164196716505;33802.830985845896;36618.45072323864;36816.81591592025;36950.6304936958;36982.07544811293;24140.87929560411;26673.19980400591;26874.462510749283;27031.594526082517;27249.31876703098 10:14:39 INFO - PROCESS | 1654 | |2;jquery.html;332.46971081125594;376.30759402699044;376.60644626365445;385.97684138951666;408.85076946914364;15735.370585176594;16139.838601613981;20661.380158595228;20898.582028359422;21026.789732102676;20262.797372026274;20995.47511312218;21271;21389.679154812653;21770.455738606543;5926.733297001645;6041.577089603729;6044.365341639131;6077.96674565325;6143.170671959296;5948.226730525026;6039.214902062726;6119.847003824918;6122.602030868002;6136.018237082068;4157.293260145782;6203.503719702413;6674.16572928384;6736.427403670669;6809;6660.666966651648;6718.227403848571;6792.218894827109;6799.218089919673;6800.863982720367;5862.9446699594055;5864.5894787364905;5867.973104706659;5881.411858814085;5898.8230353089475;24993.250202493953;26624.60063099055;26733.86633066822;26768.866155669293;26917.461650767073;2280.4184932842186;2337.649352597107;2562.397836508419;2662.201339598113;2941;12660.620181394574;15515.767263491056;15799.76300355484;15959.361625534964;16150.03099814015;1806.3045727395047;1829.5609053827118;1861.543921739169;1918.6258679557493;1944.980550194494;77406;115250;130662;132382.99999999904;134193.32903335517;10330.745815068229;10438;10947.34315941046;10953.780924381626;11162.888371116347;13673.179609223478;13703.725925481434;13709.451621935012;13722.794158087636;13741.450341986409;8236.83526329482;8524.829503410021;8574.699885503978;8645;8728.956355218183;8633.870491942625;8634.827303454022;8647.654093836301;8815.955920220358;8823.426477278956;6510.479161667055;6642.734290628321;6651.966740166365;6667.799966001027;6672.199336079701;1788.4277031349843;1815.7004094324552;1859.6838537448666;1861.6090620969749;1863.0684657671165;950.6815216902415;954.9952250238705;1017.8829434614966;1024.8923862994427;1030.294248439821;19234.71147932782;20417.591648166952;20664.58670826575;21779.891100544395;22596.322110336718;739.9593022383713;900.2977677411629;977.4233202410612;978.3787295067683;1047.219821233171;10990.450477476094;11386.316820990602;11441.455403520418;11448.141389395829;11456.312621242752;526432;529187;532135.3393233087;561618;597134;130144.69855301516;131086;137909;138081;139765;1010.9039641234071;1027.5786927359748;1048.0079373389017;1048.8374301983206;1049.884512703602;18231.908840456243;18793.1543080559;18918.243270269035;19185.8081419184;19513.317033904314;43126.78436607797;53497;53515.73242133764;54804.72597637146;55514.61213469695;79699;79769;81691;82740;84413;7939.682412703658;8311.044229913396;8491.108433614381;8505.95747021261;8632.007319158127;8804.559772011502;11199.272047316897;13082.34126984127;13537.661558461117;13621.727565448437;11319.830202546804;11385.886141138482;11650.825237621611;11653.417329133339;11685.065194784227;10970.835437468284;11163;11577.189596728485;11671.941640292083;11845.170838041255;5892.499137573182;9037.638494460149;11056.944715276371;11371.31772093677;12012.498437695289;7019.438444924292;8261.793455163668;8290.212429819157;8322;8507.191816777397;8725.738227853175;9134.588943497425;11690.941545292559;11725.296482211095;11803.527858885893 10:14:39 INFO - PROCESS | 1654 | |3;mootools.html;1378.64155319617;1520.452637050662;1562.1954693332932;1768.5578605348662;1939.6217737541185;7873.212678732124;8354.331653467723;8619.353548483874;8643.481391116522;8662.653493860254;8382.161783821615;8553.914460855389;8559;8648.221660050594;8684.826303473927;2362.893669784864;2408.2173293679625;2408.277516744978;2408.4219787251022;2408.8313818032743;2354.19957214547;2391.234804862445;2397.976020239793;2400.987995060022;2401.315625046862;2351.2123438648073;2361.6339467382495;2372.608519594265;2378.774016468433;2380.4286971126976;2062.8143467087884;2276.3057267533395;2354.6703461515403;2366.2546297916188;2379.060271192869;2333.2533589251448;2358.5990381635165;2358.752331005254;2365.7870791628666;2370.2059809963685;8489.193526614961;8658.696945606873;8780.429272097294;8794.604242809024;8796.692115775917;5216.165413533854;5410.810621628263;5418.485243901823;5420.4850539198715;5426.240326354314;3042.376312855881;3578.8926332210076;3646.2342907989355;3656.1956369598647;3663.102539877747;1062.7077553672725;1062.8990245926625;1076.838474228872;1083.5124194112602;1088.3089238333637;11296.54813807455;11422.31466112036;11442.313461192354;11625.418729063598;11628;3818.3890577508023;4655.37152484417;4836.219173974979;4840;4896.2655601660035;10113.69658910234;10132.898671013343;10138.797224055477;10155.543000564992;10184.439855807854;5422.728863556807;5429.701366424884;5431.864203394868;5434.402215756264;5439.864003399946;4318.222719910446;4430.136123455895;4581.450225972904;4831.347768051339;4937.728424936663;4008.07814202735;4034.2133284009924;4124.381342798544;4134.441850350225;4141.213169497786;1097.550004498152;1264.1972347559183;1301.4078594239597;1306.758249723804;1322.4511827591634;864.3862857371337;890.4434728294816;912.0879120879121;921.1249313152532;942.7595963029383;4716.434027916672;5780.595358324961;5970.09010615582;6093.725782339717;6183.659898705614;918.5682729117304;937.8311903857372;955.2405837359316;958.4680502321139;1014.700663304327;3730.973982154929;4039.9192016159514;4071.3279121995383;4160.854370097032;4177.310743727251;10330.690079297634;10870.782584348426;10891.292066015683;10910.945445272722;10918.781624367468;9246.815063698688;10771;10839.970202830718;10856.131509479223;10886.129109671207;8199.344052475788;8331.708390206313;8333.166683331618;8343.541105239148;8345.457545259538;5978.252718410199;6006.579539432198;6142.877142457215;6209.782657607143;6249.50003999688;9667.516624168904;10112.797744045225;10115.848262275924;10128.544215510172;10145.9492702536;9353.532323383668;9713.27150463704;9951.651692190739;10126.291159619052;10251.179905607385;7415.703371865065;7675.577843218453;7744.419168562493;7756.922430775846;7762.805929851799;8440.15598440151;8458.323334133378;8520.573971301534;8551.230389264996;8555.101714320155;7483.887741683988;7564.319211271012;7628.618569071636;7633;7664.961675191588;7485.363744081814;7491.9625401872645;7509.887351689838;7516.210797866364;7594.164641889277;6814.454843612598;7029.648517574203;7133.607651579213;7220.097487814023;7229.855402892018;5090.2364645303505;5106.183010718267;5159.226116082617;5162.457941916045;5285.577153827761;4645.930311045269;5063.291139240436;5215.13950198213;5347.171188465794;5418.539424148991 10:14:39 INFO - PROCESS | 1654 | |4;prototype.html;299.3907398444166;307.77839955232224;310.8414708498666;313.25757953649855;317.1073428299339;5409.8106566270135;5437.211604317372;5451.945480545193;5470.234167216593;5476.4249753775875;4474.977625111878;4627.699199552027;5341.706206158659;5396.865078373043;5439.782408703657;3201.887933922319;3691.8154092295276;4035.616616421435;4065.4308396824467;4122.361034039714;3596.2088340565165;4031;4060.2691515527194;4065.125997910449;4084.5915408459064;4033.942689228485;4042.7776472294013;4051.6961227907877;4051.817668204938;4079.388091786226;3395.847186876572;3532.6997205237585;3697.7797326882333;3759.586445490994;3973.46358241638;3491.3191927574135;3549.9290014199573;3551.7691350062423;3554.8578056877695;3582.283543291352;4753.572178503917;5237.423883372864;5253.4221235664045;5255.237990491396;5318.893622127536;4175.5615660355525;4527.207738645757;4688.507706690816;4692.249240121599;4702.224133018048;5018.623603229772;5611.046122159243;5619.325680918275;5644.604877658566;5648.830535083954;3921.3725803871243;3926.980365098185;3930.941035884464;3937.1338305572726;3937.818654403696;4906.018796240766;5630.493255606974;5818.650880947114;5900.675462849542;5979.252593425822;3942;3972.900677483086;3976.1451287972645;3981.2131507944555;3986.402039694069;3764.1154328732728;3831.4827498287373;4033.274010678106;4055.918881622351;4060.7563546187325;4679.391679081766;4686.133065382915;4692.483826779052;4708.976455117702;4712.95287047132;5301.6818990860675;5433.0763770159165;5653.604247702704;5700;5710.143478478261;1185.6383802940013;1204.566356111799;1336.6967206973122;1338.8285250405895;1341.597520743773;738.8079099434186;768.888955459367;779.8908152858604;820.5856042698399;827.5862068965517;4485.394471746337;4999.225120106389;5097.949020509747;5129.512696293846;5169.353830771153;4877.902441951142;5046.419661738873;5842.59101862874;5861.896672329386;5867.91688950425;4401.6987259555335;4457.866264012085;4822.614190864722;4925.310456536088;5281.894362112813;2515.91194308202;2839.892441947642;2931.296488842705;3054.144839444959;3073.523603841408;5205.947940520622;5422.4690815368895;5953.315368732651;5960.850978725566;6029.939700603026;4479.7536135512855;5147.253648220987;5554.361248456398;5621.353544342453;5936.5844390892225;5213.322268105122;5489.533389661923;5739.540836733135;5908.793192238251;5936.881262374728;4614.446266448048;4863.489333619919;5834.970825146017;5856.853578660568;5871.266091738533;5425.348958125051;5706.600537962303;5733.598648094593;5752.482276595127;5755.453231942959;4720.645951553579;5234.921476177784;5924.763009479572;5983.371745966785;6003.309619393825;2894.623698919211;3195.2810617610853;3853.826577804062;3855.460235567079;3865.1689886673375;5450.427705090909;5476.91784623223;5773.93182261283;5862.794802181903;5886.499647530008;5405.324334458192;5684.8294551163535;5697.743601537858;5703.173039909107;5726.65640061581;5461;5609.831705048855;5717.74149211051;5758.539316854725;5769.221155144001;4851.811306229996;5394.865128371822;5481.945180548143;5540.141278101901;5556.249906262603;4790.808367665254;5097.362829646294;5459.535939445032;5548.725022740657;5621.426000719805;4628.629709623289;5567.7494512747835;5573.832785016456;5580.665160090245;5580.776768929198 10:14:39 INFO - PROCESS | 1654 | |5;yui.html;672.3746915368706;755.9735409260674;757.4016526943715;803.1085495100434;803.3372467714133;15065.849341506582;16303.347866085342;16322.673546529077;16325.265363058661;16336.591585210375;14588.197649129293;14731.189784561871;14739.336729847157;14741.262936853169;14762.409503619841;710.9253528379523;737.4985010193067;882.858742601184;891.7057371067565;915.3638221436071;910.9726708198765;911.3210658059754;926.4302453990814;926.6895589977365;927.0682963621551;984.0405604535592;984.5175863826745;985.3299756165804;988.4464699768153;988.713273150789;753.1263734068455;920.1534588178891;978.0659470205936;979.1041197304494;980.7842274699555;710.4032612605384;711.6797441151502;713.393615426883;713.3971793834202;732.9010583571203;15056.171910544916;15110.773338399933;15126.168060756654;15127.319270632848;15148.394064237527;14046.648833779134;14206.502772402917;14264;14393.208373539452;14427.278636068155;12772.872271277354;12816.102872798907;12926;13112.409941552653;13147.474101036043;4435.578620031092;4544.954550454486;4564;4573.451185857685;4607.976960115178;121968;124810;127192.99999999907;128532.07201891886;128769.35615321957;10996.890031099747;11115.277506962022;11216.439178041066;11244.325340479598;11691.76616467666;18306;21896.12415503394;21961.705744138188;22005;22015.22946696858;7802.141764405911;7944.483608565425;7948.523088614702;7963;8064.475809072509;4273.764942928106;4376.302322921345;4381.18947994622;4383.430154080013;4426.557344265612;3344.364570731553;3451.154467155563;3452.205992621711;3454.240067185215;3455.87904423344;575.1832397994857;648.5913874259281;659.3143131143653;666.6366830077619;670.7887015590073;633.6673246545508;635.259922190652;637.2926052082186;637.9362063793584;658.8089454058365;8801.735947921572;9116.225120864801;9247.26021918245;9283.483501461846;9313.90686093144;516.3622925686774;548.1886807524885;557.8242853501134;581.3605034462058;632.9873402531923;4138;4449.7997590108525;4463.977680111579;4512.142692888341;4559.972140689505;152225;154002;159695;161004;166505.16747416186;102947.4852625732;103508;114781;116882.41558792321;124770.37614811868;81499;92080.23759287098;104892;108709.45645271723;111562;9746.171575416169;9846.556904939438;9871.46478354187;9977.434957385305;10077.848832267668;62183;63686.68156659187;65112.34887651063;66458.66770666116;67635.30911727247;94724.52637736767;96260;98764.01235987549;99341;101933;13268;13325.400356984153;13459.730805384033;13876.167429954236;13916.02587818843;15120.924395377953;15308.158051307286;15393.692126157639;15399.69200615959;15753.68492630118;14001.859981400463;14239.216843073316;14330.641733956734;14617.122972621677;14756.114633122046;14142.363593638516;14225.928870355581;14408.567742967727;14434.494792682206;14729.263536822902;11740.941295293469;12088;12520.74958500843;13273;14670.559883203521;10960.616378426717;11346.586926369322;11382.317060976367;11478.770424591628;11750;9546.42721436716;11066.12550982749;11547;11663.88336116662;11697.941510292394 10:14:39 INFO - PROCESS | 1654 | __end_tp_report 10:14:39 INFO - PROCESS | 1654 | __start_cc_report 10:14:39 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1333 10:14:39 INFO - PROCESS | 1654 | __end_cc_report 10:14:39 INFO - PROCESS | 1654 | __startTimestamp1469207679943__endTimestamp 10:14:39 INFO - PROCESS | 1654 | 10:14:39 INFO - PROCESS | 1654 | ------- Summary: start ------- 10:14:39 INFO - PROCESS | 1654 | Number of tests: 6 10:14:39 INFO - PROCESS | 1654 | 10:14:39 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5728.39 Median:5879.12 stddev:640.16 (10.9%) stddev-sans-first:599.97 10:14:39 INFO - PROCESS | 1654 | Values: 2816.0 3349.5 3364.9 3366.5 3420.7 5541.5 5630.2 5671.7 5680.5 5733.8 5651.2 5674.9 5677.9 5694.8 5711.9 5126.3 5178.2 5192.3 5220.7 5272.4 5273.1 5276.3 5279.2 5285.4 5292.3 5161.3 5192.5 5194.2 5214.1 5270.8 5268.7 5285.4 5296.4 5305.6 5353.4 5132.6 5147.2 5147.6 5220.7 5235.9 5630.5 5686.7 5689.9 5708.0 5748.5 5334.3 6263.9 6266.7 6273.7 6293.1 5666.8 6171.4 6237.5 6302.4 6340.5 5867.1 5879.1 5902.7 5903.9 5904.7 6288.9 6296.6 6313.2 6316.3 6321.6 5993.5 6043.5 6050.7 6100.4 6155.5 6256.4 6258.3 6277.1 6277.4 6277.5 6155.6 6192.2 6195.5 6201.6 6220.2 6045.0 6135.4 6140.2 6221.5 6234.5 4919.0 4948.6 4951.2 4960.6 4964.5 4445.1 4479.4 4499.7 4521.6 4531.3 5687.2 5707.0 5731.6 5767.1 5768.6 6259.2 6289.9 6306.6 6309.9 6342.8 5581.4 5649.7 5661.2 5696.9 5725.5 5390.9 5399.5 5405.2 5442.9 5456.8 6231.6 6268.2 6291.8 6301.5 6303.2 6132.3 6146.0 6194.3 6291.6 6298.0 6234.1 6283.5 6288.3 6293.1 6295.3 6185.6 6188.4 6196.0 6205.1 6206.6 5753.4 6179.3 6286.2 6309.1 6310.9 6089.3 6116.9 6122.2 6123.1 6124.2 6098.4 6098.8 6101.4 6106.6 6113.4 5047.6 5767.2 5774.5 5966.3 5990.8 5927.7 6004.6 6032.9 6060.0 6074.4 5767.6 5790.5 5832.3 5863.5 5868.1 10:14:39 INFO - PROCESS | 1654 | 10:14:39 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:76611.53 Median:41444.67 stddev:168429.70 (406.4%) stddev-sans-first:168805.19 10:14:39 INFO - PROCESS | 1654 | Values: 477.3 519.8 520.0 543.9 571.3 40159.6 44222.8 44270.1 44302.8 46313.8 45631.5 45801.8 45802.8 45860.5 45899.8 8993.9 9736.8 9921.3 9922.6 9923.9 8786.2 11981.9 12152.8 12203.7 12376.6 12123.3 12178.0 12185.0 12185.6 12197.1 8865.8 9056.2 11961.8 12011.7 12031.8 8951.0 8971.6 11513.2 11800.4 11807.2 42463.6 42730.8 42734.6 42880.4 42898.8 2974.7 3466.9 3648.0 3675.9 3882.9 31787.8 32129.5 33662.8 33677.2 33695.8 3061.3 3194.5 3223.2 3228.6 3233.3 544296.0 546227.0 550291.0 551948.0 553320.0 66409.7 66720.7 66736.0 66883.0 66988.0 58896.4 69730.7 69774.9 69790.7 69961.0 13608.9 16819.5 16851.0 16851.8 16854.5 11547.2 11836.2 11861.2 12834.6 12873.5 14235.7 16760.7 17137.7 17356.2 20311.2 6802.0 7689.0 7729.3 7792.1 7867.7 927.2 995.7 1020.2 1066.1 1090.3 32088.2 32157.5 32169.2 32563.0 33020.8 1009.9 1039.4 1055.1 1089.2 1109.5 14135.2 15456.8 15594.5 16201.8 16963.4 727377.0 728826.0 731627.0 731803.0 738656.0 63149.4 63584.7 63662.0 63731.4 63799.7 1273.2 1313.9 1371.4 1409.2 1430.9 28110.1 31561.5 32428.4 33156.5 33196.3 53330.7 53976.0 53988.7 54117.6 54426.0 589974.0 592980.0 594158.0 595027.0 596719.0 27682.9 28096.0 28153.6 28688.9 29246.0 49924.0 50096.7 50180.0 50797.7 50930.6 41420.2 41499.0 41827.0 42076.5 43179.8 41969.8 42510.7 42635.1 43264.0 43603.6 41032.0 41469.2 41658.6 41705.2 41790.2 33802.8 36618.5 36816.8 36950.6 36982.1 24140.9 26673.2 26874.5 27031.6 27249.3 10:14:39 INFO - PROCESS | 1654 | 10:14:39 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34169.63 Median:13777.82 stddev:92558.51 (671.8%) stddev-sans-first:92783.29 10:14:39 INFO - PROCESS | 1654 | Values: 332.5 376.3 376.6 386.0 408.9 15735.4 16139.8 20661.4 20898.6 21026.8 20262.8 20995.5 21271.0 21389.7 21770.5 5926.7 6041.6 6044.4 6078.0 6143.2 5948.2 6039.2 6119.8 6122.6 6136.0 4157.3 6203.5 6674.2 6736.4 6809.0 6660.7 6718.2 6792.2 6799.2 6800.9 5862.9 5864.6 5868.0 5881.4 5898.8 24993.3 26624.6 26733.9 26768.9 26917.5 2280.4 2337.6 2562.4 2662.2 2941.0 12660.6 15515.8 15799.8 15959.4 16150.0 1806.3 1829.6 1861.5 1918.6 1945.0 77406.0 115250.0 130662.0 132383.0 134193.3 10330.7 10438.0 10947.3 10953.8 11162.9 13673.2 13703.7 13709.5 13722.8 13741.5 8236.8 8524.8 8574.7 8645.0 8729.0 8633.9 8634.8 8647.7 8816.0 8823.4 6510.5 6642.7 6652.0 6667.8 6672.2 1788.4 1815.7 1859.7 1861.6 1863.1 950.7 955.0 1017.9 1024.9 1030.3 19234.7 20417.6 20664.6 21779.9 22596.3 740.0 900.3 977.4 978.4 1047.2 10990.5 11386.3 11441.5 11448.1 11456.3 526432.0 529187.0 532135.3 561618.0 597134.0 130144.7 131086.0 137909.0 138081.0 139765.0 1010.9 1027.6 1048.0 1048.8 1049.9 18231.9 18793.2 18918.2 19185.8 19513.3 43126.8 53497.0 53515.7 54804.7 55514.6 79699.0 79769.0 81691.0 82740.0 84413.0 7939.7 8311.0 8491.1 8506.0 8632.0 8804.6 11199.3 13082.3 13537.7 13621.7 11319.8 11385.9 11650.8 11653.4 11685.1 10970.8 11163.0 11577.2 11671.9 11845.2 5892.5 9037.6 11056.9 11371.3 12012.5 7019.4 8261.8 8290.2 8322.0 8507.2 8725.7 9134.6 11690.9 11725.3 11803.5 10:14:39 INFO - PROCESS | 1654 | 10:14:39 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5741.99 Median:4868.13 stddev:3190.53 (65.5%) stddev-sans-first:3182.63 10:14:39 INFO - PROCESS | 1654 | Values: 1378.6 1520.5 1562.2 1768.6 1939.6 7873.2 8354.3 8619.4 8643.5 8662.7 8382.2 8553.9 8559.0 8648.2 8684.8 2362.9 2408.2 2408.3 2408.4 2408.8 2354.2 2391.2 2398.0 2401.0 2401.3 2351.2 2361.6 2372.6 2378.8 2380.4 2062.8 2276.3 2354.7 2366.3 2379.1 2333.3 2358.6 2358.8 2365.8 2370.2 8489.2 8658.7 8780.4 8794.6 8796.7 5216.2 5410.8 5418.5 5420.5 5426.2 3042.4 3578.9 3646.2 3656.2 3663.1 1062.7 1062.9 1076.8 1083.5 1088.3 11296.5 11422.3 11442.3 11625.4 11628.0 3818.4 4655.4 4836.2 4840.0 4896.3 10113.7 10132.9 10138.8 10155.5 10184.4 5422.7 5429.7 5431.9 5434.4 5439.9 4318.2 4430.1 4581.5 4831.3 4937.7 4008.1 4034.2 4124.4 4134.4 4141.2 1097.6 1264.2 1301.4 1306.8 1322.5 864.4 890.4 912.1 921.1 942.8 4716.4 5780.6 5970.1 6093.7 6183.7 918.6 937.8 955.2 958.5 1014.7 3731.0 4039.9 4071.3 4160.9 4177.3 10330.7 10870.8 10891.3 10910.9 10918.8 9246.8 10771.0 10840.0 10856.1 10886.1 8199.3 8331.7 8333.2 8343.5 8345.5 5978.3 6006.6 6142.9 6209.8 6249.5 9667.5 10112.8 10115.8 10128.5 10145.9 9353.5 9713.3 9951.7 10126.3 10251.2 7415.7 7675.6 7744.4 7756.9 7762.8 8440.2 8458.3 8520.6 8551.2 8555.1 7483.9 7564.3 7628.6 7633.0 7665.0 7485.4 7492.0 7509.9 7516.2 7594.2 6814.5 7029.6 7133.6 7220.1 7229.9 5090.2 5106.2 5159.2 5162.5 5285.6 4645.9 5063.3 5215.1 5347.2 5418.5 10:14:39 INFO - PROCESS | 1654 | 10:14:39 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4528.49 Median:5097.66 stddev:1407.75 (27.6%) stddev-sans-first:1375.45 10:14:39 INFO - PROCESS | 1654 | Values: 299.4 307.8 310.8 313.3 317.1 5409.8 5437.2 5451.9 5470.2 5476.4 4475.0 4627.7 5341.7 5396.9 5439.8 3201.9 3691.8 4035.6 4065.4 4122.4 3596.2 4031.0 4060.3 4065.1 4084.6 4033.9 4042.8 4051.7 4051.8 4079.4 3395.8 3532.7 3697.8 3759.6 3973.5 3491.3 3549.9 3551.8 3554.9 3582.3 4753.6 5237.4 5253.4 5255.2 5318.9 4175.6 4527.2 4688.5 4692.2 4702.2 5018.6 5611.0 5619.3 5644.6 5648.8 3921.4 3927.0 3930.9 3937.1 3937.8 4906.0 5630.5 5818.7 5900.7 5979.3 3942.0 3972.9 3976.1 3981.2 3986.4 3764.1 3831.5 4033.3 4055.9 4060.8 4679.4 4686.1 4692.5 4709.0 4713.0 5301.7 5433.1 5653.6 5700.0 5710.1 1185.6 1204.6 1336.7 1338.8 1341.6 738.8 768.9 779.9 820.6 827.6 4485.4 4999.2 5097.9 5129.5 5169.4 4877.9 5046.4 5842.6 5861.9 5867.9 4401.7 4457.9 4822.6 4925.3 5281.9 2515.9 2839.9 2931.3 3054.1 3073.5 5205.9 5422.5 5953.3 5960.9 6029.9 4479.8 5147.3 5554.4 5621.4 5936.6 5213.3 5489.5 5739.5 5908.8 5936.9 4614.4 4863.5 5835.0 5856.9 5871.3 5425.3 5706.6 5733.6 5752.5 5755.5 4720.6 5234.9 5924.8 5983.4 6003.3 2894.6 3195.3 3853.8 3855.5 3865.2 5450.4 5476.9 5773.9 5862.8 5886.5 5405.3 5684.8 5697.7 5703.2 5726.7 5461.0 5609.8 5717.7 5758.5 5769.2 4851.8 5394.9 5481.9 5540.1 5556.2 4790.8 5097.4 5459.5 5548.7 5621.4 4628.6 5567.7 5573.8 5580.7 5580.8 10:14:39 INFO - PROCESS | 1654 | 10:14:39 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:25276.06 Median:3451.68 stddev:40281.24 (1167.0%) stddev-sans-first:40351.88 10:14:39 INFO - PROCESS | 1654 | Values: 672.4 756.0 757.4 803.1 803.3 15065.8 16303.3 16322.7 16325.3 16336.6 14588.2 14731.2 14739.3 14741.3 14762.4 710.9 737.5 882.9 891.7 915.4 911.0 911.3 926.4 926.7 927.1 984.0 984.5 985.3 988.4 988.7 753.1 920.2 978.1 979.1 980.8 710.4 711.7 713.4 713.4 732.9 15056.2 15110.8 15126.2 15127.3 15148.4 14046.6 14206.5 14264.0 14393.2 14427.3 12772.9 12816.1 12926.0 13112.4 13147.5 4435.6 4545.0 4564.0 4573.5 4608.0 121968.0 124810.0 127193.0 128532.1 128769.4 10996.9 11115.3 11216.4 11244.3 11691.8 18306.0 21896.1 21961.7 22005.0 22015.2 7802.1 7944.5 7948.5 7963.0 8064.5 4273.8 4376.3 4381.2 4383.4 4426.6 3344.4 3451.2 3452.2 3454.2 3455.9 575.2 648.6 659.3 666.6 670.8 633.7 635.3 637.3 637.9 658.8 8801.7 9116.2 9247.3 9283.5 9313.9 516.4 548.2 557.8 581.4 633.0 4138.0 4449.8 4464.0 4512.1 4560.0 152225.0 154002.0 159695.0 161004.0 166505.2 102947.5 103508.0 114781.0 116882.4 124770.4 81499.0 92080.2 104892.0 108709.5 111562.0 9746.2 9846.6 9871.5 9977.4 10077.8 62183.0 63686.7 65112.3 66458.7 67635.3 94724.5 96260.0 98764.0 99341.0 101933.0 13268.0 13325.4 13459.7 13876.2 13916.0 15120.9 15308.2 15393.7 15399.7 15753.7 14001.9 14239.2 14330.6 14617.1 14756.1 14142.4 14225.9 14408.6 14434.5 14729.3 11740.9 12088.0 12520.7 13273.0 14670.6 10960.6 11346.6 11382.3 11478.8 11750.0 9546.4 11066.1 11547.0 11663.9 11697.9 10:14:39 INFO - PROCESS | 1654 | -------- Summary: end -------- 10:14:39 INFO - PROCESS | 1654 | 10:14:40 INFO - TEST-INFO | 1654: exit 0 10:14:40 INFO - TEST-OK | dromaeo_css | took 1116747ms 10:14:40 INFO - TEST-START | kraken 10:14:40 INFO - operating with platform_type : mac_ 10:14:40 INFO - Initialising browser for kraken test... 10:14:40 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpaYaVlR/profile http://localhost:49266/getInfo.html) 10:14:42 INFO - PROCESS | 1661 | JavaScript error: , line 0: uncaught exception: undefined 10:14:42 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 10:14:42 INFO - PROCESS | 1661 | colorDepth:24 10:14:42 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 10:14:42 INFO - PROCESS | 1661 | __metrics 10:14:42 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:14:42 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:14:43 INFO - TEST-INFO | 1661: exit 0 10:14:43 INFO - Browser initialized. 10:14:43 INFO - Running cycle 1/1 for kraken test... 10:14:43 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpaYaVlR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:14:55 INFO - PROCESS | 1662 | RSS: Main: 245977088 10:14:55 INFO - PROCESS | 1662 | 10:14:58 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 10:14:59 INFO - PROCESS | 1662 | RSS: Main: 324685824 10:14:59 INFO - PROCESS | 1662 | 10:15:01 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 10:15:02 INFO - PROCESS | 1662 | RSS: Main: 330563584 10:15:02 INFO - PROCESS | 1662 | 10:15:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 10:15:06 INFO - PROCESS | 1662 | RSS: Main: 426807296 10:15:06 INFO - PROCESS | 1662 | 10:15:08 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 10:15:08 INFO - PROCESS | 1662 | RSS: Main: 329216000 10:15:08 INFO - PROCESS | 1662 | 10:15:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:15:11 INFO - PROCESS | 1662 | RSS: Main: 334532608 10:15:11 INFO - PROCESS | 1662 | 10:15:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 10:15:15 INFO - PROCESS | 1662 | RSS: Main: 371613696 10:15:15 INFO - PROCESS | 1662 | 10:15:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 10:15:18 INFO - PROCESS | 1662 | RSS: Main: 372797440 10:15:18 INFO - PROCESS | 1662 | 10:15:21 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 10:15:22 INFO - PROCESS | 1662 | RSS: Main: 372420608 10:15:22 INFO - PROCESS | 1662 | 10:15:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:15:24 INFO - PROCESS | 1662 | RSS: Main: 345628672 10:15:24 INFO - PROCESS | 1662 | 10:15:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:15:27 INFO - PROCESS | 1662 | RSS: Main: 351793152 10:15:27 INFO - PROCESS | 1662 | 10:15:29 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:15:29 INFO - PROCESS | 1662 | RSS: Main: 357969920 10:15:29 INFO - PROCESS | 1662 | 10:15:32 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:15:33 INFO - PROCESS | 1662 | RSS: Main: 361738240 10:15:33 INFO - PROCESS | 1662 | 10:15:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:15:37 INFO - PROCESS | 1662 | RSS: Main: 364490752 10:15:37 INFO - PROCESS | 1662 | 10:15:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 10:15:39 INFO - PROCESS | 1662 | RSS: Main: 407187456 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | __start_tp_report 10:15:39 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 10:15:39 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 10:15:39 INFO - PROCESS | 1662 | |i|pagename|runs| 10:15:39 INFO - PROCESS | 1662 | |0;ai-astar;102;111;111;111;112;113;111;111;109;111 10:15:39 INFO - PROCESS | 1662 | |1;audio-beat-detection;143;112;108;161;107;105;128;107;144;110 10:15:39 INFO - PROCESS | 1662 | |2;audio-dft;184;184;184;183;185;183;183;185;184;181 10:15:39 INFO - PROCESS | 1662 | |3;audio-fft;76;57;57;103;56;57;63;67;82;67 10:15:39 INFO - PROCESS | 1662 | |4;audio-oscillator;82;69;91;73;87;68;97;71;68;117 10:15:39 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;88;86;94;88;88;84;95;90;91;85 10:15:39 INFO - PROCESS | 1662 | |6;imaging-darkroom;94;94;93;94;93;93;94;94;105;149 10:15:39 INFO - PROCESS | 1662 | |7;imaging-desaturate;86;87;92;134;83;83;85;83;87;87 10:15:39 INFO - PROCESS | 1662 | |8;json-parse-financial;54;51;53;53;52;61;74;84;84;86 10:15:39 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;68;67;76;82;72;84;84;71;71;72 10:15:39 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;83;83;84;86;86;85;85;86;82;84 10:15:39 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;138;135;138;181;142;136;151;149;227;137 10:15:39 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;160;163;163;164;164;162;211;223;162;163 10:15:39 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;67;64;66;66;64;65;67;66;66;65 10:15:39 INFO - PROCESS | 1662 | __end_tp_report 10:15:39 INFO - PROCESS | 1662 | __start_cc_report 10:15:39 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2093 10:15:39 INFO - PROCESS | 1662 | __end_cc_report 10:15:39 INFO - PROCESS | 1662 | __startTimestamp1469207739001__endTimestamp 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | ------- Summary: start ------- 10:15:39 INFO - PROCESS | 1662 | Number of tests: 14 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:110.20 Median:111.00 stddev:3.05 (2.7%) stddev-sans-first:1.05 10:15:39 INFO - PROCESS | 1662 | Values: 102.0 111.0 111.0 111.0 112.0 113.0 111.0 111.0 109.0 111.0 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:122.50 Median:120.00 stddev:20.16 (16.8%) stddev-sans-first:19.97 10:15:39 INFO - PROCESS | 1662 | Values: 143.0 112.0 108.0 161.0 107.0 105.0 128.0 107.0 144.0 110.0 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.17 (0.6%) stddev-sans-first:1.24 10:15:39 INFO - PROCESS | 1662 | Values: 184.0 184.0 184.0 183.0 185.0 183.0 183.0 185.0 184.0 181.0 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:68.50 Median:65.00 stddev:14.97 (23.0%) stddev-sans-first:15.63 10:15:39 INFO - PROCESS | 1662 | Values: 76.0 57.0 57.0 103.0 56.0 57.0 63.0 67.0 82.0 67.0 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:82.30 Median:77.50 stddev:16.05 (20.7%) stddev-sans-first:17.02 10:15:39 INFO - PROCESS | 1662 | Values: 82.0 69.0 91.0 73.0 87.0 68.0 97.0 71.0 68.0 117.0 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:89.00 stddev:3.63 (4.1%) stddev-sans-first:3.84 10:15:39 INFO - PROCESS | 1662 | Values: 88.0 86.0 94.0 88.0 88.0 84.0 95.0 90.0 91.0 85.0 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:100.30 Median:94.00 stddev:17.49 (18.6%) stddev-sans-first:18.40 10:15:39 INFO - PROCESS | 1662 | Values: 94.0 94.0 93.0 94.0 93.0 93.0 94.0 94.0 105.0 149.0 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:90.70 Median:86.50 stddev:15.46 (17.9%) stddev-sans-first:16.30 10:15:39 INFO - PROCESS | 1662 | Values: 86.0 87.0 92.0 134.0 83.0 83.0 85.0 83.0 87.0 87.0 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:67.50 stddev:15.03 (22.3%) stddev-sans-first:15.39 10:15:39 INFO - PROCESS | 1662 | Values: 54.0 51.0 53.0 53.0 52.0 61.0 74.0 84.0 84.0 86.0 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:74.70 Median:74.00 stddev:6.45 (8.7%) stddev-sans-first:6.37 10:15:39 INFO - PROCESS | 1662 | Values: 68.0 67.0 76.0 82.0 72.0 84.0 84.0 71.0 71.0 72.0 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:1.43 (1.7%) stddev-sans-first:1.42 10:15:39 INFO - PROCESS | 1662 | Values: 83.0 83.0 84.0 86.0 86.0 85.0 85.0 86.0 82.0 84.0 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:153.40 Median:145.50 stddev:29.28 (20.1%) stddev-sans-first:30.53 10:15:39 INFO - PROCESS | 1662 | Values: 138.0 135.0 138.0 181.0 142.0 136.0 151.0 149.0 227.0 137.0 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:163.50 stddev:23.13 (14.1%) stddev-sans-first:24.01 10:15:39 INFO - PROCESS | 1662 | Values: 160.0 163.0 163.0 164.0 164.0 162.0 211.0 223.0 162.0 163.0 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:1.07 (1.6%) stddev-sans-first:1.01 10:15:39 INFO - PROCESS | 1662 | Values: 67.0 64.0 66.0 66.0 64.0 65.0 67.0 66.0 66.0 65.0 10:15:39 INFO - PROCESS | 1662 | -------- Summary: end -------- 10:15:39 INFO - PROCESS | 1662 | 10:15:39 INFO - TEST-INFO | 1662: exit 0 10:15:39 INFO - TEST-OK | kraken | took 59222ms 10:15:39 INFO - SUITE-END | took 1175s 10:15:40 INFO - Completed test suite (00:19:36) 10:15:40 INFO - javascript benchmark 10:15:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2816.0284701777887, 3349.4640857462796, 3364.8822291219803, 3366.4613661814114, 3420.6750358715913, 5541.501264886159, 5630.211770352149, 5671.6597004179675, 5680.460356266153, 5733.799317023905, 5651.208830763697, 5674.858128546788, 5677.886442271162, 5694.77220911163, 5711.914321285184, 5126.256692779545, 5178.249153872686, 5192.324997750287, 5220.738963051851, 5272.367315922094, 5273.050850846846, 5276.314079169702, 5279.155335146377, 5285.365756109272, 5292.338457692788, 5161.329027226455, 5192.532672059514, 5194.194899790538, 5214.113600687873, 5270.789168433277, 5268.657537260085, 5285.445028272053, 5296.364436267672, 5305.575553955674, 5353.38436079848, 5132.589392848563, 5147.202183661539, 5147.588192944556, 5220.738963051832, 5235.921461178047, 5630.493255607015, 5686.658800471943, 5689.9146512802345, 5708.029634962067, 5748.511376533, 5334.306540149794, 6263.874722505524, 6266.717997690115, 6273.686315684197, 6293.056041593752, 5666.773329066832, 6171.413715697002, 6237.469815065725, 6302.40127187921, 6340.492760579143, 5867.061270196748, 5879.118132280149, 5902.734376953047, 5903.940960590339, 5904.704764761745, 6288.937110628926, 6296.622202667763, 6313.2424109107205, 6316.30520642729, 6321.589096708699, 5993.520518358521, 6043.516518678495, 6050.727717252734, 6100.359462256488, 6155.4767844732805, 6256.374362563707, 6258.311585725566, 6277.121203031579, 6277.435030847246, 6277.466415354655, 6155.569110162246, 6192.164057852132, 6195.473384762256, 6201.565890387629, 6220.222472190976, 6044.969775151096, 6135.447809697149, 6140.17107690465, 6221.502279817604, 6234.501239900797, 4918.95081049185, 4948.628852836052, 4951.158303088484, 4960.603151747924, 4964.453910069889, 4445.133199026159, 4479.440069991251, 4499.662525310615, 4521.570450807168, 4531.320301954733, 5687.203791469197, 5706.9714651426475, 5731.598788084794, 5767.134929760486, 5768.596198266081, 6259.217597800275, 6289.874202516015, 6306.621602703853, 6309.873802523924, 6342.778002769881, 5581.441855814467, 5649.717514124277, 5661.207430959669, 5696.857578560569, 5725.456081672318, 5390.946090539122, 5399.541039011649, 5405.162199859028, 5442.8911421771345, 5456.809011684572, 6231.563790534619, 6268.216472940882, 6291.7797877075, 6301.4643755279485, 6303.180586523814, 6132.264128304632, 6146.016637338004, 6194.256689197326, 6291.622502649856, 6297.96851015742, 6234.096056071753, 6283.465905397909, 6288.276848162337, 6293.118963345227, 6295.307516173126, 6185.567010309415, 6188.381161883775, 6196.039613859859, 6205.131281620667, 6206.596571222856, 5753.424657534213, 6179.28938172098, 6286.214223222098, 6309.148264984168, 6310.936890631035, 6089.299730530868, 6116.938830611637, 6122.204113465221, 6123.050927106306, 6124.228368151265, 6098.359672234352, 6098.75604975796, 6101.420365065312, 6106.603070800384, 6113.419225173601, 5047.570956468741, 5767.192593036894, 5774.538036957118, 5966.313873904556, 5990.7903223386975, 5927.7036148191555, 6004.609700369462, 6032.909506357496, 6059.969700151471, 6074.392560744067, 5767.625104368202, 5790.478856902899, 5832.300123985149, 5863.501602363847, 5868.149118377726], "value": 5686.707146474733, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.3388856433839, 519.8258583374569, 519.9808375583856, 543.912973924172, 571.3172758553527, 40159.59840401595, 44222.7788861056, 44270.11459770802, 44302.77848610753, 46313.7684311578, 45631.54368456314, 45801.77099114508, 45802.77098614511, 45860.54139458604, 45899.770501147534, 8993.910060899372, 9736.756581085476, 9921.305508614363, 9922.553485093153, 9923.90076099237, 8786.209241168293, 11981.880181198207, 12152.756944861098, 12203.694907627292, 12376.62870113898, 12123.33321667308, 12177.964872985762, 12185.02519798414, 12185.634430967086, 12197.14619976602, 8865.822683546357, 9056.184943355063, 11961.820572691417, 12011.65772674329, 12031.759364812742, 8950.955245223797, 8971.596278167499, 11513.194076414655, 11800.350980696057, 11807.173497855154, 42463.57536424627, 42730.78634606807, 42734.57265427368, 42880.356794648105, 42898.785506072585, 2974.672785993539, 3466.8959931201853, 3647.963520364789, 3675.9081022974638, 3882.864099756495, 31787.82121787826, 32129.51805722916, 33662.83168584141, 33677.15807104817, 33695.83152084248, 3061.3265081681993, 3194.520821876714, 3223.2425380035675, 3228.580284563015, 3233.3048394595276, 544296.0, 546227.0, 550291.0, 551948.0, 553319.9999999959, 66409.66795165994, 66720.6663966677, 66735.99896001564, 66883.0, 66987.99999999903, 58896.41103588909, 69730.65134674295, 69774.9067527975, 69790.65104674445, 69961.0, 13608.863911360957, 16819.495415137564, 16851.0, 16851.831481685273, 16854.494365169063, 11547.191696581327, 11836.23064500822, 11861.229020113666, 12834.55079072228, 12873.485060597472, 14235.715285694436, 16760.73756146, 17137.742933856003, 17356.218970146372, 20311.18755249803, 6802.047713320139, 7689.0, 7729.304362607392, 7792.103908050533, 7867.724629637935, 927.1655510040882, 995.6963126246419, 1020.1532727835878, 1066.1470823341297, 1090.2695194219891, 32088.197795054843, 32157.51763723546, 32169.195770105467, 32563.0, 33020.83489582537, 1009.9446078847621, 1039.4023436524028, 1055.0926203465015, 1089.242976131581, 1109.517359948425, 14135.222562758941, 15456.768148477782, 15594.454194103151, 16201.756973645404, 16963.406280780113, 727377.0, 728826.0, 731627.0, 731803.0229546562, 738656.0, 63149.368506315266, 63584.682076590245, 63662.0, 63731.36268637347, 63799.6810015947, 1273.2233337664018, 1313.8817506424468, 1371.3897315694421, 1409.2108419285028, 1430.9427622894968, 28110.120078798263, 31561.526577100904, 32428.351432970736, 33156.50265246072, 33196.33607327792, 53330.73334633302, 53975.99999999843, 53988.730056349465, 54117.647058823844, 54426.0, 589974.0, 592980.0, 594158.0, 595027.0, 596719.0, 27682.86158569275, 28096.0, 28153.57769633498, 28688.856555717088, 29246.0, 49924.00151997012, 50096.74951625219, 50180.0, 50797.74601126971, 50930.633670791154, 41420.1715965685, 41499.0, 41827.0, 42076.527321543595, 43179.78410107929, 41969.79015104905, 42510.7246782597, 42635.14729705451, 43264.0, 43603.56396435995, 41032.0, 41469.170616588104, 41658.58341416547, 41705.165896682505, 41790.164196716505, 33802.830985845896, 36618.45072323864, 36816.81591592025, 36950.6304936958, 36982.07544811293, 24140.87929560411, 26673.19980400591, 26874.462510749283, 27031.594526082517, 27249.31876703098], "value": 21241.11258364261, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.46971081125594, 376.30759402699044, 376.60644626365445, 385.97684138951666, 408.85076946914364, 15735.370585176594, 16139.838601613981, 20661.380158595228, 20898.582028359422, 21026.789732102676, 20262.797372026274, 20995.47511312218, 21271.0, 21389.679154812653, 21770.455738606543, 5926.733297001645, 6041.577089603729, 6044.365341639131, 6077.96674565325, 6143.170671959296, 5948.226730525026, 6039.214902062726, 6119.847003824918, 6122.602030868002, 6136.018237082068, 4157.293260145782, 6203.503719702413, 6674.16572928384, 6736.427403670669, 6809.0, 6660.666966651648, 6718.227403848571, 6792.218894827109, 6799.218089919673, 6800.863982720367, 5862.9446699594055, 5864.5894787364905, 5867.973104706659, 5881.411858814085, 5898.8230353089475, 24993.250202493953, 26624.60063099055, 26733.86633066822, 26768.866155669293, 26917.461650767073, 2280.4184932842186, 2337.649352597107, 2562.397836508419, 2662.201339598113, 2941.0, 12660.620181394574, 15515.767263491056, 15799.76300355484, 15959.361625534964, 16150.03099814015, 1806.3045727395047, 1829.5609053827118, 1861.543921739169, 1918.6258679557493, 1944.980550194494, 77406.0, 115250.0, 130662.0, 132382.99999999904, 134193.32903335517, 10330.745815068229, 10438.0, 10947.34315941046, 10953.780924381626, 11162.888371116347, 13673.179609223478, 13703.725925481434, 13709.451621935012, 13722.794158087636, 13741.450341986409, 8236.83526329482, 8524.829503410021, 8574.699885503978, 8645.0, 8728.956355218183, 8633.870491942625, 8634.827303454022, 8647.654093836301, 8815.955920220358, 8823.426477278956, 6510.479161667055, 6642.734290628321, 6651.966740166365, 6667.799966001027, 6672.199336079701, 1788.4277031349843, 1815.7004094324552, 1859.6838537448666, 1861.6090620969749, 1863.0684657671165, 950.6815216902415, 954.9952250238705, 1017.8829434614966, 1024.8923862994427, 1030.294248439821, 19234.71147932782, 20417.591648166952, 20664.58670826575, 21779.891100544395, 22596.322110336718, 739.9593022383713, 900.2977677411629, 977.4233202410612, 978.3787295067683, 1047.219821233171, 10990.450477476094, 11386.316820990602, 11441.455403520418, 11448.141389395829, 11456.312621242752, 526432.0, 529187.0, 532135.3393233087, 561618.0, 597134.0, 130144.69855301516, 131086.0, 137909.0, 138081.0, 139765.0, 1010.9039641234071, 1027.5786927359748, 1048.0079373389017, 1048.8374301983206, 1049.884512703602, 18231.908840456243, 18793.1543080559, 18918.243270269035, 19185.8081419184, 19513.317033904314, 43126.78436607797, 53497.0, 53515.73242133764, 54804.72597637146, 55514.61213469695, 79699.0, 79769.0, 81691.0, 82740.0, 84413.0, 7939.682412703658, 8311.044229913396, 8491.108433614381, 8505.95747021261, 8632.007319158127, 8804.559772011502, 11199.272047316897, 13082.34126984127, 13537.661558461117, 13621.727565448437, 11319.830202546804, 11385.886141138482, 11650.825237621611, 11653.417329133339, 11685.065194784227, 10970.835437468284, 11163.0, 11577.189596728485, 11671.941640292083, 11845.170838041255, 5892.499137573182, 9037.638494460149, 11056.944715276371, 11371.31772093677, 12012.498437695289, 7019.438444924292, 8261.793455163668, 8290.212429819157, 8322.0, 8507.191816777397, 8725.738227853175, 9134.588943497425, 11690.941545292559, 11725.296482211095, 11803.527858885893], "value": 9872.345965274868, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1378.64155319617, 1520.452637050662, 1562.1954693332932, 1768.5578605348662, 1939.6217737541185, 7873.212678732124, 8354.331653467723, 8619.353548483874, 8643.481391116522, 8662.653493860254, 8382.161783821615, 8553.914460855389, 8559.0, 8648.221660050594, 8684.826303473927, 2362.893669784864, 2408.2173293679625, 2408.277516744978, 2408.4219787251022, 2408.8313818032743, 2354.19957214547, 2391.234804862445, 2397.976020239793, 2400.987995060022, 2401.315625046862, 2351.2123438648073, 2361.6339467382495, 2372.608519594265, 2378.774016468433, 2380.4286971126976, 2062.8143467087884, 2276.3057267533395, 2354.6703461515403, 2366.2546297916188, 2379.060271192869, 2333.2533589251448, 2358.5990381635165, 2358.752331005254, 2365.7870791628666, 2370.2059809963685, 8489.193526614961, 8658.696945606873, 8780.429272097294, 8794.604242809024, 8796.692115775917, 5216.165413533854, 5410.810621628263, 5418.485243901823, 5420.4850539198715, 5426.240326354314, 3042.376312855881, 3578.8926332210076, 3646.2342907989355, 3656.1956369598647, 3663.102539877747, 1062.7077553672725, 1062.8990245926625, 1076.838474228872, 1083.5124194112602, 1088.3089238333637, 11296.54813807455, 11422.31466112036, 11442.313461192354, 11625.418729063598, 11628.0, 3818.3890577508023, 4655.37152484417, 4836.219173974979, 4840.0, 4896.2655601660035, 10113.69658910234, 10132.898671013343, 10138.797224055477, 10155.543000564992, 10184.439855807854, 5422.728863556807, 5429.701366424884, 5431.864203394868, 5434.402215756264, 5439.864003399946, 4318.222719910446, 4430.136123455895, 4581.450225972904, 4831.347768051339, 4937.728424936663, 4008.07814202735, 4034.2133284009924, 4124.381342798544, 4134.441850350225, 4141.213169497786, 1097.550004498152, 1264.1972347559183, 1301.4078594239597, 1306.758249723804, 1322.4511827591634, 864.3862857371337, 890.4434728294816, 912.0879120879121, 921.1249313152532, 942.7595963029383, 4716.434027916672, 5780.595358324961, 5970.09010615582, 6093.725782339717, 6183.659898705614, 918.5682729117304, 937.8311903857372, 955.2405837359316, 958.4680502321139, 1014.700663304327, 3730.973982154929, 4039.9192016159514, 4071.3279121995383, 4160.854370097032, 4177.310743727251, 10330.690079297634, 10870.782584348426, 10891.292066015683, 10910.945445272722, 10918.781624367468, 9246.815063698688, 10771.0, 10839.970202830718, 10856.131509479223, 10886.129109671207, 8199.344052475788, 8331.708390206313, 8333.166683331618, 8343.541105239148, 8345.457545259538, 5978.252718410199, 6006.579539432198, 6142.877142457215, 6209.782657607143, 6249.50003999688, 9667.516624168904, 10112.797744045225, 10115.848262275924, 10128.544215510172, 10145.9492702536, 9353.532323383668, 9713.27150463704, 9951.651692190739, 10126.291159619052, 10251.179905607385, 7415.703371865065, 7675.577843218453, 7744.419168562493, 7756.922430775846, 7762.805929851799, 8440.15598440151, 8458.323334133378, 8520.573971301534, 8551.230389264996, 8555.101714320155, 7483.887741683988, 7564.319211271012, 7628.618569071636, 7633.0, 7664.961675191588, 7485.363744081814, 7491.9625401872645, 7509.887351689838, 7516.210797866364, 7594.164641889277, 6814.454843612598, 7029.648517574203, 7133.607651579213, 7220.097487814023, 7229.855402892018, 5090.2364645303505, 5106.183010718267, 5159.226116082617, 5162.457941916045, 5285.577153827761, 4645.930311045269, 5063.291139240436, 5215.13950198213, 5347.171188465794, 5418.539424148991], "value": 4621.6279497348405, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.3907398444166, 307.77839955232224, 310.8414708498666, 313.25757953649855, 317.1073428299339, 5409.8106566270135, 5437.211604317372, 5451.945480545193, 5470.234167216593, 5476.4249753775875, 4474.977625111878, 4627.699199552027, 5341.706206158659, 5396.865078373043, 5439.782408703657, 3201.887933922319, 3691.8154092295276, 4035.616616421435, 4065.4308396824467, 4122.361034039714, 3596.2088340565165, 4031.0, 4060.2691515527194, 4065.125997910449, 4084.5915408459064, 4033.942689228485, 4042.7776472294013, 4051.6961227907877, 4051.817668204938, 4079.388091786226, 3395.847186876572, 3532.6997205237585, 3697.7797326882333, 3759.586445490994, 3973.46358241638, 3491.3191927574135, 3549.9290014199573, 3551.7691350062423, 3554.8578056877695, 3582.283543291352, 4753.572178503917, 5237.423883372864, 5253.4221235664045, 5255.237990491396, 5318.893622127536, 4175.5615660355525, 4527.207738645757, 4688.507706690816, 4692.249240121599, 4702.224133018048, 5018.623603229772, 5611.046122159243, 5619.325680918275, 5644.604877658566, 5648.830535083954, 3921.3725803871243, 3926.980365098185, 3930.941035884464, 3937.1338305572726, 3937.818654403696, 4906.018796240766, 5630.493255606974, 5818.650880947114, 5900.675462849542, 5979.252593425822, 3942.0, 3972.900677483086, 3976.1451287972645, 3981.2131507944555, 3986.402039694069, 3764.1154328732728, 3831.4827498287373, 4033.274010678106, 4055.918881622351, 4060.7563546187325, 4679.391679081766, 4686.133065382915, 4692.483826779052, 4708.976455117702, 4712.95287047132, 5301.6818990860675, 5433.0763770159165, 5653.604247702704, 5700.0, 5710.143478478261, 1185.6383802940013, 1204.566356111799, 1336.6967206973122, 1338.8285250405895, 1341.597520743773, 738.8079099434186, 768.888955459367, 779.8908152858604, 820.5856042698399, 827.5862068965517, 4485.394471746337, 4999.225120106389, 5097.949020509747, 5129.512696293846, 5169.353830771153, 4877.902441951142, 5046.419661738873, 5842.59101862874, 5861.896672329386, 5867.91688950425, 4401.6987259555335, 4457.866264012085, 4822.614190864722, 4925.310456536088, 5281.894362112813, 2515.91194308202, 2839.892441947642, 2931.296488842705, 3054.144839444959, 3073.523603841408, 5205.947940520622, 5422.4690815368895, 5953.315368732651, 5960.850978725566, 6029.939700603026, 4479.7536135512855, 5147.253648220987, 5554.361248456398, 5621.353544342453, 5936.5844390892225, 5213.322268105122, 5489.533389661923, 5739.540836733135, 5908.793192238251, 5936.881262374728, 4614.446266448048, 4863.489333619919, 5834.970825146017, 5856.853578660568, 5871.266091738533, 5425.348958125051, 5706.600537962303, 5733.598648094593, 5752.482276595127, 5755.453231942959, 4720.645951553579, 5234.921476177784, 5924.763009479572, 5983.371745966785, 6003.309619393825, 2894.623698919211, 3195.2810617610853, 3853.826577804062, 3855.460235567079, 3865.1689886673375, 5450.427705090909, 5476.91784623223, 5773.93182261283, 5862.794802181903, 5886.499647530008, 5405.324334458192, 5684.8294551163535, 5697.743601537858, 5703.173039909107, 5726.65640061581, 5461.0, 5609.831705048855, 5717.74149211051, 5758.539316854725, 5769.221155144001, 4851.811306229996, 5394.865128371822, 5481.945180548143, 5540.141278101901, 5556.249906262603, 4790.808367665254, 5097.362829646294, 5459.535939445032, 5548.725022740657, 5621.426000719805, 4628.629709623289, 5567.7494512747835, 5573.832785016456, 5580.665160090245, 5580.776768929198], "value": 4071.5418311764083, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.3746915368706, 755.9735409260674, 757.4016526943715, 803.1085495100434, 803.3372467714133, 15065.849341506582, 16303.347866085342, 16322.673546529077, 16325.265363058661, 16336.591585210375, 14588.197649129293, 14731.189784561871, 14739.336729847157, 14741.262936853169, 14762.409503619841, 710.9253528379523, 737.4985010193067, 882.858742601184, 891.7057371067565, 915.3638221436071, 910.9726708198765, 911.3210658059754, 926.4302453990814, 926.6895589977365, 927.0682963621551, 984.0405604535592, 984.5175863826745, 985.3299756165804, 988.4464699768153, 988.713273150789, 753.1263734068455, 920.1534588178891, 978.0659470205936, 979.1041197304494, 980.7842274699555, 710.4032612605384, 711.6797441151502, 713.393615426883, 713.3971793834202, 732.9010583571203, 15056.171910544916, 15110.773338399933, 15126.168060756654, 15127.319270632848, 15148.394064237527, 14046.648833779134, 14206.502772402917, 14264.0, 14393.208373539452, 14427.278636068155, 12772.872271277354, 12816.102872798907, 12926.0, 13112.409941552653, 13147.474101036043, 4435.578620031092, 4544.954550454486, 4564.0, 4573.451185857685, 4607.976960115178, 121968.0, 124810.0, 127192.99999999907, 128532.07201891886, 128769.35615321957, 10996.890031099747, 11115.277506962022, 11216.439178041066, 11244.325340479598, 11691.76616467666, 18306.0, 21896.12415503394, 21961.705744138188, 22005.0, 22015.22946696858, 7802.141764405911, 7944.483608565425, 7948.523088614702, 7963.0, 8064.475809072509, 4273.764942928106, 4376.302322921345, 4381.18947994622, 4383.430154080013, 4426.557344265612, 3344.364570731553, 3451.154467155563, 3452.205992621711, 3454.240067185215, 3455.87904423344, 575.1832397994857, 648.5913874259281, 659.3143131143653, 666.6366830077619, 670.7887015590073, 633.6673246545508, 635.259922190652, 637.2926052082186, 637.9362063793584, 658.8089454058365, 8801.735947921572, 9116.225120864801, 9247.26021918245, 9283.483501461846, 9313.90686093144, 516.3622925686774, 548.1886807524885, 557.8242853501134, 581.3605034462058, 632.9873402531923, 4138.0, 4449.7997590108525, 4463.977680111579, 4512.142692888341, 4559.972140689505, 152225.0, 154002.0, 159695.0, 161004.0, 166505.16747416186, 102947.4852625732, 103508.0, 114781.0, 116882.41558792321, 124770.37614811868, 81499.0, 92080.23759287098, 104892.0, 108709.45645271723, 111562.0, 9746.171575416169, 9846.556904939438, 9871.46478354187, 9977.434957385305, 10077.848832267668, 62183.0, 63686.68156659187, 65112.34887651063, 66458.66770666116, 67635.30911727247, 94724.52637736767, 96260.0, 98764.01235987549, 99341.0, 101933.0, 13268.0, 13325.400356984153, 13459.730805384033, 13876.167429954236, 13916.02587818843, 15120.924395377953, 15308.158051307286, 15393.692126157639, 15399.69200615959, 15753.68492630118, 14001.859981400463, 14239.216843073316, 14330.641733956734, 14617.122972621677, 14756.114633122046, 14142.363593638516, 14225.928870355581, 14408.567742967727, 14434.494792682206, 14729.263536822902, 11740.941295293469, 12088.0, 12520.74958500843, 13273.0, 14670.559883203521, 10960.616378426717, 11346.586926369322, 11382.317060976367, 11478.770424591628, 11750.0, 9546.42721436716, 11066.12550982749, 11547.0, 11663.88336116662, 11697.941510292394], "value": 7988.387126584838, "unit": "score"}], "name": "dromaeo_css", "value": 7509.079621850931, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 111.0, 112.0, 113.0, 111.0, 111.0, 109.0, 111.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 112.0, 108.0, 161.0, 107.0, 105.0, 128.0, 107.0, 144.0, 110.0], "value": 122.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 184.0, 183.0, 185.0, 183.0, 183.0, 185.0, 184.0, 181.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 57.0, 57.0, 103.0, 56.0, 57.0, 63.0, 67.0, 82.0, 67.0], "value": 68.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 69.0, 91.0, 73.0, 87.0, 68.0, 97.0, 71.0, 68.0, 117.0], "value": 82.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 94.0, 88.0, 88.0, 84.0, 95.0, 90.0, 91.0, 85.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 105.0, 149.0], "value": 100.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 92.0, 134.0, 83.0, 83.0, 85.0, 83.0, 87.0, 87.0], "value": 90.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 53.0, 53.0, 52.0, 61.0, 74.0, 84.0, 84.0, 86.0], "value": 65.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 76.0, 82.0, 72.0, 84.0, 84.0, 71.0, 71.0, 72.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 86.0, 86.0, 85.0, 85.0, 86.0, 82.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 138.0, 181.0, 142.0, 136.0, 151.0, 149.0, 227.0, 137.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 163.0, 164.0, 164.0, 162.0, 211.0, 223.0, 162.0, 163.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 66.0, 66.0, 64.0, 65.0, 67.0, 66.0, 66.0, 65.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1463.8, "alertThreshold": 2.0}]} 10:15:40 INFO - Return code: 0 10:15:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:15:40 INFO - # TBPL SUCCESS # 10:15:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:15:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:15:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:15:40 INFO - 2016-07-22 10:15:40.324 screenresolution[1666:10501] starting screenresolution argv=screenresolution get 10:15:40 INFO - 2016-07-22 10:15:40.327 screenresolution[1666:10501] Display 0: 1600x1200x32@60 10:15:40 INFO - 2016-07-22 10:15:40.333 screenresolution[1667:10504] starting screenresolution argv=screenresolution list 10:15:40 INFO - Available Modes on Display 0 10:15:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:15:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:15:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:15:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:15:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:15:40 INFO - Intel Iris: 10:15:40 INFO - Chipset Model: Intel Iris 10:15:40 INFO - Type: GPU 10:15:40 INFO - Bus: Built-In 10:15:40 INFO - VRAM (Dynamic, Max): 1536 MB 10:15:40 INFO - Vendor: Intel (0x8086) 10:15:40 INFO - Device ID: 0x0a2e 10:15:40 INFO - Revision ID: 0x0009 10:15:40 INFO - Displays: 10:15:40 INFO - Display: 10:15:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:15:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:15:40 INFO - Display Serial Number: 1600x1200 60 10:15:40 INFO - Main Display: Yes 10:15:40 INFO - Mirror: Off 10:15:40 INFO - Online: Yes 10:15:40 INFO - Rotation: Supported 10:15:40 INFO - Return code: 0 10:15:40 INFO - Running post-action listener: _resource_record_post_action 10:15:40 INFO - [mozharness: 2016-07-22 17:15:40.568600Z] Finished run-tests step (success) 10:15:40 INFO - Running post-run listener: _resource_record_post_run 10:15:40 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32291840; Write bytes: 707798528; Read time: 324; Write time: 2520 10:15:40 INFO - TinderboxPrint: CPU usage
53.5% 10:15:40 INFO - TinderboxPrint: I/O read bytes / time
32,291,840 / 324 10:15:40 INFO - TinderboxPrint: I/O write bytes / time
707,798,528 / 2,520 10:15:40 INFO - TinderboxPrint: CPU idle
2,253.2 (46.5%) 10:15:40 INFO - TinderboxPrint: CPU system
968.1 (20.0%) 10:15:40 INFO - TinderboxPrint: CPU user
1,624.4 (33.5%) 10:15:40 INFO - TinderboxPrint: Swap in / out
436,908,032 / 0 10:15:40 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389184000; Write bytes: 382547456; Read time: 29702; Write time: 812 10:15:40 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 31370240; Write bytes: 298008576; Read time: 311; Write time: 1655 10:15:41 INFO - Running post-run listener: _upload_blobber_files 10:15:41 INFO - Blob upload gear active. 10:15:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:15:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:15:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:15:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:15:41 INFO - (blobuploader) - INFO - Open directory for files ... 10:15:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:15:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:15:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:42 INFO - (blobuploader) - INFO - Done attempting. 10:15:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:15:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:15:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:43 INFO - (blobuploader) - INFO - Done attempting. 10:15:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:15:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:15:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:49 INFO - (blobuploader) - INFO - Done attempting. 10:15:49 INFO - (blobuploader) - INFO - Iteration through files over. 10:15:49 INFO - Return code: 0 10:15:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:15:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:15:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c76d411f4d99f25ed70214baf5ba1018519cfc9472c21bf7dccd5c766576853a430b946d01d5cd35183f0d62c5e07f74f451a1f32049a5e92a848e3ec4b36cd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/820c2823caf85c2892d1137afe948932459da6f04133ac8e12331127563f4c2f7e8ed0096572cfd0b0e543ca3efc14d1c5ffb3044de68df8398a26406a16099c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/195d028e3f5933a52c75adf1f5d8332399a09db48599903ecb9d67c5e4272f974839150908ee4d8cc26a84567a13a6616f6bbe0e1f248123525d2c12883bbb7c"} 10:15:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:15:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:15:49 INFO - Contents: 10:15:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c76d411f4d99f25ed70214baf5ba1018519cfc9472c21bf7dccd5c766576853a430b946d01d5cd35183f0d62c5e07f74f451a1f32049a5e92a848e3ec4b36cd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/820c2823caf85c2892d1137afe948932459da6f04133ac8e12331127563f4c2f7e8ed0096572cfd0b0e543ca3efc14d1c5ffb3044de68df8398a26406a16099c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/195d028e3f5933a52c75adf1f5d8332399a09db48599903ecb9d67c5e4272f974839150908ee4d8cc26a84567a13a6616f6bbe0e1f248123525d2c12883bbb7c"} 10:15:49 INFO - Running post-run listener: copy_logs_to_upload_dir 10:15:49 INFO - Copying logs to upload dir... 10:15:49 INFO - mkdir: /builds/slave/test/build/upload/logs 10:15:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.853562 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-22 10:15:49.179379) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 10:15:49.184354) ========= 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.5szOLqZRff/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.zMzLrogGUm/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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/c76d411f4d99f25ed70214baf5ba1018519cfc9472c21bf7dccd5c766576853a430b946d01d5cd35183f0d62c5e07f74f451a1f32049a5e92a848e3ec4b36cd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/820c2823caf85c2892d1137afe948932459da6f04133ac8e12331127563f4c2f7e8ed0096572cfd0b0e543ca3efc14d1c5ffb3044de68df8398a26406a16099c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/195d028e3f5933a52c75adf1f5d8332399a09db48599903ecb9d67c5e4272f974839150908ee4d8cc26a84567a13a6616f6bbe0e1f248123525d2c12883bbb7c"} build_url:https://queue.taskcluster.net/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011011 build_url: 'https://queue.taskcluster.net/v1/task/LmlJ04ZfSk6Z-4qvO76ljw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c76d411f4d99f25ed70214baf5ba1018519cfc9472c21bf7dccd5c766576853a430b946d01d5cd35183f0d62c5e07f74f451a1f32049a5e92a848e3ec4b36cd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/820c2823caf85c2892d1137afe948932459da6f04133ac8e12331127563f4c2f7e8ed0096572cfd0b0e543ca3efc14d1c5ffb3044de68df8398a26406a16099c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/195d028e3f5933a52c75adf1f5d8332399a09db48599903ecb9d67c5e4272f974839150908ee4d8cc26a84567a13a6616f6bbe0e1f248123525d2c12883bbb7c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 10:15:49.226599) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 10:15:49.227023) ========= 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.5szOLqZRff/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.zMzLrogGUm/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.005199 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 10:15:49.276327) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 10:15:49.276706) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 10:15:49.277145) ========= ========= Total master_lag: 0.26 =========