builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0108 starttime: 1467216839.47 results: success (0) buildid: 20160629072230 builduid: 44330e7de3a149cabbd5abff280a7c1f revision: eeb6862f960c1310bde9aed159f3fc5bb2d4b6d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 09:13:59.470209) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 09:13:59.470627) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 09:13:59.471087) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 09:13:59.519099) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 09:13:59.519413) ========= 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.mYkdWzACKk/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.KXfHEVhisH/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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-06-29 09:13:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.3M=0.001s 2016-06-29 09:13:59 (16.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108289 ========= master_lag: 1.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 09:14:00.652067) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:14:00.652343) ========= 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.mYkdWzACKk/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.KXfHEVhisH/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.027798 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:14:00.715066) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 09:14:00.715391) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev eeb6862f960c1310bde9aed159f3fc5bb2d4b6d7 --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 eeb6862f960c1310bde9aed159f3fc5bb2d4b6d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mYkdWzACKk/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.KXfHEVhisH/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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-06-29 09:14:00,789 truncating revision to first 12 chars 2016-06-29 09:14:00,789 Setting DEBUG logging. 2016-06-29 09:14:00,789 attempt 1/10 2016-06-29 09:14:00,790 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/eeb6862f960c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 09:14:01,787 unpacking tar archive at: fx-team-eeb6862f960c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.258765 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 09:14:01.996347) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 09:14:01.996652) ========= script_repo_revision: eeb6862f960c1310bde9aed159f3fc5bb2d4b6d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 09:14:01.997039) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 09:14:01.997305) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 09:14:02.015974) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-06-29 09:14:02.016352) ========= /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.mYkdWzACKk/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.KXfHEVhisH/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:14:02 INFO - MultiFileLogger online at 20160629 09:14:02 in /builds/slave/test 09:14:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:14:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:14:02 INFO - {'append_to_log': False, 09:14:02 INFO - 'base_work_dir': '/builds/slave/test', 09:14:02 INFO - 'blob_upload_branch': 'Fx-Team', 09:14:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:14:02 INFO - 'branch': 'Fx-Team', 09:14:02 INFO - 'buildbot_json_path': 'buildprops.json', 09:14:02 INFO - 'config_files': ('talos/mac_config.py',), 09:14:02 INFO - 'default_actions': ('clobber', 09:14:02 INFO - 'read-buildbot-config', 09:14:02 INFO - 'download-and-extract', 09:14:02 INFO - 'populate-webroot', 09:14:02 INFO - 'create-virtualenv', 09:14:02 INFO - 'install', 09:14:02 INFO - 'run-tests'), 09:14:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:14:02 INFO - 'download_minidump_stackwalk': True, 09:14:02 INFO - 'download_symbols': 'ondemand', 09:14:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:14:02 INFO - 'tooltool.py': '/tools/tooltool.py', 09:14:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:14:02 INFO - '/tools/misc-python/virtualenv.py')}, 09:14:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:14:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:14:02 INFO - 'installer_path': 'installer.exe', 09:14:02 INFO - 'log_level': 'info', 09:14:02 INFO - 'log_name': 'talos', 09:14:02 INFO - 'log_to_console': True, 09:14:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:14:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:14:02 INFO - 'opt_config_files': (), 09:14:02 INFO - 'pip_index': False, 09:14:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:14:02 INFO - 'cmd': ('bash', 09:14:02 INFO - '-c', 09:14:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:14:02 INFO - 'enabled': True, 09:14:02 INFO - 'halt_on_failure': False, 09:14:02 INFO - 'name': 'check_screen_resolution'},), 09:14:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:14:02 INFO - 'cmd': ('bash', 09:14:02 INFO - '-c', 09:14:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:14:02 INFO - 'enabled': True, 09:14:02 INFO - 'halt_on_failure': False, 09:14:02 INFO - 'name': 'check_screen_resolution'},), 09:14:02 INFO - 'run_cmd_checks_enabled': True, 09:14:02 INFO - 'sps_profile': False, 09:14:02 INFO - 'sps_profile_interval': 0, 09:14:02 INFO - 'suite': 'dromaeojs', 09:14:02 INFO - 'system_bits': '32', 09:14:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:14:02 INFO - 'title': 't-yosemite-r7-0108', 09:14:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:14:02 INFO - 'use_talos_json': True, 09:14:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:14:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:14:02 INFO - 'work_dir': 'build'} 09:14:02 INFO - ##### 09:14:02 INFO - ##### Running clobber step. 09:14:02 INFO - ##### 09:14:02 INFO - Running pre-action listener: _resource_record_pre_action 09:14:02 INFO - Running main action method: clobber 09:14:02 INFO - rmtree: /builds/slave/test/build 09:14:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:14:04 INFO - Running post-action listener: _resource_record_post_action 09:14:04 INFO - ##### 09:14:04 INFO - ##### Finished clobber step (success) 09:14:04 INFO - ##### 09:14:04 INFO - ##### 09:14:04 INFO - ##### Running read-buildbot-config step. 09:14:04 INFO - ##### 09:14:04 INFO - Running pre-action listener: _resource_record_pre_action 09:14:04 INFO - Running main action method: read_buildbot_config 09:14:04 INFO - Using buildbot properties: 09:14:04 INFO - { 09:14:04 INFO - "project": "", 09:14:04 INFO - "product": "firefox", 09:14:04 INFO - "script_repo_revision": "production", 09:14:04 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:14:04 INFO - "repository": "", 09:14:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:14:04 INFO - "buildid": "20160629072230", 09:14:04 INFO - "pgo_build": "False", 09:14:04 INFO - "basedir": "/builds/slave/test", 09:14:04 INFO - "buildnumber": 790, 09:14:04 INFO - "slavename": "t-yosemite-r7-0108", 09:14:04 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:14:04 INFO - "platform": "yosemite_r7", 09:14:04 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:14:04 INFO - "branch": "fx-team", 09:14:04 INFO - "repo_path": "integration/fx-team", 09:14:04 INFO - "revision": "eeb6862f960c1310bde9aed159f3fc5bb2d4b6d7", 09:14:04 INFO - "stage_platform": "macosx64", 09:14:04 INFO - "builduid": "44330e7de3a149cabbd5abff280a7c1f", 09:14:04 INFO - "slavebuilddir": "test" 09:14:04 INFO - } 09:14:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:14:04 INFO - Running post-action listener: _resource_record_post_action 09:14:04 INFO - ##### 09:14:04 INFO - ##### Finished read-buildbot-config step (success) 09:14:04 INFO - ##### 09:14:04 INFO - ##### 09:14:04 INFO - ##### Running download-and-extract step. 09:14:04 INFO - ##### 09:14:04 INFO - Running pre-action listener: _resource_record_pre_action 09:14:04 INFO - Running main action method: download_and_extract 09:14:04 INFO - mkdir: /builds/slave/test/build/tests 09:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:04 INFO - https://queue.taskcluster.net/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:14:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:14:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:14:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:14:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:14:06 INFO - Downloaded 1199 bytes. 09:14:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:14:06 INFO - Using the following test package requirements: 09:14:06 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:14:06 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:14:06 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:14:06 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:14:06 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:14:06 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:14:06 INFO - u'jsshell-mac.zip'], 09:14:06 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:14:06 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:14:06 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:14:06 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:14:06 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:14:06 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:14:06 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:14:06 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:14:06 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:14:06 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:14:06 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:14:06 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:14:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:06 INFO - https://queue.taskcluster.net/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:14:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:14:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:14:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:14:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:14:08 INFO - Downloaded 18788455 bytes. 09:14:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:14:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:14:11 INFO - Return code: 0 09:14:11 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:11 INFO - https://queue.taskcluster.net/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:14:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:14:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:14:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:14:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:14:20 INFO - Downloaded 18788455 bytes. 09:14:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:14:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:14:23 INFO - Return code: 0 09:14:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:23 INFO - https://queue.taskcluster.net/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:14:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:14:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:14:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:14:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:14:25 INFO - Downloaded 14405245 bytes. 09:14:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:14:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:14:25 INFO - Return code: 0 09:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:25 INFO - https://queue.taskcluster.net/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:14:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:14:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:14:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:14:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:14:33 INFO - Downloaded 112475093 bytes. 09:14:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:14:33 INFO - mkdir: /builds/slave/test/properties 09:14:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:14:33 INFO - Writing to file /builds/slave/test/properties/build_url 09:14:33 INFO - Contents: 09:14:33 INFO - build_url:https://queue.taskcluster.net/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:14:34 INFO - Running post-action listener: _resource_record_post_action 09:14:34 INFO - Running post-action listener: set_extra_try_arguments 09:14:34 INFO - ##### 09:14:34 INFO - ##### Finished download-and-extract step (success) 09:14:34 INFO - ##### 09:14:34 INFO - ##### 09:14:34 INFO - ##### Running populate-webroot step. 09:14:34 INFO - ##### 09:14:34 INFO - Running pre-action listener: _resource_record_pre_action 09:14:34 INFO - Running main action method: populate_webroot 09:14:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:14:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:14:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:14:34 INFO - '--tppagecycles', 09:14:34 INFO - '7'], 09:14:34 INFO - 'tests': ['tsvgm']}}, 09:14:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:14:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:14:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:14:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:14:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:14:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:14:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:34 INFO - 'talos_options': ['--disable-e10s'], 09:14:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:14:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:14:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:34 INFO - 'talos_options': ['--disable-e10s'], 09:14:34 INFO - 'tests': ['damp', 'tps']}, 09:14:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:34 INFO - 'tests': ['damp', 'tps']}, 09:14:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:14:34 INFO - 'tests': ['dromaeo_dom']}, 09:14:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:14:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:14:34 INFO - 'tests': ['basic_compositor_video']}, 09:14:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:14:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:14:34 INFO - 'tests': ['a11yr', 09:14:34 INFO - 'ts_paint', 09:14:34 INFO - 'tpaint', 09:14:34 INFO - 'sessionrestore', 09:14:34 INFO - 'sessionrestore_no_auto_restore', 09:14:34 INFO - 'tabpaint']}, 09:14:34 INFO - 'other-e10s': {'tests': ['a11yr', 09:14:34 INFO - 'ts_paint', 09:14:34 INFO - 'tpaint', 09:14:34 INFO - 'sessionrestore', 09:14:34 INFO - 'sessionrestore_no_auto_restore', 09:14:34 INFO - 'tabpaint']}, 09:14:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:14:34 INFO - 'ts_paint', 09:14:34 INFO - 'tpaint', 09:14:34 INFO - 'sessionrestore', 09:14:34 INFO - 'sessionrestore_no_auto_restore', 09:14:34 INFO - 'tabpaint']}, 09:14:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:14:34 INFO - 'ts_paint', 09:14:34 INFO - 'tpaint', 09:14:34 INFO - 'sessionrestore', 09:14:34 INFO - 'sessionrestore_no_auto_restore', 09:14:34 INFO - 'tabpaint']}, 09:14:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:14:34 INFO - 'tests': ['a11yr', 09:14:34 INFO - 'ts_paint', 09:14:34 INFO - 'tpaint', 09:14:34 INFO - 'sessionrestore', 09:14:34 INFO - 'sessionrestore_no_auto_restore', 09:14:34 INFO - 'tabpaint']}, 09:14:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:14:34 INFO - 'tests': ['a11yr', 09:14:34 INFO - 'ts_paint', 09:14:34 INFO - 'tpaint', 09:14:34 INFO - 'sessionrestore', 09:14:34 INFO - 'sessionrestore_no_auto_restore', 09:14:34 INFO - 'tabpaint']}, 09:14:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:14:34 INFO - 'tests': ['tsvgx', 09:14:34 INFO - 'tsvgr_opacity', 09:14:34 INFO - 'tart', 09:14:34 INFO - 'tscrollx', 09:14:34 INFO - 'cart']}, 09:14:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:14:34 INFO - 'tsvgr_opacity', 09:14:34 INFO - 'tart', 09:14:34 INFO - 'tscrollx', 09:14:34 INFO - 'cart']}, 09:14:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:34 INFO - 'talos_options': ['--disable-e10s'], 09:14:34 INFO - 'tests': ['tp5o']}, 09:14:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:34 INFO - 'tests': ['tp5o']}, 09:14:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:34 INFO - 'talos_options': ['--disable-e10s', 09:14:34 INFO - '--xperf_path', 09:14:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:14:34 INFO - 'tests': ['tp5n']}, 09:14:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:34 INFO - 'talos_options': ['--xperf_path', 09:14:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:14:34 INFO - 'tests': ['tp5n']}}, 09:14:34 INFO - 'talos.zip': {'path': '', 09:14:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:14:34 INFO - Running post-action listener: _resource_record_post_action 09:14:34 INFO - ##### 09:14:34 INFO - ##### Finished populate-webroot step (success) 09:14:34 INFO - ##### 09:14:34 INFO - ##### 09:14:34 INFO - ##### Running create-virtualenv step. 09:14:34 INFO - ##### 09:14:34 INFO - Running pre-action listener: _resource_record_pre_action 09:14:34 INFO - Running main action method: create_virtualenv 09:14:34 INFO - Creating virtualenv /builds/slave/test/build/venv 09:14:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:14:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:14:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:14:34 INFO - Using real prefix '/tools/python27' 09:14:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:14:35 INFO - Installing distribute.............................................................................................................................................................................................done. 09:14:38 INFO - Installing pip.................done. 09:14:38 INFO - Return code: 0 09:14:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:38 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 0x10d231a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6595d24d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d195d30>, '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 0x10cefadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18db20>, '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.mYkdWzACKk/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.KXfHEVhisH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:14:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:14:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mYkdWzACKk/Render', 09:14:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:38 INFO - 'HOME': '/Users/cltbld', 09:14:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:38 INFO - 'LOGNAME': 'cltbld', 09:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:38 INFO - 'MOZ_NO_REMOTE': '1', 09:14:38 INFO - 'NO_EM_RESTART': '1', 09:14:38 INFO - 'PAGER': '/bin/cat', 09:14:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:38 INFO - 'PWD': '/builds/slave/test', 09:14:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:38 INFO - 'SHELL': '/bin/bash', 09:14:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXfHEVhisH/Listeners', 09:14:38 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 09:14:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:38 INFO - 'USER': 'cltbld', 09:14:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:38 INFO - 'XPC_FLAGS': '0x0', 09:14:38 INFO - 'XPC_SERVICE_NAME': '0', 09:14:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:14:38 INFO - Cleaning up... 09:14:38 INFO - Return code: 0 09:14:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:38 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 0x10d231a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6595d24d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d195d30>, '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 0x10cefadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18db20>, '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.mYkdWzACKk/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.KXfHEVhisH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:14:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:14:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mYkdWzACKk/Render', 09:14:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:38 INFO - 'HOME': '/Users/cltbld', 09:14:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:38 INFO - 'LOGNAME': 'cltbld', 09:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:38 INFO - 'MOZ_NO_REMOTE': '1', 09:14:38 INFO - 'NO_EM_RESTART': '1', 09:14:38 INFO - 'PAGER': '/bin/cat', 09:14:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:38 INFO - 'PWD': '/builds/slave/test', 09:14:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:38 INFO - 'SHELL': '/bin/bash', 09:14:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXfHEVhisH/Listeners', 09:14:38 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 09:14:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:38 INFO - 'USER': 'cltbld', 09:14:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:38 INFO - 'XPC_FLAGS': '0x0', 09:14:38 INFO - 'XPC_SERVICE_NAME': '0', 09:14:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:38 INFO - Downloading/unpacking psutil>=3.1.1 09:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:14:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:14:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:14:41 INFO - Installing collected packages: psutil 09:14:41 INFO - Running setup.py install for psutil 09:14:41 INFO - building 'psutil._psutil_osx' extension 09:14:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:14:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:14:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:14:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:14:42 INFO - building 'psutil._psutil_posix' extension 09:14:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:14:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:14:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:14:42 INFO - ^ 09:14:42 INFO - 1 warning generated. 09:14:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:14:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:14:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:14:42 INFO - Successfully installed psutil 09:14:42 INFO - Cleaning up... 09:14:42 INFO - Return code: 0 09:14:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d231a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6595d24d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d195d30>, '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 0x10cefadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18db20>, '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.mYkdWzACKk/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.KXfHEVhisH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:14: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:14: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 mozsystemmonitor==0.3 09:14:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mYkdWzACKk/Render', 09:14:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:42 INFO - 'HOME': '/Users/cltbld', 09:14:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:42 INFO - 'LOGNAME': 'cltbld', 09:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:42 INFO - 'MOZ_NO_REMOTE': '1', 09:14:42 INFO - 'NO_EM_RESTART': '1', 09:14:42 INFO - 'PAGER': '/bin/cat', 09:14:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:42 INFO - 'PWD': '/builds/slave/test', 09:14:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:42 INFO - 'SHELL': '/bin/bash', 09:14:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXfHEVhisH/Listeners', 09:14:42 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 09:14:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:42 INFO - 'USER': 'cltbld', 09:14:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:42 INFO - 'XPC_FLAGS': '0x0', 09:14:42 INFO - 'XPC_SERVICE_NAME': '0', 09:14:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:14:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:14:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:14:45 INFO - Installing collected packages: mozsystemmonitor 09:14:45 INFO - Running setup.py install for mozsystemmonitor 09:14:45 INFO - Successfully installed mozsystemmonitor 09:14:45 INFO - Cleaning up... 09:14:45 INFO - Return code: 0 09:14:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:14: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14: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', '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 0x10d231a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6595d24d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d195d30>, '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 0x10cefadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18db20>, '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.mYkdWzACKk/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.KXfHEVhisH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:14: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:14: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 jsonschema==2.5.1 09:14:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mYkdWzACKk/Render', 09:14:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:45 INFO - 'HOME': '/Users/cltbld', 09:14:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:45 INFO - 'LOGNAME': 'cltbld', 09:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:45 INFO - 'MOZ_NO_REMOTE': '1', 09:14:45 INFO - 'NO_EM_RESTART': '1', 09:14:45 INFO - 'PAGER': '/bin/cat', 09:14:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:45 INFO - 'PWD': '/builds/slave/test', 09:14:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:45 INFO - 'SHELL': '/bin/bash', 09:14:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXfHEVhisH/Listeners', 09:14:45 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 09:14:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:45 INFO - 'USER': 'cltbld', 09:14:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:45 INFO - 'XPC_FLAGS': '0x0', 09:14:45 INFO - 'XPC_SERVICE_NAME': '0', 09:14:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:46 INFO - Downloading/unpacking jsonschema==2.5.1 09:14: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 09:14: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 09:14: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 09:14: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 09:14: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 09:14: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 09:14:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:14:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:14: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 09:14: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 09:14: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 09:14: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 09:14: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 09:14: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 09:14:48 INFO - Downloading functools32-3.2.3-2.tar.gz 09:14:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:14:49 INFO - warning: no files found matching '*.txt' 09:14:49 INFO - no previously-included directories found matching 'build' 09:14:49 INFO - no previously-included directories found matching 'dist' 09:14:49 INFO - no previously-included directories found matching '.git*' 09:14:49 INFO - Installing collected packages: jsonschema, functools32 09:14:49 INFO - Running setup.py install for functools32 09:14:49 INFO - warning: no files found matching '*.txt' 09:14:49 INFO - no previously-included directories found matching 'build' 09:14:49 INFO - no previously-included directories found matching 'dist' 09:14:49 INFO - no previously-included directories found matching '.git*' 09:14:49 INFO - Successfully installed jsonschema functools32 09:14:49 INFO - Cleaning up... 09:14:49 INFO - Return code: 0 09:14:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:49 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 0x10d231a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6595d24d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d195d30>, '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 0x10cefadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18db20>, '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.mYkdWzACKk/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.KXfHEVhisH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:14:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:14:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:14:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mYkdWzACKk/Render', 09:14:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:49 INFO - 'HOME': '/Users/cltbld', 09:14:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:49 INFO - 'LOGNAME': 'cltbld', 09:14:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:49 INFO - 'MOZ_NO_REMOTE': '1', 09:14:49 INFO - 'NO_EM_RESTART': '1', 09:14:49 INFO - 'PAGER': '/bin/cat', 09:14:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:49 INFO - 'PWD': '/builds/slave/test', 09:14:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:49 INFO - 'SHELL': '/bin/bash', 09:14:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXfHEVhisH/Listeners', 09:14:49 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 09:14:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:49 INFO - 'USER': 'cltbld', 09:14:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:49 INFO - 'XPC_FLAGS': '0x0', 09:14:49 INFO - 'XPC_SERVICE_NAME': '0', 09:14:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:49 INFO - Downloading/unpacking blobuploader==1.2.4 09:14: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 09:14: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 09:14: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 09:14: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 09:14: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 09:14: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 09:14:52 INFO - Downloading blobuploader-1.2.4.tar.gz 09:14:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:14:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:14:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:53 INFO - Downloading docopt-0.6.1.tar.gz 09:14:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:14:53 INFO - Installing collected packages: blobuploader, requests, docopt 09:14:53 INFO - Running setup.py install for blobuploader 09:14:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:14:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:14:53 INFO - Running setup.py install for requests 09:14:54 INFO - Running setup.py install for docopt 09:14:54 INFO - Successfully installed blobuploader requests docopt 09:14:54 INFO - Cleaning up... 09:14:54 INFO - Return code: 0 09:14:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:54 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 0x10d231a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6595d24d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d195d30>, '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 0x10cefadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18db20>, '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.mYkdWzACKk/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.KXfHEVhisH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:14:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:14:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mYkdWzACKk/Render', 09:14:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:54 INFO - 'HOME': '/Users/cltbld', 09:14:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:54 INFO - 'LOGNAME': 'cltbld', 09:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:54 INFO - 'MOZ_NO_REMOTE': '1', 09:14:54 INFO - 'NO_EM_RESTART': '1', 09:14:54 INFO - 'PAGER': '/bin/cat', 09:14:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:54 INFO - 'PWD': '/builds/slave/test', 09:14:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:54 INFO - 'SHELL': '/bin/bash', 09:14:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXfHEVhisH/Listeners', 09:14:54 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 09:14:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:54 INFO - 'USER': 'cltbld', 09:14:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:54 INFO - 'XPC_FLAGS': '0x0', 09:14:54 INFO - 'XPC_SERVICE_NAME': '0', 09:14:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:14:54 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-4ZuP5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:14:54 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-LvfPZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:14:54 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-5wCPdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:14:54 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-VoZH8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:14:55 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-OTHdQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:14:55 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-sCAzOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:14:55 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-tvBuAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:14:55 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-yysmMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:14:55 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-hnmCrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:14:55 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Xb5ZEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:14:55 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-kueaIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:14:55 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-WNAGsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:14:55 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-4Lfn6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:14:55 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-yqUuoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:56 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-GzuQdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:14:56 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-p14ak4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:14:56 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-4JoJdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:14:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:14:56 INFO - Running setup.py install for manifestparser 09:14:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:14:56 INFO - Running setup.py install for mozcrash 09:14:56 INFO - Running setup.py install for mozdebug 09:14:56 INFO - Running setup.py install for mozdevice 09:14:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:14:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:14:56 INFO - Running setup.py install for mozfile 09:14:57 INFO - Running setup.py install for mozhttpd 09:14:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:14:57 INFO - Running setup.py install for mozinfo 09:14:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:14:57 INFO - Running setup.py install for mozInstall 09:14:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:14:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:14:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:14:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:14:57 INFO - Running setup.py install for mozleak 09:14:57 INFO - Running setup.py install for mozlog 09:14:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:14:57 INFO - Running setup.py install for moznetwork 09:14:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:14:57 INFO - Running setup.py install for mozprocess 09:14:58 INFO - Running setup.py install for mozprofile 09:14:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:14:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:14:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:14:58 INFO - Running setup.py install for mozrunner 09:14:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:14:58 INFO - Running setup.py install for mozscreenshot 09:14:58 INFO - Running setup.py install for moztest 09:14:58 INFO - Running setup.py install for mozversion 09:14:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:14:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:14:58 INFO - Cleaning up... 09:14:58 INFO - Return code: 0 09:14:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:58 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 0x10d231a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6595d24d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d195d30>, '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 0x10cefadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18db20>, '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.mYkdWzACKk/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.KXfHEVhisH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:14:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:14:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mYkdWzACKk/Render', 09:14:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:58 INFO - 'HOME': '/Users/cltbld', 09:14:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:58 INFO - 'LOGNAME': 'cltbld', 09:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:58 INFO - 'MOZ_NO_REMOTE': '1', 09:14:58 INFO - 'NO_EM_RESTART': '1', 09:14:58 INFO - 'PAGER': '/bin/cat', 09:14:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:58 INFO - 'PWD': '/builds/slave/test', 09:14:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:58 INFO - 'SHELL': '/bin/bash', 09:14:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXfHEVhisH/Listeners', 09:14:58 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 09:14:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:58 INFO - 'USER': 'cltbld', 09:14:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:58 INFO - 'XPC_FLAGS': '0x0', 09:14:58 INFO - 'XPC_SERVICE_NAME': '0', 09:14:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:14:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-VLwi4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:14:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-J8Zaw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:14:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-IBckmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:14:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-eYzktx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:14:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-dUHODI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:14:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-mb7nJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:14:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-6_1jxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:14:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-6OTjSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:15:00 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-HkpDXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:15:00 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-_NGY4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:15:00 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-FMlFr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:15:00 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-D1TzLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:15:00 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-tIUOc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:15:00 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-27k42G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:00 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-22_hgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:15:00 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-EzDAFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:15:00 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-qzSid4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:15:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:15:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:03 INFO - Downloading blessings-1.6.tar.gz 09:15:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:15:03 INFO - Installing collected packages: blessings 09:15:03 INFO - Running setup.py install for blessings 09:15:03 INFO - Successfully installed blessings 09:15:03 INFO - Cleaning up... 09:15:03 INFO - Return code: 0 09:15:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:15:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:15:04 INFO - Reading from file tmpfile_stdout 09:15:04 INFO - Current package versions: 09:15:04 INFO - blessings == 1.6 09:15:04 INFO - blobuploader == 1.2.4 09:15:04 INFO - docopt == 0.6.1 09:15:04 INFO - functools32 == 3.2.3-2 09:15:04 INFO - jsonschema == 2.5.1 09:15:04 INFO - manifestparser == 1.1 09:15:04 INFO - mozInstall == 1.12 09:15:04 INFO - mozcrash == 0.17 09:15:04 INFO - mozdebug == 0.1 09:15:04 INFO - mozdevice == 0.48 09:15:04 INFO - mozfile == 1.2 09:15:04 INFO - mozhttpd == 0.7 09:15:04 INFO - mozinfo == 0.9 09:15:04 INFO - mozleak == 0.1 09:15:04 INFO - mozlog == 3.2 09:15:04 INFO - moznetwork == 0.27 09:15:04 INFO - mozprocess == 0.22 09:15:04 INFO - mozprofile == 0.28 09:15:04 INFO - mozrunner == 6.11 09:15:04 INFO - mozscreenshot == 0.1 09:15:04 INFO - mozsystemmonitor == 0.3 09:15:04 INFO - moztest == 0.7 09:15:04 INFO - mozversion == 1.4 09:15:04 INFO - psutil == 3.1.1 09:15:04 INFO - requests == 1.2.3 09:15:04 INFO - wsgiref == 0.1.2 09:15:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:04 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 0x10d231a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6595d24d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d195d30>, '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 0x10cefadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18db20>, '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.mYkdWzACKk/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.KXfHEVhisH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:15:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:15:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mYkdWzACKk/Render', 09:15:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:04 INFO - 'HOME': '/Users/cltbld', 09:15:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:04 INFO - 'LOGNAME': 'cltbld', 09:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:04 INFO - 'MOZ_NO_REMOTE': '1', 09:15:04 INFO - 'NO_EM_RESTART': '1', 09:15:04 INFO - 'PAGER': '/bin/cat', 09:15:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:04 INFO - 'PWD': '/builds/slave/test', 09:15:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:04 INFO - 'SHELL': '/bin/bash', 09:15:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXfHEVhisH/Listeners', 09:15:04 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 09:15:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:04 INFO - 'USER': 'cltbld', 09:15:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:04 INFO - 'XPC_FLAGS': '0x0', 09:15:04 INFO - 'XPC_SERVICE_NAME': '0', 09:15:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:15:04 INFO - Cleaning up... 09:15:04 INFO - Return code: 0 09:15:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:04 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 0x10d231a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6595d24d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d195d30>, '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 0x10cefadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18db20>, '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.mYkdWzACKk/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.KXfHEVhisH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:15:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:15:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mYkdWzACKk/Render', 09:15:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:04 INFO - 'HOME': '/Users/cltbld', 09:15:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:04 INFO - 'LOGNAME': 'cltbld', 09:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:04 INFO - 'MOZ_NO_REMOTE': '1', 09:15:04 INFO - 'NO_EM_RESTART': '1', 09:15:04 INFO - 'PAGER': '/bin/cat', 09:15:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:04 INFO - 'PWD': '/builds/slave/test', 09:15:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:04 INFO - 'SHELL': '/bin/bash', 09:15:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXfHEVhisH/Listeners', 09:15:04 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 09:15:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:04 INFO - 'USER': 'cltbld', 09:15:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:04 INFO - 'XPC_FLAGS': '0x0', 09:15:04 INFO - 'XPC_SERVICE_NAME': '0', 09:15:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:15:04 INFO - Cleaning up... 09:15:04 INFO - Return code: 0 09:15:04 INFO - Running post-action listener: _resource_record_post_action 09:15:04 INFO - Running post-action listener: _start_resource_monitoring 09:15:04 INFO - Starting resource monitoring. 09:15:04 INFO - ##### 09:15:04 INFO - ##### Finished create-virtualenv step (success) 09:15:04 INFO - ##### 09:15:04 INFO - ##### 09:15:04 INFO - ##### Running install step. 09:15:04 INFO - ##### 09:15:04 INFO - Running pre-action listener: _resource_record_pre_action 09:15:04 INFO - Running main action method: install 09:15:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:15:04 INFO - Reading from file tmpfile_stdout 09:15:04 INFO - Detecting whether we're running mozinstall >=1.0... 09:15:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:15:04 INFO - Reading from file tmpfile_stdout 09:15:04 INFO - Output received: 09:15:04 INFO - Usage: mozinstall [options] installer 09:15:04 INFO - Options: 09:15:04 INFO - -h, --help show this help message and exit 09:15:04 INFO - -d DEST, --destination=DEST 09:15:04 INFO - Directory to install application into. [default: 09:15:04 INFO - "/builds/slave/test"] 09:15:04 INFO - --app=APP Application being installed. [default: firefox] 09:15:04 INFO - mkdir: /builds/slave/test/build/application 09:15:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:15:39 INFO - Reading from file tmpfile_stdout 09:15:39 INFO - Output received: 09:15:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:15:39 INFO - Running post-action listener: _resource_record_post_action 09:15:39 INFO - ##### 09:15:39 INFO - ##### Finished install step (success) 09:15:39 INFO - ##### 09:15:39 INFO - ##### 09:15:39 INFO - ##### Running run-tests step. 09:15:39 INFO - ##### 09:15:39 INFO - Running pre-action listener: _resource_record_pre_action 09:15:39 INFO - Running main action method: run_tests 09:15:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:15:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:15:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:15:39 INFO - 2016-06-29 09:15:39.687 screenresolution[1636:7647] starting screenresolution argv=screenresolution get 09:15:39 INFO - 2016-06-29 09:15:39.690 screenresolution[1636:7647] Display 0: 1600x1200x32@60 09:15:39 INFO - 2016-06-29 09:15:39.696 screenresolution[1637:7649] starting screenresolution argv=screenresolution list 09:15:39 INFO - Available Modes on Display 0 09:15:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:15:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:15:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:15:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:15:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:15:39 INFO - Intel Iris: 09:15:39 INFO - Chipset Model: Intel Iris 09:15:39 INFO - Type: GPU 09:15:39 INFO - Bus: Built-In 09:15:39 INFO - VRAM (Dynamic, Max): 1536 MB 09:15:39 INFO - Vendor: Intel (0x8086) 09:15:39 INFO - Device ID: 0x0a2e 09:15:39 INFO - Revision ID: 0x0009 09:15:39 INFO - Displays: 09:15:39 INFO - Display: 09:15:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:15:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:15:39 INFO - Display Serial Number: 1600x1200 60 09:15:39 INFO - Main Display: Yes 09:15:39 INFO - Mirror: Off 09:15:39 INFO - Online: Yes 09:15:39 INFO - Rotation: Supported 09:15:39 INFO - Return code: 0 09:15:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:15:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:15:39 INFO - Python 2.7.3 09:15:39 INFO - Return code: 0 09:15:39 INFO - grabbing minidump binary from tooltool 09:15: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:39 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 0x10d195d30>, '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 0x10cefadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:15:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:15:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:15:39 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:15:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:15:40 INFO - Return code: 0 09:15:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:15:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:15:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:15:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:15:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:15:40 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/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:15:40 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/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0108 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:15:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mYkdWzACKk/Render', 09:15:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:40 INFO - 'HOME': '/Users/cltbld', 09:15:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:40 INFO - 'LOGNAME': 'cltbld', 09:15:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:15:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:40 INFO - 'MOZ_NO_REMOTE': '1', 09:15:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:15:40 INFO - 'NO_EM_RESTART': '1', 09:15:40 INFO - 'PAGER': '/bin/cat', 09:15:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:40 INFO - 'PWD': '/builds/slave/test', 09:15:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:15:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:40 INFO - 'SHELL': '/bin/bash', 09:15:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXfHEVhisH/Listeners', 09:15:40 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 09:15:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:40 INFO - 'USER': 'cltbld', 09:15:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:40 INFO - 'XPC_FLAGS': '0x0', 09:15:40 INFO - 'XPC_SERVICE_NAME': '0', 09:15:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:40 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/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:15:40 INFO - mozversion application_buildid: 20160629072230 09:15:40 INFO - mozversion application_changeset: eeb6862f960c1310bde9aed159f3fc5bb2d4b6d7 09:15:40 INFO - mozversion application_display_name: Nightly 09:15:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:15:40 INFO - mozversion application_name: Firefox 09:15:40 INFO - mozversion application_remotingname: firefox 09:15:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:15:40 INFO - mozversion application_vendor: Mozilla 09:15:40 INFO - mozversion application_version: 50.0a1 09:15:40 INFO - mozversion platform_buildid: 20160629072230 09:15:40 INFO - mozversion platform_changeset: eeb6862f960c1310bde9aed159f3fc5bb2d4b6d7 09:15:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:15:40 INFO - mozversion platform_version: 50.0a1 09:15:40 INFO - using testdate: 1467216940 09:15:40 INFO - actual date: 1467216940 09:15:40 INFO - starting webserver on 'localhost:49274' 09:15:40 INFO - SUITE-START | Running 2 tests 09:15:40 INFO - TEST-START | dromaeo_css 09:15:40 INFO - operating with platform_type : mac_ 09:15:40 INFO - Initialising browser for dromaeo_css test... 09:15:40 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpqfR3Xl/profile http://localhost:49274/getInfo.html) 09:15:42 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 09:15:42 INFO - PROCESS | 1647 | colorDepth:24 09:15:42 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 09:15:42 INFO - PROCESS | 1647 | __metrics 09:15:42 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:15:42 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:15:43 INFO - TEST-INFO | 1647: exit 0 09:15:43 INFO - Browser initialized. 09:15:43 INFO - Running cycle 1/1 for dromaeo_css test... 09:15:43 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpqfR3Xl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:15:55 INFO - PROCESS | 1649 | RSS: Main: 245059584 09:15:55 INFO - PROCESS | 1649 | 09:16:47 INFO - PROCESS | 1649 | 1467217007099 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:18:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 09:18:59 INFO - PROCESS | 1649 | RSS: Main: 387727360 09:18:59 INFO - PROCESS | 1649 | 09:22:02 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 09:22:02 INFO - PROCESS | 1649 | RSS: Main: 379604992 09:22:02 INFO - PROCESS | 1649 | 09:25:05 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 09:25:05 INFO - PROCESS | 1649 | RSS: Main: 371380224 09:25:05 INFO - PROCESS | 1649 | 09:28:08 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 09:28:08 INFO - PROCESS | 1649 | RSS: Main: 373428224 09:28:08 INFO - PROCESS | 1649 | 09:31:12 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 09:31:12 INFO - PROCESS | 1649 | RSS: Main: 341250048 09:31:12 INFO - PROCESS | 1649 | 09:34:15 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 09:34:16 INFO - PROCESS | 1649 | RSS: Main: 429367296 09:34:16 INFO - PROCESS | 1649 | 09:34:16 INFO - PROCESS | 1649 | __start_tp_report 09:34:16 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 09:34:16 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 09:34:16 INFO - PROCESS | 1649 | |i|pagename|runs| 09:34:16 INFO - PROCESS | 1649 | |0;dojo.html;3383.966160338396;3394.4059789536823;3408.7613867029304;3431.9142021449475;3475.322312149132;5613.635113717605;5665.376808551061;5706.942930570693;5746.569007324451;5750.798722044724;5722.685252311121;5730.971345143279;5741.311042674884;5828.213191219184;5831.883362332751;5119.001794650045;5148.6910785353;5200.063988482072;5218.530332270095;5313.893722125575;5295.0733621616255;5313.548348390372;5331.333583302087;5343.305370301855;5365.436629153922;5160.922586161192;5173.793048278064;5206.401263854667;5224.111900976843;5267.3679158500845;5187.870303242411;5202.4537423570355;5208.947910520884;5212.765425555859;5220.425753167148;5203.895922081575;5204.141316682742;5220.608454365938;5228.294180285651;5273.551748101416;4669.229577119771;5633.774649014034;5750.252467179282;5827.970860145714;5845.532357411397;6248.968755156195;6283.057541368786;6328.841778955517;6330.841728956813;6331.905021424636;6343.238811342622;6364.172657554534;6364.395382438661;6390.93609063908;6402.455791257748;5707.514861236758;5756.30924289088;5801.680907550125;5847.415258474161;5917.023691090965;6296.275928268216;6307.936920630735;6310.968445157745;6329.398707122816;6355.586886852339;6194.845128871814;6221.2845522764555;6225.439710425992;6225.595336303126;6227.501799856001;6109.495762817132;6120.357362476965;6211.09939058834;6258;6340.175777148941;5537.556995440355;6260.937390626126;6301.684915754194;6301.936980630227;6315;6260.123582698426;6318.6524741138755;6319.58922670025;6329.7468101275435;6331.2085989251345;4915.066137433876;4944.035912996931;4957.851264462072;4958.727270000185;5028.5977121830165;4520.118576877543;4521.954780452219;4522.638188944942;4540.704854184468;4547.090581883636;5511.173324001352;5582.469665381782;5680.943190568124;5685.687287199161;5713;6124.3809352984035;6200.255969283625;6256.687165641791;6278.560500764994;6303.621782693053;5756.654600723886;5768.798092066758;5780;5781.566382521328;5816.621919575214;4653.674242803039;5438.537724293479;5457.263269458652;5459.099248623933;5459.508644222039;6253.749850005949;6257.2491301044165;6278.0269058296035;6297.086922396318;6317.873642527032;6308.84227894288;6313.40022697843;6332.556721029484;6349.174607301022;6352.9047064295;6293.653849038164;6299;6299.779507717208;6310.085037669604;6335.746570137328;6128.356522565066;6143.170671959407;6171.783987560414;6227.813165605039;6254.562180647311;6110.694465276807;6309.779157729457;6319;6330.177076979963;6357.078223657636;5916.408359164049;6019.187409699634;6140.109684095692;6150.077488376779;6168.845778855564;6035.456808887221;6098.855844323317;6102.2771839767565;6102.4589553536725;6136.243373036715;5895.292564892069;5952.226210592768;5964.224650795369;5976.342602313655;6011.458968692838;5923.46688797993;6073.240844894388;6110.908336375047;6143.416375444325;6144.771045790771;6018.849528761816;6020.6989650516425;6041.124037014521;6044.909326360196;6045.183900173421 09:34:16 INFO - PROCESS | 1649 | |1;ext.html;472.76125556593917;473.11527443680313;480.6971607887032;543.4483998741279;555.4029418375247;30082.796688132476;38810.22379552407;39473.223704933276;42911.35632965508;45313.77343113288;43834.78082609591;44208.3368749469;44226;44259.77870110653;44305.335419968724;8761.342899282547;9665.08181722737;9823.459709715962;9847.950760246225;9848.55681494331;8140.4708693935;11320.433978301093;12114.93942530286;12122.515099396014;12453.377331133452;12093.93953030238;12105.878941210563;12148.514059437612;12165.51337946481;12179.51281948721;8815.515146666994;11854;12038.277703337828;12089.758204835898;12171.391430428488;11884.702882427922;11936.58221962227;11961.940190298992;11969.94015029928;11979.760404791943;41491.962700932716;41863;41981;42053.57946420527;42065.369019464735;3429.6261747164226;3486.89539313821;3521.9295614087573;3621.1671315597296;3810.942835857464;31771.616984720542;32310.83844580762;33219;33226.833865830755;33325.33349333024;3221.11419359678;3268.7548433867555;3415.3852306584804;3428.074419906636;3432.845521951518;523410;526038.1773275873;527367;527378;527474;62522.749545008846;62915;62919.6854015727;63003;63015;64600;65031.67484162644;65112;65149.348506514325;65184;16541.338346466007;16700.164991750364;16784;16812.411565595146;16818.57953551171;11737.88262117385;12020.45907934145;12041;12249.816252756216;13009.739805203842;15220.630143287157;15317.387304507918;15473.071615703095;15815.36738530469;17330.480085597454;5350.357957045179;6296.5277604178955;6304.306526282106;6348.936510634927;6370;951.8524628682566;987.4124895687198;1008.9747756306004;1045.749020235138;1103.8178700514486;27872.737257152243;30158.84920575383;30244.897959183672;30863.69136308653;31055;1072.828347464402;1100.0814320042693;1136.3182090745652;1137.294877176156;1149.3333866357493;15626.374945002073;15643;16036.919815401081;16046.358145674276;16406.425775097814;659268;665021;665482;666186;666211;59426;60783.69608151931;60799;60839.39160608426;60912.39087609067;1260.8865202131851;1297.9935100324437;1386.272207091285;1389.9235542045083;1423.6440889777555;30079.248018799706;32138.35723285568;32171.839140804146;32442;32836.34327313488;51996;52648.73675631597;52694.68263293295;52752.944941101734;52806.41580752584;544305;548687;550106;550570;551392;33474.66101356016;34132;43317.3502397458;45325.773371132935;45341.09317813559;48852;49659.503404965486;49682.50952471432;50822.23766643577;50890.74554627203;36159.45760813643;37383.81308093442;39246.80376598213;40932;41547.7922610385;38425.42361864518;39984.600153998086;41055.58944410518;41389;41608.791956040026;31691.52462713015;38096;38559;39553;39591.604083958795;32942.17644558905;34511.65488345084;34748.82625586856;35416.46875296924;35466;21510.386720995673;25753.356166095997;25883.61174582345;26043.73956260489;26168.8691556541 09:34:16 INFO - PROCESS | 1649 | |2;jquery.html;355.27878406834105;376.62149539712584;386.41072364643895;387.1173723909487;407.63313018283543;15595.376184952602;15639.06165630063;20266;20613.76317420956;20636.9681515924;20708.403294155247;21000;21076.578468430624;21229.893850530763;21318.253861114863;5715.142728590714;5972.25346831646;5972.522198224132;6023.728932198062;6620.801375958729;6528.053432252321;6531.216254049521;6601.339866013408;6609.140811694473;6613.801585952429;6389.616623002611;6411.67941602918;6521.08704781331;6572.244191917919;6577.243616984037;6236.875262494725;6412.583182093149;6492.805215843508;6498.805035848908;6576.967115164442;5661.207430959627;5691.402402747734;5694.544436445074;5698.259226300595;5706;25516.744832551623;26531;26937.922483100654;26982.865085674643;26987.05545305905;2153.9461513462134;2387;2397.6403539469043;2854.8723254314427;3094.6441159266747;12025.93987030068;14727.410903563845;15315.693686126326;15318;15580.065196088271;1759.52492826936;1850.4541160357726;1874.4189301316635;1893.9621207575772;1919.89440580768;76426.61786691086;108904;109970;110819.44590277078;112186;10429.061384475282;10556.89443105559;10825.621103261348;10853.34879907208;11011.449427528592;13157.407916643773;13266.469341226435;13302.401391937192;13311.933440332736;13326.06717529783;8436.620352084172;8604.61279242423;8664.306855451548;8736.301095912311;8752.562371881508;8680.392372533988;8712.346574006975;8738.30093592511;8824.691135810344;8842.646294148162;5593.8321850344555;6554.901676474761;6655;6657.434118099919;6671.899921501182;1532.7241096602718;1778.5820332221917;1814.321752379044;1814.4193857965588;1816.3188804198426;855.6021450025768;897.3135551303258;900.7522931193975;937.9624815007323;1000.8148492528906;21101.78898210998;22009.22967696123;22106.557868842534;22162.77837221607;22220.88889555574;906.3474298505066;969.2391472694043;978.9608415663436;984.5766320482261;989.5942663507927;11288.435578221057;11602.245854019462;11719.472623731952;11818.995385392338;11853.644390668294;495961;498905;499086;601905;609010.9549452225;149563;153644.6667229544;154614;154621;154646;957.9329446938787;981.2101258486987;1006.6074231049896;1006.7181189267017;1008.4806324742764;19565.923874186487;19760.901195494505;19796.901015495405;19804;19804.60390792205;57440.71279643575;57761;59887.40112598993;60041.399586005326;60426;56426;85934.57032714796;89277.66083508883;89775.10224897668;93098.53450732703;4597.310403439377;5437.912417516592;8166.873960409431;8214.398192352674;8267.173282671925;12186.634400967985;13218.867811321763;13327.933360333136;13636.522721704692;13663.863361366259;11489.965903068764;11491.770164596828;11615.593454229062;11626.825597615873;11664.94167529157;11246.493907774333;11260.605878794202;11291.830622540832;11399.544018239178;11684.532618695497;11384.37385943781;11480.024197943269;11487.597934072268;11533.019693326161;11833.467493962964;8228;8284.005919289724;8293.543855088028;8297.875531866906;8301.750947471586;10466.424346661006;11227.045701115496;11401.771964560496;11413;11436.542538298707 09:34:16 INFO - PROCESS | 1649 | |3;mootools.html;1376.6351916742058;1491.2618253964285;1543.560085375668;1687.9578010549735;1873.0447471789391;7577.318041376275;7682.347000504957;7865.780803975385;8508.404411691168;8618.439801412904;8228.30059444948;8472.110428405022;8491;8494.745157645266;8497.065322814486;2014.4963759060236;2084.7915208479108;2093.0581238442687;2286.622707253301;2318.211807985285;2105.9473513162216;2134.3596920923737;2263.8302127340435;2348.1801603757053;2349.706286714161;2017.4855411869953;2258.774122587736;2313.710786151731;2313.8958746856347;2316.3745788637057;2247.8763667998255;2297.1500544798364;2299.7585253548473;2304.4008557774932;2306.5963456395066;1776.8045514993341;2257.9661305080435;2330.4523436992295;2340;2345.566070277004;8469.153084691543;8523.957380213122;8530.104339044436;8536.658533658647;8584;5055.191169412913;5206.776407544224;5209.557187639055;5215.322008138955;5220.973895130538;3408.9659103408894;3498.7900725956524;3564.465330200491;3570.732195085379;3576.2489877125577;1041.812473754724;1066.589363095214;1087.2443651662097;1089.6513115802945;1091.7598128411737;11320.886791132065;11356.375399353032;11399.3730344831;11411.42942852854;11420.029297509722;4417.668674849399;4500.302453119772;4664.277037059262;4680.204365257914;4682.555157260055;9649.565769540388;9707.417554946725;9712.514374281258;9715.659951901649;9719.951400242953;5224.085784987688;5226.242194881797;5228.137357335997;5228.816991405282;5231.293775340357;4697.060587882437;4791.688540244873;4801.255805350176;4803.759812009385;4824.541668541483;3840.519935008124;3847.211321679049;3891.2412079644196;3913.3542965410957;3925.1953349563273;1236.4003458322707;1251.1367156661875;1251.3492983648453;1264.76601828662;1264.7913094339515;804;874.3573473496974;896.5248418338291;916.679162293192;942.3544871762864;5535.252740880011;5759.481646651821;5925.05199168131;5933.554983376264;5966.31387390447;877.8200468903859;948.9145976861978;957.4255446731905;959.6737109382843;997.9900200998043;3290.588676415448;3644.9817750911075;3911.92176156481;3941.467901833273;4001.279769641434;10143.49282535885;10360;10525.894741052645;10528.157747380192;10571.312864663772;9836.655717050013;10293.639722609672;10300.587976480856;10479.004494573004;10488.00363965422;7837.412194085467;7857.978462799913;8089.757307280791;8099.06860711014;8111.148329425326;5911.822645320647;5973.710899437286;5994.790182343597;6002.7298771554515;6022.728977196124;9828.606855725691;9832.262580306362;9850.507474626384;9854.063863932915;9864.654737084167;8116.350691944748;9529.475878826732;9728.124468797841;10094.24293178;10127.088562029452;6902.689378978059;7202.567845929261;7446.776596702108;7463.6641351140415;7525.397968162643;7998.440109192301;8048.396370272136;8062.153473885158;8073.555954422563;8092.393070519617;7274.8908766369605;7285.854282914418;7343.375813055736;7391.519551229153;7482.438817088631;7061.6822243000215;7126.821829454305;7148.678309475982;7266.781996540112;7280.453965952681;6737.528373013842;6797.082393876961;6811.386975172259;6817.352351526597;6828.556143850634;4742.241241401359;4893.926591101065;4928.753562321941;4956.752162391938;4980.078685443204;4317.84887528935;4887.218045112765;4931.964287499522;4959.7272150032095;5083.542481176712 09:34:16 INFO - PROCESS | 1649 | |4;prototype.html;298.1963608076235;301.05618884796155;306.91559821049213;307.81684897486;310.5264471680688;4293.699441039129;4442.689011769177;5216.347956505437;5259.579233661308;5298.602604804636;5262.947410517892;5273.182656688219;5276.182191760283;5288.8413347599535;5293.6294459387855;3209.406259841925;3466.202773362128;3758.49260349852;3974.8410063597566;4000.5799391063956;3253.772235943473;3256.576645036142;3738.89702537752;3876.7092468065007;3944.2505923874514;3246.675332466746;3657.890263292092;3898.785566793825;4001.859934902279;4040.3939409088725;3406.160673236437;3874.0314921269683;3994.3009973254857;3997.2804895118866;4004.7797371144575;3080.09034398672;3315.784474009182;3420.846061927219;3490.1798077451776;3512.1570823002553;4169.97915010426;4226.830926762926;4568.520305367955;5061.949380506185;5114.411842638107;4543.7728113594185;4570.542945705436;4588.518205588399;4594.954050459486;4608.493065762764;5078.64449488536;5503.9174412383845;5513.889722205574;5521.751521181556;5563.638363506399;3767.2122629254427;3767.5478942526797;3777.8866634001024;3786.981065094657;3796.1778346393608;5525.806596769094;5560.221568980346;5641.746121424587;5775.740091695841;5883.470487656089;3467.2372078142766;3504.001359612498;3515.6660117288816;3603.279523959446;3796.6332120436623;3230.6607806180014;3859.3439115350457;3863.8840834775;3866.0721426857913;3868.458415821787;4329.502107257643;4472.8210871565425;4504.774761261924;4539.931901021488;4541.159885421208;4839.491853355417;5341.385740639798;5539.534906966362;5549.667019978733;5647.407022262685;1201.3361494330416;1280.014388920526;1302.5180683147107;1314.3888092037057;1316.8748968847945;721.4084450750335;725.5392825555705;767.5471471831646;806.7700705298962;806.7862016565615;3931.2137572485044;3956.5252169739815;4291.527931927485;4318.762468064224;4332.220200363965;4238.427812245307;4417.712848664826;4542.455565107879;4601.838935637236;4665.160271151158;3366.0743295593907;3750.9812450938116;4157.750534967911;4270.380794784739;4281.935770963438;2210.613142700021;2299.7240331160033;2327.581035413608;2327.813774898038;2521.936951576225;4023.9195216095513;4837.95162048382;4869.975650121798;4899.510048995143;4908.509149085135;4332.891677708019;4702.27114797207;4799.6400269979895;4811.9518804811505;4839.556024207753;4404.515503294635;4612.962083531179;4665.9501612137;4900.877478063006;4925.581325587365;4420.557944205682;4712.387389639325;4715.622750180046;4720.300691750898;4727.314539391838;3782.6973842092016;4210.90516465715;4648.2330415481065;4664.883377915444;4665.906681866411;4525.954740452553;4797.496262892486;4849.908770526603;5563;5856.736446859816;3500.387432199304;3729.9556124285245;3775.867844625425;3781.640744129303;3788.981055094707;5394.838154855361;5584.692841893735;5616.382197958303;5633.549316054788;5644.6330988485615;4789.856304310876;5172.689638621695;5550.333959924835;5575.972120139536;5580.469855363734;4367.519572846921;5444.292242008513;5535.529479994246;5586.692731899784;5592.496675299242;3891.452613591761;5402.973435047328;5434.2120392542065;5455.608819750938;5483.945160548344;5035.697858128524;5549.1676248563035;5555.4167312200025;5575.637583557056;5589.6366736163645;4310.2457070012;4475.26158183896;4500.437445319335;4725.818545363659;5416.2688037114485 09:34:16 INFO - PROCESS | 1649 | |5;yui.html;770.2028400605375;776.5379599138512;789.9368050555956;811.111832543365;815.6818840652151;12829.294388808612;15199.316030778615;15896.04623722576;15934.920325398385;16072;14336.641583960407;14346.497872574462;14510.201938893382;14531.055481393701;14630.780538291907;681.5672048249372;703.4794252253321;705.495570666973;842.1283971089923;864.0797550608613;699.6117154978984;701.7263267325746;702.9437644988388;833.6207025803245;868.1405408645459;898.4923518212222;901.2519608724742;906.419891269588;906.5603182456504;907.6051917415945;899.5142622983581;899.6401439424217;899.7570655922897;901.8286525560122;903.9186473217409;520.2300595119207;647.970841312142;667.8029981155571;669.0165456778528;673.3636713305928;14356.138631682132;14386.856131438657;14392.424303027867;14398.352074156688;14420.711585768331;13508.91928645706;13773.449062037507;13892.16647001183;13897.722045559134;14199.290035498183;11175.944120279428;11718.88281117195;11795.115366347472;11895.167338286323;12047.457864396123;4513.571210734975;4554.795034223434;4571.26859702446;4651.44182698075;4775.092732380836;78277;107756.46121769419;108933.45533272362;109201;110552;10773.676789696321;10799.514021869034;10957.780844383067;10996.505157267942;11331.943340283246;20836.166553337698;20847.895760521405;20855.270065547935;20857.68713469299;20881.47796305074;7803.219678032151;7899.763007109796;7912.248336408032;7942.483738556975;7992.840143197103;4396.472423309224;4460.107978404268;4466.843660471868;4594.016030268306;4615.492295847454;3393.50794134854;3497.9475307870403;3498.0205542448157;3503.2818272254126;3504.0539054455153;657.9144711187515;666.1140682891743;709.9847218477523;728.9453291003196;730.0472882887882;553.1923391847866;618.7655627024116;656.924453687832;671.6876652356677;673.397309408077;9537.452519878007;9569.234461243084;9798.461084640414;9843.114119729258;9910.35582687123;569.9857503562362;616.7687117331001;647.4108561209277;648.7956293767448;697.4141720954321;3945.940810887839;4744.430668319824;4747.928781068286;4752.619790416758;4831.661783675109;159262;165675.1716241411;166829.16585417237;169671.15164424098;173047;110229.44885275683;112907.04825331074;113888.43055784667;147346;151690.96618067796;94866;99028;100985;109109.45445272881;113297.43351283355;9884.406935583887;9904.431794979104;10069.345492542961;10093.798124037625;10110.747231319276;55869.720651396485;57593.71203143957;59096.70451647714;59755;61974.38025619867;90859.54570227106;93025;93094;93616.53191733998;94740.52629737083;11309.604163854226;13086.541971030969;13270.668233294246;13367.331633418486;13655.27318995829;14825.703485930437;14987.550373488813;15309.387624494895;15623.062616243062;15648;11432.885671143515;13522.459101635824;14050.508232211823;14221.288935553388;14556.681080703713;13563.525276615272;14176.291185440481;14246.92876535652;14348.49780257686;14625.634359141106;13646.658833528843;13685.452581896612;13700.93149534246;13985.650358741113;14390.568282951528;10715.35707857531;11498.310101393943;11524.884751152382;11709.765804683688;11873.940630296793;10875.456227188768;11423.428828558704;11453.541858325907;11584.304941703187;11773.47019384147 09:34:16 INFO - PROCESS | 1649 | __end_tp_report 09:34:16 INFO - PROCESS | 1649 | __start_cc_report 09:34:16 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1417 09:34:16 INFO - PROCESS | 1649 | __end_cc_report 09:34:16 INFO - PROCESS | 1649 | __startTimestamp1467218056139__endTimestamp 09:34:16 INFO - PROCESS | 1649 | 09:34:16 INFO - PROCESS | 1649 | ------- Summary: start ------- 09:34:16 INFO - PROCESS | 1649 | Number of tests: 6 09:34:16 INFO - PROCESS | 1649 | 09:34:16 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5768.40 Median:6011.46 stddev:644.67 (10.7%) stddev-sans-first:618.92 09:34:16 INFO - PROCESS | 1649 | Values: 3384.0 3394.4 3408.8 3431.9 3475.3 5613.6 5665.4 5706.9 5746.6 5750.8 5722.7 5731.0 5741.3 5828.2 5831.9 5119.0 5148.7 5200.1 5218.5 5313.9 5295.1 5313.5 5331.3 5343.3 5365.4 5160.9 5173.8 5206.4 5224.1 5267.4 5187.9 5202.5 5208.9 5212.8 5220.4 5203.9 5204.1 5220.6 5228.3 5273.6 4669.2 5633.8 5750.3 5828.0 5845.5 6249.0 6283.1 6328.8 6330.8 6331.9 6343.2 6364.2 6364.4 6390.9 6402.5 5707.5 5756.3 5801.7 5847.4 5917.0 6296.3 6307.9 6311.0 6329.4 6355.6 6194.8 6221.3 6225.4 6225.6 6227.5 6109.5 6120.4 6211.1 6258.0 6340.2 5537.6 6260.9 6301.7 6301.9 6315.0 6260.1 6318.7 6319.6 6329.7 6331.2 4915.1 4944.0 4957.9 4958.7 5028.6 4520.1 4522.0 4522.6 4540.7 4547.1 5511.2 5582.5 5680.9 5685.7 5713.0 6124.4 6200.3 6256.7 6278.6 6303.6 5756.7 5768.8 5780.0 5781.6 5816.6 4653.7 5438.5 5457.3 5459.1 5459.5 6253.7 6257.2 6278.0 6297.1 6317.9 6308.8 6313.4 6332.6 6349.2 6352.9 6293.7 6299.0 6299.8 6310.1 6335.7 6128.4 6143.2 6171.8 6227.8 6254.6 6110.7 6309.8 6319.0 6330.2 6357.1 5916.4 6019.2 6140.1 6150.1 6168.8 6035.5 6098.9 6102.3 6102.5 6136.2 5895.3 5952.2 5964.2 5976.3 6011.5 5923.5 6073.2 6110.9 6143.4 6144.8 6018.8 6020.7 6041.1 6044.9 6045.2 09:34:16 INFO - PROCESS | 1649 | 09:34:16 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:72278.78 Median:37739.91 stddev:155617.24 (412.3%) stddev-sans-first:155960.40 09:34:16 INFO - PROCESS | 1649 | Values: 472.8 473.1 480.7 543.4 555.4 30082.8 38810.2 39473.2 42911.4 45313.8 43834.8 44208.3 44226.0 44259.8 44305.3 8761.3 9665.1 9823.5 9848.0 9848.6 8140.5 11320.4 12114.9 12122.5 12453.4 12093.9 12105.9 12148.5 12165.5 12179.5 8815.5 11854.0 12038.3 12089.8 12171.4 11884.7 11936.6 11961.9 11969.9 11979.8 41492.0 41863.0 41981.0 42053.6 42065.4 3429.6 3486.9 3521.9 3621.2 3810.9 31771.6 32310.8 33219.0 33226.8 33325.3 3221.1 3268.8 3415.4 3428.1 3432.8 523410.0 526038.2 527367.0 527378.0 527474.0 62522.7 62915.0 62919.7 63003.0 63015.0 64600.0 65031.7 65112.0 65149.3 65184.0 16541.3 16700.2 16784.0 16812.4 16818.6 11737.9 12020.5 12041.0 12249.8 13009.7 15220.6 15317.4 15473.1 15815.4 17330.5 5350.4 6296.5 6304.3 6348.9 6370.0 951.9 987.4 1009.0 1045.7 1103.8 27872.7 30158.8 30244.9 30863.7 31055.0 1072.8 1100.1 1136.3 1137.3 1149.3 15626.4 15643.0 16036.9 16046.4 16406.4 659268.0 665021.0 665482.0 666186.0 666211.0 59426.0 60783.7 60799.0 60839.4 60912.4 1260.9 1298.0 1386.3 1389.9 1423.6 30079.2 32138.4 32171.8 32442.0 32836.3 51996.0 52648.7 52694.7 52752.9 52806.4 544305.0 548687.0 550106.0 550570.0 551392.0 33474.7 34132.0 43317.4 45325.8 45341.1 48852.0 49659.5 49682.5 50822.2 50890.7 36159.5 37383.8 39246.8 40932.0 41547.8 38425.4 39984.6 41055.6 41389.0 41608.8 31691.5 38096.0 38559.0 39553.0 39591.6 32942.2 34511.7 34748.8 35416.5 35466.0 21510.4 25753.4 25883.6 26043.7 26168.9 09:34:16 INFO - PROCESS | 1649 | 09:34:16 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:34356.92 Median:13957.19 stddev:91875.80 (658.3%) stddev-sans-first:92098.06 09:34:16 INFO - PROCESS | 1649 | Values: 355.3 376.6 386.4 387.1 407.6 15595.4 15639.1 20266.0 20613.8 20637.0 20708.4 21000.0 21076.6 21229.9 21318.3 5715.1 5972.3 5972.5 6023.7 6620.8 6528.1 6531.2 6601.3 6609.1 6613.8 6389.6 6411.7 6521.1 6572.2 6577.2 6236.9 6412.6 6492.8 6498.8 6577.0 5661.2 5691.4 5694.5 5698.3 5706.0 25516.7 26531.0 26937.9 26982.9 26987.1 2153.9 2387.0 2397.6 2854.9 3094.6 12025.9 14727.4 15315.7 15318.0 15580.1 1759.5 1850.5 1874.4 1894.0 1919.9 76426.6 108904.0 109970.0 110819.4 112186.0 10429.1 10556.9 10825.6 10853.3 11011.4 13157.4 13266.5 13302.4 13311.9 13326.1 8436.6 8604.6 8664.3 8736.3 8752.6 8680.4 8712.3 8738.3 8824.7 8842.6 5593.8 6554.9 6655.0 6657.4 6671.9 1532.7 1778.6 1814.3 1814.4 1816.3 855.6 897.3 900.8 938.0 1000.8 21101.8 22009.2 22106.6 22162.8 22220.9 906.3 969.2 979.0 984.6 989.6 11288.4 11602.2 11719.5 11819.0 11853.6 495961.0 498905.0 499086.0 601905.0 609011.0 149563.0 153644.7 154614.0 154621.0 154646.0 957.9 981.2 1006.6 1006.7 1008.5 19565.9 19760.9 19796.9 19804.0 19804.6 57440.7 57761.0 59887.4 60041.4 60426.0 56426.0 85934.6 89277.7 89775.1 93098.5 4597.3 5437.9 8166.9 8214.4 8267.2 12186.6 13218.9 13327.9 13636.5 13663.9 11490.0 11491.8 11615.6 11626.8 11664.9 11246.5 11260.6 11291.8 11399.5 11684.5 11384.4 11480.0 11487.6 11533.0 11833.5 8228.0 8284.0 8293.5 8297.9 8301.8 10466.4 11227.0 11401.8 11413.0 11436.5 09:34:16 INFO - PROCESS | 1649 | 09:34:16 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5562.64 Median:5069.37 stddev:3101.34 (61.2%) stddev-sans-first:3094.08 09:34:16 INFO - PROCESS | 1649 | Values: 1376.6 1491.3 1543.6 1688.0 1873.0 7577.3 7682.3 7865.8 8508.4 8618.4 8228.3 8472.1 8491.0 8494.7 8497.1 2014.5 2084.8 2093.1 2286.6 2318.2 2105.9 2134.4 2263.8 2348.2 2349.7 2017.5 2258.8 2313.7 2313.9 2316.4 2247.9 2297.2 2299.8 2304.4 2306.6 1776.8 2258.0 2330.5 2340.0 2345.6 8469.2 8524.0 8530.1 8536.7 8584.0 5055.2 5206.8 5209.6 5215.3 5221.0 3409.0 3498.8 3564.5 3570.7 3576.2 1041.8 1066.6 1087.2 1089.7 1091.8 11320.9 11356.4 11399.4 11411.4 11420.0 4417.7 4500.3 4664.3 4680.2 4682.6 9649.6 9707.4 9712.5 9715.7 9720.0 5224.1 5226.2 5228.1 5228.8 5231.3 4697.1 4791.7 4801.3 4803.8 4824.5 3840.5 3847.2 3891.2 3913.4 3925.2 1236.4 1251.1 1251.3 1264.8 1264.8 804.0 874.4 896.5 916.7 942.4 5535.3 5759.5 5925.1 5933.6 5966.3 877.8 948.9 957.4 959.7 998.0 3290.6 3645.0 3911.9 3941.5 4001.3 10143.5 10360.0 10525.9 10528.2 10571.3 9836.7 10293.6 10300.6 10479.0 10488.0 7837.4 7858.0 8089.8 8099.1 8111.1 5911.8 5973.7 5994.8 6002.7 6022.7 9828.6 9832.3 9850.5 9854.1 9864.7 8116.4 9529.5 9728.1 10094.2 10127.1 6902.7 7202.6 7446.8 7463.7 7525.4 7998.4 8048.4 8062.2 8073.6 8092.4 7274.9 7285.9 7343.4 7391.5 7482.4 7061.7 7126.8 7148.7 7266.8 7280.5 6737.5 6797.1 6811.4 6817.4 6828.6 4742.2 4893.9 4928.8 4956.8 4980.1 4317.8 4887.2 4932.0 4959.7 5083.5 09:34:16 INFO - PROCESS | 1649 | 09:34:16 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4181.92 Median:4532.94 stddev:1299.39 (28.7%) stddev-sans-first:1269.92 09:34:16 INFO - PROCESS | 1649 | Values: 298.2 301.1 306.9 307.8 310.5 4293.7 4442.7 5216.3 5259.6 5298.6 5262.9 5273.2 5276.2 5288.8 5293.6 3209.4 3466.2 3758.5 3974.8 4000.6 3253.8 3256.6 3738.9 3876.7 3944.3 3246.7 3657.9 3898.8 4001.9 4040.4 3406.2 3874.0 3994.3 3997.3 4004.8 3080.1 3315.8 3420.8 3490.2 3512.2 4170.0 4226.8 4568.5 5061.9 5114.4 4543.8 4570.5 4588.5 4595.0 4608.5 5078.6 5503.9 5513.9 5521.8 5563.6 3767.2 3767.5 3777.9 3787.0 3796.2 5525.8 5560.2 5641.7 5775.7 5883.5 3467.2 3504.0 3515.7 3603.3 3796.6 3230.7 3859.3 3863.9 3866.1 3868.5 4329.5 4472.8 4504.8 4539.9 4541.2 4839.5 5341.4 5539.5 5549.7 5647.4 1201.3 1280.0 1302.5 1314.4 1316.9 721.4 725.5 767.5 806.8 806.8 3931.2 3956.5 4291.5 4318.8 4332.2 4238.4 4417.7 4542.5 4601.8 4665.2 3366.1 3751.0 4157.8 4270.4 4281.9 2210.6 2299.7 2327.6 2327.8 2521.9 4023.9 4838.0 4870.0 4899.5 4908.5 4332.9 4702.3 4799.6 4812.0 4839.6 4404.5 4613.0 4666.0 4900.9 4925.6 4420.6 4712.4 4715.6 4720.3 4727.3 3782.7 4210.9 4648.2 4664.9 4665.9 4526.0 4797.5 4849.9 5563.0 5856.7 3500.4 3730.0 3775.9 3781.6 3789.0 5394.8 5584.7 5616.4 5633.5 5644.6 4789.9 5172.7 5550.3 5576.0 5580.5 4367.5 5444.3 5535.5 5586.7 5592.5 3891.5 5403.0 5434.2 5455.6 5483.9 5035.7 5549.2 5555.4 5575.6 5589.6 4310.2 4475.3 4500.4 4725.8 5416.3 09:34:16 INFO - PROCESS | 1649 | 09:34:16 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:24950.21 Median:3445.73 stddev:40502.65 (1175.4%) stddev-sans-first:40575.58 09:34:16 INFO - PROCESS | 1649 | Values: 770.2 776.5 789.9 811.1 815.7 12829.3 15199.3 15896.0 15934.9 16072.0 14336.6 14346.5 14510.2 14531.1 14630.8 681.6 703.5 705.5 842.1 864.1 699.6 701.7 702.9 833.6 868.1 898.5 901.3 906.4 906.6 907.6 899.5 899.6 899.8 901.8 903.9 520.2 648.0 667.8 669.0 673.4 14356.1 14386.9 14392.4 14398.4 14420.7 13508.9 13773.4 13892.2 13897.7 14199.3 11175.9 11718.9 11795.1 11895.2 12047.5 4513.6 4554.8 4571.3 4651.4 4775.1 78277.0 107756.5 108933.5 109201.0 110552.0 10773.7 10799.5 10957.8 10996.5 11331.9 20836.2 20847.9 20855.3 20857.7 20881.5 7803.2 7899.8 7912.2 7942.5 7992.8 4396.5 4460.1 4466.8 4594.0 4615.5 3393.5 3497.9 3498.0 3503.3 3504.1 657.9 666.1 710.0 728.9 730.0 553.2 618.8 656.9 671.7 673.4 9537.5 9569.2 9798.5 9843.1 9910.4 570.0 616.8 647.4 648.8 697.4 3945.9 4744.4 4747.9 4752.6 4831.7 159262.0 165675.2 166829.2 169671.2 173047.0 110229.4 112907.0 113888.4 147346.0 151691.0 94866.0 99028.0 100985.0 109109.5 113297.4 9884.4 9904.4 10069.3 10093.8 10110.7 55869.7 57593.7 59096.7 59755.0 61974.4 90859.5 93025.0 93094.0 93616.5 94740.5 11309.6 13086.5 13270.7 13367.3 13655.3 14825.7 14987.6 15309.4 15623.1 15648.0 11432.9 13522.5 14050.5 14221.3 14556.7 13563.5 14176.3 14246.9 14348.5 14625.6 13646.7 13685.5 13700.9 13985.7 14390.6 10715.4 11498.3 11524.9 11709.8 11873.9 10875.5 11423.4 11453.5 11584.3 11773.5 09:34:16 INFO - PROCESS | 1649 | -------- Summary: end -------- 09:34:16 INFO - PROCESS | 1649 | 09:34:16 INFO - TEST-INFO | 1649: exit 0 09:34:16 INFO - TEST-OK | dromaeo_css | took 1116437ms 09:34:16 INFO - TEST-START | kraken 09:34:16 INFO - operating with platform_type : mac_ 09:34:16 INFO - Initialising browser for kraken test... 09:34:16 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpXgxzw9/profile http://localhost:49274/getInfo.html) 09:34:18 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 09:34:18 INFO - PROCESS | 1660 | colorDepth:24 09:34:18 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 09:34:18 INFO - PROCESS | 1660 | __metrics 09:34:19 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:34:19 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:34:19 INFO - TEST-INFO | 1660: exit 0 09:34:19 INFO - Browser initialized. 09:34:19 INFO - Running cycle 1/1 for kraken test... 09:34:19 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpXgxzw9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:31 INFO - PROCESS | 1661 | RSS: Main: 243953664 09:34:31 INFO - PROCESS | 1661 | 09:34:34 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 09:34:35 INFO - PROCESS | 1661 | RSS: Main: 294137856 09:34:35 INFO - PROCESS | 1661 | 09:34:37 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 09:34:38 INFO - PROCESS | 1661 | RSS: Main: 304910336 09:34:38 INFO - PROCESS | 1661 | 09:34:41 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 09:34:42 INFO - PROCESS | 1661 | RSS: Main: 389042176 09:34:42 INFO - PROCESS | 1661 | 09:34:44 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 09:34:45 INFO - PROCESS | 1661 | RSS: Main: 292450304 09:34:45 INFO - PROCESS | 1661 | 09:34:47 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:34:47 INFO - PROCESS | 1661 | RSS: Main: 296378368 09:34:47 INFO - PROCESS | 1661 | 09:34:50 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 09:34:51 INFO - PROCESS | 1661 | RSS: Main: 332513280 09:34:51 INFO - PROCESS | 1661 | 09:34:53 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 09:34:54 INFO - PROCESS | 1661 | RSS: Main: 334950400 09:34:54 INFO - PROCESS | 1661 | 09:34:57 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 09:34:57 INFO - PROCESS | 1661 | RSS: Main: 333250560 09:34:57 INFO - PROCESS | 1661 | 09:34:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:35:00 INFO - PROCESS | 1661 | RSS: Main: 311853056 09:35:00 INFO - PROCESS | 1661 | 09:35:02 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:35:02 INFO - PROCESS | 1661 | RSS: Main: 319381504 09:35:02 INFO - PROCESS | 1661 | 09:35:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:35:05 INFO - PROCESS | 1661 | RSS: Main: 316145664 09:35:05 INFO - PROCESS | 1661 | 09:35:08 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:35:09 INFO - PROCESS | 1661 | RSS: Main: 325537792 09:35:09 INFO - PROCESS | 1661 | 09:35:11 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:35:12 INFO - PROCESS | 1661 | RSS: Main: 327479296 09:35:12 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 09:35:14 INFO - PROCESS | 1661 | RSS: Main: 371441664 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | __start_tp_report 09:35:14 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 09:35:14 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 09:35:14 INFO - PROCESS | 1661 | |i|pagename|runs| 09:35:14 INFO - PROCESS | 1661 | |0;ai-astar;117;113;113;113;113;114;114;114;113;113 09:35:14 INFO - PROCESS | 1661 | |1;audio-beat-detection;143;114;106;156;107;107;129;108;138;112 09:35:14 INFO - PROCESS | 1661 | |2;audio-dft;185;186;181;182;184;185;181;181;183;185 09:35:14 INFO - PROCESS | 1661 | |3;audio-fft;78;75;58;105;57;57;60;64;96;61 09:35:14 INFO - PROCESS | 1661 | |4;audio-oscillator;85;90;94;70;71;68;93;70;69;98 09:35:14 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;90;87;90;88;90;88;90;88;91;88 09:35:14 INFO - PROCESS | 1661 | |6;imaging-darkroom;95;96;96;96;94;95;95;94;95;96 09:35:14 INFO - PROCESS | 1661 | |7;imaging-desaturate;87;84;86;85;84;86;84;87;85;85 09:35:14 INFO - PROCESS | 1661 | |8;json-parse-financial;56;53;55;52;54;55;53;53;55;54 09:35:14 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;68;76;74;63;72;72;101;104;69;69 09:35:14 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;84;83;82;82;84;84;84;83;81;83 09:35:14 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;140;147;149;135;135;135;128;136;177;135 09:35:14 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;167;164;164;162;164;162;165;164;162;220 09:35:14 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;68;66;68;67;66;66;67;67;66;68 09:35:14 INFO - PROCESS | 1661 | __end_tp_report 09:35:14 INFO - PROCESS | 1661 | __start_cc_report 09:35:14 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2042 09:35:14 INFO - PROCESS | 1661 | __end_cc_report 09:35:14 INFO - PROCESS | 1661 | __startTimestamp1467218114481__endTimestamp 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | ------- Summary: start ------- 09:35:14 INFO - PROCESS | 1661 | Number of tests: 14 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:113.70 Median:113.50 stddev:1.25 (1.1%) stddev-sans-first:0.50 09:35:14 INFO - PROCESS | 1661 | Values: 117.0 113.0 113.0 113.0 113.0 114.0 114.0 114.0 113.0 113.0 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:122.00 Median:121.50 stddev:18.16 (14.9%) stddev-sans-first:17.60 09:35:14 INFO - PROCESS | 1661 | Values: 143.0 114.0 106.0 156.0 107.0 107.0 129.0 108.0 138.0 112.0 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.50 stddev:1.95 (1.1%) stddev-sans-first:1.96 09:35:14 INFO - PROCESS | 1661 | Values: 185.0 186.0 181.0 182.0 184.0 185.0 181.0 181.0 183.0 185.0 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:71.10 Median:62.50 stddev:17.25 (27.6%) stddev-sans-first:18.11 09:35:14 INFO - PROCESS | 1661 | Values: 78.0 75.0 58.0 105.0 57.0 57.0 60.0 64.0 96.0 61.0 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:80.80 Median:87.50 stddev:12.26 (14.0%) stddev-sans-first:12.91 09:35:14 INFO - PROCESS | 1661 | Values: 85.0 90.0 94.0 70.0 71.0 68.0 93.0 70.0 69.0 98.0 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:90.00 stddev:1.33 (1.5%) stddev-sans-first:1.36 09:35:14 INFO - PROCESS | 1661 | Values: 90.0 87.0 90.0 88.0 90.0 88.0 90.0 88.0 91.0 88.0 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:95.50 stddev:0.79 (0.8%) stddev-sans-first:0.83 09:35:14 INFO - PROCESS | 1661 | Values: 95.0 96.0 96.0 96.0 94.0 95.0 95.0 94.0 95.0 96.0 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:1.16 (1.4%) stddev-sans-first:1.05 09:35:14 INFO - PROCESS | 1661 | Values: 87.0 84.0 86.0 85.0 84.0 86.0 84.0 87.0 85.0 85.0 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.50 stddev:1.25 (2.3%) stddev-sans-first:1.09 09:35:14 INFO - PROCESS | 1661 | Values: 56.0 53.0 55.0 52.0 54.0 55.0 53.0 53.0 55.0 54.0 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:76.80 Median:70.50 stddev:14.02 (19.9%) stddev-sans-first:14.51 09:35:14 INFO - PROCESS | 1661 | Values: 68.0 76.0 74.0 63.0 72.0 72.0 101.0 104.0 69.0 69.0 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.50 stddev:1.05 (1.3%) stddev-sans-first:1.05 09:35:14 INFO - PROCESS | 1661 | Values: 84.0 83.0 82.0 82.0 84.0 84.0 84.0 83.0 81.0 83.0 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:141.70 Median:138.00 stddev:13.86 (10.0%) stddev-sans-first:14.69 09:35:14 INFO - PROCESS | 1661 | Values: 140.0 147.0 149.0 135.0 135.0 135.0 128.0 136.0 177.0 135.0 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.40 Median:164.00 stddev:17.85 (10.9%) stddev-sans-first:18.91 09:35:14 INFO - PROCESS | 1661 | Values: 167.0 164.0 164.0 162.0 164.0 162.0 165.0 164.0 162.0 220.0 09:35:14 INFO - PROCESS | 1661 | 09:35:14 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.88 (1.3%) stddev-sans-first:0.83 09:35:14 INFO - PROCESS | 1661 | Values: 68.0 66.0 68.0 67.0 66.0 66.0 67.0 67.0 66.0 68.0 09:35:14 INFO - PROCESS | 1661 | -------- Summary: end -------- 09:35:14 INFO - PROCESS | 1661 | 09:35:15 INFO - TEST-INFO | 1661: exit 0 09:35:15 INFO - TEST-OK | kraken | took 58573ms 09:35:15 INFO - SUITE-END | took 1175s 09:35:15 INFO - Completed test suite (00:19:35) 09:35:15 INFO - javascript benchmark 09:35:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3383.966160338396, 3394.4059789536823, 3408.7613867029304, 3431.9142021449475, 3475.322312149132, 5613.635113717605, 5665.376808551061, 5706.942930570693, 5746.569007324451, 5750.798722044724, 5722.685252311121, 5730.971345143279, 5741.311042674884, 5828.213191219184, 5831.883362332751, 5119.001794650045, 5148.6910785353, 5200.063988482072, 5218.530332270095, 5313.893722125575, 5295.0733621616255, 5313.548348390372, 5331.333583302087, 5343.305370301855, 5365.436629153922, 5160.922586161192, 5173.793048278064, 5206.401263854667, 5224.111900976843, 5267.3679158500845, 5187.870303242411, 5202.4537423570355, 5208.947910520884, 5212.765425555859, 5220.425753167148, 5203.895922081575, 5204.141316682742, 5220.608454365938, 5228.294180285651, 5273.551748101416, 4669.229577119771, 5633.774649014034, 5750.252467179282, 5827.970860145714, 5845.532357411397, 6248.968755156195, 6283.057541368786, 6328.841778955517, 6330.841728956813, 6331.905021424636, 6343.238811342622, 6364.172657554534, 6364.395382438661, 6390.93609063908, 6402.455791257748, 5707.514861236758, 5756.30924289088, 5801.680907550125, 5847.415258474161, 5917.023691090965, 6296.275928268216, 6307.936920630735, 6310.968445157745, 6329.398707122816, 6355.586886852339, 6194.845128871814, 6221.2845522764555, 6225.439710425992, 6225.595336303126, 6227.501799856001, 6109.495762817132, 6120.357362476965, 6211.09939058834, 6258.0, 6340.175777148941, 5537.556995440355, 6260.937390626126, 6301.684915754194, 6301.936980630227, 6315.0, 6260.123582698426, 6318.6524741138755, 6319.58922670025, 6329.7468101275435, 6331.2085989251345, 4915.066137433876, 4944.035912996931, 4957.851264462072, 4958.727270000185, 5028.5977121830165, 4520.118576877543, 4521.954780452219, 4522.638188944942, 4540.704854184468, 4547.090581883636, 5511.173324001352, 5582.469665381782, 5680.943190568124, 5685.687287199161, 5713.0, 6124.3809352984035, 6200.255969283625, 6256.687165641791, 6278.560500764994, 6303.621782693053, 5756.654600723886, 5768.798092066758, 5780.0, 5781.566382521328, 5816.621919575214, 4653.674242803039, 5438.537724293479, 5457.263269458652, 5459.099248623933, 5459.508644222039, 6253.749850005949, 6257.2491301044165, 6278.0269058296035, 6297.086922396318, 6317.873642527032, 6308.84227894288, 6313.40022697843, 6332.556721029484, 6349.174607301022, 6352.9047064295, 6293.653849038164, 6299.0, 6299.779507717208, 6310.085037669604, 6335.746570137328, 6128.356522565066, 6143.170671959407, 6171.783987560414, 6227.813165605039, 6254.562180647311, 6110.694465276807, 6309.779157729457, 6319.0, 6330.177076979963, 6357.078223657636, 5916.408359164049, 6019.187409699634, 6140.109684095692, 6150.077488376779, 6168.845778855564, 6035.456808887221, 6098.855844323317, 6102.2771839767565, 6102.4589553536725, 6136.243373036715, 5895.292564892069, 5952.226210592768, 5964.224650795369, 5976.342602313655, 6011.458968692838, 5923.46688797993, 6073.240844894388, 6110.908336375047, 6143.416375444325, 6144.771045790771, 6018.849528761816, 6020.6989650516425, 6041.124037014521, 6044.909326360196, 6045.183900173421], "value": 5727.58695898331, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.76125556593917, 473.11527443680313, 480.6971607887032, 543.4483998741279, 555.4029418375247, 30082.796688132476, 38810.22379552407, 39473.223704933276, 42911.35632965508, 45313.77343113288, 43834.78082609591, 44208.3368749469, 44226.0, 44259.77870110653, 44305.335419968724, 8761.342899282547, 9665.08181722737, 9823.459709715962, 9847.950760246225, 9848.55681494331, 8140.4708693935, 11320.433978301093, 12114.93942530286, 12122.515099396014, 12453.377331133452, 12093.93953030238, 12105.878941210563, 12148.514059437612, 12165.51337946481, 12179.51281948721, 8815.515146666994, 11854.0, 12038.277703337828, 12089.758204835898, 12171.391430428488, 11884.702882427922, 11936.58221962227, 11961.940190298992, 11969.94015029928, 11979.760404791943, 41491.962700932716, 41863.0, 41981.0, 42053.57946420527, 42065.369019464735, 3429.6261747164226, 3486.89539313821, 3521.9295614087573, 3621.1671315597296, 3810.942835857464, 31771.616984720542, 32310.83844580762, 33219.0, 33226.833865830755, 33325.33349333024, 3221.11419359678, 3268.7548433867555, 3415.3852306584804, 3428.074419906636, 3432.845521951518, 523410.0, 526038.1773275873, 527367.0, 527378.0, 527474.0, 62522.749545008846, 62915.0, 62919.6854015727, 63003.0, 63015.0, 64600.0, 65031.67484162644, 65112.0, 65149.348506514325, 65184.0, 16541.338346466007, 16700.164991750364, 16784.0, 16812.411565595146, 16818.57953551171, 11737.88262117385, 12020.45907934145, 12041.0, 12249.816252756216, 13009.739805203842, 15220.630143287157, 15317.387304507918, 15473.071615703095, 15815.36738530469, 17330.480085597454, 5350.357957045179, 6296.5277604178955, 6304.306526282106, 6348.936510634927, 6370.0, 951.8524628682566, 987.4124895687198, 1008.9747756306004, 1045.749020235138, 1103.8178700514486, 27872.737257152243, 30158.84920575383, 30244.897959183672, 30863.69136308653, 31055.0, 1072.828347464402, 1100.0814320042693, 1136.3182090745652, 1137.294877176156, 1149.3333866357493, 15626.374945002073, 15643.0, 16036.919815401081, 16046.358145674276, 16406.425775097814, 659268.0, 665021.0, 665482.0, 666186.0, 666211.0, 59426.0, 60783.69608151931, 60799.0, 60839.39160608426, 60912.39087609067, 1260.8865202131851, 1297.9935100324437, 1386.272207091285, 1389.9235542045083, 1423.6440889777555, 30079.248018799706, 32138.35723285568, 32171.839140804146, 32442.0, 32836.34327313488, 51996.0, 52648.73675631597, 52694.68263293295, 52752.944941101734, 52806.41580752584, 544305.0, 548687.0, 550106.0, 550570.0, 551392.0, 33474.66101356016, 34132.0, 43317.3502397458, 45325.773371132935, 45341.09317813559, 48852.0, 49659.503404965486, 49682.50952471432, 50822.23766643577, 50890.74554627203, 36159.45760813643, 37383.81308093442, 39246.80376598213, 40932.0, 41547.7922610385, 38425.42361864518, 39984.600153998086, 41055.58944410518, 41389.0, 41608.791956040026, 31691.52462713015, 38096.0, 38559.0, 39553.0, 39591.604083958795, 32942.17644558905, 34511.65488345084, 34748.82625586856, 35416.46875296924, 35466.0, 21510.386720995673, 25753.356166095997, 25883.61174582345, 26043.73956260489, 26168.8691556541], "value": 20860.537300460004, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.27878406834105, 376.62149539712584, 386.41072364643895, 387.1173723909487, 407.63313018283543, 15595.376184952602, 15639.06165630063, 20266.0, 20613.76317420956, 20636.9681515924, 20708.403294155247, 21000.0, 21076.578468430624, 21229.893850530763, 21318.253861114863, 5715.142728590714, 5972.25346831646, 5972.522198224132, 6023.728932198062, 6620.801375958729, 6528.053432252321, 6531.216254049521, 6601.339866013408, 6609.140811694473, 6613.801585952429, 6389.616623002611, 6411.67941602918, 6521.08704781331, 6572.244191917919, 6577.243616984037, 6236.875262494725, 6412.583182093149, 6492.805215843508, 6498.805035848908, 6576.967115164442, 5661.207430959627, 5691.402402747734, 5694.544436445074, 5698.259226300595, 5706.0, 25516.744832551623, 26531.0, 26937.922483100654, 26982.865085674643, 26987.05545305905, 2153.9461513462134, 2387.0, 2397.6403539469043, 2854.8723254314427, 3094.6441159266747, 12025.93987030068, 14727.410903563845, 15315.693686126326, 15318.0, 15580.065196088271, 1759.52492826936, 1850.4541160357726, 1874.4189301316635, 1893.9621207575772, 1919.89440580768, 76426.61786691086, 108904.0, 109970.0, 110819.44590277078, 112186.0, 10429.061384475282, 10556.89443105559, 10825.621103261348, 10853.34879907208, 11011.449427528592, 13157.407916643773, 13266.469341226435, 13302.401391937192, 13311.933440332736, 13326.06717529783, 8436.620352084172, 8604.61279242423, 8664.306855451548, 8736.301095912311, 8752.562371881508, 8680.392372533988, 8712.346574006975, 8738.30093592511, 8824.691135810344, 8842.646294148162, 5593.8321850344555, 6554.901676474761, 6655.0, 6657.434118099919, 6671.899921501182, 1532.7241096602718, 1778.5820332221917, 1814.321752379044, 1814.4193857965588, 1816.3188804198426, 855.6021450025768, 897.3135551303258, 900.7522931193975, 937.9624815007323, 1000.8148492528906, 21101.78898210998, 22009.22967696123, 22106.557868842534, 22162.77837221607, 22220.88889555574, 906.3474298505066, 969.2391472694043, 978.9608415663436, 984.5766320482261, 989.5942663507927, 11288.435578221057, 11602.245854019462, 11719.472623731952, 11818.995385392338, 11853.644390668294, 495961.0, 498905.0, 499086.0, 601905.0, 609010.9549452225, 149563.0, 153644.6667229544, 154614.0, 154621.0, 154646.0, 957.9329446938787, 981.2101258486987, 1006.6074231049896, 1006.7181189267017, 1008.4806324742764, 19565.923874186487, 19760.901195494505, 19796.901015495405, 19804.0, 19804.60390792205, 57440.71279643575, 57761.0, 59887.40112598993, 60041.399586005326, 60426.0, 56426.0, 85934.57032714796, 89277.66083508883, 89775.10224897668, 93098.53450732703, 4597.310403439377, 5437.912417516592, 8166.873960409431, 8214.398192352674, 8267.173282671925, 12186.634400967985, 13218.867811321763, 13327.933360333136, 13636.522721704692, 13663.863361366259, 11489.965903068764, 11491.770164596828, 11615.593454229062, 11626.825597615873, 11664.94167529157, 11246.493907774333, 11260.605878794202, 11291.830622540832, 11399.544018239178, 11684.532618695497, 11384.37385943781, 11480.024197943269, 11487.597934072268, 11533.019693326161, 11833.467493962964, 8228.0, 8284.005919289724, 8293.543855088028, 8297.875531866906, 8301.750947471586, 10466.424346661006, 11227.045701115496, 11401.771964560496, 11413.0, 11436.542538298707], "value": 9930.811278379622, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1376.6351916742058, 1491.2618253964285, 1543.560085375668, 1687.9578010549735, 1873.0447471789391, 7577.318041376275, 7682.347000504957, 7865.780803975385, 8508.404411691168, 8618.439801412904, 8228.30059444948, 8472.110428405022, 8491.0, 8494.745157645266, 8497.065322814486, 2014.4963759060236, 2084.7915208479108, 2093.0581238442687, 2286.622707253301, 2318.211807985285, 2105.9473513162216, 2134.3596920923737, 2263.8302127340435, 2348.1801603757053, 2349.706286714161, 2017.4855411869953, 2258.774122587736, 2313.710786151731, 2313.8958746856347, 2316.3745788637057, 2247.8763667998255, 2297.1500544798364, 2299.7585253548473, 2304.4008557774932, 2306.5963456395066, 1776.8045514993341, 2257.9661305080435, 2330.4523436992295, 2340.0, 2345.566070277004, 8469.153084691543, 8523.957380213122, 8530.104339044436, 8536.658533658647, 8584.0, 5055.191169412913, 5206.776407544224, 5209.557187639055, 5215.322008138955, 5220.973895130538, 3408.9659103408894, 3498.7900725956524, 3564.465330200491, 3570.732195085379, 3576.2489877125577, 1041.812473754724, 1066.589363095214, 1087.2443651662097, 1089.6513115802945, 1091.7598128411737, 11320.886791132065, 11356.375399353032, 11399.3730344831, 11411.42942852854, 11420.029297509722, 4417.668674849399, 4500.302453119772, 4664.277037059262, 4680.204365257914, 4682.555157260055, 9649.565769540388, 9707.417554946725, 9712.514374281258, 9715.659951901649, 9719.951400242953, 5224.085784987688, 5226.242194881797, 5228.137357335997, 5228.816991405282, 5231.293775340357, 4697.060587882437, 4791.688540244873, 4801.255805350176, 4803.759812009385, 4824.541668541483, 3840.519935008124, 3847.211321679049, 3891.2412079644196, 3913.3542965410957, 3925.1953349563273, 1236.4003458322707, 1251.1367156661875, 1251.3492983648453, 1264.76601828662, 1264.7913094339515, 804.0, 874.3573473496974, 896.5248418338291, 916.679162293192, 942.3544871762864, 5535.252740880011, 5759.481646651821, 5925.05199168131, 5933.554983376264, 5966.31387390447, 877.8200468903859, 948.9145976861978, 957.4255446731905, 959.6737109382843, 997.9900200998043, 3290.588676415448, 3644.9817750911075, 3911.92176156481, 3941.467901833273, 4001.279769641434, 10143.49282535885, 10360.0, 10525.894741052645, 10528.157747380192, 10571.312864663772, 9836.655717050013, 10293.639722609672, 10300.587976480856, 10479.004494573004, 10488.00363965422, 7837.412194085467, 7857.978462799913, 8089.757307280791, 8099.06860711014, 8111.148329425326, 5911.822645320647, 5973.710899437286, 5994.790182343597, 6002.7298771554515, 6022.728977196124, 9828.606855725691, 9832.262580306362, 9850.507474626384, 9854.063863932915, 9864.654737084167, 8116.350691944748, 9529.475878826732, 9728.124468797841, 10094.24293178, 10127.088562029452, 6902.689378978059, 7202.567845929261, 7446.776596702108, 7463.6641351140415, 7525.397968162643, 7998.440109192301, 8048.396370272136, 8062.153473885158, 8073.555954422563, 8092.393070519617, 7274.8908766369605, 7285.854282914418, 7343.375813055736, 7391.519551229153, 7482.438817088631, 7061.6822243000215, 7126.821829454305, 7148.678309475982, 7266.781996540112, 7280.453965952681, 6737.528373013842, 6797.082393876961, 6811.386975172259, 6817.352351526597, 6828.556143850634, 4742.241241401359, 4893.926591101065, 4928.753562321941, 4956.752162391938, 4980.078685443204, 4317.84887528935, 4887.218045112765, 4931.964287499522, 4959.7272150032095, 5083.542481176712], "value": 4477.272864788537, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.1963608076235, 301.05618884796155, 306.91559821049213, 307.81684897486, 310.5264471680688, 4293.699441039129, 4442.689011769177, 5216.347956505437, 5259.579233661308, 5298.602604804636, 5262.947410517892, 5273.182656688219, 5276.182191760283, 5288.8413347599535, 5293.6294459387855, 3209.406259841925, 3466.202773362128, 3758.49260349852, 3974.8410063597566, 4000.5799391063956, 3253.772235943473, 3256.576645036142, 3738.89702537752, 3876.7092468065007, 3944.2505923874514, 3246.675332466746, 3657.890263292092, 3898.785566793825, 4001.859934902279, 4040.3939409088725, 3406.160673236437, 3874.0314921269683, 3994.3009973254857, 3997.2804895118866, 4004.7797371144575, 3080.09034398672, 3315.784474009182, 3420.846061927219, 3490.1798077451776, 3512.1570823002553, 4169.97915010426, 4226.830926762926, 4568.520305367955, 5061.949380506185, 5114.411842638107, 4543.7728113594185, 4570.542945705436, 4588.518205588399, 4594.954050459486, 4608.493065762764, 5078.64449488536, 5503.9174412383845, 5513.889722205574, 5521.751521181556, 5563.638363506399, 3767.2122629254427, 3767.5478942526797, 3777.8866634001024, 3786.981065094657, 3796.1778346393608, 5525.806596769094, 5560.221568980346, 5641.746121424587, 5775.740091695841, 5883.470487656089, 3467.2372078142766, 3504.001359612498, 3515.6660117288816, 3603.279523959446, 3796.6332120436623, 3230.6607806180014, 3859.3439115350457, 3863.8840834775, 3866.0721426857913, 3868.458415821787, 4329.502107257643, 4472.8210871565425, 4504.774761261924, 4539.931901021488, 4541.159885421208, 4839.491853355417, 5341.385740639798, 5539.534906966362, 5549.667019978733, 5647.407022262685, 1201.3361494330416, 1280.014388920526, 1302.5180683147107, 1314.3888092037057, 1316.8748968847945, 721.4084450750335, 725.5392825555705, 767.5471471831646, 806.7700705298962, 806.7862016565615, 3931.2137572485044, 3956.5252169739815, 4291.527931927485, 4318.762468064224, 4332.220200363965, 4238.427812245307, 4417.712848664826, 4542.455565107879, 4601.838935637236, 4665.160271151158, 3366.0743295593907, 3750.9812450938116, 4157.750534967911, 4270.380794784739, 4281.935770963438, 2210.613142700021, 2299.7240331160033, 2327.581035413608, 2327.813774898038, 2521.936951576225, 4023.9195216095513, 4837.95162048382, 4869.975650121798, 4899.510048995143, 4908.509149085135, 4332.891677708019, 4702.27114797207, 4799.6400269979895, 4811.9518804811505, 4839.556024207753, 4404.515503294635, 4612.962083531179, 4665.9501612137, 4900.877478063006, 4925.581325587365, 4420.557944205682, 4712.387389639325, 4715.622750180046, 4720.300691750898, 4727.314539391838, 3782.6973842092016, 4210.90516465715, 4648.2330415481065, 4664.883377915444, 4665.906681866411, 4525.954740452553, 4797.496262892486, 4849.908770526603, 5563.0, 5856.736446859816, 3500.387432199304, 3729.9556124285245, 3775.867844625425, 3781.640744129303, 3788.981055094707, 5394.838154855361, 5584.692841893735, 5616.382197958303, 5633.549316054788, 5644.6330988485615, 4789.856304310876, 5172.689638621695, 5550.333959924835, 5575.972120139536, 5580.469855363734, 4367.519572846921, 5444.292242008513, 5535.529479994246, 5586.692731899784, 5592.496675299242, 3891.452613591761, 5402.973435047328, 5434.2120392542065, 5455.608819750938, 5483.945160548344, 5035.697858128524, 5549.1676248563035, 5555.4167312200025, 5575.637583557056, 5589.6366736163645, 4310.2457070012, 4475.26158183896, 4500.437445319335, 4725.818545363659, 5416.2688037114485], "value": 3774.922278401594, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [770.2028400605375, 776.5379599138512, 789.9368050555956, 811.111832543365, 815.6818840652151, 12829.294388808612, 15199.316030778615, 15896.04623722576, 15934.920325398385, 16072.0, 14336.641583960407, 14346.497872574462, 14510.201938893382, 14531.055481393701, 14630.780538291907, 681.5672048249372, 703.4794252253321, 705.495570666973, 842.1283971089923, 864.0797550608613, 699.6117154978984, 701.7263267325746, 702.9437644988388, 833.6207025803245, 868.1405408645459, 898.4923518212222, 901.2519608724742, 906.419891269588, 906.5603182456504, 907.6051917415945, 899.5142622983581, 899.6401439424217, 899.7570655922897, 901.8286525560122, 903.9186473217409, 520.2300595119207, 647.970841312142, 667.8029981155571, 669.0165456778528, 673.3636713305928, 14356.138631682132, 14386.856131438657, 14392.424303027867, 14398.352074156688, 14420.711585768331, 13508.91928645706, 13773.449062037507, 13892.16647001183, 13897.722045559134, 14199.290035498183, 11175.944120279428, 11718.88281117195, 11795.115366347472, 11895.167338286323, 12047.457864396123, 4513.571210734975, 4554.795034223434, 4571.26859702446, 4651.44182698075, 4775.092732380836, 78277.0, 107756.46121769419, 108933.45533272362, 109201.0, 110552.0, 10773.676789696321, 10799.514021869034, 10957.780844383067, 10996.505157267942, 11331.943340283246, 20836.166553337698, 20847.895760521405, 20855.270065547935, 20857.68713469299, 20881.47796305074, 7803.219678032151, 7899.763007109796, 7912.248336408032, 7942.483738556975, 7992.840143197103, 4396.472423309224, 4460.107978404268, 4466.843660471868, 4594.016030268306, 4615.492295847454, 3393.50794134854, 3497.9475307870403, 3498.0205542448157, 3503.2818272254126, 3504.0539054455153, 657.9144711187515, 666.1140682891743, 709.9847218477523, 728.9453291003196, 730.0472882887882, 553.1923391847866, 618.7655627024116, 656.924453687832, 671.6876652356677, 673.397309408077, 9537.452519878007, 9569.234461243084, 9798.461084640414, 9843.114119729258, 9910.35582687123, 569.9857503562362, 616.7687117331001, 647.4108561209277, 648.7956293767448, 697.4141720954321, 3945.940810887839, 4744.430668319824, 4747.928781068286, 4752.619790416758, 4831.661783675109, 159262.0, 165675.1716241411, 166829.16585417237, 169671.15164424098, 173047.0, 110229.44885275683, 112907.04825331074, 113888.43055784667, 147346.0, 151690.96618067796, 94866.0, 99028.0, 100985.0, 109109.45445272881, 113297.43351283355, 9884.406935583887, 9904.431794979104, 10069.345492542961, 10093.798124037625, 10110.747231319276, 55869.720651396485, 57593.71203143957, 59096.70451647714, 59755.0, 61974.38025619867, 90859.54570227106, 93025.0, 93094.0, 93616.53191733998, 94740.52629737083, 11309.604163854226, 13086.541971030969, 13270.668233294246, 13367.331633418486, 13655.27318995829, 14825.703485930437, 14987.550373488813, 15309.387624494895, 15623.062616243062, 15648.0, 11432.885671143515, 13522.459101635824, 14050.508232211823, 14221.288935553388, 14556.681080703713, 13563.525276615272, 14176.291185440481, 14246.92876535652, 14348.49780257686, 14625.634359141106, 13646.658833528843, 13685.452581896612, 13700.93149534246, 13985.650358741113, 14390.568282951528, 10715.35707857531, 11498.310101393943, 11524.884751152382, 11709.765804683688, 11873.940630296793, 10875.456227188768, 11423.428828558704, 11453.541858325907, 11584.304941703187, 11773.47019384147], "value": 7863.566822008392, "unit": "score"}], "name": "dromaeo_css", "value": 7350.429224161041, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 113.0, 113.0, 113.0, 113.0, 114.0, 114.0, 114.0, 113.0, 113.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 114.0, 106.0, 156.0, 107.0, 107.0, 129.0, 108.0, 138.0, 112.0], "value": 122.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 181.0, 182.0, 184.0, 185.0, 181.0, 181.0, 183.0, 185.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 58.0, 105.0, 57.0, 57.0, 60.0, 64.0, 96.0, 61.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 90.0, 94.0, 70.0, 71.0, 68.0, 93.0, 70.0, 69.0, 98.0], "value": 80.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 88.0, 90.0, 88.0, 90.0, 88.0, 91.0, 88.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 94.0, 95.0, 95.0, 94.0, 95.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 85.0, 84.0, 86.0, 84.0, 87.0, 85.0, 85.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 55.0, 52.0, 54.0, 55.0, 53.0, 53.0, 55.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 74.0, 63.0, 72.0, 72.0, 101.0, 104.0, 69.0, 69.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 82.0, 82.0, 84.0, 84.0, 84.0, 83.0, 81.0, 83.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 147.0, 149.0, 135.0, 135.0, 135.0, 128.0, 136.0, 177.0, 135.0], "value": 141.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 164.0, 162.0, 164.0, 162.0, 165.0, 164.0, 162.0, 220.0], "value": 169.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 68.0, 67.0, 66.0, 66.0, 67.0, 67.0, 66.0, 68.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1432.2, "alertThreshold": 2.0}]} 09:35:15 INFO - Return code: 0 09:35:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:35:15 INFO - # TBPL SUCCESS # 09:35:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:35:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:35:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:35:15 INFO - 2016-06-29 09:35:15.814 screenresolution[1665:10063] starting screenresolution argv=screenresolution get 09:35:15 INFO - 2016-06-29 09:35:15.817 screenresolution[1665:10063] Display 0: 1600x1200x32@60 09:35:15 INFO - 2016-06-29 09:35:15.823 screenresolution[1666:10066] starting screenresolution argv=screenresolution list 09:35:15 INFO - Available Modes on Display 0 09:35:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:35:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:35:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:35:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:35:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:35:16 INFO - Intel Iris: 09:35:16 INFO - Chipset Model: Intel Iris 09:35:16 INFO - Type: GPU 09:35:16 INFO - Bus: Built-In 09:35:16 INFO - VRAM (Dynamic, Max): 1536 MB 09:35:16 INFO - Vendor: Intel (0x8086) 09:35:16 INFO - Device ID: 0x0a2e 09:35:16 INFO - Revision ID: 0x0009 09:35:16 INFO - Displays: 09:35:16 INFO - Display: 09:35:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:35:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:35:16 INFO - Display Serial Number: 1600x1200 60 09:35:16 INFO - Main Display: Yes 09:35:16 INFO - Mirror: Off 09:35:16 INFO - Online: Yes 09:35:16 INFO - Rotation: Supported 09:35:16 INFO - Return code: 0 09:35:16 INFO - Running post-action listener: _resource_record_post_action 09:35:16 INFO - ##### 09:35:16 INFO - ##### Finished run-tests step (success) 09:35:16 INFO - ##### 09:35:16 INFO - Running post-run listener: _resource_record_post_run 09:35:16 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 38000128; Write bytes: 704572416; Read time: 392; Write time: 1892 09:35:16 INFO - TinderboxPrint: CPU usage
53.5% 09:35:16 INFO - TinderboxPrint: I/O read bytes / time
38,000,128 / 392 09:35:16 INFO - TinderboxPrint: I/O write bytes / time
704,572,416 / 1,892 09:35:16 INFO - TinderboxPrint: CPU idle
2,253.5 (46.5%) 09:35:16 INFO - TinderboxPrint: CPU system
965.8 (19.9%) 09:35:16 INFO - TinderboxPrint: CPU user
1,622.4 (33.5%) 09:35:16 INFO - TinderboxPrint: Swap in / out
445,116,416 / 0 09:35:16 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 407953920; Write bytes: 410263552; Read time: 30773; Write time: 766 09:35:16 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 34559488; Write bytes: 292752384; Read time: 362; Write time: 1121 09:35:16 INFO - Running post-run listener: _upload_blobber_files 09:35:16 INFO - Blob upload gear active. 09:35:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:35:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:35:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:35:16 INFO - (blobuploader) - INFO - Open directory for files ... 09:35:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:35:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:35:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:17 INFO - (blobuploader) - INFO - Done attempting. 09:35:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:35:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:35:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:18 INFO - (blobuploader) - INFO - Done attempting. 09:35:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:35:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:35:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:21 INFO - (blobuploader) - INFO - Done attempting. 09:35:21 INFO - (blobuploader) - INFO - Iteration through files over. 09:35:21 INFO - Return code: 0 09:35:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:35:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:35:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2608d032ebfbdedaa158b9262557ad485675ea8fb961cb56d893cf21dd6b099bd154c3f13f9d1adb778301c1ed97c5b9a8d16c6c099ae1ba612aa585919596d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/54b52fa90feadf2009681d4e77732ea550a9888559556643356c47e6f9bb0d1b86cc3fa6de9119df26aa2b4f3ed91fdcedcc5737a954421d96347526edbbf76d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d386ea40aedad7ce0f4a5f48c3d850c057d02c3aa4f3e0c3c6eae5cba8927bdbfcc439b282707a8641c671ceb65deff7f8cb9ebd206655a2bd269ac5e328689b"} 09:35:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:35:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:35:21 INFO - Contents: 09:35:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2608d032ebfbdedaa158b9262557ad485675ea8fb961cb56d893cf21dd6b099bd154c3f13f9d1adb778301c1ed97c5b9a8d16c6c099ae1ba612aa585919596d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/54b52fa90feadf2009681d4e77732ea550a9888559556643356c47e6f9bb0d1b86cc3fa6de9119df26aa2b4f3ed91fdcedcc5737a954421d96347526edbbf76d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d386ea40aedad7ce0f4a5f48c3d850c057d02c3aa4f3e0c3c6eae5cba8927bdbfcc439b282707a8641c671ceb65deff7f8cb9ebd206655a2bd269ac5e328689b"} 09:35:21 INFO - Running post-run listener: copy_logs_to_upload_dir 09:35:21 INFO - Copying logs to upload dir... 09:35:21 INFO - mkdir: /builds/slave/test/build/upload/logs 09:35:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.459079 ========= master_lag: 0.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-06-29 09:35:21.988230) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 09:35:21.991488) ========= 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.mYkdWzACKk/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.KXfHEVhisH/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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/d2608d032ebfbdedaa158b9262557ad485675ea8fb961cb56d893cf21dd6b099bd154c3f13f9d1adb778301c1ed97c5b9a8d16c6c099ae1ba612aa585919596d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/54b52fa90feadf2009681d4e77732ea550a9888559556643356c47e6f9bb0d1b86cc3fa6de9119df26aa2b4f3ed91fdcedcc5737a954421d96347526edbbf76d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d386ea40aedad7ce0f4a5f48c3d850c057d02c3aa4f3e0c3c6eae5cba8927bdbfcc439b282707a8641c671ceb65deff7f8cb9ebd206655a2bd269ac5e328689b"} build_url:https://queue.taskcluster.net/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011238 build_url: 'https://queue.taskcluster.net/v1/task/OAjANZVeQeivsyBvL1rhRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2608d032ebfbdedaa158b9262557ad485675ea8fb961cb56d893cf21dd6b099bd154c3f13f9d1adb778301c1ed97c5b9a8d16c6c099ae1ba612aa585919596d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/54b52fa90feadf2009681d4e77732ea550a9888559556643356c47e6f9bb0d1b86cc3fa6de9119df26aa2b4f3ed91fdcedcc5737a954421d96347526edbbf76d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d386ea40aedad7ce0f4a5f48c3d850c057d02c3aa4f3e0c3c6eae5cba8927bdbfcc439b282707a8641c671ceb65deff7f8cb9ebd206655a2bd269ac5e328689b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 09:35:22.030301) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:35:22.030652) ========= 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.mYkdWzACKk/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.KXfHEVhisH/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.005284 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:35:22.080125) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 09:35:22.080439) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 09:35:22.080854) ========= ========= Total master_lag: 1.67 =========