builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0191 starttime: 1475158325.12 results: success (0) buildid: 20160929050519 builduid: 9836f6b5518842d0b79032d5afef57ed revision: 0f2c7b2a783bbc5954e93216e7cb3b396a78003e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 07:12:05.116235) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 07:12:05.116780) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 07:12:05.117071) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 07:12:05.141926) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 07:12:05.142392) ========= 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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-29 07:12:05-- 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% 25.1M=0s 2016-09-29 07:12:05 (25.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.139770 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 07:12:05.304826) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 07:12:05.305206) ========= 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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.021024 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 07:12:05.354178) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 07:12:05.354536) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0f2c7b2a783bbc5954e93216e7cb3b396a78003e --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 0f2c7b2a783bbc5954e93216e7cb3b396a78003e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CPkkUs9HVe/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.UO2y3WWrFK/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-29 07:12:05,429 truncating revision to first 12 chars 2016-09-29 07:12:05,430 Setting DEBUG logging. 2016-09-29 07:12:05,430 attempt 1/10 2016-09-29 07:12:05,430 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0f2c7b2a783b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 07:12:06,490 unpacking tar archive at: fx-team-0f2c7b2a783b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.326116 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 07:12:06.702498) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 07:12:06.702861) ========= script_repo_revision: 0f2c7b2a783bbc5954e93216e7cb3b396a78003e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 07:12:06.703273) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 07:12:06.703538) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 07:12:06.721909) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-29 07:12:06.722344) ========= /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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:12:06 INFO - MultiFileLogger online at 20160929 07:12:06 in /builds/slave/test 07:12:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 07:12:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:12:06 INFO - {'append_to_log': False, 07:12:06 INFO - 'base_work_dir': '/builds/slave/test', 07:12:06 INFO - 'blob_upload_branch': 'Fx-Team', 07:12:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:12:06 INFO - 'branch': 'Fx-Team', 07:12:06 INFO - 'buildbot_json_path': 'buildprops.json', 07:12:06 INFO - 'config_files': ('talos/mac_config.py',), 07:12:06 INFO - 'default_actions': ('clobber', 07:12:06 INFO - 'read-buildbot-config', 07:12:06 INFO - 'download-and-extract', 07:12:06 INFO - 'populate-webroot', 07:12:06 INFO - 'create-virtualenv', 07:12:06 INFO - 'install', 07:12:06 INFO - 'run-tests'), 07:12:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:12:06 INFO - 'download_minidump_stackwalk': True, 07:12:06 INFO - 'download_symbols': 'ondemand', 07:12:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:12:06 INFO - 'tooltool.py': '/tools/tooltool.py', 07:12:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:12:06 INFO - '/tools/misc-python/virtualenv.py')}, 07:12:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:12:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:12:06 INFO - 'installer_path': 'installer.exe', 07:12:06 INFO - 'log_level': 'info', 07:12:06 INFO - 'log_name': 'talos', 07:12:06 INFO - 'log_to_console': True, 07:12:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:12:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:12:06 INFO - 'opt_config_files': (), 07:12:06 INFO - 'pip_index': False, 07:12:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:12:06 INFO - 'cmd': ('bash', 07:12:06 INFO - '-c', 07:12:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:12:06 INFO - 'enabled': True, 07:12:06 INFO - 'halt_on_failure': False, 07:12:06 INFO - 'name': 'check_screen_resolution'},), 07:12:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:12:06 INFO - 'cmd': ('bash', 07:12:06 INFO - '-c', 07:12:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:12:06 INFO - 'enabled': True, 07:12:06 INFO - 'halt_on_failure': False, 07:12:06 INFO - 'name': 'check_screen_resolution'},), 07:12:06 INFO - 'run_cmd_checks_enabled': True, 07:12:06 INFO - 'sps_profile': False, 07:12:06 INFO - 'sps_profile_interval': 0, 07:12:06 INFO - 'suite': 'dromaeojs', 07:12:06 INFO - 'system_bits': '32', 07:12:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:12:06 INFO - 'title': 't-yosemite-r7-0191', 07:12:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:12:06 INFO - 'use_talos_json': True, 07:12:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:12:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:12:06 INFO - 'work_dir': 'build'} 07:12:06 INFO - [mozharness: 2016-09-29 14:12:06.919998Z] Running clobber step. 07:12:06 INFO - Running pre-action listener: _resource_record_pre_action 07:12:06 INFO - Running main action method: clobber 07:12:06 INFO - rmtree: /builds/slave/test/build 07:12:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:12:09 INFO - Running post-action listener: _resource_record_post_action 07:12:09 INFO - [mozharness: 2016-09-29 14:12:09.029044Z] Finished clobber step (success) 07:12:09 INFO - [mozharness: 2016-09-29 14:12:09.029168Z] Running read-buildbot-config step. 07:12:09 INFO - Running pre-action listener: _resource_record_pre_action 07:12:09 INFO - Running main action method: read_buildbot_config 07:12:09 INFO - Using buildbot properties: 07:12:09 INFO - { 07:12:09 INFO - "project": "", 07:12:09 INFO - "product": "firefox", 07:12:09 INFO - "script_repo_revision": "production", 07:12:09 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:12:09 INFO - "repository": "", 07:12:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:12:09 INFO - "buildid": "20160929050519", 07:12:09 INFO - "pgo_build": "False", 07:12:09 INFO - "basedir": "/builds/slave/test", 07:12:09 INFO - "buildnumber": 31, 07:12:09 INFO - "slavename": "t-yosemite-r7-0191", 07:12:09 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 07:12:09 INFO - "platform": "yosemite_r7", 07:12:09 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:12:09 INFO - "branch": "fx-team", 07:12:09 INFO - "repo_path": "integration/fx-team", 07:12:09 INFO - "revision": "0f2c7b2a783bbc5954e93216e7cb3b396a78003e", 07:12:09 INFO - "stage_platform": "macosx64", 07:12:09 INFO - "builduid": "9836f6b5518842d0b79032d5afef57ed", 07:12:09 INFO - "slavebuilddir": "test" 07:12:09 INFO - } 07:12:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 07:12:09 INFO - Running post-action listener: _resource_record_post_action 07:12:09 INFO - [mozharness: 2016-09-29 14:12:09.032595Z] Finished read-buildbot-config step (success) 07:12:09 INFO - [mozharness: 2016-09-29 14:12:09.032725Z] Running download-and-extract step. 07:12:09 INFO - Running pre-action listener: _resource_record_pre_action 07:12:09 INFO - Running main action method: download_and_extract 07:12:09 INFO - mkdir: /builds/slave/test/build/tests 07:12:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:09 INFO - trying https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 07:12:09 INFO - Downloading https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:12:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 07:12:10 INFO - Downloaded 1199 bytes. 07:12:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:12:10 INFO - Using the following test package requirements: 07:12:10 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:12:10 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:12:10 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 07:12:10 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:12:10 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 07:12:10 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:12:10 INFO - u'jsshell-mac.zip'], 07:12:10 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:12:10 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 07:12:10 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:12:10 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:12:10 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 07:12:10 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:12:10 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 07:12:10 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:12:10 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 07:12:10 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:12:10 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 07:12:10 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:12:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:12:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:12:10 INFO - Fetch https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:12:10 INFO - Http code: 200 07:12:10 INFO - Content-Encoding: None 07:12:10 INFO - Content-Type: application/zip 07:12:10 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 07:12:10 INFO - x-amz-cf-id: 7fh4QvdmRSyPWQMsZhMvHs3hfgQePqs0h-it2c7FHqpIzFnZUedH1g== 07:12:10 INFO - x-amz-version-id: WX9WEQCmFsaIdS2Cv8Xz9lhT96.mLXdi 07:12:10 INFO - x-cache: Hit from cloudfront 07:12:11 INFO - Expected file size: 16473272 07:12:11 INFO - Obtained file size: 16473272 07:12:17 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:12:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:12:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:12:17 INFO - Fetch https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:12:18 INFO - Http code: 200 07:12:18 INFO - Content-Encoding: None 07:12:18 INFO - Content-Type: application/zip 07:12:18 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 07:12:18 INFO - x-amz-cf-id: GugWGv42nndekgINEzc3NGpAWUabi4-TbpN0IEhvcOgpMBA88nRGsw== 07:12:18 INFO - x-amz-version-id: WX9WEQCmFsaIdS2Cv8Xz9lhT96.mLXdi 07:12:18 INFO - x-cache: Hit from cloudfront 07:12:18 INFO - Expected file size: 16473272 07:12:18 INFO - Obtained file size: 16473272 07:12:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 07:12:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:12:25 INFO - Fetch https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 07:12:25 INFO - Http code: 200 07:12:25 INFO - Content-Encoding: None 07:12:25 INFO - Content-Type: application/zip 07:12:25 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 07:12:25 INFO - x-amz-cf-id: Cp9iN4r6BofSQulvAElM2TtMmB0YrDTTjYfXIh32PmbFwK0XBp_nlQ== 07:12:25 INFO - x-amz-version-id: .GBpTI1C3U0OxstnvCNwh2eF25SDnqZ2 07:12:25 INFO - x-cache: Hit from cloudfront 07:12:25 INFO - Expected file size: 14414067 07:12:25 INFO - Obtained file size: 14414067 07:12:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:26 INFO - trying https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:12:26 INFO - Downloading https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 07:12:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 07:12:29 INFO - Downloaded 111785969 bytes. 07:12:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:12:29 INFO - mkdir: /builds/slave/test/properties 07:12:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:12:29 INFO - Writing to file /builds/slave/test/properties/build_url 07:12:29 INFO - Contents: 07:12:29 INFO - build_url:https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:12:29 INFO - Running post-action listener: _resource_record_post_action 07:12:29 INFO - Running post-action listener: set_extra_try_arguments 07:12:29 INFO - [mozharness: 2016-09-29 14:12:29.812127Z] Finished download-and-extract step (success) 07:12:29 INFO - [mozharness: 2016-09-29 14:12:29.812263Z] Running populate-webroot step. 07:12:29 INFO - Running pre-action listener: _resource_record_pre_action 07:12:29 INFO - Running main action method: populate_webroot 07:12:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:12:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:12:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:12:29 INFO - '--tppagecycles', 07:12:29 INFO - '7'], 07:12:29 INFO - 'tests': ['tsvgm']}}, 07:12:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:12:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:12:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:12:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:12:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:12:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:12:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:29 INFO - 'talos_options': ['--disable-e10s'], 07:12:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:12:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:12:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:29 INFO - 'talos_options': ['--disable-e10s'], 07:12:29 INFO - 'tests': ['damp', 'tps']}, 07:12:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:29 INFO - 'tests': ['damp', 'tps']}, 07:12:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:12:29 INFO - 'tests': ['dromaeo_dom']}, 07:12:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:12:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:12:29 INFO - 'tests': ['basic_compositor_video']}, 07:12:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:12:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:12:29 INFO - 'tests': ['a11yr', 07:12:29 INFO - 'ts_paint', 07:12:29 INFO - 'tpaint', 07:12:29 INFO - 'sessionrestore', 07:12:29 INFO - 'sessionrestore_no_auto_restore', 07:12:29 INFO - 'tabpaint']}, 07:12:29 INFO - 'other-e10s': {'tests': ['a11yr', 07:12:29 INFO - 'ts_paint', 07:12:29 INFO - 'tpaint', 07:12:29 INFO - 'sessionrestore', 07:12:29 INFO - 'sessionrestore_no_auto_restore', 07:12:29 INFO - 'tabpaint']}, 07:12:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:12:29 INFO - 'ts_paint', 07:12:29 INFO - 'tpaint', 07:12:29 INFO - 'sessionrestore', 07:12:29 INFO - 'sessionrestore_no_auto_restore', 07:12:29 INFO - 'tabpaint']}, 07:12:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:12:29 INFO - 'ts_paint', 07:12:29 INFO - 'tpaint', 07:12:29 INFO - 'sessionrestore', 07:12:29 INFO - 'sessionrestore_no_auto_restore', 07:12:29 INFO - 'tabpaint']}, 07:12:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:12:29 INFO - 'tests': ['a11yr', 07:12:29 INFO - 'ts_paint', 07:12:29 INFO - 'tpaint', 07:12:29 INFO - 'sessionrestore', 07:12:29 INFO - 'sessionrestore_no_auto_restore', 07:12:29 INFO - 'tabpaint']}, 07:12:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:12:29 INFO - 'tests': ['a11yr', 07:12:29 INFO - 'ts_paint', 07:12:29 INFO - 'tpaint', 07:12:29 INFO - 'sessionrestore', 07:12:29 INFO - 'sessionrestore_no_auto_restore', 07:12:29 INFO - 'tabpaint']}, 07:12:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:12:29 INFO - 'tests': ['tsvgx', 07:12:29 INFO - 'tsvgr_opacity', 07:12:29 INFO - 'tart', 07:12:29 INFO - 'tscrollx', 07:12:29 INFO - 'cart']}, 07:12:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:12:29 INFO - 'tsvgr_opacity', 07:12:29 INFO - 'tart', 07:12:29 INFO - 'tscrollx', 07:12:29 INFO - 'cart']}, 07:12:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:29 INFO - 'talos_options': ['--disable-e10s'], 07:12:29 INFO - 'tests': ['tp5o']}, 07:12:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:29 INFO - 'tests': ['tp5o']}, 07:12:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:29 INFO - 'talos_options': ['--disable-e10s', 07:12:29 INFO - '--xperf_path', 07:12:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:12:29 INFO - 'tests': ['tp5n']}, 07:12:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:29 INFO - 'talos_options': ['--xperf_path', 07:12:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:12:29 INFO - 'tests': ['tp5n']}}, 07:12:29 INFO - 'talos.zip': {'path': '', 07:12:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:12:29 INFO - Running post-action listener: _resource_record_post_action 07:12:29 INFO - [mozharness: 2016-09-29 14:12:29.831371Z] Finished populate-webroot step (success) 07:12:29 INFO - [mozharness: 2016-09-29 14:12:29.831485Z] Running create-virtualenv step. 07:12:29 INFO - Running pre-action listener: _resource_record_pre_action 07:12:29 INFO - Running main action method: create_virtualenv 07:12:29 INFO - Creating virtualenv /builds/slave/test/build/venv 07:12:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:12:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:12:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:12:29 INFO - Using real prefix '/tools/python27' 07:12:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:12:30 INFO - Installing distribute.............................................................................................................................................................................................done. 07:12:33 INFO - Installing pip.................done. 07:12:33 INFO - Return code: 0 07:12:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:12:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:12:33 INFO - Reading from file tmpfile_stdout 07:12:33 INFO - Output received: 07:12:33 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:12:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:12:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd69490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad0cabe20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7dd30>, '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 0x10c9a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb75b20>, '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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:12:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:12:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CPkkUs9HVe/Render', 07:12:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:33 INFO - 'HOME': '/Users/cltbld', 07:12:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:33 INFO - 'LOGNAME': 'cltbld', 07:12:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:33 INFO - 'MOZ_NO_REMOTE': '1', 07:12:33 INFO - 'NO_EM_RESTART': '1', 07:12:33 INFO - 'PAGER': '/bin/cat', 07:12:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:33 INFO - 'PWD': '/builds/slave/test', 07:12:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:33 INFO - 'SHELL': '/bin/bash', 07:12:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UO2y3WWrFK/Listeners', 07:12:33 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 07:12:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:33 INFO - 'USER': 'cltbld', 07:12:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:33 INFO - 'XPC_FLAGS': '0x0', 07:12:33 INFO - 'XPC_SERVICE_NAME': '0', 07:12:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:12:34 INFO - Cleaning up... 07:12:34 INFO - Return code: 0 07:12:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:34 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 0x10cd69490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad0cabe20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7dd30>, '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 0x10c9a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb75b20>, '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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:12:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CPkkUs9HVe/Render', 07:12:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:34 INFO - 'HOME': '/Users/cltbld', 07:12:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:34 INFO - 'LOGNAME': 'cltbld', 07:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:34 INFO - 'MOZ_NO_REMOTE': '1', 07:12:34 INFO - 'NO_EM_RESTART': '1', 07:12:34 INFO - 'PAGER': '/bin/cat', 07:12:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:34 INFO - 'PWD': '/builds/slave/test', 07:12:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:34 INFO - 'SHELL': '/bin/bash', 07:12:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UO2y3WWrFK/Listeners', 07:12:34 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 07:12:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:34 INFO - 'USER': 'cltbld', 07:12:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:34 INFO - 'XPC_FLAGS': '0x0', 07:12:34 INFO - 'XPC_SERVICE_NAME': '0', 07:12:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:34 INFO - Downloading/unpacking psutil>=3.1.1 07:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:12:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:38 INFO - Installing collected packages: psutil 07:12:38 INFO - Running setup.py install for psutil 07:12:38 INFO - building 'psutil._psutil_osx' extension 07:12:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:12:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:12:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:12:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:12:38 INFO - building 'psutil._psutil_posix' extension 07:12:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:12:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:12:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:12:38 INFO - ^ 07:12:38 INFO - 1 warning generated. 07:12:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:12:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:39 INFO - Successfully installed psutil 07:12:39 INFO - Cleaning up... 07:12:39 INFO - Return code: 0 07:12:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:12:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:39 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 0x10cd69490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad0cabe20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7dd30>, '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 0x10c9a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb75b20>, '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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:12:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:12:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CPkkUs9HVe/Render', 07:12:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:39 INFO - 'HOME': '/Users/cltbld', 07:12:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:39 INFO - 'LOGNAME': 'cltbld', 07:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:39 INFO - 'MOZ_NO_REMOTE': '1', 07:12:39 INFO - 'NO_EM_RESTART': '1', 07:12:39 INFO - 'PAGER': '/bin/cat', 07:12:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:39 INFO - 'PWD': '/builds/slave/test', 07:12:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:39 INFO - 'SHELL': '/bin/bash', 07:12:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UO2y3WWrFK/Listeners', 07:12:39 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 07:12:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:39 INFO - 'USER': 'cltbld', 07:12:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:39 INFO - 'XPC_FLAGS': '0x0', 07:12:39 INFO - 'XPC_SERVICE_NAME': '0', 07:12:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:12:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:12:41 INFO - Installing collected packages: mozsystemmonitor 07:12:41 INFO - Running setup.py install for mozsystemmonitor 07:12:42 INFO - Successfully installed mozsystemmonitor 07:12:42 INFO - Cleaning up... 07:12:42 INFO - Return code: 0 07:12:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:12: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:42 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 0x10cd69490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad0cabe20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7dd30>, '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 0x10c9a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb75b20>, '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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:12:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:12:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CPkkUs9HVe/Render', 07:12:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:42 INFO - 'HOME': '/Users/cltbld', 07:12:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:42 INFO - 'LOGNAME': 'cltbld', 07:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:42 INFO - 'MOZ_NO_REMOTE': '1', 07:12:42 INFO - 'NO_EM_RESTART': '1', 07:12:42 INFO - 'PAGER': '/bin/cat', 07:12:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:42 INFO - 'PWD': '/builds/slave/test', 07:12:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:42 INFO - 'SHELL': '/bin/bash', 07:12:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UO2y3WWrFK/Listeners', 07:12:42 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 07:12:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:42 INFO - 'USER': 'cltbld', 07:12:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:42 INFO - 'XPC_FLAGS': '0x0', 07:12:42 INFO - 'XPC_SERVICE_NAME': '0', 07:12:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:42 INFO - Downloading/unpacking jsonschema==2.5.1 07:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:12:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:45 INFO - Downloading functools32-3.2.3-2.tar.gz 07:12:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:12:45 INFO - warning: no files found matching '*.txt' 07:12:45 INFO - no previously-included directories found matching 'build' 07:12:45 INFO - no previously-included directories found matching 'dist' 07:12:45 INFO - no previously-included directories found matching '.git*' 07:12:45 INFO - Installing collected packages: jsonschema, functools32 07:12:45 INFO - Running setup.py install for functools32 07:12:45 INFO - warning: no files found matching '*.txt' 07:12:45 INFO - no previously-included directories found matching 'build' 07:12:45 INFO - no previously-included directories found matching 'dist' 07:12:45 INFO - no previously-included directories found matching '.git*' 07:12:45 INFO - Successfully installed jsonschema functools32 07:12:45 INFO - Cleaning up... 07:12:45 INFO - Return code: 0 07:12:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd69490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad0cabe20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7dd30>, '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 0x10c9a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb75b20>, '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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:12:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:12:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CPkkUs9HVe/Render', 07:12:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:45 INFO - 'HOME': '/Users/cltbld', 07:12:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:45 INFO - 'LOGNAME': 'cltbld', 07:12:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:45 INFO - 'MOZ_NO_REMOTE': '1', 07:12:45 INFO - 'NO_EM_RESTART': '1', 07:12:45 INFO - 'PAGER': '/bin/cat', 07:12:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:45 INFO - 'PWD': '/builds/slave/test', 07:12:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:45 INFO - 'SHELL': '/bin/bash', 07:12:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UO2y3WWrFK/Listeners', 07:12:45 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 07:12:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:45 INFO - 'USER': 'cltbld', 07:12:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:45 INFO - 'XPC_FLAGS': '0x0', 07:12:45 INFO - 'XPC_SERVICE_NAME': '0', 07:12:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:12:45 INFO - Cleaning up... 07:12:45 INFO - Return code: 0 07:12:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:45 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 0x10cd69490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad0cabe20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7dd30>, '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 0x10c9a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb75b20>, '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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:12:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:12:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CPkkUs9HVe/Render', 07:12:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:45 INFO - 'HOME': '/Users/cltbld', 07:12:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:45 INFO - 'LOGNAME': 'cltbld', 07:12:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:45 INFO - 'MOZ_NO_REMOTE': '1', 07:12:45 INFO - 'NO_EM_RESTART': '1', 07:12:45 INFO - 'PAGER': '/bin/cat', 07:12:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:45 INFO - 'PWD': '/builds/slave/test', 07:12:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:45 INFO - 'SHELL': '/bin/bash', 07:12:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UO2y3WWrFK/Listeners', 07:12:45 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 07:12:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:45 INFO - 'USER': 'cltbld', 07:12:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:45 INFO - 'XPC_FLAGS': '0x0', 07:12:45 INFO - 'XPC_SERVICE_NAME': '0', 07:12:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:46 INFO - Downloading/unpacking blobuploader==1.2.4 07:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:48 INFO - Downloading blobuploader-1.2.4.tar.gz 07:12:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:12:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:12:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:49 INFO - Downloading docopt-0.6.1.tar.gz 07:12:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:12:49 INFO - Installing collected packages: blobuploader, requests, docopt 07:12:49 INFO - Running setup.py install for blobuploader 07:12:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:12:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:12:50 INFO - Running setup.py install for requests 07:12:50 INFO - Running setup.py install for docopt 07:12:50 INFO - Successfully installed blobuploader requests docopt 07:12:50 INFO - Cleaning up... 07:12:50 INFO - Return code: 0 07:12:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:50 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 0x10cd69490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad0cabe20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7dd30>, '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 0x10c9a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb75b20>, '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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:12:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:12:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CPkkUs9HVe/Render', 07:12:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:50 INFO - 'HOME': '/Users/cltbld', 07:12:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:50 INFO - 'LOGNAME': 'cltbld', 07:12:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:50 INFO - 'MOZ_NO_REMOTE': '1', 07:12:50 INFO - 'NO_EM_RESTART': '1', 07:12:50 INFO - 'PAGER': '/bin/cat', 07:12:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:50 INFO - 'PWD': '/builds/slave/test', 07:12:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:50 INFO - 'SHELL': '/bin/bash', 07:12:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UO2y3WWrFK/Listeners', 07:12:50 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 07:12:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:50 INFO - 'USER': 'cltbld', 07:12:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:50 INFO - 'XPC_FLAGS': '0x0', 07:12:50 INFO - 'XPC_SERVICE_NAME': '0', 07:12:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:51 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-E0AwsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:51 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-GLOzJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:51 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-T9TgrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:51 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-ETpeYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:51 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Kq0Le7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:51 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-J0Z6Aw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:51 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-hyA9hE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:51 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-51_9t0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:51 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-qLct_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:52 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-3MsDST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:52 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-XMNqG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:52 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-DGJTiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:52 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-mYcuiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:52 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-UnuHNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:52 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-O56vq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:52 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-tQc1lA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:52 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-HKJWD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:12:52 INFO - Running setup.py install for manifestparser 07:12:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Running setup.py install for mozcrash 07:12:53 INFO - Running setup.py install for mozdebug 07:12:53 INFO - Running setup.py install for mozdevice 07:12:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Running setup.py install for mozfile 07:12:53 INFO - Running setup.py install for mozhttpd 07:12:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Running setup.py install for mozinfo 07:12:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Running setup.py install for mozInstall 07:12:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:12:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:12:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:12:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:12:54 INFO - Running setup.py install for mozleak 07:12:54 INFO - Running setup.py install for mozlog 07:12:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:12:54 INFO - Running setup.py install for moznetwork 07:12:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:12:54 INFO - Running setup.py install for mozprocess 07:12:54 INFO - Running setup.py install for mozprofile 07:12:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:12:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:12:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:12:54 INFO - Running setup.py install for mozrunner 07:12:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:12:55 INFO - Running setup.py install for mozscreenshot 07:12:55 INFO - Running setup.py install for moztest 07:12:55 INFO - Running setup.py install for mozversion 07:12:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:12:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:12:55 INFO - Cleaning up... 07:12:55 INFO - Return code: 0 07:12:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:55 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 0x10cd69490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad0cabe20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7dd30>, '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 0x10c9a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb75b20>, '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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:12:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:12:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CPkkUs9HVe/Render', 07:12:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:55 INFO - 'HOME': '/Users/cltbld', 07:12:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:55 INFO - 'LOGNAME': 'cltbld', 07:12:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:55 INFO - 'MOZ_NO_REMOTE': '1', 07:12:55 INFO - 'NO_EM_RESTART': '1', 07:12:55 INFO - 'PAGER': '/bin/cat', 07:12:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:55 INFO - 'PWD': '/builds/slave/test', 07:12:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:55 INFO - 'SHELL': '/bin/bash', 07:12:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UO2y3WWrFK/Listeners', 07:12:55 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 07:12:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:55 INFO - 'USER': 'cltbld', 07:12:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:55 INFO - 'XPC_FLAGS': '0x0', 07:12:55 INFO - 'XPC_SERVICE_NAME': '0', 07:12:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:55 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-3XxS7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:55 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-pWuAl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:55 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-3IH2R8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-dcfcQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-5X79FF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-F3amgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-eVeyrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Ctr83y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-xXIqkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-PhAjyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-FcmAsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:56 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-hWKz2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-FRdhM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-4eKMXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-nzMGfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-w6hNqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:57 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-mxyP9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:12:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:59 INFO - Downloading blessings-1.6.tar.gz 07:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:13:00 INFO - Installing collected packages: blessings 07:13:00 INFO - Running setup.py install for blessings 07:13:00 INFO - Successfully installed blessings 07:13:00 INFO - Cleaning up... 07:13:00 INFO - Return code: 0 07:13:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:13:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:13:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:13:00 INFO - Reading from file tmpfile_stdout 07:13:00 INFO - Current package versions: 07:13:00 INFO - blessings == 1.6 07:13:00 INFO - blobuploader == 1.2.4 07:13:00 INFO - docopt == 0.6.1 07:13:00 INFO - functools32 == 3.2.3-2 07:13:00 INFO - jsonschema == 2.5.1 07:13:00 INFO - manifestparser == 1.1 07:13:00 INFO - mozInstall == 1.12 07:13:00 INFO - mozcrash == 0.17 07:13:00 INFO - mozdebug == 0.1 07:13:00 INFO - mozdevice == 0.48 07:13:00 INFO - mozfile == 1.2 07:13:00 INFO - mozhttpd == 0.7 07:13:00 INFO - mozinfo == 0.9 07:13:00 INFO - mozleak == 0.1 07:13:00 INFO - mozlog == 3.4 07:13:00 INFO - moznetwork == 0.27 07:13:00 INFO - mozprocess == 0.23 07:13:00 INFO - mozprofile == 0.28 07:13:00 INFO - mozrunner == 6.12 07:13:00 INFO - mozscreenshot == 0.1 07:13:00 INFO - mozsystemmonitor == 0.3 07:13:00 INFO - moztest == 0.7 07:13:00 INFO - mozversion == 1.4 07:13:00 INFO - psutil == 3.1.1 07:13:00 INFO - requests == 1.2.3 07:13:00 INFO - wsgiref == 0.1.2 07:13:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:00 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 0x10cd69490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad0cabe20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7dd30>, '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 0x10c9a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb75b20>, '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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:13:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:13:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:13:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CPkkUs9HVe/Render', 07:13:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:13:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:13:00 INFO - 'HOME': '/Users/cltbld', 07:13:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:13:00 INFO - 'LOGNAME': 'cltbld', 07:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:00 INFO - 'MOZ_NO_REMOTE': '1', 07:13:00 INFO - 'NO_EM_RESTART': '1', 07:13:00 INFO - 'PAGER': '/bin/cat', 07:13:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:00 INFO - 'PWD': '/builds/slave/test', 07:13:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:13:00 INFO - 'SHELL': '/bin/bash', 07:13:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UO2y3WWrFK/Listeners', 07:13:00 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 07:13:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:13:00 INFO - 'USER': 'cltbld', 07:13:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:13:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:00 INFO - 'XPC_FLAGS': '0x0', 07:13:00 INFO - 'XPC_SERVICE_NAME': '0', 07:13:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:13:00 INFO - Cleaning up... 07:13:00 INFO - Return code: 0 07:13:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:00 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 0x10cd69490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdad0cabe20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7dd30>, '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 0x10c9a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb75b20>, '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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:13:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:13:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:13:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CPkkUs9HVe/Render', 07:13:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:13:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:13:00 INFO - 'HOME': '/Users/cltbld', 07:13:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:13:00 INFO - 'LOGNAME': 'cltbld', 07:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:00 INFO - 'MOZ_NO_REMOTE': '1', 07:13:00 INFO - 'NO_EM_RESTART': '1', 07:13:00 INFO - 'PAGER': '/bin/cat', 07:13:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:00 INFO - 'PWD': '/builds/slave/test', 07:13:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:13:00 INFO - 'SHELL': '/bin/bash', 07:13:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UO2y3WWrFK/Listeners', 07:13:00 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 07:13:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:13:00 INFO - 'USER': 'cltbld', 07:13:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:13:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:00 INFO - 'XPC_FLAGS': '0x0', 07:13:00 INFO - 'XPC_SERVICE_NAME': '0', 07:13:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:13:01 INFO - Cleaning up... 07:13:01 INFO - Return code: 0 07:13:01 INFO - Running post-action listener: _resource_record_post_action 07:13:01 INFO - Running post-action listener: _start_resource_monitoring 07:13:01 INFO - Starting resource monitoring. 07:13:01 INFO - [mozharness: 2016-09-29 14:13:01.198230Z] Finished create-virtualenv step (success) 07:13:01 INFO - [mozharness: 2016-09-29 14:13:01.199234Z] Running install step. 07:13:01 INFO - Running pre-action listener: _resource_record_pre_action 07:13:01 INFO - Running main action method: install 07:13:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:13:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:13:01 INFO - Reading from file tmpfile_stdout 07:13:01 INFO - Detecting whether we're running mozinstall >=1.0... 07:13:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:13:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:13:01 INFO - Reading from file tmpfile_stdout 07:13:01 INFO - Output received: 07:13:01 INFO - Usage: mozinstall [options] installer 07:13:01 INFO - Options: 07:13:01 INFO - -h, --help show this help message and exit 07:13:01 INFO - -d DEST, --destination=DEST 07:13:01 INFO - Directory to install application into. [default: 07:13:01 INFO - "/builds/slave/test"] 07:13:01 INFO - --app=APP Application being installed. [default: firefox] 07:13:01 INFO - mkdir: /builds/slave/test/build/application 07:13:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:13:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:13:35 INFO - Reading from file tmpfile_stdout 07:13:35 INFO - Output received: 07:13:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:13:35 INFO - Running post-action listener: _resource_record_post_action 07:13:35 INFO - [mozharness: 2016-09-29 14:13:35.847475Z] Finished install step (success) 07:13:35 INFO - [mozharness: 2016-09-29 14:13:35.847650Z] Running run-tests step. 07:13:35 INFO - Running pre-action listener: _resource_record_pre_action 07:13:35 INFO - Running main action method: run_tests 07:13:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:13:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:13:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:13:35 INFO - 2016-09-29 07:13:35.862 screenresolution[1928:17557] starting screenresolution argv=screenresolution get 07:13:35 INFO - 2016-09-29 07:13:35.865 screenresolution[1928:17557] Display 0: 1600x1200x32@60 07:13:35 INFO - 2016-09-29 07:13:35.871 screenresolution[1929:17560] starting screenresolution argv=screenresolution list 07:13:35 INFO - Available Modes on Display 0 07:13:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:13:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:13:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:13:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:13:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:13:36 INFO - Intel Iris: 07:13:36 INFO - Chipset Model: Intel Iris 07:13:36 INFO - Type: GPU 07:13:36 INFO - Bus: Built-In 07:13:36 INFO - VRAM (Dynamic, Max): 1536 MB 07:13:36 INFO - Vendor: Intel (0x8086) 07:13:36 INFO - Device ID: 0x0a2e 07:13:36 INFO - Revision ID: 0x0009 07:13:36 INFO - Displays: 07:13:36 INFO - Display: 07:13:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:13:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:13:36 INFO - Display Serial Number: 1600x1200 60 07:13:36 INFO - Main Display: Yes 07:13:36 INFO - Mirror: Off 07:13:36 INFO - Online: Yes 07:13:36 INFO - Rotation: Supported 07:13:36 INFO - Return code: 0 07:13:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:13:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:13:36 INFO - Python 2.7.3 07:13:36 INFO - Return code: 0 07:13:36 INFO - grabbing minidump binary from tooltool 07:13:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:36 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 0x10cb7dd30>, '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 0x10c9a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb75b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:13:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:13:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:13:36 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:13:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:13:36 INFO - Return code: 0 07:13:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:13:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:13:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:13:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:13:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:13:36 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/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:13:36 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/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0191 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:13:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CPkkUs9HVe/Render', 07:13:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:13:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:13:36 INFO - 'HOME': '/Users/cltbld', 07:13:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:13:36 INFO - 'LOGNAME': 'cltbld', 07:13:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:13:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:13:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:36 INFO - 'MOZ_NO_REMOTE': '1', 07:13:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:13:36 INFO - 'NO_EM_RESTART': '1', 07:13:36 INFO - 'PAGER': '/bin/cat', 07:13:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:13:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:36 INFO - 'PWD': '/builds/slave/test', 07:13:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:13:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:13:36 INFO - 'SHELL': '/bin/bash', 07:13:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UO2y3WWrFK/Listeners', 07:13:36 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 07:13:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:13:36 INFO - 'USER': 'cltbld', 07:13:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:13:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:13:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:36 INFO - 'XPC_FLAGS': '0x0', 07:13:36 INFO - 'XPC_SERVICE_NAME': '0', 07:13:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:13:36 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/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:13:36 INFO - mozversion application_buildid: 20160929050519 07:13:36 INFO - mozversion application_changeset: 0f2c7b2a783bbc5954e93216e7cb3b396a78003e 07:13:36 INFO - mozversion application_display_name: Nightly 07:13:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:13:36 INFO - mozversion application_name: Firefox 07:13:36 INFO - mozversion application_remotingname: firefox 07:13:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:13:36 INFO - mozversion application_vendor: Mozilla 07:13:36 INFO - mozversion application_version: 52.0a1 07:13:36 INFO - mozversion platform_buildid: 20160929050519 07:13:36 INFO - mozversion platform_changeset: 0f2c7b2a783bbc5954e93216e7cb3b396a78003e 07:13:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:13:36 INFO - mozversion platform_version: 52.0a1 07:13:36 INFO - using testdate: 1475158416 07:13:36 INFO - actual date: 1475158416 07:13:36 INFO - starting webserver on 'localhost:56160' 07:13:36 INFO - SUITE-START | Running 2 tests 07:13:36 INFO - TEST-START | dromaeo_css 07:13:36 INFO - operating with platform_type : mac_ 07:13:36 INFO - Initialising browser for dromaeo_css test... 07:13:36 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpTj4XmG/profile http://localhost:56160/getInfo.html) 07:13:38 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 07:13:38 INFO - PROCESS | 1939 | colorDepth:24 07:13:38 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 07:13:38 INFO - PROCESS | 1939 | __metrics 07:13:38 INFO - PROCESS | 1939 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:13:38 INFO - PROCESS | 1939 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:13:39 INFO - TEST-INFO | 1939: exit 0 07:13:39 INFO - Browser initialized. 07:13:39 INFO - Running cycle 1/1 for dromaeo_css test... 07:13:39 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpTj4XmG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:13:51 INFO - PROCESS | 1941 | RSS: Main: 236048384 07:13:51 INFO - PROCESS | 1941 | 07:14:43 INFO - PROCESS | 1941 | 1475158483521 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:16:55 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:56160/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56160/tests/dromaeo/cssquery-ext.html) 07:16:56 INFO - PROCESS | 1941 | RSS: Main: 385884160 07:16:56 INFO - PROCESS | 1941 | 07:19:59 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:56160/tests/dromaeo/cssquery-ext.html (next: http://localhost:56160/tests/dromaeo/cssquery-jquery.html) 07:20:00 INFO - PROCESS | 1941 | RSS: Main: 371912704 07:20:00 INFO - PROCESS | 1941 | 07:23:02 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:56160/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56160/tests/dromaeo/cssquery-mootools.html) 07:23:03 INFO - PROCESS | 1941 | RSS: Main: 343957504 07:23:03 INFO - PROCESS | 1941 | 07:26:05 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:56160/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56160/tests/dromaeo/cssquery-prototype.html) 07:26:06 INFO - PROCESS | 1941 | RSS: Main: 345853952 07:26:06 INFO - PROCESS | 1941 | 07:29:09 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:56160/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56160/tests/dromaeo/cssquery-yui.html) 07:29:10 INFO - PROCESS | 1941 | RSS: Main: 332414976 07:29:10 INFO - PROCESS | 1941 | 07:32:13 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:56160/tests/dromaeo/cssquery-yui.html (next: http://localhost:56160/tests/dromaeo/cssquery-dojo.html) 07:32:13 INFO - PROCESS | 1941 | RSS: Main: 430010368 07:32:13 INFO - PROCESS | 1941 | 07:32:13 INFO - PROCESS | 1941 | __start_tp_report 07:32:13 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 07:32:13 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 07:32:13 INFO - PROCESS | 1941 | |i|pagename|runs| 07:32:13 INFO - PROCESS | 1941 | |0;dojo.html;2741.697693595542;3142.9999999999986;3298.1589694627874;3325.418051840927;3384.4077286474876;5509.807156749514;5600.215969764236;5714.485696287334;5736.827895163152;5751.079827227639;5424.376196737377;5624.943750562504;5644.971775141129;5662.688552129631;5778.8266352009505;5068.834168141329;5116.923246151311;5176.171812509999;5204.32343795306;5210.739463026834;5232.973835130819;5313.256144139824;5319.281896943921;5320.334958130234;5325.840224793263;5274.498922602346;5308.389535203443;5308.681479111227;5349.251104845324;5366.356037275533;5114.386273647149;5125.026245013435;5185.274061631375;5195.818146364897;5196.246544251062;5114.616403769732;5135.922961155586;5174.767135478913;5187.325647665779;5271.103912334912;5042.873928151826;5610.018246806792;5669.40471250521;5683.60214784961;5689.317281926154;6107.603005804608;6130.581587307925;6143.481172719164;6238.9376106238815;6263;6150.84622884427;6157.830012297649;6234.501239900797;6298.181236439279;6308.810735677937;5785.739641716133;5789.189513468118;5790.36306006339;5810.360860305405;5830.56270779689;6121.081837724288;6264.248290205113;6273.12176295319;6286.779962701284;6309.716062777186;5096.898062038813;6079.604825686316;6105.938940610537;6155.138280640714;6180.8763824723255;6247.062940558862;6267.2165979252595;6272.717727702265;6278.30938596754;6283.59156654816;6176.4441200292185;6207.906881396782;6231.9688401557705;6245.906311405332;6247.781327653511;5373.785048598091;6063.757449701962;6117.766507567544;6122.215079804129;6253.874922501524;4909.7790599423115;4915.680480768739;4921.384826896638;4926.1379258629595;4928.063667903087;4359.651227901761;4394.978025109854;4465.575770301816;4486.12520558488;4502.099580083931;4777.8088876445245;5665.008623490955;5707.771689132388;5811.912821307684;5830.47525722687;6286.465650419674;6286.874262514724;6292.339304373067;6298.212723409574;6298.244210694655;5722.570807189478;5763.221965034751;5765.682887441231;5774.566907481955;5819.476247137778;5305.575553955674;5339.11904535755;5351.598630102758;5359.651622644516;5387.757550910218;6242.344553821874;6297.307296197323;6302.369763023844;6302.905456418157;6314.179156709598;6275.090111933653;6285.622862628257;6293.433590976834;6298.811035668938;6301.306856245901;5315.202719592092;6185.319614842274;6301.716422761078;6309.211348581427;6326.810195693953;6112.50348092447;6118;6119.07606531266;6137.46891820379;6206.472449841814;6270.7491700331475;6280.309165991646;6285.654289014148;6286.339934306832;6301.684915754102;6071.696415179312;6084.6349219047;6103.05402662588;6117.418845209698;6141.477974372228;5957.285125784933;6042.1843051187525;6047.727852246571;6079.209702738615;6111.04482360895;5903.085021821625;5916.437938395846;5955.166276721349;5971.193888824953;5973.731182096903;6113.266408031065;6127.387261273837;6130.570860039755;6131.816045518642;6139.938600613937;5797.8550536236935;5866.6186697864505;5892.35184129754;5911.497522710618;5959.10613407975 07:32:13 INFO - PROCESS | 1941 | |1;ext.html;458.3399904138042;470.35326426164016;512.941011783645;520.1391696741894;550.0923476264161;29097.272568185806;29655.96204132856;30738.385232295368;30777.076687699348;38937.61062389375;38688;40842.183156336854;40878;40903.79548102263;40930.59069409305;9632.084951929555;9819.558119884587;9821.2634052446;9835.901640983606;9860.556274967643;8706.825863482727;8916.866247006301;8918.73243802687;9572.32993690442;9700.272479564026;8821.60302786376;8879.51162686052;11921.821172682417;12240.755184896298;12251.571195008177;12134.211276267059;12188.695282617917;12195.963343115845;12206.694832629166;12209.145359824815;11757.470913808898;11845.348505832177;11845.703857403547;11855.940720296343;11871.940640296829;30499.08502744921;30778.692213077808;30852.53721194184;31961.52057719136;38879.41680874789;3229.7254733347695;3257.7068063874367;3381.239221175216;3401.829908504565;3834.1564855731694;29446.558301625493;30472;30861.845690771628;30862;31397.686023139704;3148.8268145252;3257.5765150530515;3305.801651900894;3335.483000134983;3371.2414706691043;481201;481216;483331;484337.99999999645;484505;54412.183817242774;54843.45156548463;54866.17700734492;54889.451105489235;54947.72526137344;58298.834023319294;58395.416045838996;58445;58475.122873156935;58594.4140558589;15195.772063419057;15247.923760381127;15521.922390387976;15529.223538823013;15543.922280388751;12213.877861221274;12305.138640295094;12310.138290319592;12325.260484370967;12343.629691109281;13558.186508809504;14827.258637068104;14846.406143754344;14910.403583856742;15117.395304187929;5209.583233341324;5373.650712703662;6587.670616469158;6589.308122647149;6628.668566571652;850.2815121222557;959.4099628728281;973.4062222044547;1008.9445080520642;1029.459533744775;25419.491610167694;29320.492774695984;29347.55978660322;29503.85248073746;29710.554341684892;877.5963056993727;1035.4615599888166;1049.8267785815408;1069.5882085397022;1124.3759713359163;13808.654783630489;15056.32246548908;15286;15499.457518986781;15557.29992150356;586235;602325;602947;603453;603608;52382.690432738724;52542.73728631409;52580.737096314275;52619.94760104777;52660.47339526556;1205.4274219745726;1256.8805963433642;1342.9395677194354;1370.8629137086411;1409.5137177673687;28901.132966011053;30596.84701576478;30600.234994125323;30891.69108308888;31206.531902021034;45885.31172032489;46501;46521.53478465172;46629.53370466252;46688.53311466842;412617;412672;413318;413516;414089;24489.755102448747;25128.994840206185;25428.872855636342;26060.73939260659;37397.065073373386;45247;45349.505777182574;45362.546374535836;45420.545794541635;45789;32009.8399508001;36759.77262614726;37241.25517489689;37317.81341093277;38420.807895960344;35087.64912350844;37142.25715485621;38445.807770960964;38699.41950870683;38738.6126138735;36043.27913441769;36419.63580364268;37145.81427092847;37173.814130929175;37374.8131259342;31558;31836.84081579577;33318;33438.33123337471;33894.66105339014;24457.26628201157;24470.51058978846;24683.629745553473;24931.082098294508;25033.74966250387 07:32:13 INFO - PROCESS | 1941 | |2;jquery.html;333.64133556426845;368.39950880065487;371.2056199732573;380.2452132516954;400.82163437270424;14054.859451405495;15273.847261527382;20418.489537761565;20468.89765551172;21129.788702113012;20440.488987775312;20489.282875099376;20631.174753009862;21011.2646057388;21292.787072129275;5837.59136860421;6187.938120618781;6188.257409110913;6194.566380353354;6199.628022318653;3480.738944579154;4368.5194628590825;6059.3637668044885;6741.325867413244;6767.729290828361;6505.674716264191;6788.524803263774;6814.28450012749;6830.282820303871;6835.248122706498;6696.330366963289;6704.195496540447;6738.427233685143;6748.122744043292;6778.220504641955;5567.220589117527;5729.083346664555;5780.277465316835;5810.273715785527;5817.825465236049;25748.227553173434;25831;25913.870430647727;25966.961321547114;26126.86936565305;2185.1368709359713;2378.774016468433;2769.75072243497;3105.627324721048;3206.6633003534525;15048.097114173075;15583.532494025198;15615.453459128876;16062.815815910843;16349.019058856507;1837.476319249021;1877.9624407511774;1884.1897983866932;1898.8765730227492;1901.3535397964758;112116.43941780321;117329.30682772765;117387;117528;117714;9249.260059195249;9798.755031124136;9943.055409736064;10229.795404091876;10434.113100386552;12456.75086498265;12963.027772917068;12976.805347919788;13198.010149238846;14087.36606852694;7005.334493223136;8161.714339998071;8224.588770561568;8288.21261980111;8462.534560599226;6623.337666233435;8198.42611017235;8217.301529369952;8384;8416.368772342099;4399.890002749957;4456.264716321771;4463.910721785546;4541.182587134347;4544.500104988449;1609.871210303173;1831.688612935804;1855.5082903030707;1874.6157037807218;1883.4255552056754;876.7676565710092;886.9866951995725;973.8247115519133;994.3834822410151;1006.9446180459998;19916.800831991783;20328.898355508423;20726.792732072485;20815.479613009797;21102.366928992156;797.9960100199462;887.5784002598843;924.066692640438;952.8237276103943;958.6213445688913;10080.546375413123;10415.323004004715;10570.10154136907;10892.455377231106;10972.122230221563;533295;535120;536976;538237;543576;125473.37263313807;127312.36343818222;128031.35984320019;130734;131512;946.6402766948515;954.0697819625809;966.4442945306477;966.5070813884829;967.7048500207363;17817.376391826223;18259;18318.725219121457;18692.878427294407;18814.52963675919;52427.854328740395;53036;53506.73246633742;53993.19010214772;54177.729111354194;74923;77285.61357193178;79234;79550;83155.5842220785;7109.502334836512;7353.191148973716;7920.317052570583;8112.959435202786;8358.540280284642;10963.397013164033;12030.398480076135;12308.938455307667;12388.256704597752;13582.79625805594;10127.341722787995;11232.55069797199;11273.830892536454;11470;11669.066474682175;11226.607068752555;11476.508053953266;11635.301881887113;11761.647150585497;11805.996490298077;11348.205625606128;11472.254303470248;11898;11950.641480755592;12049.698757531132;7403.703851845866;7460.925390746023;7571.318581327707;7590.0891892973195;7599.900472754416;9033.728988130366;11250.718732031764;11527.365994870363;11649.825252621387;11696.06431485496 07:32:13 INFO - PROCESS | 1941 | |3;mootools.html;1291.6318849127988;1512.357248169528;1517.5523220649907;1618.5791694159516;1750.2036573359123;7950.085740139885;8287.544185069826;8648.827023459535;8919.24186444153;8951.373403861733;8723.47659140452;8732.694355697551;8832.60253288602;8836.425632333894;8837.248833849115;2335.3577766114367;2336.7546407627124;2341.344423561397;2344.120954642009;2364.2316247219637;2319.257837492003;2351.2240960483;2359.1153317505937;2366.597678394677;2367.822413318999;1950.5123719070232;2319.9768002320016;2343.95312093758;2362.6574146748712;2365.06579900939;2311.133325003124;2327.60430726776;2347.3779448446003;2352.3413444235644;2360.0795689680867;2324.721033475994;2335.988320058406;2337.4974380508274;2338.672585837984;2339.847909885869;8651.783705407352;8765.649374025032;8804.691835785781;8870.645174193025;8985.326100542485;5305.628605997582;5448.42791506894;5457.863553411157;5467.371252305956;5478.397376288605;3112.1752735525106;3660.9450858237146;3673.522442082539;3735.794531300777;3756.7745935243975;1061.9628313009007;1065.6430095917876;1072.6299426697856;1091.7434402915308;1098.4727330881221;11547.249428787187;11609.883901160965;11703.473343699554;11713.355765432898;11778.41107944608;4351.73889566627;4425.269830477935;4600.6549508786975;4602.677812553089;4629.421322334708;9204.539773011322;9215.53922303882;9221.17009469151;9224.30817688676;9248.213901818286;5244.5542128918705;5244.632875698664;5251.737413129405;5262.1317482615705;5277.656952298088;4165.354370072643;4367.606915377632;4403.3174857897075;4532.7280363178315;4929.704217746875;3704.4628528863163;3756.530433695788;3813.9511634300234;3815.771053736785;3817.541894972621;1132.2979752553306;1133.54091592905;1137.374444055766;1145.9369734664674;1150.8216226484908;770.938324934004;791.2799352589117;843.0641987393988;882.7881308485917;907.9001309855911;5573.888522229533;5603.663780173202;5705.486506214377;5822.970885145547;5977.940220597825;418.6671596081123;421.3532228029959;425.61056633180294;433.7831084457771;435.4426334292111;3837;3855.4602355670227;3953.6441720245316;3960.6237407446247;3999.4800675911947;10481.790364192673;10710.571577136827;10862.891371086345;10865.130789536817;10889;9167.08329167078;9754;10776.946115269373;10788.51451684676;10867.78264434707;8037.7990550235545;8127.024757029076;8210.261076503144;8214.63034163464;8223.301019413417;5657.43425657431;5769.365369809234;5785.479306862402;5823.475887170175;5849.064149736192;8253.298469629908;9843;10015.749606259902;10046.54790534413;10092.444915529717;8325.750227493185;9289.256859451092;10327.225458090523;10328.948355258175;10352.792944141225;6569.835754106186;6743.831404214934;6838.111045564045;6971.930280697331;7035.542689725152;6750.898736519054;8278.83442331162;8311.210435008665;8414.579271036546;8478.406511544134;7481.962590187014;7514;7534.698612055676;7590.013298271189;7604.353629941517;7277.963610181915;7293.9635301823155;7352.926560186025;7380.77857664271;7418.406527477897;6281.434670879643;6698.129243198353;6762.96618516924;6923.273056329013;7013.263607321158;4864.294677271847;4963.8262660806695;4988.152014157675;5018.7741551631;5092.134337162765;4972.602191824718;5068.265101560327;5079.441261461162;5127.3847138343635;5127.717975511383 07:32:13 INFO - PROCESS | 1941 | |4;prototype.html;299.4236095516131;299.7976365952981;308.36015268318243;314.63816610897464;314.7387668235362;4942.382202224722;5352.946470535294;5385.407605163429;5402.000629883474;5422.105352616818;4570.971531405432;4942.528735632184;5128.230765385194;5200.895982080366;5324.627276090675;3396.7112795412295;3746;4016.7305472407998;4078.3678529827775;4095.385692146172;3412.5259187089287;4087.9182416351655;4090.8568200113;4115.650169735576;4128.153728485653;3942.2903877302074;4057.7768222747736;4077.4903137107863;4084.4281800547947;4098.733582317155;3247.5128730690467;4068.9796551017057;4069.47096877407;4095.672346212296;4115.444415003966;3006.4738670732754;3514.8594056237976;3531.8057506837113;3558.6441355864467;3592.2218501841758;4969.527894849984;5178.40448348441;5182.4299327073995;5185.403678576937;5222.373315202162;4049.7367671101283;4356.586124318216;4626.30605409188;4636.211843986531;4719.433667959866;5299.913517728856;5337.839864804023;5436.565074794007;5436.728163591844;5466.371367292772;3865.9806700966597;3869.9226015479535;3906.609339066099;3928.3125453045604;3931.9803400983096;5217.921731174036;5451.37309209442;5828.475437210672;5845.7369418376275;5939.346671866091;3320.020593924798;3447.6897079262485;3937.4684417603257;3954.426608141803;4005.619466150711;3135.3885992231753;3527.3827080260853;3590.5152804371596;3874.0121269076203;4029.879103626896;4227.239096962577;4494.797549200934;4544.977275113603;4677.368555245066;4696.154692155376;4915.631327650441;5172.043172013189;5542.251796007568;5561.6663000220115;5607.915881261784;1156.849609550753;1239.4856134703996;1306.9281189534668;1331.8468376136675;1344.4554955243143;795.3796039089519;831.7338451695399;851.1616058182685;851.7104184577273;905.5064989580694;4768.976155119202;4826.613870890391;5204.323437953042;5231.659942103751;5303.761330740126;5429.416585053846;5562.610617256834;5649.406812284733;5677.318721753416;5753.705416281303;4798.528757689793;5080.145746802465;5192.532672059458;5221.55616772571;5238.188080847474;2449.755024497536;2597.519369002636;2853.3036280714527;2919.1972207642643;2945.0415473709745;4645.465771436328;4893.73084480357;5764.337101233412;5774.335951365562;5775.711214439261;4801.51984801517;5102.438731739506;5868.354481007086;5904.527637788967;5935.821925342247;5388.24564560954;5830.621009634445;5948.702564871825;5961;5983.1623572699855;4678.602318802804;5032.723200224023;5174.783925777515;5290.920636190383;5672.7737835312955;5442.605253688364;5622.662640241599;5622.746976385991;5630.380658127685;5644.6330988485615;4737.8341758038305;5466.453354664502;5595.804146854841;5835.891180675658;5840.386759390372;3711.4803927450716;3776.811159442072;3798.639129282714;3799.7720136790776;3846.6538011577973;4624.768761561976;4919.286703428054;4962.106820772296;5009.468324830913;5716.514096301861;4830.29960655696;4901.534354236342;5195.818146364859;5581;5582.413846546055;5238.4237733850005;5536.889262214814;5566.97216513931;5575.776968921197;5600.831975040755;4890.706557606412;5187.273781670494;5347.51872331492;5426.131818908955;5478.740149610975;4419.447569053868;4556.407667003268;4598.494165641843;5507.752151153128;5558.666480011212;4731.526847315241;5431.565474762088;5479.53423958968;5484.670919744828;5622.95975244581 07:32:13 INFO - PROCESS | 1941 | |5;yui.html;700.2577268095815;761.9580923049233;770.5530792140559;813.5118928642815;823.1069289820545;15365;15506.457273995413;15928.601784955383;15956.601084972866;15975.840241597565;14520.128792272444;14521.49174778883;14539.781903271432;14618.12291262525;14892.255387230649;650.3236633900738;671.8824205763996;833.1751565949696;833.7623777223507;861.8922634670666;633.6673246545578;804.4770898915722;808.3613944983456;811.5090370325956;812.0864027968536;895.3866601378045;896.6906417286059;897.1701176411825;899.1413200393641;899.2266650680409;684.650828077679;892.848215803315;893.5175005497056;893.5264309915755;895.8611415230682;529.0582762682432;529.954953828925;530.7187190788889;530.7399374306561;662.8077857421342;14940.32768525419;15376.769348459782;15386.461473848474;15388.30752616135;15397.999130056622;13617.659558511017;13866.098703584235;13918.58244252674;13967.79048314276;14129.222892740896;10611.84082238767;12443.626691199279;12459.875401245961;12543.184692994926;13062.738745225042;4427.712198707074;4473.4855491618555;4570.200214962369;4584.243599806028;4703.012367402849;112259.43870280679;112733;113408.29887551694;113746;116626;10851;10890.782184356269;10912.399818009926;10968.725781855517;11113.751348996442;17886.731699024524;21623.67564486534;21623.783762162493;21655.56688866214;21659.350219493124;7903.249191326815;7927.128015918244;7986.560739159402;8155.510669359857;8163.673453061811;3819.7708137511836;4183.330667093251;4423.601875831127;4437.911241775147;4625.861224163281;3364;3411.778234414755;3435.3988052091286;3472.0625431133453;3481.129717570607;663.2174034639172;672.3646154384157;676.9830754231184;717.2612209424301;721.7473884140509;596.7881402102191;623.865868838202;678.6799674632791;678.710450144729;680.5610381304031;8499.957500212584;8993.775155621031;10175;10282.897171028344;10350.585976561004;573.8852229554022;629.4618101523132;646.1664452855857;648.9513286503532;696.2376198063113;4596.862094137181;4726.314684370747;4731.787069581877;4766.856994290177;4770.236762118045;86713;86885.56557217173;97395;153308;160122;128885;130994;131706;133021;134334;109853.90146098596;112561;114593.4270328643;114628.85371146348;116339.41830290963;8216.178382161735;8986.910130898606;9025.503597302211;9061.954690226507;9159.35884488106;60949.69525152346;62202.68898655478;62249.37750622436;63018.36981630125;65274.34725652872;93492.50866115763;97784;98272.7689516988;99543;100919;11443.828342575034;11678.182527223013;11746.119041071783;11814.704632384259;12372.505099795908;12911.483540658268;13124.671883202996;13215.14101583394;13361.26513041753;13427.932860335635;11832;11841;12587.307698076695;12656.17734847232;12674.873251267369;10260.384376937407;11141.053010494199;12037.45831437605;12193.817092743438;12466.189697669623;11991.289571903166;12130.332831693988;12357.567485137977;12436.440360183995;12538.749225015632;8368.623411946599;9985;10025.799484010426;10312.226583006455;10332.036912986809;7180.754342876794;8489.660413583388;9387.718368448957;9999.950000249952;10488 07:32:13 INFO - PROCESS | 1941 | __end_tp_report 07:32:13 INFO - PROCESS | 1941 | __start_cc_report 07:32:13 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,1404 07:32:13 INFO - PROCESS | 1941 | __end_cc_report 07:32:13 INFO - PROCESS | 1941 | __startTimestamp1475159533636__endTimestamp 07:32:13 INFO - PROCESS | 1941 | 07:32:13 INFO - PROCESS | 1941 | ------- Summary: start ------- 07:32:13 INFO - PROCESS | 1941 | Number of tests: 6 07:32:13 INFO - PROCESS | 1941 | 07:32:13 INFO - PROCESS | 1941 | [#0] dojo.html Cycles:165 Average:5712.76 Median:5911.50 stddev:664.55 (11.2%) stddev-sans-first:624.38 07:32:13 INFO - PROCESS | 1941 | Values: 2741.7 3143.0 3298.2 3325.4 3384.4 5509.8 5600.2 5714.5 5736.8 5751.1 5424.4 5624.9 5645.0 5662.7 5778.8 5068.8 5116.9 5176.2 5204.3 5210.7 5233.0 5313.3 5319.3 5320.3 5325.8 5274.5 5308.4 5308.7 5349.3 5366.4 5114.4 5125.0 5185.3 5195.8 5196.2 5114.6 5135.9 5174.8 5187.3 5271.1 5042.9 5610.0 5669.4 5683.6 5689.3 6107.6 6130.6 6143.5 6238.9 6263.0 6150.8 6157.8 6234.5 6298.2 6308.8 5785.7 5789.2 5790.4 5810.4 5830.6 6121.1 6264.2 6273.1 6286.8 6309.7 5096.9 6079.6 6105.9 6155.1 6180.9 6247.1 6267.2 6272.7 6278.3 6283.6 6176.4 6207.9 6232.0 6245.9 6247.8 5373.8 6063.8 6117.8 6122.2 6253.9 4909.8 4915.7 4921.4 4926.1 4928.1 4359.7 4395.0 4465.6 4486.1 4502.1 4777.8 5665.0 5707.8 5811.9 5830.5 6286.5 6286.9 6292.3 6298.2 6298.2 5722.6 5763.2 5765.7 5774.6 5819.5 5305.6 5339.1 5351.6 5359.7 5387.8 6242.3 6297.3 6302.4 6302.9 6314.2 6275.1 6285.6 6293.4 6298.8 6301.3 5315.2 6185.3 6301.7 6309.2 6326.8 6112.5 6118.0 6119.1 6137.5 6206.5 6270.7 6280.3 6285.7 6286.3 6301.7 6071.7 6084.6 6103.1 6117.4 6141.5 5957.3 6042.2 6047.7 6079.2 6111.0 5903.1 5916.4 5955.2 5971.2 5973.7 6113.3 6127.4 6130.6 6131.8 6139.9 5797.9 5866.6 5892.4 5911.5 5959.1 07:32:13 INFO - PROCESS | 1941 | 07:32:13 INFO - PROCESS | 1941 | [#1] ext.html Cycles:180 Average:63110.17 Median:19244.74 stddev:134557.86 (699.2%) stddev-sans-first:134853.10 07:32:13 INFO - PROCESS | 1941 | Values: 458.3 470.4 512.9 520.1 550.1 29097.3 29656.0 30738.4 30777.1 38937.6 38688.0 40842.2 40878.0 40903.8 40930.6 9632.1 9819.6 9821.3 9835.9 9860.6 8706.8 8916.9 8918.7 9572.3 9700.3 8821.6 8879.5 11921.8 12240.8 12251.6 12134.2 12188.7 12196.0 12206.7 12209.1 11757.5 11845.3 11845.7 11855.9 11871.9 30499.1 30778.7 30852.5 31961.5 38879.4 3229.7 3257.7 3381.2 3401.8 3834.2 29446.6 30472.0 30861.8 30862.0 31397.7 3148.8 3257.6 3305.8 3335.5 3371.2 481201.0 481216.0 483331.0 484338.0 484505.0 54412.2 54843.5 54866.2 54889.5 54947.7 58298.8 58395.4 58445.0 58475.1 58594.4 15195.8 15247.9 15521.9 15529.2 15543.9 12213.9 12305.1 12310.1 12325.3 12343.6 13558.2 14827.3 14846.4 14910.4 15117.4 5209.6 5373.7 6587.7 6589.3 6628.7 850.3 959.4 973.4 1008.9 1029.5 25419.5 29320.5 29347.6 29503.9 29710.6 877.6 1035.5 1049.8 1069.6 1124.4 13808.7 15056.3 15286.0 15499.5 15557.3 586235.0 602325.0 602947.0 603453.0 603608.0 52382.7 52542.7 52580.7 52619.9 52660.5 1205.4 1256.9 1342.9 1370.9 1409.5 28901.1 30596.8 30600.2 30891.7 31206.5 45885.3 46501.0 46521.5 46629.5 46688.5 412617.0 412672.0 413318.0 413516.0 414089.0 24489.8 25129.0 25428.9 26060.7 37397.1 45247.0 45349.5 45362.5 45420.5 45789.0 32009.8 36759.8 37241.3 37317.8 38420.8 35087.6 37142.3 38445.8 38699.4 38738.6 36043.3 36419.6 37145.8 37173.8 37374.8 31558.0 31836.8 33318.0 33438.3 33894.7 24457.3 24470.5 24683.6 24931.1 25033.7 07:32:13 INFO - PROCESS | 1941 | 07:32:13 INFO - PROCESS | 1941 | [#2] jquery.html Cycles:180 Average:33389.07 Median:3156.15 stddev:90295.41 (2860.9%) stddev-sans-first:90514.60 07:32:13 INFO - PROCESS | 1941 | Values: 333.6 368.4 371.2 380.2 400.8 14054.9 15273.8 20418.5 20468.9 21129.8 20440.5 20489.3 20631.2 21011.3 21292.8 5837.6 6187.9 6188.3 6194.6 6199.6 3480.7 4368.5 6059.4 6741.3 6767.7 6505.7 6788.5 6814.3 6830.3 6835.2 6696.3 6704.2 6738.4 6748.1 6778.2 5567.2 5729.1 5780.3 5810.3 5817.8 25748.2 25831.0 25913.9 25967.0 26126.9 2185.1 2378.8 2769.8 3105.6 3206.7 15048.1 15583.5 15615.5 16062.8 16349.0 1837.5 1878.0 1884.2 1898.9 1901.4 112116.4 117329.3 117387.0 117528.0 117714.0 9249.3 9798.8 9943.1 10229.8 10434.1 12456.8 12963.0 12976.8 13198.0 14087.4 7005.3 8161.7 8224.6 8288.2 8462.5 6623.3 8198.4 8217.3 8384.0 8416.4 4399.9 4456.3 4463.9 4541.2 4544.5 1609.9 1831.7 1855.5 1874.6 1883.4 876.8 887.0 973.8 994.4 1006.9 19916.8 20328.9 20726.8 20815.5 21102.4 798.0 887.6 924.1 952.8 958.6 10080.5 10415.3 10570.1 10892.5 10972.1 533295.0 535120.0 536976.0 538237.0 543576.0 125473.4 127312.4 128031.4 130734.0 131512.0 946.6 954.1 966.4 966.5 967.7 17817.4 18259.0 18318.7 18692.9 18814.5 52427.9 53036.0 53506.7 53993.2 54177.7 74923.0 77285.6 79234.0 79550.0 83155.6 7109.5 7353.2 7920.3 8113.0 8358.5 10963.4 12030.4 12308.9 12388.3 13582.8 10127.3 11232.6 11273.8 11470.0 11669.1 11226.6 11476.5 11635.3 11761.6 11806.0 11348.2 11472.3 11898.0 11950.6 12049.7 7403.7 7460.9 7571.3 7590.1 7599.9 9033.7 11250.7 11527.4 11649.8 11696.1 07:32:13 INFO - PROCESS | 1941 | 07:32:13 INFO - PROCESS | 1941 | [#3] mootools.html Cycles:180 Average:5592.91 Median:4968.21 stddev:3167.10 (63.7%) stddev-sans-first:3159.49 07:32:13 INFO - PROCESS | 1941 | Values: 1291.6 1512.4 1517.6 1618.6 1750.2 7950.1 8287.5 8648.8 8919.2 8951.4 8723.5 8732.7 8832.6 8836.4 8837.2 2335.4 2336.8 2341.3 2344.1 2364.2 2319.3 2351.2 2359.1 2366.6 2367.8 1950.5 2320.0 2344.0 2362.7 2365.1 2311.1 2327.6 2347.4 2352.3 2360.1 2324.7 2336.0 2337.5 2338.7 2339.8 8651.8 8765.6 8804.7 8870.6 8985.3 5305.6 5448.4 5457.9 5467.4 5478.4 3112.2 3660.9 3673.5 3735.8 3756.8 1062.0 1065.6 1072.6 1091.7 1098.5 11547.2 11609.9 11703.5 11713.4 11778.4 4351.7 4425.3 4600.7 4602.7 4629.4 9204.5 9215.5 9221.2 9224.3 9248.2 5244.6 5244.6 5251.7 5262.1 5277.7 4165.4 4367.6 4403.3 4532.7 4929.7 3704.5 3756.5 3814.0 3815.8 3817.5 1132.3 1133.5 1137.4 1145.9 1150.8 770.9 791.3 843.1 882.8 907.9 5573.9 5603.7 5705.5 5823.0 5977.9 418.7 421.4 425.6 433.8 435.4 3837.0 3855.5 3953.6 3960.6 3999.5 10481.8 10710.6 10862.9 10865.1 10889.0 9167.1 9754.0 10776.9 10788.5 10867.8 8037.8 8127.0 8210.3 8214.6 8223.3 5657.4 5769.4 5785.5 5823.5 5849.1 8253.3 9843.0 10015.7 10046.5 10092.4 8325.8 9289.3 10327.2 10328.9 10352.8 6569.8 6743.8 6838.1 6971.9 7035.5 6750.9 8278.8 8311.2 8414.6 8478.4 7482.0 7514.0 7534.7 7590.0 7604.4 7278.0 7294.0 7352.9 7380.8 7418.4 6281.4 6698.1 6763.0 6923.3 7013.3 4864.3 4963.8 4988.2 5018.8 5092.1 4972.6 5068.3 5079.4 5127.4 5127.7 07:32:13 INFO - PROCESS | 1941 | 07:32:13 INFO - PROCESS | 1941 | [#4] prototype.html Cycles:180 Average:4457.08 Median:4952.32 stddev:1367.39 (27.6%) stddev-sans-first:1335.14 07:32:13 INFO - PROCESS | 1941 | Values: 299.4 299.8 308.4 314.6 314.7 4942.4 5352.9 5385.4 5402.0 5422.1 4571.0 4942.5 5128.2 5200.9 5324.6 3396.7 3746.0 4016.7 4078.4 4095.4 3412.5 4087.9 4090.9 4115.7 4128.2 3942.3 4057.8 4077.5 4084.4 4098.7 3247.5 4069.0 4069.5 4095.7 4115.4 3006.5 3514.9 3531.8 3558.6 3592.2 4969.5 5178.4 5182.4 5185.4 5222.4 4049.7 4356.6 4626.3 4636.2 4719.4 5299.9 5337.8 5436.6 5436.7 5466.4 3866.0 3869.9 3906.6 3928.3 3932.0 5217.9 5451.4 5828.5 5845.7 5939.3 3320.0 3447.7 3937.5 3954.4 4005.6 3135.4 3527.4 3590.5 3874.0 4029.9 4227.2 4494.8 4545.0 4677.4 4696.2 4915.6 5172.0 5542.3 5561.7 5607.9 1156.8 1239.5 1306.9 1331.8 1344.5 795.4 831.7 851.2 851.7 905.5 4769.0 4826.6 5204.3 5231.7 5303.8 5429.4 5562.6 5649.4 5677.3 5753.7 4798.5 5080.1 5192.5 5221.6 5238.2 2449.8 2597.5 2853.3 2919.2 2945.0 4645.5 4893.7 5764.3 5774.3 5775.7 4801.5 5102.4 5868.4 5904.5 5935.8 5388.2 5830.6 5948.7 5961.0 5983.2 4678.6 5032.7 5174.8 5290.9 5672.8 5442.6 5622.7 5622.7 5630.4 5644.6 4737.8 5466.5 5595.8 5835.9 5840.4 3711.5 3776.8 3798.6 3799.8 3846.7 4624.8 4919.3 4962.1 5009.5 5716.5 4830.3 4901.5 5195.8 5581.0 5582.4 5238.4 5536.9 5567.0 5575.8 5600.8 4890.7 5187.3 5347.5 5426.1 5478.7 4419.4 4556.4 4598.5 5507.8 5558.7 4731.5 5431.6 5479.5 5484.7 5623.0 07:32:13 INFO - PROCESS | 1941 | 07:32:13 INFO - PROCESS | 1941 | [#5] yui.html Cycles:180 Average:24187.06 Median:4001.55 stddev:38584.49 (964.2%) stddev-sans-first:38652.43 07:32:13 INFO - PROCESS | 1941 | Values: 700.3 762.0 770.6 813.5 823.1 15365.0 15506.5 15928.6 15956.6 15975.8 14520.1 14521.5 14539.8 14618.1 14892.3 650.3 671.9 833.2 833.8 861.9 633.7 804.5 808.4 811.5 812.1 895.4 896.7 897.2 899.1 899.2 684.7 892.8 893.5 893.5 895.9 529.1 530.0 530.7 530.7 662.8 14940.3 15376.8 15386.5 15388.3 15398.0 13617.7 13866.1 13918.6 13967.8 14129.2 10611.8 12443.6 12459.9 12543.2 13062.7 4427.7 4473.5 4570.2 4584.2 4703.0 112259.4 112733.0 113408.3 113746.0 116626.0 10851.0 10890.8 10912.4 10968.7 11113.8 17886.7 21623.7 21623.8 21655.6 21659.4 7903.2 7927.1 7986.6 8155.5 8163.7 3819.8 4183.3 4423.6 4437.9 4625.9 3364.0 3411.8 3435.4 3472.1 3481.1 663.2 672.4 677.0 717.3 721.7 596.8 623.9 678.7 678.7 680.6 8500.0 8993.8 10175.0 10282.9 10350.6 573.9 629.5 646.2 649.0 696.2 4596.9 4726.3 4731.8 4766.9 4770.2 86713.0 86885.6 97395.0 153308.0 160122.0 128885.0 130994.0 131706.0 133021.0 134334.0 109853.9 112561.0 114593.4 114628.9 116339.4 8216.2 8986.9 9025.5 9062.0 9159.4 60949.7 62202.7 62249.4 63018.4 65274.3 93492.5 97784.0 98272.8 99543.0 100919.0 11443.8 11678.2 11746.1 11814.7 12372.5 12911.5 13124.7 13215.1 13361.3 13427.9 11832.0 11841.0 12587.3 12656.2 12674.9 10260.4 11141.1 12037.5 12193.8 12466.2 11991.3 12130.3 12357.6 12436.4 12538.7 8368.6 9985.0 10025.8 10312.2 10332.0 7180.8 8489.7 9387.7 10000.0 10488.0 07:32:13 INFO - PROCESS | 1941 | -------- Summary: end -------- 07:32:13 INFO - PROCESS | 1941 | 07:32:14 INFO - TEST-INFO | 1941: exit 0 07:32:14 INFO - TEST-OK | dromaeo_css | took 1117805ms 07:32:14 INFO - TEST-START | kraken 07:32:14 INFO - operating with platform_type : mac_ 07:32:14 INFO - Initialising browser for kraken test... 07:32:14 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpmswbEY/profile http://localhost:56160/getInfo.html) 07:32:16 INFO - PROCESS | 1945 | __metrics Screen width/height:1600/1200 07:32:16 INFO - PROCESS | 1945 | colorDepth:24 07:32:16 INFO - PROCESS | 1945 | Browser inner width/height: 1024/689 07:32:16 INFO - PROCESS | 1945 | __metrics 07:32:16 INFO - PROCESS | 1945 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:32:16 INFO - PROCESS | 1945 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:32:17 INFO - TEST-INFO | 1945: exit 0 07:32:17 INFO - Browser initialized. 07:32:17 INFO - Running cycle 1/1 for kraken test... 07:32:17 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpmswbEY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:32:29 INFO - PROCESS | 1946 | RSS: Main: 236068864 07:32:29 INFO - PROCESS | 1946 | 07:32:32 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56160/tests/kraken/driver.html?testName=audio-beat-detection) 07:32:33 INFO - PROCESS | 1946 | RSS: Main: 294768640 07:32:33 INFO - PROCESS | 1946 | 07:32:35 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56160/tests/kraken/driver.html?testName=audio-dft) 07:32:36 INFO - PROCESS | 1946 | RSS: Main: 293265408 07:32:36 INFO - PROCESS | 1946 | 07:32:39 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56160/tests/kraken/driver.html?testName=audio-fft) 07:32:40 INFO - PROCESS | 1946 | RSS: Main: 384249856 07:32:40 INFO - PROCESS | 1946 | 07:32:42 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56160/tests/kraken/driver.html?testName=audio-oscillator) 07:32:42 INFO - PROCESS | 1946 | RSS: Main: 284999680 07:32:42 INFO - PROCESS | 1946 | 07:32:45 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56160/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:32:45 INFO - PROCESS | 1946 | RSS: Main: 290238464 07:32:45 INFO - PROCESS | 1946 | 07:32:48 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56160/tests/kraken/driver.html?testName=imaging-darkroom) 07:32:48 INFO - PROCESS | 1946 | RSS: Main: 328212480 07:32:48 INFO - PROCESS | 1946 | 07:32:51 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56160/tests/kraken/driver.html?testName=imaging-desaturate) 07:32:52 INFO - PROCESS | 1946 | RSS: Main: 329334784 07:32:52 INFO - PROCESS | 1946 | 07:32:55 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56160/tests/kraken/driver.html?testName=json-parse-financial) 07:32:55 INFO - PROCESS | 1946 | RSS: Main: 327548928 07:32:55 INFO - PROCESS | 1946 | 07:32:57 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56160/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:32:58 INFO - PROCESS | 1946 | RSS: Main: 300363776 07:32:58 INFO - PROCESS | 1946 | 07:32:59 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56160/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:33:00 INFO - PROCESS | 1946 | RSS: Main: 306462720 07:33:00 INFO - PROCESS | 1946 | 07:33:02 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56160/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:33:03 INFO - PROCESS | 1946 | RSS: Main: 308703232 07:33:03 INFO - PROCESS | 1946 | 07:33:06 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56160/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:33:07 INFO - PROCESS | 1946 | RSS: Main: 316547072 07:33:07 INFO - PROCESS | 1946 | 07:33:10 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56160/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:33:10 INFO - PROCESS | 1946 | RSS: Main: 318849024 07:33:10 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:56160/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56160/tests/kraken/driver.html?testName=ai-astar) 07:33:12 INFO - PROCESS | 1946 | RSS: Main: 362545152 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | __start_tp_report 07:33:12 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 07:33:12 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 07:33:12 INFO - PROCESS | 1946 | |i|pagename|runs| 07:33:12 INFO - PROCESS | 1946 | |0;ai-astar;115;114;115;113;114;115;113;114;113;115 07:33:12 INFO - PROCESS | 1946 | |1;audio-beat-detection;148;112;108;146;108;110;110;111;138;108 07:33:12 INFO - PROCESS | 1946 | |2;audio-dft;182;183;185;186;183;183;178;182;183;183 07:33:12 INFO - PROCESS | 1946 | |3;audio-fft;81;59;59;89;60;60;59;60;83;58 07:33:12 INFO - PROCESS | 1946 | |4;audio-oscillator;87;96;126;100;68;70;92;68;69;90 07:33:12 INFO - PROCESS | 1946 | |5;imaging-gaussian-blur;87;85;87;84;88;86;91;85;87;84 07:33:12 INFO - PROCESS | 1946 | |6;imaging-darkroom;93;94;93;94;93;93;94;95;93;92 07:33:12 INFO - PROCESS | 1946 | |7;imaging-desaturate;84;102;121;130;131;84;85;84;83;87 07:33:12 INFO - PROCESS | 1946 | |8;json-parse-financial;56;55;53;53;53;56;55;54;54;57 07:33:12 INFO - PROCESS | 1946 | |9;json-stringify-tinderbox;65;76;66;76;72;70;69;71;72;72 07:33:12 INFO - PROCESS | 1946 | |10;stanford-crypto-aes;83;88;85;85;86;87;85;81;76;90 07:33:12 INFO - PROCESS | 1946 | |11;stanford-crypto-ccm;138;138;142;148;152;126;164;129;235;152 07:33:12 INFO - PROCESS | 1946 | |12;stanford-crypto-pbkdf2;170;168;172;174;168;168;171;171;168;166 07:33:12 INFO - PROCESS | 1946 | |13;stanford-crypto-sha256-iterative;67;68;69;69;68;67;66;67;68;66 07:33:12 INFO - PROCESS | 1946 | __end_tp_report 07:33:12 INFO - PROCESS | 1946 | __start_cc_report 07:33:12 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,2068 07:33:12 INFO - PROCESS | 1946 | __end_cc_report 07:33:12 INFO - PROCESS | 1946 | __startTimestamp1475159592736__endTimestamp 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | ------- Summary: start ------- 07:33:12 INFO - PROCESS | 1946 | Number of tests: 14 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.50 stddev:0.88 (0.8%) stddev-sans-first:0.87 07:33:12 INFO - PROCESS | 1946 | Values: 115.0 114.0 115.0 113.0 114.0 115.0 113.0 114.0 113.0 115.0 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#1] audio-beat-detection Cycles:10 Average:119.90 Median:111.50 stddev:16.87 (15.1%) stddev-sans-first:14.51 07:33:12 INFO - PROCESS | 1946 | Values: 148.0 112.0 108.0 146.0 108.0 110.0 110.0 111.0 138.0 108.0 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.00 stddev:2.10 (1.1%) stddev-sans-first:2.20 07:33:12 INFO - PROCESS | 1946 | Values: 182.0 183.0 185.0 186.0 183.0 183.0 178.0 182.0 183.0 183.0 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#3] audio-fft Cycles:10 Average:66.80 Median:60.00 stddev:12.27 (20.5%) stddev-sans-first:11.89 07:33:12 INFO - PROCESS | 1946 | Values: 81.0 59.0 59.0 89.0 60.0 60.0 59.0 60.0 83.0 58.0 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#4] audio-oscillator Cycles:10 Average:86.60 Median:78.50 stddev:18.67 (23.8%) stddev-sans-first:19.81 07:33:12 INFO - PROCESS | 1946 | Values: 87.0 96.0 126.0 100.0 68.0 70.0 92.0 68.0 69.0 90.0 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:87.00 stddev:2.12 (2.4%) stddev-sans-first:2.24 07:33:12 INFO - PROCESS | 1946 | Values: 87.0 85.0 87.0 84.0 88.0 86.0 91.0 85.0 87.0 84.0 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.50 stddev:0.84 (0.9%) stddev-sans-first:0.88 07:33:12 INFO - PROCESS | 1946 | Values: 93.0 94.0 93.0 94.0 93.0 93.0 94.0 95.0 93.0 92.0 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#7] imaging-desaturate Cycles:10 Average:99.10 Median:84.00 stddev:20.41 (24.3%) stddev-sans-first:20.90 07:33:12 INFO - PROCESS | 1946 | Values: 84.0 102.0 121.0 130.0 131.0 84.0 85.0 84.0 83.0 87.0 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.43 (2.6%) stddev-sans-first:1.42 07:33:12 INFO - PROCESS | 1946 | Values: 56.0 55.0 53.0 53.0 53.0 56.0 55.0 54.0 54.0 57.0 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:72.00 stddev:3.63 (5.0%) stddev-sans-first:3.17 07:33:12 INFO - PROCESS | 1946 | Values: 65.0 76.0 66.0 76.0 72.0 70.0 69.0 71.0 72.0 72.0 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:85.50 stddev:3.92 (4.6%) stddev-sans-first:4.12 07:33:12 INFO - PROCESS | 1946 | Values: 83.0 88.0 85.0 85.0 86.0 87.0 85.0 81.0 76.0 90.0 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#11] stanford-crypto-ccm Cycles:10 Average:152.40 Median:150.00 stddev:31.17 (20.8%) stddev-sans-first:32.62 07:33:12 INFO - PROCESS | 1946 | Values: 138.0 138.0 142.0 148.0 152.0 126.0 164.0 129.0 235.0 152.0 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:170.50 stddev:2.41 (1.4%) stddev-sans-first:2.55 07:33:12 INFO - PROCESS | 1946 | Values: 170.0 168.0 172.0 174.0 168.0 168.0 171.0 171.0 168.0 166.0 07:33:12 INFO - PROCESS | 1946 | 07:33:12 INFO - PROCESS | 1946 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.08 (1.6%) stddev-sans-first:1.13 07:33:12 INFO - PROCESS | 1946 | Values: 67.0 68.0 69.0 69.0 68.0 67.0 66.0 67.0 68.0 66.0 07:33:12 INFO - PROCESS | 1946 | -------- Summary: end -------- 07:33:12 INFO - PROCESS | 1946 | 07:33:13 INFO - TEST-INFO | 1946: exit 0 07:33:13 INFO - TEST-OK | kraken | took 59381ms 07:33:13 INFO - SUITE-END | took 1177s 07:33:13 INFO - Completed test suite (00:19:37) 07:33:13 INFO - javascript benchmark 07:33:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2741.697693595542, 3142.9999999999986, 3298.1589694627874, 3325.418051840927, 3384.4077286474876, 5509.807156749514, 5600.215969764236, 5714.485696287334, 5736.827895163152, 5751.079827227639, 5424.376196737377, 5624.943750562504, 5644.971775141129, 5662.688552129631, 5778.8266352009505, 5068.834168141329, 5116.923246151311, 5176.171812509999, 5204.32343795306, 5210.739463026834, 5232.973835130819, 5313.256144139824, 5319.281896943921, 5320.334958130234, 5325.840224793263, 5274.498922602346, 5308.389535203443, 5308.681479111227, 5349.251104845324, 5366.356037275533, 5114.386273647149, 5125.026245013435, 5185.274061631375, 5195.818146364897, 5196.246544251062, 5114.616403769732, 5135.922961155586, 5174.767135478913, 5187.325647665779, 5271.103912334912, 5042.873928151826, 5610.018246806792, 5669.40471250521, 5683.60214784961, 5689.317281926154, 6107.603005804608, 6130.581587307925, 6143.481172719164, 6238.9376106238815, 6263.0, 6150.84622884427, 6157.830012297649, 6234.501239900797, 6298.181236439279, 6308.810735677937, 5785.739641716133, 5789.189513468118, 5790.36306006339, 5810.360860305405, 5830.56270779689, 6121.081837724288, 6264.248290205113, 6273.12176295319, 6286.779962701284, 6309.716062777186, 5096.898062038813, 6079.604825686316, 6105.938940610537, 6155.138280640714, 6180.8763824723255, 6247.062940558862, 6267.2165979252595, 6272.717727702265, 6278.30938596754, 6283.59156654816, 6176.4441200292185, 6207.906881396782, 6231.9688401557705, 6245.906311405332, 6247.781327653511, 5373.785048598091, 6063.757449701962, 6117.766507567544, 6122.215079804129, 6253.874922501524, 4909.7790599423115, 4915.680480768739, 4921.384826896638, 4926.1379258629595, 4928.063667903087, 4359.651227901761, 4394.978025109854, 4465.575770301816, 4486.12520558488, 4502.099580083931, 4777.8088876445245, 5665.008623490955, 5707.771689132388, 5811.912821307684, 5830.47525722687, 6286.465650419674, 6286.874262514724, 6292.339304373067, 6298.212723409574, 6298.244210694655, 5722.570807189478, 5763.221965034751, 5765.682887441231, 5774.566907481955, 5819.476247137778, 5305.575553955674, 5339.11904535755, 5351.598630102758, 5359.651622644516, 5387.757550910218, 6242.344553821874, 6297.307296197323, 6302.369763023844, 6302.905456418157, 6314.179156709598, 6275.090111933653, 6285.622862628257, 6293.433590976834, 6298.811035668938, 6301.306856245901, 5315.202719592092, 6185.319614842274, 6301.716422761078, 6309.211348581427, 6326.810195693953, 6112.50348092447, 6118.0, 6119.07606531266, 6137.46891820379, 6206.472449841814, 6270.7491700331475, 6280.309165991646, 6285.654289014148, 6286.339934306832, 6301.684915754102, 6071.696415179312, 6084.6349219047, 6103.05402662588, 6117.418845209698, 6141.477974372228, 5957.285125784933, 6042.1843051187525, 6047.727852246571, 6079.209702738615, 6111.04482360895, 5903.085021821625, 5916.437938395846, 5955.166276721349, 5971.193888824953, 5973.731182096903, 6113.266408031065, 6127.387261273837, 6130.570860039755, 6131.816045518642, 6139.938600613937, 5797.8550536236935, 5866.6186697864505, 5892.35184129754, 5911.497522710618, 5959.10613407975], "value": 5668.223839593145, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.3399904138042, 470.35326426164016, 512.941011783645, 520.1391696741894, 550.0923476264161, 29097.272568185806, 29655.96204132856, 30738.385232295368, 30777.076687699348, 38937.61062389375, 38688.0, 40842.183156336854, 40878.0, 40903.79548102263, 40930.59069409305, 9632.084951929555, 9819.558119884587, 9821.2634052446, 9835.901640983606, 9860.556274967643, 8706.825863482727, 8916.866247006301, 8918.73243802687, 9572.32993690442, 9700.272479564026, 8821.60302786376, 8879.51162686052, 11921.821172682417, 12240.755184896298, 12251.571195008177, 12134.211276267059, 12188.695282617917, 12195.963343115845, 12206.694832629166, 12209.145359824815, 11757.470913808898, 11845.348505832177, 11845.703857403547, 11855.940720296343, 11871.940640296829, 30499.08502744921, 30778.692213077808, 30852.53721194184, 31961.52057719136, 38879.41680874789, 3229.7254733347695, 3257.7068063874367, 3381.239221175216, 3401.829908504565, 3834.1564855731694, 29446.558301625493, 30472.0, 30861.845690771628, 30862.0, 31397.686023139704, 3148.8268145252, 3257.5765150530515, 3305.801651900894, 3335.483000134983, 3371.2414706691043, 481201.0, 481216.0, 483331.0, 484337.99999999645, 484505.0, 54412.183817242774, 54843.45156548463, 54866.17700734492, 54889.451105489235, 54947.72526137344, 58298.834023319294, 58395.416045838996, 58445.0, 58475.122873156935, 58594.4140558589, 15195.772063419057, 15247.923760381127, 15521.922390387976, 15529.223538823013, 15543.922280388751, 12213.877861221274, 12305.138640295094, 12310.138290319592, 12325.260484370967, 12343.629691109281, 13558.186508809504, 14827.258637068104, 14846.406143754344, 14910.403583856742, 15117.395304187929, 5209.583233341324, 5373.650712703662, 6587.670616469158, 6589.308122647149, 6628.668566571652, 850.2815121222557, 959.4099628728281, 973.4062222044547, 1008.9445080520642, 1029.459533744775, 25419.491610167694, 29320.492774695984, 29347.55978660322, 29503.85248073746, 29710.554341684892, 877.5963056993727, 1035.4615599888166, 1049.8267785815408, 1069.5882085397022, 1124.3759713359163, 13808.654783630489, 15056.32246548908, 15286.0, 15499.457518986781, 15557.29992150356, 586235.0, 602325.0, 602947.0, 603453.0, 603608.0, 52382.690432738724, 52542.73728631409, 52580.737096314275, 52619.94760104777, 52660.47339526556, 1205.4274219745726, 1256.8805963433642, 1342.9395677194354, 1370.8629137086411, 1409.5137177673687, 28901.132966011053, 30596.84701576478, 30600.234994125323, 30891.69108308888, 31206.531902021034, 45885.31172032489, 46501.0, 46521.53478465172, 46629.53370466252, 46688.53311466842, 412617.0, 412672.0, 413318.0, 413516.0, 414089.0, 24489.755102448747, 25128.994840206185, 25428.872855636342, 26060.73939260659, 37397.065073373386, 45247.0, 45349.505777182574, 45362.546374535836, 45420.545794541635, 45789.0, 32009.8399508001, 36759.77262614726, 37241.25517489689, 37317.81341093277, 38420.807895960344, 35087.64912350844, 37142.25715485621, 38445.807770960964, 38699.41950870683, 38738.6126138735, 36043.27913441769, 36419.63580364268, 37145.81427092847, 37173.814130929175, 37374.8131259342, 31558.0, 31836.84081579577, 33318.0, 33438.33123337471, 33894.66105339014, 24457.26628201157, 24470.51058978846, 24683.629745553473, 24931.082098294508, 25033.74966250387], "value": 19144.41895189901, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.64133556426845, 368.39950880065487, 371.2056199732573, 380.2452132516954, 400.82163437270424, 14054.859451405495, 15273.847261527382, 20418.489537761565, 20468.89765551172, 21129.788702113012, 20440.488987775312, 20489.282875099376, 20631.174753009862, 21011.2646057388, 21292.787072129275, 5837.59136860421, 6187.938120618781, 6188.257409110913, 6194.566380353354, 6199.628022318653, 3480.738944579154, 4368.5194628590825, 6059.3637668044885, 6741.325867413244, 6767.729290828361, 6505.674716264191, 6788.524803263774, 6814.28450012749, 6830.282820303871, 6835.248122706498, 6696.330366963289, 6704.195496540447, 6738.427233685143, 6748.122744043292, 6778.220504641955, 5567.220589117527, 5729.083346664555, 5780.277465316835, 5810.273715785527, 5817.825465236049, 25748.227553173434, 25831.0, 25913.870430647727, 25966.961321547114, 26126.86936565305, 2185.1368709359713, 2378.774016468433, 2769.75072243497, 3105.627324721048, 3206.6633003534525, 15048.097114173075, 15583.532494025198, 15615.453459128876, 16062.815815910843, 16349.019058856507, 1837.476319249021, 1877.9624407511774, 1884.1897983866932, 1898.8765730227492, 1901.3535397964758, 112116.43941780321, 117329.30682772765, 117387.0, 117528.0, 117714.0, 9249.260059195249, 9798.755031124136, 9943.055409736064, 10229.795404091876, 10434.113100386552, 12456.75086498265, 12963.027772917068, 12976.805347919788, 13198.010149238846, 14087.36606852694, 7005.334493223136, 8161.714339998071, 8224.588770561568, 8288.21261980111, 8462.534560599226, 6623.337666233435, 8198.42611017235, 8217.301529369952, 8384.0, 8416.368772342099, 4399.890002749957, 4456.264716321771, 4463.910721785546, 4541.182587134347, 4544.500104988449, 1609.871210303173, 1831.688612935804, 1855.5082903030707, 1874.6157037807218, 1883.4255552056754, 876.7676565710092, 886.9866951995725, 973.8247115519133, 994.3834822410151, 1006.9446180459998, 19916.800831991783, 20328.898355508423, 20726.792732072485, 20815.479613009797, 21102.366928992156, 797.9960100199462, 887.5784002598843, 924.066692640438, 952.8237276103943, 958.6213445688913, 10080.546375413123, 10415.323004004715, 10570.10154136907, 10892.455377231106, 10972.122230221563, 533295.0, 535120.0, 536976.0, 538237.0, 543576.0, 125473.37263313807, 127312.36343818222, 128031.35984320019, 130734.0, 131512.0, 946.6402766948515, 954.0697819625809, 966.4442945306477, 966.5070813884829, 967.7048500207363, 17817.376391826223, 18259.0, 18318.725219121457, 18692.878427294407, 18814.52963675919, 52427.854328740395, 53036.0, 53506.73246633742, 53993.19010214772, 54177.729111354194, 74923.0, 77285.61357193178, 79234.0, 79550.0, 83155.5842220785, 7109.502334836512, 7353.191148973716, 7920.317052570583, 8112.959435202786, 8358.540280284642, 10963.397013164033, 12030.398480076135, 12308.938455307667, 12388.256704597752, 13582.79625805594, 10127.341722787995, 11232.55069797199, 11273.830892536454, 11470.0, 11669.066474682175, 11226.607068752555, 11476.508053953266, 11635.301881887113, 11761.647150585497, 11805.996490298077, 11348.205625606128, 11472.254303470248, 11898.0, 11950.641480755592, 12049.698757531132, 7403.703851845866, 7460.925390746023, 7571.318581327707, 7590.0891892973195, 7599.900472754416, 9033.728988130366, 11250.718732031764, 11527.365994870363, 11649.825252621387, 11696.06431485496], "value": 9606.333181337755, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1291.6318849127988, 1512.357248169528, 1517.5523220649907, 1618.5791694159516, 1750.2036573359123, 7950.085740139885, 8287.544185069826, 8648.827023459535, 8919.24186444153, 8951.373403861733, 8723.47659140452, 8732.694355697551, 8832.60253288602, 8836.425632333894, 8837.248833849115, 2335.3577766114367, 2336.7546407627124, 2341.344423561397, 2344.120954642009, 2364.2316247219637, 2319.257837492003, 2351.2240960483, 2359.1153317505937, 2366.597678394677, 2367.822413318999, 1950.5123719070232, 2319.9768002320016, 2343.95312093758, 2362.6574146748712, 2365.06579900939, 2311.133325003124, 2327.60430726776, 2347.3779448446003, 2352.3413444235644, 2360.0795689680867, 2324.721033475994, 2335.988320058406, 2337.4974380508274, 2338.672585837984, 2339.847909885869, 8651.783705407352, 8765.649374025032, 8804.691835785781, 8870.645174193025, 8985.326100542485, 5305.628605997582, 5448.42791506894, 5457.863553411157, 5467.371252305956, 5478.397376288605, 3112.1752735525106, 3660.9450858237146, 3673.522442082539, 3735.794531300777, 3756.7745935243975, 1061.9628313009007, 1065.6430095917876, 1072.6299426697856, 1091.7434402915308, 1098.4727330881221, 11547.249428787187, 11609.883901160965, 11703.473343699554, 11713.355765432898, 11778.41107944608, 4351.73889566627, 4425.269830477935, 4600.6549508786975, 4602.677812553089, 4629.421322334708, 9204.539773011322, 9215.53922303882, 9221.17009469151, 9224.30817688676, 9248.213901818286, 5244.5542128918705, 5244.632875698664, 5251.737413129405, 5262.1317482615705, 5277.656952298088, 4165.354370072643, 4367.606915377632, 4403.3174857897075, 4532.7280363178315, 4929.704217746875, 3704.4628528863163, 3756.530433695788, 3813.9511634300234, 3815.771053736785, 3817.541894972621, 1132.2979752553306, 1133.54091592905, 1137.374444055766, 1145.9369734664674, 1150.8216226484908, 770.938324934004, 791.2799352589117, 843.0641987393988, 882.7881308485917, 907.9001309855911, 5573.888522229533, 5603.663780173202, 5705.486506214377, 5822.970885145547, 5977.940220597825, 418.6671596081123, 421.3532228029959, 425.61056633180294, 433.7831084457771, 435.4426334292111, 3837.0, 3855.4602355670227, 3953.6441720245316, 3960.6237407446247, 3999.4800675911947, 10481.790364192673, 10710.571577136827, 10862.891371086345, 10865.130789536817, 10889.0, 9167.08329167078, 9754.0, 10776.946115269373, 10788.51451684676, 10867.78264434707, 8037.7990550235545, 8127.024757029076, 8210.261076503144, 8214.63034163464, 8223.301019413417, 5657.43425657431, 5769.365369809234, 5785.479306862402, 5823.475887170175, 5849.064149736192, 8253.298469629908, 9843.0, 10015.749606259902, 10046.54790534413, 10092.444915529717, 8325.750227493185, 9289.256859451092, 10327.225458090523, 10328.948355258175, 10352.792944141225, 6569.835754106186, 6743.831404214934, 6838.111045564045, 6971.930280697331, 7035.542689725152, 6750.898736519054, 8278.83442331162, 8311.210435008665, 8414.579271036546, 8478.406511544134, 7481.962590187014, 7514.0, 7534.698612055676, 7590.013298271189, 7604.353629941517, 7277.963610181915, 7293.9635301823155, 7352.926560186025, 7380.77857664271, 7418.406527477897, 6281.434670879643, 6698.129243198353, 6762.96618516924, 6923.273056329013, 7013.263607321158, 4864.294677271847, 4963.8262660806695, 4988.152014157675, 5018.7741551631, 5092.134337162765, 4972.602191824718, 5068.265101560327, 5079.441261461162, 5127.3847138343635, 5127.717975511383], "value": 4402.256530863172, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.4236095516131, 299.7976365952981, 308.36015268318243, 314.63816610897464, 314.7387668235362, 4942.382202224722, 5352.946470535294, 5385.407605163429, 5402.000629883474, 5422.105352616818, 4570.971531405432, 4942.528735632184, 5128.230765385194, 5200.895982080366, 5324.627276090675, 3396.7112795412295, 3746.0, 4016.7305472407998, 4078.3678529827775, 4095.385692146172, 3412.5259187089287, 4087.9182416351655, 4090.8568200113, 4115.650169735576, 4128.153728485653, 3942.2903877302074, 4057.7768222747736, 4077.4903137107863, 4084.4281800547947, 4098.733582317155, 3247.5128730690467, 4068.9796551017057, 4069.47096877407, 4095.672346212296, 4115.444415003966, 3006.4738670732754, 3514.8594056237976, 3531.8057506837113, 3558.6441355864467, 3592.2218501841758, 4969.527894849984, 5178.40448348441, 5182.4299327073995, 5185.403678576937, 5222.373315202162, 4049.7367671101283, 4356.586124318216, 4626.30605409188, 4636.211843986531, 4719.433667959866, 5299.913517728856, 5337.839864804023, 5436.565074794007, 5436.728163591844, 5466.371367292772, 3865.9806700966597, 3869.9226015479535, 3906.609339066099, 3928.3125453045604, 3931.9803400983096, 5217.921731174036, 5451.37309209442, 5828.475437210672, 5845.7369418376275, 5939.346671866091, 3320.020593924798, 3447.6897079262485, 3937.4684417603257, 3954.426608141803, 4005.619466150711, 3135.3885992231753, 3527.3827080260853, 3590.5152804371596, 3874.0121269076203, 4029.879103626896, 4227.239096962577, 4494.797549200934, 4544.977275113603, 4677.368555245066, 4696.154692155376, 4915.631327650441, 5172.043172013189, 5542.251796007568, 5561.6663000220115, 5607.915881261784, 1156.849609550753, 1239.4856134703996, 1306.9281189534668, 1331.8468376136675, 1344.4554955243143, 795.3796039089519, 831.7338451695399, 851.1616058182685, 851.7104184577273, 905.5064989580694, 4768.976155119202, 4826.613870890391, 5204.323437953042, 5231.659942103751, 5303.761330740126, 5429.416585053846, 5562.610617256834, 5649.406812284733, 5677.318721753416, 5753.705416281303, 4798.528757689793, 5080.145746802465, 5192.532672059458, 5221.55616772571, 5238.188080847474, 2449.755024497536, 2597.519369002636, 2853.3036280714527, 2919.1972207642643, 2945.0415473709745, 4645.465771436328, 4893.73084480357, 5764.337101233412, 5774.335951365562, 5775.711214439261, 4801.51984801517, 5102.438731739506, 5868.354481007086, 5904.527637788967, 5935.821925342247, 5388.24564560954, 5830.621009634445, 5948.702564871825, 5961.0, 5983.1623572699855, 4678.602318802804, 5032.723200224023, 5174.783925777515, 5290.920636190383, 5672.7737835312955, 5442.605253688364, 5622.662640241599, 5622.746976385991, 5630.380658127685, 5644.6330988485615, 4737.8341758038305, 5466.453354664502, 5595.804146854841, 5835.891180675658, 5840.386759390372, 3711.4803927450716, 3776.811159442072, 3798.639129282714, 3799.7720136790776, 3846.6538011577973, 4624.768761561976, 4919.286703428054, 4962.106820772296, 5009.468324830913, 5716.514096301861, 4830.29960655696, 4901.534354236342, 5195.818146364859, 5581.0, 5582.413846546055, 5238.4237733850005, 5536.889262214814, 5566.97216513931, 5575.776968921197, 5600.831975040755, 4890.706557606412, 5187.273781670494, 5347.51872331492, 5426.131818908955, 5478.740149610975, 4419.447569053868, 4556.407667003268, 4598.494165641843, 5507.752151153128, 5558.666480011212, 4731.526847315241, 5431.565474762088, 5479.53423958968, 5484.670919744828, 5622.95975244581], "value": 4019.5426663328717, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.2577268095815, 761.9580923049233, 770.5530792140559, 813.5118928642815, 823.1069289820545, 15365.0, 15506.457273995413, 15928.601784955383, 15956.601084972866, 15975.840241597565, 14520.128792272444, 14521.49174778883, 14539.781903271432, 14618.12291262525, 14892.255387230649, 650.3236633900738, 671.8824205763996, 833.1751565949696, 833.7623777223507, 861.8922634670666, 633.6673246545578, 804.4770898915722, 808.3613944983456, 811.5090370325956, 812.0864027968536, 895.3866601378045, 896.6906417286059, 897.1701176411825, 899.1413200393641, 899.2266650680409, 684.650828077679, 892.848215803315, 893.5175005497056, 893.5264309915755, 895.8611415230682, 529.0582762682432, 529.954953828925, 530.7187190788889, 530.7399374306561, 662.8077857421342, 14940.32768525419, 15376.769348459782, 15386.461473848474, 15388.30752616135, 15397.999130056622, 13617.659558511017, 13866.098703584235, 13918.58244252674, 13967.79048314276, 14129.222892740896, 10611.84082238767, 12443.626691199279, 12459.875401245961, 12543.184692994926, 13062.738745225042, 4427.712198707074, 4473.4855491618555, 4570.200214962369, 4584.243599806028, 4703.012367402849, 112259.43870280679, 112733.0, 113408.29887551694, 113746.0, 116626.0, 10851.0, 10890.782184356269, 10912.399818009926, 10968.725781855517, 11113.751348996442, 17886.731699024524, 21623.67564486534, 21623.783762162493, 21655.56688866214, 21659.350219493124, 7903.249191326815, 7927.128015918244, 7986.560739159402, 8155.510669359857, 8163.673453061811, 3819.7708137511836, 4183.330667093251, 4423.601875831127, 4437.911241775147, 4625.861224163281, 3364.0, 3411.778234414755, 3435.3988052091286, 3472.0625431133453, 3481.129717570607, 663.2174034639172, 672.3646154384157, 676.9830754231184, 717.2612209424301, 721.7473884140509, 596.7881402102191, 623.865868838202, 678.6799674632791, 678.710450144729, 680.5610381304031, 8499.957500212584, 8993.775155621031, 10175.0, 10282.897171028344, 10350.585976561004, 573.8852229554022, 629.4618101523132, 646.1664452855857, 648.9513286503532, 696.2376198063113, 4596.862094137181, 4726.314684370747, 4731.787069581877, 4766.856994290177, 4770.236762118045, 86713.0, 86885.56557217173, 97395.0, 153308.0, 160122.0, 128885.0, 130994.0, 131706.0, 133021.0, 134334.0, 109853.90146098596, 112561.0, 114593.4270328643, 114628.85371146348, 116339.41830290963, 8216.178382161735, 8986.910130898606, 9025.503597302211, 9061.954690226507, 9159.35884488106, 60949.69525152346, 62202.68898655478, 62249.37750622436, 63018.36981630125, 65274.34725652872, 93492.50866115763, 97784.0, 98272.7689516988, 99543.0, 100919.0, 11443.828342575034, 11678.182527223013, 11746.119041071783, 11814.704632384259, 12372.505099795908, 12911.483540658268, 13124.671883202996, 13215.14101583394, 13361.26513041753, 13427.932860335635, 11832.0, 11841.0, 12587.307698076695, 12656.17734847232, 12674.873251267369, 10260.384376937407, 11141.053010494199, 12037.45831437605, 12193.817092743438, 12466.189697669623, 11991.289571903166, 12130.332831693988, 12357.567485137977, 12436.440360183995, 12538.749225015632, 8368.623411946599, 9985.0, 10025.799484010426, 10312.226583006455, 10332.036912986809, 7180.754342876794, 8489.660413583388, 9387.718368448957, 9999.950000249952, 10488.0], "value": 7599.779202032572, "unit": "score"}], "name": "dromaeo_css", "value": 7207.607043524388, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 115.0, 113.0, 114.0, 115.0, 113.0, 114.0, 113.0, 115.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 112.0, 108.0, 146.0, 108.0, 110.0, 110.0, 111.0, 138.0, 108.0], "value": 119.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 185.0, 186.0, 183.0, 183.0, 178.0, 182.0, 183.0, 183.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 89.0, 60.0, 60.0, 59.0, 60.0, 83.0, 58.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 96.0, 126.0, 100.0, 68.0, 70.0, 92.0, 68.0, 69.0, 90.0], "value": 86.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 88.0, 86.0, 91.0, 85.0, 87.0, 84.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 94.0, 93.0, 93.0, 94.0, 95.0, 93.0, 92.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 102.0, 121.0, 130.0, 131.0, 84.0, 85.0, 84.0, 83.0, 87.0], "value": 99.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 53.0, 53.0, 53.0, 56.0, 55.0, 54.0, 54.0, 57.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 66.0, 76.0, 72.0, 70.0, 69.0, 71.0, 72.0, 72.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 85.0, 85.0, 86.0, 87.0, 85.0, 81.0, 76.0, 90.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 142.0, 148.0, 152.0, 126.0, 164.0, 129.0, 235.0, 152.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 172.0, 174.0, 168.0, 168.0, 171.0, 171.0, 168.0, 166.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 69.0, 68.0, 67.0, 66.0, 67.0, 68.0, 66.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1448.7, "alertThreshold": 2.0}]} 07:33:13 INFO - Return code: 0 07:33:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:33:14 INFO - # TBPL SUCCESS # 07:33:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:33:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:33:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:33:14 INFO - 2016-09-29 07:33:14.050 screenresolution[1950:19840] starting screenresolution argv=screenresolution get 07:33:14 INFO - 2016-09-29 07:33:14.053 screenresolution[1950:19840] Display 0: 1600x1200x32@60 07:33:14 INFO - 2016-09-29 07:33:14.059 screenresolution[1951:19843] starting screenresolution argv=screenresolution list 07:33:14 INFO - Available Modes on Display 0 07:33:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:33:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:33:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:33:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:33:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:33:14 INFO - Intel Iris: 07:33:14 INFO - Chipset Model: Intel Iris 07:33:14 INFO - Type: GPU 07:33:14 INFO - Bus: Built-In 07:33:14 INFO - VRAM (Dynamic, Max): 1536 MB 07:33:14 INFO - Vendor: Intel (0x8086) 07:33:14 INFO - Device ID: 0x0a2e 07:33:14 INFO - Revision ID: 0x0009 07:33:14 INFO - Displays: 07:33:14 INFO - Display: 07:33:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:33:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:33:14 INFO - Display Serial Number: 1600x1200 60 07:33:14 INFO - Main Display: Yes 07:33:14 INFO - Mirror: Off 07:33:14 INFO - Online: Yes 07:33:14 INFO - Rotation: Supported 07:33:14 INFO - Return code: 0 07:33:14 INFO - Running post-action listener: _resource_record_post_action 07:33:14 INFO - [mozharness: 2016-09-29 14:33:14.295336Z] Finished run-tests step (success) 07:33:14 INFO - Running post-run listener: _resource_record_post_run 07:33:14 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 11334656; Write bytes: 714732032; Read time: 166; Write time: 1954 07:33:14 INFO - TinderboxPrint: CPU usage
53.4% 07:33:14 INFO - TinderboxPrint: I/O read bytes / time
11,334,656 / 166 07:33:14 INFO - TinderboxPrint: I/O write bytes / time
714,732,032 / 1,954 07:33:14 INFO - TinderboxPrint: CPU idle
2,261.4 (46.6%) 07:33:14 INFO - TinderboxPrint: CPU system
967.8 (20.0%) 07:33:14 INFO - TinderboxPrint: CPU user
1,620.4 (33.4%) 07:33:14 INFO - TinderboxPrint: Swap in / out
410,873,856 / 0 07:33:14 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 395438592; Write bytes: 389938176; Read time: 29507; Write time: 673 07:33:14 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 11334656; Write bytes: 303867392; Read time: 166; Write time: 1236 07:33:14 INFO - Running post-run listener: _upload_blobber_files 07:33:14 INFO - Blob upload gear active. 07:33:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:33:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:33:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:33:15 INFO - (blobuploader) - INFO - Open directory for files ... 07:33:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:33:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:33:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:15 INFO - (blobuploader) - INFO - Done attempting. 07:33:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:33:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:33:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:16 INFO - (blobuploader) - INFO - Done attempting. 07:33:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:33:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:33:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:19 INFO - (blobuploader) - INFO - Done attempting. 07:33:19 INFO - (blobuploader) - INFO - Iteration through files over. 07:33:19 INFO - Return code: 0 07:33:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:33:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:33:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3bc799206346fc0ff9eff6360e1bf9b6d97b2e93cc11b06068d7918f56226aba14262270c664fc14ac13d0c26f7d9a627a76432ab472634896f71733f5135ef7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ec5384786c7e78e7fb4be004bf4780f813f22a73800de97c59be55fa6e3a433c7ff1d92c88a87cf2c0a0534020acdcf9e6f1a538710a24a4f759fff76a7c555a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdd20b43f83fc80db32b0060ae3f6518a34130a16396fa0f8bace3a0855ef1310f9a3479b4a3d53602e4a73431642d72c2cb19d9826d5572bef23963edfb28ef"} 07:33:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:33:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:33:19 INFO - Contents: 07:33:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3bc799206346fc0ff9eff6360e1bf9b6d97b2e93cc11b06068d7918f56226aba14262270c664fc14ac13d0c26f7d9a627a76432ab472634896f71733f5135ef7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ec5384786c7e78e7fb4be004bf4780f813f22a73800de97c59be55fa6e3a433c7ff1d92c88a87cf2c0a0534020acdcf9e6f1a538710a24a4f759fff76a7c555a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdd20b43f83fc80db32b0060ae3f6518a34130a16396fa0f8bace3a0855ef1310f9a3479b4a3d53602e4a73431642d72c2cb19d9826d5572bef23963edfb28ef"} 07:33:19 INFO - Running post-run listener: copy_logs_to_upload_dir 07:33:19 INFO - Copying logs to upload dir... 07:33:19 INFO - mkdir: /builds/slave/test/build/upload/logs 07:33:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.557267 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-29 07:33:19.320106) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 07:33:19.323441) ========= 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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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/3bc799206346fc0ff9eff6360e1bf9b6d97b2e93cc11b06068d7918f56226aba14262270c664fc14ac13d0c26f7d9a627a76432ab472634896f71733f5135ef7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ec5384786c7e78e7fb4be004bf4780f813f22a73800de97c59be55fa6e3a433c7ff1d92c88a87cf2c0a0534020acdcf9e6f1a538710a24a4f759fff76a7c555a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdd20b43f83fc80db32b0060ae3f6518a34130a16396fa0f8bace3a0855ef1310f9a3479b4a3d53602e4a73431642d72c2cb19d9826d5572bef23963edfb28ef"} build_url:https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011074 build_url: 'https://queue.taskcluster.net/v1/task/UrboLR40SKidwWPzQZR29w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3bc799206346fc0ff9eff6360e1bf9b6d97b2e93cc11b06068d7918f56226aba14262270c664fc14ac13d0c26f7d9a627a76432ab472634896f71733f5135ef7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ec5384786c7e78e7fb4be004bf4780f813f22a73800de97c59be55fa6e3a433c7ff1d92c88a87cf2c0a0534020acdcf9e6f1a538710a24a4f759fff76a7c555a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdd20b43f83fc80db32b0060ae3f6518a34130a16396fa0f8bace3a0855ef1310f9a3479b4a3d53602e4a73431642d72c2cb19d9826d5572bef23963edfb28ef"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 07:33:19.356733) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 07:33:19.357134) ========= 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.CPkkUs9HVe/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.UO2y3WWrFK/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.005135 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 07:33:19.406429) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 07:33:19.406785) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 07:33:19.407238) ========= ========= Total master_lag: 0.18 =========