builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0282 starttime: 1493110983.67 results: success (0) buildid: 20170425004026 builduid: 91ba0f635e824acc9d48ac1355f32dd1 revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-25 02:03:03.672365) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-25 02:03:03.672857) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-25 02:03:03.673208) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-25 02:03:03.956953) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 02:03:03.957325) ========= 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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 --2017-04-25 02:03:03-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 105M=0s 2017-04-25 02:03:04 (105 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.580279 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 02:03:04.585621) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 02:03:04.585966) ========= 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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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.026997 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 02:03:04.648120) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-04-25 02:03:04.648431) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Bkev6vApHh/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.6Gx6c8uFZT/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 2017-04-25 02:03:04,751 truncating revision to first 12 chars 2017-04-25 02:03:04,751 Setting DEBUG logging. 2017-04-25 02:03:04,751 attempt 1/10 2017-04-25 02:03:04,751 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-25 02:03:07,093 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=3.817769 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-04-25 02:03:08.496081) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-25 02:03:08.496883) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-25 02:03:08.497589) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-25 02:03:08.497921) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-25 02:03:08.519555) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2017-04-25 02:03:08.519923) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Bkev6vApHh/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.6Gx6c8uFZT/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:03:08 INFO - MultiFileLogger online at 20170425 02:03:08 in /builds/slave/test 02:03:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:03:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:03:08 INFO - {'append_to_log': False, 02:03:08 INFO - 'base_work_dir': '/builds/slave/test', 02:03:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:03:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:03:08 INFO - 'branch': 'Mozilla-Aurora', 02:03:08 INFO - 'buildbot_json_path': 'buildprops.json', 02:03:08 INFO - 'config_files': ('talos/mac_config.py',), 02:03:08 INFO - 'default_actions': ('clobber', 02:03:08 INFO - 'read-buildbot-config', 02:03:08 INFO - 'download-and-extract', 02:03:08 INFO - 'populate-webroot', 02:03:08 INFO - 'create-virtualenv', 02:03:08 INFO - 'install', 02:03:08 INFO - 'run-tests'), 02:03:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:03:08 INFO - 'download_minidump_stackwalk': True, 02:03:08 INFO - 'download_symbols': 'ondemand', 02:03:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:03:08 INFO - 'tooltool.py': '/tools/tooltool.py', 02:03:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:03:08 INFO - '/tools/misc-python/virtualenv.py')}, 02:03:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:03:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:03:08 INFO - 'gecko_profile': False, 02:03:08 INFO - 'gecko_profile_interval': 0, 02:03:08 INFO - 'installer_path': 'installer.exe', 02:03:08 INFO - 'log_level': 'info', 02:03:08 INFO - 'log_name': 'talos', 02:03:08 INFO - 'log_to_console': True, 02:03:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:03:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:03:08 INFO - 'opt_config_files': (), 02:03:08 INFO - 'pip_index': False, 02:03:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:03:08 INFO - 'cmd': ('bash', 02:03:08 INFO - '-c', 02:03:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:03:08 INFO - 'enabled': True, 02:03:08 INFO - 'halt_on_failure': False, 02:03:08 INFO - 'name': 'check_screen_resolution'},), 02:03:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:03:08 INFO - 'cmd': ('bash', 02:03:08 INFO - '-c', 02:03:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:03:08 INFO - 'enabled': True, 02:03:08 INFO - 'halt_on_failure': False, 02:03:08 INFO - 'name': 'check_screen_resolution'},), 02:03:08 INFO - 'run_cmd_checks_enabled': True, 02:03:08 INFO - 'suite': 'dromaeojs', 02:03:08 INFO - 'system_bits': '32', 02:03:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:03:08 INFO - 'title': 't-yosemite-r7-0282', 02:03:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:03:08 INFO - 'use_talos_json': True, 02:03:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:03:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:03:08 INFO - 'work_dir': 'build'} 02:03:08 INFO - [mozharness: 2017-04-25 09:03:08.726005Z] Running clobber step. 02:03:08 INFO - Running pre-action listener: _resource_record_pre_action 02:03:08 INFO - Running main action method: clobber 02:03:08 INFO - rmtree: /builds/slave/test/build 02:03:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:03:11 INFO - Running post-action listener: _resource_record_post_action 02:03:11 INFO - [mozharness: 2017-04-25 09:03:11.299666Z] Finished clobber step (success) 02:03:11 INFO - [mozharness: 2017-04-25 09:03:11.299842Z] Running read-buildbot-config step. 02:03:11 INFO - Running pre-action listener: _resource_record_pre_action 02:03:11 INFO - Running main action method: read_buildbot_config 02:03:11 INFO - Using buildbot properties: 02:03:11 INFO - { 02:03:11 INFO - "project": "", 02:03:11 INFO - "product": "firefox", 02:03:11 INFO - "script_repo_revision": "production", 02:03:11 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:03:11 INFO - "repository": "", 02:03:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:03:11 INFO - "buildid": "20170425004026", 02:03:11 INFO - "stage_platform": "macosx64", 02:03:11 INFO - "basedir": "/builds/slave/test", 02:03:11 INFO - "nightly_build": "True", 02:03:11 INFO - "buildnumber": 50, 02:03:11 INFO - "slavename": "t-yosemite-r7-0282", 02:03:11 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:03:11 INFO - "platform": "yosemite_r7", 02:03:11 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:03:11 INFO - "branch": "mozilla-aurora", 02:03:11 INFO - "repo_path": "releases/mozilla-aurora", 02:03:11 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 02:03:11 INFO - "pgo_build": "False", 02:03:11 INFO - "builduid": "91ba0f635e824acc9d48ac1355f32dd1", 02:03:11 INFO - "slavebuilddir": "test" 02:03:11 INFO - } 02:03:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:03:11 INFO - Running post-action listener: _resource_record_post_action 02:03:11 INFO - [mozharness: 2017-04-25 09:03:11.303311Z] Finished read-buildbot-config step (success) 02:03:11 INFO - [mozharness: 2017-04-25 09:03:11.303393Z] Running download-and-extract step. 02:03:11 INFO - Running pre-action listener: _resource_record_pre_action 02:03:11 INFO - Running main action method: download_and_extract 02:03:11 INFO - mkdir: /builds/slave/test/build/tests 02:03:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:11 INFO - trying https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:03:11 INFO - Downloading https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:03:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 02:03:12 INFO - Downloaded 1199 bytes. 02:03:12 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:03:12 INFO - Using the following test package requirements: 02:03:12 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:03:12 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:03:12 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:03:12 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:03:12 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:03:12 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:03:12 INFO - u'jsshell-mac.zip'], 02:03:12 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:03:12 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:03:12 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:03:12 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:03:12 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:03:12 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:03:12 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:03:12 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:03:12 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:03:12 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:03:12 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:03:12 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:03:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:03:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:03:12 INFO - Fetch https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:03:14 INFO - Expected file size: 51791083 02:03:14 INFO - Obtained file size: 51791083 02:03:29 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:03:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:03:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:03:29 INFO - Fetch https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:03:32 INFO - Expected file size: 51791083 02:03:32 INFO - Obtained file size: 51791083 02:03:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:03:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:03:47 INFO - Fetch https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:03:48 INFO - Expected file size: 14404424 02:03:48 INFO - Obtained file size: 14404424 02:03:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:48 INFO - trying https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:03:48 INFO - Downloading https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:03:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 02:03:56 INFO - Downloaded 74782728 bytes. 02:03:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:03:56 INFO - mkdir: /builds/slave/test/properties 02:03:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:03:56 INFO - Writing to file /builds/slave/test/properties/build_url 02:03:56 INFO - Contents: 02:03:56 INFO - build_url:https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:03:57 INFO - Running post-action listener: _resource_record_post_action 02:03:57 INFO - Running post-action listener: set_extra_try_arguments 02:03:57 INFO - [mozharness: 2017-04-25 09:03:57.360303Z] Finished download-and-extract step (success) 02:03:57 INFO - [mozharness: 2017-04-25 09:03:57.360431Z] Running populate-webroot step. 02:03:57 INFO - Running pre-action listener: _resource_record_pre_action 02:03:57 INFO - Running main action method: populate_webroot 02:03:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:03:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:03:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:03:57 INFO - '--tppagecycles', 02:03:57 INFO - '7'], 02:03:57 INFO - 'tests': ['tsvgm']}}, 02:03:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:03:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:03:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:03:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:03:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:03:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:03:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:03:57 INFO - 'talos_options': ['--disable-e10s'], 02:03:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:03:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:03:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:03:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:03:57 INFO - 'talos_options': ['--disable-e10s'], 02:03:57 INFO - 'tests': ['damp', 'tps']}, 02:03:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:03:57 INFO - 'tests': ['damp', 'tps']}, 02:03:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:03:57 INFO - 'tests': ['dromaeo_dom']}, 02:03:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:03:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:03:57 INFO - 'tests': ['basic_compositor_video']}, 02:03:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:03:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:03:57 INFO - 'tests': ['a11yr', 02:03:57 INFO - 'ts_paint', 02:03:57 INFO - 'tpaint', 02:03:57 INFO - 'sessionrestore', 02:03:57 INFO - 'sessionrestore_no_auto_restore', 02:03:57 INFO - 'tabpaint']}, 02:03:57 INFO - 'other-e10s': {'tests': ['a11yr', 02:03:57 INFO - 'ts_paint', 02:03:57 INFO - 'tpaint', 02:03:57 INFO - 'sessionrestore', 02:03:57 INFO - 'sessionrestore_no_auto_restore', 02:03:57 INFO - 'tabpaint']}, 02:03:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:03:57 INFO - 'ts_paint', 02:03:57 INFO - 'tpaint', 02:03:57 INFO - 'sessionrestore', 02:03:57 INFO - 'sessionrestore_no_auto_restore', 02:03:57 INFO - 'tabpaint']}, 02:03:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:03:57 INFO - 'ts_paint', 02:03:57 INFO - 'tpaint', 02:03:57 INFO - 'sessionrestore', 02:03:57 INFO - 'sessionrestore_no_auto_restore', 02:03:57 INFO - 'tabpaint']}, 02:03:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:03:57 INFO - 'tests': ['a11yr', 02:03:57 INFO - 'ts_paint', 02:03:57 INFO - 'tpaint', 02:03:57 INFO - 'sessionrestore', 02:03:57 INFO - 'sessionrestore_no_auto_restore', 02:03:57 INFO - 'tabpaint']}, 02:03:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:03:57 INFO - 'tests': ['a11yr', 02:03:57 INFO - 'ts_paint', 02:03:57 INFO - 'tpaint', 02:03:57 INFO - 'sessionrestore', 02:03:57 INFO - 'sessionrestore_no_auto_restore', 02:03:57 INFO - 'tabpaint']}, 02:03:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:03:57 INFO - 'tests': ['tsvgx', 02:03:57 INFO - 'tsvgr_opacity', 02:03:57 INFO - 'tart', 02:03:57 INFO - 'tscrollx', 02:03:57 INFO - 'cart', 02:03:57 INFO - 'tsvg_static']}, 02:03:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:03:57 INFO - 'tsvgr_opacity', 02:03:57 INFO - 'tart', 02:03:57 INFO - 'tscrollx', 02:03:57 INFO - 'cart', 02:03:57 INFO - 'tsvg_static']}, 02:03:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:03:57 INFO - 'talos_options': ['--disable-e10s'], 02:03:57 INFO - 'tests': ['tp5o']}, 02:03:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:03:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:03:57 INFO - 'talos_options': ['--disable-e10s', 02:03:57 INFO - '--xperf_path', 02:03:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:03:57 INFO - 'tests': ['tp5n']}, 02:03:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:03:57 INFO - 'talos_options': ['--xperf_path', 02:03:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:03:57 INFO - 'tests': ['tp5n']}}, 02:03:57 INFO - 'talos.zip': {'path': '', 02:03:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:03:57 INFO - Running post-action listener: _resource_record_post_action 02:03:57 INFO - [mozharness: 2017-04-25 09:03:57.379026Z] Finished populate-webroot step (success) 02:03:57 INFO - [mozharness: 2017-04-25 09:03:57.379167Z] Running create-virtualenv step. 02:03:57 INFO - Running pre-action listener: _resource_record_pre_action 02:03:57 INFO - Running main action method: create_virtualenv 02:03:57 INFO - Creating virtualenv /builds/slave/test/build/venv 02:03:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:03:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:03:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:03:57 INFO - Using real prefix '/tools/python27' 02:03:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:03:58 INFO - Installing distribute.............................................................................................................................................................................................done. 02:04:01 INFO - Installing pip.................done. 02:04:01 INFO - Return code: 0 02:04:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:04:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:04:01 INFO - Reading from file tmpfile_stdout 02:04:01 INFO - Output received: 02:04:01 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:04:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10df7bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df7e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e85b8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd59d30>, '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 0x10dc80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ebe8>, '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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:04:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:04:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:04:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bkev6vApHh/Render', 02:04:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:01 INFO - 'HOME': '/Users/cltbld', 02:04:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:01 INFO - 'LOGNAME': 'cltbld', 02:04:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:01 INFO - 'MOZ_NO_REMOTE': '1', 02:04:01 INFO - 'NO_EM_RESTART': '1', 02:04:01 INFO - 'PAGER': '/bin/cat', 02:04:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:01 INFO - 'PWD': '/builds/slave/test', 02:04:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:01 INFO - 'SHELL': '/bin/bash', 02:04:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Gx6c8uFZT/Listeners', 02:04:01 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:04:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:01 INFO - 'USER': 'cltbld', 02:04:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:01 INFO - 'XPC_FLAGS': '0x0', 02:04:01 INFO - 'XPC_SERVICE_NAME': '0', 02:04:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:04:01 INFO - Cleaning up... 02:04:01 INFO - Return code: 0 02:04:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10df7bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df7e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e85b8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd59d30>, '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 0x10dc80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ebe8>, '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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:04:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:04:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:04:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bkev6vApHh/Render', 02:04:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:01 INFO - 'HOME': '/Users/cltbld', 02:04:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:01 INFO - 'LOGNAME': 'cltbld', 02:04:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:01 INFO - 'MOZ_NO_REMOTE': '1', 02:04:01 INFO - 'NO_EM_RESTART': '1', 02:04:01 INFO - 'PAGER': '/bin/cat', 02:04:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:01 INFO - 'PWD': '/builds/slave/test', 02:04:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:01 INFO - 'SHELL': '/bin/bash', 02:04:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Gx6c8uFZT/Listeners', 02:04:01 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:04:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:01 INFO - 'USER': 'cltbld', 02:04:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:01 INFO - 'XPC_FLAGS': '0x0', 02:04:01 INFO - 'XPC_SERVICE_NAME': '0', 02:04:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:02 INFO - Downloading/unpacking psutil>=3.1.1 02:04:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:04:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:04:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:04:07 INFO - Installing collected packages: psutil 02:04:07 INFO - Running setup.py install for psutil 02:04:07 INFO - building 'psutil._psutil_osx' extension 02:04:07 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 02:04:08 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 02:04:08 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 02:04:08 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 02:04:08 INFO - building 'psutil._psutil_posix' extension 02:04:08 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 02:04:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:04:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:04:08 INFO - ^ 02:04:08 INFO - 1 warning generated. 02:04:08 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 02:04:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:04:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:04:08 INFO - Successfully installed psutil 02:04:08 INFO - Cleaning up... 02:04:08 INFO - Return code: 0 02:04:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10df7bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df7e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e85b8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd59d30>, '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 0x10dc80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ebe8>, '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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:04:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:04:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:04:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bkev6vApHh/Render', 02:04:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:08 INFO - 'HOME': '/Users/cltbld', 02:04:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:08 INFO - 'LOGNAME': 'cltbld', 02:04:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:08 INFO - 'MOZ_NO_REMOTE': '1', 02:04:08 INFO - 'NO_EM_RESTART': '1', 02:04:08 INFO - 'PAGER': '/bin/cat', 02:04:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:08 INFO - 'PWD': '/builds/slave/test', 02:04:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:08 INFO - 'SHELL': '/bin/bash', 02:04:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Gx6c8uFZT/Listeners', 02:04:08 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:04:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:08 INFO - 'USER': 'cltbld', 02:04:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:08 INFO - 'XPC_FLAGS': '0x0', 02:04:08 INFO - 'XPC_SERVICE_NAME': '0', 02:04:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:04:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:04:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:04:11 INFO - Installing collected packages: mozsystemmonitor 02:04:11 INFO - Running setup.py install for mozsystemmonitor 02:04:12 INFO - Successfully installed mozsystemmonitor 02:04:12 INFO - Cleaning up... 02:04:12 INFO - Return code: 0 02:04:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:04:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10df7bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df7e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e85b8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd59d30>, '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 0x10dc80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ebe8>, '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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:04:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:04:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:04:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bkev6vApHh/Render', 02:04:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:12 INFO - 'HOME': '/Users/cltbld', 02:04:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:12 INFO - 'LOGNAME': 'cltbld', 02:04:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:12 INFO - 'MOZ_NO_REMOTE': '1', 02:04:12 INFO - 'NO_EM_RESTART': '1', 02:04:12 INFO - 'PAGER': '/bin/cat', 02:04:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:12 INFO - 'PWD': '/builds/slave/test', 02:04:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:12 INFO - 'SHELL': '/bin/bash', 02:04:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Gx6c8uFZT/Listeners', 02:04:12 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:04:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:12 INFO - 'USER': 'cltbld', 02:04:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:12 INFO - 'XPC_FLAGS': '0x0', 02:04:12 INFO - 'XPC_SERVICE_NAME': '0', 02:04:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:12 INFO - Downloading/unpacking jsonschema==2.5.1 02:04:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:04:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:04:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:15 INFO - Downloading functools32-3.2.3-2.tar.gz 02:04:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:04:15 INFO - warning: no files found matching '*.txt' 02:04:15 INFO - no previously-included directories found matching 'build' 02:04:15 INFO - no previously-included directories found matching 'dist' 02:04:15 INFO - no previously-included directories found matching '.git*' 02:04:15 INFO - Installing collected packages: jsonschema, functools32 02:04:15 INFO - Running setup.py install for functools32 02:04:15 INFO - warning: no files found matching '*.txt' 02:04:15 INFO - no previously-included directories found matching 'build' 02:04:15 INFO - no previously-included directories found matching 'dist' 02:04:15 INFO - no previously-included directories found matching '.git*' 02:04:15 INFO - Successfully installed jsonschema functools32 02:04:15 INFO - Cleaning up... 02:04:15 INFO - Return code: 0 02:04:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:04:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10df7bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df7e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e85b8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd59d30>, '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 0x10dc80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ebe8>, '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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:04:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:04:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:04:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bkev6vApHh/Render', 02:04:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:15 INFO - 'HOME': '/Users/cltbld', 02:04:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:15 INFO - 'LOGNAME': 'cltbld', 02:04:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:15 INFO - 'MOZ_NO_REMOTE': '1', 02:04:15 INFO - 'NO_EM_RESTART': '1', 02:04:15 INFO - 'PAGER': '/bin/cat', 02:04:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:15 INFO - 'PWD': '/builds/slave/test', 02:04:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:15 INFO - 'SHELL': '/bin/bash', 02:04:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Gx6c8uFZT/Listeners', 02:04:15 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:04:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:15 INFO - 'USER': 'cltbld', 02:04:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:15 INFO - 'XPC_FLAGS': '0x0', 02:04:15 INFO - 'XPC_SERVICE_NAME': '0', 02:04:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:04:16 INFO - Cleaning up... 02:04:16 INFO - Return code: 0 02:04:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:04:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:16 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 0x10df7bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df7e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e85b8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd59d30>, '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 0x10dc80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ebe8>, '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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:04:16 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 02:04:16 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 02:04:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bkev6vApHh/Render', 02:04:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:16 INFO - 'HOME': '/Users/cltbld', 02:04:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:16 INFO - 'LOGNAME': 'cltbld', 02:04:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:16 INFO - 'MOZ_NO_REMOTE': '1', 02:04:16 INFO - 'NO_EM_RESTART': '1', 02:04:16 INFO - 'PAGER': '/bin/cat', 02:04:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:16 INFO - 'PWD': '/builds/slave/test', 02:04:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:16 INFO - 'SHELL': '/bin/bash', 02:04:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Gx6c8uFZT/Listeners', 02:04:16 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:04:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:16 INFO - 'USER': 'cltbld', 02:04:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:16 INFO - 'XPC_FLAGS': '0x0', 02:04:16 INFO - 'XPC_SERVICE_NAME': '0', 02:04:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:16 INFO - Downloading/unpacking blobuploader==1.2.4 02:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:19 INFO - Downloading blobuploader-1.2.4.tar.gz 02:04:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:04:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:04:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:04:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:04:20 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 02:04:20 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 02:04:20 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 02:04:20 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 02:04:20 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 02:04:20 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 02:04:21 INFO - Downloading docopt-0.6.1.tar.gz 02:04:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:04:21 INFO - Installing collected packages: blobuploader, requests, docopt 02:04:21 INFO - Running setup.py install for blobuploader 02:04:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:04:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:04:21 INFO - Running setup.py install for requests 02:04:21 INFO - Running setup.py install for docopt 02:04:21 INFO - Successfully installed blobuploader requests docopt 02:04:21 INFO - Cleaning up... 02:04:22 INFO - Return code: 0 02:04:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:22 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 0x10df7bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df7e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e85b8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd59d30>, '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 0x10dc80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ebe8>, '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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:04:22 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 02:04:22 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 02:04:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bkev6vApHh/Render', 02:04:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:22 INFO - 'HOME': '/Users/cltbld', 02:04:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:22 INFO - 'LOGNAME': 'cltbld', 02:04:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:22 INFO - 'MOZ_NO_REMOTE': '1', 02:04:22 INFO - 'NO_EM_RESTART': '1', 02:04:22 INFO - 'PAGER': '/bin/cat', 02:04:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:22 INFO - 'PWD': '/builds/slave/test', 02:04:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:22 INFO - 'SHELL': '/bin/bash', 02:04:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Gx6c8uFZT/Listeners', 02:04:22 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:04:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:22 INFO - 'USER': 'cltbld', 02:04:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:22 INFO - 'XPC_FLAGS': '0x0', 02:04:22 INFO - 'XPC_SERVICE_NAME': '0', 02:04:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:04:22 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-5T7E8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:04:22 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-XrR6od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:04:22 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-swnQSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:04:22 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-Zss5Zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:04:22 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-DERXOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:04:22 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-RT0o_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:04:22 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-yTdiWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:04:23 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-sfzRE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:04:23 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-Pb6Qi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:04:23 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-Sw7ZfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:04:23 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-MlQYQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:04:23 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-3EH9Nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:04:23 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-G7YrLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:04:23 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-dIUtqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:04:23 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-TRAU5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:04:23 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-Hh72zA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:04:24 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-II7WOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:04:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:04:24 INFO - Running setup.py install for manifestparser 02:04:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:04:24 INFO - Running setup.py install for mozcrash 02:04:24 INFO - Running setup.py install for mozdebug 02:04:24 INFO - Running setup.py install for mozdevice 02:04:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:04:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:04:24 INFO - Running setup.py install for mozfile 02:04:24 INFO - Running setup.py install for mozhttpd 02:04:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:04:25 INFO - Running setup.py install for mozinfo 02:04:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:04:25 INFO - Running setup.py install for mozInstall 02:04:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:04:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:04:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:04:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:04:25 INFO - Running setup.py install for mozleak 02:04:25 INFO - Running setup.py install for mozlog 02:04:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:04:25 INFO - Running setup.py install for moznetwork 02:04:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:04:25 INFO - Running setup.py install for mozprocess 02:04:25 INFO - Running setup.py install for mozprofile 02:04:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:04:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:04:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:04:26 INFO - Running setup.py install for mozrunner 02:04:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:04:26 INFO - Running setup.py install for mozscreenshot 02:04:26 INFO - Running setup.py install for moztest 02:04:26 INFO - Running setup.py install for mozversion 02:04:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:04:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:04:26 INFO - Cleaning up... 02:04:26 INFO - Return code: 0 02:04:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:26 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 0x10df7bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df7e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e85b8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd59d30>, '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 0x10dc80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ebe8>, '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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:04:26 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 02:04:26 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 02:04:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bkev6vApHh/Render', 02:04:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:26 INFO - 'HOME': '/Users/cltbld', 02:04:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:26 INFO - 'LOGNAME': 'cltbld', 02:04:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:26 INFO - 'MOZ_NO_REMOTE': '1', 02:04:26 INFO - 'NO_EM_RESTART': '1', 02:04:26 INFO - 'PAGER': '/bin/cat', 02:04:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:26 INFO - 'PWD': '/builds/slave/test', 02:04:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:26 INFO - 'SHELL': '/bin/bash', 02:04:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Gx6c8uFZT/Listeners', 02:04:26 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:04:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:26 INFO - 'USER': 'cltbld', 02:04:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:26 INFO - 'XPC_FLAGS': '0x0', 02:04:26 INFO - 'XPC_SERVICE_NAME': '0', 02:04:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:04:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-xHPhn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:04:27 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)) 02:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:04:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-ptjx10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:04:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 02:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:04:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-uxKW1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:04:27 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)) 02:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:04:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-3g8RgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:04:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 02:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:04:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-iScbeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:04:27 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)) 02:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:04:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-pcNVY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:04:27 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)) 02:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:04:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-GIDXqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:04:27 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)) 02:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:04:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-iw7bhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:04:27 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)) 02:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:04:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-LSf4hH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:04:27 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)) 02:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:04:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-K_CUUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:04:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:04:28 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-2Bwv4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:04:28 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)) 02:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:04:28 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-DSt5p_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:04:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 02:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:04:28 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-c4zcWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:04:28 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)) 02:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:04:28 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-0yzseX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:04:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 02:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:04:28 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-PQZHqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:04:28 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)) 02:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:04:28 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-np_GTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:04:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 02:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:04:28 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-2wdCU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:04:28 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)) 02:04:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:04:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:04:28 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)) 02:04:28 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:04:28 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:04:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:32 INFO - Downloading blessings-1.6.tar.gz 02:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:04:32 INFO - Installing collected packages: blessings 02:04:32 INFO - Running setup.py install for blessings 02:04:32 INFO - Successfully installed blessings 02:04:32 INFO - Cleaning up... 02:04:32 INFO - Return code: 0 02:04:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:04:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:04:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:04:32 INFO - Reading from file tmpfile_stdout 02:04:32 INFO - Current package versions: 02:04:32 INFO - blessings == 1.6 02:04:32 INFO - blobuploader == 1.2.4 02:04:32 INFO - docopt == 0.6.1 02:04:32 INFO - functools32 == 3.2.3-2 02:04:32 INFO - jsonschema == 2.5.1 02:04:32 INFO - manifestparser == 1.1 02:04:32 INFO - mozInstall == 1.12 02:04:32 INFO - mozcrash == 1.0 02:04:32 INFO - mozdebug == 0.1 02:04:32 INFO - mozdevice == 0.49 02:04:32 INFO - mozfile == 1.2 02:04:32 INFO - mozhttpd == 0.7 02:04:32 INFO - mozinfo == 0.9 02:04:32 INFO - mozleak == 0.1 02:04:32 INFO - mozlog == 3.4 02:04:32 INFO - moznetwork == 0.27 02:04:32 INFO - mozprocess == 0.25 02:04:32 INFO - mozprofile == 0.28 02:04:32 INFO - mozrunner == 6.13 02:04:32 INFO - mozscreenshot == 0.1 02:04:32 INFO - mozsystemmonitor == 0.3 02:04:32 INFO - moztest == 0.8 02:04:32 INFO - mozversion == 1.4 02:04:32 INFO - psutil == 3.1.1 02:04:32 INFO - requests == 1.2.3 02:04:32 INFO - wsgiref == 0.1.2 02:04:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:32 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 0x10df7bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df7e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e85b8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd59d30>, '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 0x10dc80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ebe8>, '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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:04:32 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 02:04:32 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 02:04:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bkev6vApHh/Render', 02:04:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:32 INFO - 'HOME': '/Users/cltbld', 02:04:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:32 INFO - 'LOGNAME': 'cltbld', 02:04:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:32 INFO - 'MOZ_NO_REMOTE': '1', 02:04:32 INFO - 'NO_EM_RESTART': '1', 02:04:32 INFO - 'PAGER': '/bin/cat', 02:04:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:32 INFO - 'PWD': '/builds/slave/test', 02:04:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:32 INFO - 'SHELL': '/bin/bash', 02:04:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Gx6c8uFZT/Listeners', 02:04:32 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:04:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:32 INFO - 'USER': 'cltbld', 02:04:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:32 INFO - 'XPC_FLAGS': '0x0', 02:04:32 INFO - 'XPC_SERVICE_NAME': '0', 02:04:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:33 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)) 02:04:33 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)) 02:04:33 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)) 02:04:33 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)) 02:04:33 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)) 02:04:33 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)) 02:04:33 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)) 02:04:33 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)) 02:04:33 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)) 02:04:33 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)) 02:04:33 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)) 02:04:33 INFO - Cleaning up... 02:04:33 INFO - Return code: 0 02:04:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10df7bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df7e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e85b8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd59d30>, '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 0x10dc80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ebe8>, '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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:04:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:04:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bkev6vApHh/Render', 02:04:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:33 INFO - 'HOME': '/Users/cltbld', 02:04:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:33 INFO - 'LOGNAME': 'cltbld', 02:04:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:33 INFO - 'MOZ_NO_REMOTE': '1', 02:04:33 INFO - 'NO_EM_RESTART': '1', 02:04:33 INFO - 'PAGER': '/bin/cat', 02:04:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:33 INFO - 'PWD': '/builds/slave/test', 02:04:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:33 INFO - 'SHELL': '/bin/bash', 02:04:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Gx6c8uFZT/Listeners', 02:04:33 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:04:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:33 INFO - 'USER': 'cltbld', 02:04:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:33 INFO - 'XPC_FLAGS': '0x0', 02:04:33 INFO - 'XPC_SERVICE_NAME': '0', 02:04:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:04:33 INFO - Cleaning up... 02:04:33 INFO - Return code: 0 02:04:33 INFO - Running post-action listener: _resource_record_post_action 02:04:33 INFO - Running post-action listener: _start_resource_monitoring 02:04:33 INFO - Starting resource monitoring. 02:04:33 INFO - [mozharness: 2017-04-25 09:04:33.320290Z] Finished create-virtualenv step (success) 02:04:33 INFO - [mozharness: 2017-04-25 09:04:33.321331Z] Running install step. 02:04:33 INFO - Running pre-action listener: _resource_record_pre_action 02:04:33 INFO - Running main action method: install 02:04:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:04:33 INFO - Reading from file tmpfile_stdout 02:04:33 INFO - Detecting whether we're running mozinstall >=1.0... 02:04:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:04:33 INFO - Reading from file tmpfile_stdout 02:04:33 INFO - Output received: 02:04:33 INFO - Usage: mozinstall [options] installer 02:04:33 INFO - Options: 02:04:33 INFO - -h, --help show this help message and exit 02:04:33 INFO - -d DEST, --destination=DEST 02:04:33 INFO - Directory to install application into. [default: 02:04:33 INFO - "/builds/slave/test"] 02:04:33 INFO - --app=APP Application being installed. [default: firefox] 02:04:33 INFO - mkdir: /builds/slave/test/build/application 02:04:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:04:53 INFO - Reading from file tmpfile_stdout 02:04:53 INFO - Output received: 02:04:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:04:53 INFO - Running post-action listener: _resource_record_post_action 02:04:53 INFO - [mozharness: 2017-04-25 09:04:53.807642Z] Finished install step (success) 02:04:53 INFO - [mozharness: 2017-04-25 09:04:53.807810Z] Running run-tests step. 02:04:53 INFO - Running pre-action listener: _resource_record_pre_action 02:04:53 INFO - Running main action method: run_tests 02:04:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:04:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:04:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:04:53 INFO - 2017-04-25 02:04:53.822 screenresolution[1628:8838] starting screenresolution argv=screenresolution get 02:04:53 INFO - 2017-04-25 02:04:53.825 screenresolution[1628:8838] Display 0: 1600x1200x32@60 02:04:53 INFO - 2017-04-25 02:04:53.831 screenresolution[1629:8841] starting screenresolution argv=screenresolution list 02:04:53 INFO - Available Modes on Display 0 02:04:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:04:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:04:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:04:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:04:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:04:54 INFO - Intel Iris: 02:04:54 INFO - Chipset Model: Intel Iris 02:04:54 INFO - Type: GPU 02:04:54 INFO - Bus: Built-In 02:04:54 INFO - VRAM (Dynamic, Max): 1536 MB 02:04:54 INFO - Vendor: Intel (0x8086) 02:04:54 INFO - Device ID: 0x0a2e 02:04:54 INFO - Revision ID: 0x0009 02:04:54 INFO - Displays: 02:04:54 INFO - Display: 02:04:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:04:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:04:54 INFO - Display Serial Number: 1600x1200 60 02:04:54 INFO - Main Display: Yes 02:04:54 INFO - Mirror: Off 02:04:54 INFO - Online: Yes 02:04:54 INFO - Rotation: Supported 02:04:54 INFO - Return code: 0 02:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:04:54 INFO - Python 2.7.3 02:04:54 INFO - Return code: 0 02:04:54 INFO - grabbing minidump binary from tooltool 02:04: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:54 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 0x10dd59d30>, '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 0x10dc80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:04:54 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 02:04:54 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 02:04:54 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 02:04:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:04:54 INFO - Return code: 0 02:04:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:04:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:04:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:04:54 INFO - ENV: RUST_BACKTRACE is now 1 02:04:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:04:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0282', '--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 02:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0282 --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 02:04:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bkev6vApHh/Render', 02:04:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:54 INFO - 'HOME': '/Users/cltbld', 02:04:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:54 INFO - 'LOGNAME': 'cltbld', 02:04:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:04:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:54 INFO - 'MOZ_NO_REMOTE': '1', 02:04:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:04:54 INFO - 'NO_EM_RESTART': '1', 02:04:54 INFO - 'PAGER': '/bin/cat', 02:04:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:54 INFO - 'PWD': '/builds/slave/test', 02:04:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:04:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:54 INFO - 'RUST_BACKTRACE': '1', 02:04:54 INFO - 'SHELL': '/bin/bash', 02:04:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Gx6c8uFZT/Listeners', 02:04:54 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:04:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:54 INFO - 'USER': 'cltbld', 02:04:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:54 INFO - 'XPC_FLAGS': '0x0', 02:04:54 INFO - 'XPC_SERVICE_NAME': '0', 02:04:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0282', '--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 02:04:54 INFO - mozversion application_buildid: 20170425004026 02:04:54 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:04:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:04:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:04:54 INFO - mozversion application_name: Firefox 02:04:54 INFO - mozversion application_remotingname: firefox-dev 02:04:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:04:54 INFO - mozversion application_vendor: Mozilla 02:04:54 INFO - mozversion application_version: 54.0a2 02:04:54 INFO - mozversion platform_buildid: 20170425004026 02:04:54 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:04:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:04:54 INFO - mozversion platform_version: 54.0a2 02:04:54 INFO - using testdate: 1493111094 02:04:54 INFO - actual date: 1493111094 02:04:54 INFO - starting webserver on 'localhost:49249' 02:04:54 INFO - SUITE-START | Running 2 tests 02:04:54 INFO - TEST-START | dromaeo_css 02:04:54 INFO - operating with platform_type : mac_ 02:04:54 INFO - Initialising browser for dromaeo_css test... 02:04:54 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpAvlPN1/profile http://localhost:49249/getInfo.html) 02:04:57 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 02:04:57 INFO - PROCESS | 1639 | colorDepth:24 02:04:57 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 02:04:57 INFO - PROCESS | 1639 | __metrics 02:04:57 INFO - PROCESS | 1639 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:04:57 INFO - PROCESS | 1639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:04:58 INFO - TEST-INFO | 1639: exit 0 02:04:58 INFO - Browser initialized. 02:04:58 INFO - Running cycle 1/1 for dromaeo_css test... 02:04:58 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpAvlPN1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:05:10 INFO - PROCESS | 1644 | RSS: Main: 210747392 02:05:10 INFO - PROCESS | 1644 | 02:08:13 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 02:08:16 INFO - PROCESS | 1644 | RSS: Main: 415911936 02:08:16 INFO - PROCESS | 1644 | 02:09:59 INFO - PROCESS | 1644 | ************************* 02:09:59 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 02:09:59 INFO - PROCESS | 1644 | 02:09:59 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:09:59 INFO - PROCESS | 1644 | Full stack: 02:09:59 INFO - PROCESS | 1644 | ************************* 02:09:59 INFO - PROCESS | 1644 | ************************* 02:09:59 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 02:09:59 INFO - PROCESS | 1644 | 02:09:59 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:09:59 INFO - PROCESS | 1644 | Full stack: 02:09:59 INFO - PROCESS | 1644 | ************************* 02:09:59 INFO - PROCESS | 1644 | ************************* 02:09:59 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 02:09:59 INFO - PROCESS | 1644 | 02:09:59 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:09:59 INFO - PROCESS | 1644 | Full stack: 02:09:59 INFO - PROCESS | 1644 | ************************* 02:09:59 INFO - PROCESS | 1644 | ************************* 02:09:59 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 02:09:59 INFO - PROCESS | 1644 | 02:09:59 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:09:59 INFO - PROCESS | 1644 | Full stack: 02:09:59 INFO - PROCESS | 1644 | ************************* 02:11:19 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 02:11:20 INFO - PROCESS | 1644 | RSS: Main: 350199808 02:11:20 INFO - PROCESS | 1644 | 02:14:22 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 02:14:23 INFO - PROCESS | 1644 | RSS: Main: 333905920 02:14:23 INFO - PROCESS | 1644 | 02:17:25 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 02:17:26 INFO - PROCESS | 1644 | RSS: Main: 317374464 02:17:26 INFO - PROCESS | 1644 | 02:20:30 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 02:20:30 INFO - PROCESS | 1644 | RSS: Main: 322306048 02:20:31 INFO - PROCESS | 1644 | 02:23:34 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 02:23:34 INFO - PROCESS | 1644 | RSS: Main: 383406080 02:23:34 INFO - PROCESS | 1644 | 02:23:34 INFO - PROCESS | 1644 | __start_tp_report 02:23:34 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 02:23:34 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 02:23:34 INFO - PROCESS | 1644 | |i|pagename|runs| 02:23:34 INFO - PROCESS | 1644 | |0;dojo.html;2840.3467202543416;3396.422608156615;3412.3687117883187;3423.8801641942523;3425.5375524304213;5724.112762521805;5771.509421699156;5774;5796.913046304298;5803.825885223439;5779.335376431712;5854.765809367631;5880.529557635389;5888.352281249059;5916.437938395857;5281.155015197569;5314.441983591715;5315.495027972336;5342.706151161685;5377.919331210015;5203.323567936163;5227.712475813829;5265.76304066318;5390.56875449965;5400.459954004607;5330.28041214435;5337.839864804062;5346.572274218072;5372.274742909696;5405.5135037846585;5373.462653734615;5379.677219366831;5381.192821076831;5381.5425688816495;5388.703621300827;5187.247849061902;5223.425423203445;5278.97360513195;5313.388960269541;5359.490848369399;4798.328234047236;5671.631343962629;5686.772529098831;5716.428357164292;5831.475167234969;6336.4930805535905;6372.840678983016;6376.139221205124;6379.87240255197;6397.5521713480075;6287.1198032275515;6336.303006669309;6349.206349206349;6351;6373.426391624776;5727.083666613322;5853.824385268449;5858.941410585925;5969.641821490724;5979.671118088547;6349.682515874188;6360.872782544323;6380.202474690664;6382.5532212744665;6397.168368112116;6312.0531920211415;6320.905186422207;6322.399372059739;6327.905081423783;6332.651704156316;6212.534059945522;6245;6304.464120549713;6318.71565779541;6340.048992651047;6150.831342044997;6290.55966082379;6321;6356.14192084072;6379.138816259839;6238.563300568917;6249.6875156242;6254;6310.810675679737;6380.106785050097;4997.825076122391;5081.212412076134;5084.9745751271;5093.668911520739;5097.235414687826;4552.6813123081065;4590.036092420562;4599.149157405891;4599.218132917412;4611.515790841981;5655.349634791969;5666.829995100154;5683.857903552444;5856.970715146397;5873.823785286363;6266.655333956676;6279.968600157062;6287.182666253358;6305.747770089145;6319.715612797435;4906.705597664151;5849.561282903799;5856.8535786604825;5859.44335288147;5878.235829342244;5524.558035357162;5529.142982837586;5561.58288128392;5568.1091025435735;5582.385937546866;6010.248718910137;6254.124422580933;6314.179156709598;6366.331535188739;6447.677616119269;6312.147860038836;6335.619862808247;6336.334684858208;6347.238331400262;6356.11014457967;6412.166418365583;6416.294207637063;6454.48364130859;6463.806085817433;6478.740850365933;6117.096718007379;6134.099846298167;6196.566240363131;6324.747010119728;6347.984322508377;5840.678762668094;6331.620102793847;6347.016212487072;6352.745890164341;6365.299817020216;6266.185395898687;6271.498280137669;6273.937260627518;6274.152989346562;6275;5953.077273022688;6063.393660633901;6104.847378815565;6276.152719382825;6280.780172693934;5919.792807251725;6063.545234107548;6073.210482637229;6110.51115910735;6133.723982420713;6203.658798766111;6241.875162496815;6256.5620406572925;6265.373462653881;6267.4986001120715;5786.652800831964;5828;5848.678322692293;5882.823515294548;6051.3041000283765 02:23:34 INFO - PROCESS | 1644 | |1;ext.html;707.6284950401039;816.216432225064;861.7285555050158;872.0799556467928;970.1075010989889;37669.62330376695;38023;38169.42745858814;39155.825325240214;39496.60503394972;31033;37877.621223787755;38104.61895381045;38124.61875381245;38283;7391.667374968112;7534.660940257674;7716.189800070982;7862.567558784264;10832.40421776802;10942.288751231185;11024.44877756109;11054.889451105466;11057.723556911102;11066;11068.889311106906;11073.667789966274;11079;11090.500927458284;11175.944120279388;10985.670429887115;11019.063379612702;11020.944895275552;11028.28316159447;11031.72420689481;10708.41103739294;10746.408947507884;10757.731056723565;10773.730656733565;10780.40707761073;38534.843954681404;38661.22677546462;38789.41815872764;38828.223435531414;38850.22299553993;3852.9229415411537;3863.343231650598;3888.2612303662213;3929.6659783918403;3942.704297177723;25984.480310393687;26775.464490710077;28323.57514637282;28543.286417839514;28762.568561471595;3044.4672182368;3089.413011527825;3264.5919260092487;3279.311344617633;3280.507923811447;326196;326513;330551;330731;331130;40996.38505422421;42290.78854605769;44408;50798.49201508011;53147.46852531425;54439;54654.72672636611;54719.17921231185;55135.44864551303;56882.7155864218;14401.42394304216;14540.927295363666;14551.3451894665;14560.199189044492;14568.927155364156;12572.685682858002;12583.811242831365;12613.558525451564;12655.557055503014;12706;11866.88133118675;11891.345975971231;11907.583234586747;11944.76110477803;11995.580154694544;4639.582437580634;5006.574441172541;5214.009338225801;5453.291072160594;5498.5876059295715;951.3293128344501;963.4026903319847;968.7190714692802;975.6243846119295;1011.6712068577742;22877.88561057184;26250.474990500083;27393.31516712058;28083.157505274874;28225;1035.6323505155713;1038.3614077342531;1042.7341028037802;1049.7847941172042;1069.1820757120809;13817.792733109012;14180;14264.786028209584;14563.41746330135;14648.487302944346;374092;446251.7687411542;446481;446951;447149;54702.72648636731;54714.45285547093;54811.72594137004;54815.17777233265;54904.901901962534;1436.6192958866118;1482.629342664334;1483.4956114920763;1565.2565031610122;1570.5445420827832;25449;26275.343116421478;27008.45983080288;27017.324566885985;27100.32249193786;41926.371104432845;42069.78965105155;42088.36867447052;42095.57904420917;42989.355159672006;412335;413899;414174.9291253525;414517;414621;26449.471010580066;27049.86475067691;27878.44243115167;33138.84014059496;33229.83385083156;36274.63725362712;42470.78764606157;43608.691739246606;43635.78182109069;45239;28725.13824585182;29296;29985;30171.69828301689;30278.394432111676;25515.872420637777;29826.85086574553;30199;30706.232344191572;30812;35309.823450882584;35824.64175358317;36215.81892090523;36260.09349766277;36305;30277.243068923453;30313.393732124794;31023.379532408773;31080.533791992686;31176.53235201519;23233.88383058074;24217.51564968726;24229.63655545133;24577.50844983126;24879 02:23:34 INFO - PROCESS | 1644 | |2;jquery.html;383.6604604924641;411.8867311489342;418.9874303770887;425.12000159669486;445.99331010034837;12872.935635321823;14801.629959251011;21027.47431314218;21038.948052597367;21639.45901352463;21234.25680101197;21293.68059479105;21545.13819447224;22550.43623909403;22941.54116917665;6520.673966301678;6860.897086543706;6898.310168983086;7203.495755297131;7375.262473752636;7024.192217894931;7049.436045116404;7177.9282207177785;7210.315020073111;7274.4180465562895;7546.962265188667;7622.2758837910305;7630.42771792115;7637.847243055136;7643.770686879375;7034.788956331318;7132.714691412337;7191.352778249982;7201.17186523546;7626.466147369686;6713.2279787824045;6983.266756990544;6983.650817459106;7004.334588214111;7051.259617740115;24815.50368992628;25805;26025.089121880832;26246.86876565624;26856.328591785168;2807.368342123026;2858.2711408590885;2869.383082637243;2876.7123287671275;2921.5033444314518;13948.442062317496;15392.997820566621;16155.676886462323;16359.263833127538;16487.83512164875;1989.1247850945538;2015.617032763785;2022.7396208733944;2027.8479114066504;2071.7513898332145;88360.23279534437;130198;154017.45982540143;158870;161209;17266.827331726774;19721.60556788856;20468.590628187354;20601.793982060288;20699;31500.684993150233;31849.363012739614;32094.358112837614;32125.875594354085;32228.83885580557;9951.601935922483;10153;10346.637867674595;10358.482075896174;10499.527521261562;8512.914870851337;8533.786655333666;8565.400421970528;8687.305015598864;8707.172818582225;7489.438292128112;7660.578668173192;9369.437833729999;9446.669366572138;9482.573284202228;4006.639402453793;4009.278329900646;4020.4974378202724;4025.9798701006894;4030.5969403060044;961.7884065505577;983.6360546597805;985.3102828020415;1020.9693709188737;1025.0620682075967;21146.894265528575;21533.78466215349;21536.892315538324;21573.46066348354;21577.56844863094;983.3804703037041;984.2913102566141;1005.4168582222294;1015.6390436814556;1021.7496713305288;10741.033307002408;11072.557097716162;11135.665930022113;11150.609728659458;11190.440477976068;716703;721336;728025;742199;751278;104071;124236.37881810532;135285;143971;156170;1080.3949788118678;1164.1908873332952;1177.8586569611703;1204.4760529169664;1209.1656756837754;19402.150580576952;20251.18995240174;20335;20368.59262814765;20372.592548149252;61397;62212.06681899685;63830;64317;65180.67409662921;90115;90253;90392.54803725939;91220;92956;11287.774244515227;11425.771484570429;11498.930192565083;11614.883851161718;11723.589674361356;12326.691832704255;12464.189827661174;12659.936700316439;12722.61832145037;12779.744405112031;10674.46627668598;10727.463626818784;10957.61648342336;10991.505382257978;11197.944010280222;8719.607617657093;10299;10340;10463.895361046596;10547.156227501628;9521;10625.671791026121;11105.722356941142;11558.884411155781;11559;11398.08815294758;11453.255538389978;11516.654500365003;11544.711382215512;11784.351860647746;11148.944255278671;11200.271982321123;11442;11495.367754773568;11497.597584084517 02:23:34 INFO - PROCESS | 1644 | |3;mootools.html;1424.1526291856344;1499.9325030373632;1544.7837302777614;1556.2763315058498;1568.458881685818;8573.356998225134;10817.783644327128;10827.025567698905;10839.241253112285;10865.674029779118;10667.733306667336;11030.28303160294;11051.944740276307;11066.778664426707;11282.210245282833;2382.059086660772;2443.218170185541;2443.389152711822;2445.315311712723;2446.2049833803994;2353.7646235376496;2422.769836865495;2433.1727212747664;2439.1706819681303;2441.4506735984437;2311.318161142467;2371.2649078785544;2372.79831214347;2399.4961058177805;2401.435662619283;2292.3695983604475;2332.54515369503;2338.6959695239593;2345.1791872844537;2417.903283868643;2378.3102900158933;2391.820613453998;2406.795422389099;2408.626662867241;2409.4578719788083;10670.413127277996;10879.728006799814;10887;10895.455227238606;10936.179786515964;5377.46225377463;5619.80330688424;5649.689267090308;5878.41215878413;5891.882162356729;3107.207662046186;3580.104973756561;3644.362236608583;3710.870119545803;3729.5524537055458;1193.272104016558;1204.31354128147;1242.4719494214924;1247.1332423965348;1262.8863402293746;13524;13933.372998215105;14149.292535373294;15021.774673379909;15390.615234619112;4778.95221047792;4958.925616115761;4968.056069346812;4970.701757894538;5037.874053148701;11407.543698252142;12732.427040783186;12754.68113297175;12754.80867786984;12771.744565108645;5055.646104772704;5102.642815002914;5105.2597373381395;5107.106256405114;5109.616778741609;4682.695624784378;5128.410232823273;5205.011047900886;5247.212918062245;5248.580113590903;3965.8017099144927;4090.1615168890307;4093.16090201508;4101.507819061731;4194.8112334945;1099.3458891959272;1141.9771604567861;1144.725265936186;1158.362900404774;1163.3194581169942;763.4426868386109;781.656071328625;788.8540619985321;798.5488199167452;809.4778867630463;5601.5238704709745;6026.457618814327;6312.147860038928;6359;6634.13756211699;965.8940513112587;1007.9647212347533;1016.8576399304103;1026.5893642543042;1071.3625392891217;3823.5794062653085;3978.4430179774854;4035.7174997750462;4106.055607210358;4190;13155.013373996988;13676.65808354799;13720.725585488235;13726.107802992774;13735.175889446466;13573.796393053915;13583.049186556846;13623.727525449436;13626.386812593457;13634.727305453835;9878.654247101316;9880.604775808888;10060.195184385379;10270.0756931875;10279;6333.896390768398;6342.8731425370315;6348.936510634834;6478.4169424752;6589.934100658932;12045.33750643723;12078.577249796215;12081.516739330329;12260.08049396281;12331.876681233432;9630.518474076409;11479.483423246142;12352.876471235531;12455.065870059601;12589.496420143092;7799.766007019798;8400.327973762207;8424.199701028392;8507.191816777397;8513.148685131686;10010.649627263303;10227.28409011362;10243.94878025605;10250.282480226313;10279.023492768176;8531.061583225965;8662.13378662154;8744.956275218583;8745.825083498421;8746.91253087461;8549.27331176832;8550.78623034429;8592.871106933277;8643.524606146471;8645.567721613768;7642.273983971514;7782.338501227459;7790.14308426084;7799;7810.453268271166;5640.435956404327;5652.5477961762135;5655.717214139194;5805.883882322414;5845.970770146122;5016.749162541785;5043.949560504348;5715.2855893013375;5732.140178973187;5904.704764761659 02:23:34 INFO - PROCESS | 1644 | |4;prototype.html;332.6676659976793;379.18853653182197;384.20277923309123;384.4694321835868;385.74155315938356;5004.796929964826;5040.5211504907065;5059.392872855254;5122.385313762349;5130.461301563338;4478.014836735921;4617.722936623805;4789.97015641637;4973.850784476463;5092.592592592593;3535.9116022099397;3608.63913608639;3690.852365905361;3910.687145028391;4121.876343709693;3752.3058234226755;3833.5399752029657;3993.760374377532;4150.941509914967;4178.331466965286;3528.359312919492;4059.391091336294;4143.482064741907;4170.622745035331;4202.747835129902;3420.9486857697157;3704.6107709608896;3797.7023900540053;4105.815238314283;4174.77038762868;3222.4360736870954;3345.5650765400587;3567.6967457766123;3799;3811.5426148862316;4106.897327566805;4592.954070459319;4627.907441851178;4831.6325000749375;4834.984653222828;4634.189016922025;4803.951960480385;4804.25534042224;4818.614510839125;4830.9999999999645;4390.582894625006;5082.567981721558;5135.35808023997;5256.001359741636;5299.708516031616;3709.812859884838;3968.4840970673918;4256.723312984646;4283.764392958386;4305.74165550068;5135.6918584885025;5438.564914806806;5457.181422786613;5474.70062573716;5621.634593751434;3784.3566593679143;3841.4237864320576;4030.5767894371256;4305.913881722348;4341.587549182823;3795.5065841440437;4139.8965025873995;4159.272127377697;4305.4187684662165;4397;4324.2648749712625;4457.665675074382;4541.568550987651;4614.538546145359;4775.617950563947;4910.607151427877;5301.257823904656;5342.500662066353;5378.596605254544;5380.838574842761;1414.9812135262594;1512.4781950227155;1515.2272341106116;1518.8177418709604;1519.4302136698739;1010.3432768700403;1024.9084724768097;1062.3625824505234;1141.3095077478172;1142.2232881640398;4349.565043495625;4377.759223242752;4987.002599480046;5009.343179479;5034.633722150584;4601.654875884322;5015.598752099823;5028.622853286018;5101.744912754348;5489.752961116679;4275.273203555403;4341.956580434218;4419.580139886705;4548.545145485425;4869.853904382874;2466.6793316869052;2625.658664373619;2699.6917635421823;2848.9715102848704;2854.9000784972427;5679.517241034476;5694.3271589824335;5708.914366284509;5731.077150653196;5736.397678243808;5407.242985981966;5489.1959473594625;5630.8029218977135;5645.5201307888465;5668.348139963874;5037.29477873098;5484.561235101182;5545.445455454503;5603.887922241451;5641.238432811682;4627.953720462887;4852.587530059844;5394.655313705528;5403.324584426947;5615.876824635171;4595.805090676381;4774.856754297377;5283.565682306532;5475.72621368938;5499.945000550103;5297.655652382583;5497.866216256855;5720.713964301852;5737.8565535861935;5744.798932037359;4223.809928553161;4308;4312.353147027971;4328.156009578102;4331.592232524379;5067.366579177603;5231.869203269949;5274.472552744694;5541.695206763667;5552.167174923632;4855.441624213261;5226.581873450191;5262.842114736564;5308.893822123613;5315.654482458628;5209.374875015023;5299.933026119894;5331.253624492497;5399.919001215064;5462.562994960473;4352.978235108931;4892.975535122444;4947.554720075211;5213.191955246943;5223.843284701465;4988.900221995467;5054.916500934353;5181.922271166011;5216.2436446714255;5341.9198712020125;4516.796744146587;4604.631629469702;4911.729854857874;4919.385076865392;5075.187969924795 02:23:34 INFO - PROCESS | 1644 | |5;yui.html;702.9367356937875;748.4611080022382;771.0362047440699;774.1252384805169;830.588858515035;15366.001209921362;15850.920745396286;15880.047197168178;15881.36474541017;15901.602459938493;14349.28253587319;14525.05587136838;14526.636834079154;14531.709365812678;15158.84841151591;916.2578311567635;936.6113063078814;959.1990687775716;970.175350951689;970.6845275285525;954.0793134625086;955.9235261179089;958.089814676057;958.6309270930707;958.7267628725815;992.6575331510618;995.1640621877622;995.5669283861507;996.4718699089494;1000.2498126405196;986.6497393425374;989.159214667534;989.6931951095112;990.8166989107002;991.4794732765283;736.1129838544541;736.8636802191559;737.4395459450802;737.8524295140993;737.9926400463329;15395.307211175525;15588.922055389763;15763.054216747032;15788.684226315409;15916.602084947854;13921.373538190806;13993;14011.64970875726;14017.299135043208;14249.572512824632;10835.458227088693;11286.92774186451;11754.05967522596;11778.646640600795;12282.631521054383;3570.5179800727087;3625.6918161956264;3685.1155722626377;3720.1629633332554;3833.137544052594;111514.44242778735;112896;114249.4287528557;115253.84746152515;116101;10754.516046777913;10907.727306817234;10987.560497580167;11021.559137634566;11120.221584489009;22076.11695532193;22431.439214019454;22830.086796528285;22838.543229135325;22841.657375139388;7634.923650763532;7637.121731000895;7717.421193410517;7751;7761.301482866569;4228.830846766156;4268.189044081614;4331.45856767904;4472.552744725566;4518.141553104965;3409.232922592397;3436.9656303437146;3496.7027802637062;3507.859685612547;3541.13242255649;599.6372194822158;606.8088552106072;619.6344156947423;631.4033238589581;635.1774452084561;666.6166954001469;705.3228900255812;707.4411215139982;709.4891677991837;713.7501874343938;9526.618935242514;9661.516924153904;9719.854202186973;10024.649137280161;10068.295219334575;541.8022421816008;583.8102616649605;597.3518732175552;599.2030599303004;603.1796756411277;3627.6553727395853;4084.14233011071;4147.688923330762;4165.229432554987;4304.332828411601;97164.51417742866;98685.5197172043;99205.00794991957;100094.49858252132;101087.49456252671;47883.521164787904;61292;71672;79390.20609793943;88813.55593221993;69589.60820783555;70363.29636703772;71070.64464677643;71379.64310178415;72440;10599.788004240027;10932.418051829276;10943.45282735844;11047.834282485608;11057.61298354586;44529.777351113036;44542.55457445384;47127.46544009282;48554;49285.01429971452;58312.54218644568;60988.39011610005;61910.38089619226;65675.67162164158;65718.35704107245;12122.575709850113;12582.119251652663;12661.87338126607;12786.296753678636;13127.606171814861;14136.22250776176;14788.926055369655;14816.851831481978;15197.544073677807;15553.76669349938;13361.53234636783;13698.589042328746;13912.860871391156;13983.860161398256;14391.28043597837;12662.303573303188;13519.864801351861;13704.10923289983;14021.228832414316;14139.77234767086;12869.906057985176;13482.123661961941;13581.253031083384;13637.6775479691;13828.585142445743;9600.951995239979;10044;10303.845442318221;10526.526306316387;10627.734306642396;11300.321980681185;11553.191276610556;11557.422128893353;11571.13216508782;11629.418529073682 02:23:34 INFO - PROCESS | 1644 | __end_tp_report 02:23:34 INFO - PROCESS | 1644 | __start_cc_report 02:23:34 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,2607 02:23:34 INFO - PROCESS | 1644 | __end_cc_report 02:23:34 INFO - PROCESS | 1644 | __startTimestamp1493112214887__endTimestamp 02:23:34 INFO - PROCESS | 1644 | 02:23:34 INFO - PROCESS | 1644 | ------- Summary: start ------- 02:23:34 INFO - PROCESS | 1644 | Number of tests: 6 02:23:34 INFO - PROCESS | 1644 | 02:23:34 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5827.97 Median:6010.25 stddev:655.77 (10.9%) stddev-sans-first:614.47 02:23:34 INFO - PROCESS | 1644 | Values: 2840.3 3396.4 3412.4 3423.9 3425.5 5724.1 5771.5 5774.0 5796.9 5803.8 5779.3 5854.8 5880.5 5888.4 5916.4 5281.2 5314.4 5315.5 5342.7 5377.9 5203.3 5227.7 5265.8 5390.6 5400.5 5330.3 5337.8 5346.6 5372.3 5405.5 5373.5 5379.7 5381.2 5381.5 5388.7 5187.2 5223.4 5279.0 5313.4 5359.5 4798.3 5671.6 5686.8 5716.4 5831.5 6336.5 6372.8 6376.1 6379.9 6397.6 6287.1 6336.3 6349.2 6351.0 6373.4 5727.1 5853.8 5858.9 5969.6 5979.7 6349.7 6360.9 6380.2 6382.6 6397.2 6312.1 6320.9 6322.4 6327.9 6332.7 6212.5 6245.0 6304.5 6318.7 6340.0 6150.8 6290.6 6321.0 6356.1 6379.1 6238.6 6249.7 6254.0 6310.8 6380.1 4997.8 5081.2 5085.0 5093.7 5097.2 4552.7 4590.0 4599.1 4599.2 4611.5 5655.3 5666.8 5683.9 5857.0 5873.8 6266.7 6280.0 6287.2 6305.7 6319.7 4906.7 5849.6 5856.9 5859.4 5878.2 5524.6 5529.1 5561.6 5568.1 5582.4 6010.2 6254.1 6314.2 6366.3 6447.7 6312.1 6335.6 6336.3 6347.2 6356.1 6412.2 6416.3 6454.5 6463.8 6478.7 6117.1 6134.1 6196.6 6324.7 6348.0 5840.7 6331.6 6347.0 6352.7 6365.3 6266.2 6271.5 6273.9 6274.2 6275.0 5953.1 6063.4 6104.8 6276.2 6280.8 5919.8 6063.5 6073.2 6110.5 6133.7 6203.7 6241.9 6256.6 6265.4 6267.5 5786.7 5828.0 5848.7 5882.8 6051.3 02:23:34 INFO - PROCESS | 1644 | 02:23:34 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:53122.49 Median:31056.77 stddev:104470.11 (336.4%) stddev-sans-first:104689.05 02:23:34 INFO - PROCESS | 1644 | Values: 707.6 816.2 861.7 872.1 970.1 37669.6 38023.0 38169.4 39155.8 39496.6 31033.0 37877.6 38104.6 38124.6 38283.0 7391.7 7534.7 7716.2 7862.6 10832.4 10942.3 11024.4 11054.9 11057.7 11066.0 11068.9 11073.7 11079.0 11090.5 11175.9 10985.7 11019.1 11020.9 11028.3 11031.7 10708.4 10746.4 10757.7 10773.7 10780.4 38534.8 38661.2 38789.4 38828.2 38850.2 3852.9 3863.3 3888.3 3929.7 3942.7 25984.5 26775.5 28323.6 28543.3 28762.6 3044.5 3089.4 3264.6 3279.3 3280.5 326196.0 326513.0 330551.0 330731.0 331130.0 40996.4 42290.8 44408.0 50798.5 53147.5 54439.0 54654.7 54719.2 55135.4 56882.7 14401.4 14540.9 14551.3 14560.2 14568.9 12572.7 12583.8 12613.6 12655.6 12706.0 11866.9 11891.3 11907.6 11944.8 11995.6 4639.6 5006.6 5214.0 5453.3 5498.6 951.3 963.4 968.7 975.6 1011.7 22877.9 26250.5 27393.3 28083.2 28225.0 1035.6 1038.4 1042.7 1049.8 1069.2 13817.8 14180.0 14264.8 14563.4 14648.5 374092.0 446251.8 446481.0 446951.0 447149.0 54702.7 54714.5 54811.7 54815.2 54904.9 1436.6 1482.6 1483.5 1565.3 1570.5 25449.0 26275.3 27008.5 27017.3 27100.3 41926.4 42069.8 42088.4 42095.6 42989.4 412335.0 413899.0 414174.9 414517.0 414621.0 26449.5 27049.9 27878.4 33138.8 33229.8 36274.6 42470.8 43608.7 43635.8 45239.0 28725.1 29296.0 29985.0 30171.7 30278.4 25515.9 29826.9 30199.0 30706.2 30812.0 35309.8 35824.6 36215.8 36260.1 36305.0 30277.2 30313.4 31023.4 31080.5 31176.5 23233.9 24217.5 24229.6 24577.5 24879.0 02:23:34 INFO - PROCESS | 1644 | 02:23:34 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:41627.14 Median:21559.30 stddev:121586.88 (564.0%) stddev-sans-first:121888.52 02:23:34 INFO - PROCESS | 1644 | Values: 383.7 411.9 419.0 425.1 446.0 12872.9 14801.6 21027.5 21038.9 21639.5 21234.3 21293.7 21545.1 22550.4 22941.5 6520.7 6860.9 6898.3 7203.5 7375.3 7024.2 7049.4 7177.9 7210.3 7274.4 7547.0 7622.3 7630.4 7637.8 7643.8 7034.8 7132.7 7191.4 7201.2 7626.5 6713.2 6983.3 6983.7 7004.3 7051.3 24815.5 25805.0 26025.1 26246.9 26856.3 2807.4 2858.3 2869.4 2876.7 2921.5 13948.4 15393.0 16155.7 16359.3 16487.8 1989.1 2015.6 2022.7 2027.8 2071.8 88360.2 130198.0 154017.5 158870.0 161209.0 17266.8 19721.6 20468.6 20601.8 20699.0 31500.7 31849.4 32094.4 32125.9 32228.8 9951.6 10153.0 10346.6 10358.5 10499.5 8512.9 8533.8 8565.4 8687.3 8707.2 7489.4 7660.6 9369.4 9446.7 9482.6 4006.6 4009.3 4020.5 4026.0 4030.6 961.8 983.6 985.3 1021.0 1025.1 21146.9 21533.8 21536.9 21573.5 21577.6 983.4 984.3 1005.4 1015.6 1021.7 10741.0 11072.6 11135.7 11150.6 11190.4 716703.0 721336.0 728025.0 742199.0 751278.0 104071.0 124236.4 135285.0 143971.0 156170.0 1080.4 1164.2 1177.9 1204.5 1209.2 19402.2 20251.2 20335.0 20368.6 20372.6 61397.0 62212.1 63830.0 64317.0 65180.7 90115.0 90253.0 90392.5 91220.0 92956.0 11287.8 11425.8 11498.9 11614.9 11723.6 12326.7 12464.2 12659.9 12722.6 12779.7 10674.5 10727.5 10957.6 10991.5 11197.9 8719.6 10299.0 10340.0 10463.9 10547.2 9521.0 10625.7 11105.7 11558.9 11559.0 11398.1 11453.3 11516.7 11544.7 11784.4 11148.9 11200.3 11442.0 11495.4 11497.6 02:23:34 INFO - PROCESS | 1644 | 02:23:34 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:6606.55 Median:2442.33 stddev:4142.03 (169.6%) stddev-sans-first:4135.34 02:23:34 INFO - PROCESS | 1644 | Values: 1424.2 1499.9 1544.8 1556.3 1568.5 8573.4 10817.8 10827.0 10839.2 10865.7 10667.7 11030.3 11051.9 11066.8 11282.2 2382.1 2443.2 2443.4 2445.3 2446.2 2353.8 2422.8 2433.2 2439.2 2441.5 2311.3 2371.3 2372.8 2399.5 2401.4 2292.4 2332.5 2338.7 2345.2 2417.9 2378.3 2391.8 2406.8 2408.6 2409.5 10670.4 10879.7 10887.0 10895.5 10936.2 5377.5 5619.8 5649.7 5878.4 5891.9 3107.2 3580.1 3644.4 3710.9 3729.6 1193.3 1204.3 1242.5 1247.1 1262.9 13524.0 13933.4 14149.3 15021.8 15390.6 4779.0 4958.9 4968.1 4970.7 5037.9 11407.5 12732.4 12754.7 12754.8 12771.7 5055.6 5102.6 5105.3 5107.1 5109.6 4682.7 5128.4 5205.0 5247.2 5248.6 3965.8 4090.2 4093.2 4101.5 4194.8 1099.3 1142.0 1144.7 1158.4 1163.3 763.4 781.7 788.9 798.5 809.5 5601.5 6026.5 6312.1 6359.0 6634.1 965.9 1008.0 1016.9 1026.6 1071.4 3823.6 3978.4 4035.7 4106.1 4190.0 13155.0 13676.7 13720.7 13726.1 13735.2 13573.8 13583.0 13623.7 13626.4 13634.7 9878.7 9880.6 10060.2 10270.1 10279.0 6333.9 6342.9 6348.9 6478.4 6589.9 12045.3 12078.6 12081.5 12260.1 12331.9 9630.5 11479.5 12352.9 12455.1 12589.5 7799.8 8400.3 8424.2 8507.2 8513.1 10010.6 10227.3 10243.9 10250.3 10279.0 8531.1 8662.1 8745.0 8745.8 8746.9 8549.3 8550.8 8592.9 8643.5 8645.6 7642.3 7782.3 7790.1 7799.0 7810.5 5640.4 5652.5 5655.7 5805.9 5846.0 5016.7 5043.9 5715.3 5732.1 5904.7 02:23:34 INFO - PROCESS | 1644 | 02:23:34 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4406.45 Median:4796.96 stddev:1249.91 (26.1%) stddev-sans-first:1215.44 02:23:34 INFO - PROCESS | 1644 | Values: 332.7 379.2 384.2 384.5 385.7 5004.8 5040.5 5059.4 5122.4 5130.5 4478.0 4617.7 4790.0 4973.9 5092.6 3535.9 3608.6 3690.9 3910.7 4121.9 3752.3 3833.5 3993.8 4150.9 4178.3 3528.4 4059.4 4143.5 4170.6 4202.7 3420.9 3704.6 3797.7 4105.8 4174.8 3222.4 3345.6 3567.7 3799.0 3811.5 4106.9 4593.0 4627.9 4831.6 4835.0 4634.2 4804.0 4804.3 4818.6 4831.0 4390.6 5082.6 5135.4 5256.0 5299.7 3709.8 3968.5 4256.7 4283.8 4305.7 5135.7 5438.6 5457.2 5474.7 5621.6 3784.4 3841.4 4030.6 4305.9 4341.6 3795.5 4139.9 4159.3 4305.4 4397.0 4324.3 4457.7 4541.6 4614.5 4775.6 4910.6 5301.3 5342.5 5378.6 5380.8 1415.0 1512.5 1515.2 1518.8 1519.4 1010.3 1024.9 1062.4 1141.3 1142.2 4349.6 4377.8 4987.0 5009.3 5034.6 4601.7 5015.6 5028.6 5101.7 5489.8 4275.3 4342.0 4419.6 4548.5 4869.9 2466.7 2625.7 2699.7 2849.0 2854.9 5679.5 5694.3 5708.9 5731.1 5736.4 5407.2 5489.2 5630.8 5645.5 5668.3 5037.3 5484.6 5545.4 5603.9 5641.2 4628.0 4852.6 5394.7 5403.3 5615.9 4595.8 4774.9 5283.6 5475.7 5499.9 5297.7 5497.9 5720.7 5737.9 5744.8 4223.8 4308.0 4312.4 4328.2 4331.6 5067.4 5231.9 5274.5 5541.7 5552.2 4855.4 5226.6 5262.8 5308.9 5315.7 5209.4 5299.9 5331.3 5399.9 5462.6 4353.0 4893.0 4947.6 5213.2 5223.8 4988.9 5054.9 5181.9 5216.2 5341.9 4516.8 4604.6 4911.7 4919.4 5075.2 02:23:34 INFO - PROCESS | 1644 | 02:23:34 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:19727.76 Median:3626.67 stddev:28095.33 (774.7%) stddev-sans-first:28137.82 02:23:34 INFO - PROCESS | 1644 | Values: 702.9 748.5 771.0 774.1 830.6 15366.0 15850.9 15880.0 15881.4 15901.6 14349.3 14525.1 14526.6 14531.7 15158.8 916.3 936.6 959.2 970.2 970.7 954.1 955.9 958.1 958.6 958.7 992.7 995.2 995.6 996.5 1000.2 986.6 989.2 989.7 990.8 991.5 736.1 736.9 737.4 737.9 738.0 15395.3 15588.9 15763.1 15788.7 15916.6 13921.4 13993.0 14011.6 14017.3 14249.6 10835.5 11286.9 11754.1 11778.6 12282.6 3570.5 3625.7 3685.1 3720.2 3833.1 111514.4 112896.0 114249.4 115253.8 116101.0 10754.5 10907.7 10987.6 11021.6 11120.2 22076.1 22431.4 22830.1 22838.5 22841.7 7634.9 7637.1 7717.4 7751.0 7761.3 4228.8 4268.2 4331.5 4472.6 4518.1 3409.2 3437.0 3496.7 3507.9 3541.1 599.6 606.8 619.6 631.4 635.2 666.6 705.3 707.4 709.5 713.8 9526.6 9661.5 9719.9 10024.6 10068.3 541.8 583.8 597.4 599.2 603.2 3627.7 4084.1 4147.7 4165.2 4304.3 97164.5 98685.5 99205.0 100094.5 101087.5 47883.5 61292.0 71672.0 79390.2 88813.6 69589.6 70363.3 71070.6 71379.6 72440.0 10599.8 10932.4 10943.5 11047.8 11057.6 44529.8 44542.6 47127.5 48554.0 49285.0 58312.5 60988.4 61910.4 65675.7 65718.4 12122.6 12582.1 12661.9 12786.3 13127.6 14136.2 14788.9 14816.9 15197.5 15553.8 13361.5 13698.6 13912.9 13983.9 14391.3 12662.3 13519.9 13704.1 14021.2 14139.8 12869.9 13482.1 13581.3 13637.7 13828.6 9601.0 10044.0 10303.8 10526.5 10627.7 11300.3 11553.2 11557.4 11571.1 11629.4 02:23:34 INFO - PROCESS | 1644 | -------- Summary: end -------- 02:23:34 INFO - PROCESS | 1644 | 02:23:35 INFO - TEST-INFO | 1644: exit 0 02:23:35 INFO - TEST-OK | dromaeo_css | took 1121208ms 02:23:35 INFO - TEST-START | kraken 02:23:35 INFO - operating with platform_type : mac_ 02:23:35 INFO - Initialising browser for kraken test... 02:23:35 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpyML4Ti/profile http://localhost:49249/getInfo.html) 02:23:37 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 02:23:37 INFO - PROCESS | 1651 | colorDepth:24 02:23:37 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 02:23:37 INFO - PROCESS | 1651 | __metrics 02:23:38 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:23:38 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:23:38 INFO - TEST-INFO | 1651: exit 0 02:23:38 INFO - Browser initialized. 02:23:38 INFO - Running cycle 1/1 for kraken test... 02:23:38 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpyML4Ti/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:23:51 INFO - PROCESS | 1653 | RSS: Main: 210743296 02:23:51 INFO - PROCESS | 1653 | 02:23:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 02:23:54 INFO - PROCESS | 1653 | RSS: Main: 265007104 02:23:54 INFO - PROCESS | 1653 | 02:23:57 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 02:23:57 INFO - PROCESS | 1653 | RSS: Main: 279347200 02:23:57 INFO - PROCESS | 1653 | 02:24:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 02:24:01 INFO - PROCESS | 1653 | RSS: Main: 374734848 02:24:01 INFO - PROCESS | 1653 | 02:24:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 02:24:04 INFO - PROCESS | 1653 | RSS: Main: 279056384 02:24:04 INFO - PROCESS | 1653 | 02:24:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:24:07 INFO - PROCESS | 1653 | RSS: Main: 280489984 02:24:07 INFO - PROCESS | 1653 | 02:24:10 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 02:24:10 INFO - PROCESS | 1653 | RSS: Main: 320946176 02:24:10 INFO - PROCESS | 1653 | 02:24:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 02:24:14 INFO - PROCESS | 1653 | RSS: Main: 320417792 02:24:14 INFO - PROCESS | 1653 | 02:24:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 02:24:18 INFO - PROCESS | 1653 | RSS: Main: 319365120 02:24:18 INFO - PROCESS | 1653 | 02:24:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:24:20 INFO - PROCESS | 1653 | RSS: Main: 295825408 02:24:20 INFO - PROCESS | 1653 | 02:24:22 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:24:22 INFO - PROCESS | 1653 | RSS: Main: 299028480 02:24:22 INFO - PROCESS | 1653 | 02:24:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:24:25 INFO - PROCESS | 1653 | RSS: Main: 302698496 02:24:25 INFO - PROCESS | 1653 | 02:24:28 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:24:29 INFO - PROCESS | 1653 | RSS: Main: 319344640 02:24:29 INFO - PROCESS | 1653 | 02:24:32 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:24:32 INFO - PROCESS | 1653 | RSS: Main: 312967168 02:24:32 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 02:24:34 INFO - PROCESS | 1653 | RSS: Main: 357183488 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | __start_tp_report 02:24:34 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 02:24:34 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 02:24:34 INFO - PROCESS | 1653 | |i|pagename|runs| 02:24:34 INFO - PROCESS | 1653 | |0;ai-astar;106;116;118;115;116;117;117;117;115;116 02:24:34 INFO - PROCESS | 1653 | |1;audio-beat-detection;144;111;108;144;111;109;112;109;129;108 02:24:34 INFO - PROCESS | 1653 | |2;audio-dft;185;184;181;184;183;188;185;183;184;184 02:24:34 INFO - PROCESS | 1653 | |3;audio-fft;80;62;62;91;65;62;61;61;77;75 02:24:34 INFO - PROCESS | 1653 | |4;audio-oscillator;86;70;87;74;97;98;117;69;70;85 02:24:34 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;88;87;88;96;136;87;89;87;87;88 02:24:34 INFO - PROCESS | 1653 | |6;imaging-darkroom;95;94;95;95;95;96;96;95;94;95 02:24:34 INFO - PROCESS | 1653 | |7;imaging-desaturate;86;86;86;94;140;137;83;83;85;83 02:24:34 INFO - PROCESS | 1653 | |8;json-parse-financial;59;60;60;59;65;63;63;64;63;63 02:24:34 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;54;61;67;65;61;58;59;58;58;59 02:24:34 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;88;99;101;81;85;85;82;83;85;87 02:24:34 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;143;134;136;133;137;143;151;153;178;148 02:24:34 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;157;163;165;160;164;164;164;163;162;162 02:24:34 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;65;66;66;66;65;64;63;63;63;65 02:24:34 INFO - PROCESS | 1653 | __end_tp_report 02:24:34 INFO - PROCESS | 1653 | __start_cc_report 02:24:34 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,2113 02:24:34 INFO - PROCESS | 1653 | __end_cc_report 02:24:34 INFO - PROCESS | 1653 | __startTimestamp1493112274811__endTimestamp 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | ------- Summary: start ------- 02:24:34 INFO - PROCESS | 1653 | Number of tests: 14 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:115.30 Median:116.50 stddev:3.40 (2.9%) stddev-sans-first:1.00 02:24:34 INFO - PROCESS | 1653 | Values: 106.0 116.0 118.0 115.0 116.0 117.0 117.0 117.0 115.0 116.0 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:118.50 Median:111.50 stddev:14.78 (13.3%) stddev-sans-first:12.47 02:24:34 INFO - PROCESS | 1653 | Values: 144.0 111.0 108.0 144.0 111.0 109.0 112.0 109.0 129.0 108.0 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.00 stddev:1.79 (1.0%) stddev-sans-first:1.87 02:24:34 INFO - PROCESS | 1653 | Values: 185.0 184.0 181.0 184.0 183.0 188.0 185.0 183.0 184.0 184.0 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:69.60 Median:70.00 stddev:10.50 (15.0%) stddev-sans-first:10.44 02:24:34 INFO - PROCESS | 1653 | Values: 80.0 62.0 62.0 91.0 65.0 62.0 61.0 61.0 77.0 75.0 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:85.30 Median:85.50 stddev:15.52 (18.2%) stddev-sans-first:16.46 02:24:34 INFO - PROCESS | 1653 | Values: 86.0 70.0 87.0 74.0 97.0 98.0 117.0 69.0 70.0 85.0 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:88.00 stddev:15.25 (17.3%) stddev-sans-first:16.05 02:24:34 INFO - PROCESS | 1653 | Values: 88.0 87.0 88.0 96.0 136.0 87.0 89.0 87.0 87.0 88.0 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 02:24:34 INFO - PROCESS | 1653 | Values: 95.0 94.0 95.0 95.0 95.0 96.0 96.0 95.0 94.0 95.0 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:85.50 stddev:22.48 (26.3%) stddev-sans-first:23.53 02:24:34 INFO - PROCESS | 1653 | Values: 86.0 86.0 86.0 94.0 140.0 137.0 83.0 83.0 85.0 83.0 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:63.00 stddev:2.18 (3.5%) stddev-sans-first:2.05 02:24:34 INFO - PROCESS | 1653 | Values: 59.0 60.0 60.0 59.0 65.0 63.0 63.0 64.0 63.0 63.0 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:3.74 (6.2%) stddev-sans-first:3.28 02:24:34 INFO - PROCESS | 1653 | Values: 54.0 61.0 67.0 65.0 61.0 58.0 59.0 58.0 58.0 59.0 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:85.00 stddev:6.88 (8.1%) stddev-sans-first:7.30 02:24:34 INFO - PROCESS | 1653 | Values: 88.0 99.0 101.0 81.0 85.0 85.0 82.0 83.0 85.0 87.0 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:145.50 stddev:13.38 (9.2%) stddev-sans-first:14.16 02:24:34 INFO - PROCESS | 1653 | Values: 143.0 134.0 136.0 133.0 137.0 143.0 151.0 153.0 178.0 148.0 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.40 Median:163.50 stddev:2.37 (1.4%) stddev-sans-first:1.50 02:24:34 INFO - PROCESS | 1653 | Values: 157.0 163.0 165.0 160.0 164.0 164.0 164.0 163.0 162.0 162.0 02:24:34 INFO - PROCESS | 1653 | 02:24:34 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:65.00 stddev:1.26 (1.9%) stddev-sans-first:1.33 02:24:34 INFO - PROCESS | 1653 | Values: 65.0 66.0 66.0 66.0 65.0 64.0 63.0 63.0 63.0 65.0 02:24:34 INFO - PROCESS | 1653 | -------- Summary: end -------- 02:24:34 INFO - PROCESS | 1653 | 02:24:35 INFO - TEST-INFO | 1653: exit 0 02:24:35 INFO - TEST-OK | kraken | took 60138ms 02:24:35 INFO - SUITE-END | took 1181s 02:24:35 INFO - Completed test suite (00:19:41) 02:24:35 INFO - javascript benchmark 02:24:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7564.697297692789, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2840.3467202543416, 3396.422608156615, 3412.3687117883187, 3423.8801641942523, 3425.5375524304213, 5724.112762521805, 5771.509421699156, 5774.0, 5796.913046304298, 5803.825885223439, 5779.335376431712, 5854.765809367631, 5880.529557635389, 5888.352281249059, 5916.437938395857, 5281.155015197569, 5314.441983591715, 5315.495027972336, 5342.706151161685, 5377.919331210015, 5203.323567936163, 5227.712475813829, 5265.76304066318, 5390.56875449965, 5400.459954004607, 5330.28041214435, 5337.839864804062, 5346.572274218072, 5372.274742909696, 5405.5135037846585, 5373.462653734615, 5379.677219366831, 5381.192821076831, 5381.5425688816495, 5388.703621300827, 5187.247849061902, 5223.425423203445, 5278.97360513195, 5313.388960269541, 5359.490848369399, 4798.328234047236, 5671.631343962629, 5686.772529098831, 5716.428357164292, 5831.475167234969, 6336.4930805535905, 6372.840678983016, 6376.139221205124, 6379.87240255197, 6397.5521713480075, 6287.1198032275515, 6336.303006669309, 6349.206349206349, 6351.0, 6373.426391624776, 5727.083666613322, 5853.824385268449, 5858.941410585925, 5969.641821490724, 5979.671118088547, 6349.682515874188, 6360.872782544323, 6380.202474690664, 6382.5532212744665, 6397.168368112116, 6312.0531920211415, 6320.905186422207, 6322.399372059739, 6327.905081423783, 6332.651704156316, 6212.534059945522, 6245.0, 6304.464120549713, 6318.71565779541, 6340.048992651047, 6150.831342044997, 6290.55966082379, 6321.0, 6356.14192084072, 6379.138816259839, 6238.563300568917, 6249.6875156242, 6254.0, 6310.810675679737, 6380.106785050097, 4997.825076122391, 5081.212412076134, 5084.9745751271, 5093.668911520739, 5097.235414687826, 4552.6813123081065, 4590.036092420562, 4599.149157405891, 4599.218132917412, 4611.515790841981, 5655.349634791969, 5666.829995100154, 5683.857903552444, 5856.970715146397, 5873.823785286363, 6266.655333956676, 6279.968600157062, 6287.182666253358, 6305.747770089145, 6319.715612797435, 4906.705597664151, 5849.561282903799, 5856.8535786604825, 5859.44335288147, 5878.235829342244, 5524.558035357162, 5529.142982837586, 5561.58288128392, 5568.1091025435735, 5582.385937546866, 6010.248718910137, 6254.124422580933, 6314.179156709598, 6366.331535188739, 6447.677616119269, 6312.147860038836, 6335.619862808247, 6336.334684858208, 6347.238331400262, 6356.11014457967, 6412.166418365583, 6416.294207637063, 6454.48364130859, 6463.806085817433, 6478.740850365933, 6117.096718007379, 6134.099846298167, 6196.566240363131, 6324.747010119728, 6347.984322508377, 5840.678762668094, 6331.620102793847, 6347.016212487072, 6352.745890164341, 6365.299817020216, 6266.185395898687, 6271.498280137669, 6273.937260627518, 6274.152989346562, 6275.0, 5953.077273022688, 6063.393660633901, 6104.847378815565, 6276.152719382825, 6280.780172693934, 5919.792807251725, 6063.545234107548, 6073.210482637229, 6110.51115910735, 6133.723982420713, 6203.658798766111, 6241.875162496815, 6256.5620406572925, 6265.373462653881, 6267.4986001120715, 5786.652800831964, 5828.0, 5848.678322692293, 5882.823515294548, 6051.3041000283765], "value": 5784.984654411821, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.6284950401039, 816.216432225064, 861.7285555050158, 872.0799556467928, 970.1075010989889, 37669.62330376695, 38023.0, 38169.42745858814, 39155.825325240214, 39496.60503394972, 31033.0, 37877.621223787755, 38104.61895381045, 38124.61875381245, 38283.0, 7391.667374968112, 7534.660940257674, 7716.189800070982, 7862.567558784264, 10832.40421776802, 10942.288751231185, 11024.44877756109, 11054.889451105466, 11057.723556911102, 11066.0, 11068.889311106906, 11073.667789966274, 11079.0, 11090.500927458284, 11175.944120279388, 10985.670429887115, 11019.063379612702, 11020.944895275552, 11028.28316159447, 11031.72420689481, 10708.41103739294, 10746.408947507884, 10757.731056723565, 10773.730656733565, 10780.40707761073, 38534.843954681404, 38661.22677546462, 38789.41815872764, 38828.223435531414, 38850.22299553993, 3852.9229415411537, 3863.343231650598, 3888.2612303662213, 3929.6659783918403, 3942.704297177723, 25984.480310393687, 26775.464490710077, 28323.57514637282, 28543.286417839514, 28762.568561471595, 3044.4672182368, 3089.413011527825, 3264.5919260092487, 3279.311344617633, 3280.507923811447, 326196.0, 326513.0, 330551.0, 330731.0, 331130.0, 40996.38505422421, 42290.78854605769, 44408.0, 50798.49201508011, 53147.46852531425, 54439.0, 54654.72672636611, 54719.17921231185, 55135.44864551303, 56882.7155864218, 14401.42394304216, 14540.927295363666, 14551.3451894665, 14560.199189044492, 14568.927155364156, 12572.685682858002, 12583.811242831365, 12613.558525451564, 12655.557055503014, 12706.0, 11866.88133118675, 11891.345975971231, 11907.583234586747, 11944.76110477803, 11995.580154694544, 4639.582437580634, 5006.574441172541, 5214.009338225801, 5453.291072160594, 5498.5876059295715, 951.3293128344501, 963.4026903319847, 968.7190714692802, 975.6243846119295, 1011.6712068577742, 22877.88561057184, 26250.474990500083, 27393.31516712058, 28083.157505274874, 28225.0, 1035.6323505155713, 1038.3614077342531, 1042.7341028037802, 1049.7847941172042, 1069.1820757120809, 13817.792733109012, 14180.0, 14264.786028209584, 14563.41746330135, 14648.487302944346, 374092.0, 446251.7687411542, 446481.0, 446951.0, 447149.0, 54702.72648636731, 54714.45285547093, 54811.72594137004, 54815.17777233265, 54904.901901962534, 1436.6192958866118, 1482.629342664334, 1483.4956114920763, 1565.2565031610122, 1570.5445420827832, 25449.0, 26275.343116421478, 27008.45983080288, 27017.324566885985, 27100.32249193786, 41926.371104432845, 42069.78965105155, 42088.36867447052, 42095.57904420917, 42989.355159672006, 412335.0, 413899.0, 414174.9291253525, 414517.0, 414621.0, 26449.471010580066, 27049.86475067691, 27878.44243115167, 33138.84014059496, 33229.83385083156, 36274.63725362712, 42470.78764606157, 43608.691739246606, 43635.78182109069, 45239.0, 28725.13824585182, 29296.0, 29985.0, 30171.69828301689, 30278.394432111676, 25515.872420637777, 29826.85086574553, 30199.0, 30706.232344191572, 30812.0, 35309.823450882584, 35824.64175358317, 36215.81892090523, 36260.09349766277, 36305.0, 30277.243068923453, 30313.393732124794, 31023.379532408773, 31080.533791992686, 31176.53235201519, 23233.88383058074, 24217.51564968726, 24229.63655545133, 24577.50844983126, 24879.0], "value": 18400.085931877766, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.6604604924641, 411.8867311489342, 418.9874303770887, 425.12000159669486, 445.99331010034837, 12872.935635321823, 14801.629959251011, 21027.47431314218, 21038.948052597367, 21639.45901352463, 21234.25680101197, 21293.68059479105, 21545.13819447224, 22550.43623909403, 22941.54116917665, 6520.673966301678, 6860.897086543706, 6898.310168983086, 7203.495755297131, 7375.262473752636, 7024.192217894931, 7049.436045116404, 7177.9282207177785, 7210.315020073111, 7274.4180465562895, 7546.962265188667, 7622.2758837910305, 7630.42771792115, 7637.847243055136, 7643.770686879375, 7034.788956331318, 7132.714691412337, 7191.352778249982, 7201.17186523546, 7626.466147369686, 6713.2279787824045, 6983.266756990544, 6983.650817459106, 7004.334588214111, 7051.259617740115, 24815.50368992628, 25805.0, 26025.089121880832, 26246.86876565624, 26856.328591785168, 2807.368342123026, 2858.2711408590885, 2869.383082637243, 2876.7123287671275, 2921.5033444314518, 13948.442062317496, 15392.997820566621, 16155.676886462323, 16359.263833127538, 16487.83512164875, 1989.1247850945538, 2015.617032763785, 2022.7396208733944, 2027.8479114066504, 2071.7513898332145, 88360.23279534437, 130198.0, 154017.45982540143, 158870.0, 161209.0, 17266.827331726774, 19721.60556788856, 20468.590628187354, 20601.793982060288, 20699.0, 31500.684993150233, 31849.363012739614, 32094.358112837614, 32125.875594354085, 32228.83885580557, 9951.601935922483, 10153.0, 10346.637867674595, 10358.482075896174, 10499.527521261562, 8512.914870851337, 8533.786655333666, 8565.400421970528, 8687.305015598864, 8707.172818582225, 7489.438292128112, 7660.578668173192, 9369.437833729999, 9446.669366572138, 9482.573284202228, 4006.639402453793, 4009.278329900646, 4020.4974378202724, 4025.9798701006894, 4030.5969403060044, 961.7884065505577, 983.6360546597805, 985.3102828020415, 1020.9693709188737, 1025.0620682075967, 21146.894265528575, 21533.78466215349, 21536.892315538324, 21573.46066348354, 21577.56844863094, 983.3804703037041, 984.2913102566141, 1005.4168582222294, 1015.6390436814556, 1021.7496713305288, 10741.033307002408, 11072.557097716162, 11135.665930022113, 11150.609728659458, 11190.440477976068, 716703.0, 721336.0, 728025.0, 742199.0, 751278.0, 104071.0, 124236.37881810532, 135285.0, 143971.0, 156170.0, 1080.3949788118678, 1164.1908873332952, 1177.8586569611703, 1204.4760529169664, 1209.1656756837754, 19402.150580576952, 20251.18995240174, 20335.0, 20368.59262814765, 20372.592548149252, 61397.0, 62212.06681899685, 63830.0, 64317.0, 65180.67409662921, 90115.0, 90253.0, 90392.54803725939, 91220.0, 92956.0, 11287.774244515227, 11425.771484570429, 11498.930192565083, 11614.883851161718, 11723.589674361356, 12326.691832704255, 12464.189827661174, 12659.936700316439, 12722.61832145037, 12779.744405112031, 10674.46627668598, 10727.463626818784, 10957.61648342336, 10991.505382257978, 11197.944010280222, 8719.607617657093, 10299.0, 10340.0, 10463.895361046596, 10547.156227501628, 9521.0, 10625.671791026121, 11105.722356941142, 11558.884411155781, 11559.0, 11398.08815294758, 11453.255538389978, 11516.654500365003, 11544.711382215512, 11784.351860647746, 11148.944255278671, 11200.271982321123, 11442.0, 11495.367754773568, 11497.597584084517], "value": 11481.338258486883, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.1526291856344, 1499.9325030373632, 1544.7837302777614, 1556.2763315058498, 1568.458881685818, 8573.356998225134, 10817.783644327128, 10827.025567698905, 10839.241253112285, 10865.674029779118, 10667.733306667336, 11030.28303160294, 11051.944740276307, 11066.778664426707, 11282.210245282833, 2382.059086660772, 2443.218170185541, 2443.389152711822, 2445.315311712723, 2446.2049833803994, 2353.7646235376496, 2422.769836865495, 2433.1727212747664, 2439.1706819681303, 2441.4506735984437, 2311.318161142467, 2371.2649078785544, 2372.79831214347, 2399.4961058177805, 2401.435662619283, 2292.3695983604475, 2332.54515369503, 2338.6959695239593, 2345.1791872844537, 2417.903283868643, 2378.3102900158933, 2391.820613453998, 2406.795422389099, 2408.626662867241, 2409.4578719788083, 10670.413127277996, 10879.728006799814, 10887.0, 10895.455227238606, 10936.179786515964, 5377.46225377463, 5619.80330688424, 5649.689267090308, 5878.41215878413, 5891.882162356729, 3107.207662046186, 3580.104973756561, 3644.362236608583, 3710.870119545803, 3729.5524537055458, 1193.272104016558, 1204.31354128147, 1242.4719494214924, 1247.1332423965348, 1262.8863402293746, 13524.0, 13933.372998215105, 14149.292535373294, 15021.774673379909, 15390.615234619112, 4778.95221047792, 4958.925616115761, 4968.056069346812, 4970.701757894538, 5037.874053148701, 11407.543698252142, 12732.427040783186, 12754.68113297175, 12754.80867786984, 12771.744565108645, 5055.646104772704, 5102.642815002914, 5105.2597373381395, 5107.106256405114, 5109.616778741609, 4682.695624784378, 5128.410232823273, 5205.011047900886, 5247.212918062245, 5248.580113590903, 3965.8017099144927, 4090.1615168890307, 4093.16090201508, 4101.507819061731, 4194.8112334945, 1099.3458891959272, 1141.9771604567861, 1144.725265936186, 1158.362900404774, 1163.3194581169942, 763.4426868386109, 781.656071328625, 788.8540619985321, 798.5488199167452, 809.4778867630463, 5601.5238704709745, 6026.457618814327, 6312.147860038928, 6359.0, 6634.13756211699, 965.8940513112587, 1007.9647212347533, 1016.8576399304103, 1026.5893642543042, 1071.3625392891217, 3823.5794062653085, 3978.4430179774854, 4035.7174997750462, 4106.055607210358, 4190.0, 13155.013373996988, 13676.65808354799, 13720.725585488235, 13726.107802992774, 13735.175889446466, 13573.796393053915, 13583.049186556846, 13623.727525449436, 13626.386812593457, 13634.727305453835, 9878.654247101316, 9880.604775808888, 10060.195184385379, 10270.0756931875, 10279.0, 6333.896390768398, 6342.8731425370315, 6348.936510634834, 6478.4169424752, 6589.934100658932, 12045.33750643723, 12078.577249796215, 12081.516739330329, 12260.08049396281, 12331.876681233432, 9630.518474076409, 11479.483423246142, 12352.876471235531, 12455.065870059601, 12589.496420143092, 7799.766007019798, 8400.327973762207, 8424.199701028392, 8507.191816777397, 8513.148685131686, 10010.649627263303, 10227.28409011362, 10243.94878025605, 10250.282480226313, 10279.023492768176, 8531.061583225965, 8662.13378662154, 8744.956275218583, 8745.825083498421, 8746.91253087461, 8549.27331176832, 8550.78623034429, 8592.871106933277, 8643.524606146471, 8645.567721613768, 7642.273983971514, 7782.338501227459, 7790.14308426084, 7799.0, 7810.453268271166, 5640.435956404327, 5652.5477961762135, 5655.717214139194, 5805.883882322414, 5845.970770146122, 5016.749162541785, 5043.949560504348, 5715.2855893013375, 5732.140178973187, 5904.704764761659], "value": 5050.7712084632485, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.6676659976793, 379.18853653182197, 384.20277923309123, 384.4694321835868, 385.74155315938356, 5004.796929964826, 5040.5211504907065, 5059.392872855254, 5122.385313762349, 5130.461301563338, 4478.014836735921, 4617.722936623805, 4789.97015641637, 4973.850784476463, 5092.592592592593, 3535.9116022099397, 3608.63913608639, 3690.852365905361, 3910.687145028391, 4121.876343709693, 3752.3058234226755, 3833.5399752029657, 3993.760374377532, 4150.941509914967, 4178.331466965286, 3528.359312919492, 4059.391091336294, 4143.482064741907, 4170.622745035331, 4202.747835129902, 3420.9486857697157, 3704.6107709608896, 3797.7023900540053, 4105.815238314283, 4174.77038762868, 3222.4360736870954, 3345.5650765400587, 3567.6967457766123, 3799.0, 3811.5426148862316, 4106.897327566805, 4592.954070459319, 4627.907441851178, 4831.6325000749375, 4834.984653222828, 4634.189016922025, 4803.951960480385, 4804.25534042224, 4818.614510839125, 4830.9999999999645, 4390.582894625006, 5082.567981721558, 5135.35808023997, 5256.001359741636, 5299.708516031616, 3709.812859884838, 3968.4840970673918, 4256.723312984646, 4283.764392958386, 4305.74165550068, 5135.6918584885025, 5438.564914806806, 5457.181422786613, 5474.70062573716, 5621.634593751434, 3784.3566593679143, 3841.4237864320576, 4030.5767894371256, 4305.913881722348, 4341.587549182823, 3795.5065841440437, 4139.8965025873995, 4159.272127377697, 4305.4187684662165, 4397.0, 4324.2648749712625, 4457.665675074382, 4541.568550987651, 4614.538546145359, 4775.617950563947, 4910.607151427877, 5301.257823904656, 5342.500662066353, 5378.596605254544, 5380.838574842761, 1414.9812135262594, 1512.4781950227155, 1515.2272341106116, 1518.8177418709604, 1519.4302136698739, 1010.3432768700403, 1024.9084724768097, 1062.3625824505234, 1141.3095077478172, 1142.2232881640398, 4349.565043495625, 4377.759223242752, 4987.002599480046, 5009.343179479, 5034.633722150584, 4601.654875884322, 5015.598752099823, 5028.622853286018, 5101.744912754348, 5489.752961116679, 4275.273203555403, 4341.956580434218, 4419.580139886705, 4548.545145485425, 4869.853904382874, 2466.6793316869052, 2625.658664373619, 2699.6917635421823, 2848.9715102848704, 2854.9000784972427, 5679.517241034476, 5694.3271589824335, 5708.914366284509, 5731.077150653196, 5736.397678243808, 5407.242985981966, 5489.1959473594625, 5630.8029218977135, 5645.5201307888465, 5668.348139963874, 5037.29477873098, 5484.561235101182, 5545.445455454503, 5603.887922241451, 5641.238432811682, 4627.953720462887, 4852.587530059844, 5394.655313705528, 5403.324584426947, 5615.876824635171, 4595.805090676381, 4774.856754297377, 5283.565682306532, 5475.72621368938, 5499.945000550103, 5297.655652382583, 5497.866216256855, 5720.713964301852, 5737.8565535861935, 5744.798932037359, 4223.809928553161, 4308.0, 4312.353147027971, 4328.156009578102, 4331.592232524379, 5067.366579177603, 5231.869203269949, 5274.472552744694, 5541.695206763667, 5552.167174923632, 4855.441624213261, 5226.581873450191, 5262.842114736564, 5308.893822123613, 5315.654482458628, 5209.374875015023, 5299.933026119894, 5331.253624492497, 5399.919001215064, 5462.562994960473, 4352.978235108931, 4892.975535122444, 4947.554720075211, 5213.191955246943, 5223.843284701465, 4988.900221995467, 5054.916500934353, 5181.922271166011, 5216.2436446714255, 5341.9198712020125, 4516.796744146587, 4604.631629469702, 4911.729854857874, 4919.385076865392, 5075.187969924795], "value": 4043.8415964500455, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.9367356937875, 748.4611080022382, 771.0362047440699, 774.1252384805169, 830.588858515035, 15366.001209921362, 15850.920745396286, 15880.047197168178, 15881.36474541017, 15901.602459938493, 14349.28253587319, 14525.05587136838, 14526.636834079154, 14531.709365812678, 15158.84841151591, 916.2578311567635, 936.6113063078814, 959.1990687775716, 970.175350951689, 970.6845275285525, 954.0793134625086, 955.9235261179089, 958.089814676057, 958.6309270930707, 958.7267628725815, 992.6575331510618, 995.1640621877622, 995.5669283861507, 996.4718699089494, 1000.2498126405196, 986.6497393425374, 989.159214667534, 989.6931951095112, 990.8166989107002, 991.4794732765283, 736.1129838544541, 736.8636802191559, 737.4395459450802, 737.8524295140993, 737.9926400463329, 15395.307211175525, 15588.922055389763, 15763.054216747032, 15788.684226315409, 15916.602084947854, 13921.373538190806, 13993.0, 14011.64970875726, 14017.299135043208, 14249.572512824632, 10835.458227088693, 11286.92774186451, 11754.05967522596, 11778.646640600795, 12282.631521054383, 3570.5179800727087, 3625.6918161956264, 3685.1155722626377, 3720.1629633332554, 3833.137544052594, 111514.44242778735, 112896.0, 114249.4287528557, 115253.84746152515, 116101.0, 10754.516046777913, 10907.727306817234, 10987.560497580167, 11021.559137634566, 11120.221584489009, 22076.11695532193, 22431.439214019454, 22830.086796528285, 22838.543229135325, 22841.657375139388, 7634.923650763532, 7637.121731000895, 7717.421193410517, 7751.0, 7761.301482866569, 4228.830846766156, 4268.189044081614, 4331.45856767904, 4472.552744725566, 4518.141553104965, 3409.232922592397, 3436.9656303437146, 3496.7027802637062, 3507.859685612547, 3541.13242255649, 599.6372194822158, 606.8088552106072, 619.6344156947423, 631.4033238589581, 635.1774452084561, 666.6166954001469, 705.3228900255812, 707.4411215139982, 709.4891677991837, 713.7501874343938, 9526.618935242514, 9661.516924153904, 9719.854202186973, 10024.649137280161, 10068.295219334575, 541.8022421816008, 583.8102616649605, 597.3518732175552, 599.2030599303004, 603.1796756411277, 3627.6553727395853, 4084.14233011071, 4147.688923330762, 4165.229432554987, 4304.332828411601, 97164.51417742866, 98685.5197172043, 99205.00794991957, 100094.49858252132, 101087.49456252671, 47883.521164787904, 61292.0, 71672.0, 79390.20609793943, 88813.55593221993, 69589.60820783555, 70363.29636703772, 71070.64464677643, 71379.64310178415, 72440.0, 10599.788004240027, 10932.418051829276, 10943.45282735844, 11047.834282485608, 11057.61298354586, 44529.777351113036, 44542.55457445384, 47127.46544009282, 48554.0, 49285.01429971452, 58312.54218644568, 60988.39011610005, 61910.38089619226, 65675.67162164158, 65718.35704107245, 12122.575709850113, 12582.119251652663, 12661.87338126607, 12786.296753678636, 13127.606171814861, 14136.22250776176, 14788.926055369655, 14816.851831481978, 15197.544073677807, 15553.76669349938, 13361.53234636783, 13698.589042328746, 13912.860871391156, 13983.860161398256, 14391.28043597837, 12662.303573303188, 13519.864801351861, 13704.10923289983, 14021.228832414316, 14139.77234767086, 12869.906057985176, 13482.123661961941, 13581.253031083384, 13637.6775479691, 13828.585142445743, 9600.951995239979, 10044.0, 10303.845442318221, 10526.526306316387, 10627.734306642396, 11300.321980681185, 11553.191276610556, 11557.422128893353, 11571.13216508782, 11629.418529073682], "value": 7506.5374636540555, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1439.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 118.0, 115.0, 116.0, 117.0, 117.0, 117.0, 115.0, 116.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 111.0, 108.0, 144.0, 111.0, 109.0, 112.0, 109.0, 129.0, 108.0], "value": 118.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 181.0, 184.0, 183.0, 188.0, 185.0, 183.0, 184.0, 184.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 62.0, 62.0, 91.0, 65.0, 62.0, 61.0, 61.0, 77.0, 75.0], "value": 69.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 87.0, 74.0, 97.0, 98.0, 117.0, 69.0, 70.0, 85.0], "value": 85.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 96.0, 136.0, 87.0, 89.0, 87.0, 87.0, 88.0], "value": 93.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 94.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 94.0, 140.0, 137.0, 83.0, 83.0, 85.0, 83.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 65.0, 63.0, 63.0, 64.0, 63.0, 63.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 61.0, 67.0, 65.0, 61.0, 58.0, 59.0, 58.0, 58.0, 59.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 99.0, 101.0, 81.0, 85.0, 85.0, 82.0, 83.0, 85.0, 87.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 134.0, 136.0, 133.0, 137.0, 143.0, 151.0, 153.0, 178.0, 148.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 163.0, 165.0, 160.0, 164.0, 164.0, 164.0, 163.0, 162.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 66.0, 65.0, 64.0, 63.0, 63.0, 63.0, 65.0], "value": 64.6, "unit": "score"}]}]} 02:24:36 INFO - Return code: 0 02:24:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:24:36 ERROR - # TBPL SUCCESS # 02:24:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:24:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:24:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:24:36 INFO - 2017-04-25 02:24:36.129 screenresolution[1657:11240] starting screenresolution argv=screenresolution get 02:24:36 INFO - 2017-04-25 02:24:36.132 screenresolution[1657:11240] Display 0: 1600x1200x32@60 02:24:36 INFO - 2017-04-25 02:24:36.138 screenresolution[1658:11243] starting screenresolution argv=screenresolution list 02:24:36 INFO - Available Modes on Display 0 02:24:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:24:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:24:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:24:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:24:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:24:36 INFO - Intel Iris: 02:24:36 INFO - Chipset Model: Intel Iris 02:24:36 INFO - Type: GPU 02:24:36 INFO - Bus: Built-In 02:24:36 INFO - VRAM (Dynamic, Max): 1536 MB 02:24:36 INFO - Vendor: Intel (0x8086) 02:24:36 INFO - Device ID: 0x0a2e 02:24:36 INFO - Revision ID: 0x0009 02:24:36 INFO - Displays: 02:24:36 INFO - Display: 02:24:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:24:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:24:36 INFO - Display Serial Number: 1600x1200 60 02:24:36 INFO - Main Display: Yes 02:24:36 INFO - Mirror: Off 02:24:36 INFO - Online: Yes 02:24:36 INFO - Rotation: Supported 02:24:36 INFO - Return code: 0 02:24:36 INFO - Running post-action listener: _resource_record_post_action 02:24:36 INFO - [mozharness: 2017-04-25 09:24:36.377868Z] Finished run-tests step (success) 02:24:36 INFO - Running post-run listener: _resource_record_post_run 02:24:36 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32988160; Write bytes: 511397376; Read time: 198; Write time: 1580 02:24:36 INFO - TinderboxPrint: CPU usage
54.0% 02:24:36 INFO - TinderboxPrint: I/O read bytes / time
32,988,160 / 198 02:24:36 INFO - TinderboxPrint: I/O write bytes / time
511,397,376 / 1,580 02:24:36 INFO - TinderboxPrint: CPU idle
2,214.3 (46.0%) 02:24:36 INFO - TinderboxPrint: CPU system
956.3 (19.9%) 02:24:36 INFO - TinderboxPrint: CPU user
1,639.1 (34.1%) 02:24:36 INFO - TinderboxPrint: Swap in / out
237,547,520 / 0 02:24:36 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203899392; Write bytes: 199348224; Read time: 15661; Write time: 438 02:24:36 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 30972928; Write bytes: 301252096; Read time: 180; Write time: 1107 02:24:36 INFO - Running post-run listener: _upload_blobber_files 02:24:36 INFO - Blob upload gear active. 02:24:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:24:36 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 02:24:36 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:24:36 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:24:37 INFO - (blobuploader) - INFO - Open directory for files ... 02:24:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:24:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:24:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:38 INFO - (blobuploader) - INFO - Done attempting. 02:24:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:24:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:24:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:39 INFO - (blobuploader) - INFO - Done attempting. 02:24:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:24:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:24:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:40 INFO - (blobuploader) - INFO - Done attempting. 02:24:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:24:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:24:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:42 INFO - (blobuploader) - INFO - Done attempting. 02:24:42 INFO - (blobuploader) - INFO - Iteration through files over. 02:24:42 INFO - Return code: 0 02:24:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:24:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:24:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de91480b933b68548438fe3371daa2818320b060f425c7e11defe33d81c42a9027f3afba26f1d83ae4cf670e770f34bbfdbe30ff50b8ba0e0969125b278f74e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6c1ab6f84b6d161a43559c33a41099689f757c1f395d01756c3c966572a6fe9dc8a9d52f5c5d94147976c40ae67de074e56a7ad81cdc54fab6ce5915571acc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a05df567500a26b742554e59947c3079da651050187b6c125791b1b5a446a7bdcaa63f8df1522a174def3037eea6806275f7bfd76a569528bf479429c145bb6e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e442275b487eb99948ce89f7b74494494d91dc1d22db0064171a9af91cb1f6164ff2bf108fe1bd8fc8767234c83312af38581247a9512354d09fd3f99e60e812"} 02:24:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:24:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:24:42 INFO - Contents: 02:24:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de91480b933b68548438fe3371daa2818320b060f425c7e11defe33d81c42a9027f3afba26f1d83ae4cf670e770f34bbfdbe30ff50b8ba0e0969125b278f74e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6c1ab6f84b6d161a43559c33a41099689f757c1f395d01756c3c966572a6fe9dc8a9d52f5c5d94147976c40ae67de074e56a7ad81cdc54fab6ce5915571acc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a05df567500a26b742554e59947c3079da651050187b6c125791b1b5a446a7bdcaa63f8df1522a174def3037eea6806275f7bfd76a569528bf479429c145bb6e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e442275b487eb99948ce89f7b74494494d91dc1d22db0064171a9af91cb1f6164ff2bf108fe1bd8fc8767234c83312af38581247a9512354d09fd3f99e60e812"} 02:24:42 INFO - Running post-run listener: copy_logs_to_upload_dir 02:24:42 INFO - Copying logs to upload dir... 02:24:42 INFO - mkdir: /builds/slave/test/build/upload/logs 02:24:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1293.935156 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2017-04-25 02:24:42.493474) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-25 02:24:42.497267) ========= 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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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/Mozilla-Aurora/sha512/de91480b933b68548438fe3371daa2818320b060f425c7e11defe33d81c42a9027f3afba26f1d83ae4cf670e770f34bbfdbe30ff50b8ba0e0969125b278f74e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6c1ab6f84b6d161a43559c33a41099689f757c1f395d01756c3c966572a6fe9dc8a9d52f5c5d94147976c40ae67de074e56a7ad81cdc54fab6ce5915571acc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a05df567500a26b742554e59947c3079da651050187b6c125791b1b5a446a7bdcaa63f8df1522a174def3037eea6806275f7bfd76a569528bf479429c145bb6e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e442275b487eb99948ce89f7b74494494d91dc1d22db0064171a9af91cb1f6164ff2bf108fe1bd8fc8767234c83312af38581247a9512354d09fd3f99e60e812"} build_url:https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010175 build_url: 'https://queue.taskcluster.net/v1/task/V4ksDYyuQ9Sj3vNf9qPtmg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de91480b933b68548438fe3371daa2818320b060f425c7e11defe33d81c42a9027f3afba26f1d83ae4cf670e770f34bbfdbe30ff50b8ba0e0969125b278f74e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6c1ab6f84b6d161a43559c33a41099689f757c1f395d01756c3c966572a6fe9dc8a9d52f5c5d94147976c40ae67de074e56a7ad81cdc54fab6ce5915571acc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a05df567500a26b742554e59947c3079da651050187b6c125791b1b5a446a7bdcaa63f8df1522a174def3037eea6806275f7bfd76a569528bf479429c145bb6e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e442275b487eb99948ce89f7b74494494d91dc1d22db0064171a9af91cb1f6164ff2bf108fe1bd8fc8767234c83312af38581247a9512354d09fd3f99e60e812"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-25 02:24:42.532972) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 02:24:42.533317) ========= 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.Bkev6vApHh/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.6Gx6c8uFZT/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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.004137 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 02:24:42.581799) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-25 02:24:42.585305) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-25 02:24:42.585662) ========= ========= Total master_lag: 0.22 =========