builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0324 starttime: 1492275409.65 results: success (0) buildid: 20170415080306 builduid: 639dc5d28acf48b984b9186b5314f2b4 revision: 9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-15 09:56:49.649042) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-15 09:56:49.649501) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-15 09:56:49.649793) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-15 09:56:49.678720) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 09:56:49.679150) ========= 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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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-15 09:56:49-- 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% 171M=0s 2017-04-15 09:56:49 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.178589 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 09:56:49.905342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 09:56:49.905715) ========= 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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.042412 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 09:56:49.971347) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-15 09:56:49.971708) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e --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 9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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-15 09:56:50,071 truncating revision to first 12 chars 2017-04-15 09:56:50,071 Setting DEBUG logging. 2017-04-15 09:56:50,071 attempt 1/10 2017-04-15 09:56:50,071 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9da0d3c7fc50?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-15 09:56:51,197 unpacking tar archive at: mozilla-aurora-9da0d3c7fc50/testing/mozharness/ program finished with exit code 0 elapsedTime=1.405811 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-15 09:56:51.401495) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-15 09:56:51.401813) ========= script_repo_revision: 9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-15 09:56:51.402204) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-15 09:56:51.402470) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-15 09:56:51.432165) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-15 09:56:51.432550) ========= /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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:56:51 INFO - MultiFileLogger online at 20170415 09:56:51 in /builds/slave/test 09:56:51 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 09:56:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:56:51 INFO - {'append_to_log': False, 09:56:51 INFO - 'base_work_dir': '/builds/slave/test', 09:56:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:56:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:56:51 INFO - 'branch': 'Mozilla-Aurora', 09:56:51 INFO - 'buildbot_json_path': 'buildprops.json', 09:56:51 INFO - 'config_files': ('talos/mac_config.py',), 09:56:51 INFO - 'default_actions': ('clobber', 09:56:51 INFO - 'read-buildbot-config', 09:56:51 INFO - 'download-and-extract', 09:56:51 INFO - 'populate-webroot', 09:56:51 INFO - 'create-virtualenv', 09:56:51 INFO - 'install', 09:56:51 INFO - 'run-tests'), 09:56:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:56:51 INFO - 'download_minidump_stackwalk': True, 09:56:51 INFO - 'download_symbols': 'ondemand', 09:56:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:56:51 INFO - 'tooltool.py': '/tools/tooltool.py', 09:56:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:56:51 INFO - '/tools/misc-python/virtualenv.py')}, 09:56:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:56:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:56:51 INFO - 'gecko_profile': False, 09:56:51 INFO - 'gecko_profile_interval': 0, 09:56:51 INFO - 'installer_path': 'installer.exe', 09:56:51 INFO - 'log_level': 'info', 09:56:51 INFO - 'log_name': 'talos', 09:56:51 INFO - 'log_to_console': True, 09:56:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:56:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:56:51 INFO - 'opt_config_files': (), 09:56:51 INFO - 'pip_index': False, 09:56:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:56:51 INFO - 'cmd': ('bash', 09:56:51 INFO - '-c', 09:56:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:56:51 INFO - 'enabled': True, 09:56:51 INFO - 'halt_on_failure': False, 09:56:51 INFO - 'name': 'check_screen_resolution'},), 09:56:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:56:51 INFO - 'cmd': ('bash', 09:56:51 INFO - '-c', 09:56:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:56:51 INFO - 'enabled': True, 09:56:51 INFO - 'halt_on_failure': False, 09:56:51 INFO - 'name': 'check_screen_resolution'},), 09:56:51 INFO - 'run_cmd_checks_enabled': True, 09:56:51 INFO - 'suite': 'dromaeojs', 09:56:51 INFO - 'system_bits': '32', 09:56:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:56:51 INFO - 'title': 't-yosemite-r7-0324', 09:56:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:56:51 INFO - 'use_talos_json': True, 09:56:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:56:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:56:51 INFO - 'work_dir': 'build'} 09:56:51 INFO - [mozharness: 2017-04-15 16:56:51.639321Z] Running clobber step. 09:56:51 INFO - Running pre-action listener: _resource_record_pre_action 09:56:51 INFO - Running main action method: clobber 09:56:51 INFO - rmtree: /builds/slave/test/build 09:56:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:56:54 INFO - Running post-action listener: _resource_record_post_action 09:56:54 INFO - [mozharness: 2017-04-15 16:56:54.043308Z] Finished clobber step (success) 09:56:54 INFO - [mozharness: 2017-04-15 16:56:54.043437Z] Running read-buildbot-config step. 09:56:54 INFO - Running pre-action listener: _resource_record_pre_action 09:56:54 INFO - Running main action method: read_buildbot_config 09:56:54 INFO - Using buildbot properties: 09:56:54 INFO - { 09:56:54 INFO - "project": "", 09:56:54 INFO - "product": "firefox", 09:56:54 INFO - "script_repo_revision": "production", 09:56:54 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:56:54 INFO - "repository": "", 09:56:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:56:54 INFO - "buildid": "20170415080306", 09:56:54 INFO - "pgo_build": "False", 09:56:54 INFO - "basedir": "/builds/slave/test", 09:56:54 INFO - "buildnumber": 95, 09:56:54 INFO - "slavename": "t-yosemite-r7-0324", 09:56:54 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:56:54 INFO - "platform": "yosemite_r7", 09:56:54 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:56:54 INFO - "branch": "mozilla-aurora", 09:56:54 INFO - "repo_path": "releases/mozilla-aurora", 09:56:54 INFO - "revision": "9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e", 09:56:54 INFO - "stage_platform": "macosx64", 09:56:54 INFO - "builduid": "639dc5d28acf48b984b9186b5314f2b4", 09:56:54 INFO - "slavebuilddir": "test" 09:56:54 INFO - } 09:56:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 09:56:54 INFO - Running post-action listener: _resource_record_post_action 09:56:54 INFO - [mozharness: 2017-04-15 16:56:54.047094Z] Finished read-buildbot-config step (success) 09:56:54 INFO - [mozharness: 2017-04-15 16:56:54.047358Z] Running download-and-extract step. 09:56:54 INFO - Running pre-action listener: _resource_record_pre_action 09:56:54 INFO - Running main action method: download_and_extract 09:56:54 INFO - mkdir: /builds/slave/test/build/tests 09:56: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')]} 09:56:54 INFO - trying https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 09:56:54 INFO - Downloading https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/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 09:56:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/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 09:56:55 INFO - Downloaded 1199 bytes. 09:56:55 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:56:55 INFO - Using the following test package requirements: 09:56:55 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:56:55 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:56:55 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 09:56:55 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:56:55 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 09:56:55 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:56:55 INFO - u'jsshell-mac.zip'], 09:56:55 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:56:55 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 09:56:55 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:56:55 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:56:55 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 09:56:55 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:56:55 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 09:56:55 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:56:55 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 09:56:55 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:56:55 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 09:56:55 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:56:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:56:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:56:55 INFO - Fetch https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:56:56 INFO - Expected file size: 51795983 09:56:56 INFO - Obtained file size: 51795983 09:57:12 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 09:57:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:57:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:57:12 INFO - Fetch https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:57:14 INFO - Expected file size: 51795983 09:57:14 INFO - Obtained file size: 51795983 09:57:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 09:57:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:57:29 INFO - Fetch https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 09:57:30 INFO - Expected file size: 14404424 09:57:30 INFO - Obtained file size: 14404424 09:57:30 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')]} 09:57:30 INFO - trying https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:57:30 INFO - Downloading https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 09:57:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/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 09:57:32 INFO - Downloaded 74744277 bytes. 09:57:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:57:32 INFO - mkdir: /builds/slave/test/properties 09:57:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:57:32 INFO - Writing to file /builds/slave/test/properties/build_url 09:57:32 INFO - Contents: 09:57:32 INFO - build_url:https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:57:33 INFO - Running post-action listener: _resource_record_post_action 09:57:33 INFO - Running post-action listener: set_extra_try_arguments 09:57:33 INFO - [mozharness: 2017-04-15 16:57:33.114377Z] Finished download-and-extract step (success) 09:57:33 INFO - [mozharness: 2017-04-15 16:57:33.114539Z] Running populate-webroot step. 09:57:33 INFO - Running pre-action listener: _resource_record_pre_action 09:57:33 INFO - Running main action method: populate_webroot 09:57:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:57:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:57:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:57:33 INFO - '--tppagecycles', 09:57:33 INFO - '7'], 09:57:33 INFO - 'tests': ['tsvgm']}}, 09:57:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:57:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:57:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:57:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:57:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:57:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:57:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:57:33 INFO - 'talos_options': ['--disable-e10s'], 09:57:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:57:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:57:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:57:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:57:33 INFO - 'talos_options': ['--disable-e10s'], 09:57:33 INFO - 'tests': ['damp', 'tps']}, 09:57:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:57:33 INFO - 'tests': ['damp', 'tps']}, 09:57:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:57:33 INFO - 'tests': ['dromaeo_dom']}, 09:57:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:57:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:57:33 INFO - 'tests': ['basic_compositor_video']}, 09:57:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:57:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:57:33 INFO - 'tests': ['a11yr', 09:57:33 INFO - 'ts_paint', 09:57:33 INFO - 'tpaint', 09:57:33 INFO - 'sessionrestore', 09:57:33 INFO - 'sessionrestore_no_auto_restore', 09:57:33 INFO - 'tabpaint']}, 09:57:33 INFO - 'other-e10s': {'tests': ['a11yr', 09:57:33 INFO - 'ts_paint', 09:57:33 INFO - 'tpaint', 09:57:33 INFO - 'sessionrestore', 09:57:33 INFO - 'sessionrestore_no_auto_restore', 09:57:33 INFO - 'tabpaint']}, 09:57:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:57:33 INFO - 'ts_paint', 09:57:33 INFO - 'tpaint', 09:57:33 INFO - 'sessionrestore', 09:57:33 INFO - 'sessionrestore_no_auto_restore', 09:57:33 INFO - 'tabpaint']}, 09:57:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:57:33 INFO - 'ts_paint', 09:57:33 INFO - 'tpaint', 09:57:33 INFO - 'sessionrestore', 09:57:33 INFO - 'sessionrestore_no_auto_restore', 09:57:33 INFO - 'tabpaint']}, 09:57:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:57:33 INFO - 'tests': ['a11yr', 09:57:33 INFO - 'ts_paint', 09:57:33 INFO - 'tpaint', 09:57:33 INFO - 'sessionrestore', 09:57:33 INFO - 'sessionrestore_no_auto_restore', 09:57:33 INFO - 'tabpaint']}, 09:57:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:57:33 INFO - 'tests': ['a11yr', 09:57:33 INFO - 'ts_paint', 09:57:33 INFO - 'tpaint', 09:57:33 INFO - 'sessionrestore', 09:57:33 INFO - 'sessionrestore_no_auto_restore', 09:57:33 INFO - 'tabpaint']}, 09:57:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:57:33 INFO - 'tests': ['tsvgx', 09:57:33 INFO - 'tsvgr_opacity', 09:57:33 INFO - 'tart', 09:57:33 INFO - 'tscrollx', 09:57:33 INFO - 'cart', 09:57:33 INFO - 'tsvg_static']}, 09:57:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:57:33 INFO - 'tsvgr_opacity', 09:57:33 INFO - 'tart', 09:57:33 INFO - 'tscrollx', 09:57:33 INFO - 'cart', 09:57:33 INFO - 'tsvg_static']}, 09:57:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:57:33 INFO - 'talos_options': ['--disable-e10s'], 09:57:33 INFO - 'tests': ['tp5o']}, 09:57:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:57:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:57:33 INFO - 'talos_options': ['--disable-e10s', 09:57:33 INFO - '--xperf_path', 09:57:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:57:33 INFO - 'tests': ['tp5n']}, 09:57:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:57:33 INFO - 'talos_options': ['--xperf_path', 09:57:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:57:33 INFO - 'tests': ['tp5n']}}, 09:57:33 INFO - 'talos.zip': {'path': '', 09:57:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:57:33 INFO - Running post-action listener: _resource_record_post_action 09:57:33 INFO - [mozharness: 2017-04-15 16:57:33.132806Z] Finished populate-webroot step (success) 09:57:33 INFO - [mozharness: 2017-04-15 16:57:33.132936Z] Running create-virtualenv step. 09:57:33 INFO - Running pre-action listener: _resource_record_pre_action 09:57:33 INFO - Running main action method: create_virtualenv 09:57:33 INFO - Creating virtualenv /builds/slave/test/build/venv 09:57:33 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:57:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:57:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:57:33 INFO - Using real prefix '/tools/python27' 09:57:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:57:33 INFO - Installing distribute.............................................................................................................................................................................................done. 09:57:36 INFO - Installing pip.................done. 09:57:36 INFO - Return code: 0 09:57:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:57:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:57:37 INFO - Reading from file tmpfile_stdout 09:57:37 INFO - Output received: 09:57:37 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) 09:57:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:57:37 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')]} 09:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:37 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')]} 09:57:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:37 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 0x104c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc842c74350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a0ad30>, '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 0x104931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ffbe8>, '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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:57:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:57:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:57:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH2ZjVHP46/Render', 09:57:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:37 INFO - 'HOME': '/Users/cltbld', 09:57:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:37 INFO - 'LOGNAME': 'cltbld', 09:57:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:37 INFO - 'MOZ_NO_REMOTE': '1', 09:57:37 INFO - 'NO_EM_RESTART': '1', 09:57:37 INFO - 'PAGER': '/bin/cat', 09:57:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:37 INFO - 'PWD': '/builds/slave/test', 09:57:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:37 INFO - 'SHELL': '/bin/bash', 09:57:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8Eg9Sncx4/Listeners', 09:57:37 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 09:57:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:37 INFO - 'USER': 'cltbld', 09:57:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:37 INFO - 'XPC_FLAGS': '0x0', 09:57:37 INFO - 'XPC_SERVICE_NAME': '0', 09:57:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:57:37 INFO - Cleaning up... 09:57:37 INFO - Return code: 0 09:57:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:57:37 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')]} 09:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:37 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')]} 09:57:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:37 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 0x104c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc842c74350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a0ad30>, '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 0x104931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ffbe8>, '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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:57:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:57:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:57:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH2ZjVHP46/Render', 09:57:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:37 INFO - 'HOME': '/Users/cltbld', 09:57:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:37 INFO - 'LOGNAME': 'cltbld', 09:57:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:37 INFO - 'MOZ_NO_REMOTE': '1', 09:57:37 INFO - 'NO_EM_RESTART': '1', 09:57:37 INFO - 'PAGER': '/bin/cat', 09:57:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:37 INFO - 'PWD': '/builds/slave/test', 09:57:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:37 INFO - 'SHELL': '/bin/bash', 09:57:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8Eg9Sncx4/Listeners', 09:57:37 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 09:57:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:37 INFO - 'USER': 'cltbld', 09:57:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:37 INFO - 'XPC_FLAGS': '0x0', 09:57:37 INFO - 'XPC_SERVICE_NAME': '0', 09:57:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:37 INFO - Downloading/unpacking psutil>=3.1.1 09:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:57:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:57:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:57:41 INFO - Installing collected packages: psutil 09:57:41 INFO - Running setup.py install for psutil 09:57:41 INFO - building 'psutil._psutil_osx' extension 09:57:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:57:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:57:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:57:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:57:42 INFO - building 'psutil._psutil_posix' extension 09:57:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:57:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:57:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:57:42 INFO - ^ 09:57:42 INFO - 1 warning generated. 09:57:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:57:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:57:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:57:42 INFO - Successfully installed psutil 09:57:42 INFO - Cleaning up... 09:57:42 INFO - Return code: 0 09:57:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:57:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc842c74350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a0ad30>, '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 0x104931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ffbe8>, '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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:57:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:57:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:57:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH2ZjVHP46/Render', 09:57:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:42 INFO - 'HOME': '/Users/cltbld', 09:57:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:42 INFO - 'LOGNAME': 'cltbld', 09:57:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:42 INFO - 'MOZ_NO_REMOTE': '1', 09:57:42 INFO - 'NO_EM_RESTART': '1', 09:57:42 INFO - 'PAGER': '/bin/cat', 09:57:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:42 INFO - 'PWD': '/builds/slave/test', 09:57:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:42 INFO - 'SHELL': '/bin/bash', 09:57:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8Eg9Sncx4/Listeners', 09:57:42 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 09:57:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:42 INFO - 'USER': 'cltbld', 09:57:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:42 INFO - 'XPC_FLAGS': '0x0', 09:57:42 INFO - 'XPC_SERVICE_NAME': '0', 09:57:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:57:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:57:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:57:45 INFO - Installing collected packages: mozsystemmonitor 09:57:45 INFO - Running setup.py install for mozsystemmonitor 09:57:45 INFO - Successfully installed mozsystemmonitor 09:57:45 INFO - Cleaning up... 09:57:46 INFO - Return code: 0 09:57:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:57:46 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')]} 09:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:46 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')]} 09:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:46 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 0x104c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc842c74350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a0ad30>, '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 0x104931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ffbe8>, '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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:57:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:57:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:57:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH2ZjVHP46/Render', 09:57:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:46 INFO - 'HOME': '/Users/cltbld', 09:57:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:46 INFO - 'LOGNAME': 'cltbld', 09:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:46 INFO - 'MOZ_NO_REMOTE': '1', 09:57:46 INFO - 'NO_EM_RESTART': '1', 09:57:46 INFO - 'PAGER': '/bin/cat', 09:57:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:46 INFO - 'PWD': '/builds/slave/test', 09:57:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:46 INFO - 'SHELL': '/bin/bash', 09:57:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8Eg9Sncx4/Listeners', 09:57:46 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 09:57:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:46 INFO - 'USER': 'cltbld', 09:57:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:46 INFO - 'XPC_FLAGS': '0x0', 09:57:46 INFO - 'XPC_SERVICE_NAME': '0', 09:57:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:46 INFO - Downloading/unpacking jsonschema==2.5.1 09:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:57:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:49 INFO - Downloading functools32-3.2.3-2.tar.gz 09:57:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:57:49 INFO - warning: no files found matching '*.txt' 09:57:49 INFO - no previously-included directories found matching 'build' 09:57:49 INFO - no previously-included directories found matching 'dist' 09:57:49 INFO - no previously-included directories found matching '.git*' 09:57:49 INFO - Installing collected packages: jsonschema, functools32 09:57:49 INFO - Running setup.py install for functools32 09:57:49 INFO - warning: no files found matching '*.txt' 09:57:49 INFO - no previously-included directories found matching 'build' 09:57:49 INFO - no previously-included directories found matching 'dist' 09:57:49 INFO - no previously-included directories found matching '.git*' 09:57:49 INFO - Successfully installed jsonschema functools32 09:57:49 INFO - Cleaning up... 09:57:49 INFO - Return code: 0 09:57:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc842c74350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a0ad30>, '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 0x104931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ffbe8>, '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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:57:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:57:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:57:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH2ZjVHP46/Render', 09:57:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:49 INFO - 'HOME': '/Users/cltbld', 09:57:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:49 INFO - 'LOGNAME': 'cltbld', 09:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:49 INFO - 'MOZ_NO_REMOTE': '1', 09:57:49 INFO - 'NO_EM_RESTART': '1', 09:57:49 INFO - 'PAGER': '/bin/cat', 09:57:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:49 INFO - 'PWD': '/builds/slave/test', 09:57:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:49 INFO - 'SHELL': '/bin/bash', 09:57:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8Eg9Sncx4/Listeners', 09:57:49 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 09:57:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:49 INFO - 'USER': 'cltbld', 09:57:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:49 INFO - 'XPC_FLAGS': '0x0', 09:57:49 INFO - 'XPC_SERVICE_NAME': '0', 09:57:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:57:49 INFO - Cleaning up... 09:57:50 INFO - Return code: 0 09:57:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:57:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:50 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 0x104c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc842c74350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a0ad30>, '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 0x104931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ffbe8>, '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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:57:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:57:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:57:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH2ZjVHP46/Render', 09:57:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:50 INFO - 'HOME': '/Users/cltbld', 09:57:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:50 INFO - 'LOGNAME': 'cltbld', 09:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:50 INFO - 'MOZ_NO_REMOTE': '1', 09:57:50 INFO - 'NO_EM_RESTART': '1', 09:57:50 INFO - 'PAGER': '/bin/cat', 09:57:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:50 INFO - 'PWD': '/builds/slave/test', 09:57:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:50 INFO - 'SHELL': '/bin/bash', 09:57:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8Eg9Sncx4/Listeners', 09:57:50 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 09:57:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:50 INFO - 'USER': 'cltbld', 09:57:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:50 INFO - 'XPC_FLAGS': '0x0', 09:57:50 INFO - 'XPC_SERVICE_NAME': '0', 09:57:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:50 INFO - Downloading/unpacking blobuploader==1.2.4 09:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:52 INFO - Downloading blobuploader-1.2.4.tar.gz 09:57:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:57:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:57:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:54 INFO - Downloading docopt-0.6.1.tar.gz 09:57:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:57:54 INFO - Installing collected packages: blobuploader, requests, docopt 09:57:54 INFO - Running setup.py install for blobuploader 09:57:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:57:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:57:54 INFO - Running setup.py install for requests 09:57:54 INFO - Running setup.py install for docopt 09:57:55 INFO - Successfully installed blobuploader requests docopt 09:57:55 INFO - Cleaning up... 09:57:55 INFO - Return code: 0 09:57:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:55 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 0x104c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc842c74350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a0ad30>, '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 0x104931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ffbe8>, '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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:57:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:57:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH2ZjVHP46/Render', 09:57:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:55 INFO - 'HOME': '/Users/cltbld', 09:57:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:55 INFO - 'LOGNAME': 'cltbld', 09:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:55 INFO - 'MOZ_NO_REMOTE': '1', 09:57:55 INFO - 'NO_EM_RESTART': '1', 09:57:55 INFO - 'PAGER': '/bin/cat', 09:57:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:55 INFO - 'PWD': '/builds/slave/test', 09:57:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:55 INFO - 'SHELL': '/bin/bash', 09:57:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8Eg9Sncx4/Listeners', 09:57:55 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 09:57:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:55 INFO - 'USER': 'cltbld', 09:57:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:55 INFO - 'XPC_FLAGS': '0x0', 09:57:55 INFO - 'XPC_SERVICE_NAME': '0', 09:57:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:57:55 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-06pZOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:57:55 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-B2bLa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:57:55 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-rvU_pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:57:55 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-di1u7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:57:55 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-FaOsEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:57:55 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-WEHtS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:57:55 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-i1ihjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:57:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-FVsmoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:57:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-LwoHdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:57:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-TJ_46G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:57:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-m3ViTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:57:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-njRE3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:57:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-DZC3LY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:57:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Vwbq0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:57:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-87luEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:57:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-IwmAwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:57:57 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-dxLe89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:57:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:57:57 INFO - Running setup.py install for manifestparser 09:57:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:57:57 INFO - Running setup.py install for mozcrash 09:57:57 INFO - Running setup.py install for mozdebug 09:57:57 INFO - Running setup.py install for mozdevice 09:57:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:57:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:57:57 INFO - Running setup.py install for mozfile 09:57:57 INFO - Running setup.py install for mozhttpd 09:57:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:57:58 INFO - Running setup.py install for mozinfo 09:57:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:57:58 INFO - Running setup.py install for mozInstall 09:57:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:57:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:57:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:57:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:57:58 INFO - Running setup.py install for mozleak 09:57:58 INFO - Running setup.py install for mozlog 09:57:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:57:58 INFO - Running setup.py install for moznetwork 09:57:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:57:58 INFO - Running setup.py install for mozprocess 09:57:58 INFO - Running setup.py install for mozprofile 09:57:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:57:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:57:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:57:59 INFO - Running setup.py install for mozrunner 09:57:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:57:59 INFO - Running setup.py install for mozscreenshot 09:57:59 INFO - Running setup.py install for moztest 09:57:59 INFO - Running setup.py install for mozversion 09:57:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:57:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:57:59 INFO - Cleaning up... 09:57:59 INFO - Return code: 0 09:57:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:57:59 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')]} 09:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:59 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')]} 09:57:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:59 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 0x104c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc842c74350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a0ad30>, '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 0x104931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ffbe8>, '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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:57:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:57:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:57:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH2ZjVHP46/Render', 09:57:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:59 INFO - 'HOME': '/Users/cltbld', 09:57:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:59 INFO - 'LOGNAME': 'cltbld', 09:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:59 INFO - 'MOZ_NO_REMOTE': '1', 09:57:59 INFO - 'NO_EM_RESTART': '1', 09:57:59 INFO - 'PAGER': '/bin/cat', 09:57:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:59 INFO - 'PWD': '/builds/slave/test', 09:57:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:59 INFO - 'SHELL': '/bin/bash', 09:57:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8Eg9Sncx4/Listeners', 09:57:59 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 09:57:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:59 INFO - 'USER': 'cltbld', 09:57:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:59 INFO - 'XPC_FLAGS': '0x0', 09:57:59 INFO - 'XPC_SERVICE_NAME': '0', 09:57:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-KSvARz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-gLIimd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:00 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)) 09:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-NOcx7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-bvePr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:00 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)) 09:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-4DJyvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-N58kIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-0waRRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Q8veI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-6fBgv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-J3vVO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:01 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)) 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-MqTpUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-VKreUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:01 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)) 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-MIivBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-2CDiTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:01 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)) 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-d9PGfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Wv9H3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:01 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)) 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-asfcEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:58:01 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)) 09:58:01 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)) 09:58:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:58:01 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)) 09:58:01 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)) 09:58:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:04 INFO - Downloading blessings-1.6.tar.gz 09:58:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:58:04 INFO - Installing collected packages: blessings 09:58:04 INFO - Running setup.py install for blessings 09:58:04 INFO - Successfully installed blessings 09:58:04 INFO - Cleaning up... 09:58:04 INFO - Return code: 0 09:58:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:58:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:05 INFO - Reading from file tmpfile_stdout 09:58:05 INFO - Current package versions: 09:58:05 INFO - blessings == 1.6 09:58:05 INFO - blobuploader == 1.2.4 09:58:05 INFO - docopt == 0.6.1 09:58:05 INFO - functools32 == 3.2.3-2 09:58:05 INFO - jsonschema == 2.5.1 09:58:05 INFO - manifestparser == 1.1 09:58:05 INFO - mozInstall == 1.12 09:58:05 INFO - mozcrash == 1.0 09:58:05 INFO - mozdebug == 0.1 09:58:05 INFO - mozdevice == 0.49 09:58:05 INFO - mozfile == 1.2 09:58:05 INFO - mozhttpd == 0.7 09:58:05 INFO - mozinfo == 0.9 09:58:05 INFO - mozleak == 0.1 09:58:05 INFO - mozlog == 3.4 09:58:05 INFO - moznetwork == 0.27 09:58:05 INFO - mozprocess == 0.25 09:58:05 INFO - mozprofile == 0.28 09:58:05 INFO - mozrunner == 6.13 09:58:05 INFO - mozscreenshot == 0.1 09:58:05 INFO - mozsystemmonitor == 0.3 09:58:05 INFO - moztest == 0.8 09:58:05 INFO - mozversion == 1.4 09:58:05 INFO - psutil == 3.1.1 09:58:05 INFO - requests == 1.2.3 09:58:05 INFO - wsgiref == 0.1.2 09:58:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:05 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 0x104c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc842c74350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a0ad30>, '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 0x104931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ffbe8>, '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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:58:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:58:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:58:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH2ZjVHP46/Render', 09:58:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:05 INFO - 'HOME': '/Users/cltbld', 09:58:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:05 INFO - 'LOGNAME': 'cltbld', 09:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:05 INFO - 'MOZ_NO_REMOTE': '1', 09:58:05 INFO - 'NO_EM_RESTART': '1', 09:58:05 INFO - 'PAGER': '/bin/cat', 09:58:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:05 INFO - 'PWD': '/builds/slave/test', 09:58:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:05 INFO - 'SHELL': '/bin/bash', 09:58:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8Eg9Sncx4/Listeners', 09:58:05 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 09:58:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:05 INFO - 'USER': 'cltbld', 09:58:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:05 INFO - 'XPC_FLAGS': '0x0', 09:58:05 INFO - 'XPC_SERVICE_NAME': '0', 09:58:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:58:05 INFO - Cleaning up... 09:58:05 INFO - Return code: 0 09:58:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:58:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c2cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c2f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc842c74350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a0ad30>, '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 0x104931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ffbe8>, '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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:58:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:58:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:58:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH2ZjVHP46/Render', 09:58:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:05 INFO - 'HOME': '/Users/cltbld', 09:58:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:05 INFO - 'LOGNAME': 'cltbld', 09:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:05 INFO - 'MOZ_NO_REMOTE': '1', 09:58:05 INFO - 'NO_EM_RESTART': '1', 09:58:05 INFO - 'PAGER': '/bin/cat', 09:58:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:05 INFO - 'PWD': '/builds/slave/test', 09:58:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:05 INFO - 'SHELL': '/bin/bash', 09:58:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8Eg9Sncx4/Listeners', 09:58:05 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 09:58:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:05 INFO - 'USER': 'cltbld', 09:58:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:05 INFO - 'XPC_FLAGS': '0x0', 09:58:05 INFO - 'XPC_SERVICE_NAME': '0', 09:58:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:58:05 INFO - Cleaning up... 09:58:05 INFO - Return code: 0 09:58:05 INFO - Running post-action listener: _resource_record_post_action 09:58:05 INFO - Running post-action listener: _start_resource_monitoring 09:58:05 INFO - Starting resource monitoring. 09:58:05 INFO - [mozharness: 2017-04-15 16:58:05.748087Z] Finished create-virtualenv step (success) 09:58:05 INFO - [mozharness: 2017-04-15 16:58:05.749053Z] Running install step. 09:58:05 INFO - Running pre-action listener: _resource_record_pre_action 09:58:05 INFO - Running main action method: install 09:58:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:06 INFO - Reading from file tmpfile_stdout 09:58:06 INFO - Detecting whether we're running mozinstall >=1.0... 09:58:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:58:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:58:06 INFO - Reading from file tmpfile_stdout 09:58:06 INFO - Output received: 09:58:06 INFO - Usage: mozinstall [options] installer 09:58:06 INFO - Options: 09:58:06 INFO - -h, --help show this help message and exit 09:58:06 INFO - -d DEST, --destination=DEST 09:58:06 INFO - Directory to install application into. [default: 09:58:06 INFO - "/builds/slave/test"] 09:58:06 INFO - --app=APP Application being installed. [default: firefox] 09:58:06 INFO - mkdir: /builds/slave/test/build/application 09:58:06 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'] 09:58:06 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 09:58:26 INFO - Reading from file tmpfile_stdout 09:58:26 INFO - Output received: 09:58:26 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:58:26 INFO - Running post-action listener: _resource_record_post_action 09:58:26 INFO - [mozharness: 2017-04-15 16:58:26.155412Z] Finished install step (success) 09:58:26 INFO - [mozharness: 2017-04-15 16:58:26.155580Z] Running run-tests step. 09:58:26 INFO - Running pre-action listener: _resource_record_pre_action 09:58:26 INFO - Running main action method: run_tests 09:58:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:58:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:58:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:58:26 INFO - 2017-04-15 09:58:26.170 screenresolution[1642:12183] starting screenresolution argv=screenresolution get 09:58:26 INFO - 2017-04-15 09:58:26.172 screenresolution[1642:12183] Display 0: 1600x1200x32@60 09:58:26 INFO - 2017-04-15 09:58:26.178 screenresolution[1643:12186] starting screenresolution argv=screenresolution list 09:58:26 INFO - Available Modes on Display 0 09:58:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:58:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:58:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:58:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:58:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:58:26 INFO - Intel Iris: 09:58:26 INFO - Chipset Model: Intel Iris 09:58:26 INFO - Type: GPU 09:58:26 INFO - Bus: Built-In 09:58:26 INFO - VRAM (Dynamic, Max): 1536 MB 09:58:26 INFO - Vendor: Intel (0x8086) 09:58:26 INFO - Device ID: 0x0a2e 09:58:26 INFO - Revision ID: 0x0009 09:58:26 INFO - Displays: 09:58:26 INFO - Display: 09:58:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:58:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:58:26 INFO - Display Serial Number: 1600x1200 60 09:58:26 INFO - Main Display: Yes 09:58:26 INFO - Mirror: Off 09:58:26 INFO - Online: Yes 09:58:26 INFO - Rotation: Supported 09:58:26 INFO - Return code: 0 09:58:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:58:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:58:26 INFO - Python 2.7.3 09:58:26 INFO - Return code: 0 09:58:26 INFO - grabbing minidump binary from tooltool 09:58: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')]} 09:58:26 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 0x104a0ad30>, '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 0x104931db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049ffbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:58:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:58:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:58:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:58:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:58:26 INFO - Return code: 0 09:58:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:58:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:58:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:58:26 INFO - ENV: RUST_BACKTRACE is now 1 09:58:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:58:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:58:26 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/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:58:26 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/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0324 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:58:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH2ZjVHP46/Render', 09:58:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:26 INFO - 'HOME': '/Users/cltbld', 09:58:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:26 INFO - 'LOGNAME': 'cltbld', 09:58:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:58:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:26 INFO - 'MOZ_NO_REMOTE': '1', 09:58:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:58:26 INFO - 'NO_EM_RESTART': '1', 09:58:26 INFO - 'PAGER': '/bin/cat', 09:58:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:26 INFO - 'PWD': '/builds/slave/test', 09:58:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:58:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:26 INFO - 'RUST_BACKTRACE': '1', 09:58:26 INFO - 'SHELL': '/bin/bash', 09:58:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b8Eg9Sncx4/Listeners', 09:58:26 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 09:58:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:26 INFO - 'USER': 'cltbld', 09:58:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:26 INFO - 'XPC_FLAGS': '0x0', 09:58:26 INFO - 'XPC_SERVICE_NAME': '0', 09:58:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:26 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/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:58:26 INFO - mozversion application_buildid: 20170415080306 09:58:26 INFO - mozversion application_changeset: 9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e 09:58:26 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:58:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:58:26 INFO - mozversion application_name: Firefox 09:58:26 INFO - mozversion application_remotingname: firefox-dev 09:58:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:58:26 INFO - mozversion application_vendor: Mozilla 09:58:26 INFO - mozversion application_version: 54.0a2 09:58:26 INFO - mozversion platform_buildid: 20170415080306 09:58:26 INFO - mozversion platform_changeset: 9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e 09:58:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:58:26 INFO - mozversion platform_version: 54.0a2 09:58:26 INFO - using testdate: 1492275506 09:58:26 INFO - actual date: 1492275506 09:58:26 INFO - starting webserver on 'localhost:49249' 09:58:26 INFO - SUITE-START | Running 2 tests 09:58:26 INFO - TEST-START | dromaeo_css 09:58:26 INFO - operating with platform_type : mac_ 09:58:26 INFO - Initialising browser for dromaeo_css test... 09:58:26 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpw2dDGk/profile http://localhost:49249/getInfo.html) 09:58:29 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 09:58:29 INFO - PROCESS | 1653 | colorDepth:24 09:58:29 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 09:58:29 INFO - PROCESS | 1653 | __metrics 09:58:29 INFO - PROCESS | 1653 | 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" 09:58:29 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:58:29 INFO - TEST-INFO | 1653: exit 0 09:58:29 INFO - Browser initialized. 09:58:29 INFO - Running cycle 1/1 for dromaeo_css test... 09:58:29 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpw2dDGk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:58:42 INFO - PROCESS | 1657 | RSS: Main: 210509824 09:58:42 INFO - PROCESS | 1657 | 10:01:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 10:01:47 INFO - PROCESS | 1657 | RSS: Main: 342581248 10:01:47 INFO - PROCESS | 1657 | 10:03:31 INFO - PROCESS | 1657 | ************************* 10:03:31 INFO - PROCESS | 1657 | A coding exception was thrown and uncaught in a Task. 10:03:31 INFO - PROCESS | 1657 | 10:03:31 ERROR - PROCESS | 1657 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:03:31 INFO - PROCESS | 1657 | Full stack: 10:03:31 INFO - PROCESS | 1657 | ************************* 10:03:31 INFO - PROCESS | 1657 | ************************* 10:03:31 INFO - PROCESS | 1657 | A coding exception was thrown and uncaught in a Task. 10:03:31 INFO - PROCESS | 1657 | 10:03:31 ERROR - PROCESS | 1657 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:03:31 INFO - PROCESS | 1657 | Full stack: 10:03:31 INFO - PROCESS | 1657 | ************************* 10:03:31 INFO - PROCESS | 1657 | ************************* 10:03:31 INFO - PROCESS | 1657 | A coding exception was thrown and uncaught in a Task. 10:03:31 INFO - PROCESS | 1657 | 10:03:31 ERROR - PROCESS | 1657 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:03:31 INFO - PROCESS | 1657 | Full stack: 10:03:31 INFO - PROCESS | 1657 | ************************* 10:03:31 INFO - PROCESS | 1657 | ************************* 10:03:31 INFO - PROCESS | 1657 | A coding exception was thrown and uncaught in a Task. 10:03:31 INFO - PROCESS | 1657 | 10:03:31 ERROR - PROCESS | 1657 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:03:31 INFO - PROCESS | 1657 | Full stack: 10:03:31 INFO - PROCESS | 1657 | ************************* 10:04:50 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 10:04:51 INFO - PROCESS | 1657 | RSS: Main: 350162944 10:04:51 INFO - PROCESS | 1657 | 10:07:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 10:07:54 INFO - PROCESS | 1657 | RSS: Main: 337022976 10:07:54 INFO - PROCESS | 1657 | 10:10:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 10:10:58 INFO - PROCESS | 1657 | RSS: Main: 316182528 10:10:58 INFO - PROCESS | 1657 | 10:14:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 10:14:02 INFO - PROCESS | 1657 | RSS: Main: 327065600 10:14:02 INFO - PROCESS | 1657 | 10:17:05 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 10:17:05 INFO - PROCESS | 1657 | RSS: Main: 333639680 10:17:05 INFO - PROCESS | 1657 | 10:17:05 INFO - PROCESS | 1657 | __start_tp_report 10:17:05 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 10:17:05 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 10:17:05 INFO - PROCESS | 1657 | |i|pagename|runs| 10:17:05 INFO - PROCESS | 1657 | |0;dojo.html;2847.106674061749;3390.5761779777526;3396.8980930572093;3410.300888317895;3431.914202144946;5902.734376953037;5939.821805345847;5947.2268605081335;5957.851053723659;5981.102834574811;4930.506949305068;4936.531029552196;5939.960506911295;6007.4593286604195;6048;5310.8406747797535;5324.600654950894;5351.518363347298;5393.703346315951;5426.511613954744;5223.660462069954;5239.030779305841;5451.836444906639;5453.263809385741;5477.643953143033;5269.209618557228;5301;5378.381486129087;5407.567394608422;5474.288342515468;5400.567954563626;5406.297181366417;5416.783328666848;5428.75570599324;5447.809326673587;5232.162853943389;5329.7601607927345;5333.146696528537;5335.0663633863915;5375.3280839895015;5674.233978412903;5701.230333904953;5705.54355651551;5949.8810023799715;5950.285965684102;6207.81376558704;6270.937290627081;6333.683315834236;6344.206974128234;6356.713947872357;6321.146645202884;6391.58454700443;6395.104685344056;6407.519436042269;6410.903836442457;5836.241288632451;6037.969810150921;6063.393660633946;6101.359357267512;6114.357992410733;6283.59156654816;6288.025356069817;6294.0558916162945;6308.369163083655;6348.619082854951;5247.895042099137;6271.811845644638;6292.528060395489;6310.0534919761485;6310.684465776692;6285.497160227171;6344.238691357066;6345.777897773556;6349.873002539924;6365.013422919363;6191.84520386994;6240.095186197976;6282.905756413657;6309.053641953652;6313.936860631335;6192.442680158717;6255.71849266775;6271.717772700148;6297.905531417033;6442.6778661066755;5098.949010509921;5102.413222479462;5102.744862756922;5104.770285337168;5131.538161565477;4430.357598148251;4473.642108631302;4574.5425457453985;4577.450705915245;4586.72479651222;5857.209276747584;5861.384554621789;5984.820455386345;6019.307779605401;6074.696265186723;6374.426301632875;6382.808515744535;6391.58454700443;6394.552381333355;6395.584287021329;6016.458518733335;6021.759129634853;6027.517798576191;6046.425589569072;6049;5475.890482190334;5482.945170548323;5574.665520068809;5575.553955683536;5652.463016013472;6307.211598550181;6357.459615932605;6558.672066396565;6584.4732421405215;6690.83272918181;6404.039394090923;6405.743770249138;6407.135036769976;6417.294097649348;6441.098246245623;6676.799696009128;6683.264840867598;6688.933110669026;6690.19717633887;6696;6180.81457556274;6222.16000839899;6238;6301.180846489927;6322.083297921868;6210.099535567408;6210.378962103934;6303.086052522451;6358.554901156875;6361.395667411589;6145.617236121837;6199;6237.438630523275;6250.468710159687;6297.086922396318;6276.968615156895;6278.121063051268;6287.654179020198;6288.717007734572;6299.401556852092;5989.281286245504;5993.280806303097;6018.699065046818;6044.516438684983;6047.848803779941;6157.784477543264;6219.036049412348;6238.812835614939;6272.4982001440685;6287.811365659038;6214.782482613087;6214.782482613087;6227.972384556679;6229.345918678655;6235.282942461675 10:17:05 INFO - PROCESS | 1657 | |1;ext.html;784.3215618490007;812.9024517057954;858.8840506531616;883.7834730491022;885.8006948436603;32155.87454439095;39645.207095858066;41912.37131443027;41944.790276048654;42076;39875.401868971916;39894.8005259974;39897;39982.200355992936;40023.59976400235;7323.414126869864;9176.036516165823;9304.81390372192;9330.580123894408;9335.626574936994;7408.629568521579;10953.890461095407;11130.49912753924;11322.886771132265;11363.318200907972;10318.484075796217;10483.741950965858;11138.888611113865;11172.050375718032;11174.385408802513;9166.954165229197;11093.667189984313;11125;11158;11159.553617855276;10730.892691073068;10911.563537458493;10912.563497460093;10931.508082136323;10943.398113103776;38018.23963520714;38227.23545529102;38257.043573910596;38347.424788628196;38481.42277865834;3245.8701651933893;3837.4435706822633;3843.3466310727244;3877.418387241936;3901.804909754501;26601.46797064048;26744.331391715365;26753.86623066892;28439;28524.714752852415;3056.3276079262528;3176.142441540794;3279.4261004324385;3307.751918606114;3312.3706495765728;350416;350908;350917.490825091;351551;352221;41564.3765343514;42227.73316800501;51914.48085519097;52015.21977170346;52074.73962630163;45122.097558048656;51532.22701659478;52372.73813630984;52413.47586524086;52602.210966835526;14540.781888271684;14612.707745845024;14616.561503154922;14623.341949612293;14686.045407048508;12701.42843572023;12743.36283185837;12747.36263186837;12748;12784.744305113845;11897.464614092387;11991.520339186334;11999.220050696676;12034.338111403957;12071.517139314328;5236.895262094813;5292.44429334922;5295.008320299019;5303.469653034743;5307.717681396959;1102.151343465543;1104.7414307894321;1124.656979621207;1148.1331594646156;1185.4902391971534;25932;26196.8690156548;26303.34241643936;26353.868230658725;26446.735532644812;1028.6708253358854;1220.4995951659778;1225.779359715242;1254.7678679444332;1279.539365828301;13330.400131994083;13364;13663.931680341733;13683.015962685555;13721.245331506663;418524;422651;424700;425248.87375562923;425535;55088;55255.44744552583;55336;55373.446265537634;55486.72256638772;1392.6031081141825;1442.6393401649589;1442.783582462639;1445.5085271007488;1449.427476146916;24818.627720583845;25012.124575639766;25391.6191257135;25441.87279063667;25468.617970730083;45260.773696131306;45261.77369113133;45269.868253293935;45312.09375812399;45384.31923521084;405907;406913;407111;409141.90858091036;410322;32552.186195345304;33063;33136;33167.66832331646;33181.17047073746;41383;41854.162916742105;42310.57689423066;42432.15135697331;42577.787111064245;27712;28847.5672864903;29067.563986539797;29615.555766663085;29767.851160744056;23137.884310578338;28801.855990719912;29206.853965730035;29830.552541712324;29850;34159.14602134967;34367.48448773321;35094.9471515855;35131.64868351284;35231.82384088063;27052.458950821267;28195.872165113986;29048.854755726086;29463.852680736458;29678.406431871674;22039.66940495862;22448.214312498985;22586.435339116655;22630.321090367317;23079.6538051926 10:17:05 INFO - PROCESS | 1657 | |2;jquery.html;373.5461414381526;385.366072810227;395.3338624417858;408.9795510224488;413.87169977307036;13224.140430872;13367;15865.682686346267;20915.895420522877;21008;21395.037223324947;21414.357569272906;21512.89243553784;21794.564108717816;21913.780862191372;6330.841728956779;6663.133792606955;6674.56615320005;7472.850542989137;7839.294463498284;7699.692012319529;7917.524948503079;7957.442978991473;7979.640916158787;7994.200579942018;7922.128565857751;7979.760607181794;7998.880016799752;8003.839923201533;8008.51948883066;7151.213366529703;7490.850182996364;7670.463067585271;7676.193999630014;7979.042514898191;7487.1764105948305;7491.438142139307;7530.8117297067465;7831.882521762178;7913.683452661887;29143.271418214714;29204.123876283746;29260.414791704046;29268.41463170725;29441.705582944112;2945.3225758075764;2949.6136815696805;3010.005213634901;3032.7877048606606;3033.893813716509;15944.043357398594;16109.516714498584;16358.754618680729;17002.659946800995;17056.48830535086;1863.138251409509;1864.3567969050655;1880.9341673041379;1904.561950751321;1929.5465565592074;90412;96000;111978;136534.6346536532;173183.13408432878;22847;23698.76301237;23760.52478950446;24079.638805417933;24158.637620435708;41146.794266028475;41843.58156418458;42274.788626056674;42331.7883410581;42348.15303693909;10082.546285417175;10563.313384630128;11186.832197517044;11701.00541453969;11756.94121529387;9135.771605709777;9154.954225228832;9154.99295077541;9313.34806563534;9337.953310233406;10930.344179349264;11021.338719676845;11042.779144417227;11161.888381116247;11209.55161793519;3806.849130241405;3822;4364.75993820343;4497.550244975476;4532.660050496226;857.7255278311005;1033.121846430528;1049.7690508088208;1055.9788804223872;1065.6856227412932;20878.686819697716;21264.574708506054;21316.14735410597;21382.57234855294;21537.284286786366;1039.163473403902;1043.2697112021463;1056.1603525197486;1083.9620613278498;1094.0864378244096;10955.397453139994;11172.832407513893;11181.608643697431;11190.94404527972;11251.874812518683;716424;754721;765578;778537;800796.9920300723;157825;158418;158659;160660.59009114874;166177.33822661618;1158.5870464741508;1182.586094866807;1205.1323047405854;1207.7825991321647;1212.7938250497614;17619.471415857544;18323.63352732911;18502;18550.628987419906;18551.44345669632;60831.695841522276;61362.38637613567;61793.691031546354;62726.37273627205;63125.421864453754;87594.68607971013;88399;89137.21725565582;90165;90597.54701226452;9867.753306167404;9978.501074946078;10263.84604230952;11412.600558980395;11569.479373428048;12298.385080745747;12363.072769542141;12411.813822792485;12436.19164754288;12506.749865002466;10611.522481488506;10725.731856703645;10756.354618722902;10832.404217768099;10933.234673572773;8551.358648101294;10034.548445319831;10219.642312519323;10273.229507787095;10340.638077667245;10766.838497422701;11001.559937602406;11036.282641628268;11089.778204436028;11144.996950274513;11166.944165279121;11535.596254131066;11607.825882611936;11613.709657258636;11624;10586.8411973822;10689.358638481715;10900.454977251264;10901;11011 10:17:05 INFO - PROCESS | 1657 | |3;mootools.html;1361.1900918953222;1415.5045733993104;1512.9924350378246;1527.3737767515322;1553.6970290793292;10431.113355364794;11008.669739907795;11017.669469915905;11020.118390528758;11050.115990720744;10330.845037324447;10740;10881.401522916236;10889.128869690425;10914.290571112871;2323.8140948724104;2375.608024675935;2376.1327115602767;2381.404648837791;2382.059086660768;2288.565172617206;2366.2309749331534;2367.4081479630095;2376.4058985253687;2377.5839228135005;2295.2310975823034;2356.3284463927785;2364.893579788905;2367.7277113132036;2380.1074597026113;2318.9072437102495;2319.3505818370713;2344.0975224538506;2352.447174913894;2356.5051339218785;2336.4158960259933;2346.8826558672167;2368.609179485383;2369.0997420979916;2371.5256948610345;10734.946325268402;10933.234673572853;10949.562017519289;10957.561697532168;10970.232083754141;5434.076207044812;5599.300087489064;5604.327480702301;5609.214709940612;5630.915536266959;3407.9659203407896;3468.254325320043;3487.668671476206;3516.9472457913153;3539.7699149555197;1101.251149218531;1109.1902910875026;1130.8416821644976;1174.5360582569922;1180.8523934508187;13347.466101355934;13645.931770341085;14009.859901400958;14995.550133496012;15006.549803505803;4426.84506042287;4563.885902852388;4583.633309335245;4587.770611469413;4618.769061546909;11493.482793274323;11506.654800355835;11507.827382589268;11543;11566.710832229093;4631.282151266559;4771.642126840501;4790.688605240648;4792.856214313576;4793.18515852306;4294.613484786384;4937.530934561211;5227.973860130675;5245.134552798745;5247.02929957959;3678.1724112075103;3725.0873535983314;3792.791396473165;3795.1650636859845;3809.42858571211;956.8947415784259;968.3221744778683;980.5146452506056;980.5342462330336;981.249344251648;775.8331958628809;829.0755807274845;913.3150137396952;919.8804155459882;922.686286662538;6135.141080248769;6151.169592105009;6399.9040014399825;6419.197600299963;6453.870922581616;968.3608818180109;977.2914636888199;983.537737263485;986.4574484033752;994.3139233928566;3343.2310568569364;3549;3927.3323534999117;3979.263836190314;4141.192467468875;13447.260400678057;13993.3702983366;14043.297835108204;14047.227402492757;14051.227182505061;13906.30468476572;13926.234057126752;13945.093568917988;13952.58142255734;13967;9843.75390615226;9930.652427165014;10148.289619726549;10155.593776249016;10174.186065114773;6147.231596050494;6161.135072191273;6164.537659675452;6286.402791734778;6428.742850285937;11229.71925701864;12046.457909393923;12263.141580089665;12379.62861114168;12669.429875655433;10288.2798204128;12204.877951220375;12888.42002109926;12935.094543381872;12964.481420743441;8946.731598052069;8956.149165829236;9023;9033.50315732641;9097.681581144629;9900.158486528726;9965;10057.29598928068;10090.69727908164;10108.090271875566;8990.145936136056;9158.40470369424;9195.126462986005;9217.723468295962;9350.438973661601;9014.41406308614;9036;9107.180353768194;9117.08829117083;9222.40054396462;7788.337991270579;7859.371250299848;7867.645955932111;7904.960475197588;7971.322437592869;5184.860208192013;5602.215689803512;5764.077747560538;5813.680247586423;6003.939960600338;5727.226824378656;5836.153757705194;5912.320083190488;6008.969955150196;6047.183630209865 10:17:05 INFO - PROCESS | 1657 | |4;prototype.html;327.1657273951423;331.7528441311223;360.8159838482373;372.66646351515396;374.1021548284116;4147.543770185277;4428.977855110724;5112.642115051948;5133.358330208724;5141.331626888504;4146.150039241956;4666.953330466695;4701.600363969058;5002.374703162105;5005.524475174852;4240.600601801413;4364.847230346939;4371.912561748763;4421.491528474218;4486.439195100613;4353.586409291112;4363.54182810805;4404.876756427107;4431;4456.866294011184;3945.6326204277348;4273.995611031405;4441.977790111045;4445.199864024475;4459.55404459553;3998.1204135090234;4098.8975275618195;4368.934465983013;4406.647468202536;4512.16524942885;3796.0889386547306;3906.8437262509465;4061.3501839705505;4232.899446143994;4237.156836775982;4149.668026557898;4946.5795407390415;4968.3292755477905;4972.201825238662;5051.262066071117;4413.470383553994;4720.834770783041;4857.688424125467;5154.329937108152;5156.396020009871;4865.708057516525;4962.0324036812835;5352.197170424429;5434.22321486567;5445.700486473243;4026.8187931542857;4388.100439409914;4598.448186217642;4715.3634259374885;4800.919218664142;5338.27933229013;5421.1326187810155;5575.386707462217;5725.08398656213;5868.735906884201;3976.5625781164626;4298.699091063658;4343.739375637409;4525;4612.60792832613;4687.015726697432;4697.4832768395445;4702.249184227197;4706.552877476634;4720.197566413718;4634.745089020069;4647.629708848512;4670.813167473263;4929.975350123226;4953.380827396575;4251.766152861623;4862.325720853587;5166.844994650167;5393.379761327497;5457.918131228034;1663.3180396037567;1681.3022595622924;1700.600358915654;1709.6837085139289;1710.4954038558656;1274.3246079577839;1283.6790802299424;1358.3751474321923;1381.2679279981626;1402.3549167383119;4253.404523366731;4547.477040140427;4677.170311961282;4991.401031876198;4997.200447928314;4926;5299.973500132552;5553.4450353900975;5602.495775380154;5770.672745268528;4367.235733746582;4434.8912771807045;5029.471905449948;5060.949390506048;5079.1365467870555;2709.6722605922955;2786.874590643426;2845.5162622353837;2892.7107289271325;2917.460269850042;5288.704267454498;5769.336526299529;5776.393478684678;5785.8553536161935;5884.941150588439;4979.879527106372;5058.392992840809;5358.383785864676;5500.7524661390335;5696.231008813757;5172.508611681884;5757.769689212385;5768.394318596487;5817.50551203144;5851.444112809277;5485.807996720096;5532.518659885505;5598;5610.971945140412;5636.801647357616;4993.650444468853;5042.319286896222;5436.401995780382;5510.641808282448;5511.889762204813;5182;5227.947720522746;5750.453706897838;5761.971190144022;5788.421157884346;4479.484859241229;4567.794449249725;4603.110371735877;4845.878853028703;4845.9757701211265;4590.317875766633;5376.784928602812;5519.586031047767;5534.501894829485;5541.5289700375915;4616.838410655611;4689.296605509201;4743.573078422958;5151.583314588368;5200.4279529251;5022.799088036584;5053.974730126326;5369.785208591613;5390.4879036491475;5434.701091440009;4631.675782695179;5079.4697685108495;5169.922451163311;5220.694826293427;5238;4759.214729569582;4773.832915847928;4827.648258487629;5224.764885580082;5421.906460622205;4824.068384266951;5065.670731402447;5180.948190518046;5231.607629427885;5249.186376111708 10:17:05 INFO - PROCESS | 1657 | |5;yui.html;742.3912391838691;745;745.4744405194339;813.4549851599428;827.2430725885815;13797.620237976198;14646.34091465884;15338.69322613547;15768.369265229405;15768.92115539422;14011.579652610413;14380.424783008668;14406.495772647959;14433.566992990227;15057.77413338798;941.3410612571193;956.5743244256308;957.3824882950493;958.3722661656626;960.433344326847;911.1662828511937;934.8737920380729;949.4113649537296;949.7008442340641;950.7242899559119;1010.3028910052077;1011.1102230037557;1012.5595366015807;1012.8278192707221;1015.7003683913264;1004.9749255759122;1006.5621454667244;1007.1539906478594;1007.3804610164769;1007.9344842585208;751.2074761127009;751.3012898004853;752.6011214056559;755.467395486181;758.3440324119651;15374.231288435534;15631.45289914859;15648.374065037386;16142.435014774544;16236.43172488969;13719.588412347646;13879.028468007244;14098.295085245696;14106.647333816634;14138;11770.352630605314;11807.881921180764;11931.582394616233;11948.522059117626;12140.907318341304;3488.9651103488895;3622.655847694491;3696.4085746280734;3705.74059815813;3725.6833169180654;104320.47839760753;105713.94286057117;107375.46312268467;108612;108782.3682644753;10460.163186945027;10578.471076446302;10612.681619551426;10652.467376631292;10930.836037459445;21066.368008959755;21080.472988175112;21088.05103770334;21093.26173583948;21097.789022109584;7359.558426494427;7364.631768411558;7429.554226746304;7559.433042521723;7639.732609358646;4187.120704651997;4223.113146239324;4260.978695106504;4348.130373925228;4456.866294011184;3430.3139372125675;3463.6882680558365;3517.3317069756663;3524.665156810099;3526.594441639193;533.3919331961567;634.0932466572846;638.9233292004989;649.5290914087249;650.313918815654;662.8442316055723;693.1370443797505;694.0664805836142;698.4307789151825;704.3766266853769;8514.808145723675;10193.286469947181;10231.232657550565;10254.6410875619;10410;685.8902575587882;708.7873637908607;708.9012031351488;713.3515634288485;726.4478995963107;4781.928271075936;4800.887055286109;4806.158922188603;4840.115772221678;4858.44127925286;96125.51937240269;98503;98517.5222371665;99249.00750992398;100398.4980075095;73959;87867.60661966876;89973.55013224891;90629.09370906338;91857.7035574116;75381;76182;76351.61824190844;78256.21743782604;78298.82551761615;9524.380915240487;9574.904250957401;9577.808443831223;9584.952075239578;9622.759431014281;53377;53564.196537052754;53834;54500.45499544954;54813.903721924544;66432.6678366605;67303.66348168228;68143.31856681568;68633.65683171753;69894;11164.776704465703;11360.886391135982;11964.92315691592;12189.207701499374;12433.502659893504;14088.436462541384;14478.565643030726;14699.706005879609;15218.239088045775;15572.844271557593;12882.227066376048;13563;13900.02699811044;13926.373313201133;14225.861931045698;12615.180013299107;13877.722445551235;13897.374618142007;13898.722025559635;14231.146131232159;12816.743665126833;13742.831859292071;13758.93120534391;14242.643933901736;14349.497767578085;9142.451452912846;9229.400088994194;9585.760355991157;9621.422714637145;9756.902430975599;8124.15654520913;9695.612175513183;10229.392713758954;10447.9910193173;10450.843237351293 10:17:05 INFO - PROCESS | 1657 | __end_tp_report 10:17:05 INFO - PROCESS | 1657 | __start_cc_report 10:17:05 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1871 10:17:05 INFO - PROCESS | 1657 | __end_cc_report 10:17:05 INFO - PROCESS | 1657 | __startTimestamp1492276625685__endTimestamp 10:17:05 INFO - PROCESS | 1657 | 10:17:05 INFO - PROCESS | 1657 | ------- Summary: start ------- 10:17:05 INFO - PROCESS | 1657 | Number of tests: 6 10:17:05 INFO - PROCESS | 1657 | 10:17:05 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5883.55 Median:6157.78 stddev:674.46 (11.0%) stddev-sans-first:633.07 10:17:05 INFO - PROCESS | 1657 | Values: 2847.1 3390.6 3396.9 3410.3 3431.9 5902.7 5939.8 5947.2 5957.9 5981.1 4930.5 4936.5 5940.0 6007.5 6048.0 5310.8 5324.6 5351.5 5393.7 5426.5 5223.7 5239.0 5451.8 5453.3 5477.6 5269.2 5301.0 5378.4 5407.6 5474.3 5400.6 5406.3 5416.8 5428.8 5447.8 5232.2 5329.8 5333.1 5335.1 5375.3 5674.2 5701.2 5705.5 5949.9 5950.3 6207.8 6270.9 6333.7 6344.2 6356.7 6321.1 6391.6 6395.1 6407.5 6410.9 5836.2 6038.0 6063.4 6101.4 6114.4 6283.6 6288.0 6294.1 6308.4 6348.6 5247.9 6271.8 6292.5 6310.1 6310.7 6285.5 6344.2 6345.8 6349.9 6365.0 6191.8 6240.1 6282.9 6309.1 6313.9 6192.4 6255.7 6271.7 6297.9 6442.7 5098.9 5102.4 5102.7 5104.8 5131.5 4430.4 4473.6 4574.5 4577.5 4586.7 5857.2 5861.4 5984.8 6019.3 6074.7 6374.4 6382.8 6391.6 6394.6 6395.6 6016.5 6021.8 6027.5 6046.4 6049.0 5475.9 5482.9 5574.7 5575.6 5652.5 6307.2 6357.5 6558.7 6584.5 6690.8 6404.0 6405.7 6407.1 6417.3 6441.1 6676.8 6683.3 6688.9 6690.2 6696.0 6180.8 6222.2 6238.0 6301.2 6322.1 6210.1 6210.4 6303.1 6358.6 6361.4 6145.6 6199.0 6237.4 6250.5 6297.1 6277.0 6278.1 6287.7 6288.7 6299.4 5989.3 5993.3 6018.7 6044.5 6047.8 6157.8 6219.0 6238.8 6272.5 6287.8 6214.8 6214.8 6228.0 6229.3 6235.3 10:17:05 INFO - PROCESS | 1657 | 10:17:05 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:53287.69 Median:17700.87 stddev:104613.96 (591.0%) stddev-sans-first:104833.16 10:17:05 INFO - PROCESS | 1657 | Values: 784.3 812.9 858.9 883.8 885.8 32155.9 39645.2 41912.4 41944.8 42076.0 39875.4 39894.8 39897.0 39982.2 40023.6 7323.4 9176.0 9304.8 9330.6 9335.6 7408.6 10953.9 11130.5 11322.9 11363.3 10318.5 10483.7 11138.9 11172.1 11174.4 9167.0 11093.7 11125.0 11158.0 11159.6 10730.9 10911.6 10912.6 10931.5 10943.4 38018.2 38227.2 38257.0 38347.4 38481.4 3245.9 3837.4 3843.3 3877.4 3901.8 26601.5 26744.3 26753.9 28439.0 28524.7 3056.3 3176.1 3279.4 3307.8 3312.4 350416.0 350908.0 350917.5 351551.0 352221.0 41564.4 42227.7 51914.5 52015.2 52074.7 45122.1 51532.2 52372.7 52413.5 52602.2 14540.8 14612.7 14616.6 14623.3 14686.0 12701.4 12743.4 12747.4 12748.0 12784.7 11897.5 11991.5 11999.2 12034.3 12071.5 5236.9 5292.4 5295.0 5303.5 5307.7 1102.2 1104.7 1124.7 1148.1 1185.5 25932.0 26196.9 26303.3 26353.9 26446.7 1028.7 1220.5 1225.8 1254.8 1279.5 13330.4 13364.0 13663.9 13683.0 13721.2 418524.0 422651.0 424700.0 425248.9 425535.0 55088.0 55255.4 55336.0 55373.4 55486.7 1392.6 1442.6 1442.8 1445.5 1449.4 24818.6 25012.1 25391.6 25441.9 25468.6 45260.8 45261.8 45269.9 45312.1 45384.3 405907.0 406913.0 407111.0 409141.9 410322.0 32552.2 33063.0 33136.0 33167.7 33181.2 41383.0 41854.2 42310.6 42432.2 42577.8 27712.0 28847.6 29067.6 29615.6 29767.9 23137.9 28801.9 29206.9 29830.6 29850.0 34159.1 34367.5 35094.9 35131.6 35231.8 27052.5 28195.9 29048.9 29463.9 29678.4 22039.7 22448.2 22586.4 22630.3 23079.7 10:17:05 INFO - PROCESS | 1657 | 10:17:05 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:43229.17 Median:21290.36 stddev:126829.21 (595.7%) stddev-sans-first:127144.17 10:17:05 INFO - PROCESS | 1657 | Values: 373.5 385.4 395.3 409.0 413.9 13224.1 13367.0 15865.7 20915.9 21008.0 21395.0 21414.4 21512.9 21794.6 21913.8 6330.8 6663.1 6674.6 7472.9 7839.3 7699.7 7917.5 7957.4 7979.6 7994.2 7922.1 7979.8 7998.9 8003.8 8008.5 7151.2 7490.9 7670.5 7676.2 7979.0 7487.2 7491.4 7530.8 7831.9 7913.7 29143.3 29204.1 29260.4 29268.4 29441.7 2945.3 2949.6 3010.0 3032.8 3033.9 15944.0 16109.5 16358.8 17002.7 17056.5 1863.1 1864.4 1880.9 1904.6 1929.5 90412.0 96000.0 111978.0 136534.6 173183.1 22847.0 23698.8 23760.5 24079.6 24158.6 41146.8 41843.6 42274.8 42331.8 42348.2 10082.5 10563.3 11186.8 11701.0 11756.9 9135.8 9155.0 9155.0 9313.3 9338.0 10930.3 11021.3 11042.8 11161.9 11209.6 3806.8 3822.0 4364.8 4497.6 4532.7 857.7 1033.1 1049.8 1056.0 1065.7 20878.7 21264.6 21316.1 21382.6 21537.3 1039.2 1043.3 1056.2 1084.0 1094.1 10955.4 11172.8 11181.6 11190.9 11251.9 716424.0 754721.0 765578.0 778537.0 800797.0 157825.0 158418.0 158659.0 160660.6 166177.3 1158.6 1182.6 1205.1 1207.8 1212.8 17619.5 18323.6 18502.0 18550.6 18551.4 60831.7 61362.4 61793.7 62726.4 63125.4 87594.7 88399.0 89137.2 90165.0 90597.5 9867.8 9978.5 10263.8 11412.6 11569.5 12298.4 12363.1 12411.8 12436.2 12506.7 10611.5 10725.7 10756.4 10832.4 10933.2 8551.4 10034.5 10219.6 10273.2 10340.6 10766.8 11001.6 11036.3 11089.8 11145.0 11166.9 11535.6 11607.8 11613.7 11624.0 10586.8 10689.4 10900.5 10901.0 11011.0 10:17:05 INFO - PROCESS | 1657 | 10:17:05 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:6611.67 Median:3528.36 stddev:4205.69 (119.2%) stddev-sans-first:4198.98 10:17:05 INFO - PROCESS | 1657 | Values: 1361.2 1415.5 1513.0 1527.4 1553.7 10431.1 11008.7 11017.7 11020.1 11050.1 10330.8 10740.0 10881.4 10889.1 10914.3 2323.8 2375.6 2376.1 2381.4 2382.1 2288.6 2366.2 2367.4 2376.4 2377.6 2295.2 2356.3 2364.9 2367.7 2380.1 2318.9 2319.4 2344.1 2352.4 2356.5 2336.4 2346.9 2368.6 2369.1 2371.5 10734.9 10933.2 10949.6 10957.6 10970.2 5434.1 5599.3 5604.3 5609.2 5630.9 3408.0 3468.3 3487.7 3516.9 3539.8 1101.3 1109.2 1130.8 1174.5 1180.9 13347.5 13645.9 14009.9 14995.6 15006.5 4426.8 4563.9 4583.6 4587.8 4618.8 11493.5 11506.7 11507.8 11543.0 11566.7 4631.3 4771.6 4790.7 4792.9 4793.2 4294.6 4937.5 5228.0 5245.1 5247.0 3678.2 3725.1 3792.8 3795.2 3809.4 956.9 968.3 980.5 980.5 981.2 775.8 829.1 913.3 919.9 922.7 6135.1 6151.2 6399.9 6419.2 6453.9 968.4 977.3 983.5 986.5 994.3 3343.2 3549.0 3927.3 3979.3 4141.2 13447.3 13993.4 14043.3 14047.2 14051.2 13906.3 13926.2 13945.1 13952.6 13967.0 9843.8 9930.7 10148.3 10155.6 10174.2 6147.2 6161.1 6164.5 6286.4 6428.7 11229.7 12046.5 12263.1 12379.6 12669.4 10288.3 12204.9 12888.4 12935.1 12964.5 8946.7 8956.1 9023.0 9033.5 9097.7 9900.2 9965.0 10057.3 10090.7 10108.1 8990.1 9158.4 9195.1 9217.7 9350.4 9014.4 9036.0 9107.2 9117.1 9222.4 7788.3 7859.4 7867.6 7905.0 7971.3 5184.9 5602.2 5764.1 5813.7 6003.9 5727.2 5836.2 5912.3 6009.0 6047.2 10:17:05 INFO - PROCESS | 1657 | 10:17:05 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4563.35 Median:4851.83 stddev:1207.36 (24.9%) stddev-sans-first:1168.13 10:17:05 INFO - PROCESS | 1657 | Values: 327.2 331.8 360.8 372.7 374.1 4147.5 4429.0 5112.6 5133.4 5141.3 4146.2 4667.0 4701.6 5002.4 5005.5 4240.6 4364.8 4371.9 4421.5 4486.4 4353.6 4363.5 4404.9 4431.0 4456.9 3945.6 4274.0 4442.0 4445.2 4459.6 3998.1 4098.9 4368.9 4406.6 4512.2 3796.1 3906.8 4061.4 4232.9 4237.2 4149.7 4946.6 4968.3 4972.2 5051.3 4413.5 4720.8 4857.7 5154.3 5156.4 4865.7 4962.0 5352.2 5434.2 5445.7 4026.8 4388.1 4598.4 4715.4 4800.9 5338.3 5421.1 5575.4 5725.1 5868.7 3976.6 4298.7 4343.7 4525.0 4612.6 4687.0 4697.5 4702.2 4706.6 4720.2 4634.7 4647.6 4670.8 4930.0 4953.4 4251.8 4862.3 5166.8 5393.4 5457.9 1663.3 1681.3 1700.6 1709.7 1710.5 1274.3 1283.7 1358.4 1381.3 1402.4 4253.4 4547.5 4677.2 4991.4 4997.2 4926.0 5300.0 5553.4 5602.5 5770.7 4367.2 4434.9 5029.5 5060.9 5079.1 2709.7 2786.9 2845.5 2892.7 2917.5 5288.7 5769.3 5776.4 5785.9 5884.9 4979.9 5058.4 5358.4 5500.8 5696.2 5172.5 5757.8 5768.4 5817.5 5851.4 5485.8 5532.5 5598.0 5611.0 5636.8 4993.7 5042.3 5436.4 5510.6 5511.9 5182.0 5227.9 5750.5 5762.0 5788.4 4479.5 4567.8 4603.1 4845.9 4846.0 4590.3 5376.8 5519.6 5534.5 5541.5 4616.8 4689.3 4743.6 5151.6 5200.4 5022.8 5054.0 5369.8 5390.5 5434.7 4631.7 5079.5 5169.9 5220.7 5238.0 4759.2 4773.8 4827.6 5224.8 5421.9 4824.1 5065.7 5180.9 5231.6 5249.2 10:17:05 INFO - PROCESS | 1657 | 10:17:05 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:20336.10 Median:3701.07 stddev:29112.63 (786.6%) stddev-sans-first:29157.12 10:17:05 INFO - PROCESS | 1657 | Values: 742.4 745.0 745.5 813.5 827.2 13797.6 14646.3 15338.7 15768.4 15768.9 14011.6 14380.4 14406.5 14433.6 15057.8 941.3 956.6 957.4 958.4 960.4 911.2 934.9 949.4 949.7 950.7 1010.3 1011.1 1012.6 1012.8 1015.7 1005.0 1006.6 1007.2 1007.4 1007.9 751.2 751.3 752.6 755.5 758.3 15374.2 15631.5 15648.4 16142.4 16236.4 13719.6 13879.0 14098.3 14106.6 14138.0 11770.4 11807.9 11931.6 11948.5 12140.9 3489.0 3622.7 3696.4 3705.7 3725.7 104320.5 105713.9 107375.5 108612.0 108782.4 10460.2 10578.5 10612.7 10652.5 10930.8 21066.4 21080.5 21088.1 21093.3 21097.8 7359.6 7364.6 7429.6 7559.4 7639.7 4187.1 4223.1 4261.0 4348.1 4456.9 3430.3 3463.7 3517.3 3524.7 3526.6 533.4 634.1 638.9 649.5 650.3 662.8 693.1 694.1 698.4 704.4 8514.8 10193.3 10231.2 10254.6 10410.0 685.9 708.8 708.9 713.4 726.4 4781.9 4800.9 4806.2 4840.1 4858.4 96125.5 98503.0 98517.5 99249.0 100398.5 73959.0 87867.6 89973.6 90629.1 91857.7 75381.0 76182.0 76351.6 78256.2 78298.8 9524.4 9574.9 9577.8 9585.0 9622.8 53377.0 53564.2 53834.0 54500.5 54813.9 66432.7 67303.7 68143.3 68633.7 69894.0 11164.8 11360.9 11964.9 12189.2 12433.5 14088.4 14478.6 14699.7 15218.2 15572.8 12882.2 13563.0 13900.0 13926.4 14225.9 12615.2 13877.7 13897.4 13898.7 14231.1 12816.7 13742.8 13758.9 14242.6 14349.5 9142.5 9229.4 9585.8 9621.4 9756.9 8124.2 9695.6 10229.4 10448.0 10450.8 10:17:05 INFO - PROCESS | 1657 | -------- Summary: end -------- 10:17:05 INFO - PROCESS | 1657 | 10:17:06 INFO - TEST-INFO | 1657: exit 0 10:17:06 INFO - TEST-OK | dromaeo_css | took 1119619ms 10:17:06 INFO - TEST-START | kraken 10:17:06 INFO - operating with platform_type : mac_ 10:17:06 INFO - Initialising browser for kraken test... 10:17:06 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmp6K88zs/profile http://localhost:49249/getInfo.html) 10:17:08 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 10:17:08 INFO - PROCESS | 1664 | colorDepth:24 10:17:08 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 10:17:08 INFO - PROCESS | 1664 | __metrics 10:17:08 INFO - PROCESS | 1664 | 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" 10:17:08 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:17:09 INFO - TEST-INFO | 1664: exit 0 10:17:09 INFO - Browser initialized. 10:17:09 INFO - Running cycle 1/1 for kraken test... 10:17:09 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmp6K88zs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:17:21 INFO - PROCESS | 1665 | RSS: Main: 215093248 10:17:21 INFO - PROCESS | 1665 | 10:17:24 INFO - PROCESS | 1665 | 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) 10:17:25 INFO - PROCESS | 1665 | RSS: Main: 267984896 10:17:25 INFO - PROCESS | 1665 | 10:17:28 INFO - PROCESS | 1665 | 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) 10:17:28 INFO - PROCESS | 1665 | RSS: Main: 279334912 10:17:28 INFO - PROCESS | 1665 | 10:17:31 INFO - PROCESS | 1665 | 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) 10:17:32 INFO - PROCESS | 1665 | RSS: Main: 375623680 10:17:32 INFO - PROCESS | 1665 | 10:17:34 INFO - PROCESS | 1665 | 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) 10:17:35 INFO - PROCESS | 1665 | RSS: Main: 279138304 10:17:35 INFO - PROCESS | 1665 | 10:17:37 INFO - PROCESS | 1665 | 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) 10:17:37 INFO - PROCESS | 1665 | RSS: Main: 281100288 10:17:37 INFO - PROCESS | 1665 | 10:17:40 INFO - PROCESS | 1665 | 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) 10:17:41 INFO - PROCESS | 1665 | RSS: Main: 320851968 10:17:41 INFO - PROCESS | 1665 | 10:17:44 INFO - PROCESS | 1665 | 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) 10:17:44 INFO - PROCESS | 1665 | RSS: Main: 323313664 10:17:44 INFO - PROCESS | 1665 | 10:17:47 INFO - PROCESS | 1665 | 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) 10:17:48 INFO - PROCESS | 1665 | RSS: Main: 319463424 10:17:48 INFO - PROCESS | 1665 | 10:17:50 INFO - PROCESS | 1665 | 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) 10:17:50 INFO - PROCESS | 1665 | RSS: Main: 295133184 10:17:50 INFO - PROCESS | 1665 | 10:17:52 INFO - PROCESS | 1665 | 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) 10:17:53 INFO - PROCESS | 1665 | RSS: Main: 298553344 10:17:53 INFO - PROCESS | 1665 | 10:17:55 INFO - PROCESS | 1665 | 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) 10:17:56 INFO - PROCESS | 1665 | RSS: Main: 302161920 10:17:56 INFO - PROCESS | 1665 | 10:17:59 INFO - PROCESS | 1665 | 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) 10:17:59 INFO - PROCESS | 1665 | RSS: Main: 319463424 10:17:59 INFO - PROCESS | 1665 | 10:18:02 INFO - PROCESS | 1665 | 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) 10:18:03 INFO - PROCESS | 1665 | RSS: Main: 312004608 10:18:03 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | 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) 10:18:05 INFO - PROCESS | 1665 | RSS: Main: 356036608 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | __start_tp_report 10:18:05 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 10:18:05 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 10:18:05 INFO - PROCESS | 1665 | |i|pagename|runs| 10:18:05 INFO - PROCESS | 1665 | |0;ai-astar;118;117;116;116;115;117;116;115;116;116 10:18:05 INFO - PROCESS | 1665 | |1;audio-beat-detection;166;134;135;161;112;111;110;107;129;110 10:18:05 INFO - PROCESS | 1665 | |2;audio-dft;186;186;183;184;188;183;185;181;185;183 10:18:05 INFO - PROCESS | 1665 | |3;audio-fft;81;60;61;92;63;61;60;60;77;61 10:18:05 INFO - PROCESS | 1665 | |4;audio-oscillator;89;71;87;70;68;69;83;69;69;104 10:18:05 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;87;87;86;87;87;88;88;87;88;86 10:18:05 INFO - PROCESS | 1665 | |6;imaging-darkroom;95;95;97;96;95;94;95;95;94;94 10:18:05 INFO - PROCESS | 1665 | |7;imaging-desaturate;106;139;128;86;85;84;83;82;83;84 10:18:05 INFO - PROCESS | 1665 | |8;json-parse-financial;64;63;61;62;62;63;63;62;63;65 10:18:05 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;55;66;69;63;64;80;81;84;56;59 10:18:05 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;85;85;83;83;83;73;88;105;100;84 10:18:05 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;145;139;152;135;136;139;140;132;192;174 10:18:05 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;167;168;211;208;171;168;164;166;163;164 10:18:05 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;68;62;57;66;76;82;82;81;67;64 10:18:05 INFO - PROCESS | 1665 | __end_tp_report 10:18:05 INFO - PROCESS | 1665 | __start_cc_report 10:18:05 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2099 10:18:05 INFO - PROCESS | 1665 | __end_cc_report 10:18:05 INFO - PROCESS | 1665 | __startTimestamp1492276685538__endTimestamp 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | ------- Summary: start ------- 10:18:05 INFO - PROCESS | 1665 | Number of tests: 14 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:116.20 Median:116.00 stddev:0.92 (0.8%) stddev-sans-first:0.71 10:18:05 INFO - PROCESS | 1665 | Values: 118.0 117.0 116.0 116.0 115.0 117.0 116.0 115.0 116.0 116.0 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:131.50 stddev:21.73 (16.5%) stddev-sans-first:18.04 10:18:05 INFO - PROCESS | 1665 | Values: 166.0 134.0 135.0 161.0 112.0 111.0 110.0 107.0 129.0 110.0 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:2.01 (1.1%) stddev-sans-first:2.05 10:18:05 INFO - PROCESS | 1665 | Values: 186.0 186.0 183.0 184.0 188.0 183.0 185.0 181.0 185.0 183.0 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:67.60 Median:62.00 stddev:11.49 (18.5%) stddev-sans-first:11.12 10:18:05 INFO - PROCESS | 1665 | Values: 81.0 60.0 61.0 92.0 63.0 61.0 60.0 60.0 77.0 61.0 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:70.50 stddev:12.29 (17.4%) stddev-sans-first:12.36 10:18:05 INFO - PROCESS | 1665 | Values: 89.0 71.0 87.0 70.0 68.0 69.0 83.0 69.0 69.0 104.0 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:87.10 Median:87.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 10:18:05 INFO - PROCESS | 1665 | Values: 87.0 87.0 86.0 87.0 87.0 88.0 88.0 87.0 88.0 86.0 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:0.94 (1.0%) stddev-sans-first:1.00 10:18:05 INFO - PROCESS | 1665 | Values: 95.0 95.0 97.0 96.0 95.0 94.0 95.0 95.0 94.0 94.0 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:83.50 stddev:21.12 (25.3%) stddev-sans-first:22.09 10:18:05 INFO - PROCESS | 1665 | Values: 106.0 139.0 128.0 86.0 85.0 84.0 83.0 82.0 83.0 84.0 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:63.00 stddev:1.14 (1.8%) stddev-sans-first:1.12 10:18:05 INFO - PROCESS | 1665 | Values: 64.0 63.0 61.0 62.0 62.0 63.0 63.0 62.0 63.0 65.0 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:67.50 stddev:10.58 (15.7%) stddev-sans-first:10.18 10:18:05 INFO - PROCESS | 1665 | Values: 55.0 66.0 69.0 63.0 64.0 80.0 81.0 84.0 56.0 59.0 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:83.50 stddev:9.16 (11.0%) stddev-sans-first:9.69 10:18:05 INFO - PROCESS | 1665 | Values: 85.0 85.0 83.0 83.0 83.0 73.0 88.0 105.0 100.0 84.0 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:148.40 Median:142.50 stddev:19.52 (13.7%) stddev-sans-first:20.67 10:18:05 INFO - PROCESS | 1665 | Values: 145.0 139.0 152.0 135.0 136.0 139.0 140.0 132.0 192.0 174.0 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.00 Median:168.00 stddev:18.35 (10.9%) stddev-sans-first:19.23 10:18:05 INFO - PROCESS | 1665 | Values: 167.0 168.0 211.0 208.0 171.0 168.0 164.0 166.0 163.0 164.0 10:18:05 INFO - PROCESS | 1665 | 10:18:05 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:72.00 stddev:9.07 (12.6%) stddev-sans-first:9.58 10:18:05 INFO - PROCESS | 1665 | Values: 68.0 62.0 57.0 66.0 76.0 82.0 82.0 81.0 67.0 64.0 10:18:05 INFO - PROCESS | 1665 | -------- Summary: end -------- 10:18:05 INFO - PROCESS | 1665 | 10:18:06 INFO - TEST-INFO | 1665: exit 0 10:18:06 INFO - TEST-OK | kraken | took 60093ms 10:18:06 INFO - SUITE-END | took 1179s 10:18:06 INFO - Completed test suite (00:19:39) 10:18:06 INFO - javascript benchmark 10:18:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7668.671541496423, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2847.106674061749, 3390.5761779777526, 3396.8980930572093, 3410.300888317895, 3431.914202144946, 5902.734376953037, 5939.821805345847, 5947.2268605081335, 5957.851053723659, 5981.102834574811, 4930.506949305068, 4936.531029552196, 5939.960506911295, 6007.4593286604195, 6048.0, 5310.8406747797535, 5324.600654950894, 5351.518363347298, 5393.703346315951, 5426.511613954744, 5223.660462069954, 5239.030779305841, 5451.836444906639, 5453.263809385741, 5477.643953143033, 5269.209618557228, 5301.0, 5378.381486129087, 5407.567394608422, 5474.288342515468, 5400.567954563626, 5406.297181366417, 5416.783328666848, 5428.75570599324, 5447.809326673587, 5232.162853943389, 5329.7601607927345, 5333.146696528537, 5335.0663633863915, 5375.3280839895015, 5674.233978412903, 5701.230333904953, 5705.54355651551, 5949.8810023799715, 5950.285965684102, 6207.81376558704, 6270.937290627081, 6333.683315834236, 6344.206974128234, 6356.713947872357, 6321.146645202884, 6391.58454700443, 6395.104685344056, 6407.519436042269, 6410.903836442457, 5836.241288632451, 6037.969810150921, 6063.393660633946, 6101.359357267512, 6114.357992410733, 6283.59156654816, 6288.025356069817, 6294.0558916162945, 6308.369163083655, 6348.619082854951, 5247.895042099137, 6271.811845644638, 6292.528060395489, 6310.0534919761485, 6310.684465776692, 6285.497160227171, 6344.238691357066, 6345.777897773556, 6349.873002539924, 6365.013422919363, 6191.84520386994, 6240.095186197976, 6282.905756413657, 6309.053641953652, 6313.936860631335, 6192.442680158717, 6255.71849266775, 6271.717772700148, 6297.905531417033, 6442.6778661066755, 5098.949010509921, 5102.413222479462, 5102.744862756922, 5104.770285337168, 5131.538161565477, 4430.357598148251, 4473.642108631302, 4574.5425457453985, 4577.450705915245, 4586.72479651222, 5857.209276747584, 5861.384554621789, 5984.820455386345, 6019.307779605401, 6074.696265186723, 6374.426301632875, 6382.808515744535, 6391.58454700443, 6394.552381333355, 6395.584287021329, 6016.458518733335, 6021.759129634853, 6027.517798576191, 6046.425589569072, 6049.0, 5475.890482190334, 5482.945170548323, 5574.665520068809, 5575.553955683536, 5652.463016013472, 6307.211598550181, 6357.459615932605, 6558.672066396565, 6584.4732421405215, 6690.83272918181, 6404.039394090923, 6405.743770249138, 6407.135036769976, 6417.294097649348, 6441.098246245623, 6676.799696009128, 6683.264840867598, 6688.933110669026, 6690.19717633887, 6696.0, 6180.81457556274, 6222.16000839899, 6238.0, 6301.180846489927, 6322.083297921868, 6210.099535567408, 6210.378962103934, 6303.086052522451, 6358.554901156875, 6361.395667411589, 6145.617236121837, 6199.0, 6237.438630523275, 6250.468710159687, 6297.086922396318, 6276.968615156895, 6278.121063051268, 6287.654179020198, 6288.717007734572, 6299.401556852092, 5989.281286245504, 5993.280806303097, 6018.699065046818, 6044.516438684983, 6047.848803779941, 6157.784477543264, 6219.036049412348, 6238.812835614939, 6272.4982001440685, 6287.811365659038, 6214.782482613087, 6214.782482613087, 6227.972384556679, 6229.345918678655, 6235.282942461675], "value": 5838.4657855186615, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.3215618490007, 812.9024517057954, 858.8840506531616, 883.7834730491022, 885.8006948436603, 32155.87454439095, 39645.207095858066, 41912.37131443027, 41944.790276048654, 42076.0, 39875.401868971916, 39894.8005259974, 39897.0, 39982.200355992936, 40023.59976400235, 7323.414126869864, 9176.036516165823, 9304.81390372192, 9330.580123894408, 9335.626574936994, 7408.629568521579, 10953.890461095407, 11130.49912753924, 11322.886771132265, 11363.318200907972, 10318.484075796217, 10483.741950965858, 11138.888611113865, 11172.050375718032, 11174.385408802513, 9166.954165229197, 11093.667189984313, 11125.0, 11158.0, 11159.553617855276, 10730.892691073068, 10911.563537458493, 10912.563497460093, 10931.508082136323, 10943.398113103776, 38018.23963520714, 38227.23545529102, 38257.043573910596, 38347.424788628196, 38481.42277865834, 3245.8701651933893, 3837.4435706822633, 3843.3466310727244, 3877.418387241936, 3901.804909754501, 26601.46797064048, 26744.331391715365, 26753.86623066892, 28439.0, 28524.714752852415, 3056.3276079262528, 3176.142441540794, 3279.4261004324385, 3307.751918606114, 3312.3706495765728, 350416.0, 350908.0, 350917.490825091, 351551.0, 352221.0, 41564.3765343514, 42227.73316800501, 51914.48085519097, 52015.21977170346, 52074.73962630163, 45122.097558048656, 51532.22701659478, 52372.73813630984, 52413.47586524086, 52602.210966835526, 14540.781888271684, 14612.707745845024, 14616.561503154922, 14623.341949612293, 14686.045407048508, 12701.42843572023, 12743.36283185837, 12747.36263186837, 12748.0, 12784.744305113845, 11897.464614092387, 11991.520339186334, 11999.220050696676, 12034.338111403957, 12071.517139314328, 5236.895262094813, 5292.44429334922, 5295.008320299019, 5303.469653034743, 5307.717681396959, 1102.151343465543, 1104.7414307894321, 1124.656979621207, 1148.1331594646156, 1185.4902391971534, 25932.0, 26196.8690156548, 26303.34241643936, 26353.868230658725, 26446.735532644812, 1028.6708253358854, 1220.4995951659778, 1225.779359715242, 1254.7678679444332, 1279.539365828301, 13330.400131994083, 13364.0, 13663.931680341733, 13683.015962685555, 13721.245331506663, 418524.0, 422651.0, 424700.0, 425248.87375562923, 425535.0, 55088.0, 55255.44744552583, 55336.0, 55373.446265537634, 55486.72256638772, 1392.6031081141825, 1442.6393401649589, 1442.783582462639, 1445.5085271007488, 1449.427476146916, 24818.627720583845, 25012.124575639766, 25391.6191257135, 25441.87279063667, 25468.617970730083, 45260.773696131306, 45261.77369113133, 45269.868253293935, 45312.09375812399, 45384.31923521084, 405907.0, 406913.0, 407111.0, 409141.90858091036, 410322.0, 32552.186195345304, 33063.0, 33136.0, 33167.66832331646, 33181.17047073746, 41383.0, 41854.162916742105, 42310.57689423066, 42432.15135697331, 42577.787111064245, 27712.0, 28847.5672864903, 29067.563986539797, 29615.555766663085, 29767.851160744056, 23137.884310578338, 28801.855990719912, 29206.853965730035, 29830.552541712324, 29850.0, 34159.14602134967, 34367.48448773321, 35094.9471515855, 35131.64868351284, 35231.82384088063, 27052.458950821267, 28195.872165113986, 29048.854755726086, 29463.852680736458, 29678.406431871674, 22039.66940495862, 22448.214312498985, 22586.435339116655, 22630.321090367317, 23079.6538051926], "value": 18470.62060428933, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.5461414381526, 385.366072810227, 395.3338624417858, 408.9795510224488, 413.87169977307036, 13224.140430872, 13367.0, 15865.682686346267, 20915.895420522877, 21008.0, 21395.037223324947, 21414.357569272906, 21512.89243553784, 21794.564108717816, 21913.780862191372, 6330.841728956779, 6663.133792606955, 6674.56615320005, 7472.850542989137, 7839.294463498284, 7699.692012319529, 7917.524948503079, 7957.442978991473, 7979.640916158787, 7994.200579942018, 7922.128565857751, 7979.760607181794, 7998.880016799752, 8003.839923201533, 8008.51948883066, 7151.213366529703, 7490.850182996364, 7670.463067585271, 7676.193999630014, 7979.042514898191, 7487.1764105948305, 7491.438142139307, 7530.8117297067465, 7831.882521762178, 7913.683452661887, 29143.271418214714, 29204.123876283746, 29260.414791704046, 29268.41463170725, 29441.705582944112, 2945.3225758075764, 2949.6136815696805, 3010.005213634901, 3032.7877048606606, 3033.893813716509, 15944.043357398594, 16109.516714498584, 16358.754618680729, 17002.659946800995, 17056.48830535086, 1863.138251409509, 1864.3567969050655, 1880.9341673041379, 1904.561950751321, 1929.5465565592074, 90412.0, 96000.0, 111978.0, 136534.6346536532, 173183.13408432878, 22847.0, 23698.76301237, 23760.52478950446, 24079.638805417933, 24158.637620435708, 41146.794266028475, 41843.58156418458, 42274.788626056674, 42331.7883410581, 42348.15303693909, 10082.546285417175, 10563.313384630128, 11186.832197517044, 11701.00541453969, 11756.94121529387, 9135.771605709777, 9154.954225228832, 9154.99295077541, 9313.34806563534, 9337.953310233406, 10930.344179349264, 11021.338719676845, 11042.779144417227, 11161.888381116247, 11209.55161793519, 3806.849130241405, 3822.0, 4364.75993820343, 4497.550244975476, 4532.660050496226, 857.7255278311005, 1033.121846430528, 1049.7690508088208, 1055.9788804223872, 1065.6856227412932, 20878.686819697716, 21264.574708506054, 21316.14735410597, 21382.57234855294, 21537.284286786366, 1039.163473403902, 1043.2697112021463, 1056.1603525197486, 1083.9620613278498, 1094.0864378244096, 10955.397453139994, 11172.832407513893, 11181.608643697431, 11190.94404527972, 11251.874812518683, 716424.0, 754721.0, 765578.0, 778537.0, 800796.9920300723, 157825.0, 158418.0, 158659.0, 160660.59009114874, 166177.33822661618, 1158.5870464741508, 1182.586094866807, 1205.1323047405854, 1207.7825991321647, 1212.7938250497614, 17619.471415857544, 18323.63352732911, 18502.0, 18550.628987419906, 18551.44345669632, 60831.695841522276, 61362.38637613567, 61793.691031546354, 62726.37273627205, 63125.421864453754, 87594.68607971013, 88399.0, 89137.21725565582, 90165.0, 90597.54701226452, 9867.753306167404, 9978.501074946078, 10263.84604230952, 11412.600558980395, 11569.479373428048, 12298.385080745747, 12363.072769542141, 12411.813822792485, 12436.19164754288, 12506.749865002466, 10611.522481488506, 10725.731856703645, 10756.354618722902, 10832.404217768099, 10933.234673572773, 8551.358648101294, 10034.548445319831, 10219.642312519323, 10273.229507787095, 10340.638077667245, 10766.838497422701, 11001.559937602406, 11036.282641628268, 11089.778204436028, 11144.996950274513, 11166.944165279121, 11535.596254131066, 11607.825882611936, 11613.709657258636, 11624.0, 10586.8411973822, 10689.358638481715, 10900.454977251264, 10901.0, 11011.0], "value": 11829.402006461583, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.1900918953222, 1415.5045733993104, 1512.9924350378246, 1527.3737767515322, 1553.6970290793292, 10431.113355364794, 11008.669739907795, 11017.669469915905, 11020.118390528758, 11050.115990720744, 10330.845037324447, 10740.0, 10881.401522916236, 10889.128869690425, 10914.290571112871, 2323.8140948724104, 2375.608024675935, 2376.1327115602767, 2381.404648837791, 2382.059086660768, 2288.565172617206, 2366.2309749331534, 2367.4081479630095, 2376.4058985253687, 2377.5839228135005, 2295.2310975823034, 2356.3284463927785, 2364.893579788905, 2367.7277113132036, 2380.1074597026113, 2318.9072437102495, 2319.3505818370713, 2344.0975224538506, 2352.447174913894, 2356.5051339218785, 2336.4158960259933, 2346.8826558672167, 2368.609179485383, 2369.0997420979916, 2371.5256948610345, 10734.946325268402, 10933.234673572853, 10949.562017519289, 10957.561697532168, 10970.232083754141, 5434.076207044812, 5599.300087489064, 5604.327480702301, 5609.214709940612, 5630.915536266959, 3407.9659203407896, 3468.254325320043, 3487.668671476206, 3516.9472457913153, 3539.7699149555197, 1101.251149218531, 1109.1902910875026, 1130.8416821644976, 1174.5360582569922, 1180.8523934508187, 13347.466101355934, 13645.931770341085, 14009.859901400958, 14995.550133496012, 15006.549803505803, 4426.84506042287, 4563.885902852388, 4583.633309335245, 4587.770611469413, 4618.769061546909, 11493.482793274323, 11506.654800355835, 11507.827382589268, 11543.0, 11566.710832229093, 4631.282151266559, 4771.642126840501, 4790.688605240648, 4792.856214313576, 4793.18515852306, 4294.613484786384, 4937.530934561211, 5227.973860130675, 5245.134552798745, 5247.02929957959, 3678.1724112075103, 3725.0873535983314, 3792.791396473165, 3795.1650636859845, 3809.42858571211, 956.8947415784259, 968.3221744778683, 980.5146452506056, 980.5342462330336, 981.249344251648, 775.8331958628809, 829.0755807274845, 913.3150137396952, 919.8804155459882, 922.686286662538, 6135.141080248769, 6151.169592105009, 6399.9040014399825, 6419.197600299963, 6453.870922581616, 968.3608818180109, 977.2914636888199, 983.537737263485, 986.4574484033752, 994.3139233928566, 3343.2310568569364, 3549.0, 3927.3323534999117, 3979.263836190314, 4141.192467468875, 13447.260400678057, 13993.3702983366, 14043.297835108204, 14047.227402492757, 14051.227182505061, 13906.30468476572, 13926.234057126752, 13945.093568917988, 13952.58142255734, 13967.0, 9843.75390615226, 9930.652427165014, 10148.289619726549, 10155.593776249016, 10174.186065114773, 6147.231596050494, 6161.135072191273, 6164.537659675452, 6286.402791734778, 6428.742850285937, 11229.71925701864, 12046.457909393923, 12263.141580089665, 12379.62861114168, 12669.429875655433, 10288.2798204128, 12204.877951220375, 12888.42002109926, 12935.094543381872, 12964.481420743441, 8946.731598052069, 8956.149165829236, 9023.0, 9033.50315732641, 9097.681581144629, 9900.158486528726, 9965.0, 10057.29598928068, 10090.69727908164, 10108.090271875566, 8990.145936136056, 9158.40470369424, 9195.126462986005, 9217.723468295962, 9350.438973661601, 9014.41406308614, 9036.0, 9107.180353768194, 9117.08829117083, 9222.40054396462, 7788.337991270579, 7859.371250299848, 7867.645955932111, 7904.960475197588, 7971.322437592869, 5184.860208192013, 5602.215689803512, 5764.077747560538, 5813.680247586423, 6003.939960600338, 5727.226824378656, 5836.153757705194, 5912.320083190488, 6008.969955150196, 6047.183630209865], "value": 4999.168400742809, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.1657273951423, 331.7528441311223, 360.8159838482373, 372.66646351515396, 374.1021548284116, 4147.543770185277, 4428.977855110724, 5112.642115051948, 5133.358330208724, 5141.331626888504, 4146.150039241956, 4666.953330466695, 4701.600363969058, 5002.374703162105, 5005.524475174852, 4240.600601801413, 4364.847230346939, 4371.912561748763, 4421.491528474218, 4486.439195100613, 4353.586409291112, 4363.54182810805, 4404.876756427107, 4431.0, 4456.866294011184, 3945.6326204277348, 4273.995611031405, 4441.977790111045, 4445.199864024475, 4459.55404459553, 3998.1204135090234, 4098.8975275618195, 4368.934465983013, 4406.647468202536, 4512.16524942885, 3796.0889386547306, 3906.8437262509465, 4061.3501839705505, 4232.899446143994, 4237.156836775982, 4149.668026557898, 4946.5795407390415, 4968.3292755477905, 4972.201825238662, 5051.262066071117, 4413.470383553994, 4720.834770783041, 4857.688424125467, 5154.329937108152, 5156.396020009871, 4865.708057516525, 4962.0324036812835, 5352.197170424429, 5434.22321486567, 5445.700486473243, 4026.8187931542857, 4388.100439409914, 4598.448186217642, 4715.3634259374885, 4800.919218664142, 5338.27933229013, 5421.1326187810155, 5575.386707462217, 5725.08398656213, 5868.735906884201, 3976.5625781164626, 4298.699091063658, 4343.739375637409, 4525.0, 4612.60792832613, 4687.015726697432, 4697.4832768395445, 4702.249184227197, 4706.552877476634, 4720.197566413718, 4634.745089020069, 4647.629708848512, 4670.813167473263, 4929.975350123226, 4953.380827396575, 4251.766152861623, 4862.325720853587, 5166.844994650167, 5393.379761327497, 5457.918131228034, 1663.3180396037567, 1681.3022595622924, 1700.600358915654, 1709.6837085139289, 1710.4954038558656, 1274.3246079577839, 1283.6790802299424, 1358.3751474321923, 1381.2679279981626, 1402.3549167383119, 4253.404523366731, 4547.477040140427, 4677.170311961282, 4991.401031876198, 4997.200447928314, 4926.0, 5299.973500132552, 5553.4450353900975, 5602.495775380154, 5770.672745268528, 4367.235733746582, 4434.8912771807045, 5029.471905449948, 5060.949390506048, 5079.1365467870555, 2709.6722605922955, 2786.874590643426, 2845.5162622353837, 2892.7107289271325, 2917.460269850042, 5288.704267454498, 5769.336526299529, 5776.393478684678, 5785.8553536161935, 5884.941150588439, 4979.879527106372, 5058.392992840809, 5358.383785864676, 5500.7524661390335, 5696.231008813757, 5172.508611681884, 5757.769689212385, 5768.394318596487, 5817.50551203144, 5851.444112809277, 5485.807996720096, 5532.518659885505, 5598.0, 5610.971945140412, 5636.801647357616, 4993.650444468853, 5042.319286896222, 5436.401995780382, 5510.641808282448, 5511.889762204813, 5182.0, 5227.947720522746, 5750.453706897838, 5761.971190144022, 5788.421157884346, 4479.484859241229, 4567.794449249725, 4603.110371735877, 4845.878853028703, 4845.9757701211265, 4590.317875766633, 5376.784928602812, 5519.586031047767, 5534.501894829485, 5541.5289700375915, 4616.838410655611, 4689.296605509201, 4743.573078422958, 5151.583314588368, 5200.4279529251, 5022.799088036584, 5053.974730126326, 5369.785208591613, 5390.4879036491475, 5434.701091440009, 4631.675782695179, 5079.4697685108495, 5169.922451163311, 5220.694826293427, 5238.0, 4759.214729569582, 4773.832915847928, 4827.648258487629, 5224.764885580082, 5421.906460622205, 4824.068384266951, 5065.670731402447, 5180.948190518046, 5231.607629427885, 5249.186376111708], "value": 4216.474957474387, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.3912391838691, 745.0, 745.4744405194339, 813.4549851599428, 827.2430725885815, 13797.620237976198, 14646.34091465884, 15338.69322613547, 15768.369265229405, 15768.92115539422, 14011.579652610413, 14380.424783008668, 14406.495772647959, 14433.566992990227, 15057.77413338798, 941.3410612571193, 956.5743244256308, 957.3824882950493, 958.3722661656626, 960.433344326847, 911.1662828511937, 934.8737920380729, 949.4113649537296, 949.7008442340641, 950.7242899559119, 1010.3028910052077, 1011.1102230037557, 1012.5595366015807, 1012.8278192707221, 1015.7003683913264, 1004.9749255759122, 1006.5621454667244, 1007.1539906478594, 1007.3804610164769, 1007.9344842585208, 751.2074761127009, 751.3012898004853, 752.6011214056559, 755.467395486181, 758.3440324119651, 15374.231288435534, 15631.45289914859, 15648.374065037386, 16142.435014774544, 16236.43172488969, 13719.588412347646, 13879.028468007244, 14098.295085245696, 14106.647333816634, 14138.0, 11770.352630605314, 11807.881921180764, 11931.582394616233, 11948.522059117626, 12140.907318341304, 3488.9651103488895, 3622.655847694491, 3696.4085746280734, 3705.74059815813, 3725.6833169180654, 104320.47839760753, 105713.94286057117, 107375.46312268467, 108612.0, 108782.3682644753, 10460.163186945027, 10578.471076446302, 10612.681619551426, 10652.467376631292, 10930.836037459445, 21066.368008959755, 21080.472988175112, 21088.05103770334, 21093.26173583948, 21097.789022109584, 7359.558426494427, 7364.631768411558, 7429.554226746304, 7559.433042521723, 7639.732609358646, 4187.120704651997, 4223.113146239324, 4260.978695106504, 4348.130373925228, 4456.866294011184, 3430.3139372125675, 3463.6882680558365, 3517.3317069756663, 3524.665156810099, 3526.594441639193, 533.3919331961567, 634.0932466572846, 638.9233292004989, 649.5290914087249, 650.313918815654, 662.8442316055723, 693.1370443797505, 694.0664805836142, 698.4307789151825, 704.3766266853769, 8514.808145723675, 10193.286469947181, 10231.232657550565, 10254.6410875619, 10410.0, 685.8902575587882, 708.7873637908607, 708.9012031351488, 713.3515634288485, 726.4478995963107, 4781.928271075936, 4800.887055286109, 4806.158922188603, 4840.115772221678, 4858.44127925286, 96125.51937240269, 98503.0, 98517.5222371665, 99249.00750992398, 100398.4980075095, 73959.0, 87867.60661966876, 89973.55013224891, 90629.09370906338, 91857.7035574116, 75381.0, 76182.0, 76351.61824190844, 78256.21743782604, 78298.82551761615, 9524.380915240487, 9574.904250957401, 9577.808443831223, 9584.952075239578, 9622.759431014281, 53377.0, 53564.196537052754, 53834.0, 54500.45499544954, 54813.903721924544, 66432.6678366605, 67303.66348168228, 68143.31856681568, 68633.65683171753, 69894.0, 11164.776704465703, 11360.886391135982, 11964.92315691592, 12189.207701499374, 12433.502659893504, 14088.436462541384, 14478.565643030726, 14699.706005879609, 15218.239088045775, 15572.844271557593, 12882.227066376048, 13563.0, 13900.02699811044, 13926.373313201133, 14225.861931045698, 12615.180013299107, 13877.722445551235, 13897.374618142007, 13898.722025559635, 14231.146131232159, 12816.743665126833, 13742.831859292071, 13758.93120534391, 14242.643933901736, 14349.497767578085, 9142.451452912846, 9229.400088994194, 9585.760355991157, 9621.422714637145, 9756.902430975599, 8124.15654520913, 9695.612175513183, 10229.392713758954, 10447.9910193173, 10450.843237351293], "value": 7562.900864783666, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1463.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 116.0, 115.0, 117.0, 116.0, 115.0, 116.0, 116.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 134.0, 135.0, 161.0, 112.0, 111.0, 110.0, 107.0, 129.0, 110.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 183.0, 184.0, 188.0, 183.0, 185.0, 181.0, 185.0, 183.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 61.0, 92.0, 63.0, 61.0, 60.0, 60.0, 77.0, 61.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 87.0, 70.0, 68.0, 69.0, 83.0, 69.0, 69.0, 104.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 87.0, 87.0, 88.0, 88.0, 87.0, 88.0, 86.0], "value": 87.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 96.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 139.0, 128.0, 86.0, 85.0, 84.0, 83.0, 82.0, 83.0, 84.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 61.0, 62.0, 62.0, 63.0, 63.0, 62.0, 63.0, 65.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 66.0, 69.0, 63.0, 64.0, 80.0, 81.0, 84.0, 56.0, 59.0], "value": 67.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 83.0, 83.0, 83.0, 73.0, 88.0, 105.0, 100.0, 84.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 139.0, 152.0, 135.0, 136.0, 139.0, 140.0, 132.0, 192.0, 174.0], "value": 148.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 211.0, 208.0, 171.0, 168.0, 164.0, 166.0, 163.0, 164.0], "value": 175.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 62.0, 57.0, 66.0, 76.0, 82.0, 82.0, 81.0, 67.0, 64.0], "value": 70.5, "unit": "score"}]}]} 10:18:06 INFO - Return code: 0 10:18:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:18:06 ERROR - # TBPL SUCCESS # 10:18:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:18:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:18:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:18:06 INFO - 2017-04-15 10:18:06.882 screenresolution[1669:14476] starting screenresolution argv=screenresolution get 10:18:06 INFO - 2017-04-15 10:18:06.886 screenresolution[1669:14476] Display 0: 1600x1200x32@60 10:18:06 INFO - 2017-04-15 10:18:06.892 screenresolution[1670:14479] starting screenresolution argv=screenresolution list 10:18:06 INFO - Available Modes on Display 0 10:18:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:18:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:18:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:18:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:18:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:18:07 INFO - Intel Iris: 10:18:07 INFO - Chipset Model: Intel Iris 10:18:07 INFO - Type: GPU 10:18:07 INFO - Bus: Built-In 10:18:07 INFO - VRAM (Dynamic, Max): 1536 MB 10:18:07 INFO - Vendor: Intel (0x8086) 10:18:07 INFO - Device ID: 0x0a2e 10:18:07 INFO - Revision ID: 0x0009 10:18:07 INFO - Displays: 10:18:07 INFO - Display: 10:18:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:18:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:18:07 INFO - Display Serial Number: 1600x1200 60 10:18:07 INFO - Main Display: Yes 10:18:07 INFO - Mirror: Off 10:18:07 INFO - Online: Yes 10:18:07 INFO - Rotation: Supported 10:18:07 INFO - Return code: 0 10:18:07 INFO - Running post-action listener: _resource_record_post_action 10:18:07 INFO - [mozharness: 2017-04-15 17:18:07.133982Z] Finished run-tests step (success) 10:18:07 INFO - Running post-run listener: _resource_record_post_run 10:18:07 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 32173056; Write bytes: 510767104; Read time: 195; Write time: 1565 10:18:07 INFO - TinderboxPrint: CPU usage
54.1% 10:18:07 INFO - TinderboxPrint: I/O read bytes / time
32,173,056 / 195 10:18:07 INFO - TinderboxPrint: I/O write bytes / time
510,767,104 / 1,565 10:18:07 INFO - TinderboxPrint: CPU idle
2,202.3 (45.9%) 10:18:07 INFO - TinderboxPrint: CPU system
960.3 (20.0%) 10:18:07 INFO - TinderboxPrint: CPU user
1,639.0 (34.1%) 10:18:07 INFO - TinderboxPrint: Swap in / out
236,007,424 / 0 10:18:07 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205996544; Write bytes: 203270144; Read time: 15843; Write time: 437 10:18:07 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 30133248; Write bytes: 296515584; Read time: 175; Write time: 1092 10:18:07 INFO - Running post-run listener: _upload_blobber_files 10:18:07 INFO - Blob upload gear active. 10:18:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:18:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:18:07 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'] 10:18:07 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 10:18:08 INFO - (blobuploader) - INFO - Open directory for files ... 10:18:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:18:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:18:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:09 INFO - (blobuploader) - INFO - Done attempting. 10:18:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:18:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:18:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:10 INFO - (blobuploader) - INFO - Done attempting. 10:18:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:18:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:18:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:11 INFO - (blobuploader) - INFO - Done attempting. 10:18:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:18:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:18:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:14 INFO - (blobuploader) - INFO - Done attempting. 10:18:14 INFO - (blobuploader) - INFO - Iteration through files over. 10:18:14 INFO - Return code: 0 10:18:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:18:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:18:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d88cc402cdd34e9db38a7410b842ef6cc82df3a8079f88ee1e425626ffb9205311460ab1a5ec58a3522ab866822b4724d71ff35f4a59723284fc0da492ccff19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/09aa3d9f231a46e1f126dedac28d0a4a4ef47fc6f1c27fcad2f5125e120160ece62e58caca1dfa1a739c8cd14f4ad49dc40d96bb885d27e149533b77b55f9726", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8496cbf08a756808f6a6061a22f27a45625e89470334e486dc9f05b75705d66082c39fe663de191da1130a122a6917ce6c11dff0d917f9e8af53f06e9594fbb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/75862bd7b05e0926d2fef5499b873e5be57483aa13d4321834c6cb32a781bcc56facfc4f7fd8b0fc8d5b07ab9ed41b357d507d8380e105437f91b43b075c048d"} 10:18:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:18:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:18:14 INFO - Contents: 10:18:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d88cc402cdd34e9db38a7410b842ef6cc82df3a8079f88ee1e425626ffb9205311460ab1a5ec58a3522ab866822b4724d71ff35f4a59723284fc0da492ccff19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/09aa3d9f231a46e1f126dedac28d0a4a4ef47fc6f1c27fcad2f5125e120160ece62e58caca1dfa1a739c8cd14f4ad49dc40d96bb885d27e149533b77b55f9726", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8496cbf08a756808f6a6061a22f27a45625e89470334e486dc9f05b75705d66082c39fe663de191da1130a122a6917ce6c11dff0d917f9e8af53f06e9594fbb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/75862bd7b05e0926d2fef5499b873e5be57483aa13d4321834c6cb32a781bcc56facfc4f7fd8b0fc8d5b07ab9ed41b357d507d8380e105437f91b43b075c048d"} 10:18:14 INFO - Running post-run listener: copy_logs_to_upload_dir 10:18:14 INFO - Copying logs to upload dir... 10:18:14 INFO - mkdir: /builds/slave/test/build/upload/logs 10:18:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.673951 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-15 10:18:14.161156) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-15 10:18:14.165971) ========= 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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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/d88cc402cdd34e9db38a7410b842ef6cc82df3a8079f88ee1e425626ffb9205311460ab1a5ec58a3522ab866822b4724d71ff35f4a59723284fc0da492ccff19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/09aa3d9f231a46e1f126dedac28d0a4a4ef47fc6f1c27fcad2f5125e120160ece62e58caca1dfa1a739c8cd14f4ad49dc40d96bb885d27e149533b77b55f9726", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8496cbf08a756808f6a6061a22f27a45625e89470334e486dc9f05b75705d66082c39fe663de191da1130a122a6917ce6c11dff0d917f9e8af53f06e9594fbb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/75862bd7b05e0926d2fef5499b873e5be57483aa13d4321834c6cb32a781bcc56facfc4f7fd8b0fc8d5b07ab9ed41b357d507d8380e105437f91b43b075c048d"} build_url:https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011153 build_url: 'https://queue.taskcluster.net/v1/task/D5H4xV3lSd6PMQZNo3RlUg/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/d88cc402cdd34e9db38a7410b842ef6cc82df3a8079f88ee1e425626ffb9205311460ab1a5ec58a3522ab866822b4724d71ff35f4a59723284fc0da492ccff19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/09aa3d9f231a46e1f126dedac28d0a4a4ef47fc6f1c27fcad2f5125e120160ece62e58caca1dfa1a739c8cd14f4ad49dc40d96bb885d27e149533b77b55f9726", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8496cbf08a756808f6a6061a22f27a45625e89470334e486dc9f05b75705d66082c39fe663de191da1130a122a6917ce6c11dff0d917f9e8af53f06e9594fbb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/75862bd7b05e0926d2fef5499b873e5be57483aa13d4321834c6cb32a781bcc56facfc4f7fd8b0fc8d5b07ab9ed41b357d507d8380e105437f91b43b075c048d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-15 10:18:14.206007) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 10:18:14.206498) ========= 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.TH2ZjVHP46/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.b8Eg9Sncx4/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.005260 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 10:18:14.258556) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-15 10:18:14.259286) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-15 10:18:14.260043) ========= ========= Total master_lag: 0.23 =========