builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0102 starttime: 1486632820.86 results: success (0) buildid: 20170209004121 builduid: 52e397d362ae4cdd9f1b63e705284348 revision: eae7e45deb55defb86c7c30fdfcf20c5d017b98d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 01:33:40.862389) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 01:33:40.862938) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 01:33:40.863280) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 01:33:40.891863) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 01:33:40.892554) ========= 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.udtgeCDDp3/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.UcFI8EMwye/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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-02-09 01:33:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.12M=0.001s 2017-02-09 01:33:41 (9.12 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.109845 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 01:33:41.028990) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 01:33:41.029350) ========= 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.udtgeCDDp3/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.UcFI8EMwye/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.029006 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 01:33:41.083666) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 01:33:41.084516) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev eae7e45deb55defb86c7c30fdfcf20c5d017b98d --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 eae7e45deb55defb86c7c30fdfcf20c5d017b98d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.udtgeCDDp3/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.UcFI8EMwye/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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-02-09 01:33:41,181 truncating revision to first 12 chars 2017-02-09 01:33:41,182 Setting DEBUG logging. 2017-02-09 01:33:41,182 attempt 1/10 2017-02-09 01:33:41,182 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/eae7e45deb55?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 01:33:42,447 unpacking tar archive at: mozilla-aurora-eae7e45deb55/testing/mozharness/ program finished with exit code 0 elapsedTime=1.547737 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 01:33:42.658224) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 01:33:42.658768) ========= script_repo_revision: eae7e45deb55defb86c7c30fdfcf20c5d017b98d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 01:33:42.659220) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 01:33:42.659571) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 01:33:42.679556) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-09 01:33:42.679936) ========= /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.udtgeCDDp3/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.UcFI8EMwye/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 01:33:42 INFO - MultiFileLogger online at 20170209 01:33:42 in /builds/slave/test 01:33:42 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 01:33:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:33:42 INFO - {'append_to_log': False, 01:33:42 INFO - 'base_work_dir': '/builds/slave/test', 01:33:42 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:33:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:33:42 INFO - 'branch': 'Mozilla-Aurora', 01:33:42 INFO - 'buildbot_json_path': 'buildprops.json', 01:33:42 INFO - 'config_files': ('talos/mac_config.py',), 01:33:42 INFO - 'default_actions': ('clobber', 01:33:42 INFO - 'read-buildbot-config', 01:33:42 INFO - 'download-and-extract', 01:33:42 INFO - 'populate-webroot', 01:33:42 INFO - 'create-virtualenv', 01:33:42 INFO - 'install', 01:33:42 INFO - 'run-tests'), 01:33:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:33:42 INFO - 'download_minidump_stackwalk': True, 01:33:42 INFO - 'download_symbols': 'ondemand', 01:33:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:33:42 INFO - 'tooltool.py': '/tools/tooltool.py', 01:33:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:33:42 INFO - '/tools/misc-python/virtualenv.py')}, 01:33:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:33:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:33:42 INFO - 'installer_path': 'installer.exe', 01:33:42 INFO - 'log_level': 'info', 01:33:42 INFO - 'log_name': 'talos', 01:33:42 INFO - 'log_to_console': True, 01:33:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:33:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:33:42 INFO - 'opt_config_files': (), 01:33:42 INFO - 'pip_index': False, 01:33:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:33:42 INFO - 'cmd': ('bash', 01:33:42 INFO - '-c', 01:33:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:33:42 INFO - 'enabled': True, 01:33:42 INFO - 'halt_on_failure': False, 01:33:42 INFO - 'name': 'check_screen_resolution'},), 01:33:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:33:42 INFO - 'cmd': ('bash', 01:33:42 INFO - '-c', 01:33:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:33:42 INFO - 'enabled': True, 01:33:42 INFO - 'halt_on_failure': False, 01:33:42 INFO - 'name': 'check_screen_resolution'},), 01:33:42 INFO - 'run_cmd_checks_enabled': True, 01:33:42 INFO - 'sps_profile': False, 01:33:42 INFO - 'sps_profile_interval': 0, 01:33:42 INFO - 'suite': 'dromaeojs', 01:33:42 INFO - 'system_bits': '32', 01:33:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:33:42 INFO - 'title': 't-yosemite-r7-0102', 01:33:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:33:42 INFO - 'use_talos_json': True, 01:33:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:33:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:33:42 INFO - 'work_dir': 'build'} 01:33:42 INFO - [mozharness: 2017-02-09 09:33:42.889137Z] Running clobber step. 01:33:42 INFO - Running pre-action listener: _resource_record_pre_action 01:33:42 INFO - Running main action method: clobber 01:33:42 INFO - rmtree: /builds/slave/test/build 01:33:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:33:45 INFO - Running post-action listener: _resource_record_post_action 01:33:45 INFO - [mozharness: 2017-02-09 09:33:45.296586Z] Finished clobber step (success) 01:33:45 INFO - [mozharness: 2017-02-09 09:33:45.296718Z] Running read-buildbot-config step. 01:33:45 INFO - Running pre-action listener: _resource_record_pre_action 01:33:45 INFO - Running main action method: read_buildbot_config 01:33:45 INFO - Using buildbot properties: 01:33:45 INFO - { 01:33:45 INFO - "project": "", 01:33:45 INFO - "product": "firefox", 01:33:45 INFO - "script_repo_revision": "production", 01:33:45 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 01:33:45 INFO - "repository": "", 01:33:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 01:33:45 INFO - "buildid": "20170209004121", 01:33:45 INFO - "pgo_build": "False", 01:33:45 INFO - "basedir": "/builds/slave/test", 01:33:45 INFO - "buildnumber": 61, 01:33:45 INFO - "slavename": "t-yosemite-r7-0102", 01:33:45 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 01:33:45 INFO - "platform": "yosemite_r7", 01:33:45 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:33:45 INFO - "branch": "mozilla-aurora", 01:33:45 INFO - "repo_path": "releases/mozilla-aurora", 01:33:45 INFO - "revision": "eae7e45deb55defb86c7c30fdfcf20c5d017b98d", 01:33:45 INFO - "stage_platform": "macosx64", 01:33:45 INFO - "builduid": "52e397d362ae4cdd9f1b63e705284348", 01:33:45 INFO - "slavebuilddir": "test" 01:33:45 INFO - } 01:33:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 01:33:45 INFO - Running post-action listener: _resource_record_post_action 01:33:45 INFO - [mozharness: 2017-02-09 09:33:45.300059Z] Finished read-buildbot-config step (success) 01:33:45 INFO - [mozharness: 2017-02-09 09:33:45.300188Z] Running download-and-extract step. 01:33:45 INFO - Running pre-action listener: _resource_record_pre_action 01:33:45 INFO - Running main action method: download_and_extract 01:33:45 INFO - mkdir: /builds/slave/test/build/tests 01:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:45 INFO - trying https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 01:33:45 INFO - Downloading https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 01:33:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 01:33:46 INFO - Downloaded 1199 bytes. 01:33:46 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 01:33:46 INFO - Using the following test package requirements: 01:33:46 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 01:33:46 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:33:46 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 01:33:46 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:33:46 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 01:33:46 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:33:46 INFO - u'jsshell-mac.zip'], 01:33:46 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:33:46 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 01:33:46 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 01:33:46 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:33:46 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 01:33:46 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:33:46 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 01:33:46 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:33:46 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 01:33:46 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:33:46 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 01:33:46 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:33:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 01:33:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:33:46 INFO - Fetch https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 01:33:47 INFO - Expected file size: 16357990 01:33:47 INFO - Obtained file size: 16357990 01:33:53 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:33:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 01:33:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:33:53 INFO - Fetch https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 01:33:54 INFO - Expected file size: 16357990 01:33:54 INFO - Obtained file size: 16357990 01:34:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 01:34:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:34:01 INFO - Fetch https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 01:34:02 INFO - Expected file size: 14403485 01:34:02 INFO - Obtained file size: 14403485 01:34:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:02 INFO - trying https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 01:34:02 INFO - Downloading https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 01:34:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 01:34:04 INFO - Downloaded 73818091 bytes. 01:34:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 01:34:04 INFO - mkdir: /builds/slave/test/properties 01:34:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:34:04 INFO - Writing to file /builds/slave/test/properties/build_url 01:34:04 INFO - Contents: 01:34:04 INFO - build_url:https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 01:34:05 INFO - Running post-action listener: _resource_record_post_action 01:34:05 INFO - Running post-action listener: set_extra_try_arguments 01:34:05 INFO - [mozharness: 2017-02-09 09:34:05.251100Z] Finished download-and-extract step (success) 01:34:05 INFO - [mozharness: 2017-02-09 09:34:05.251227Z] Running populate-webroot step. 01:34:05 INFO - Running pre-action listener: _resource_record_pre_action 01:34:05 INFO - Running main action method: populate_webroot 01:34:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:34:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:34:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:34:05 INFO - '--tppagecycles', 01:34:05 INFO - '7'], 01:34:05 INFO - 'tests': ['tsvgm']}}, 01:34:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:34:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:34:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:34:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:34:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:34:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:34:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:34:05 INFO - 'talos_options': ['--disable-e10s'], 01:34:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:34:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:34:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:34:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:34:05 INFO - 'talos_options': ['--disable-e10s'], 01:34:05 INFO - 'tests': ['damp', 'tps']}, 01:34:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:34:05 INFO - 'tests': ['damp', 'tps']}, 01:34:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:34:05 INFO - 'tests': ['dromaeo_dom']}, 01:34:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:34:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:34:05 INFO - 'tests': ['basic_compositor_video']}, 01:34:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:34:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:34:05 INFO - 'tests': ['a11yr', 01:34:05 INFO - 'ts_paint', 01:34:05 INFO - 'tpaint', 01:34:05 INFO - 'sessionrestore', 01:34:05 INFO - 'sessionrestore_no_auto_restore', 01:34:05 INFO - 'tabpaint']}, 01:34:05 INFO - 'other-e10s': {'tests': ['a11yr', 01:34:05 INFO - 'ts_paint', 01:34:05 INFO - 'tpaint', 01:34:05 INFO - 'sessionrestore', 01:34:05 INFO - 'sessionrestore_no_auto_restore', 01:34:05 INFO - 'tabpaint']}, 01:34:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:34:05 INFO - 'ts_paint', 01:34:05 INFO - 'tpaint', 01:34:05 INFO - 'sessionrestore', 01:34:05 INFO - 'sessionrestore_no_auto_restore', 01:34:05 INFO - 'tabpaint']}, 01:34:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:34:05 INFO - 'ts_paint', 01:34:05 INFO - 'tpaint', 01:34:05 INFO - 'sessionrestore', 01:34:05 INFO - 'sessionrestore_no_auto_restore', 01:34:05 INFO - 'tabpaint']}, 01:34:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:34:05 INFO - 'tests': ['a11yr', 01:34:05 INFO - 'ts_paint', 01:34:05 INFO - 'tpaint', 01:34:05 INFO - 'sessionrestore', 01:34:05 INFO - 'sessionrestore_no_auto_restore', 01:34:05 INFO - 'tabpaint']}, 01:34:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:34:05 INFO - 'tests': ['a11yr', 01:34:05 INFO - 'ts_paint', 01:34:05 INFO - 'tpaint', 01:34:05 INFO - 'sessionrestore', 01:34:05 INFO - 'sessionrestore_no_auto_restore', 01:34:05 INFO - 'tabpaint']}, 01:34:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:34:05 INFO - 'tests': ['tsvgx', 01:34:05 INFO - 'tsvgr_opacity', 01:34:05 INFO - 'tart', 01:34:05 INFO - 'tscrollx', 01:34:05 INFO - 'cart', 01:34:05 INFO - 'tsvg_static']}, 01:34:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:34:05 INFO - 'tsvgr_opacity', 01:34:05 INFO - 'tart', 01:34:05 INFO - 'tscrollx', 01:34:05 INFO - 'cart', 01:34:05 INFO - 'tsvg_static']}, 01:34:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:34:05 INFO - 'talos_options': ['--disable-e10s'], 01:34:05 INFO - 'tests': ['tp5o']}, 01:34:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:34:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:34:05 INFO - 'talos_options': ['--disable-e10s', 01:34:05 INFO - '--xperf_path', 01:34:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:34:05 INFO - 'tests': ['tp5n']}, 01:34:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:34:05 INFO - 'talos_options': ['--xperf_path', 01:34:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:34:05 INFO - 'tests': ['tp5n']}}, 01:34:05 INFO - 'talos.zip': {'path': '', 01:34:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:34:05 INFO - Running post-action listener: _resource_record_post_action 01:34:05 INFO - [mozharness: 2017-02-09 09:34:05.269819Z] Finished populate-webroot step (success) 01:34:05 INFO - [mozharness: 2017-02-09 09:34:05.269958Z] Running create-virtualenv step. 01:34:05 INFO - Running pre-action listener: _resource_record_pre_action 01:34:05 INFO - Running main action method: create_virtualenv 01:34:05 INFO - Creating virtualenv /builds/slave/test/build/venv 01:34:05 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 01:34:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:34:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:34:05 INFO - Using real prefix '/tools/python27' 01:34:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:34:06 INFO - Installing distribute.............................................................................................................................................................................................done. 01:34:08 INFO - Installing pip.................done. 01:34:08 INFO - Return code: 0 01:34:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:34:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:34:09 INFO - Reading from file tmpfile_stdout 01:34:09 INFO - Output received: 01:34:09 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) 01:34:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:09 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 0x103afdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3f3584b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038dbd30>, '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 0x103802db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038d0be8>, '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.udtgeCDDp3/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.UcFI8EMwye/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 01:34:09 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 01:34:09 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 01:34:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udtgeCDDp3/Render', 01:34:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:09 INFO - 'HOME': '/Users/cltbld', 01:34:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:09 INFO - 'LOGNAME': 'cltbld', 01:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:09 INFO - 'MOZ_NO_REMOTE': '1', 01:34:09 INFO - 'NO_EM_RESTART': '1', 01:34:09 INFO - 'PAGER': '/bin/cat', 01:34:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:09 INFO - 'PWD': '/builds/slave/test', 01:34:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:09 INFO - 'SHELL': '/bin/bash', 01:34:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UcFI8EMwye/Listeners', 01:34:09 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 01:34:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:09 INFO - 'USER': 'cltbld', 01:34:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:09 INFO - 'XPC_FLAGS': '0x0', 01:34:09 INFO - 'XPC_SERVICE_NAME': '0', 01:34:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:09 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 01:34:09 INFO - Cleaning up... 01:34:09 INFO - Return code: 0 01:34:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:09 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 0x103afdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3f3584b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038dbd30>, '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 0x103802db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038d0be8>, '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.udtgeCDDp3/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.UcFI8EMwye/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 01:34:09 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 01:34:09 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 01:34:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udtgeCDDp3/Render', 01:34:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:09 INFO - 'HOME': '/Users/cltbld', 01:34:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:09 INFO - 'LOGNAME': 'cltbld', 01:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:09 INFO - 'MOZ_NO_REMOTE': '1', 01:34:09 INFO - 'NO_EM_RESTART': '1', 01:34:09 INFO - 'PAGER': '/bin/cat', 01:34:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:09 INFO - 'PWD': '/builds/slave/test', 01:34:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:09 INFO - 'SHELL': '/bin/bash', 01:34:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UcFI8EMwye/Listeners', 01:34:09 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 01:34:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:09 INFO - 'USER': 'cltbld', 01:34:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:09 INFO - 'XPC_FLAGS': '0x0', 01:34:09 INFO - 'XPC_SERVICE_NAME': '0', 01:34:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:09 INFO - Downloading/unpacking psutil>=3.1.1 01:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:34:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:34:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:34:13 INFO - Installing collected packages: psutil 01:34:13 INFO - Running setup.py install for psutil 01:34:13 INFO - building 'psutil._psutil_osx' extension 01:34:13 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 01:34:14 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 01:34:14 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 01:34:14 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 01:34:14 INFO - building 'psutil._psutil_posix' extension 01:34:14 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 01:34:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:34:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:34:14 INFO - ^ 01:34:14 INFO - 1 warning generated. 01:34:14 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 01:34:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:34:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:34:14 INFO - Successfully installed psutil 01:34:14 INFO - Cleaning up... 01:34:14 INFO - Return code: 0 01:34:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:34:14 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')]} 01:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:14 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')]} 01:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:14 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 0x103afdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3f3584b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038dbd30>, '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 0x103802db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038d0be8>, '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.udtgeCDDp3/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.UcFI8EMwye/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 01:34:14 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 01:34:14 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 01:34:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udtgeCDDp3/Render', 01:34:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:14 INFO - 'HOME': '/Users/cltbld', 01:34:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:14 INFO - 'LOGNAME': 'cltbld', 01:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:14 INFO - 'MOZ_NO_REMOTE': '1', 01:34:14 INFO - 'NO_EM_RESTART': '1', 01:34:14 INFO - 'PAGER': '/bin/cat', 01:34:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:14 INFO - 'PWD': '/builds/slave/test', 01:34:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:14 INFO - 'SHELL': '/bin/bash', 01:34:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UcFI8EMwye/Listeners', 01:34:14 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 01:34:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:14 INFO - 'USER': 'cltbld', 01:34:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:14 INFO - 'XPC_FLAGS': '0x0', 01:34:14 INFO - 'XPC_SERVICE_NAME': '0', 01:34:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:34:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:34:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:34:17 INFO - Installing collected packages: mozsystemmonitor 01:34:17 INFO - Running setup.py install for mozsystemmonitor 01:34:17 INFO - Successfully installed mozsystemmonitor 01:34:17 INFO - Cleaning up... 01:34:17 INFO - Return code: 0 01:34:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:17 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 0x103afdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3f3584b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038dbd30>, '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 0x103802db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038d0be8>, '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.udtgeCDDp3/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.UcFI8EMwye/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 01:34:17 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 01:34:17 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 01:34:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udtgeCDDp3/Render', 01:34:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:17 INFO - 'HOME': '/Users/cltbld', 01:34:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:17 INFO - 'LOGNAME': 'cltbld', 01:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:17 INFO - 'MOZ_NO_REMOTE': '1', 01:34:17 INFO - 'NO_EM_RESTART': '1', 01:34:17 INFO - 'PAGER': '/bin/cat', 01:34:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:17 INFO - 'PWD': '/builds/slave/test', 01:34:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:17 INFO - 'SHELL': '/bin/bash', 01:34:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UcFI8EMwye/Listeners', 01:34:17 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 01:34:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:17 INFO - 'USER': 'cltbld', 01:34:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:17 INFO - 'XPC_FLAGS': '0x0', 01:34:17 INFO - 'XPC_SERVICE_NAME': '0', 01:34:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:18 INFO - Downloading/unpacking jsonschema==2.5.1 01:34:18 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 01:34:18 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 01:34:18 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 01:34:18 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 01:34:18 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 01:34:18 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 01:34:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:34:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:21 INFO - Downloading functools32-3.2.3-2.tar.gz 01:34:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:34:21 INFO - warning: no files found matching '*.txt' 01:34:21 INFO - no previously-included directories found matching 'build' 01:34:21 INFO - no previously-included directories found matching 'dist' 01:34:21 INFO - no previously-included directories found matching '.git*' 01:34:21 INFO - Installing collected packages: jsonschema, functools32 01:34:21 INFO - Running setup.py install for functools32 01:34:21 INFO - warning: no files found matching '*.txt' 01:34:21 INFO - no previously-included directories found matching 'build' 01:34:21 INFO - no previously-included directories found matching 'dist' 01:34:21 INFO - no previously-included directories found matching '.git*' 01:34:21 INFO - Successfully installed jsonschema functools32 01:34:21 INFO - Cleaning up... 01:34:21 INFO - Return code: 0 01:34:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:34:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:21 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 0x103afdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3f3584b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038dbd30>, '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 0x103802db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038d0be8>, '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.udtgeCDDp3/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.UcFI8EMwye/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 01:34:21 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 01:34:21 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 01:34:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udtgeCDDp3/Render', 01:34:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:21 INFO - 'HOME': '/Users/cltbld', 01:34:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:21 INFO - 'LOGNAME': 'cltbld', 01:34:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:21 INFO - 'MOZ_NO_REMOTE': '1', 01:34:21 INFO - 'NO_EM_RESTART': '1', 01:34:21 INFO - 'PAGER': '/bin/cat', 01:34:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:21 INFO - 'PWD': '/builds/slave/test', 01:34:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:21 INFO - 'SHELL': '/bin/bash', 01:34:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UcFI8EMwye/Listeners', 01:34:21 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 01:34:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:21 INFO - 'USER': 'cltbld', 01:34:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:21 INFO - 'XPC_FLAGS': '0x0', 01:34:21 INFO - 'XPC_SERVICE_NAME': '0', 01:34:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:34:21 INFO - Cleaning up... 01:34:21 INFO - Return code: 0 01:34:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:34:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:21 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 0x103afdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3f3584b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038dbd30>, '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 0x103802db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038d0be8>, '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.udtgeCDDp3/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.UcFI8EMwye/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 01:34:21 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 01:34:21 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 01:34:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udtgeCDDp3/Render', 01:34:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:21 INFO - 'HOME': '/Users/cltbld', 01:34:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:21 INFO - 'LOGNAME': 'cltbld', 01:34:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:21 INFO - 'MOZ_NO_REMOTE': '1', 01:34:21 INFO - 'NO_EM_RESTART': '1', 01:34:21 INFO - 'PAGER': '/bin/cat', 01:34:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:21 INFO - 'PWD': '/builds/slave/test', 01:34:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:21 INFO - 'SHELL': '/bin/bash', 01:34:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UcFI8EMwye/Listeners', 01:34:21 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 01:34:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:21 INFO - 'USER': 'cltbld', 01:34:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:21 INFO - 'XPC_FLAGS': '0x0', 01:34:21 INFO - 'XPC_SERVICE_NAME': '0', 01:34:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:22 INFO - Downloading/unpacking blobuploader==1.2.4 01:34:22 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 01:34:22 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 01:34:22 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 01:34:22 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 01:34:22 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 01:34:22 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 01:34:24 INFO - Downloading blobuploader-1.2.4.tar.gz 01:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:34:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:34:24 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 01:34:24 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 01:34:24 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 01:34:24 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 01:34:24 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 01:34:24 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 01:34:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:34:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:34:25 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 01:34:25 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 01:34:25 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 01:34:25 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 01:34:25 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 01:34:25 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 01:34:25 INFO - Downloading docopt-0.6.1.tar.gz 01:34:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:34:25 INFO - Installing collected packages: blobuploader, requests, docopt 01:34:25 INFO - Running setup.py install for blobuploader 01:34:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:34:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:34:26 INFO - Running setup.py install for requests 01:34:26 INFO - Running setup.py install for docopt 01:34:26 INFO - Successfully installed blobuploader requests docopt 01:34:26 INFO - Cleaning up... 01:34:26 INFO - Return code: 0 01:34:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:34: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')]} 01:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34: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')]} 01:34:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:26 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 0x103afdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3f3584b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038dbd30>, '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 0x103802db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038d0be8>, '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.udtgeCDDp3/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.UcFI8EMwye/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 01:34:26 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 01:34:26 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 01:34:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udtgeCDDp3/Render', 01:34:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:26 INFO - 'HOME': '/Users/cltbld', 01:34:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:26 INFO - 'LOGNAME': 'cltbld', 01:34:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:26 INFO - 'MOZ_NO_REMOTE': '1', 01:34:26 INFO - 'NO_EM_RESTART': '1', 01:34:26 INFO - 'PAGER': '/bin/cat', 01:34:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:26 INFO - 'PWD': '/builds/slave/test', 01:34:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:26 INFO - 'SHELL': '/bin/bash', 01:34:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UcFI8EMwye/Listeners', 01:34:26 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 01:34:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:26 INFO - 'USER': 'cltbld', 01:34:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:26 INFO - 'XPC_FLAGS': '0x0', 01:34:26 INFO - 'XPC_SERVICE_NAME': '0', 01:34:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:34:27 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-1NSprR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:34:27 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-pTUKkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:34:27 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-3D1E3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:34:27 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-ZwhnMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:34:27 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-wPYjxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:34:27 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-9Mdlgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:34:27 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-e32BMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:34:27 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-NHFbKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:34:27 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-dAwKQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:34:28 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-d4BrRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:34:28 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-NcnAZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:34:28 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-yV0Xez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:34:28 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-ssAZ3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:34:28 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-mKKbl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:34:28 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-Z3rj5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:34:28 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-DwCE9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:34:28 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-KhXoBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:34:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:34:28 INFO - Running setup.py install for manifestparser 01:34:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:34:29 INFO - Running setup.py install for mozcrash 01:34:29 INFO - Running setup.py install for mozdebug 01:34:29 INFO - Running setup.py install for mozdevice 01:34:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:34:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:34:29 INFO - Running setup.py install for mozfile 01:34:29 INFO - Running setup.py install for mozhttpd 01:34:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:34:29 INFO - Running setup.py install for mozinfo 01:34:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:34:29 INFO - Running setup.py install for mozInstall 01:34:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:34:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:34:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:34:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:34:30 INFO - Running setup.py install for mozleak 01:34:30 INFO - Running setup.py install for mozlog 01:34:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:34:30 INFO - Running setup.py install for moznetwork 01:34:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:34:30 INFO - Running setup.py install for mozprocess 01:34:30 INFO - Running setup.py install for mozprofile 01:34:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:34:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:34:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:34:30 INFO - Running setup.py install for mozrunner 01:34:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:34:31 INFO - Running setup.py install for mozscreenshot 01:34:31 INFO - Running setup.py install for moztest 01:34:31 INFO - Running setup.py install for mozversion 01:34:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:34:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:34:31 INFO - Cleaning up... 01:34:31 INFO - Return code: 0 01:34:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:34:31 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')]} 01:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:31 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')]} 01:34:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:31 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 0x103afdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3f3584b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038dbd30>, '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 0x103802db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038d0be8>, '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.udtgeCDDp3/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.UcFI8EMwye/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 01:34:31 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 01:34:31 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 01:34:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udtgeCDDp3/Render', 01:34:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:31 INFO - 'HOME': '/Users/cltbld', 01:34:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:31 INFO - 'LOGNAME': 'cltbld', 01:34:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:31 INFO - 'MOZ_NO_REMOTE': '1', 01:34:31 INFO - 'NO_EM_RESTART': '1', 01:34:31 INFO - 'PAGER': '/bin/cat', 01:34:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:31 INFO - 'PWD': '/builds/slave/test', 01:34:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:31 INFO - 'SHELL': '/bin/bash', 01:34:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UcFI8EMwye/Listeners', 01:34:31 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 01:34:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:31 INFO - 'USER': 'cltbld', 01:34:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:31 INFO - 'XPC_FLAGS': '0x0', 01:34:31 INFO - 'XPC_SERVICE_NAME': '0', 01:34:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:34:31 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-scOATU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:34:31 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)) 01:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:34:31 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-xXj2t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:34:31 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)) 01:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:34:31 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-_Np5ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:34:32 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)) 01:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:34:32 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-9heU_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:34:32 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)) 01:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:34:32 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-uG_Dqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:34:32 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)) 01:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:34:32 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-vqtGlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:34:32 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)) 01:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:34:32 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-kVxpSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:34:32 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)) 01:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:34:32 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-Q8ZUpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:34:32 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)) 01:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:34:32 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-4apijM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:34:32 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)) 01:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:34:32 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-OgYMdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:34:32 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)) 01:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:34:32 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-jXdzNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:34:32 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)) 01:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:34:32 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-SRUB1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:34:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 01:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:34:33 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-2pMIrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:34:33 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)) 01:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:34:33 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-awtCdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:34:33 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)) 01:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:34:33 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-2SmNKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:34:33 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)) 01:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:34:33 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-gm_d2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:34:33 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)) 01:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:34:33 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-qDibe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:34:33 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)) 01:34:33 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)) 01:34:33 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)) 01:34:33 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)) 01:34:33 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)) 01:34:33 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)) 01:34:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:34:33 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 01:34:33 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 01:34:33 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 01:34:33 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 01:34:33 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 01:34:33 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 01:34:36 INFO - Downloading blessings-1.6.tar.gz 01:34:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:34:36 INFO - Installing collected packages: blessings 01:34:36 INFO - Running setup.py install for blessings 01:34:36 INFO - Successfully installed blessings 01:34:36 INFO - Cleaning up... 01:34:36 INFO - Return code: 0 01:34:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:34:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:34:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:34:36 INFO - Reading from file tmpfile_stdout 01:34:36 INFO - Current package versions: 01:34:36 INFO - blessings == 1.6 01:34:36 INFO - blobuploader == 1.2.4 01:34:36 INFO - docopt == 0.6.1 01:34:36 INFO - functools32 == 3.2.3-2 01:34:36 INFO - jsonschema == 2.5.1 01:34:36 INFO - manifestparser == 1.1 01:34:36 INFO - mozInstall == 1.12 01:34:36 INFO - mozcrash == 1.0 01:34:36 INFO - mozdebug == 0.1 01:34:36 INFO - mozdevice == 0.49 01:34:36 INFO - mozfile == 1.2 01:34:36 INFO - mozhttpd == 0.7 01:34:36 INFO - mozinfo == 0.9 01:34:36 INFO - mozleak == 0.1 01:34:36 INFO - mozlog == 3.4 01:34:36 INFO - moznetwork == 0.27 01:34:36 INFO - mozprocess == 0.24 01:34:36 INFO - mozprofile == 0.28 01:34:36 INFO - mozrunner == 6.13 01:34:36 INFO - mozscreenshot == 0.1 01:34:36 INFO - mozsystemmonitor == 0.3 01:34:36 INFO - moztest == 0.8 01:34:36 INFO - mozversion == 1.4 01:34:36 INFO - psutil == 3.1.1 01:34:36 INFO - requests == 1.2.3 01:34:36 INFO - wsgiref == 0.1.2 01:34:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:34:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34: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')]} 01:34:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:37 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 0x103afdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3f3584b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038dbd30>, '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 0x103802db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038d0be8>, '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.udtgeCDDp3/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.UcFI8EMwye/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 01:34:37 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 01:34:37 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 01:34:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udtgeCDDp3/Render', 01:34:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:37 INFO - 'HOME': '/Users/cltbld', 01:34:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:37 INFO - 'LOGNAME': 'cltbld', 01:34:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:37 INFO - 'MOZ_NO_REMOTE': '1', 01:34:37 INFO - 'NO_EM_RESTART': '1', 01:34:37 INFO - 'PAGER': '/bin/cat', 01:34:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:37 INFO - 'PWD': '/builds/slave/test', 01:34:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:37 INFO - 'SHELL': '/bin/bash', 01:34:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UcFI8EMwye/Listeners', 01:34:37 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 01:34:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:37 INFO - 'USER': 'cltbld', 01:34:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:37 INFO - 'XPC_FLAGS': '0x0', 01:34:37 INFO - 'XPC_SERVICE_NAME': '0', 01:34:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:37 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)) 01:34:37 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)) 01:34:37 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)) 01:34:37 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)) 01:34:37 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)) 01:34:37 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)) 01:34:37 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)) 01:34:37 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)) 01:34:37 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)) 01:34:37 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)) 01:34:37 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)) 01:34:37 INFO - Cleaning up... 01:34:37 INFO - Return code: 0 01:34:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:34: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')]} 01:34:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34: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')]} 01:34:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103afdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3f3584b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038dbd30>, '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 0x103802db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038d0be8>, '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.udtgeCDDp3/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.UcFI8EMwye/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 01:34:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:34:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:34:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udtgeCDDp3/Render', 01:34:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:38 INFO - 'HOME': '/Users/cltbld', 01:34:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:38 INFO - 'LOGNAME': 'cltbld', 01:34:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:38 INFO - 'MOZ_NO_REMOTE': '1', 01:34:38 INFO - 'NO_EM_RESTART': '1', 01:34:38 INFO - 'PAGER': '/bin/cat', 01:34:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:38 INFO - 'PWD': '/builds/slave/test', 01:34:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:38 INFO - 'SHELL': '/bin/bash', 01:34:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UcFI8EMwye/Listeners', 01:34:38 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 01:34:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:38 INFO - 'USER': 'cltbld', 01:34:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:38 INFO - 'XPC_FLAGS': '0x0', 01:34:38 INFO - 'XPC_SERVICE_NAME': '0', 01:34:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:34:38 INFO - Cleaning up... 01:34:38 INFO - Return code: 0 01:34:38 INFO - Running post-action listener: _resource_record_post_action 01:34:38 INFO - Running post-action listener: _start_resource_monitoring 01:34:38 INFO - Starting resource monitoring. 01:34:38 INFO - [mozharness: 2017-02-09 09:34:38.540917Z] Finished create-virtualenv step (success) 01:34:38 INFO - [mozharness: 2017-02-09 09:34:38.541849Z] Running install step. 01:34:38 INFO - Running pre-action listener: _resource_record_pre_action 01:34:38 INFO - Running main action method: install 01:34:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:34:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:34:38 INFO - Reading from file tmpfile_stdout 01:34:38 INFO - Detecting whether we're running mozinstall >=1.0... 01:34:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:34:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:34:38 INFO - Reading from file tmpfile_stdout 01:34:38 INFO - Output received: 01:34:38 INFO - Usage: mozinstall [options] installer 01:34:38 INFO - Options: 01:34:38 INFO - -h, --help show this help message and exit 01:34:38 INFO - -d DEST, --destination=DEST 01:34:38 INFO - Directory to install application into. [default: 01:34:38 INFO - "/builds/slave/test"] 01:34:38 INFO - --app=APP Application being installed. [default: firefox] 01:34:38 INFO - mkdir: /builds/slave/test/build/application 01:34:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:34:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:34:58 INFO - Reading from file tmpfile_stdout 01:34:58 INFO - Output received: 01:34:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:34:58 INFO - Running post-action listener: _resource_record_post_action 01:34:58 INFO - [mozharness: 2017-02-09 09:34:58.848914Z] Finished install step (success) 01:34:58 INFO - [mozharness: 2017-02-09 09:34:58.849078Z] Running run-tests step. 01:34:58 INFO - Running pre-action listener: _resource_record_pre_action 01:34:58 INFO - Running main action method: run_tests 01:34:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:34:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:34:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:34:58 INFO - 2017-02-09 01:34:58.863 screenresolution[1625:7410] starting screenresolution argv=screenresolution get 01:34:58 INFO - 2017-02-09 01:34:58.866 screenresolution[1625:7410] Display 0: 1600x1200x32@60 01:34:58 INFO - 2017-02-09 01:34:58.872 screenresolution[1626:7413] starting screenresolution argv=screenresolution list 01:34:58 INFO - Available Modes on Display 0 01:34:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:34:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:34:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:34:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:34:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:34:59 INFO - Intel Iris: 01:34:59 INFO - Chipset Model: Intel Iris 01:34:59 INFO - Type: GPU 01:34:59 INFO - Bus: Built-In 01:34:59 INFO - VRAM (Dynamic, Max): 1536 MB 01:34:59 INFO - Vendor: Intel (0x8086) 01:34:59 INFO - Device ID: 0x0a2e 01:34:59 INFO - Revision ID: 0x0009 01:34:59 INFO - Displays: 01:34:59 INFO - Display: 01:34:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:34:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:34:59 INFO - Display Serial Number: 1600x1200 60 01:34:59 INFO - Main Display: Yes 01:34:59 INFO - Mirror: Off 01:34:59 INFO - Online: Yes 01:34:59 INFO - Rotation: Supported 01:34:59 INFO - Return code: 0 01:34:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:34:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:34:59 INFO - Python 2.7.3 01:34:59 INFO - Return code: 0 01:34:59 INFO - grabbing minidump binary from tooltool 01:34: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')]} 01:34:59 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 0x1038dbd30>, '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 0x103802db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038d0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:34:59 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 01:34:59 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 01:34:59 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 01:34:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:34:59 INFO - Return code: 0 01:34:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:34:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:34:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:34:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:34:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:34:59 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/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0102', '--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 01:34:59 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/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0102 --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 01:34:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udtgeCDDp3/Render', 01:34:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:59 INFO - 'HOME': '/Users/cltbld', 01:34:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:59 INFO - 'LOGNAME': 'cltbld', 01:34:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:34:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:34:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:59 INFO - 'MOZ_NO_REMOTE': '1', 01:34:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:34:59 INFO - 'NO_EM_RESTART': '1', 01:34:59 INFO - 'PAGER': '/bin/cat', 01:34:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:59 INFO - 'PWD': '/builds/slave/test', 01:34:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:34:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:59 INFO - 'SHELL': '/bin/bash', 01:34:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UcFI8EMwye/Listeners', 01:34:59 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 01:34:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:59 INFO - 'USER': 'cltbld', 01:34:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:59 INFO - 'XPC_FLAGS': '0x0', 01:34:59 INFO - 'XPC_SERVICE_NAME': '0', 01:34:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:59 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/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0102', '--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 01:34:59 INFO - mozversion application_buildid: 20170209004121 01:34:59 INFO - mozversion application_changeset: eae7e45deb55defb86c7c30fdfcf20c5d017b98d 01:34:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:34:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:34:59 INFO - mozversion application_name: Firefox 01:34:59 INFO - mozversion application_remotingname: firefox-dev 01:34:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:34:59 INFO - mozversion application_vendor: Mozilla 01:34:59 INFO - mozversion application_version: 53.0a2 01:34:59 INFO - mozversion platform_buildid: 20170209004121 01:34:59 INFO - mozversion platform_changeset: eae7e45deb55defb86c7c30fdfcf20c5d017b98d 01:34:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:34:59 INFO - mozversion platform_version: 53.0a2 01:34:59 INFO - using testdate: 1486632899 01:34:59 INFO - actual date: 1486632899 01:34:59 INFO - starting webserver on 'localhost:49242' 01:34:59 INFO - SUITE-START | Running 2 tests 01:34:59 INFO - TEST-START | dromaeo_css 01:34:59 INFO - operating with platform_type : mac_ 01:34:59 INFO - Initialising browser for dromaeo_css test... 01:34:59 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpD9k0hl/profile http://localhost:49242/getInfo.html) 01:35:01 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 01:35:01 INFO - PROCESS | 1636 | colorDepth:24 01:35:01 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 01:35:01 INFO - PROCESS | 1636 | __metrics 01:35:01 INFO - PROCESS | 1636 | 1486632901979 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 01:35:02 INFO - PROCESS | 1636 | 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" 01:35:02 INFO - PROCESS | 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:35:02 INFO - TEST-INFO | 1636: exit 0 01:35:02 INFO - Browser initialized. 01:35:02 INFO - Running cycle 1/1 for dromaeo_css test... 01:35:02 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpD9k0hl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:35:14 INFO - PROCESS | 1638 | RSS: Main: 213000192 01:35:14 INFO - PROCESS | 1638 | 01:38:18 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 01:38:20 INFO - PROCESS | 1638 | RSS: Main: 332185600 01:38:20 INFO - PROCESS | 1638 | 01:40:04 INFO - PROCESS | 1638 | ************************* 01:40:04 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 01:40:04 INFO - PROCESS | 1638 | 01:40:04 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:40:04 INFO - PROCESS | 1638 | Full stack: 01:40:04 INFO - PROCESS | 1638 | ************************* 01:41:23 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 01:41:23 INFO - PROCESS | 1638 | RSS: Main: 349634560 01:41:23 INFO - PROCESS | 1638 | 01:44:26 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 01:44:26 INFO - PROCESS | 1638 | RSS: Main: 322097152 01:44:26 INFO - PROCESS | 1638 | 01:47:29 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 01:47:29 INFO - PROCESS | 1638 | RSS: Main: 338673664 01:47:29 INFO - PROCESS | 1638 | 01:50:33 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 01:50:33 INFO - PROCESS | 1638 | RSS: Main: 315138048 01:50:33 INFO - PROCESS | 1638 | 01:53:37 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 01:53:37 INFO - PROCESS | 1638 | RSS: Main: 459022336 01:53:37 INFO - PROCESS | 1638 | 01:53:37 INFO - PROCESS | 1638 | __start_tp_report 01:53:37 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 01:53:37 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 01:53:37 INFO - PROCESS | 1638 | |i|pagename|runs| 01:53:37 INFO - PROCESS | 1638 | |0;dojo.html;3407.4888766685;3410.300888317895;3434.7423943204267;3437.2438063625996;3480.095175254433;5806.100054491555;5830;5846.590738648296;5848.649081055129;5858.912116318259;5724.62789918655;5820.621659592123;5873.970630146854;5880.206172166767;5889.116632505126;5282.313299271099;5297.973510132444;5362;5377.059014672436;5407.297051383334;4274;5206.557442617382;5238.345206849144;5277.8944421111555;5409.0263752524725;4313.547077556858;5384.892302153955;5390.460953904598;5390.73046347683;5404.32445944257;5370.838874833761;5388.488093631099;5390.973045134789;5413.891722165555;5414.648047876875;5141.922871156936;5234.18870075139;5306.6550674206055;5315.362156541201;5370.946290537084;5700;5776.971115144397;5787.3923238059815;5799.2750906136735;5805.10020946754;6142.385761423867;6154.015357542817;6157.876842463171;6163.630182189038;6184.938150618481;6228.564000519966;6277.340879207709;6277.591956522811;6293.842653933642;6345.650989195592;6019.759209631653;6022.850438016457;6025.9096113558335;6032.426919442646;6053.273607167124;6414.262359828679;6430.164078669743;6434.549581529248;6458.418742313121;6464.095026696266;6132.78385080153;6137.539684523678;6142.972818969729;6162.907556386658;6204.410580994799;6313.905291420632;6349.2698339690605;6350.396712312323;6364.363563643598;6370.968145159337;6263.937360626426;6270.623762574261;6270.7805226816845;6272.937270627326;6276.592021518587;6143.419763699168;6177.135201071764;6185.474234690012;6287.2140982377205;6289.465395441439;5060.01329740697;5101.69389836611;5115.795368185305;5142.177251639794;5143.996920600447;4501.909961800746;4517.412736344254;4520.095980803852;4520.141173177151;4552.339910712928;5775.971120144456;5954.791582294599;6002.189704389939;6020.127081573148;6027.156198132265;6206.503479721611;6208.441240288395;6222.315545290015;6246.6876656167005;6294.055891616203;5722.313322401255;5941.55438342126;5951.9107213391835;5968.403159683997;5983.910241346383;4710.69380490267;5454.972725136428;5489.917651235235;5518.172274158828;5544.112941929272;6403.96798016007;6442.38797314245;6476.417122459001;6496.187976502937;6503.122078519528;6158.3225845157895;6212.409821066992;6218.595791273553;6263.060540918898;6271.96864015677;6498.252700939453;6545.443637290693;6554.901676474952;6558.409743123142;6570.408663220333;6266.530010249158;6279.497640188865;6301.117843501822;6311.0217916223055;6312.5581209314905;6207.6585787782105;6240.126382306572;6265.655388953469;6267.2479302484;6269.937300627118;6062.636241825505;6135.110179431545;6253.687315634109;6253.8749225014335;6275.6548389839;6186.659733714689;6189.257289125334;6239.719212635351;6250.156228909221;6253.6560489173535;5920.644761314335;5941.613795103304;6110.7861224856915;6138.1713468681155;6165.229346331708;6168.845778855564;6181.381861813783;6204.5036397089025;6279.497640188682;6283.057541368831;4950.504949505164;5444.210589464585;6119.265688117454;6122.938770612237;6141 01:53:37 INFO - PROCESS | 1638 | |1;ext.html;507.9771410286537;509.49559935663706;510.7471801458279;517.8757098296408;547.463485783932;39788;42475.57524424755;42494.78752606236;42605.573944260585;44303.77848110763;42872.9281767956;43439.565604343945;43441.56558434415;43463.565364346345;43490.130197396036;8427.157284271585;9118.316126290521;9266.629334826599;9317.767055823591;9320.02139775324;8490.830183396329;11296.378699171542;11512.42437878107;11528.827067593993;11693.70765730855;11271;11292;11330.830037549402;11362.31826090433;11363.602273920415;8520.062793092755;8736.8689469658;11265.887341126607;11325.490352934137;11338.659840204808;11088.944555277172;11373.829392559119;11394.487248073856;11506.367149806758;11562.479688414041;34037.82981085079;40727;41692.791536042125;41949.79025104886;41968.580314197075;3847.0368632774766;3913.8515360227466;3916.980415097906;3956.5054368203973;4028.811500607328;29749.851250743606;29902.85048574765;30241;31303;31508.842455787802;3246.594175728034;3248.772585918987;3257.38109759145;3304.520844477537;3316.701496865294;459654;460710;461388;461627;463335.6833215846;60039;60845.39154608397;61206;61284.69357653272;61424;65793.67103164454;65873.67063164653;65875.67062164658;65976.67011664911;65998.67000665062;16659.66680666404;16921.323147074225;16930;16937.407190748265;16953.067581283147;11663.825042624367;11970.623378311606;11970.982466490446;12974.675633109247;13040.80438793419;16515.91742041282;16799.748003779954;16973.81183317156;17197.828021719874;17410.39063632792;5326.041312563776;5543.390227075019;6503.414692677682;6600.471962243009;6623.7350505980185;868.5613765048736;876.1326286975848;878.3368556739621;893.1068931068932;903.408267584733;29932.850335748182;30062;30258;30513.48981787746;30735.23161920925;950.8019894932435;1070.277562645227;1073.5920350267004;1114.5820317380983;1193.3138445393934;15822.446214382442;15892.887497875037;15961.914595764436;15986.120763358127;16165.191740412933;196129;239608;240547;241596.7920160423;587845;61337.693311533156;61374.772504549655;61557.692211538655;61577;61747.691261543405;1422.7581311177125;1432.0333774702035;1461.8099647045815;1465.157534417714;1467.4350375105662;29071.273218169714;29083.41833163367;29691.812327506657;30283.394332113676;31193;40377;43675.12649747051;51271.69277382601;51309.743451282506;51406.971860563324;520638;522034;522618;523552;524357;34847.65152348545;40751.79624101861;41216.79391603023;41334;41666.79166604148;37490.25019499649;48609.80101513762;48703.756481217366;50553.49446505635;50993;33446;39720.00699982524;40105.79947100246;40609.207890199476;41711.58288417077;40099;40562;40972;41826.58173418227;41847.1630567393;37529.560578562305;37576.43635345527;38465.80767096147;38778.80610596929;38942.80528597339;33589.83205083959;34811.65188348084;35647.64352356547;35658.82170589131;35686.46470303;25411.872940635178;25562.48875022452;25765.613515797653;26045;26122.085727000223 01:53:37 INFO - PROCESS | 1638 | |2;jquery.html;340.3142667524938;392.42705649751343;402.157480079234;402.58131543195077;426.1647171543774;13307.804212304958;19662.016899155038;20081.39755807328;20877.37367878964;20879.164833406663;16643.583910402245;20458.590828183427;21386.572268554657;21526.677099843477;21666.674999875013;5650.180723795047;6041.697915104249;6044.879102417949;6045.486133678621;6052.182955301044;5733.655980641175;5908.67502287374;5914.733836977346;5984.97007514964;6028.909566356486;5087.440381558025;5921.526277897779;5930.495907847817;5936;5958.52331813456;5650.491455768959;5810.331811841608;5849;5902.291724993029;5912.763489460416;5212.452692467312;5222.817201397971;5226.137687281594;5228.660137091077;5236.659617124875;25332.62001069967;25989.960401583914;27092.322691932663;27266.591001135;27591.86204068967;3096.4581198290207;3097.643770966345;3100.286934005169;3114.7663925205475;3115.2367669921014;15924.442644507502;16232.513024609281;16284.348626054943;16314;16543.91728041364;1598.776171336014;1838.6046999895086;1881.7271495633192;1885.604023155139;1912.3211260002638;125597;128593.35703321517;134204.3289783545;137257;141529;23519.41201469977;23863.329566930148;24257.14857028601;24337.14819981292;24541.63187552188;34936;42376.78811605922;42387.57612423898;43675.56324436778;43700.78149609231;11423.714407139889;11517.884821151682;11704.297742135499;11759.47082381295;11893.881061189277;7497.550146991198;9239.676611318571;9253.72238832836;9451.952740236393;9457.38526995757;9376.812463750686;10870.239083264096;11165.218434709655;11212.66362009141;11400.315981041163;4116.217918595457;4191.91616167675;4192.436429035357;4217.7891105444605;4229.429027081304;1005.7183988483237;1050.3907733514543;1057.6245432871374;1098.0392156862745;1101.130107215307;15433.768493472608;19966.30117945865;20474.566780325233;21113.66590022987;21384.679229811565;968.8595153702673;1056.3820165203288;1058.3015209961388;1076.4832880217539;1084.5444913136503;10501.579936802595;11299.604513841976;11301.869813018633;11344.262622929484;11582.826257606142;758712;775097;781298;790561;801459;133373;136554;145561;155459;158786;1000.3497726477702;1001.9248556358302;1011.8279892418159;1018.8624535687735;1019.454591793387;18082.186301616723;18737.250509979993;19023.714644280622;19103;19162.71255931134;57532.42467575271;57537.42462575321;58734.70632646809;59595.40404595899;59896.40103598908;85101.72347414917;85146.57426712826;86117.13882861091;86510.56744716236;89941.55029224812;8247.134050924806;8310.335173186251;8590.957045214735;8611.224989750952;8636.611352489028;10623.575056997943;12438.19151755133;12989.480420783062;13654.658633534242;13797.862021379658;11406.461134961672;11755;11812.114091443347;11818.468168932246;11842.171048026556;7796.805079872822;11390;11572.6528204154;12027.639170824888;12081.335526546125;11038.889611104107;11175.49710263024;11780.882191178322;11802.350870702194;11994.460249288628;7182.4972251943955;8453.112423195476;8624.439411438236;8773.122687731176;8857.601407936529;10653.414062226651;11345.829812552984;11440.313581185155;11722.062235021349;11880.881191187977 01:53:37 INFO - PROCESS | 1638 | |3;mootools.html;1238.8203710461983;1424.5299051313068;1450.0212356659256;1450.5285782120814;1455.3741890986878;6720.428763555097;8459.984801823774;8469.703560375388;8484.830303393928;8497.575121243935;7431.256874312566;7700.383969282444;7727.995360603128;8046.356291496681;8293.91706082939;2327.150590034638;2364.5980183368783;2369.230000249917;2371.0515793682493;2372.2527403867725;2016.68741345091;2351.870647114408;2385.272491889973;2393.0308225168747;2395.856248625088;2040.387883634911;2118.8834614096218;2266.422062374092;2324.6513023046596;2350.8942097605564;2321.570509455756;2324.070371851256;2327.069172331073;2327.2552783109413;2329.918452854159;2281.53228588139;2341.5433990371885;2367.7040369953756;2379.464620460383;2381.7975472084895;8400.621972011275;8479.533625650587;8482.703105391343;8485.745427637181;8507.702230421968;5124.692518448905;5272.578193744491;5274.2879711238875;5284.471552844685;5287.07476191665;3386.6613338666184;3550.5029295898594;3551.2897420516;3614.638536146391;3616.8553257869653;1173.4367503598235;1213.8907498325104;1217.190568272098;1263.3178083834719;1272.9809052864214;9847.852282215772;10145.64490242838;10965.287256328393;10980.670579882617;11006.504707288112;4494.191045611756;4686.484486706459;4690.343351930733;4744.478107408182;4779.832705855279;9371.48456834867;9577.281703872237;9588.712338629852;9603.567839447242;9646.083622055894;5276.577873770089;5278.498542638443;5283.920741188886;5286.841394758163;5291.232771248147;3310.9834450827593;4432.339849234693;4447.132809102259;4734.360861283752;4739.052189562101;3870.5161854768153;3876.96123038766;3892.863749768801;3984.083660758042;4001.979990100031;1264.4246867675186;1272.0459655258555;1279.2388528825334;1293.140061858872;1295.2746461981321;715.7959981405379;847.0978407995465;903.5075883643431;909.5770466733001;913.7441516375426;4822.397200349957;5404.108322126805;5433.701146436984;5588.6646801192055;5608.747606357723;978.5107446276861;996.1383403356045;1003.433060320931;1007.9193664506822;1026.820306446369;3714.8669655754716;3933.449317095609;3988.720789544704;4009.0578714002186;4029.8388064477094;9761.511924403894;9776;9946.254030947708;9978.002199780109;9982.35114717541;9484.762880928032;9617.374870633386;9892.25808064398;9896.802063958681;9928.106470417697;7585.886211706828;7702.845943081107;7769.028871391076;7845.450818442769;7972.123066462685;5267.7366131694025;5333.7866485340155;5394.703291319015;5623.550115990793;5655.1517272409465;9053.637854485747;9283.582238799405;9288.767780805534;9320.860187097334;9344.392614480037;8834.249088827264;9220.769480763034;9229.676961306322;9303.813923721624;9340.53297335117;6844.110265665432;7054;7130.2156762757095;7369.668364923698;7518.548887066575;7634.122075961335;7777.144514103557;7880.05439347282;7983.281504664608;7985.960070199611;5977.581569290108;7008.439324853897;7049.330313620198;7156.105486814148;7161.785146445615;6890.035395044798;6991.475639327173;7005.474589405917;7026.367626913602;7050.823729406806;6197;6384.904226436514;6452.12896258999;6457.644829534421;6479.157709497735;4273.91452170961;4939.48135445773;4965.379327584052;4966.528179822911;5023.497650234947;4942;4948.430930443045;4968.527989840959;4995.775190116381;5111.667741596784 01:53:37 INFO - PROCESS | 1638 | |4;prototype.html;353.8991387454575;360.6303538872655;362.3911828128744;374.29819089207734;375.0960185951857;5444;5474.425185355535;5501.779928802848;5507.80722674706;5529.175372127198;5064.467766116942;5186.688798672082;5228.620977455258;5255.947440525593;5379.946200537994;3546.982265088677;4228.181881790829;4273.546994022032;4275.957240427587;4285;3981.889598512793;4181.623653871151;4232.449781528412;4243.469566304212;4270.338097594878;3828.7365169494;4067.715259931806;4116.855910043149;4119.567445418218;4263.488381394237;4076.8369265229353;4087.4073259377224;4089.3865920111925;4089.8119506064113;4101.9179616407655;3509.491123787062;3511.087117349482;3523.7885726856216;3524.1189702574356;3534.028142260873;3864.7487913285827;4350.521442641307;4802.783874725646;5248.343957005374;5354.848707527861;3866;4123.525794533637;4182.870624931282;4625.745583992879;4667.183242932473;4727.7163370197895;4832.323474713543;5300.416954135042;5650.643845477097;5708.80019199326;3480.095175254427;3931.606839316074;3947.756456716154;3956.7032472564674;3960.584138665456;5579.414161513023;5590.944090559124;5598.468145526209;5948.881022379528;6004.54965877561;3784.0918179636687;3894.2406230784727;3906.921861562753;3913.5499417566775;3920.8039598019986;3974.7615143091507;3981.6217459341315;3996;4000.7199496035;4000.8199631016673;4349.652027837766;4614.723116612947;4640.91158978461;4649.651276154302;4707.882302942386;5488.7530061147345;5777.62445441045;5798.82603521895;5805.419458054245;5890.705464726747;1104.6023431564631;1248.4694005047818;1253.6615113919372;1318.7692153873033;1334.9199048056953;833.7040350675544;841.3731769831393;868.8834847221291;880.872273520336;898.6405437824792;4631.536846315409;4658.045100754405;4903.681260718042;5152.269297272452;5180.481951804864;5248.029114613809;5475.835724928259;5592.105263157875;5676.943230567724;5696.9490983246;4623.884402889955;4910.926336104962;5286.233496143038;5438.945610543923;5445.809396671098;2664;2945.659724825199;2955.8274668118916;2967.3620171662765;3006.8195908245575;5080.958403527268;5416.756245968941;5497.725113744297;5724.856878577985;5820.883582328414;5650.660960342392;5667.489925906606;5735.799247026334;5806.9419305806405;5810.651360918274;5155.690658560498;5194.558462530727;5650.915236271459;5676.7009361208775;5903.291605007426;4933.6299777516115;5014.5737612303365;5244.564810049424;5310.468953104659;5650.29371328584;4824.831130910401;4913.361263035782;5487.451254874481;5535.197396377584;5569.33168019824;5394.32570928634;5688.886222275614;5859.970700146472;5870.945169190749;5886.175935368966;3561.8219089044924;3695.981520092382;3699.741018128705;3704.370257056361;3745.4943582615997;5019.949800501949;5182.222666600041;5183.740812959412;5574.721263936706;5641.40765219646;4555.476120246081;4661.114388266218;5033.188052301241;5395.487428694268;5418.539424148991;5197.480251974772;5402.72986350673;5746.1676398454765;5749.568782341257;5756.812411283686;5362.356517217959;5370.624056316176;5472.890542189054;5516;5535.5848311376;5425.538829199562;5754.856128596819;5759.337676167127;5779.890340025944;5809.448102430262;4954.266006897557;5304.708241046779;5416.918746218731;5649.01142300104;5670.404607516151 01:53:37 INFO - PROCESS | 1638 | |5;yui.html;706.7314420520208;707.395177123559;717.849251657152;757.4394947738675;838.8699751538512;14791.334389952452;15020.849791502082;15401.614959625987;15425.922870385646;15648.687026259468;13224.140430871987;14439.350229239682;14619.488317908848;14636.634084147901;14639.926800365984;658.26932105363;679.0595025889154;847.2120927537388;848.2365870716374;862.180928118287;678.0710426715407;827.6482494939627;855.5422848775906;858.8196478739472;862.2153840005565;911.3529394130176;914.1087439746261;914.2822884036033;915.1260546178416;915.6566287642135;696.4846013949691;718.8310746974457;725.1551941987578;910.6493999810087;912.4160537256129;534.5429657642737;535.8499620106337;652.3118110393533;670.4100391655361;678.494521581425;14352.06711563745;15050.698986020328;15113.244337783177;15137.924310378488;15143.924280378527;12820.807687884597;12929.48282068716;13023.413946372437;13273.00452466069;13576.59270221895;12327.815082773766;12333.153767881748;12681.302528360937;12912.935435322857;12987.285699286536;4205.663546916239;4308.159908817782;4532;4535.183666939983;4603.447586289667;102715;108089;113668;115448;115586;10317.380957142595;10637.734056648645;10675.25273230882;10842.512086956105;10968;20890.791092089188;20920.790792091884;20922.95385230763;20938.162473500888;20979.055942482624;7310.232425595343;7525.774226773206;7624.69501219945;7797.92202077972;7850.921490785019;4088.9591104088577;4108.342665173558;4141.967584167996;4149.585041495887;4207.852725154605;3462.307538492312;3527.3474407234744;3531.046617413284;3539;3557.48416479609;541.9945800542023;642.8071578526424;652.8400541867178;654.9815037616552;656.4485831901226;541.2855031358569;554.6699713670359;557.5205323421854;580.7241213574721;587.7502061623793;9642.298602471355;10235.181185505142;10285;10342.327748696462;10510;544.8311023582702;558.3160628230467;588.1501230721566;625.5660531434178;715.7316006497564;4969.875753106201;5273.306129769898;5402.243685883979;5517.724113794295;5715.428457154251;88542.55728721315;98448.01551984531;99938.50030749945;103578;115348.84651153549;68026.8163797881;73065.6346718263;79934;91007;91659.62510562348;71368.28631713823;77461.6126919373;78639.21360786434;78672;78699;9220.400673956172;10363.952512291413;10578.682639520826;10766.138708903112;10820.404877731486;45935.77032114818;46839.29741053955;48888.02223955572;49286.753566233376;49984.50046498611;60794;61409.77180456455;61719.765604686756;63008;65780.34219657742;12557.937210314256;13055.412506437044;13151.34243287851;13179.14335568185;13274.601761947157;13689.041767076209;13963.4414623418;13999.300034998005;14765.335559899615;15216.771748423562;12710.872891270968;13050.216986980431;13150.73698526005;13291.335433228107;13303.534376296782;13458.990575706664;13643.931780341034;13669.453221871014;13875;14094.788578171541;12578.99368050572;12776;12803.487860485477;13430.19418834873;13705.520306789613;10936.398498082683;11232.494537745984;11321.830172547254;11691.590794322157;11814.822777658514;10542.314749541561;10652.09457196116;10671.786564268516;11058.39178845139;11400.429978500875 01:53:37 INFO - PROCESS | 1638 | __end_tp_report 01:53:37 INFO - PROCESS | 1638 | __start_cc_report 01:53:37 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,1765 01:53:37 INFO - PROCESS | 1638 | __end_cc_report 01:53:37 INFO - PROCESS | 1638 | __startTimestamp1486634017338__endTimestamp 01:53:37 INFO - PROCESS | 1638 | 01:53:37 INFO - PROCESS | 1638 | ------- Summary: start ------- 01:53:37 INFO - PROCESS | 1638 | Number of tests: 6 01:53:37 INFO - PROCESS | 1638 | 01:53:37 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5829.80 Median:6122.94 stddev:659.81 (10.8%) stddev-sans-first:633.87 01:53:37 INFO - PROCESS | 1638 | Values: 3407.5 3410.3 3434.7 3437.2 3480.1 5806.1 5830.0 5846.6 5848.6 5858.9 5724.6 5820.6 5874.0 5880.2 5889.1 5282.3 5298.0 5362.0 5377.1 5407.3 4274.0 5206.6 5238.3 5277.9 5409.0 4313.5 5384.9 5390.5 5390.7 5404.3 5370.8 5388.5 5391.0 5413.9 5414.6 5141.9 5234.2 5306.7 5315.4 5370.9 5700.0 5777.0 5787.4 5799.3 5805.1 6142.4 6154.0 6157.9 6163.6 6184.9 6228.6 6277.3 6277.6 6293.8 6345.7 6019.8 6022.9 6025.9 6032.4 6053.3 6414.3 6430.2 6434.5 6458.4 6464.1 6132.8 6137.5 6143.0 6162.9 6204.4 6313.9 6349.3 6350.4 6364.4 6371.0 6263.9 6270.6 6270.8 6272.9 6276.6 6143.4 6177.1 6185.5 6287.2 6289.5 5060.0 5101.7 5115.8 5142.2 5144.0 4501.9 4517.4 4520.1 4520.1 4552.3 5776.0 5954.8 6002.2 6020.1 6027.2 6206.5 6208.4 6222.3 6246.7 6294.1 5722.3 5941.6 5951.9 5968.4 5983.9 4710.7 5455.0 5489.9 5518.2 5544.1 6404.0 6442.4 6476.4 6496.2 6503.1 6158.3 6212.4 6218.6 6263.1 6272.0 6498.3 6545.4 6554.9 6558.4 6570.4 6266.5 6279.5 6301.1 6311.0 6312.6 6207.7 6240.1 6265.7 6267.2 6269.9 6062.6 6135.1 6253.7 6253.9 6275.7 6186.7 6189.3 6239.7 6250.2 6253.7 5920.6 5941.6 6110.8 6138.2 6165.2 6168.8 6181.4 6204.5 6279.5 6283.1 4950.5 5444.2 6119.3 6122.9 6141.0 01:53:37 INFO - PROCESS | 1638 | 01:53:37 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:59346.04 Median:3915.42 stddev:118765.33 (3033.3%) stddev-sans-first:119016.33 01:53:37 INFO - PROCESS | 1638 | Values: 508.0 509.5 510.7 517.9 547.5 39788.0 42475.6 42494.8 42605.6 44303.8 42872.9 43439.6 43441.6 43463.6 43490.1 8427.2 9118.3 9266.6 9317.8 9320.0 8490.8 11296.4 11512.4 11528.8 11693.7 11271.0 11292.0 11330.8 11362.3 11363.6 8520.1 8736.9 11265.9 11325.5 11338.7 11088.9 11373.8 11394.5 11506.4 11562.5 34037.8 40727.0 41692.8 41949.8 41968.6 3847.0 3913.9 3917.0 3956.5 4028.8 29749.9 29902.9 30241.0 31303.0 31508.8 3246.6 3248.8 3257.4 3304.5 3316.7 459654.0 460710.0 461388.0 461627.0 463335.7 60039.0 60845.4 61206.0 61284.7 61424.0 65793.7 65873.7 65875.7 65976.7 65998.7 16659.7 16921.3 16930.0 16937.4 16953.1 11663.8 11970.6 11971.0 12974.7 13040.8 16515.9 16799.7 16973.8 17197.8 17410.4 5326.0 5543.4 6503.4 6600.5 6623.7 868.6 876.1 878.3 893.1 903.4 29932.9 30062.0 30258.0 30513.5 30735.2 950.8 1070.3 1073.6 1114.6 1193.3 15822.4 15892.9 15961.9 15986.1 16165.2 196129.0 239608.0 240547.0 241596.8 587845.0 61337.7 61374.8 61557.7 61577.0 61747.7 1422.8 1432.0 1461.8 1465.2 1467.4 29071.3 29083.4 29691.8 30283.4 31193.0 40377.0 43675.1 51271.7 51309.7 51407.0 520638.0 522034.0 522618.0 523552.0 524357.0 34847.7 40751.8 41216.8 41334.0 41666.8 37490.3 48609.8 48703.8 50553.5 50993.0 33446.0 39720.0 40105.8 40609.2 41711.6 40099.0 40562.0 40972.0 41826.6 41847.2 37529.6 37576.4 38465.8 38778.8 38942.8 33589.8 34811.7 35647.6 35658.8 35686.5 25411.9 25562.5 25765.6 26045.0 26122.1 01:53:37 INFO - PROCESS | 1638 | 01:53:37 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:43096.28 Median:21596.68 stddev:129508.29 (599.7%) stddev-sans-first:129831.80 01:53:37 INFO - PROCESS | 1638 | Values: 340.3 392.4 402.2 402.6 426.2 13307.8 19662.0 20081.4 20877.4 20879.2 16643.6 20458.6 21386.6 21526.7 21666.7 5650.2 6041.7 6044.9 6045.5 6052.2 5733.7 5908.7 5914.7 5985.0 6028.9 5087.4 5921.5 5930.5 5936.0 5958.5 5650.5 5810.3 5849.0 5902.3 5912.8 5212.5 5222.8 5226.1 5228.7 5236.7 25332.6 25990.0 27092.3 27266.6 27591.9 3096.5 3097.6 3100.3 3114.8 3115.2 15924.4 16232.5 16284.3 16314.0 16543.9 1598.8 1838.6 1881.7 1885.6 1912.3 125597.0 128593.4 134204.3 137257.0 141529.0 23519.4 23863.3 24257.1 24337.1 24541.6 34936.0 42376.8 42387.6 43675.6 43700.8 11423.7 11517.9 11704.3 11759.5 11893.9 7497.6 9239.7 9253.7 9452.0 9457.4 9376.8 10870.2 11165.2 11212.7 11400.3 4116.2 4191.9 4192.4 4217.8 4229.4 1005.7 1050.4 1057.6 1098.0 1101.1 15433.8 19966.3 20474.6 21113.7 21384.7 968.9 1056.4 1058.3 1076.5 1084.5 10501.6 11299.6 11301.9 11344.3 11582.8 758712.0 775097.0 781298.0 790561.0 801459.0 133373.0 136554.0 145561.0 155459.0 158786.0 1000.3 1001.9 1011.8 1018.9 1019.5 18082.2 18737.3 19023.7 19103.0 19162.7 57532.4 57537.4 58734.7 59595.4 59896.4 85101.7 85146.6 86117.1 86510.6 89941.6 8247.1 8310.3 8591.0 8611.2 8636.6 10623.6 12438.2 12989.5 13654.7 13797.9 11406.5 11755.0 11812.1 11818.5 11842.2 7796.8 11390.0 11572.7 12027.6 12081.3 11038.9 11175.5 11780.9 11802.4 11994.5 7182.5 8453.1 8624.4 8773.1 8857.6 10653.4 11345.8 11440.3 11722.1 11880.9 01:53:37 INFO - PROCESS | 1638 | 01:53:37 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5408.07 Median:5285.66 stddev:2922.00 (55.3%) stddev-sans-first:2913.40 01:53:37 INFO - PROCESS | 1638 | Values: 1238.8 1424.5 1450.0 1450.5 1455.4 6720.4 8460.0 8469.7 8484.8 8497.6 7431.3 7700.4 7728.0 8046.4 8293.9 2327.2 2364.6 2369.2 2371.1 2372.3 2016.7 2351.9 2385.3 2393.0 2395.9 2040.4 2118.9 2266.4 2324.7 2350.9 2321.6 2324.1 2327.1 2327.3 2329.9 2281.5 2341.5 2367.7 2379.5 2381.8 8400.6 8479.5 8482.7 8485.7 8507.7 5124.7 5272.6 5274.3 5284.5 5287.1 3386.7 3550.5 3551.3 3614.6 3616.9 1173.4 1213.9 1217.2 1263.3 1273.0 9847.9 10145.6 10965.3 10980.7 11006.5 4494.2 4686.5 4690.3 4744.5 4779.8 9371.5 9577.3 9588.7 9603.6 9646.1 5276.6 5278.5 5283.9 5286.8 5291.2 3311.0 4432.3 4447.1 4734.4 4739.1 3870.5 3877.0 3892.9 3984.1 4002.0 1264.4 1272.0 1279.2 1293.1 1295.3 715.8 847.1 903.5 909.6 913.7 4822.4 5404.1 5433.7 5588.7 5608.7 978.5 996.1 1003.4 1007.9 1026.8 3714.9 3933.4 3988.7 4009.1 4029.8 9761.5 9776.0 9946.3 9978.0 9982.4 9484.8 9617.4 9892.3 9896.8 9928.1 7585.9 7702.8 7769.0 7845.5 7972.1 5267.7 5333.8 5394.7 5623.6 5655.2 9053.6 9283.6 9288.8 9320.9 9344.4 8834.2 9220.8 9229.7 9303.8 9340.5 6844.1 7054.0 7130.2 7369.7 7518.5 7634.1 7777.1 7880.1 7983.3 7986.0 5977.6 7008.4 7049.3 7156.1 7161.8 6890.0 6991.5 7005.5 7026.4 7050.8 6197.0 6384.9 6452.1 6457.6 6479.2 4273.9 4939.5 4965.4 4966.5 5023.5 4942.0 4948.4 4968.5 4995.8 5111.7 01:53:37 INFO - PROCESS | 1638 | 01:53:37 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4524.98 Median:5116.61 stddev:1365.70 (26.7%) stddev-sans-first:1333.17 01:53:37 INFO - PROCESS | 1638 | Values: 353.9 360.6 362.4 374.3 375.1 5444.0 5474.4 5501.8 5507.8 5529.2 5064.5 5186.7 5228.6 5255.9 5379.9 3547.0 4228.2 4273.5 4276.0 4285.0 3981.9 4181.6 4232.4 4243.5 4270.3 3828.7 4067.7 4116.9 4119.6 4263.5 4076.8 4087.4 4089.4 4089.8 4101.9 3509.5 3511.1 3523.8 3524.1 3534.0 3864.7 4350.5 4802.8 5248.3 5354.8 3866.0 4123.5 4182.9 4625.7 4667.2 4727.7 4832.3 5300.4 5650.6 5708.8 3480.1 3931.6 3947.8 3956.7 3960.6 5579.4 5590.9 5598.5 5948.9 6004.5 3784.1 3894.2 3906.9 3913.5 3920.8 3974.8 3981.6 3996.0 4000.7 4000.8 4349.7 4614.7 4640.9 4649.7 4707.9 5488.8 5777.6 5798.8 5805.4 5890.7 1104.6 1248.5 1253.7 1318.8 1334.9 833.7 841.4 868.9 880.9 898.6 4631.5 4658.0 4903.7 5152.3 5180.5 5248.0 5475.8 5592.1 5676.9 5696.9 4623.9 4910.9 5286.2 5438.9 5445.8 2664.0 2945.7 2955.8 2967.4 3006.8 5081.0 5416.8 5497.7 5724.9 5820.9 5650.7 5667.5 5735.8 5806.9 5810.7 5155.7 5194.6 5650.9 5676.7 5903.3 4933.6 5014.6 5244.6 5310.5 5650.3 4824.8 4913.4 5487.5 5535.2 5569.3 5394.3 5688.9 5860.0 5870.9 5886.2 3561.8 3696.0 3699.7 3704.4 3745.5 5019.9 5182.2 5183.7 5574.7 5641.4 4555.5 4661.1 5033.2 5395.5 5418.5 5197.5 5402.7 5746.2 5749.6 5756.8 5362.4 5370.6 5472.9 5516.0 5535.6 5425.5 5754.9 5759.3 5779.9 5809.4 4954.3 5304.7 5416.9 5649.0 5670.4 01:53:37 INFO - PROCESS | 1638 | 01:53:37 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20119.05 Median:4098.65 stddev:28919.63 (705.6%) stddev-sans-first:28964.03 01:53:37 INFO - PROCESS | 1638 | Values: 706.7 707.4 717.8 757.4 838.9 14791.3 15020.8 15401.6 15425.9 15648.7 13224.1 14439.4 14619.5 14636.6 14639.9 658.3 679.1 847.2 848.2 862.2 678.1 827.6 855.5 858.8 862.2 911.4 914.1 914.3 915.1 915.7 696.5 718.8 725.2 910.6 912.4 534.5 535.8 652.3 670.4 678.5 14352.1 15050.7 15113.2 15137.9 15143.9 12820.8 12929.5 13023.4 13273.0 13576.6 12327.8 12333.2 12681.3 12912.9 12987.3 4205.7 4308.2 4532.0 4535.2 4603.4 102715.0 108089.0 113668.0 115448.0 115586.0 10317.4 10637.7 10675.3 10842.5 10968.0 20890.8 20920.8 20923.0 20938.2 20979.1 7310.2 7525.8 7624.7 7797.9 7850.9 4089.0 4108.3 4142.0 4149.6 4207.9 3462.3 3527.3 3531.0 3539.0 3557.5 542.0 642.8 652.8 655.0 656.4 541.3 554.7 557.5 580.7 587.8 9642.3 10235.2 10285.0 10342.3 10510.0 544.8 558.3 588.2 625.6 715.7 4969.9 5273.3 5402.2 5517.7 5715.4 88542.6 98448.0 99938.5 103578.0 115348.8 68026.8 73065.6 79934.0 91007.0 91659.6 71368.3 77461.6 78639.2 78672.0 78699.0 9220.4 10364.0 10578.7 10766.1 10820.4 45935.8 46839.3 48888.0 49286.8 49984.5 60794.0 61409.8 61719.8 63008.0 65780.3 12557.9 13055.4 13151.3 13179.1 13274.6 13689.0 13963.4 13999.3 14765.3 15216.8 12710.9 13050.2 13150.7 13291.3 13303.5 13459.0 13643.9 13669.5 13875.0 14094.8 12579.0 12776.0 12803.5 13430.2 13705.5 10936.4 11232.5 11321.8 11691.6 11814.8 10542.3 10652.1 10671.8 11058.4 11400.4 01:53:37 INFO - PROCESS | 1638 | -------- Summary: end -------- 01:53:37 INFO - PROCESS | 1638 | 01:53:37 INFO - TEST-INFO | 1638: exit 0 01:53:37 INFO - TEST-OK | dromaeo_css | took 1118508ms 01:53:37 INFO - TEST-START | kraken 01:53:37 INFO - operating with platform_type : mac_ 01:53:37 INFO - Initialising browser for kraken test... 01:53:37 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpsSBkJn/profile http://localhost:49242/getInfo.html) 01:53:40 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 01:53:40 INFO - PROCESS | 1647 | colorDepth:24 01:53:40 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 01:53:40 INFO - PROCESS | 1647 | __metrics 01:53:40 INFO - PROCESS | 1647 | 1486634020182 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 01:53:40 INFO - PROCESS | 1647 | 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" 01:53:40 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:53:40 INFO - TEST-INFO | 1647: exit 0 01:53:40 INFO - Browser initialized. 01:53:40 INFO - Running cycle 1/1 for kraken test... 01:53:40 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpsSBkJn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:53:53 INFO - PROCESS | 1648 | RSS: Main: 213352448 01:53:53 INFO - PROCESS | 1648 | 01:53:55 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 01:53:56 INFO - PROCESS | 1648 | RSS: Main: 265748480 01:53:56 INFO - PROCESS | 1648 | 01:53:59 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 01:53:59 INFO - PROCESS | 1648 | RSS: Main: 274526208 01:53:59 INFO - PROCESS | 1648 | 01:54:03 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 01:54:03 INFO - PROCESS | 1648 | RSS: Main: 372404224 01:54:03 INFO - PROCESS | 1648 | 01:54:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 01:54:06 INFO - PROCESS | 1648 | RSS: Main: 275619840 01:54:06 INFO - PROCESS | 1648 | 01:54:08 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:54:08 INFO - PROCESS | 1648 | RSS: Main: 278114304 01:54:08 INFO - PROCESS | 1648 | 01:54:11 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 01:54:12 INFO - PROCESS | 1648 | RSS: Main: 317767680 01:54:12 INFO - PROCESS | 1648 | 01:54:15 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 01:54:15 INFO - PROCESS | 1648 | RSS: Main: 319258624 01:54:15 INFO - PROCESS | 1648 | 01:54:18 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 01:54:19 INFO - PROCESS | 1648 | RSS: Main: 316182528 01:54:19 INFO - PROCESS | 1648 | 01:54:20 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:54:21 INFO - PROCESS | 1648 | RSS: Main: 294813696 01:54:21 INFO - PROCESS | 1648 | 01:54:23 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:54:23 INFO - PROCESS | 1648 | RSS: Main: 297709568 01:54:23 INFO - PROCESS | 1648 | 01:54:26 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:54:26 INFO - PROCESS | 1648 | RSS: Main: 300343296 01:54:26 INFO - PROCESS | 1648 | 01:54:29 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:54:30 INFO - PROCESS | 1648 | RSS: Main: 317145088 01:54:30 INFO - PROCESS | 1648 | 01:54:33 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:54:33 INFO - PROCESS | 1648 | RSS: Main: 310939648 01:54:33 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 01:54:35 INFO - PROCESS | 1648 | RSS: Main: 355274752 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | __start_tp_report 01:54:35 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 01:54:35 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 01:54:35 INFO - PROCESS | 1648 | |i|pagename|runs| 01:54:35 INFO - PROCESS | 1648 | |0;ai-astar;114;114;113;114;114;114;112;111;114;114 01:54:35 INFO - PROCESS | 1648 | |1;audio-beat-detection;138;108;106;141;108;108;121;106;127;107 01:54:35 INFO - PROCESS | 1648 | |2;audio-dft;184;183;185;185;181;182;199;258;198;183 01:54:35 INFO - PROCESS | 1648 | |3;audio-fft;79;59;59;89;61;59;58;58;72;58 01:54:35 INFO - PROCESS | 1648 | |4;audio-oscillator;86;69;85;70;68;69;81;67;69;81 01:54:35 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;88;86;92;85;87;84;89;85;88;84 01:54:35 INFO - PROCESS | 1648 | |6;imaging-darkroom;94;94;108;150;93;94;95;102;143;94 01:54:35 INFO - PROCESS | 1648 | |7;imaging-desaturate;85;88;86;87;87;88;86;88;86;87 01:54:35 INFO - PROCESS | 1648 | |8;json-parse-financial;56;52;53;52;55;57;51;56;55;57 01:54:35 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;65;69;78;77;73;69;69;69;69;72 01:54:35 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;84;87;82;83;81;81;81;91;101;80 01:54:35 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;141;153;132;136;135;135;157;133;182;149 01:54:35 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;165;164;169;166;166;166;148;149;162;169 01:54:35 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;67;84;84;83;84;72;65;63;65;64 01:54:35 INFO - PROCESS | 1648 | __end_tp_report 01:54:35 INFO - PROCESS | 1648 | __start_cc_report 01:54:35 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1970 01:54:35 INFO - PROCESS | 1648 | __end_cc_report 01:54:35 INFO - PROCESS | 1648 | __startTimestamp1486634075857__endTimestamp 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | ------- Summary: start ------- 01:54:35 INFO - PROCESS | 1648 | Number of tests: 14 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:1.07 (0.9%) stddev-sans-first:1.12 01:54:35 INFO - PROCESS | 1648 | Values: 114.0 114.0 113.0 114.0 114.0 114.0 112.0 111.0 114.0 114.0 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:117.00 Median:114.50 stddev:13.82 (12.1%) stddev-sans-first:12.39 01:54:35 INFO - PROCESS | 1648 | Values: 138.0 108.0 106.0 141.0 108.0 108.0 121.0 106.0 127.0 107.0 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:193.80 Median:185.00 stddev:23.46 (12.7%) stddev-sans-first:24.61 01:54:35 INFO - PROCESS | 1648 | Values: 184.0 183.0 185.0 185.0 181.0 182.0 199.0 258.0 198.0 183.0 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:65.20 Median:60.00 stddev:11.01 (18.4%) stddev-sans-first:10.49 01:54:35 INFO - PROCESS | 1648 | Values: 79.0 59.0 59.0 89.0 61.0 59.0 58.0 58.0 72.0 58.0 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:74.50 Median:75.50 stddev:7.72 (10.2%) stddev-sans-first:6.98 01:54:35 INFO - PROCESS | 1648 | Values: 86.0 69.0 85.0 70.0 68.0 69.0 81.0 67.0 69.0 81.0 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.50 stddev:2.53 (2.9%) stddev-sans-first:2.65 01:54:35 INFO - PROCESS | 1648 | Values: 88.0 86.0 92.0 85.0 87.0 84.0 89.0 85.0 88.0 84.0 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:106.70 Median:94.00 stddev:21.57 (22.9%) stddev-sans-first:22.38 01:54:35 INFO - PROCESS | 1648 | Values: 94.0 94.0 108.0 150.0 93.0 94.0 95.0 102.0 143.0 94.0 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:86.80 Median:87.00 stddev:1.03 (1.2%) stddev-sans-first:0.87 01:54:35 INFO - PROCESS | 1648 | Values: 85.0 88.0 86.0 87.0 87.0 88.0 86.0 88.0 86.0 87.0 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.50 stddev:2.22 (4.0%) stddev-sans-first:2.28 01:54:35 INFO - PROCESS | 1648 | Values: 56.0 52.0 53.0 52.0 55.0 57.0 51.0 56.0 55.0 57.0 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:70.50 stddev:4.03 (5.7%) stddev-sans-first:3.64 01:54:35 INFO - PROCESS | 1648 | Values: 65.0 69.0 78.0 77.0 73.0 69.0 69.0 69.0 69.0 72.0 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:82.50 stddev:6.52 (7.9%) stddev-sans-first:6.91 01:54:35 INFO - PROCESS | 1648 | Values: 84.0 87.0 82.0 83.0 81.0 81.0 81.0 91.0 101.0 80.0 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:145.00 stddev:15.64 (10.8%) stddev-sans-first:16.51 01:54:35 INFO - PROCESS | 1648 | Values: 141.0 153.0 132.0 136.0 135.0 135.0 157.0 133.0 182.0 149.0 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.40 Median:166.00 stddev:7.62 (4.6%) stddev-sans-first:8.02 01:54:35 INFO - PROCESS | 1648 | Values: 165.0 164.0 169.0 166.0 166.0 166.0 148.0 149.0 162.0 169.0 01:54:35 INFO - PROCESS | 1648 | 01:54:35 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:77.50 stddev:9.48 (12.2%) stddev-sans-first:9.80 01:54:35 INFO - PROCESS | 1648 | Values: 67.0 84.0 84.0 83.0 84.0 72.0 65.0 63.0 65.0 64.0 01:54:35 INFO - PROCESS | 1648 | -------- Summary: end -------- 01:54:35 INFO - PROCESS | 1648 | 01:54:36 INFO - TEST-INFO | 1648: exit 0 01:54:36 INFO - TEST-OK | kraken | took 58773ms 01:54:36 INFO - SUITE-END | took 1177s 01:54:37 INFO - Completed test suite (00:19:37) 01:54:37 INFO - javascript benchmark 01:54:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3407.4888766685, 3410.300888317895, 3434.7423943204267, 3437.2438063625996, 3480.095175254433, 5806.100054491555, 5830.0, 5846.590738648296, 5848.649081055129, 5858.912116318259, 5724.62789918655, 5820.621659592123, 5873.970630146854, 5880.206172166767, 5889.116632505126, 5282.313299271099, 5297.973510132444, 5362.0, 5377.059014672436, 5407.297051383334, 4274.0, 5206.557442617382, 5238.345206849144, 5277.8944421111555, 5409.0263752524725, 4313.547077556858, 5384.892302153955, 5390.460953904598, 5390.73046347683, 5404.32445944257, 5370.838874833761, 5388.488093631099, 5390.973045134789, 5413.891722165555, 5414.648047876875, 5141.922871156936, 5234.18870075139, 5306.6550674206055, 5315.362156541201, 5370.946290537084, 5700.0, 5776.971115144397, 5787.3923238059815, 5799.2750906136735, 5805.10020946754, 6142.385761423867, 6154.015357542817, 6157.876842463171, 6163.630182189038, 6184.938150618481, 6228.564000519966, 6277.340879207709, 6277.591956522811, 6293.842653933642, 6345.650989195592, 6019.759209631653, 6022.850438016457, 6025.9096113558335, 6032.426919442646, 6053.273607167124, 6414.262359828679, 6430.164078669743, 6434.549581529248, 6458.418742313121, 6464.095026696266, 6132.78385080153, 6137.539684523678, 6142.972818969729, 6162.907556386658, 6204.410580994799, 6313.905291420632, 6349.2698339690605, 6350.396712312323, 6364.363563643598, 6370.968145159337, 6263.937360626426, 6270.623762574261, 6270.7805226816845, 6272.937270627326, 6276.592021518587, 6143.419763699168, 6177.135201071764, 6185.474234690012, 6287.2140982377205, 6289.465395441439, 5060.01329740697, 5101.69389836611, 5115.795368185305, 5142.177251639794, 5143.996920600447, 4501.909961800746, 4517.412736344254, 4520.095980803852, 4520.141173177151, 4552.339910712928, 5775.971120144456, 5954.791582294599, 6002.189704389939, 6020.127081573148, 6027.156198132265, 6206.503479721611, 6208.441240288395, 6222.315545290015, 6246.6876656167005, 6294.055891616203, 5722.313322401255, 5941.55438342126, 5951.9107213391835, 5968.403159683997, 5983.910241346383, 4710.69380490267, 5454.972725136428, 5489.917651235235, 5518.172274158828, 5544.112941929272, 6403.96798016007, 6442.38797314245, 6476.417122459001, 6496.187976502937, 6503.122078519528, 6158.3225845157895, 6212.409821066992, 6218.595791273553, 6263.060540918898, 6271.96864015677, 6498.252700939453, 6545.443637290693, 6554.901676474952, 6558.409743123142, 6570.408663220333, 6266.530010249158, 6279.497640188865, 6301.117843501822, 6311.0217916223055, 6312.5581209314905, 6207.6585787782105, 6240.126382306572, 6265.655388953469, 6267.2479302484, 6269.937300627118, 6062.636241825505, 6135.110179431545, 6253.687315634109, 6253.8749225014335, 6275.6548389839, 6186.659733714689, 6189.257289125334, 6239.719212635351, 6250.156228909221, 6253.6560489173535, 5920.644761314335, 5941.613795103304, 6110.7861224856915, 6138.1713468681155, 6165.229346331708, 6168.845778855564, 6181.381861813783, 6204.5036397089025, 6279.497640188682, 6283.057541368831, 4950.504949505164, 5444.210589464585, 6119.265688117454, 6122.938770612237, 6141.0], "value": 5788.026651923375, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.9771410286537, 509.49559935663706, 510.7471801458279, 517.8757098296408, 547.463485783932, 39788.0, 42475.57524424755, 42494.78752606236, 42605.573944260585, 44303.77848110763, 42872.9281767956, 43439.565604343945, 43441.56558434415, 43463.565364346345, 43490.130197396036, 8427.157284271585, 9118.316126290521, 9266.629334826599, 9317.767055823591, 9320.02139775324, 8490.830183396329, 11296.378699171542, 11512.42437878107, 11528.827067593993, 11693.70765730855, 11271.0, 11292.0, 11330.830037549402, 11362.31826090433, 11363.602273920415, 8520.062793092755, 8736.8689469658, 11265.887341126607, 11325.490352934137, 11338.659840204808, 11088.944555277172, 11373.829392559119, 11394.487248073856, 11506.367149806758, 11562.479688414041, 34037.82981085079, 40727.0, 41692.791536042125, 41949.79025104886, 41968.580314197075, 3847.0368632774766, 3913.8515360227466, 3916.980415097906, 3956.5054368203973, 4028.811500607328, 29749.851250743606, 29902.85048574765, 30241.0, 31303.0, 31508.842455787802, 3246.594175728034, 3248.772585918987, 3257.38109759145, 3304.520844477537, 3316.701496865294, 459654.0, 460710.0, 461388.0, 461627.0, 463335.6833215846, 60039.0, 60845.39154608397, 61206.0, 61284.69357653272, 61424.0, 65793.67103164454, 65873.67063164653, 65875.67062164658, 65976.67011664911, 65998.67000665062, 16659.66680666404, 16921.323147074225, 16930.0, 16937.407190748265, 16953.067581283147, 11663.825042624367, 11970.623378311606, 11970.982466490446, 12974.675633109247, 13040.80438793419, 16515.91742041282, 16799.748003779954, 16973.81183317156, 17197.828021719874, 17410.39063632792, 5326.041312563776, 5543.390227075019, 6503.414692677682, 6600.471962243009, 6623.7350505980185, 868.5613765048736, 876.1326286975848, 878.3368556739621, 893.1068931068932, 903.408267584733, 29932.850335748182, 30062.0, 30258.0, 30513.48981787746, 30735.23161920925, 950.8019894932435, 1070.277562645227, 1073.5920350267004, 1114.5820317380983, 1193.3138445393934, 15822.446214382442, 15892.887497875037, 15961.914595764436, 15986.120763358127, 16165.191740412933, 196129.0, 239608.0, 240547.0, 241596.7920160423, 587845.0, 61337.693311533156, 61374.772504549655, 61557.692211538655, 61577.0, 61747.691261543405, 1422.7581311177125, 1432.0333774702035, 1461.8099647045815, 1465.157534417714, 1467.4350375105662, 29071.273218169714, 29083.41833163367, 29691.812327506657, 30283.394332113676, 31193.0, 40377.0, 43675.12649747051, 51271.69277382601, 51309.743451282506, 51406.971860563324, 520638.0, 522034.0, 522618.0, 523552.0, 524357.0, 34847.65152348545, 40751.79624101861, 41216.79391603023, 41334.0, 41666.79166604148, 37490.25019499649, 48609.80101513762, 48703.756481217366, 50553.49446505635, 50993.0, 33446.0, 39720.00699982524, 40105.79947100246, 40609.207890199476, 41711.58288417077, 40099.0, 40562.0, 40972.0, 41826.58173418227, 41847.1630567393, 37529.560578562305, 37576.43635345527, 38465.80767096147, 38778.80610596929, 38942.80528597339, 33589.83205083959, 34811.65188348084, 35647.64352356547, 35658.82170589131, 35686.46470303, 25411.872940635178, 25562.48875022452, 25765.613515797653, 26045.0, 26122.085727000223], "value": 20059.63218664283, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.3142667524938, 392.42705649751343, 402.157480079234, 402.58131543195077, 426.1647171543774, 13307.804212304958, 19662.016899155038, 20081.39755807328, 20877.37367878964, 20879.164833406663, 16643.583910402245, 20458.590828183427, 21386.572268554657, 21526.677099843477, 21666.674999875013, 5650.180723795047, 6041.697915104249, 6044.879102417949, 6045.486133678621, 6052.182955301044, 5733.655980641175, 5908.67502287374, 5914.733836977346, 5984.97007514964, 6028.909566356486, 5087.440381558025, 5921.526277897779, 5930.495907847817, 5936.0, 5958.52331813456, 5650.491455768959, 5810.331811841608, 5849.0, 5902.291724993029, 5912.763489460416, 5212.452692467312, 5222.817201397971, 5226.137687281594, 5228.660137091077, 5236.659617124875, 25332.62001069967, 25989.960401583914, 27092.322691932663, 27266.591001135, 27591.86204068967, 3096.4581198290207, 3097.643770966345, 3100.286934005169, 3114.7663925205475, 3115.2367669921014, 15924.442644507502, 16232.513024609281, 16284.348626054943, 16314.0, 16543.91728041364, 1598.776171336014, 1838.6046999895086, 1881.7271495633192, 1885.604023155139, 1912.3211260002638, 125597.0, 128593.35703321517, 134204.3289783545, 137257.0, 141529.0, 23519.41201469977, 23863.329566930148, 24257.14857028601, 24337.14819981292, 24541.63187552188, 34936.0, 42376.78811605922, 42387.57612423898, 43675.56324436778, 43700.78149609231, 11423.714407139889, 11517.884821151682, 11704.297742135499, 11759.47082381295, 11893.881061189277, 7497.550146991198, 9239.676611318571, 9253.72238832836, 9451.952740236393, 9457.38526995757, 9376.812463750686, 10870.239083264096, 11165.218434709655, 11212.66362009141, 11400.315981041163, 4116.217918595457, 4191.91616167675, 4192.436429035357, 4217.7891105444605, 4229.429027081304, 1005.7183988483237, 1050.3907733514543, 1057.6245432871374, 1098.0392156862745, 1101.130107215307, 15433.768493472608, 19966.30117945865, 20474.566780325233, 21113.66590022987, 21384.679229811565, 968.8595153702673, 1056.3820165203288, 1058.3015209961388, 1076.4832880217539, 1084.5444913136503, 10501.579936802595, 11299.604513841976, 11301.869813018633, 11344.262622929484, 11582.826257606142, 758712.0, 775097.0, 781298.0, 790561.0, 801459.0, 133373.0, 136554.0, 145561.0, 155459.0, 158786.0, 1000.3497726477702, 1001.9248556358302, 1011.8279892418159, 1018.8624535687735, 1019.454591793387, 18082.186301616723, 18737.250509979993, 19023.714644280622, 19103.0, 19162.71255931134, 57532.42467575271, 57537.42462575321, 58734.70632646809, 59595.40404595899, 59896.40103598908, 85101.72347414917, 85146.57426712826, 86117.13882861091, 86510.56744716236, 89941.55029224812, 8247.134050924806, 8310.335173186251, 8590.957045214735, 8611.224989750952, 8636.611352489028, 10623.575056997943, 12438.19151755133, 12989.480420783062, 13654.658633534242, 13797.862021379658, 11406.461134961672, 11755.0, 11812.114091443347, 11818.468168932246, 11842.171048026556, 7796.805079872822, 11390.0, 11572.6528204154, 12027.639170824888, 12081.335526546125, 11038.889611104107, 11175.49710263024, 11780.882191178322, 11802.350870702194, 11994.460249288628, 7182.4972251943955, 8453.112423195476, 8624.439411438236, 8773.122687731176, 8857.601407936529, 10653.414062226651, 11345.829812552984, 11440.313581185155, 11722.062235021349, 11880.881191187977], "value": 11207.40800327107, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1238.8203710461983, 1424.5299051313068, 1450.0212356659256, 1450.5285782120814, 1455.3741890986878, 6720.428763555097, 8459.984801823774, 8469.703560375388, 8484.830303393928, 8497.575121243935, 7431.256874312566, 7700.383969282444, 7727.995360603128, 8046.356291496681, 8293.91706082939, 2327.150590034638, 2364.5980183368783, 2369.230000249917, 2371.0515793682493, 2372.2527403867725, 2016.68741345091, 2351.870647114408, 2385.272491889973, 2393.0308225168747, 2395.856248625088, 2040.387883634911, 2118.8834614096218, 2266.422062374092, 2324.6513023046596, 2350.8942097605564, 2321.570509455756, 2324.070371851256, 2327.069172331073, 2327.2552783109413, 2329.918452854159, 2281.53228588139, 2341.5433990371885, 2367.7040369953756, 2379.464620460383, 2381.7975472084895, 8400.621972011275, 8479.533625650587, 8482.703105391343, 8485.745427637181, 8507.702230421968, 5124.692518448905, 5272.578193744491, 5274.2879711238875, 5284.471552844685, 5287.07476191665, 3386.6613338666184, 3550.5029295898594, 3551.2897420516, 3614.638536146391, 3616.8553257869653, 1173.4367503598235, 1213.8907498325104, 1217.190568272098, 1263.3178083834719, 1272.9809052864214, 9847.852282215772, 10145.64490242838, 10965.287256328393, 10980.670579882617, 11006.504707288112, 4494.191045611756, 4686.484486706459, 4690.343351930733, 4744.478107408182, 4779.832705855279, 9371.48456834867, 9577.281703872237, 9588.712338629852, 9603.567839447242, 9646.083622055894, 5276.577873770089, 5278.498542638443, 5283.920741188886, 5286.841394758163, 5291.232771248147, 3310.9834450827593, 4432.339849234693, 4447.132809102259, 4734.360861283752, 4739.052189562101, 3870.5161854768153, 3876.96123038766, 3892.863749768801, 3984.083660758042, 4001.979990100031, 1264.4246867675186, 1272.0459655258555, 1279.2388528825334, 1293.140061858872, 1295.2746461981321, 715.7959981405379, 847.0978407995465, 903.5075883643431, 909.5770466733001, 913.7441516375426, 4822.397200349957, 5404.108322126805, 5433.701146436984, 5588.6646801192055, 5608.747606357723, 978.5107446276861, 996.1383403356045, 1003.433060320931, 1007.9193664506822, 1026.820306446369, 3714.8669655754716, 3933.449317095609, 3988.720789544704, 4009.0578714002186, 4029.8388064477094, 9761.511924403894, 9776.0, 9946.254030947708, 9978.002199780109, 9982.35114717541, 9484.762880928032, 9617.374870633386, 9892.25808064398, 9896.802063958681, 9928.106470417697, 7585.886211706828, 7702.845943081107, 7769.028871391076, 7845.450818442769, 7972.123066462685, 5267.7366131694025, 5333.7866485340155, 5394.703291319015, 5623.550115990793, 5655.1517272409465, 9053.637854485747, 9283.582238799405, 9288.767780805534, 9320.860187097334, 9344.392614480037, 8834.249088827264, 9220.769480763034, 9229.676961306322, 9303.813923721624, 9340.53297335117, 6844.110265665432, 7054.0, 7130.2156762757095, 7369.668364923698, 7518.548887066575, 7634.122075961335, 7777.144514103557, 7880.05439347282, 7983.281504664608, 7985.960070199611, 5977.581569290108, 7008.439324853897, 7049.330313620198, 7156.105486814148, 7161.785146445615, 6890.035395044798, 6991.475639327173, 7005.474589405917, 7026.367626913602, 7050.823729406806, 6197.0, 6384.904226436514, 6452.12896258999, 6457.644829534421, 6479.157709497735, 4273.91452170961, 4939.48135445773, 4965.379327584052, 4966.528179822911, 5023.497650234947, 4942.0, 4948.430930443045, 4968.527989840959, 4995.775190116381, 5111.667741596784], "value": 4415.528860422371, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.8991387454575, 360.6303538872655, 362.3911828128744, 374.29819089207734, 375.0960185951857, 5444.0, 5474.425185355535, 5501.779928802848, 5507.80722674706, 5529.175372127198, 5064.467766116942, 5186.688798672082, 5228.620977455258, 5255.947440525593, 5379.946200537994, 3546.982265088677, 4228.181881790829, 4273.546994022032, 4275.957240427587, 4285.0, 3981.889598512793, 4181.623653871151, 4232.449781528412, 4243.469566304212, 4270.338097594878, 3828.7365169494, 4067.715259931806, 4116.855910043149, 4119.567445418218, 4263.488381394237, 4076.8369265229353, 4087.4073259377224, 4089.3865920111925, 4089.8119506064113, 4101.9179616407655, 3509.491123787062, 3511.087117349482, 3523.7885726856216, 3524.1189702574356, 3534.028142260873, 3864.7487913285827, 4350.521442641307, 4802.783874725646, 5248.343957005374, 5354.848707527861, 3866.0, 4123.525794533637, 4182.870624931282, 4625.745583992879, 4667.183242932473, 4727.7163370197895, 4832.323474713543, 5300.416954135042, 5650.643845477097, 5708.80019199326, 3480.095175254427, 3931.606839316074, 3947.756456716154, 3956.7032472564674, 3960.584138665456, 5579.414161513023, 5590.944090559124, 5598.468145526209, 5948.881022379528, 6004.54965877561, 3784.0918179636687, 3894.2406230784727, 3906.921861562753, 3913.5499417566775, 3920.8039598019986, 3974.7615143091507, 3981.6217459341315, 3996.0, 4000.7199496035, 4000.8199631016673, 4349.652027837766, 4614.723116612947, 4640.91158978461, 4649.651276154302, 4707.882302942386, 5488.7530061147345, 5777.62445441045, 5798.82603521895, 5805.419458054245, 5890.705464726747, 1104.6023431564631, 1248.4694005047818, 1253.6615113919372, 1318.7692153873033, 1334.9199048056953, 833.7040350675544, 841.3731769831393, 868.8834847221291, 880.872273520336, 898.6405437824792, 4631.536846315409, 4658.045100754405, 4903.681260718042, 5152.269297272452, 5180.481951804864, 5248.029114613809, 5475.835724928259, 5592.105263157875, 5676.943230567724, 5696.9490983246, 4623.884402889955, 4910.926336104962, 5286.233496143038, 5438.945610543923, 5445.809396671098, 2664.0, 2945.659724825199, 2955.8274668118916, 2967.3620171662765, 3006.8195908245575, 5080.958403527268, 5416.756245968941, 5497.725113744297, 5724.856878577985, 5820.883582328414, 5650.660960342392, 5667.489925906606, 5735.799247026334, 5806.9419305806405, 5810.651360918274, 5155.690658560498, 5194.558462530727, 5650.915236271459, 5676.7009361208775, 5903.291605007426, 4933.6299777516115, 5014.5737612303365, 5244.564810049424, 5310.468953104659, 5650.29371328584, 4824.831130910401, 4913.361263035782, 5487.451254874481, 5535.197396377584, 5569.33168019824, 5394.32570928634, 5688.886222275614, 5859.970700146472, 5870.945169190749, 5886.175935368966, 3561.8219089044924, 3695.981520092382, 3699.741018128705, 3704.370257056361, 3745.4943582615997, 5019.949800501949, 5182.222666600041, 5183.740812959412, 5574.721263936706, 5641.40765219646, 4555.476120246081, 4661.114388266218, 5033.188052301241, 5395.487428694268, 5418.539424148991, 5197.480251974772, 5402.72986350673, 5746.1676398454765, 5749.568782341257, 5756.812411283686, 5362.356517217959, 5370.624056316176, 5472.890542189054, 5516.0, 5535.5848311376, 5425.538829199562, 5754.856128596819, 5759.337676167127, 5779.890340025944, 5809.448102430262, 4954.266006897557, 5304.708241046779, 5416.918746218731, 5649.01142300104, 5670.404607516151], "value": 4099.264154138495, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.7314420520208, 707.395177123559, 717.849251657152, 757.4394947738675, 838.8699751538512, 14791.334389952452, 15020.849791502082, 15401.614959625987, 15425.922870385646, 15648.687026259468, 13224.140430871987, 14439.350229239682, 14619.488317908848, 14636.634084147901, 14639.926800365984, 658.26932105363, 679.0595025889154, 847.2120927537388, 848.2365870716374, 862.180928118287, 678.0710426715407, 827.6482494939627, 855.5422848775906, 858.8196478739472, 862.2153840005565, 911.3529394130176, 914.1087439746261, 914.2822884036033, 915.1260546178416, 915.6566287642135, 696.4846013949691, 718.8310746974457, 725.1551941987578, 910.6493999810087, 912.4160537256129, 534.5429657642737, 535.8499620106337, 652.3118110393533, 670.4100391655361, 678.494521581425, 14352.06711563745, 15050.698986020328, 15113.244337783177, 15137.924310378488, 15143.924280378527, 12820.807687884597, 12929.48282068716, 13023.413946372437, 13273.00452466069, 13576.59270221895, 12327.815082773766, 12333.153767881748, 12681.302528360937, 12912.935435322857, 12987.285699286536, 4205.663546916239, 4308.159908817782, 4532.0, 4535.183666939983, 4603.447586289667, 102715.0, 108089.0, 113668.0, 115448.0, 115586.0, 10317.380957142595, 10637.734056648645, 10675.25273230882, 10842.512086956105, 10968.0, 20890.791092089188, 20920.790792091884, 20922.95385230763, 20938.162473500888, 20979.055942482624, 7310.232425595343, 7525.774226773206, 7624.69501219945, 7797.92202077972, 7850.921490785019, 4088.9591104088577, 4108.342665173558, 4141.967584167996, 4149.585041495887, 4207.852725154605, 3462.307538492312, 3527.3474407234744, 3531.046617413284, 3539.0, 3557.48416479609, 541.9945800542023, 642.8071578526424, 652.8400541867178, 654.9815037616552, 656.4485831901226, 541.2855031358569, 554.6699713670359, 557.5205323421854, 580.7241213574721, 587.7502061623793, 9642.298602471355, 10235.181185505142, 10285.0, 10342.327748696462, 10510.0, 544.8311023582702, 558.3160628230467, 588.1501230721566, 625.5660531434178, 715.7316006497564, 4969.875753106201, 5273.306129769898, 5402.243685883979, 5517.724113794295, 5715.428457154251, 88542.55728721315, 98448.01551984531, 99938.50030749945, 103578.0, 115348.84651153549, 68026.8163797881, 73065.6346718263, 79934.0, 91007.0, 91659.62510562348, 71368.28631713823, 77461.6126919373, 78639.21360786434, 78672.0, 78699.0, 9220.400673956172, 10363.952512291413, 10578.682639520826, 10766.138708903112, 10820.404877731486, 45935.77032114818, 46839.29741053955, 48888.02223955572, 49286.753566233376, 49984.50046498611, 60794.0, 61409.77180456455, 61719.765604686756, 63008.0, 65780.34219657742, 12557.937210314256, 13055.412506437044, 13151.34243287851, 13179.14335568185, 13274.601761947157, 13689.041767076209, 13963.4414623418, 13999.300034998005, 14765.335559899615, 15216.771748423562, 12710.872891270968, 13050.216986980431, 13150.73698526005, 13291.335433228107, 13303.534376296782, 13458.990575706664, 13643.931780341034, 13669.453221871014, 13875.0, 14094.788578171541, 12578.99368050572, 12776.0, 12803.487860485477, 13430.19418834873, 13705.520306789613, 10936.398498082683, 11232.494537745984, 11321.830172547254, 11691.590794322157, 11814.822777658514, 10542.314749541561, 10652.09457196116, 10671.786564268516, 11058.39178845139, 11400.429978500875], "value": 7381.355376013877, "unit": "score"}], "name": "dromaeo_css", "value": 7470.887518787749, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 112.0, 111.0, 114.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 108.0, 106.0, 141.0, 108.0, 108.0, 121.0, 106.0, 127.0, 107.0], "value": 117.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 185.0, 185.0, 181.0, 182.0, 199.0, 258.0, 198.0, 183.0], "value": 193.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 89.0, 61.0, 59.0, 58.0, 58.0, 72.0, 58.0], "value": 65.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 85.0, 70.0, 68.0, 69.0, 81.0, 67.0, 69.0, 81.0], "value": 74.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 92.0, 85.0, 87.0, 84.0, 89.0, 85.0, 88.0, 84.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 108.0, 150.0, 93.0, 94.0, 95.0, 102.0, 143.0, 94.0], "value": 106.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 86.0, 87.0, 87.0, 88.0, 86.0, 88.0, 86.0, 87.0], "value": 86.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 52.0, 53.0, 52.0, 55.0, 57.0, 51.0, 56.0, 55.0, 57.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 78.0, 77.0, 73.0, 69.0, 69.0, 69.0, 69.0, 72.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 82.0, 83.0, 81.0, 81.0, 81.0, 91.0, 101.0, 80.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 153.0, 132.0, 136.0, 135.0, 135.0, 157.0, 133.0, 182.0, 149.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 169.0, 166.0, 166.0, 166.0, 148.0, 149.0, 162.0, 169.0], "value": 162.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 84.0, 84.0, 83.0, 84.0, 72.0, 65.0, 63.0, 65.0, 64.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1435.5, "alertThreshold": 2.0}]} 01:54:37 INFO - Return code: 0 01:54:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:54:37 ERROR - # TBPL SUCCESS # 01:54:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:54:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:54:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:54:37 INFO - 2017-02-09 01:54:37.177 screenresolution[1652:9835] starting screenresolution argv=screenresolution get 01:54:37 INFO - 2017-02-09 01:54:37.179 screenresolution[1652:9835] Display 0: 1600x1200x32@60 01:54:37 INFO - 2017-02-09 01:54:37.185 screenresolution[1653:9838] starting screenresolution argv=screenresolution list 01:54:37 INFO - Available Modes on Display 0 01:54:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:54:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:54:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:54:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:54:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:54:37 INFO - Intel Iris: 01:54:37 INFO - Chipset Model: Intel Iris 01:54:37 INFO - Type: GPU 01:54:37 INFO - Bus: Built-In 01:54:37 INFO - VRAM (Dynamic, Max): 1536 MB 01:54:37 INFO - Vendor: Intel (0x8086) 01:54:37 INFO - Device ID: 0x0a2e 01:54:37 INFO - Revision ID: 0x0009 01:54:37 INFO - Displays: 01:54:37 INFO - Display: 01:54:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:54:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:54:37 INFO - Display Serial Number: 1600x1200 60 01:54:37 INFO - Main Display: Yes 01:54:37 INFO - Mirror: Off 01:54:37 INFO - Online: Yes 01:54:37 INFO - Rotation: Supported 01:54:37 INFO - Return code: 0 01:54:37 INFO - Running post-action listener: _resource_record_post_action 01:54:37 INFO - [mozharness: 2017-02-09 09:54:37.426924Z] Finished run-tests step (success) 01:54:37 INFO - Running post-run listener: _resource_record_post_run 01:54:37 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 39138304; Write bytes: 507796992; Read time: 390; Write time: 1556 01:54:37 INFO - TinderboxPrint: CPU usage
53.5% 01:54:37 INFO - TinderboxPrint: I/O read bytes / time
39,138,304 / 390 01:54:37 INFO - TinderboxPrint: I/O write bytes / time
507,796,992 / 1,556 01:54:37 INFO - TinderboxPrint: CPU idle
2,227.0 (46.5%) 01:54:37 INFO - TinderboxPrint: CPU system
957.3 (20.0%) 01:54:37 INFO - TinderboxPrint: CPU user
1,609.4 (33.6%) 01:54:37 INFO - TinderboxPrint: Swap in / out
246,427,648 / 0 01:54:37 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205566464; Write bytes: 200669184; Read time: 15628; Write time: 461 01:54:37 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36877312; Write bytes: 296101376; Read time: 355; Write time: 1061 01:54:37 INFO - Running post-run listener: _upload_blobber_files 01:54:37 INFO - Blob upload gear active. 01:54:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:54:37 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 01:54:37 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'] 01:54:37 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 01:54:38 INFO - (blobuploader) - INFO - Open directory for files ... 01:54:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:54:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:54:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:54:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:54:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:54:39 INFO - (blobuploader) - INFO - Done attempting. 01:54:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:54:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:54:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:54:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:54:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:54:40 INFO - (blobuploader) - INFO - Done attempting. 01:54:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:54:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:54:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:54:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:54:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:54:41 INFO - (blobuploader) - INFO - Done attempting. 01:54:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:54:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:54:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:54:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:54:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:54:44 INFO - (blobuploader) - INFO - Done attempting. 01:54:44 INFO - (blobuploader) - INFO - Iteration through files over. 01:54:44 INFO - Return code: 0 01:54:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:54:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:54:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8003954bcd772946de502d3a7270f4124549ed081728a132ae5d7e80b67fda87d4d49af45267b482ebbf0763e6127e621330f8ce63ffed6f617bdda3ec6c355f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04858323bb443f940e3fe942ad57ef0e7d853b64384b21b10bf3d6fb519bc83033f8101efa4ff5ef01cc8abdfebfaa19df734e3d41c8e5ff5d306e26a4be47f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d841b7a1349197678479ac4cb827e1c5c3dbe47dbe88dfec5deb525333aa132337f3850cd74afb863c878d1ef1eb328ac94e0c72a8b413f4ad7f15dc5c7a9d54", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/838429a040a392fb8b2abf50653ba3ac422d9097ea923d37974a1141fae91386b208ac94c82529c4533317505dd1b5d42c462b92b53054c93027035ae23f9aee"} 01:54:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:54:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:54:44 INFO - Contents: 01:54:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8003954bcd772946de502d3a7270f4124549ed081728a132ae5d7e80b67fda87d4d49af45267b482ebbf0763e6127e621330f8ce63ffed6f617bdda3ec6c355f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04858323bb443f940e3fe942ad57ef0e7d853b64384b21b10bf3d6fb519bc83033f8101efa4ff5ef01cc8abdfebfaa19df734e3d41c8e5ff5d306e26a4be47f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d841b7a1349197678479ac4cb827e1c5c3dbe47dbe88dfec5deb525333aa132337f3850cd74afb863c878d1ef1eb328ac94e0c72a8b413f4ad7f15dc5c7a9d54", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/838429a040a392fb8b2abf50653ba3ac422d9097ea923d37974a1141fae91386b208ac94c82529c4533317505dd1b5d42c462b92b53054c93027035ae23f9aee"} 01:54:44 INFO - Running post-run listener: copy_logs_to_upload_dir 01:54:44 INFO - Copying logs to upload dir... 01:54:44 INFO - mkdir: /builds/slave/test/build/upload/logs 01:54:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.702020 ========= master_lag: 0.42 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-09 01:54:44.799176) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 01:54:44.803033) ========= 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.udtgeCDDp3/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.UcFI8EMwye/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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/8003954bcd772946de502d3a7270f4124549ed081728a132ae5d7e80b67fda87d4d49af45267b482ebbf0763e6127e621330f8ce63ffed6f617bdda3ec6c355f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04858323bb443f940e3fe942ad57ef0e7d853b64384b21b10bf3d6fb519bc83033f8101efa4ff5ef01cc8abdfebfaa19df734e3d41c8e5ff5d306e26a4be47f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d841b7a1349197678479ac4cb827e1c5c3dbe47dbe88dfec5deb525333aa132337f3850cd74afb863c878d1ef1eb328ac94e0c72a8b413f4ad7f15dc5c7a9d54", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/838429a040a392fb8b2abf50653ba3ac422d9097ea923d37974a1141fae91386b208ac94c82529c4533317505dd1b5d42c462b92b53054c93027035ae23f9aee"} build_url:https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011025 build_url: 'https://queue.taskcluster.net/v1/task/UqpcEbEySv-5a1E8h--tQg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8003954bcd772946de502d3a7270f4124549ed081728a132ae5d7e80b67fda87d4d49af45267b482ebbf0763e6127e621330f8ce63ffed6f617bdda3ec6c355f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04858323bb443f940e3fe942ad57ef0e7d853b64384b21b10bf3d6fb519bc83033f8101efa4ff5ef01cc8abdfebfaa19df734e3d41c8e5ff5d306e26a4be47f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d841b7a1349197678479ac4cb827e1c5c3dbe47dbe88dfec5deb525333aa132337f3850cd74afb863c878d1ef1eb328ac94e0c72a8b413f4ad7f15dc5c7a9d54", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/838429a040a392fb8b2abf50653ba3ac422d9097ea923d37974a1141fae91386b208ac94c82529c4533317505dd1b5d42c462b92b53054c93027035ae23f9aee"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 01:54:44.837203) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 01:54:44.837524) ========= 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.udtgeCDDp3/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.UcFI8EMwye/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.004853 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 01:54:44.885223) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 01:54:44.885514) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 01:54:44.885820) ========= ========= Total master_lag: 0.56 =========