builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0125 starttime: 1468373017.68 results: success (0) buildid: 20160712160926 builduid: 8116fcd54ed841418d98492b38aab6de revision: 1fe5b3ad867bafb6c0deaaba54aa7e44c4ea1101 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:37.680784) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:37.681310) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:37.681627) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:37.709267) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:37.709643) ========= 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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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-12 18:23:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 148M=0s 2016-07-12 18:23:37 (148 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094060 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:37.826678) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:37.827027) ========= 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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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.031424 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:37.882168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:37.882882) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1fe5b3ad867bafb6c0deaaba54aa7e44c4ea1101 --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 1fe5b3ad867bafb6c0deaaba54aa7e44c4ea1101 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.axVGSRR1kZ/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.nvFNHbsbBz/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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-12 18:23:37,958 truncating revision to first 12 chars 2016-07-12 18:23:37,958 Setting DEBUG logging. 2016-07-12 18:23:37,958 attempt 1/10 2016-07-12 18:23:37,958 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1fe5b3ad867b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 18:23:38,587 unpacking tar archive at: fx-team-1fe5b3ad867b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.906631 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:38.814238) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:38.814608) ========= script_repo_revision: 1fe5b3ad867bafb6c0deaaba54aa7e44c4ea1101 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:38.815057) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:38.815363) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 18:23:38.833982) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-12 18:23:38.834295) ========= /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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 18:23:39 INFO - MultiFileLogger online at 20160712 18:23:39 in /builds/slave/test 18:23: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 18:23:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:23:39 INFO - {'append_to_log': False, 18:23:39 INFO - 'base_work_dir': '/builds/slave/test', 18:23:39 INFO - 'blob_upload_branch': 'Fx-Team', 18:23:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:23:39 INFO - 'branch': 'Fx-Team', 18:23:39 INFO - 'buildbot_json_path': 'buildprops.json', 18:23:39 INFO - 'config_files': ('talos/mac_config.py',), 18:23:39 INFO - 'default_actions': ('clobber', 18:23:39 INFO - 'read-buildbot-config', 18:23:39 INFO - 'download-and-extract', 18:23:39 INFO - 'populate-webroot', 18:23:39 INFO - 'create-virtualenv', 18:23:39 INFO - 'install', 18:23:39 INFO - 'run-tests'), 18:23:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:23:39 INFO - 'download_minidump_stackwalk': True, 18:23:39 INFO - 'download_symbols': 'ondemand', 18:23:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:23:39 INFO - 'tooltool.py': '/tools/tooltool.py', 18:23:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:23:39 INFO - '/tools/misc-python/virtualenv.py')}, 18:23:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:23:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:23:39 INFO - 'installer_path': 'installer.exe', 18:23:39 INFO - 'log_level': 'info', 18:23:39 INFO - 'log_name': 'talos', 18:23:39 INFO - 'log_to_console': True, 18:23:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:23:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:23:39 INFO - 'opt_config_files': (), 18:23:39 INFO - 'pip_index': False, 18:23:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:23:39 INFO - 'cmd': ('bash', 18:23:39 INFO - '-c', 18:23:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:23:39 INFO - 'enabled': True, 18:23:39 INFO - 'halt_on_failure': False, 18:23:39 INFO - 'name': 'check_screen_resolution'},), 18:23:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:23:39 INFO - 'cmd': ('bash', 18:23:39 INFO - '-c', 18:23:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:23:39 INFO - 'enabled': True, 18:23:39 INFO - 'halt_on_failure': False, 18:23:39 INFO - 'name': 'check_screen_resolution'},), 18:23:39 INFO - 'run_cmd_checks_enabled': True, 18:23:39 INFO - 'sps_profile': False, 18:23:39 INFO - 'sps_profile_interval': 0, 18:23:39 INFO - 'suite': 'dromaeojs', 18:23:39 INFO - 'system_bits': '32', 18:23:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:23:39 INFO - 'title': 't-yosemite-r7-0125', 18:23:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:23:39 INFO - 'use_talos_json': True, 18:23:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:23:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:23:39 INFO - 'work_dir': 'build'} 18:23:39 INFO - ##### 18:23:39 INFO - ##### Running clobber step. 18:23:39 INFO - ##### 18:23:39 INFO - Running pre-action listener: _resource_record_pre_action 18:23:39 INFO - Running main action method: clobber 18:23:39 INFO - rmtree: /builds/slave/test/build 18:23:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:23:41 INFO - Running post-action listener: _resource_record_post_action 18:23:41 INFO - ##### 18:23:41 INFO - ##### Finished clobber step (success) 18:23:41 INFO - ##### 18:23:41 INFO - ##### 18:23:41 INFO - ##### Running read-buildbot-config step. 18:23:41 INFO - ##### 18:23:41 INFO - Running pre-action listener: _resource_record_pre_action 18:23:41 INFO - Running main action method: read_buildbot_config 18:23:41 INFO - Using buildbot properties: 18:23:41 INFO - { 18:23:41 INFO - "project": "", 18:23:41 INFO - "product": "firefox", 18:23:41 INFO - "script_repo_revision": "production", 18:23:41 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:23:41 INFO - "repository": "", 18:23:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:23:41 INFO - "buildid": "20160712160926", 18:23:41 INFO - "pgo_build": "False", 18:23:41 INFO - "basedir": "/builds/slave/test", 18:23:41 INFO - "buildnumber": 100, 18:23:41 INFO - "slavename": "t-yosemite-r7-0125", 18:23:41 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:23:41 INFO - "platform": "yosemite_r7", 18:23:41 INFO - "scheduler": "tests-fx-team-macosx64-talos", 18:23:41 INFO - "branch": "fx-team", 18:23:41 INFO - "repo_path": "integration/fx-team", 18:23:41 INFO - "revision": "1fe5b3ad867bafb6c0deaaba54aa7e44c4ea1101", 18:23:41 INFO - "stage_platform": "macosx64", 18:23:41 INFO - "builduid": "8116fcd54ed841418d98492b38aab6de", 18:23:41 INFO - "slavebuilddir": "test" 18:23:41 INFO - } 18:23:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 18:23:41 INFO - Running post-action listener: _resource_record_post_action 18:23:41 INFO - ##### 18:23:41 INFO - ##### Finished read-buildbot-config step (success) 18:23:41 INFO - ##### 18:23:41 INFO - ##### 18:23:41 INFO - ##### Running download-and-extract step. 18:23:41 INFO - ##### 18:23:41 INFO - Running pre-action listener: _resource_record_pre_action 18:23:41 INFO - Running main action method: download_and_extract 18:23:41 INFO - mkdir: /builds/slave/test/build/tests 18:23: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')]} 18:23:41 INFO - https://queue.taskcluster.net/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:23:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 18:23:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 18:23:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/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 18:23:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/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 18:23:42 INFO - Downloaded 1199 bytes. 18:23:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 18:23:42 INFO - Using the following test package requirements: 18:23:42 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 18:23:42 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:23:42 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 18:23:42 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:23:42 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 18:23:42 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:23:42 INFO - u'jsshell-mac.zip'], 18:23:42 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:23:42 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 18:23:42 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 18:23:42 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:23:42 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 18:23:42 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:23:42 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 18:23:42 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:23:42 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 18:23:42 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:23:42 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 18:23:42 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:23: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')]} 18:23:42 INFO - https://queue.taskcluster.net/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:23:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:23:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:23:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/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 18:23:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/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 18:23:43 INFO - Downloaded 18822443 bytes. 18:23:43 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'] 18:23:43 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 18:23:47 INFO - Return code: 0 18:23: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 18:23: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')]} 18:23:47 INFO - https://queue.taskcluster.net/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:23:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:23:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:23:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/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 18:23:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/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 18:23:48 INFO - Downloaded 18822443 bytes. 18:23:48 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'] 18:23:48 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 18:23:51 INFO - Return code: 0 18:23:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:23:51 INFO - https://queue.taskcluster.net/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:23:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:23:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:23:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/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 18:23:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/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 18:23:52 INFO - Downloaded 14403317 bytes. 18:23:52 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'] 18:23:52 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 18:23:53 INFO - Return code: 0 18:23: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')]} 18:23:53 INFO - https://queue.taskcluster.net/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:23:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:23:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:23:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 18:23:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L76Lwp3uS8CsNx3BJjntPA/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 18:23:56 INFO - Downloaded 112416939 bytes. 18:23:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:23:56 INFO - mkdir: /builds/slave/test/properties 18:23:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:23:56 INFO - Writing to file /builds/slave/test/properties/build_url 18:23:56 INFO - Contents: 18:23:56 INFO - build_url:https://queue.taskcluster.net/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:23:57 INFO - Running post-action listener: _resource_record_post_action 18:23:57 INFO - Running post-action listener: set_extra_try_arguments 18:23:57 INFO - ##### 18:23:57 INFO - ##### Finished download-and-extract step (success) 18:23:57 INFO - ##### 18:23:57 INFO - ##### 18:23:57 INFO - ##### Running populate-webroot step. 18:23:57 INFO - ##### 18:23:57 INFO - Running pre-action listener: _resource_record_pre_action 18:23:57 INFO - Running main action method: populate_webroot 18:23:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:23:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:23:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:23:57 INFO - '--tppagecycles', 18:23:57 INFO - '7'], 18:23:57 INFO - 'tests': ['tsvgm']}}, 18:23:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:23:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:23:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:23:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:23:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:23:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:23:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:57 INFO - 'talos_options': ['--disable-e10s'], 18:23:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:57 INFO - 'talos_options': ['--disable-e10s'], 18:23:57 INFO - 'tests': ['damp', 'tps']}, 18:23:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:57 INFO - 'tests': ['damp', 'tps']}, 18:23:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:23:57 INFO - 'tests': ['dromaeo_dom']}, 18:23:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:23:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:23:57 INFO - 'tests': ['basic_compositor_video']}, 18:23:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:23:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:23:57 INFO - 'tests': ['a11yr', 18:23:57 INFO - 'ts_paint', 18:23:57 INFO - 'tpaint', 18:23:57 INFO - 'sessionrestore', 18:23:57 INFO - 'sessionrestore_no_auto_restore', 18:23:57 INFO - 'tabpaint']}, 18:23:57 INFO - 'other-e10s': {'tests': ['a11yr', 18:23:57 INFO - 'ts_paint', 18:23:57 INFO - 'tpaint', 18:23:57 INFO - 'sessionrestore', 18:23:57 INFO - 'sessionrestore_no_auto_restore', 18:23:57 INFO - 'tabpaint']}, 18:23:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:23:57 INFO - 'ts_paint', 18:23:57 INFO - 'tpaint', 18:23:57 INFO - 'sessionrestore', 18:23:57 INFO - 'sessionrestore_no_auto_restore', 18:23:57 INFO - 'tabpaint']}, 18:23:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:23:57 INFO - 'ts_paint', 18:23:57 INFO - 'tpaint', 18:23:57 INFO - 'sessionrestore', 18:23:57 INFO - 'sessionrestore_no_auto_restore', 18:23:57 INFO - 'tabpaint']}, 18:23:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:23:57 INFO - 'tests': ['a11yr', 18:23:57 INFO - 'ts_paint', 18:23:57 INFO - 'tpaint', 18:23:57 INFO - 'sessionrestore', 18:23:57 INFO - 'sessionrestore_no_auto_restore', 18:23:57 INFO - 'tabpaint']}, 18:23:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:23:57 INFO - 'tests': ['a11yr', 18:23:57 INFO - 'ts_paint', 18:23:57 INFO - 'tpaint', 18:23:57 INFO - 'sessionrestore', 18:23:57 INFO - 'sessionrestore_no_auto_restore', 18:23:57 INFO - 'tabpaint']}, 18:23:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:23:57 INFO - 'tests': ['tsvgx', 18:23:57 INFO - 'tsvgr_opacity', 18:23:57 INFO - 'tart', 18:23:57 INFO - 'tscrollx', 18:23:57 INFO - 'cart']}, 18:23:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:23:57 INFO - 'tsvgr_opacity', 18:23:57 INFO - 'tart', 18:23:57 INFO - 'tscrollx', 18:23:57 INFO - 'cart']}, 18:23:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:57 INFO - 'talos_options': ['--disable-e10s'], 18:23:57 INFO - 'tests': ['tp5o']}, 18:23:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:57 INFO - 'tests': ['tp5o']}, 18:23:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:57 INFO - 'talos_options': ['--disable-e10s', 18:23:57 INFO - '--xperf_path', 18:23:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:57 INFO - 'tests': ['tp5n']}, 18:23:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:57 INFO - 'talos_options': ['--xperf_path', 18:23:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:57 INFO - 'tests': ['tp5n']}}, 18:23:57 INFO - 'talos.zip': {'path': '', 18:23:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:23:57 INFO - Running post-action listener: _resource_record_post_action 18:23:57 INFO - ##### 18:23:57 INFO - ##### Finished populate-webroot step (success) 18:23:57 INFO - ##### 18:23:57 INFO - ##### 18:23:57 INFO - ##### Running create-virtualenv step. 18:23:57 INFO - ##### 18:23:57 INFO - Running pre-action listener: _resource_record_pre_action 18:23:57 INFO - Running main action method: create_virtualenv 18:23:57 INFO - Creating virtualenv /builds/slave/test/build/venv 18:23:57 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 18:23:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:23:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:23:57 INFO - Using real prefix '/tools/python27' 18:23:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:23:58 INFO - Installing distribute.............................................................................................................................................................................................done. 18:24:01 INFO - Installing pip.................done. 18:24:01 INFO - Return code: 0 18:24:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:24:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:01 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 0x105cb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc980d84cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c18d30>, '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 0x10597ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c10b20>, '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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 18:24:01 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 18:24:01 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 18:24:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axVGSRR1kZ/Render', 18:24:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:01 INFO - 'HOME': '/Users/cltbld', 18:24:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:01 INFO - 'LOGNAME': 'cltbld', 18:24:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:01 INFO - 'MOZ_NO_REMOTE': '1', 18:24:01 INFO - 'NO_EM_RESTART': '1', 18:24:01 INFO - 'PAGER': '/bin/cat', 18:24:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:01 INFO - 'PWD': '/builds/slave/test', 18:24:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:01 INFO - 'SHELL': '/bin/bash', 18:24:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvFNHbsbBz/Listeners', 18:24:01 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 18:24:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:01 INFO - 'USER': 'cltbld', 18:24:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:01 INFO - 'XPC_FLAGS': '0x0', 18:24:01 INFO - 'XPC_SERVICE_NAME': '0', 18:24:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:01 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 18:24:01 INFO - Cleaning up... 18:24:01 INFO - Return code: 0 18:24:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:24:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:01 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 0x105cb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc980d84cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c18d30>, '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 0x10597ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c10b20>, '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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 18:24:01 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 18:24:01 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 18:24:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axVGSRR1kZ/Render', 18:24:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:01 INFO - 'HOME': '/Users/cltbld', 18:24:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:01 INFO - 'LOGNAME': 'cltbld', 18:24:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:01 INFO - 'MOZ_NO_REMOTE': '1', 18:24:01 INFO - 'NO_EM_RESTART': '1', 18:24:01 INFO - 'PAGER': '/bin/cat', 18:24:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:01 INFO - 'PWD': '/builds/slave/test', 18:24:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:01 INFO - 'SHELL': '/bin/bash', 18:24:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvFNHbsbBz/Listeners', 18:24:01 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 18:24:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:01 INFO - 'USER': 'cltbld', 18:24:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:01 INFO - 'XPC_FLAGS': '0x0', 18:24:01 INFO - 'XPC_SERVICE_NAME': '0', 18:24:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:01 INFO - Downloading/unpacking psutil>=3.1.1 18:24:01 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 18:24:01 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 18:24:01 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 18:24:01 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 18:24:01 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 18:24:01 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 18:24:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:24:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:24:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:24:04 INFO - Installing collected packages: psutil 18:24:04 INFO - Running setup.py install for psutil 18:24:04 INFO - building 'psutil._psutil_osx' extension 18:24:04 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 18:24: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 18:24: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:24:05 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 18:24:05 INFO - building 'psutil._psutil_posix' extension 18:24:05 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 18:24:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:24:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:24:05 INFO - ^ 18:24:05 INFO - 1 warning generated. 18:24:05 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 18:24:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:24:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:24:05 INFO - Successfully installed psutil 18:24:05 INFO - Cleaning up... 18:24:05 INFO - Return code: 0 18:24:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:24:05 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')]} 18:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:05 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')]} 18:24:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:05 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 0x105cb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc980d84cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c18d30>, '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 0x10597ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c10b20>, '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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 18:24:05 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 18:24:05 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 18:24:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axVGSRR1kZ/Render', 18:24:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:05 INFO - 'HOME': '/Users/cltbld', 18:24:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:05 INFO - 'LOGNAME': 'cltbld', 18:24:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:05 INFO - 'MOZ_NO_REMOTE': '1', 18:24:05 INFO - 'NO_EM_RESTART': '1', 18:24:05 INFO - 'PAGER': '/bin/cat', 18:24:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:05 INFO - 'PWD': '/builds/slave/test', 18:24:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:05 INFO - 'SHELL': '/bin/bash', 18:24:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvFNHbsbBz/Listeners', 18:24:05 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 18:24:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:05 INFO - 'USER': 'cltbld', 18:24:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:05 INFO - 'XPC_FLAGS': '0x0', 18:24:05 INFO - 'XPC_SERVICE_NAME': '0', 18:24:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:24: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 18:24: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 18:24: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 18:24: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 18:24: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 18:24: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 18:24:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:24:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:24:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:24:08 INFO - Installing collected packages: mozsystemmonitor 18:24:08 INFO - Running setup.py install for mozsystemmonitor 18:24:08 INFO - Successfully installed mozsystemmonitor 18:24:08 INFO - Cleaning up... 18:24:08 INFO - Return code: 0 18:24:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:24:08 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')]} 18:24:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:08 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')]} 18:24:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:08 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 0x105cb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc980d84cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c18d30>, '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 0x10597ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c10b20>, '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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 18:24:08 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 18:24:08 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 18:24:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axVGSRR1kZ/Render', 18:24:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:08 INFO - 'HOME': '/Users/cltbld', 18:24:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:08 INFO - 'LOGNAME': 'cltbld', 18:24:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:08 INFO - 'MOZ_NO_REMOTE': '1', 18:24:08 INFO - 'NO_EM_RESTART': '1', 18:24:08 INFO - 'PAGER': '/bin/cat', 18:24:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:08 INFO - 'PWD': '/builds/slave/test', 18:24:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:08 INFO - 'SHELL': '/bin/bash', 18:24:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvFNHbsbBz/Listeners', 18:24:08 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 18:24:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:08 INFO - 'USER': 'cltbld', 18:24:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:08 INFO - 'XPC_FLAGS': '0x0', 18:24:08 INFO - 'XPC_SERVICE_NAME': '0', 18:24:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:09 INFO - Downloading/unpacking jsonschema==2.5.1 18:24: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 18:24: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 18:24: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 18:24: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 18:24: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 18:24: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 18:24:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:24:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:24:11 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 18:24:11 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 18:24:11 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 18:24:11 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 18:24:11 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 18:24:11 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 18:24:11 INFO - Downloading functools32-3.2.3-2.tar.gz 18:24:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:24:11 INFO - warning: no files found matching '*.txt' 18:24:11 INFO - no previously-included directories found matching 'build' 18:24:11 INFO - no previously-included directories found matching 'dist' 18:24:11 INFO - no previously-included directories found matching '.git*' 18:24:11 INFO - Installing collected packages: jsonschema, functools32 18:24:11 INFO - Running setup.py install for functools32 18:24:12 INFO - warning: no files found matching '*.txt' 18:24:12 INFO - no previously-included directories found matching 'build' 18:24:12 INFO - no previously-included directories found matching 'dist' 18:24:12 INFO - no previously-included directories found matching '.git*' 18:24:12 INFO - Successfully installed jsonschema functools32 18:24:12 INFO - Cleaning up... 18:24:12 INFO - Return code: 0 18:24:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:24:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:24:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:24:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:12 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 0x105cb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc980d84cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c18d30>, '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 0x10597ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c10b20>, '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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 18:24:12 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 18:24:12 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 18:24:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axVGSRR1kZ/Render', 18:24:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:12 INFO - 'HOME': '/Users/cltbld', 18:24:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:12 INFO - 'LOGNAME': 'cltbld', 18:24:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:12 INFO - 'MOZ_NO_REMOTE': '1', 18:24:12 INFO - 'NO_EM_RESTART': '1', 18:24:12 INFO - 'PAGER': '/bin/cat', 18:24:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:12 INFO - 'PWD': '/builds/slave/test', 18:24:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:12 INFO - 'SHELL': '/bin/bash', 18:24:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvFNHbsbBz/Listeners', 18:24:12 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 18:24:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:12 INFO - 'USER': 'cltbld', 18:24:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:12 INFO - 'XPC_FLAGS': '0x0', 18:24:12 INFO - 'XPC_SERVICE_NAME': '0', 18:24:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:12 INFO - Downloading/unpacking blobuploader==1.2.4 18:24: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 18:24: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 18:24: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 18:24: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 18:24: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 18:24: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 18:24:14 INFO - Downloading blobuploader-1.2.4.tar.gz 18:24:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:24:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:24:14 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 18:24:14 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 18:24:14 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 18:24:14 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 18:24:14 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 18:24:14 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 18:24:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:24:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:24: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 18:24: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 18:24: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 18:24: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 18:24: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 18:24: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 18:24:16 INFO - Downloading docopt-0.6.1.tar.gz 18:24:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:24:16 INFO - Installing collected packages: blobuploader, requests, docopt 18:24:16 INFO - Running setup.py install for blobuploader 18:24:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:24:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:24:16 INFO - Running setup.py install for requests 18:24:16 INFO - Running setup.py install for docopt 18:24:16 INFO - Successfully installed blobuploader requests docopt 18:24:16 INFO - Cleaning up... 18:24:17 INFO - Return code: 0 18:24:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24: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')]} 18:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24: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')]} 18:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24: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 0x105cb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc980d84cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c18d30>, '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 0x10597ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c10b20>, '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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 18:24: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 18:24: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 18:24:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axVGSRR1kZ/Render', 18:24:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:17 INFO - 'HOME': '/Users/cltbld', 18:24:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:17 INFO - 'LOGNAME': 'cltbld', 18:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:17 INFO - 'MOZ_NO_REMOTE': '1', 18:24:17 INFO - 'NO_EM_RESTART': '1', 18:24:17 INFO - 'PAGER': '/bin/cat', 18:24:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:17 INFO - 'PWD': '/builds/slave/test', 18:24:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:17 INFO - 'SHELL': '/bin/bash', 18:24:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvFNHbsbBz/Listeners', 18:24:17 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 18:24:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:17 INFO - 'USER': 'cltbld', 18:24:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:17 INFO - 'XPC_FLAGS': '0x0', 18:24:17 INFO - 'XPC_SERVICE_NAME': '0', 18:24:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:17 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-wJtuaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:17 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-DeLkuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:17 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-ICYxWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:17 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-VbfSiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:17 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-oJAjuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:17 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-I26yjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:17 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-yjH2FZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-1HKTUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-L_JQ0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-pwqP21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-F8aKo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-WTNKOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-9k6Al3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-OCKs0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-BXwhHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-WsYMTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-SyBqXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:24:19 INFO - Running setup.py install for manifestparser 18:24:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:24:19 INFO - Running setup.py install for mozcrash 18:24:19 INFO - Running setup.py install for mozdebug 18:24:19 INFO - Running setup.py install for mozdevice 18:24:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:24:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:24:19 INFO - Running setup.py install for mozfile 18:24:19 INFO - Running setup.py install for mozhttpd 18:24:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:24:20 INFO - Running setup.py install for mozinfo 18:24:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:24:20 INFO - Running setup.py install for mozInstall 18:24:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:24:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:24:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:24:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:24:20 INFO - Running setup.py install for mozleak 18:24:20 INFO - Running setup.py install for mozlog 18:24:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:24:20 INFO - Running setup.py install for moznetwork 18:24:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:24:20 INFO - Running setup.py install for mozprocess 18:24:20 INFO - Running setup.py install for mozprofile 18:24:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:24:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:24:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:24:21 INFO - Running setup.py install for mozrunner 18:24:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:24:21 INFO - Running setup.py install for mozscreenshot 18:24:21 INFO - Running setup.py install for moztest 18:24:21 INFO - Running setup.py install for mozversion 18:24:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:24:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:24:21 INFO - Cleaning up... 18:24:21 INFO - Return code: 0 18:24:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:24:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:21 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 0x105cb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc980d84cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c18d30>, '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 0x10597ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c10b20>, '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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 18:24:21 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 18:24:21 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 18:24:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axVGSRR1kZ/Render', 18:24:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:21 INFO - 'HOME': '/Users/cltbld', 18:24:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:21 INFO - 'LOGNAME': 'cltbld', 18:24:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:21 INFO - 'MOZ_NO_REMOTE': '1', 18:24:21 INFO - 'NO_EM_RESTART': '1', 18:24:21 INFO - 'PAGER': '/bin/cat', 18:24:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:21 INFO - 'PWD': '/builds/slave/test', 18:24:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:21 INFO - 'SHELL': '/bin/bash', 18:24:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvFNHbsbBz/Listeners', 18:24:21 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 18:24:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:21 INFO - 'USER': 'cltbld', 18:24:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:21 INFO - 'XPC_FLAGS': '0x0', 18:24:21 INFO - 'XPC_SERVICE_NAME': '0', 18:24:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:22 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-xsWmBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:22 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)) 18:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:22 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-l52YXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:22 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)) 18:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:22 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-WSDXP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:22 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)) 18:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:22 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-XL9RnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:22 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)) 18:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:22 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-ZVaGdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:22 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)) 18:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:22 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-hrABVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:22 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)) 18:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:22 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-PxPmda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:22 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)) 18:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:22 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-Z6CyFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:22 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)) 18:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:22 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-RbuQTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:22 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)) 18:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:22 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-8vAwn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-syicyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:23 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)) 18:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-Ash6eV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:23 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)) 18:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-MgGYXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:23 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)) 18:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-BZ7nQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:23 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)) 18:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-QTSq0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:23 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)) 18:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-Q7LSvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:23 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)) 18:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-4oj9gK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:23 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)) 18:24:23 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)) 18:24:23 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)) 18:24:23 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)) 18:24:23 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)) 18:24:23 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)) 18:24:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:26 INFO - Downloading blessings-1.6.tar.gz 18:24:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:24:26 INFO - Installing collected packages: blessings 18:24:26 INFO - Running setup.py install for blessings 18:24:26 INFO - Successfully installed blessings 18:24:26 INFO - Cleaning up... 18:24:26 INFO - Return code: 0 18:24:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:24:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:26 INFO - Reading from file tmpfile_stdout 18:24:26 INFO - Current package versions: 18:24:26 INFO - blessings == 1.6 18:24:26 INFO - blobuploader == 1.2.4 18:24:26 INFO - docopt == 0.6.1 18:24:26 INFO - functools32 == 3.2.3-2 18:24:26 INFO - jsonschema == 2.5.1 18:24:26 INFO - manifestparser == 1.1 18:24:26 INFO - mozInstall == 1.12 18:24:26 INFO - mozcrash == 0.17 18:24:26 INFO - mozdebug == 0.1 18:24:26 INFO - mozdevice == 0.48 18:24:26 INFO - mozfile == 1.2 18:24:26 INFO - mozhttpd == 0.7 18:24:26 INFO - mozinfo == 0.9 18:24:26 INFO - mozleak == 0.1 18:24:26 INFO - mozlog == 3.2 18:24:26 INFO - moznetwork == 0.27 18:24:26 INFO - mozprocess == 0.23 18:24:26 INFO - mozprofile == 0.28 18:24:26 INFO - mozrunner == 6.12 18:24:26 INFO - mozscreenshot == 0.1 18:24:26 INFO - mozsystemmonitor == 0.3 18:24:26 INFO - moztest == 0.7 18:24:26 INFO - mozversion == 1.4 18:24:26 INFO - psutil == 3.1.1 18:24:26 INFO - requests == 1.2.3 18:24:26 INFO - wsgiref == 0.1.2 18:24:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:26 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 0x105cb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc980d84cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c18d30>, '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 0x10597ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c10b20>, '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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 18:24:26 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 18:24:26 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 18:24:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axVGSRR1kZ/Render', 18:24:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:26 INFO - 'HOME': '/Users/cltbld', 18:24:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:26 INFO - 'LOGNAME': 'cltbld', 18:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:26 INFO - 'MOZ_NO_REMOTE': '1', 18:24:26 INFO - 'NO_EM_RESTART': '1', 18:24:26 INFO - 'PAGER': '/bin/cat', 18:24:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:26 INFO - 'PWD': '/builds/slave/test', 18:24:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:26 INFO - 'SHELL': '/bin/bash', 18:24:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvFNHbsbBz/Listeners', 18:24:26 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 18:24:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:26 INFO - 'USER': 'cltbld', 18:24:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:26 INFO - 'XPC_FLAGS': '0x0', 18:24:26 INFO - 'XPC_SERVICE_NAME': '0', 18:24:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:27 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)) 18:24:27 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)) 18:24:27 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)) 18:24:27 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)) 18:24:27 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)) 18:24:27 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)) 18:24:27 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)) 18:24:27 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)) 18:24:27 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)) 18:24:27 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)) 18:24:27 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)) 18:24:27 INFO - Cleaning up... 18:24:27 INFO - Return code: 0 18:24:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:24: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')]} 18:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24: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')]} 18:24:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:27 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 0x105cb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc980d84cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c18d30>, '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 0x10597ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c10b20>, '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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 18:24:27 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 18:24:27 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 18:24:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axVGSRR1kZ/Render', 18:24:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:27 INFO - 'HOME': '/Users/cltbld', 18:24:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:27 INFO - 'LOGNAME': 'cltbld', 18:24:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:27 INFO - 'MOZ_NO_REMOTE': '1', 18:24:27 INFO - 'NO_EM_RESTART': '1', 18:24:27 INFO - 'PAGER': '/bin/cat', 18:24:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:27 INFO - 'PWD': '/builds/slave/test', 18:24:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:27 INFO - 'SHELL': '/bin/bash', 18:24:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvFNHbsbBz/Listeners', 18:24:27 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 18:24:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:27 INFO - 'USER': 'cltbld', 18:24:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:27 INFO - 'XPC_FLAGS': '0x0', 18:24:27 INFO - 'XPC_SERVICE_NAME': '0', 18:24:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:24:27 INFO - Cleaning up... 18:24:27 INFO - Return code: 0 18:24:27 INFO - Running post-action listener: _resource_record_post_action 18:24:27 INFO - Running post-action listener: _start_resource_monitoring 18:24:27 INFO - Starting resource monitoring. 18:24:27 INFO - ##### 18:24:27 INFO - ##### Finished create-virtualenv step (success) 18:24:27 INFO - ##### 18:24:27 INFO - ##### 18:24:27 INFO - ##### Running install step. 18:24:27 INFO - ##### 18:24:27 INFO - Running pre-action listener: _resource_record_pre_action 18:24:27 INFO - Running main action method: install 18:24:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:27 INFO - Reading from file tmpfile_stdout 18:24:27 INFO - Detecting whether we're running mozinstall >=1.0... 18:24:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:24:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:24:27 INFO - Reading from file tmpfile_stdout 18:24:27 INFO - Output received: 18:24:27 INFO - Usage: mozinstall [options] installer 18:24:27 INFO - Options: 18:24:27 INFO - -h, --help show this help message and exit 18:24:27 INFO - -d DEST, --destination=DEST 18:24:27 INFO - Directory to install application into. [default: 18:24:27 INFO - "/builds/slave/test"] 18:24:27 INFO - --app=APP Application being installed. [default: firefox] 18:24:27 INFO - mkdir: /builds/slave/test/build/application 18:24:27 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'] 18:24:27 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 18:25:01 INFO - Reading from file tmpfile_stdout 18:25:01 INFO - Output received: 18:25:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:25:01 INFO - Running post-action listener: _resource_record_post_action 18:25:01 INFO - ##### 18:25:01 INFO - ##### Finished install step (success) 18:25:01 INFO - ##### 18:25:01 INFO - ##### 18:25:01 INFO - ##### Running run-tests step. 18:25:01 INFO - ##### 18:25:01 INFO - Running pre-action listener: _resource_record_pre_action 18:25:01 INFO - Running main action method: run_tests 18:25:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:25:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:25:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:25:01 INFO - 2016-07-12 18:25:01.926 screenresolution[1636:7677] starting screenresolution argv=screenresolution get 18:25:01 INFO - 2016-07-12 18:25:01.928 screenresolution[1636:7677] Display 0: 1600x1200x32@60 18:25:01 INFO - 2016-07-12 18:25:01.934 screenresolution[1637:7684] starting screenresolution argv=screenresolution list 18:25:01 INFO - Available Modes on Display 0 18:25:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:25:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:25:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:25:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:25:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:25:02 INFO - Intel Iris: 18:25:02 INFO - Chipset Model: Intel Iris 18:25:02 INFO - Type: GPU 18:25:02 INFO - Bus: Built-In 18:25:02 INFO - VRAM (Dynamic, Max): 1536 MB 18:25:02 INFO - Vendor: Intel (0x8086) 18:25:02 INFO - Device ID: 0x0a2e 18:25:02 INFO - Revision ID: 0x0009 18:25:02 INFO - Displays: 18:25:02 INFO - Display: 18:25:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:25:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:25:02 INFO - Display Serial Number: 1600x1200 60 18:25:02 INFO - Main Display: Yes 18:25:02 INFO - Mirror: Off 18:25:02 INFO - Online: Yes 18:25:02 INFO - Rotation: Supported 18:25:02 INFO - Return code: 0 18:25:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:25:02 INFO - Python 2.7.3 18:25:02 INFO - Return code: 0 18:25:02 INFO - grabbing minidump binary from tooltool 18:25: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')]} 18:25:02 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 0x105c18d30>, '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 0x10597ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c10b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:25:02 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 18:25:02 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 18:25:02 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 18:25:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:25:02 INFO - Return code: 0 18:25:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:25:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:25:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:25:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:25:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:25:02 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/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--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 18:25:02 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/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0125 --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 18:25:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axVGSRR1kZ/Render', 18:25:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:02 INFO - 'HOME': '/Users/cltbld', 18:25:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:02 INFO - 'LOGNAME': 'cltbld', 18:25:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:25:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:02 INFO - 'MOZ_NO_REMOTE': '1', 18:25:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:25:02 INFO - 'NO_EM_RESTART': '1', 18:25:02 INFO - 'PAGER': '/bin/cat', 18:25:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:02 INFO - 'PWD': '/builds/slave/test', 18:25:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:25:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:02 INFO - 'SHELL': '/bin/bash', 18:25:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nvFNHbsbBz/Listeners', 18:25:02 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 18:25:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:02 INFO - 'USER': 'cltbld', 18:25:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:02 INFO - 'XPC_FLAGS': '0x0', 18:25:02 INFO - 'XPC_SERVICE_NAME': '0', 18:25:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:02 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/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--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 18:25:02 INFO - mozversion application_buildid: 20160712160926 18:25:02 INFO - mozversion application_changeset: 1fe5b3ad867bafb6c0deaaba54aa7e44c4ea1101 18:25:02 INFO - mozversion application_display_name: Nightly 18:25:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:25:02 INFO - mozversion application_name: Firefox 18:25:02 INFO - mozversion application_remotingname: firefox 18:25:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:25:02 INFO - mozversion application_vendor: Mozilla 18:25:02 INFO - mozversion application_version: 50.0a1 18:25:02 INFO - mozversion platform_buildid: 20160712160926 18:25:02 INFO - mozversion platform_changeset: 1fe5b3ad867bafb6c0deaaba54aa7e44c4ea1101 18:25:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:25:02 INFO - mozversion platform_version: 50.0a1 18:25:02 INFO - using testdate: 1468373102 18:25:02 INFO - actual date: 1468373102 18:25:02 INFO - starting webserver on 'localhost:49272' 18:25:02 INFO - SUITE-START | Running 2 tests 18:25:02 INFO - TEST-START | dromaeo_css 18:25:02 INFO - operating with platform_type : mac_ 18:25:02 INFO - Initialising browser for dromaeo_css test... 18:25:02 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpENQ06c/profile http://localhost:49272/getInfo.html) 18:25:04 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 18:25:04 INFO - PROCESS | 1647 | colorDepth:24 18:25:04 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 18:25:04 INFO - PROCESS | 1647 | __metrics 18:25:05 INFO - PROCESS | 1647 | 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" 18:25:05 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:25:05 INFO - TEST-INFO | 1647: exit 0 18:25:05 INFO - Browser initialized. 18:25:05 INFO - Running cycle 1/1 for dromaeo_css test... 18:25:05 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpENQ06c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:25:17 INFO - PROCESS | 1649 | RSS: Main: 243806208 18:25:17 INFO - PROCESS | 1649 | 18:26:09 INFO - PROCESS | 1649 | 1468373169421 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] 18:28:19 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 18:28:21 INFO - PROCESS | 1649 | RSS: Main: 335056896 18:28:21 INFO - PROCESS | 1649 | 18:31:24 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 18:31:24 INFO - PROCESS | 1649 | RSS: Main: 382181376 18:31:24 INFO - PROCESS | 1649 | 18:34:27 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 18:34:27 INFO - PROCESS | 1649 | RSS: Main: 372535296 18:34:27 INFO - PROCESS | 1649 | 18:37:29 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 18:37:30 INFO - PROCESS | 1649 | RSS: Main: 372072448 18:37:30 INFO - PROCESS | 1649 | 18:40:33 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 18:40:34 INFO - PROCESS | 1649 | RSS: Main: 344305664 18:40:34 INFO - PROCESS | 1649 | 18:43:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 18:43:38 INFO - PROCESS | 1649 | RSS: Main: 385024000 18:43:38 INFO - PROCESS | 1649 | 18:43:38 INFO - PROCESS | 1649 | __start_tp_report 18:43:38 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 18:43:38 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 18:43:38 INFO - PROCESS | 1649 | |i|pagename|runs| 18:43:38 INFO - PROCESS | 1649 | |0;dojo.html;2442.5114977004596;3406.352792969334;3409.1988382730055;3417.8550185687795;3453.7755045922013;5668.43315668434;5672.432756724325;5736.139579063142;5774.769009239631;5785.884282314357;5701.0878259478495;5788.27646544182;5812.186293918855;5824.417558244173;5827.213326200962;5213.895722085575;5235.267062611238;5300.575953923677;5311.681299122046;5328.866778330534;5337.653052551591;5383.81156659517;5400.405955344909;5418.485243901823;5435.945640543583;5220.190870415073;5221.921671174935;5253.474652534755;5310.787568497255;5312.521873031426;5217;5263.657862238981;5277.445868183862;5280.498352656491;5292.629515933886;5198.064348417283;5220.060389129955;5261.684298942076;5295.417504074549;5344.278522399465;5804.303483581955;5839.503642190419;5878.147668588031;5882.000059989812;5889.234399528077;6107.5833010365195;6213.782517611907;6214.9689251553455;6276.96861515694;6286.842828929268;6253.87492250157;6272.65500397478;6308.589941653778;6342.39747224013;6349.682515874188;5886.793962211302;5904.084866845646;5911.9113213301835;5960.076188190794;5980.102984552308;6210.347913469111;6237.719302631393;6241.656708881055;6258.968705156445;6268.122462855204;6257.374262573797;6258.155149054819;6264.812055638338;6275.152854364694;6286;6298.937010629927;6315.273743519462;6334.461570766536;6337.873242535124;6339;6100.3086879273915;6138.07928810673;6162.352952939877;6208.192934918432;6272.780452684134;6296.307406185316;6318.462930650946;6336.746530138835;6342.809715708536;6343.238811342669;4934.777934992934;4971.90056198874;4978.900421991612;4981.252812078144;5039.622028347815;4391.4730232371685;4412.02935354228;4419.712718673276;4494.505604383515;4508.887277818081;5712.971665100322;5744.253247077937;5760.135979603093;5760.53915686744;5823.737931793079;6231.40801623845;6248.4376406123665;6285.088662143963;6289.465395441439;6295.622262664255;5800.4489573490455;5840.591158618856;5843.123531470228;5843.2403787507355;5899.056151015817;5345.305110335631;5407.324084489439;5411.675299482044;5487.094629386106;5489.478499542537;6277.843053923689;6396.42432181106;6397.936020639921;6402.871942561216;6404.967975160188;6190.442860142609;6275.905861411991;6296.275928268308;6300.054991751274;6307.085472606354;6172.660503672341;6272.341404152681;6288.937110628835;6388.105665206782;6394.424501794861;5111.412187598474;6234.127222188807;6254.749810007549;6267.561270711189;6267.655278959519;6257.234119710002;6269.811905642838;6295.748170073329;6299.30707622152;6304.432601065926;6034.788782392595;6124.785632503019;6128.172696686069;6163.044728067157;6190.7523699051535;6014.097885317237;6159.784407545714;6170.376208386154;6219.626822390671;6230.127782110418;6085.939140608537;6086.969565152146;6109.663968481598;6110.036353870931;6116.305337585956;6048.879022419439;6119.106526814713;6151.384861513813;6169.907451388322;6262.09199666055;6076.179715738319;6087.714492067947;6099.309783606499;6108.08378743192;6130.356312587114 18:43:38 INFO - PROCESS | 1649 | |1;ext.html;496.6597880451891;504.8334049763578;518.9688618682878;522.1045906270747;555.0924238869452;30616.69383306166;42200.577994220046;43066;43199.78400107999;45693.40073097434;44668.3299750504;45115.54884451155;45123.09753804922;45168.77415612918;45196.3220551692;8812.339074569401;9772.169365603932;9950.85073723891;9956.85064724026;9962.352447090916;8915.019347871728;11939.701507462296;11983.760324793499;12127.93936030323;12384.504619815196;12138.878611213908;12201.267923924594;12206.389680515984;12209;12242.877571224262;8749.256313213384;11837.230580012272;12025.03799696031;12109.273443593413;12248.632541023739;11931.224470409396;11981;12027;12031.819522707166;12038.69903252417;42111.157776844295;42475.93810154771;42513.57486425127;42516;42631.147377052286;3450.4824276358745;3522.3483655523555;3557.1285035166293;3577.3560759063357;3649.6167902370134;27006.45987080267;32223.51664725031;32650.183745406317;33408.33183336344;33410.49884251738;3241.8865339712997;3410.3520331137006;3416.026432466735;3451.5340429042008;3454.5681789776277;491977;492784;495080;495691;503301.48349258385;53322.46677533175;63830;64002.35997640057;64247.357526425076;64324.35675643184;66220.33779662238;66918;66949.6652516744;66974.33025669778;67110;16754.07852568096;17023.89344692591;17060.744088838677;17087;17094;11782.764344713058;12110.939445302893;12114.636560903187;12348.320842353756;13043.869561304455;16064.75902861458;17679.939203647566;18167.72748408775;18177.545561361072;18642.44072677822;5284.762185701653;5500.257465242221;6206.813795586139;6269.937300627026;6290.24517057956;912.9178373946244;966.7293157915794;967.2938754708961;1054.699410667956;1080.6271836216492;29972.250693732833;30853.920112795946;31004.68995310063;31099.378012440076;31664.891728789382;1065.547142464465;1094.9233553651327;1120.3501968858207;1122.9494672739747;1184.5025089462447;15909.363625454853;16244;16517;16555.751663725056;16585;638115;638674;644457;648643;651972;62351.6882415585;62414.0637890432;62473.75052499015;62701.05948410778;62872.68563657244;1291.3026965438557;1374.2510331869155;1422.73679369317;1438.4821464273064;1439.0358459831727;32424.351512969137;32557;32681.509777353833;33050.66949330476;33367.961150571435;52472;53038.46961530489;53054.204186936455;53100;53200.46799531955;430261;446341;448036;448360;448865;33697.66302336945;36031.45952810658;36786.448203277505;42150.78924605357;44839.291874352726;37441.625583743815;48049.279260810414;48489.27266090941;48716.75641621769;49759.25361119658;40483.79758101191;40789;41056.384154237116;41747.37378939258;41774;40138.99652508594;40508.59491405048;40689.796551017054;41604;41777.16445671009;35292.47061294134;37435.06412339713;38676.226475470896;39071.41392879166;39237.6266830671;26411.471770564865;34694.479582805776;35064.29871402507;35845;35853;24682.259532214062;25723.871380642977;26150.73849261483;26303.322272418616;26470.07354742575 18:43:38 INFO - PROCESS | 1649 | |2;jquery.html;298.64461291063617;347.7166109620659;372.8694956765132;386.85106234099874;386.8897364251187;15123;15960.76058859118;21280.787192128075;21283;21285.25501607444;20544.17823287067;20746.89626551865;21374.786252137474;21508.354749357506;21519.784802151975;4995.750212489379;5318.547923426513;5445.29084838864;5544.833654990337;5549.556035517149;4641.930371044437;5453.509184173424;5473.698946557938;5475.890482190353;5480.698561579112;5256.710880901549;5430.968116057937;5461.1535212042;5470.753816078286;5478.315210598676;5431.891362172754;5440.619156659035;5459.781608735646;5463.9180412293845;5495.780168793243;5080.390353157608;5297.258383826267;5339.519443250126;5343.465653434664;5344.278522399465;23895.805209739443;25777.35556611081;26194.607080893802;26717.797699103394;26802.79387427551;2190.255313193522;2208.2160832904246;2249.2352600116037;2280.931572052841;2350.5651454480803;10018.849717254247;10801.729956751065;10816.51325690338;12864.549740759028;15381.154036527987;1880.5204672808484;1884.3498992847447;1894.3180455036177;1894.336982056283;1930.1121484117323;108622;127230.36384818202;128005;129375.35312323472;131583;11613.825792613117;11827.822582661267;11840;11862.88137118635;12038.939805301092;14892.404303827941;14987.775183372258;15011.699766004618;15017.849821501864;15018.624534386729;8628.309735221168;8817.985931617817;8832.02847686754;8896.42173258749;8915.910840891507;8737.126287371211;8746.65013399457;8758.956205218934;8865.911340886509;8890.377673562789;4560.95439045612;4642.605378542794;4682.204025315705;4702.694324868872;4784;1885.6982882738696;1944.931927382535;1953.5213872601303;1953.8534609904314;1956.5597740508556;907.0158877617858;914.5564401265381;955.1642312976146;1020.9540570674337;1065.8507808906759;21589.352319430443;22450.550988980456;22527.77472225257;22530;22613.547729045655;904.5251243097426;918.2286879021653;924.4314746431031;964.9179819715263;1017.8473229015707;11327;11374.829377559343;11714.180007399571;11715.238509496856;11780.99861511764;214138;214454.9277253635;214972;291125;601375;131605;132041;139625;140507.29746351202;143168.5683143176;985.3250523391499;996.1582462819051;1054.4253381906879;1081.291753901196;1081.551156270139;15699.607509811889;19750.901245493682;19845.60308793845;19892.20970112697;19901.303454379027;56971.14543281937;57574;57972.42027579839;58071.709641451525;58262.4173758257;81277.18722812696;86820.5658971701;86953;87440.03877080907;89817.1618844773;8144.226298501633;8224.38317126206;8282.130376310643;8564.828703426021;8574.442661227;11234.213605047567;13119.409626567018;13150.802737958747;13214.339283036003;13330;11020.889791101987;11329.603463878724;11429.885701142883;11440.199186056896;11451.541938322707;11402.885971140515;11446.94276528612;11457.59898403552;11458.130294624918;11630.825537616773;11517.366544839779;11550.018248448976;11580.768384632092;11646.475908584303;11737.002354799937;7033.261507541839;8223.259906608433;8258.42191046621;8427.00826265165;8462.576871156542;10667.039966403061;10907.727306817393;11197.888021119685;11447.713807154554;11509.309441433541 18:43:38 INFO - PROCESS | 1649 | |3;mootools.html;1386.2237147197563;1526.191118707085;1568.309943624805;1723.3279021181734;1878.2956391353243;7715.382769378451;8194.754157375273;8453.07016228216;8736.301095912328;8739.257163141141;8520.531370774605;8632.136786321365;8697;8698.434601750881;8727.345449091328;2358.0332063853825;2359.1035406545575;2359.2096647623066;2361.185391040097;2366.3729111785347;2320.4430936575186;2354.8469349492316;2361.8110551155864;2365.8343915925893;2366.077229880348;1956.2761778142108;1993.372087792348;2322.756110608387;2353.576356255873;2369.5616310982523;2299.5056062946546;2324.6280595104704;2325.802306803915;2330.487292795582;2331.300609817048;2276.1805749930013;2281.634938409863;2281.6577513372963;2284.862908225495;2287.0965968442374;8733.475991440533;8736.388452808305;8794.736157915273;8814.294856411536;8824.602892869772;5149.819756308549;5167.6641018333685;5306.6550674206055;5308.203769434615;5354.892902141935;3545.5213546171194;3620.8913732588067;3650.653187947141;3683.1712864605515;3686.083236716919;1072.7371793910463;1094.4691824465128;1099.2195541165781;1103.823388257883;1107.413071072333;11547.422628868522;11646.359450230233;11646.70883227926;11666.358350290731;11684.649460516199;4400.559944005574;4429.800658970354;4610.700304480265;4643.141018911445;4643.466001409881;9084.40951338161;9087.36388452802;9087.545622718837;9102.180803727564;9104.13510716481;4641.512641172696;4910.189818679878;5228.398734145546;5231.3199284092825;5238.214267859851;4186.6859985501205;4359.38968544404;4362.1275744851155;4511.796969136331;4907.9263811042865;3750.343689854319;3783.7729736215915;3784.7918364489665;3826.559945606235;3837.4052021936645;1030.8711960403346;1093.606301731376;1140.5836869542557;1143.0798207443088;1154.6593754842343;873.5151990645126;926.8934072581604;933.6452148183782;954.7279025477706;1008.1178968402647;5919.467247947706;5928.703564821742;5954.523638108941;6146.323904370515;6171.074338849208;940.3934462271797;940.7742141886035;956.9808603828023;965.4448692002127;985.5318723606373;3824.7513911595656;4002.8799136025964;4054.8175332110127;4066;4091.324931386288;9992.65025724096;10630.25588208818;10633.840492392621;10642.201834862417;10674.839877401846;10369.222308326905;10487.580496780196;10557.313774604627;10612.57549698019;10641.787164256672;8079.5960201989665;8193.221643943934;8228.21831925966;8243.670253189925;8249.79375515605;5735.655860648208;5867.413258674099;5875.41245875409;5908.5568582357355;5973.104034394875;9610.807783844144;9980.451075190935;9986.650467233612;9991.300608957303;10020.298579099393;9753.219742420732;10075.949620251851;10121.848172277569;10135.442550659785;10169.03394177552;7436.516626419266;7484.064491938508;7572.280633339824;7581.848363032819;7583.848323033619;8010.479318844256;8137.430379873352;8161.959190204011;8209.917900820916;8237.764335349746;7351.66917488725;7445.888311675221;7457.813554661177;7488.812779680334;7572.280633339824;7291.927080729125;7294.854102917806;7381.372583330262;7432.665530051053;7439.032925719622;6682.398584127452;6984.6158461283085;7003.894941575983;7010.018597396471;7067.469939754436;4988.900221995467;5001.074801161797;5017.347744793154;5058.317127187783;5161.406438259498;4998.400191976986;5077.111505486599;5087.4403815581;5108.56577190943;5255.3168088148295 18:43:38 INFO - PROCESS | 1649 | |4;prototype.html;256.2019309849818;309.17912941141276;312.61548295596424;317.4333814141756;321.97263232625227;4366.323219900916;5378.731063446827;5402.945970540299;5425.647332923367;5465.781368745245;4389.429374181352;5316.840494785153;5346.198070289459;5357.4374690657405;5360.65983504124;3603.747737658365;3886.7862267575274;3970.325044742386;4042.4542686737354;4064.817083231247;3473.99254216277;3615.9819200903958;3864.4589757433982;4039.5560685785117;4050.392441133824;3591.6049234584175;3964.167524819791;4059.451973983504;4059.5128584569893;4078.8171430285183;4076.2458945095104;4078.9592104079024;4087.386891966199;4091.754494730326;4104.250152397901;3339.632640409553;3391.253924136711;3470.7570470067108;3537.7877327360184;3566.554180727409;4292.5063617683745;5120.231965205249;5150.48495150482;5154.587632989352;5178.637495375326;4645.279981602857;4647.837325693618;4655.3947986761505;4655.720656760605;4718;4492.7528985905765;5173.861750414902;5428.592855535811;5519.834404967817;5610.354809196954;3106.7359274461696;3559.7330200235087;3579.8365531202203;3866.709996750255;3872.419137129425;4445.021965225476;4549.408576885017;5004.674696144775;5549.500544950974;5648.378678345379;3291.6997785874446;3320.4189266878197;3507.2985402919007;3929.410588411704;3929.6070392960473;4037.9394309085387;4038.757674539537;4047.7368971017377;4049.1091959768805;4054.3985125875115;4295.3771703102875;4463.486699029653;4521.299198624219;4630.351750754897;4706.317583950308;5221;5562.888742225214;5624.353199382123;5672.290963629546;5681.091025435993;1139.168407062832;1141.1669481278714;1321.6167311479755;1333.8999575031912;1350.702845374016;696.5611664651339;760.2017881224692;796.1441450440799;796.8644681329083;844.9704260350858;3891.4357418174204;3936.6418585587926;4340.088581397878;4377.321515165155;4392.758398288125;4316.6546676265825;4532.484471118561;4804.903901921942;4817.759112044384;4889.926651100236;3849.480320156743;4136.524299705512;4155.729877557949;4443.422355093817;4534.120833270831;2456.533258680845;2559.756823101802;2753.765929895941;2797.888084476598;3065.513226085347;5440.836774896759;5500.50495455403;5617.8708649142845;6028.895119013711;6126.172966649535;5136.382039657496;5162.638615296968;5301.628885977944;5896.351401345849;5953.7023148843255;5220.321358223407;5447.237386765856;5603.805830676475;5987.311459182076;6024.216851809237;5472.808451704331;5833.912491312719;5838.503727183067;5869.4717475427415;5919.6152250103605;4580.473245576668;5753.89289783529;5760.8271751847515;5791.797287094932;5816.82549523515;5678.630888992202;5844.181814546052;5929.733162007807;5986.16193732869;6039.78860739872;3865.3622152345674;3871.3612253977776;3898.629630185128;3910.9217815644097;3929.5088113985753;5664.44227837348;5772.5959182856795;5846.386129456346;5862.179294898803;5882.735276912464;5584.860378490408;5634.521065709297;5668.234788303526;5671.291088613923;5751.9137212941005;5624.831255062355;5638;5680.68756218412;5688.5733569981585;5701.857453563694;5121.308623335951;5508.641938274;5511;5526.58550608714;5566.582506312124;4507.729536227836;5307.6284660073425;5631.943680563306;5637.689927053887;5659.462351076641;4772.475027746876;5537.342075803717;5549;5565.860853478695;5570.832875013756 18:43:38 INFO - PROCESS | 1649 | |5;yui.html;676.3439463720191;703.4899697719154;708.9787306380811;734.5592644413352;744.7170075371358;15116.697666046672;15956.920215398906;16505.66988660226;16513.917430412846;16749.99500029999;12484.875151248485;15311.310991005405;15318.846811531881;15344;15458.458953936615;717.7918403662932;740.7111226621622;857.7212405968054;880.7533890510667;885.0706757904208;726.3789460011693;898.7258886039788;898.8202359528087;903.6204793986542;907.6823111910811;933.8319102561536;941.8540126280403;947.8530827721714;948.5257371314343;949.1125797379473;739.0835364148471;919.2829592917535;939.0797018921461;940.5955439161158;951.1962391779005;702.6556987076295;703.4829400390709;703.6552089476171;704.246456291768;704.9471289653296;15257.542273731806;15331;15332.693346133126;15336.15651139198;15352.769708454382;13958.720825583432;14069.437222511087;14071.085379450304;14151.29243537819;14340.56978290653;12080.516779328816;12116.575919842851;12783.936080319632;12832.743345133138;12981.221126732426;4528.977355113237;4538.546145385468;4568.977155114237;4639.953600463986;4717.43390793107;87656.12343876544;102899.485502572;116159.08382904857;117509.41245293805;117896;10832.78334433307;10980.560777568808;11185.552577896955;11412.657620271406;11621.941890290494;22375.552488950456;22400.43998900008;22419.88790056072;22490.887545562167;22495.887520562294;6783.660816959133;7997.960010199912;8014.83970320602;8124.83750325002;8130.83738325242;4352.934705979413;4484.394606728115;4578;4578.519255478193;4580.564846339659;3441.328940856509;3546.8935931922083;3571.3035957988463;3573.3210689968823;3573.8391772370305;583.0438081546267;584.5615788158881;591.2638764737928;603.9245094363205;622.3403192615841;603.9094135879566;610.7465401858268;625.3070704363721;640.1837656987378;681.7136802542943;8592.95703521491;8870.201681848665;8971.309093979236;9066.95466522663;9293.814123717488;604.0817956705782;618.264265524025;625.4808508937571;641.1408712325506;646.4763541531374;4319.524852266248;4482.932756008662;4567.543245675406;5103.209002604603;5305.681659100389;143639.2818035924;154937.2253138727;159809.40190598177;160026.39973600348;161350;134962;136451;138067;139851;141829;116414;116476;120000;121047.39476302562;124581;9550.713478595653;9724.9027509724;9740.318177727491;9764.316497845082;9911.65309214174;56807.147892782465;61007.69496152491;61527.692361537906;62088;64091.35908640854;52955.470445295054;70175.94736079065;91415;96424.51787741017;98494;13285.468581257028;13698.931505342409;13791.724165516433;13976.650583735487;14135.858641413453;14524.85475145235;15112.773308400603;15114.54656360311;15155.848441515885;15620.765688514453;13754.243516606683;13905.930470347583;13977.371018304404;14258.857411425754;14599;13929.721405571629;13964.650883727989;14170.645733856736;14214;14224.928875355557;13682.589522314347;13729.725405491634;13793.655158620713;13948.302584870513;13972.161670299814;11441.370724610226;11558.653240402802;11687.474063667325;11696.941515292368;12035.638930832089;11027.397260274036;11250.718732031764;11372.20394572372;11477.713057173638;11544 18:43:38 INFO - PROCESS | 1649 | __end_tp_report 18:43:38 INFO - PROCESS | 1649 | __start_cc_report 18:43:38 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1321 18:43:38 INFO - PROCESS | 1649 | __end_cc_report 18:43:38 INFO - PROCESS | 1649 | __startTimestamp1468374218014__endTimestamp 18:43:38 INFO - PROCESS | 1649 | 18:43:38 INFO - PROCESS | 1649 | ------- Summary: start ------- 18:43:38 INFO - PROCESS | 1649 | Number of tests: 6 18:43:38 INFO - PROCESS | 1649 | 18:43:38 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5803.04 Median:6087.71 stddev:663.03 (10.9%) stddev-sans-first:610.41 18:43:38 INFO - PROCESS | 1649 | Values: 2442.5 3406.4 3409.2 3417.9 3453.8 5668.4 5672.4 5736.1 5774.8 5785.9 5701.1 5788.3 5812.2 5824.4 5827.2 5213.9 5235.3 5300.6 5311.7 5328.9 5337.7 5383.8 5400.4 5418.5 5435.9 5220.2 5221.9 5253.5 5310.8 5312.5 5217.0 5263.7 5277.4 5280.5 5292.6 5198.1 5220.1 5261.7 5295.4 5344.3 5804.3 5839.5 5878.1 5882.0 5889.2 6107.6 6213.8 6215.0 6277.0 6286.8 6253.9 6272.7 6308.6 6342.4 6349.7 5886.8 5904.1 5911.9 5960.1 5980.1 6210.3 6237.7 6241.7 6259.0 6268.1 6257.4 6258.2 6264.8 6275.2 6286.0 6298.9 6315.3 6334.5 6337.9 6339.0 6100.3 6138.1 6162.4 6208.2 6272.8 6296.3 6318.5 6336.7 6342.8 6343.2 4934.8 4971.9 4978.9 4981.3 5039.6 4391.5 4412.0 4419.7 4494.5 4508.9 5713.0 5744.3 5760.1 5760.5 5823.7 6231.4 6248.4 6285.1 6289.5 6295.6 5800.4 5840.6 5843.1 5843.2 5899.1 5345.3 5407.3 5411.7 5487.1 5489.5 6277.8 6396.4 6397.9 6402.9 6405.0 6190.4 6275.9 6296.3 6300.1 6307.1 6172.7 6272.3 6288.9 6388.1 6394.4 5111.4 6234.1 6254.7 6267.6 6267.7 6257.2 6269.8 6295.7 6299.3 6304.4 6034.8 6124.8 6128.2 6163.0 6190.8 6014.1 6159.8 6170.4 6219.6 6230.1 6085.9 6087.0 6109.7 6110.0 6116.3 6048.9 6119.1 6151.4 6169.9 6262.1 6076.2 6087.7 6099.3 6108.1 6130.4 18:43:38 INFO - PROCESS | 1649 | 18:43:38 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:68180.07 Median:37438.34 stddev:142494.07 (380.6%) stddev-sans-first:142803.19 18:43:38 INFO - PROCESS | 1649 | Values: 496.7 504.8 519.0 522.1 555.1 30616.7 42200.6 43066.0 43199.8 45693.4 44668.3 45115.5 45123.1 45168.8 45196.3 8812.3 9772.2 9950.9 9956.9 9962.4 8915.0 11939.7 11983.8 12127.9 12384.5 12138.9 12201.3 12206.4 12209.0 12242.9 8749.3 11837.2 12025.0 12109.3 12248.6 11931.2 11981.0 12027.0 12031.8 12038.7 42111.2 42475.9 42513.6 42516.0 42631.1 3450.5 3522.3 3557.1 3577.4 3649.6 27006.5 32223.5 32650.2 33408.3 33410.5 3241.9 3410.4 3416.0 3451.5 3454.6 491977.0 492784.0 495080.0 495691.0 503301.5 53322.5 63830.0 64002.4 64247.4 64324.4 66220.3 66918.0 66949.7 66974.3 67110.0 16754.1 17023.9 17060.7 17087.0 17094.0 11782.8 12110.9 12114.6 12348.3 13043.9 16064.8 17679.9 18167.7 18177.5 18642.4 5284.8 5500.3 6206.8 6269.9 6290.2 912.9 966.7 967.3 1054.7 1080.6 29972.3 30853.9 31004.7 31099.4 31664.9 1065.5 1094.9 1120.4 1122.9 1184.5 15909.4 16244.0 16517.0 16555.8 16585.0 638115.0 638674.0 644457.0 648643.0 651972.0 62351.7 62414.1 62473.8 62701.1 62872.7 1291.3 1374.3 1422.7 1438.5 1439.0 32424.4 32557.0 32681.5 33050.7 33368.0 52472.0 53038.5 53054.2 53100.0 53200.5 430261.0 446341.0 448036.0 448360.0 448865.0 33697.7 36031.5 36786.4 42150.8 44839.3 37441.6 48049.3 48489.3 48716.8 49759.3 40483.8 40789.0 41056.4 41747.4 41774.0 40139.0 40508.6 40689.8 41604.0 41777.2 35292.5 37435.1 38676.2 39071.4 39237.6 26411.5 34694.5 35064.3 35845.0 35853.0 24682.3 25723.9 26150.7 26303.3 26470.1 18:43:38 INFO - PROCESS | 1649 | 18:43:38 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:27913.68 Median:2199.24 stddev:62176.28 (2827.2%) stddev-sans-first:62316.13 18:43:38 INFO - PROCESS | 1649 | Values: 298.6 347.7 372.9 386.9 386.9 15123.0 15960.8 21280.8 21283.0 21285.3 20544.2 20746.9 21374.8 21508.4 21519.8 4995.8 5318.5 5445.3 5544.8 5549.6 4641.9 5453.5 5473.7 5475.9 5480.7 5256.7 5431.0 5461.2 5470.8 5478.3 5431.9 5440.6 5459.8 5463.9 5495.8 5080.4 5297.3 5339.5 5343.5 5344.3 23895.8 25777.4 26194.6 26717.8 26802.8 2190.3 2208.2 2249.2 2280.9 2350.6 10018.8 10801.7 10816.5 12864.5 15381.2 1880.5 1884.3 1894.3 1894.3 1930.1 108622.0 127230.4 128005.0 129375.4 131583.0 11613.8 11827.8 11840.0 11862.9 12038.9 14892.4 14987.8 15011.7 15017.8 15018.6 8628.3 8818.0 8832.0 8896.4 8915.9 8737.1 8746.7 8759.0 8865.9 8890.4 4561.0 4642.6 4682.2 4702.7 4784.0 1885.7 1944.9 1953.5 1953.9 1956.6 907.0 914.6 955.2 1021.0 1065.9 21589.4 22450.6 22527.8 22530.0 22613.5 904.5 918.2 924.4 964.9 1017.8 11327.0 11374.8 11714.2 11715.2 11781.0 214138.0 214454.9 214972.0 291125.0 601375.0 131605.0 132041.0 139625.0 140507.3 143168.6 985.3 996.2 1054.4 1081.3 1081.6 15699.6 19750.9 19845.6 19892.2 19901.3 56971.1 57574.0 57972.4 58071.7 58262.4 81277.2 86820.6 86953.0 87440.0 89817.2 8144.2 8224.4 8282.1 8564.8 8574.4 11234.2 13119.4 13150.8 13214.3 13330.0 11020.9 11329.6 11429.9 11440.2 11451.5 11402.9 11446.9 11457.6 11458.1 11630.8 11517.4 11550.0 11580.8 11646.5 11737.0 7033.3 8223.3 8258.4 8427.0 8462.6 10667.0 10907.7 11197.9 11447.7 11509.3 18:43:38 INFO - PROCESS | 1649 | 18:43:38 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5646.19 Median:5037.83 stddev:3152.59 (62.6%) stddev-sans-first:3145.18 18:43:38 INFO - PROCESS | 1649 | Values: 1386.2 1526.2 1568.3 1723.3 1878.3 7715.4 8194.8 8453.1 8736.3 8739.3 8520.5 8632.1 8697.0 8698.4 8727.3 2358.0 2359.1 2359.2 2361.2 2366.4 2320.4 2354.8 2361.8 2365.8 2366.1 1956.3 1993.4 2322.8 2353.6 2369.6 2299.5 2324.6 2325.8 2330.5 2331.3 2276.2 2281.6 2281.7 2284.9 2287.1 8733.5 8736.4 8794.7 8814.3 8824.6 5149.8 5167.7 5306.7 5308.2 5354.9 3545.5 3620.9 3650.7 3683.2 3686.1 1072.7 1094.5 1099.2 1103.8 1107.4 11547.4 11646.4 11646.7 11666.4 11684.6 4400.6 4429.8 4610.7 4643.1 4643.5 9084.4 9087.4 9087.5 9102.2 9104.1 4641.5 4910.2 5228.4 5231.3 5238.2 4186.7 4359.4 4362.1 4511.8 4907.9 3750.3 3783.8 3784.8 3826.6 3837.4 1030.9 1093.6 1140.6 1143.1 1154.7 873.5 926.9 933.6 954.7 1008.1 5919.5 5928.7 5954.5 6146.3 6171.1 940.4 940.8 957.0 965.4 985.5 3824.8 4002.9 4054.8 4066.0 4091.3 9992.7 10630.3 10633.8 10642.2 10674.8 10369.2 10487.6 10557.3 10612.6 10641.8 8079.6 8193.2 8228.2 8243.7 8249.8 5735.7 5867.4 5875.4 5908.6 5973.1 9610.8 9980.5 9986.7 9991.3 10020.3 9753.2 10075.9 10121.8 10135.4 10169.0 7436.5 7484.1 7572.3 7581.8 7583.8 8010.5 8137.4 8162.0 8209.9 8237.8 7351.7 7445.9 7457.8 7488.8 7572.3 7291.9 7294.9 7381.4 7432.7 7439.0 6682.4 6984.6 7003.9 7010.0 7067.5 4988.9 5001.1 5017.3 5058.3 5161.4 4998.4 5077.1 5087.4 5108.6 5255.3 18:43:38 INFO - PROCESS | 1649 | 18:43:38 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4453.17 Median:4788.69 stddev:1404.81 (29.3%) stddev-sans-first:1372.98 18:43:38 INFO - PROCESS | 1649 | Values: 256.2 309.2 312.6 317.4 322.0 4366.3 5378.7 5402.9 5425.6 5465.8 4389.4 5316.8 5346.2 5357.4 5360.7 3603.7 3886.8 3970.3 4042.5 4064.8 3474.0 3616.0 3864.5 4039.6 4050.4 3591.6 3964.2 4059.5 4059.5 4078.8 4076.2 4079.0 4087.4 4091.8 4104.3 3339.6 3391.3 3470.8 3537.8 3566.6 4292.5 5120.2 5150.5 5154.6 5178.6 4645.3 4647.8 4655.4 4655.7 4718.0 4492.8 5173.9 5428.6 5519.8 5610.4 3106.7 3559.7 3579.8 3866.7 3872.4 4445.0 4549.4 5004.7 5549.5 5648.4 3291.7 3320.4 3507.3 3929.4 3929.6 4037.9 4038.8 4047.7 4049.1 4054.4 4295.4 4463.5 4521.3 4630.4 4706.3 5221.0 5562.9 5624.4 5672.3 5681.1 1139.2 1141.2 1321.6 1333.9 1350.7 696.6 760.2 796.1 796.9 845.0 3891.4 3936.6 4340.1 4377.3 4392.8 4316.7 4532.5 4804.9 4817.8 4889.9 3849.5 4136.5 4155.7 4443.4 4534.1 2456.5 2559.8 2753.8 2797.9 3065.5 5440.8 5500.5 5617.9 6028.9 6126.2 5136.4 5162.6 5301.6 5896.4 5953.7 5220.3 5447.2 5603.8 5987.3 6024.2 5472.8 5833.9 5838.5 5869.5 5919.6 4580.5 5753.9 5760.8 5791.8 5816.8 5678.6 5844.2 5929.7 5986.2 6039.8 3865.4 3871.4 3898.6 3910.9 3929.5 5664.4 5772.6 5846.4 5862.2 5882.7 5584.9 5634.5 5668.2 5671.3 5751.9 5624.8 5638.0 5680.7 5688.6 5701.9 5121.3 5508.6 5511.0 5526.6 5566.6 4507.7 5307.6 5631.9 5637.7 5659.5 4772.5 5537.3 5549.0 5565.9 5570.8 18:43:38 INFO - PROCESS | 1649 | 18:43:38 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:25459.70 Median:3559.10 stddev:41083.57 (1154.3%) stddev-sans-first:41156.68 18:43:38 INFO - PROCESS | 1649 | Values: 676.3 703.5 709.0 734.6 744.7 15116.7 15956.9 16505.7 16513.9 16750.0 12484.9 15311.3 15318.8 15344.0 15458.5 717.8 740.7 857.7 880.8 885.1 726.4 898.7 898.8 903.6 907.7 933.8 941.9 947.9 948.5 949.1 739.1 919.3 939.1 940.6 951.2 702.7 703.5 703.7 704.2 704.9 15257.5 15331.0 15332.7 15336.2 15352.8 13958.7 14069.4 14071.1 14151.3 14340.6 12080.5 12116.6 12783.9 12832.7 12981.2 4529.0 4538.5 4569.0 4640.0 4717.4 87656.1 102899.5 116159.1 117509.4 117896.0 10832.8 10980.6 11185.6 11412.7 11621.9 22375.6 22400.4 22419.9 22490.9 22495.9 6783.7 7998.0 8014.8 8124.8 8130.8 4352.9 4484.4 4578.0 4578.5 4580.6 3441.3 3546.9 3571.3 3573.3 3573.8 583.0 584.6 591.3 603.9 622.3 603.9 610.7 625.3 640.2 681.7 8593.0 8870.2 8971.3 9067.0 9293.8 604.1 618.3 625.5 641.1 646.5 4319.5 4482.9 4567.5 5103.2 5305.7 143639.3 154937.2 159809.4 160026.4 161350.0 134962.0 136451.0 138067.0 139851.0 141829.0 116414.0 116476.0 120000.0 121047.4 124581.0 9550.7 9724.9 9740.3 9764.3 9911.7 56807.1 61007.7 61527.7 62088.0 64091.4 52955.5 70175.9 91415.0 96424.5 98494.0 13285.5 13698.9 13791.7 13976.7 14135.9 14524.9 15112.8 15114.5 15155.8 15620.8 13754.2 13905.9 13977.4 14258.9 14599.0 13929.7 13964.7 14170.6 14214.0 14224.9 13682.6 13729.7 13793.7 13948.3 13972.2 11441.4 11558.7 11687.5 11696.9 12035.6 11027.4 11250.7 11372.2 11477.7 11544.0 18:43:38 INFO - PROCESS | 1649 | -------- Summary: end -------- 18:43:38 INFO - PROCESS | 1649 | 18:43:38 INFO - TEST-INFO | 1649: exit 0 18:43:38 INFO - TEST-OK | dromaeo_css | took 1116149ms 18:43:38 INFO - TEST-START | kraken 18:43:38 INFO - operating with platform_type : mac_ 18:43:38 INFO - Initialising browser for kraken test... 18:43:38 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpBStJ8O/profile http://localhost:49272/getInfo.html) 18:43:41 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 18:43:41 INFO - PROCESS | 1660 | colorDepth:24 18:43:41 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 18:43:41 INFO - PROCESS | 1660 | __metrics 18:43:41 INFO - PROCESS | 1660 | 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" 18:43:41 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:43:41 INFO - TEST-INFO | 1660: exit 0 18:43:41 INFO - Browser initialized. 18:43:41 INFO - Running cycle 1/1 for kraken test... 18:43:41 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpBStJ8O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:43:53 INFO - PROCESS | 1661 | RSS: Main: 243576832 18:43:53 INFO - PROCESS | 1661 | 18:43:56 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 18:43:57 INFO - PROCESS | 1661 | RSS: Main: 293920768 18:43:57 INFO - PROCESS | 1661 | 18:44:00 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 18:44:00 INFO - PROCESS | 1661 | RSS: Main: 304111616 18:44:00 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 18:44:04 INFO - PROCESS | 1661 | RSS: Main: 390414336 18:44:04 INFO - PROCESS | 1661 | 18:44:07 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 18:44:07 INFO - PROCESS | 1661 | RSS: Main: 290656256 18:44:07 INFO - PROCESS | 1661 | 18:44:09 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:44:10 INFO - PROCESS | 1661 | RSS: Main: 294653952 18:44:10 INFO - PROCESS | 1661 | 18:44:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 18:44:13 INFO - PROCESS | 1661 | RSS: Main: 331833344 18:44:13 INFO - PROCESS | 1661 | 18:44:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 18:44:17 INFO - PROCESS | 1661 | RSS: Main: 334761984 18:44:17 INFO - PROCESS | 1661 | 18:44:20 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 18:44:20 INFO - PROCESS | 1661 | RSS: Main: 333004800 18:44:20 INFO - PROCESS | 1661 | 18:44:22 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:44:22 INFO - PROCESS | 1661 | RSS: Main: 311447552 18:44:22 INFO - PROCESS | 1661 | 18:44:24 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:44:25 INFO - PROCESS | 1661 | RSS: Main: 318107648 18:44:25 INFO - PROCESS | 1661 | 18:44:27 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:44:28 INFO - PROCESS | 1661 | RSS: Main: 316071936 18:44:28 INFO - PROCESS | 1661 | 18:44:31 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:44:31 INFO - PROCESS | 1661 | RSS: Main: 324935680 18:44:31 INFO - PROCESS | 1661 | 18:44:34 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:44:35 INFO - PROCESS | 1661 | RSS: Main: 326942720 18:44:35 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 18:44:37 INFO - PROCESS | 1661 | RSS: Main: 370151424 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | __start_tp_report 18:44:37 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 18:44:37 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 18:44:37 INFO - PROCESS | 1661 | |i|pagename|runs| 18:44:37 INFO - PROCESS | 1661 | |0;ai-astar;101;113;113;111;112;113;111;111;111;111 18:44:37 INFO - PROCESS | 1661 | |1;audio-beat-detection;164;139;136;164;110;128;143;106;159;107 18:44:37 INFO - PROCESS | 1661 | |2;audio-dft;184;186;184;184;186;182;182;181;180;183 18:44:37 INFO - PROCESS | 1661 | |3;audio-fft;81;75;58;109;62;80;87;64;87;56 18:44:37 INFO - PROCESS | 1661 | |4;audio-oscillator;84;90;92;70;70;68;96;70;68;100 18:44:37 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;89;86;89;86;89;87;89;86;89;90 18:44:37 INFO - PROCESS | 1661 | |6;imaging-darkroom;95;95;95;94;94;94;93;94;93;93 18:44:37 INFO - PROCESS | 1661 | |7;imaging-desaturate;85;84;85;84;85;84;84;85;84;85 18:44:37 INFO - PROCESS | 1661 | |8;json-parse-financial;55;54;54;56;54;56;56;54;55;55 18:44:37 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;65;71;80;72;74;72;71;73;72;72 18:44:37 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;85;94;105;101;103;85;85;86;83;78 18:44:37 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;139;137;139;136;135;136;138;141;176;129 18:44:37 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;164;164;163;163;163;164;167;165;218;220 18:44:37 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;60;64;88;65;60;72;83;80;65;69 18:44:37 INFO - PROCESS | 1661 | __end_tp_report 18:44:37 INFO - PROCESS | 1661 | __start_cc_report 18:44:37 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2196 18:44:37 INFO - PROCESS | 1661 | __end_cc_report 18:44:37 INFO - PROCESS | 1661 | __startTimestamp1468374277614__endTimestamp 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | ------- Summary: start ------- 18:44:37 INFO - PROCESS | 1661 | Number of tests: 14 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.50 stddev:3.53 (3.2%) stddev-sans-first:0.97 18:44:37 INFO - PROCESS | 1661 | Values: 101.0 113.0 113.0 111.0 112.0 113.0 111.0 111.0 111.0 111.0 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:141.00 stddev:22.69 (16.1%) stddev-sans-first:21.62 18:44:37 INFO - PROCESS | 1661 | Values: 164.0 139.0 136.0 164.0 110.0 128.0 143.0 106.0 159.0 107.0 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:1.99 (1.1%) stddev-sans-first:2.09 18:44:37 INFO - PROCESS | 1661 | Values: 184.0 186.0 184.0 184.0 186.0 182.0 182.0 181.0 180.0 183.0 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:75.90 Median:77.50 stddev:16.45 (21.2%) stddev-sans-first:17.35 18:44:37 INFO - PROCESS | 1661 | Values: 81.0 75.0 58.0 109.0 62.0 80.0 87.0 64.0 87.0 56.0 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:80.80 Median:77.00 stddev:12.90 (16.8%) stddev-sans-first:13.63 18:44:37 INFO - PROCESS | 1661 | Values: 84.0 90.0 92.0 70.0 70.0 68.0 96.0 70.0 68.0 100.0 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:89.00 stddev:1.56 (1.8%) stddev-sans-first:1.62 18:44:37 INFO - PROCESS | 1661 | Values: 89.0 86.0 89.0 86.0 89.0 87.0 89.0 86.0 89.0 90.0 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.78 18:44:37 INFO - PROCESS | 1661 | Values: 95.0 95.0 95.0 94.0 94.0 94.0 93.0 94.0 93.0 93.0 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 18:44:37 INFO - PROCESS | 1661 | Values: 85.0 84.0 85.0 84.0 85.0 84.0 84.0 85.0 84.0 85.0 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:0.88 (1.6%) stddev-sans-first:0.93 18:44:37 INFO - PROCESS | 1661 | Values: 55.0 54.0 54.0 56.0 54.0 56.0 56.0 54.0 55.0 55.0 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:72.00 stddev:3.65 (5.1%) stddev-sans-first:2.78 18:44:37 INFO - PROCESS | 1661 | Values: 65.0 71.0 80.0 72.0 74.0 72.0 71.0 73.0 72.0 72.0 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:90.50 Median:85.00 stddev:9.50 (11.2%) stddev-sans-first:9.87 18:44:37 INFO - PROCESS | 1661 | Values: 85.0 94.0 105.0 101.0 103.0 85.0 85.0 86.0 83.0 78.0 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:140.60 Median:138.50 stddev:12.85 (9.3%) stddev-sans-first:13.62 18:44:37 INFO - PROCESS | 1661 | Values: 139.0 137.0 139.0 136.0 135.0 136.0 138.0 141.0 176.0 129.0 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:164.50 stddev:23.17 (14.1%) stddev-sans-first:24.23 18:44:37 INFO - PROCESS | 1661 | Values: 164.0 164.0 163.0 163.0 163.0 164.0 167.0 165.0 218.0 220.0 18:44:37 INFO - PROCESS | 1661 | 18:44:37 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:70.50 stddev:9.89 (14.0%) stddev-sans-first:9.72 18:44:37 INFO - PROCESS | 1661 | Values: 60.0 64.0 88.0 65.0 60.0 72.0 83.0 80.0 65.0 69.0 18:44:37 INFO - PROCESS | 1661 | -------- Summary: end -------- 18:44:37 INFO - PROCESS | 1661 | 18:44:38 INFO - TEST-INFO | 1661: exit 0 18:44:38 INFO - TEST-OK | kraken | took 59877ms 18:44:38 INFO - SUITE-END | took 1176s 18:44:38 INFO - Completed test suite (00:19:36) 18:44:38 INFO - javascript benchmark 18:44:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2442.5114977004596, 3406.352792969334, 3409.1988382730055, 3417.8550185687795, 3453.7755045922013, 5668.43315668434, 5672.432756724325, 5736.139579063142, 5774.769009239631, 5785.884282314357, 5701.0878259478495, 5788.27646544182, 5812.186293918855, 5824.417558244173, 5827.213326200962, 5213.895722085575, 5235.267062611238, 5300.575953923677, 5311.681299122046, 5328.866778330534, 5337.653052551591, 5383.81156659517, 5400.405955344909, 5418.485243901823, 5435.945640543583, 5220.190870415073, 5221.921671174935, 5253.474652534755, 5310.787568497255, 5312.521873031426, 5217.0, 5263.657862238981, 5277.445868183862, 5280.498352656491, 5292.629515933886, 5198.064348417283, 5220.060389129955, 5261.684298942076, 5295.417504074549, 5344.278522399465, 5804.303483581955, 5839.503642190419, 5878.147668588031, 5882.000059989812, 5889.234399528077, 6107.5833010365195, 6213.782517611907, 6214.9689251553455, 6276.96861515694, 6286.842828929268, 6253.87492250157, 6272.65500397478, 6308.589941653778, 6342.39747224013, 6349.682515874188, 5886.793962211302, 5904.084866845646, 5911.9113213301835, 5960.076188190794, 5980.102984552308, 6210.347913469111, 6237.719302631393, 6241.656708881055, 6258.968705156445, 6268.122462855204, 6257.374262573797, 6258.155149054819, 6264.812055638338, 6275.152854364694, 6286.0, 6298.937010629927, 6315.273743519462, 6334.461570766536, 6337.873242535124, 6339.0, 6100.3086879273915, 6138.07928810673, 6162.352952939877, 6208.192934918432, 6272.780452684134, 6296.307406185316, 6318.462930650946, 6336.746530138835, 6342.809715708536, 6343.238811342669, 4934.777934992934, 4971.90056198874, 4978.900421991612, 4981.252812078144, 5039.622028347815, 4391.4730232371685, 4412.02935354228, 4419.712718673276, 4494.505604383515, 4508.887277818081, 5712.971665100322, 5744.253247077937, 5760.135979603093, 5760.53915686744, 5823.737931793079, 6231.40801623845, 6248.4376406123665, 6285.088662143963, 6289.465395441439, 6295.622262664255, 5800.4489573490455, 5840.591158618856, 5843.123531470228, 5843.2403787507355, 5899.056151015817, 5345.305110335631, 5407.324084489439, 5411.675299482044, 5487.094629386106, 5489.478499542537, 6277.843053923689, 6396.42432181106, 6397.936020639921, 6402.871942561216, 6404.967975160188, 6190.442860142609, 6275.905861411991, 6296.275928268308, 6300.054991751274, 6307.085472606354, 6172.660503672341, 6272.341404152681, 6288.937110628835, 6388.105665206782, 6394.424501794861, 5111.412187598474, 6234.127222188807, 6254.749810007549, 6267.561270711189, 6267.655278959519, 6257.234119710002, 6269.811905642838, 6295.748170073329, 6299.30707622152, 6304.432601065926, 6034.788782392595, 6124.785632503019, 6128.172696686069, 6163.044728067157, 6190.7523699051535, 6014.097885317237, 6159.784407545714, 6170.376208386154, 6219.626822390671, 6230.127782110418, 6085.939140608537, 6086.969565152146, 6109.663968481598, 6110.036353870931, 6116.305337585956, 6048.879022419439, 6119.106526814713, 6151.384861513813, 6169.907451388322, 6262.09199666055, 6076.179715738319, 6087.714492067947, 6099.309783606499, 6108.08378743192, 6130.356312587114], "value": 5757.965519590566, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.6597880451891, 504.8334049763578, 518.9688618682878, 522.1045906270747, 555.0924238869452, 30616.69383306166, 42200.577994220046, 43066.0, 43199.78400107999, 45693.40073097434, 44668.3299750504, 45115.54884451155, 45123.09753804922, 45168.77415612918, 45196.3220551692, 8812.339074569401, 9772.169365603932, 9950.85073723891, 9956.85064724026, 9962.352447090916, 8915.019347871728, 11939.701507462296, 11983.760324793499, 12127.93936030323, 12384.504619815196, 12138.878611213908, 12201.267923924594, 12206.389680515984, 12209.0, 12242.877571224262, 8749.256313213384, 11837.230580012272, 12025.03799696031, 12109.273443593413, 12248.632541023739, 11931.224470409396, 11981.0, 12027.0, 12031.819522707166, 12038.69903252417, 42111.157776844295, 42475.93810154771, 42513.57486425127, 42516.0, 42631.147377052286, 3450.4824276358745, 3522.3483655523555, 3557.1285035166293, 3577.3560759063357, 3649.6167902370134, 27006.45987080267, 32223.51664725031, 32650.183745406317, 33408.33183336344, 33410.49884251738, 3241.8865339712997, 3410.3520331137006, 3416.026432466735, 3451.5340429042008, 3454.5681789776277, 491977.0, 492784.0, 495080.0, 495691.0, 503301.48349258385, 53322.46677533175, 63830.0, 64002.35997640057, 64247.357526425076, 64324.35675643184, 66220.33779662238, 66918.0, 66949.6652516744, 66974.33025669778, 67110.0, 16754.07852568096, 17023.89344692591, 17060.744088838677, 17087.0, 17094.0, 11782.764344713058, 12110.939445302893, 12114.636560903187, 12348.320842353756, 13043.869561304455, 16064.75902861458, 17679.939203647566, 18167.72748408775, 18177.545561361072, 18642.44072677822, 5284.762185701653, 5500.257465242221, 6206.813795586139, 6269.937300627026, 6290.24517057956, 912.9178373946244, 966.7293157915794, 967.2938754708961, 1054.699410667956, 1080.6271836216492, 29972.250693732833, 30853.920112795946, 31004.68995310063, 31099.378012440076, 31664.891728789382, 1065.547142464465, 1094.9233553651327, 1120.3501968858207, 1122.9494672739747, 1184.5025089462447, 15909.363625454853, 16244.0, 16517.0, 16555.751663725056, 16585.0, 638115.0, 638674.0, 644457.0, 648643.0, 651972.0, 62351.6882415585, 62414.0637890432, 62473.75052499015, 62701.05948410778, 62872.68563657244, 1291.3026965438557, 1374.2510331869155, 1422.73679369317, 1438.4821464273064, 1439.0358459831727, 32424.351512969137, 32557.0, 32681.509777353833, 33050.66949330476, 33367.961150571435, 52472.0, 53038.46961530489, 53054.204186936455, 53100.0, 53200.46799531955, 430261.0, 446341.0, 448036.0, 448360.0, 448865.0, 33697.66302336945, 36031.45952810658, 36786.448203277505, 42150.78924605357, 44839.291874352726, 37441.625583743815, 48049.279260810414, 48489.27266090941, 48716.75641621769, 49759.25361119658, 40483.79758101191, 40789.0, 41056.384154237116, 41747.37378939258, 41774.0, 40138.99652508594, 40508.59491405048, 40689.796551017054, 41604.0, 41777.16445671009, 35292.47061294134, 37435.06412339713, 38676.226475470896, 39071.41392879166, 39237.6266830671, 26411.471770564865, 34694.479582805776, 35064.29871402507, 35845.0, 35853.0, 24682.259532214062, 25723.871380642977, 26150.73849261483, 26303.322272418616, 26470.07354742575], "value": 20870.610678431352, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.64461291063617, 347.7166109620659, 372.8694956765132, 386.85106234099874, 386.8897364251187, 15123.0, 15960.76058859118, 21280.787192128075, 21283.0, 21285.25501607444, 20544.17823287067, 20746.89626551865, 21374.786252137474, 21508.354749357506, 21519.784802151975, 4995.750212489379, 5318.547923426513, 5445.29084838864, 5544.833654990337, 5549.556035517149, 4641.930371044437, 5453.509184173424, 5473.698946557938, 5475.890482190353, 5480.698561579112, 5256.710880901549, 5430.968116057937, 5461.1535212042, 5470.753816078286, 5478.315210598676, 5431.891362172754, 5440.619156659035, 5459.781608735646, 5463.9180412293845, 5495.780168793243, 5080.390353157608, 5297.258383826267, 5339.519443250126, 5343.465653434664, 5344.278522399465, 23895.805209739443, 25777.35556611081, 26194.607080893802, 26717.797699103394, 26802.79387427551, 2190.255313193522, 2208.2160832904246, 2249.2352600116037, 2280.931572052841, 2350.5651454480803, 10018.849717254247, 10801.729956751065, 10816.51325690338, 12864.549740759028, 15381.154036527987, 1880.5204672808484, 1884.3498992847447, 1894.3180455036177, 1894.336982056283, 1930.1121484117323, 108622.0, 127230.36384818202, 128005.0, 129375.35312323472, 131583.0, 11613.825792613117, 11827.822582661267, 11840.0, 11862.88137118635, 12038.939805301092, 14892.404303827941, 14987.775183372258, 15011.699766004618, 15017.849821501864, 15018.624534386729, 8628.309735221168, 8817.985931617817, 8832.02847686754, 8896.42173258749, 8915.910840891507, 8737.126287371211, 8746.65013399457, 8758.956205218934, 8865.911340886509, 8890.377673562789, 4560.95439045612, 4642.605378542794, 4682.204025315705, 4702.694324868872, 4784.0, 1885.6982882738696, 1944.931927382535, 1953.5213872601303, 1953.8534609904314, 1956.5597740508556, 907.0158877617858, 914.5564401265381, 955.1642312976146, 1020.9540570674337, 1065.8507808906759, 21589.352319430443, 22450.550988980456, 22527.77472225257, 22530.0, 22613.547729045655, 904.5251243097426, 918.2286879021653, 924.4314746431031, 964.9179819715263, 1017.8473229015707, 11327.0, 11374.829377559343, 11714.180007399571, 11715.238509496856, 11780.99861511764, 214138.0, 214454.9277253635, 214972.0, 291125.0, 601375.0, 131605.0, 132041.0, 139625.0, 140507.29746351202, 143168.5683143176, 985.3250523391499, 996.1582462819051, 1054.4253381906879, 1081.291753901196, 1081.551156270139, 15699.607509811889, 19750.901245493682, 19845.60308793845, 19892.20970112697, 19901.303454379027, 56971.14543281937, 57574.0, 57972.42027579839, 58071.709641451525, 58262.4173758257, 81277.18722812696, 86820.5658971701, 86953.0, 87440.03877080907, 89817.1618844773, 8144.226298501633, 8224.38317126206, 8282.130376310643, 8564.828703426021, 8574.442661227, 11234.213605047567, 13119.409626567018, 13150.802737958747, 13214.339283036003, 13330.0, 11020.889791101987, 11329.603463878724, 11429.885701142883, 11440.199186056896, 11451.541938322707, 11402.885971140515, 11446.94276528612, 11457.59898403552, 11458.130294624918, 11630.825537616773, 11517.366544839779, 11550.018248448976, 11580.768384632092, 11646.475908584303, 11737.002354799937, 7033.261507541839, 8223.259906608433, 8258.42191046621, 8427.00826265165, 8462.576871156542, 10667.039966403061, 10907.727306817393, 11197.888021119685, 11447.713807154554, 11509.309441433541], "value": 9552.823131362358, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.2237147197563, 1526.191118707085, 1568.309943624805, 1723.3279021181734, 1878.2956391353243, 7715.382769378451, 8194.754157375273, 8453.07016228216, 8736.301095912328, 8739.257163141141, 8520.531370774605, 8632.136786321365, 8697.0, 8698.434601750881, 8727.345449091328, 2358.0332063853825, 2359.1035406545575, 2359.2096647623066, 2361.185391040097, 2366.3729111785347, 2320.4430936575186, 2354.8469349492316, 2361.8110551155864, 2365.8343915925893, 2366.077229880348, 1956.2761778142108, 1993.372087792348, 2322.756110608387, 2353.576356255873, 2369.5616310982523, 2299.5056062946546, 2324.6280595104704, 2325.802306803915, 2330.487292795582, 2331.300609817048, 2276.1805749930013, 2281.634938409863, 2281.6577513372963, 2284.862908225495, 2287.0965968442374, 8733.475991440533, 8736.388452808305, 8794.736157915273, 8814.294856411536, 8824.602892869772, 5149.819756308549, 5167.6641018333685, 5306.6550674206055, 5308.203769434615, 5354.892902141935, 3545.5213546171194, 3620.8913732588067, 3650.653187947141, 3683.1712864605515, 3686.083236716919, 1072.7371793910463, 1094.4691824465128, 1099.2195541165781, 1103.823388257883, 1107.413071072333, 11547.422628868522, 11646.359450230233, 11646.70883227926, 11666.358350290731, 11684.649460516199, 4400.559944005574, 4429.800658970354, 4610.700304480265, 4643.141018911445, 4643.466001409881, 9084.40951338161, 9087.36388452802, 9087.545622718837, 9102.180803727564, 9104.13510716481, 4641.512641172696, 4910.189818679878, 5228.398734145546, 5231.3199284092825, 5238.214267859851, 4186.6859985501205, 4359.38968544404, 4362.1275744851155, 4511.796969136331, 4907.9263811042865, 3750.343689854319, 3783.7729736215915, 3784.7918364489665, 3826.559945606235, 3837.4052021936645, 1030.8711960403346, 1093.606301731376, 1140.5836869542557, 1143.0798207443088, 1154.6593754842343, 873.5151990645126, 926.8934072581604, 933.6452148183782, 954.7279025477706, 1008.1178968402647, 5919.467247947706, 5928.703564821742, 5954.523638108941, 6146.323904370515, 6171.074338849208, 940.3934462271797, 940.7742141886035, 956.9808603828023, 965.4448692002127, 985.5318723606373, 3824.7513911595656, 4002.8799136025964, 4054.8175332110127, 4066.0, 4091.324931386288, 9992.65025724096, 10630.25588208818, 10633.840492392621, 10642.201834862417, 10674.839877401846, 10369.222308326905, 10487.580496780196, 10557.313774604627, 10612.57549698019, 10641.787164256672, 8079.5960201989665, 8193.221643943934, 8228.21831925966, 8243.670253189925, 8249.79375515605, 5735.655860648208, 5867.413258674099, 5875.41245875409, 5908.5568582357355, 5973.104034394875, 9610.807783844144, 9980.451075190935, 9986.650467233612, 9991.300608957303, 10020.298579099393, 9753.219742420732, 10075.949620251851, 10121.848172277569, 10135.442550659785, 10169.03394177552, 7436.516626419266, 7484.064491938508, 7572.280633339824, 7581.848363032819, 7583.848323033619, 8010.479318844256, 8137.430379873352, 8161.959190204011, 8209.917900820916, 8237.764335349746, 7351.66917488725, 7445.888311675221, 7457.813554661177, 7488.812779680334, 7572.280633339824, 7291.927080729125, 7294.854102917806, 7381.372583330262, 7432.665530051053, 7439.032925719622, 6682.398584127452, 6984.6158461283085, 7003.894941575983, 7010.018597396471, 7067.469939754436, 4988.900221995467, 5001.074801161797, 5017.347744793154, 5058.317127187783, 5161.406438259498, 4998.400191976986, 5077.111505486599, 5087.4403815581, 5108.56577190943, 5255.3168088148295], "value": 4539.0153301725395, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.2019309849818, 309.17912941141276, 312.61548295596424, 317.4333814141756, 321.97263232625227, 4366.323219900916, 5378.731063446827, 5402.945970540299, 5425.647332923367, 5465.781368745245, 4389.429374181352, 5316.840494785153, 5346.198070289459, 5357.4374690657405, 5360.65983504124, 3603.747737658365, 3886.7862267575274, 3970.325044742386, 4042.4542686737354, 4064.817083231247, 3473.99254216277, 3615.9819200903958, 3864.4589757433982, 4039.5560685785117, 4050.392441133824, 3591.6049234584175, 3964.167524819791, 4059.451973983504, 4059.5128584569893, 4078.8171430285183, 4076.2458945095104, 4078.9592104079024, 4087.386891966199, 4091.754494730326, 4104.250152397901, 3339.632640409553, 3391.253924136711, 3470.7570470067108, 3537.7877327360184, 3566.554180727409, 4292.5063617683745, 5120.231965205249, 5150.48495150482, 5154.587632989352, 5178.637495375326, 4645.279981602857, 4647.837325693618, 4655.3947986761505, 4655.720656760605, 4718.0, 4492.7528985905765, 5173.861750414902, 5428.592855535811, 5519.834404967817, 5610.354809196954, 3106.7359274461696, 3559.7330200235087, 3579.8365531202203, 3866.709996750255, 3872.419137129425, 4445.021965225476, 4549.408576885017, 5004.674696144775, 5549.500544950974, 5648.378678345379, 3291.6997785874446, 3320.4189266878197, 3507.2985402919007, 3929.410588411704, 3929.6070392960473, 4037.9394309085387, 4038.757674539537, 4047.7368971017377, 4049.1091959768805, 4054.3985125875115, 4295.3771703102875, 4463.486699029653, 4521.299198624219, 4630.351750754897, 4706.317583950308, 5221.0, 5562.888742225214, 5624.353199382123, 5672.290963629546, 5681.091025435993, 1139.168407062832, 1141.1669481278714, 1321.6167311479755, 1333.8999575031912, 1350.702845374016, 696.5611664651339, 760.2017881224692, 796.1441450440799, 796.8644681329083, 844.9704260350858, 3891.4357418174204, 3936.6418585587926, 4340.088581397878, 4377.321515165155, 4392.758398288125, 4316.6546676265825, 4532.484471118561, 4804.903901921942, 4817.759112044384, 4889.926651100236, 3849.480320156743, 4136.524299705512, 4155.729877557949, 4443.422355093817, 4534.120833270831, 2456.533258680845, 2559.756823101802, 2753.765929895941, 2797.888084476598, 3065.513226085347, 5440.836774896759, 5500.50495455403, 5617.8708649142845, 6028.895119013711, 6126.172966649535, 5136.382039657496, 5162.638615296968, 5301.628885977944, 5896.351401345849, 5953.7023148843255, 5220.321358223407, 5447.237386765856, 5603.805830676475, 5987.311459182076, 6024.216851809237, 5472.808451704331, 5833.912491312719, 5838.503727183067, 5869.4717475427415, 5919.6152250103605, 4580.473245576668, 5753.89289783529, 5760.8271751847515, 5791.797287094932, 5816.82549523515, 5678.630888992202, 5844.181814546052, 5929.733162007807, 5986.16193732869, 6039.78860739872, 3865.3622152345674, 3871.3612253977776, 3898.629630185128, 3910.9217815644097, 3929.5088113985753, 5664.44227837348, 5772.5959182856795, 5846.386129456346, 5862.179294898803, 5882.735276912464, 5584.860378490408, 5634.521065709297, 5668.234788303526, 5671.291088613923, 5751.9137212941005, 5624.831255062355, 5638.0, 5680.68756218412, 5688.5733569981585, 5701.857453563694, 5121.308623335951, 5508.641938274, 5511.0, 5526.58550608714, 5566.582506312124, 4507.729536227836, 5307.6284660073425, 5631.943680563306, 5637.689927053887, 5659.462351076641, 4772.475027746876, 5537.342075803717, 5549.0, 5565.860853478695, 5570.832875013756], "value": 3998.6855321375056, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.3439463720191, 703.4899697719154, 708.9787306380811, 734.5592644413352, 744.7170075371358, 15116.697666046672, 15956.920215398906, 16505.66988660226, 16513.917430412846, 16749.99500029999, 12484.875151248485, 15311.310991005405, 15318.846811531881, 15344.0, 15458.458953936615, 717.7918403662932, 740.7111226621622, 857.7212405968054, 880.7533890510667, 885.0706757904208, 726.3789460011693, 898.7258886039788, 898.8202359528087, 903.6204793986542, 907.6823111910811, 933.8319102561536, 941.8540126280403, 947.8530827721714, 948.5257371314343, 949.1125797379473, 739.0835364148471, 919.2829592917535, 939.0797018921461, 940.5955439161158, 951.1962391779005, 702.6556987076295, 703.4829400390709, 703.6552089476171, 704.246456291768, 704.9471289653296, 15257.542273731806, 15331.0, 15332.693346133126, 15336.15651139198, 15352.769708454382, 13958.720825583432, 14069.437222511087, 14071.085379450304, 14151.29243537819, 14340.56978290653, 12080.516779328816, 12116.575919842851, 12783.936080319632, 12832.743345133138, 12981.221126732426, 4528.977355113237, 4538.546145385468, 4568.977155114237, 4639.953600463986, 4717.43390793107, 87656.12343876544, 102899.485502572, 116159.08382904857, 117509.41245293805, 117896.0, 10832.78334433307, 10980.560777568808, 11185.552577896955, 11412.657620271406, 11621.941890290494, 22375.552488950456, 22400.43998900008, 22419.88790056072, 22490.887545562167, 22495.887520562294, 6783.660816959133, 7997.960010199912, 8014.83970320602, 8124.83750325002, 8130.83738325242, 4352.934705979413, 4484.394606728115, 4578.0, 4578.519255478193, 4580.564846339659, 3441.328940856509, 3546.8935931922083, 3571.3035957988463, 3573.3210689968823, 3573.8391772370305, 583.0438081546267, 584.5615788158881, 591.2638764737928, 603.9245094363205, 622.3403192615841, 603.9094135879566, 610.7465401858268, 625.3070704363721, 640.1837656987378, 681.7136802542943, 8592.95703521491, 8870.201681848665, 8971.309093979236, 9066.95466522663, 9293.814123717488, 604.0817956705782, 618.264265524025, 625.4808508937571, 641.1408712325506, 646.4763541531374, 4319.524852266248, 4482.932756008662, 4567.543245675406, 5103.209002604603, 5305.681659100389, 143639.2818035924, 154937.2253138727, 159809.40190598177, 160026.39973600348, 161350.0, 134962.0, 136451.0, 138067.0, 139851.0, 141829.0, 116414.0, 116476.0, 120000.0, 121047.39476302562, 124581.0, 9550.713478595653, 9724.9027509724, 9740.318177727491, 9764.316497845082, 9911.65309214174, 56807.147892782465, 61007.69496152491, 61527.692361537906, 62088.0, 64091.35908640854, 52955.470445295054, 70175.94736079065, 91415.0, 96424.51787741017, 98494.0, 13285.468581257028, 13698.931505342409, 13791.724165516433, 13976.650583735487, 14135.858641413453, 14524.85475145235, 15112.773308400603, 15114.54656360311, 15155.848441515885, 15620.765688514453, 13754.243516606683, 13905.930470347583, 13977.371018304404, 14258.857411425754, 14599.0, 13929.721405571629, 13964.650883727989, 14170.645733856736, 14214.0, 14224.928875355557, 13682.589522314347, 13729.725405491634, 13793.655158620713, 13948.302584870513, 13972.161670299814, 11441.370724610226, 11558.653240402802, 11687.474063667325, 11696.941515292368, 12035.638930832089, 11027.397260274036, 11250.718732031764, 11372.20394572372, 11477.713057173638, 11544.0], "value": 7980.668155327188, "unit": "score"}], "name": "dromaeo_css", "value": 7415.672105776159, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 111.0, 112.0, 113.0, 111.0, 111.0, 111.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 139.0, 136.0, 164.0, 110.0, 128.0, 143.0, 106.0, 159.0, 107.0], "value": 135.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 184.0, 184.0, 186.0, 182.0, 182.0, 181.0, 180.0, 183.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 75.0, 58.0, 109.0, 62.0, 80.0, 87.0, 64.0, 87.0, 56.0], "value": 75.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 90.0, 92.0, 70.0, 70.0, 68.0, 96.0, 70.0, 68.0, 100.0], "value": 80.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 89.0, 87.0, 89.0, 86.0, 89.0, 90.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 93.0, 94.0, 93.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 84.0, 85.0, 84.0, 84.0, 85.0, 84.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 56.0, 54.0, 56.0, 56.0, 54.0, 55.0, 55.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 80.0, 72.0, 74.0, 72.0, 71.0, 73.0, 72.0, 72.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 94.0, 105.0, 101.0, 103.0, 85.0, 85.0, 86.0, 83.0, 78.0], "value": 90.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 137.0, 139.0, 136.0, 135.0, 136.0, 138.0, 141.0, 176.0, 129.0], "value": 140.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 163.0, 163.0, 163.0, 164.0, 167.0, 165.0, 218.0, 220.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 88.0, 65.0, 60.0, 72.0, 83.0, 80.0, 65.0, 69.0], "value": 70.6, "unit": "score"}], "name": "kraken", "value": 1456.5999999999997, "alertThreshold": 2.0}]} 18:44:38 INFO - Return code: 0 18:44:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:44:38 INFO - # TBPL SUCCESS # 18:44:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:44:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:44:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:44:38 INFO - 2016-07-12 18:44:38.932 screenresolution[1665:9821] starting screenresolution argv=screenresolution get 18:44:38 INFO - 2016-07-12 18:44:38.935 screenresolution[1665:9821] Display 0: 1600x1200x32@60 18:44:38 INFO - 2016-07-12 18:44:38.942 screenresolution[1666:9824] starting screenresolution argv=screenresolution list 18:44:38 INFO - Available Modes on Display 0 18:44:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:44:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:44:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:44:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:44:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:44:39 INFO - Intel Iris: 18:44:39 INFO - Chipset Model: Intel Iris 18:44:39 INFO - Type: GPU 18:44:39 INFO - Bus: Built-In 18:44:39 INFO - VRAM (Dynamic, Max): 1536 MB 18:44:39 INFO - Vendor: Intel (0x8086) 18:44:39 INFO - Device ID: 0x0a2e 18:44:39 INFO - Revision ID: 0x0009 18:44:39 INFO - Displays: 18:44:39 INFO - Display: 18:44:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:44:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:44:39 INFO - Display Serial Number: 1600x1200 60 18:44:39 INFO - Main Display: Yes 18:44:39 INFO - Mirror: Off 18:44:39 INFO - Online: Yes 18:44:39 INFO - Rotation: Supported 18:44:39 INFO - Return code: 0 18:44:39 INFO - Running post-action listener: _resource_record_post_action 18:44:39 INFO - ##### 18:44:39 INFO - ##### Finished run-tests step (success) 18:44:39 INFO - ##### 18:44:39 INFO - Running post-run listener: _resource_record_post_run 18:44:39 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37873152; Write bytes: 702559232; Read time: 392; Write time: 1848 18:44:39 INFO - TinderboxPrint: CPU usage
53.4% 18:44:39 INFO - TinderboxPrint: I/O read bytes / time
37,873,152 / 392 18:44:39 INFO - TinderboxPrint: I/O write bytes / time
702,559,232 / 1,848 18:44:39 INFO - TinderboxPrint: CPU idle
2,259.8 (46.6%) 18:44:39 INFO - TinderboxPrint: CPU system
963.8 (19.9%) 18:44:39 INFO - TinderboxPrint: CPU user
1,622.0 (33.5%) 18:44:39 INFO - TinderboxPrint: Swap in / out
444,358,656 / 0 18:44:39 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400214528; Write bytes: 394114560; Read time: 29425; Write time: 704 18:44:39 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 35808768; Write bytes: 292814336; Read time: 369; Write time: 1106 18:44:39 INFO - Running post-run listener: _upload_blobber_files 18:44:39 INFO - Blob upload gear active. 18:44:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:44:39 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 18:44:39 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'] 18:44:39 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 18:44:40 INFO - (blobuploader) - INFO - Open directory for files ... 18:44:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:44:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:44:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:41 INFO - (blobuploader) - INFO - Done attempting. 18:44:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:44:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:44:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:41 INFO - (blobuploader) - INFO - Done attempting. 18:44:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:44:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:44:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:44 INFO - (blobuploader) - INFO - Done attempting. 18:44:44 INFO - (blobuploader) - INFO - Iteration through files over. 18:44:44 INFO - Return code: 0 18:44:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:44:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:44:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5f9af682998a145953d5143393d396d790f1bfcb7e3953fa02743da7ed314e83f5e90ae8ab96f42ee68769982b0516c760040a49e28fe8f653f92d02a2b5fe2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/712d901051bf61cedabbc2b4afc218d8b2092aee47b8c5be652e5e161ff6a9ccb829a47891128b895207275ae819bb8aa4a7d45ba007582b913ba1c2790fc859", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8df5dfbff0c5f969f8d37951ba37dace993ed9479564261da58ee3cdb6fe2e1f5b41f6ffd84dc83fb455078f5cd0b7a7d2ed3423b09aab3ea8378ab74ef82d0f"} 18:44:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:44:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:44:44 INFO - Contents: 18:44:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5f9af682998a145953d5143393d396d790f1bfcb7e3953fa02743da7ed314e83f5e90ae8ab96f42ee68769982b0516c760040a49e28fe8f653f92d02a2b5fe2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/712d901051bf61cedabbc2b4afc218d8b2092aee47b8c5be652e5e161ff6a9ccb829a47891128b895207275ae819bb8aa4a7d45ba007582b913ba1c2790fc859", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8df5dfbff0c5f969f8d37951ba37dace993ed9479564261da58ee3cdb6fe2e1f5b41f6ffd84dc83fb455078f5cd0b7a7d2ed3423b09aab3ea8378ab74ef82d0f"} 18:44:44 INFO - Running post-run listener: copy_logs_to_upload_dir 18:44:44 INFO - Copying logs to upload dir... 18:44:44 INFO - mkdir: /builds/slave/test/build/upload/logs 18:44:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.800087 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-12 18:44:44.680048) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 18:44:44.683168) ========= 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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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/a5f9af682998a145953d5143393d396d790f1bfcb7e3953fa02743da7ed314e83f5e90ae8ab96f42ee68769982b0516c760040a49e28fe8f653f92d02a2b5fe2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/712d901051bf61cedabbc2b4afc218d8b2092aee47b8c5be652e5e161ff6a9ccb829a47891128b895207275ae819bb8aa4a7d45ba007582b913ba1c2790fc859", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8df5dfbff0c5f969f8d37951ba37dace993ed9479564261da58ee3cdb6fe2e1f5b41f6ffd84dc83fb455078f5cd0b7a7d2ed3423b09aab3ea8378ab74ef82d0f"} build_url:https://queue.taskcluster.net/v1/task/L76Lwp3uS8CsNx3BJjntPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011056 build_url: 'https://queue.taskcluster.net/v1/task/L76Lwp3uS8CsNx3BJjntPA/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/a5f9af682998a145953d5143393d396d790f1bfcb7e3953fa02743da7ed314e83f5e90ae8ab96f42ee68769982b0516c760040a49e28fe8f653f92d02a2b5fe2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/712d901051bf61cedabbc2b4afc218d8b2092aee47b8c5be652e5e161ff6a9ccb829a47891128b895207275ae819bb8aa4a7d45ba007582b913ba1c2790fc859", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8df5dfbff0c5f969f8d37951ba37dace993ed9479564261da58ee3cdb6fe2e1f5b41f6ffd84dc83fb455078f5cd0b7a7d2ed3423b09aab3ea8378ab74ef82d0f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 18:44:44.716356) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:44:44.716742) ========= 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.axVGSRR1kZ/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.nvFNHbsbBz/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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.005175 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:44:44.766109) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 18:44:44.766450) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 18:44:44.766798) ========= ========= Total master_lag: 0.18 =========