builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0248 starttime: 1480111571.02 results: success (0) buildid: 20161125122519 builduid: 78dbc2e280e34d38b045fbe066f3a206 revision: bafd3cf3970490e029351e6befa56667f568f85f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:11.019490) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:11.019887) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:11.020172) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:11.046898) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:11.047157) ========= 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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-25 14:06:11-- 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% 18.9M=0.001s 2016-11-25 14:06:11 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114548 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:11.209792) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:11.210090) ========= 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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.025517 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:11.262493) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 14:06:11.262789) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bafd3cf3970490e029351e6befa56667f568f85f --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 bafd3cf3970490e029351e6befa56667f568f85f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7hSnLa1nXy/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.k9cBaJl6r6/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-25 14:06:11,362 truncating revision to first 12 chars 2016-11-25 14:06:11,362 Setting DEBUG logging. 2016-11-25 14:06:11,362 attempt 1/10 2016-11-25 14:06:11,362 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bafd3cf39704?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 14:06:12,436 unpacking tar archive at: mozilla-aurora-bafd3cf39704/testing/mozharness/ program finished with exit code 0 elapsedTime=1.346967 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 14:06:12.633944) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:12.634204) ========= script_repo_revision: bafd3cf3970490e029351e6befa56667f568f85f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:12.634560) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:12.634814) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:12.654467) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-25 14:06:12.654756) ========= /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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:06:12 INFO - MultiFileLogger online at 20161125 14:06:12 in /builds/slave/test 14:06:12 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 14:06:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:06:12 INFO - {'append_to_log': False, 14:06:12 INFO - 'base_work_dir': '/builds/slave/test', 14:06:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:06:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:06:12 INFO - 'branch': 'Mozilla-Aurora', 14:06:12 INFO - 'buildbot_json_path': 'buildprops.json', 14:06:12 INFO - 'config_files': ('talos/mac_config.py',), 14:06:12 INFO - 'default_actions': ('clobber', 14:06:12 INFO - 'read-buildbot-config', 14:06:12 INFO - 'download-and-extract', 14:06:12 INFO - 'populate-webroot', 14:06:12 INFO - 'create-virtualenv', 14:06:12 INFO - 'install', 14:06:12 INFO - 'run-tests'), 14:06:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:06:12 INFO - 'download_minidump_stackwalk': True, 14:06:12 INFO - 'download_symbols': 'ondemand', 14:06:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:06:12 INFO - 'tooltool.py': '/tools/tooltool.py', 14:06:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:06:12 INFO - '/tools/misc-python/virtualenv.py')}, 14:06:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:06:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:06:12 INFO - 'installer_path': 'installer.exe', 14:06:12 INFO - 'log_level': 'info', 14:06:12 INFO - 'log_name': 'talos', 14:06:12 INFO - 'log_to_console': True, 14:06:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:06:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:06:12 INFO - 'opt_config_files': (), 14:06:12 INFO - 'pip_index': False, 14:06:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:06:12 INFO - 'cmd': ('bash', 14:06:12 INFO - '-c', 14:06:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:06:12 INFO - 'enabled': True, 14:06:12 INFO - 'halt_on_failure': False, 14:06:12 INFO - 'name': 'check_screen_resolution'},), 14:06:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:06:12 INFO - 'cmd': ('bash', 14:06:12 INFO - '-c', 14:06:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:06:12 INFO - 'enabled': True, 14:06:12 INFO - 'halt_on_failure': False, 14:06:12 INFO - 'name': 'check_screen_resolution'},), 14:06:12 INFO - 'run_cmd_checks_enabled': True, 14:06:12 INFO - 'sps_profile': False, 14:06:12 INFO - 'sps_profile_interval': 0, 14:06:12 INFO - 'suite': 'dromaeojs', 14:06:12 INFO - 'system_bits': '32', 14:06:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:06:12 INFO - 'title': 't-yosemite-r7-0248', 14:06:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:06:12 INFO - 'use_talos_json': True, 14:06:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:06:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:06:12 INFO - 'work_dir': 'build'} 14:06:12 INFO - [mozharness: 2016-11-25 22:06:12.857536Z] Running clobber step. 14:06:12 INFO - Running pre-action listener: _resource_record_pre_action 14:06:12 INFO - Running main action method: clobber 14:06:12 INFO - rmtree: /builds/slave/test/build 14:06:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:06:15 INFO - Running post-action listener: _resource_record_post_action 14:06:15 INFO - [mozharness: 2016-11-25 22:06:15.100507Z] Finished clobber step (success) 14:06:15 INFO - [mozharness: 2016-11-25 22:06:15.100649Z] Running read-buildbot-config step. 14:06:15 INFO - Running pre-action listener: _resource_record_pre_action 14:06:15 INFO - Running main action method: read_buildbot_config 14:06:15 INFO - Using buildbot properties: 14:06:15 INFO - { 14:06:15 INFO - "project": "", 14:06:15 INFO - "product": "firefox", 14:06:15 INFO - "script_repo_revision": "production", 14:06:15 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 14:06:15 INFO - "repository": "", 14:06:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 14:06:15 INFO - "buildid": "20161125122519", 14:06:15 INFO - "pgo_build": "False", 14:06:15 INFO - "basedir": "/builds/slave/test", 14:06:15 INFO - "buildnumber": 5, 14:06:15 INFO - "slavename": "t-yosemite-r7-0248", 14:06:15 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 14:06:15 INFO - "platform": "yosemite_r7", 14:06:15 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:06:15 INFO - "branch": "mozilla-aurora", 14:06:15 INFO - "repo_path": "releases/mozilla-aurora", 14:06:15 INFO - "revision": "bafd3cf3970490e029351e6befa56667f568f85f", 14:06:15 INFO - "stage_platform": "macosx64", 14:06:15 INFO - "builduid": "78dbc2e280e34d38b045fbe066f3a206", 14:06:15 INFO - "slavebuilddir": "test" 14:06:15 INFO - } 14:06:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 14:06:15 INFO - Running post-action listener: _resource_record_post_action 14:06:15 INFO - [mozharness: 2016-11-25 22:06:15.104839Z] Finished read-buildbot-config step (success) 14:06:15 INFO - [mozharness: 2016-11-25 22:06:15.104970Z] Running download-and-extract step. 14:06:15 INFO - Running pre-action listener: _resource_record_pre_action 14:06:15 INFO - Running main action method: download_and_extract 14:06:15 INFO - mkdir: /builds/slave/test/build/tests 14:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:06:15 INFO - trying https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 14:06:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 14:06:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 14:06:16 INFO - Downloaded 1199 bytes. 14:06:16 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 14:06:16 INFO - Using the following test package requirements: 14:06:16 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:06:16 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:16 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 14:06:16 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:16 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 14:06:16 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:16 INFO - u'jsshell-mac.zip'], 14:06:16 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:16 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 14:06:16 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:06:16 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:16 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 14:06:16 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:16 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 14:06:16 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:16 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 14:06:16 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:16 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 14:06:16 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:06:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:06:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:06:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:06:16 INFO - Http code: 200 14:06:16 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 14:06:16 INFO - x-amz-cf-id: SeYTFyUcSW8lx5jSeYmzR-jhffamFTJV8aVMRk_Ns1KINqTpUJjn9A== 14:06:16 INFO - x-amz-version-id: MtZQH6NQkWQ6rOqYvN0PHXlhUA_DMnBW 14:06:16 INFO - Expected file size: 16278947 14:06:16 INFO - Obtained file size: 16278947 14:06:23 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 14:06:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:06:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:06:23 INFO - Fetch https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:06:24 INFO - Http code: 200 14:06:24 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 14:06:24 INFO - x-amz-cf-id: 8LAb6NxBgKpbPzaxFKyyP54sW5vSFhnMVD99X4gXQSNSFBhTff1Ayw== 14:06:24 INFO - x-amz-version-id: MtZQH6NQkWQ6rOqYvN0PHXlhUA_DMnBW 14:06:24 INFO - Expected file size: 16278947 14:06:24 INFO - Obtained file size: 16278947 14:06:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 14:06:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:06:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 14:06:31 INFO - Http code: 200 14:06:31 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 14:06:31 INFO - x-amz-cf-id: 9ByjJLN_dd5wc3-X8mpir_aR8WQNwzPiI-hyQuwi_uap5CTGts0pMA== 14:06:31 INFO - x-amz-version-id: nUeaCLjTADObzprT99S4fcRH.wxQ4xcN 14:06:32 INFO - Expected file size: 14409038 14:06:32 INFO - Obtained file size: 14409038 14:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:06:32 INFO - trying https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:06:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 14:06:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 14:06:35 INFO - Downloaded 109742471 bytes. 14:06:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:06:35 INFO - mkdir: /builds/slave/test/properties 14:06:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:06:35 INFO - Writing to file /builds/slave/test/properties/build_url 14:06:35 INFO - Contents: 14:06:35 INFO - build_url:https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:06:35 INFO - Running post-action listener: _resource_record_post_action 14:06:35 INFO - Running post-action listener: set_extra_try_arguments 14:06:35 INFO - [mozharness: 2016-11-25 22:06:35.772633Z] Finished download-and-extract step (success) 14:06:35 INFO - [mozharness: 2016-11-25 22:06:35.772755Z] Running populate-webroot step. 14:06:35 INFO - Running pre-action listener: _resource_record_pre_action 14:06:35 INFO - Running main action method: populate_webroot 14:06:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:06:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:06:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:06:35 INFO - '--tppagecycles', 14:06:35 INFO - '7'], 14:06:35 INFO - 'tests': ['tsvgm']}}, 14:06:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:06:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:06:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:06:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:06:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:06:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:06:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:35 INFO - 'talos_options': ['--disable-e10s'], 14:06:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:06:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:06:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:35 INFO - 'talos_options': ['--disable-e10s'], 14:06:35 INFO - 'tests': ['damp', 'tps']}, 14:06:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:35 INFO - 'tests': ['damp', 'tps']}, 14:06:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:06:35 INFO - 'tests': ['dromaeo_dom']}, 14:06:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:06:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:06:35 INFO - 'tests': ['basic_compositor_video']}, 14:06:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:06:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:06:35 INFO - 'tests': ['a11yr', 14:06:35 INFO - 'ts_paint', 14:06:35 INFO - 'tpaint', 14:06:35 INFO - 'sessionrestore', 14:06:35 INFO - 'sessionrestore_no_auto_restore', 14:06:35 INFO - 'tabpaint']}, 14:06:35 INFO - 'other-e10s': {'tests': ['a11yr', 14:06:35 INFO - 'ts_paint', 14:06:35 INFO - 'tpaint', 14:06:35 INFO - 'sessionrestore', 14:06:35 INFO - 'sessionrestore_no_auto_restore', 14:06:35 INFO - 'tabpaint']}, 14:06:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:06:35 INFO - 'ts_paint', 14:06:35 INFO - 'tpaint', 14:06:35 INFO - 'sessionrestore', 14:06:35 INFO - 'sessionrestore_no_auto_restore', 14:06:35 INFO - 'tabpaint']}, 14:06:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:06:35 INFO - 'ts_paint', 14:06:35 INFO - 'tpaint', 14:06:35 INFO - 'sessionrestore', 14:06:35 INFO - 'sessionrestore_no_auto_restore', 14:06:35 INFO - 'tabpaint']}, 14:06:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:06:35 INFO - 'tests': ['a11yr', 14:06:35 INFO - 'ts_paint', 14:06:35 INFO - 'tpaint', 14:06:35 INFO - 'sessionrestore', 14:06:35 INFO - 'sessionrestore_no_auto_restore', 14:06:35 INFO - 'tabpaint']}, 14:06:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:06:35 INFO - 'tests': ['a11yr', 14:06:35 INFO - 'ts_paint', 14:06:35 INFO - 'tpaint', 14:06:35 INFO - 'sessionrestore', 14:06:35 INFO - 'sessionrestore_no_auto_restore', 14:06:35 INFO - 'tabpaint']}, 14:06:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:06:35 INFO - 'tests': ['tsvgx', 14:06:35 INFO - 'tsvgr_opacity', 14:06:35 INFO - 'tart', 14:06:35 INFO - 'tscrollx', 14:06:35 INFO - 'cart']}, 14:06:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:06:35 INFO - 'tsvgr_opacity', 14:06:35 INFO - 'tart', 14:06:35 INFO - 'tscrollx', 14:06:35 INFO - 'cart']}, 14:06:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:35 INFO - 'talos_options': ['--disable-e10s'], 14:06:35 INFO - 'tests': ['tp5o']}, 14:06:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:35 INFO - 'tests': ['tp5o']}, 14:06:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:35 INFO - 'talos_options': ['--disable-e10s', 14:06:35 INFO - '--xperf_path', 14:06:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:06:35 INFO - 'tests': ['tp5n']}, 14:06:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:35 INFO - 'talos_options': ['--xperf_path', 14:06:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:06:35 INFO - 'tests': ['tp5n']}}, 14:06:35 INFO - 'talos.zip': {'path': '', 14:06:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:06:35 INFO - Running post-action listener: _resource_record_post_action 14:06:35 INFO - [mozharness: 2016-11-25 22:06:35.791403Z] Finished populate-webroot step (success) 14:06:35 INFO - [mozharness: 2016-11-25 22:06:35.791538Z] Running create-virtualenv step. 14:06:35 INFO - Running pre-action listener: _resource_record_pre_action 14:06:35 INFO - Running main action method: create_virtualenv 14:06:35 INFO - Creating virtualenv /builds/slave/test/build/venv 14:06:35 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 14:06:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:06:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:06:35 INFO - Using real prefix '/tools/python27' 14:06:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:06:36 INFO - Installing distribute.............................................................................................................................................................................................done. 14:06:39 INFO - Installing pip.................done. 14:06:39 INFO - Return code: 0 14:06:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:06:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:06:39 INFO - Reading from file tmpfile_stdout 14:06:39 INFO - Output received: 14:06:39 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) 14:06:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1046e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10465d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb75bd864a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044fbd30>, '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 0x104361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044f3b20>, '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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:06:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:06:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:06:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7hSnLa1nXy/Render', 14:06:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:39 INFO - 'HOME': '/Users/cltbld', 14:06:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:39 INFO - 'LOGNAME': 'cltbld', 14:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:39 INFO - 'MOZ_NO_REMOTE': '1', 14:06:39 INFO - 'NO_EM_RESTART': '1', 14:06:39 INFO - 'PAGER': '/bin/cat', 14:06:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:39 INFO - 'PWD': '/builds/slave/test', 14:06:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:39 INFO - 'SHELL': '/bin/bash', 14:06:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k9cBaJl6r6/Listeners', 14:06:39 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:06:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:39 INFO - 'USER': 'cltbld', 14:06:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:39 INFO - 'XPC_FLAGS': '0x0', 14:06:39 INFO - 'XPC_SERVICE_NAME': '0', 14:06:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:06:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:40 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 14:06:40 INFO - Cleaning up... 14:06:40 INFO - Return code: 0 14:06:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:06:40 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')]} 14:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:40 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')]} 14:06:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:40 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 0x1046e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10465d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb75bd864a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044fbd30>, '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 0x104361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044f3b20>, '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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:06:40 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 14:06:40 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 14:06:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7hSnLa1nXy/Render', 14:06:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:40 INFO - 'HOME': '/Users/cltbld', 14:06:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:40 INFO - 'LOGNAME': 'cltbld', 14:06:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:40 INFO - 'MOZ_NO_REMOTE': '1', 14:06:40 INFO - 'NO_EM_RESTART': '1', 14:06:40 INFO - 'PAGER': '/bin/cat', 14:06:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:40 INFO - 'PWD': '/builds/slave/test', 14:06:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:40 INFO - 'SHELL': '/bin/bash', 14:06:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k9cBaJl6r6/Listeners', 14:06:40 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:06:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:40 INFO - 'USER': 'cltbld', 14:06:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:40 INFO - 'XPC_FLAGS': '0x0', 14:06:40 INFO - 'XPC_SERVICE_NAME': '0', 14:06:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:40 INFO - Downloading/unpacking psutil>=3.1.1 14:06:40 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 14:06:40 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 14:06:40 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 14:06:40 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 14:06:40 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 14:06:40 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 14:06:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:06:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:06:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:06:44 INFO - Installing collected packages: psutil 14:06:44 INFO - Running setup.py install for psutil 14:06:44 INFO - building 'psutil._psutil_osx' extension 14:06:44 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 14:06:44 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 14:06:44 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 14:06:44 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 14:06:45 INFO - building 'psutil._psutil_posix' extension 14:06:45 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 14:06:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:06:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:06:45 INFO - ^ 14:06:45 INFO - 1 warning generated. 14:06:45 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 14:06:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:06:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:06:45 INFO - Successfully installed psutil 14:06:45 INFO - Cleaning up... 14:06:45 INFO - Return code: 0 14:06:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:06: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')]} 14:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06: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')]} 14:06:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1046e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10465d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb75bd864a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044fbd30>, '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 0x104361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044f3b20>, '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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:06:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:06:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:06:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7hSnLa1nXy/Render', 14:06:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:45 INFO - 'HOME': '/Users/cltbld', 14:06:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:45 INFO - 'LOGNAME': 'cltbld', 14:06:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:45 INFO - 'MOZ_NO_REMOTE': '1', 14:06:45 INFO - 'NO_EM_RESTART': '1', 14:06:45 INFO - 'PAGER': '/bin/cat', 14:06:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:45 INFO - 'PWD': '/builds/slave/test', 14:06:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:45 INFO - 'SHELL': '/bin/bash', 14:06:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k9cBaJl6r6/Listeners', 14:06:45 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:06:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:45 INFO - 'USER': 'cltbld', 14:06:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:45 INFO - 'XPC_FLAGS': '0x0', 14:06:45 INFO - 'XPC_SERVICE_NAME': '0', 14:06:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:06:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:06:45 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 14:06:45 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 14:06:45 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 14:06:45 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 14:06:45 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 14:06:45 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 14:06:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:06:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:06:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:06:48 INFO - Installing collected packages: mozsystemmonitor 14:06:48 INFO - Running setup.py install for mozsystemmonitor 14:06:48 INFO - Successfully installed mozsystemmonitor 14:06:48 INFO - Cleaning up... 14:06:48 INFO - Return code: 0 14:06:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:06:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:48 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 0x1046e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10465d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb75bd864a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044fbd30>, '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 0x104361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044f3b20>, '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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:06:48 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 14:06:48 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 14:06:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7hSnLa1nXy/Render', 14:06:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:48 INFO - 'HOME': '/Users/cltbld', 14:06:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:48 INFO - 'LOGNAME': 'cltbld', 14:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:48 INFO - 'MOZ_NO_REMOTE': '1', 14:06:48 INFO - 'NO_EM_RESTART': '1', 14:06:48 INFO - 'PAGER': '/bin/cat', 14:06:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:48 INFO - 'PWD': '/builds/slave/test', 14:06:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:48 INFO - 'SHELL': '/bin/bash', 14:06:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k9cBaJl6r6/Listeners', 14:06:48 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:06:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:48 INFO - 'USER': 'cltbld', 14:06:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:48 INFO - 'XPC_FLAGS': '0x0', 14:06:48 INFO - 'XPC_SERVICE_NAME': '0', 14:06:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:48 INFO - Downloading/unpacking jsonschema==2.5.1 14:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:06:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:06:51 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 14:06:51 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 14:06:51 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 14:06:51 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 14:06:51 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 14:06:51 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 14:06:51 INFO - Downloading functools32-3.2.3-2.tar.gz 14:06:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:06:51 INFO - warning: no files found matching '*.txt' 14:06:51 INFO - no previously-included directories found matching 'build' 14:06:51 INFO - no previously-included directories found matching 'dist' 14:06:51 INFO - no previously-included directories found matching '.git*' 14:06:51 INFO - Installing collected packages: jsonschema, functools32 14:06:51 INFO - Running setup.py install for functools32 14:06:51 INFO - warning: no files found matching '*.txt' 14:06:51 INFO - no previously-included directories found matching 'build' 14:06:51 INFO - no previously-included directories found matching 'dist' 14:06:51 INFO - no previously-included directories found matching '.git*' 14:06:51 INFO - Successfully installed jsonschema functools32 14:06:51 INFO - Cleaning up... 14:06:51 INFO - Return code: 0 14:06:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:06:51 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')]} 14:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:51 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')]} 14:06:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:51 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 0x1046e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10465d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb75bd864a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044fbd30>, '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 0x104361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044f3b20>, '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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:06:51 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 14:06:51 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 14:06:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7hSnLa1nXy/Render', 14:06:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:51 INFO - 'HOME': '/Users/cltbld', 14:06:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:51 INFO - 'LOGNAME': 'cltbld', 14:06:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:51 INFO - 'MOZ_NO_REMOTE': '1', 14:06:51 INFO - 'NO_EM_RESTART': '1', 14:06:51 INFO - 'PAGER': '/bin/cat', 14:06:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:51 INFO - 'PWD': '/builds/slave/test', 14:06:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:51 INFO - 'SHELL': '/bin/bash', 14:06:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k9cBaJl6r6/Listeners', 14:06:51 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:06:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:51 INFO - 'USER': 'cltbld', 14:06:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:51 INFO - 'XPC_FLAGS': '0x0', 14:06:51 INFO - 'XPC_SERVICE_NAME': '0', 14:06:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:06:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:06:52 INFO - Cleaning up... 14:06:52 INFO - Return code: 0 14:06:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:06:52 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')]} 14:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:52 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')]} 14:06:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:52 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 0x1046e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10465d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb75bd864a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044fbd30>, '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 0x104361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044f3b20>, '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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:06:52 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 14:06:52 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 14:06:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7hSnLa1nXy/Render', 14:06:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:52 INFO - 'HOME': '/Users/cltbld', 14:06:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:52 INFO - 'LOGNAME': 'cltbld', 14:06:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:52 INFO - 'MOZ_NO_REMOTE': '1', 14:06:52 INFO - 'NO_EM_RESTART': '1', 14:06:52 INFO - 'PAGER': '/bin/cat', 14:06:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:52 INFO - 'PWD': '/builds/slave/test', 14:06:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:52 INFO - 'SHELL': '/bin/bash', 14:06:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k9cBaJl6r6/Listeners', 14:06:52 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:06:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:52 INFO - 'USER': 'cltbld', 14:06:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:52 INFO - 'XPC_FLAGS': '0x0', 14:06:52 INFO - 'XPC_SERVICE_NAME': '0', 14:06:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:06:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:52 INFO - Downloading/unpacking blobuploader==1.2.4 14:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:54 INFO - Downloading blobuploader-1.2.4.tar.gz 14:06:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:06:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:06:55 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 14:06:55 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 14:06:55 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 14:06:55 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 14:06:55 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 14:06:55 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 14:06:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:06:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:06:55 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 14:06:55 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 14:06:55 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 14:06:55 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 14:06:55 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 14:06:55 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 14:06:56 INFO - Downloading docopt-0.6.1.tar.gz 14:06:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:06:56 INFO - Installing collected packages: blobuploader, requests, docopt 14:06:56 INFO - Running setup.py install for blobuploader 14:06:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:06:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:06:56 INFO - Running setup.py install for requests 14:06:56 INFO - Running setup.py install for docopt 14:06:57 INFO - Successfully installed blobuploader requests docopt 14:06:57 INFO - Cleaning up... 14:06:57 INFO - Return code: 0 14:06:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:06:57 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')]} 14:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:57 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')]} 14:06:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:57 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 0x1046e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10465d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb75bd864a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044fbd30>, '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 0x104361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044f3b20>, '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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:06:57 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 14:06:57 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 14:06:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7hSnLa1nXy/Render', 14:06:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:57 INFO - 'HOME': '/Users/cltbld', 14:06:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:57 INFO - 'LOGNAME': 'cltbld', 14:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:57 INFO - 'MOZ_NO_REMOTE': '1', 14:06:57 INFO - 'NO_EM_RESTART': '1', 14:06:57 INFO - 'PAGER': '/bin/cat', 14:06:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:57 INFO - 'PWD': '/builds/slave/test', 14:06:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:57 INFO - 'SHELL': '/bin/bash', 14:06:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k9cBaJl6r6/Listeners', 14:06:57 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:06:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:57 INFO - 'USER': 'cltbld', 14:06:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:57 INFO - 'XPC_FLAGS': '0x0', 14:06:57 INFO - 'XPC_SERVICE_NAME': '0', 14:06:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:06:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:06:57 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-OqZw8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:06:57 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-5t16gk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:06:57 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-oTm4VA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:06:57 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-mfpgf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:06:57 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-21348n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:06:57 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-pny_RW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:06:58 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-OtIkWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:06:58 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-WbgPS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:06:58 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-ivaffm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:06:58 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-POjMWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:06:58 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-mCqWwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:06:58 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-_PLwhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:06:58 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-3HrD1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:06:58 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-pivRoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:06:58 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-E21X_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:06:58 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-FKrUkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:06:59 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-CpMMmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:06:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:06:59 INFO - Running setup.py install for manifestparser 14:06:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:06:59 INFO - Running setup.py install for mozcrash 14:06:59 INFO - Running setup.py install for mozdebug 14:06:59 INFO - Running setup.py install for mozdevice 14:06:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:06:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:06:59 INFO - Running setup.py install for mozfile 14:06:59 INFO - Running setup.py install for mozhttpd 14:07:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:07:00 INFO - Running setup.py install for mozinfo 14:07:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:07:00 INFO - Running setup.py install for mozInstall 14:07:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:07:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:07:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:07:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:07:00 INFO - Running setup.py install for mozleak 14:07:00 INFO - Running setup.py install for mozlog 14:07:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:07:00 INFO - Running setup.py install for moznetwork 14:07:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:07:00 INFO - Running setup.py install for mozprocess 14:07:00 INFO - Running setup.py install for mozprofile 14:07:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:07:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:07:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:07:01 INFO - Running setup.py install for mozrunner 14:07:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:07:01 INFO - Running setup.py install for mozscreenshot 14:07:01 INFO - Running setup.py install for moztest 14:07:01 INFO - Running setup.py install for mozversion 14:07:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:07:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:07:01 INFO - Cleaning up... 14:07:01 INFO - Return code: 0 14:07:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:07:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:01 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 0x1046e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10465d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb75bd864a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044fbd30>, '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 0x104361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044f3b20>, '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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:07:01 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 14:07:01 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 14:07:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7hSnLa1nXy/Render', 14:07:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:07:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:07:01 INFO - 'HOME': '/Users/cltbld', 14:07:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:07:01 INFO - 'LOGNAME': 'cltbld', 14:07:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:01 INFO - 'MOZ_NO_REMOTE': '1', 14:07:01 INFO - 'NO_EM_RESTART': '1', 14:07:01 INFO - 'PAGER': '/bin/cat', 14:07:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:07:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:01 INFO - 'PWD': '/builds/slave/test', 14:07:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:07:01 INFO - 'SHELL': '/bin/bash', 14:07:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k9cBaJl6r6/Listeners', 14:07:01 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:07:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:07:01 INFO - 'USER': 'cltbld', 14:07:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:07:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:07:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:01 INFO - 'XPC_FLAGS': '0x0', 14:07:01 INFO - 'XPC_SERVICE_NAME': '0', 14:07:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:07:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:07:02 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-8MRoUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:07:02 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)) 14:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:07:02 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-RQ82sH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:07:02 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)) 14:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:07:02 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-Cne6pA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:07:02 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)) 14:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:07:02 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-e1Cf2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:07:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:07:02 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-4CSE1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:07:02 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)) 14:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:07:02 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-rednGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:07:02 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)) 14:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:07:02 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-HZl48Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:07:02 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)) 14:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:07:02 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-i5JjwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:07:02 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)) 14:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:07:02 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-nSplsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:07:02 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)) 14:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:07:02 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-jqoh1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:07:03 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)) 14:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:07:03 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-hvw30P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:07:03 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)) 14:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:07:03 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-zMEwwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:07:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:07:03 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-xpce84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:07:03 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)) 14:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:07:03 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-rVb5LC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:07:03 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)) 14:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:07:03 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-yKSc0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:07:03 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)) 14:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:07:03 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-_56EP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:07:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:07:03 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-XGHrB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:07:03 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)) 14:07:03 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)) 14:07:03 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)) 14:07:03 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)) 14:07:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:07:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:07:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:07:03 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 14:07:03 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 14:07:03 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 14:07:03 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 14:07:03 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 14:07:03 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 14:07:06 INFO - Downloading blessings-1.6.tar.gz 14:07:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:07:06 INFO - Installing collected packages: blessings 14:07:06 INFO - Running setup.py install for blessings 14:07:06 INFO - Successfully installed blessings 14:07:06 INFO - Cleaning up... 14:07:06 INFO - Return code: 0 14:07:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:07:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:07:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:07:06 INFO - Reading from file tmpfile_stdout 14:07:06 INFO - Current package versions: 14:07:06 INFO - blessings == 1.6 14:07:06 INFO - blobuploader == 1.2.4 14:07:06 INFO - docopt == 0.6.1 14:07:06 INFO - functools32 == 3.2.3-2 14:07:06 INFO - jsonschema == 2.5.1 14:07:06 INFO - manifestparser == 1.1 14:07:06 INFO - mozInstall == 1.12 14:07:06 INFO - mozcrash == 1.0 14:07:06 INFO - mozdebug == 0.1 14:07:06 INFO - mozdevice == 0.48 14:07:06 INFO - mozfile == 1.2 14:07:06 INFO - mozhttpd == 0.7 14:07:06 INFO - mozinfo == 0.9 14:07:06 INFO - mozleak == 0.1 14:07:06 INFO - mozlog == 3.4 14:07:06 INFO - moznetwork == 0.27 14:07:06 INFO - mozprocess == 0.23 14:07:06 INFO - mozprofile == 0.28 14:07:06 INFO - mozrunner == 6.13 14:07:06 INFO - mozscreenshot == 0.1 14:07:06 INFO - mozsystemmonitor == 0.3 14:07:06 INFO - moztest == 0.7 14:07:06 INFO - mozversion == 1.4 14:07:06 INFO - psutil == 3.1.1 14:07:06 INFO - requests == 1.2.3 14:07:06 INFO - wsgiref == 0.1.2 14:07:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:07:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:07:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:07:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:06 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 0x1046e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10465d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb75bd864a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044fbd30>, '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 0x104361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044f3b20>, '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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:07:06 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 14:07:06 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 14:07:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7hSnLa1nXy/Render', 14:07:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:07:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:07:06 INFO - 'HOME': '/Users/cltbld', 14:07:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:07:06 INFO - 'LOGNAME': 'cltbld', 14:07:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:06 INFO - 'MOZ_NO_REMOTE': '1', 14:07:06 INFO - 'NO_EM_RESTART': '1', 14:07:06 INFO - 'PAGER': '/bin/cat', 14:07:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:07:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:06 INFO - 'PWD': '/builds/slave/test', 14:07:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:07:06 INFO - 'SHELL': '/bin/bash', 14:07:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k9cBaJl6r6/Listeners', 14:07:06 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:07:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:07:06 INFO - 'USER': 'cltbld', 14:07:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:07:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:07:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:06 INFO - 'XPC_FLAGS': '0x0', 14:07:06 INFO - 'XPC_SERVICE_NAME': '0', 14:07:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:07:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:07 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)) 14:07:07 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)) 14:07:07 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)) 14:07:07 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)) 14:07:07 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)) 14:07:07 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)) 14:07:07 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)) 14:07:07 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)) 14:07:07 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)) 14:07:07 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)) 14:07:07 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)) 14:07:07 INFO - Cleaning up... 14:07:07 INFO - Return code: 0 14:07:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:07:07 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')]} 14:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:07 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')]} 14:07:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:07 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 0x1046e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10465d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb75bd864a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044fbd30>, '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 0x104361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044f3b20>, '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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:07:07 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 14:07:07 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 14:07:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7hSnLa1nXy/Render', 14:07:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:07:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:07:07 INFO - 'HOME': '/Users/cltbld', 14:07:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:07:07 INFO - 'LOGNAME': 'cltbld', 14:07:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:07 INFO - 'MOZ_NO_REMOTE': '1', 14:07:07 INFO - 'NO_EM_RESTART': '1', 14:07:07 INFO - 'PAGER': '/bin/cat', 14:07:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:07:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:07 INFO - 'PWD': '/builds/slave/test', 14:07:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:07:07 INFO - 'SHELL': '/bin/bash', 14:07:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k9cBaJl6r6/Listeners', 14:07:07 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:07:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:07:07 INFO - 'USER': 'cltbld', 14:07:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:07:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:07:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:07 INFO - 'XPC_FLAGS': '0x0', 14:07:07 INFO - 'XPC_SERVICE_NAME': '0', 14:07:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:07:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:07:07 INFO - Cleaning up... 14:07:07 INFO - Return code: 0 14:07:07 INFO - Running post-action listener: _resource_record_post_action 14:07:07 INFO - Running post-action listener: _start_resource_monitoring 14:07:07 INFO - Starting resource monitoring. 14:07:07 INFO - [mozharness: 2016-11-25 22:07:07.549294Z] Finished create-virtualenv step (success) 14:07:07 INFO - [mozharness: 2016-11-25 22:07:07.550351Z] Running install step. 14:07:07 INFO - Running pre-action listener: _resource_record_pre_action 14:07:07 INFO - Running main action method: install 14:07:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:07:07 INFO - Reading from file tmpfile_stdout 14:07:07 INFO - Detecting whether we're running mozinstall >=1.0... 14:07:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:07:07 INFO - Reading from file tmpfile_stdout 14:07:07 INFO - Output received: 14:07:07 INFO - Usage: mozinstall [options] installer 14:07:07 INFO - Options: 14:07:07 INFO - -h, --help show this help message and exit 14:07:07 INFO - -d DEST, --destination=DEST 14:07:07 INFO - Directory to install application into. [default: 14:07:07 INFO - "/builds/slave/test"] 14:07:07 INFO - --app=APP Application being installed. [default: firefox] 14:07:07 INFO - mkdir: /builds/slave/test/build/application 14:07:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 14:07:38 INFO - Reading from file tmpfile_stdout 14:07:38 INFO - Output received: 14:07:38 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:07:38 INFO - Running post-action listener: _resource_record_post_action 14:07:38 INFO - [mozharness: 2016-11-25 22:07:38.860610Z] Finished install step (success) 14:07:38 INFO - [mozharness: 2016-11-25 22:07:38.860719Z] Running run-tests step. 14:07:38 INFO - Running pre-action listener: _resource_record_pre_action 14:07:38 INFO - Running main action method: run_tests 14:07:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:07:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:07:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:07:38 INFO - 2016-11-25 14:07:38.873 screenresolution[1634:7913] starting screenresolution argv=screenresolution get 14:07:38 INFO - 2016-11-25 14:07:38.876 screenresolution[1634:7913] Display 0: 1600x1200x32@60 14:07:38 INFO - 2016-11-25 14:07:38.881 screenresolution[1635:7915] starting screenresolution argv=screenresolution list 14:07:38 INFO - Available Modes on Display 0 14:07:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:07:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:07:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:07:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:07:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:07:39 INFO - Intel Iris: 14:07:39 INFO - Chipset Model: Intel Iris 14:07:39 INFO - Type: GPU 14:07:39 INFO - Bus: Built-In 14:07:39 INFO - VRAM (Dynamic, Max): 1536 MB 14:07:39 INFO - Vendor: Intel (0x8086) 14:07:39 INFO - Device ID: 0x0a2e 14:07:39 INFO - Revision ID: 0x0009 14:07:39 INFO - Displays: 14:07:39 INFO - Display: 14:07:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:07:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:07:39 INFO - Display Serial Number: 1600x1200 60 14:07:39 INFO - Main Display: Yes 14:07:39 INFO - Mirror: Off 14:07:39 INFO - Online: Yes 14:07:39 INFO - Rotation: Supported 14:07:39 INFO - Return code: 0 14:07:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:07:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:07:39 INFO - Python 2.7.3 14:07:39 INFO - Return code: 0 14:07:39 INFO - grabbing minidump binary from tooltool 14:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:07:39 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044fbd30>, '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 0x104361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044f3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:07:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:07:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:07:39 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:07:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:07:39 INFO - Return code: 0 14:07:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:07:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:07:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:07:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:07:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:07:39 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/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--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 14:07:39 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/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0248 --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 14:07:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7hSnLa1nXy/Render', 14:07:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:07:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:07:39 INFO - 'HOME': '/Users/cltbld', 14:07:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:07:39 INFO - 'LOGNAME': 'cltbld', 14:07:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:07:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:39 INFO - 'MOZ_NO_REMOTE': '1', 14:07:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:07:39 INFO - 'NO_EM_RESTART': '1', 14:07:39 INFO - 'PAGER': '/bin/cat', 14:07:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:39 INFO - 'PWD': '/builds/slave/test', 14:07:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:07:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:07:39 INFO - 'SHELL': '/bin/bash', 14:07:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k9cBaJl6r6/Listeners', 14:07:39 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:07:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:07:39 INFO - 'USER': 'cltbld', 14:07:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:07:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:39 INFO - 'XPC_FLAGS': '0x0', 14:07:39 INFO - 'XPC_SERVICE_NAME': '0', 14:07:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:07:39 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/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--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 14:07:39 INFO - mozversion application_buildid: 20161125122519 14:07:39 INFO - mozversion application_changeset: bafd3cf3970490e029351e6befa56667f568f85f 14:07:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:07:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:07:39 INFO - mozversion application_name: Firefox 14:07:39 INFO - mozversion application_remotingname: firefox-dev 14:07:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:07:39 INFO - mozversion application_vendor: Mozilla 14:07:39 INFO - mozversion application_version: 52.0a2 14:07:39 INFO - mozversion platform_buildid: 20161125122519 14:07:39 INFO - mozversion platform_changeset: bafd3cf3970490e029351e6befa56667f568f85f 14:07:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:07:39 INFO - mozversion platform_version: 52.0a2 14:07:39 INFO - using testdate: 1480111659 14:07:39 INFO - actual date: 1480111659 14:07:39 INFO - starting webserver on 'localhost:49235' 14:07:39 INFO - SUITE-START | Running 2 tests 14:07:39 INFO - TEST-START | dromaeo_css 14:07:39 INFO - operating with platform_type : mac_ 14:07:39 INFO - Initialising browser for dromaeo_css test... 14:07:39 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmp75iZXY/profile http://localhost:49235/getInfo.html) 14:07:42 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 14:07:42 INFO - PROCESS | 1645 | colorDepth:24 14:07:42 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 14:07:42 INFO - PROCESS | 1645 | __metrics 14:07:42 INFO - PROCESS | 1645 | 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" 14:07:42 INFO - PROCESS | 1645 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:07:43 INFO - TEST-INFO | 1645: exit 0 14:07:43 INFO - Browser initialized. 14:07:43 INFO - Running cycle 1/1 for dromaeo_css test... 14:07:43 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmp75iZXY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:07:55 INFO - PROCESS | 1648 | RSS: Main: 222892032 14:07:55 INFO - PROCESS | 1648 | 14:08:46 INFO - PROCESS | 1648 | 1480111726692 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:10:59 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 14:11:01 INFO - PROCESS | 1648 | RSS: Main: 385167360 14:11:01 INFO - PROCESS | 1648 | 14:14:04 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 14:14:04 INFO - PROCESS | 1648 | RSS: Main: 348180480 14:14:04 INFO - PROCESS | 1648 | 14:17:07 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 14:17:07 INFO - PROCESS | 1648 | RSS: Main: 313499648 14:17:07 INFO - PROCESS | 1648 | 14:20:10 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 14:20:10 INFO - PROCESS | 1648 | RSS: Main: 318439424 14:20:10 INFO - PROCESS | 1648 | 14:23:14 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 14:23:14 INFO - PROCESS | 1648 | RSS: Main: 308477952 14:23:14 INFO - PROCESS | 1648 | 14:26:17 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 14:26:18 INFO - PROCESS | 1648 | RSS: Main: 416550912 14:26:18 INFO - PROCESS | 1648 | 14:26:18 INFO - PROCESS | 1648 | __start_tp_report 14:26:18 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 14:26:18 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 14:26:18 INFO - PROCESS | 1648 | |i|pagename|runs| 14:26:18 INFO - PROCESS | 1648 | |0;dojo.html;3299.521569372441;3309.189248634085;3309.9338013239735;3322.601287845459;3365.276465559904;5638.492535671789;5680.573956953224;5694.601377903548;5710.685912274823;5730.197772311869;5623.465770751783;5683.99999999999;5696.25948626679;5729.598928075036;5733.340665823432;4342.196693611684;5185.455527169649;5188.740562971855;5227.398849132342;5249.396319423277;5157.458466860982;5159.845204643848;5175.378954525443;5313.326302214809;5340.145576707728;5177.896442071156;5196.792128314882;5223.529882310591;5231.659942103751;5253.7373131343475;5249.685018898878;5291.441922895272;5305.071612467803;5314.5748340132695;5341.706206158659;5067.417247016603;5134.383873935151;5216.634835561512;5223.451537588575;5232.372115346183;5550.084236101038;5580.246666699984;5588.580856435784;5632.436756324376;5634.971825140889;6126.29547602027;6169.629822210637;6182.319944806068;6222.284437289724;6227;6219.471344935686;6243.906341404882;6260.906086408708;6265.4361107500545;6267.843303917393;5658.632188907708;5727.11229759388;5731.254936858182;5755.107958266475;5896.997510423238;6233.283172435228;6273.686315684197;6278.058291256348;6291.591046582049;6304.464120549713;6149.96925015372;6175.382461753788;6188.164597779332;6189.226346706661;6193.1329613854095;6116.97495384395;6142.477889379364;6157.476614487819;6182.907256391158;6234.688265586702;6173.135760993375;6238.376162383725;6243.719032643542;6247.125402443661;6264.843378915563;6225.937740622536;6260.937390626035;6262.1546091278005;6269.8746025079245;6285.182926219562;4851.684640498356;4924.901501969941;4968.627352948543;4974.403071631355;4992.875178120504;4392;4396.054848207651;4432.180046691373;4449.532799056117;4451.888702782457;5780.855478612984;5781.53747700183;5803.390643982321;5812.360640329561;5816.563757718103;6289;6305.747770089237;6307.621542707452;6308.27454842699;6328.557001009885;4743.644226683013;5593.049181639131;5639.520640745583;5677.006523858308;5746.827595172152;5373.516383525423;5391.137418013099;5405.945940540544;5428.67427954324;5447.319085114361;5296.920546191887;6295.748170073238;6305.936940630535;6320.525960552977;6342.904856427065;5853.94146058534;6162.722677479434;6288.11966324724;6296.779612713716;6301.243850737757;6224.533160013108;6242.438180563771;6277.5605707600025;6291.528135389955;6304.747810087545;6147.385261473817;6173.938260617337;6199.56603037783;6205.944989351911;6221.253449586078;6082.847928801815;6240.937590624036;6275.090111933835;6290.654014029273;6298.02380631003;5853.590248682552;5921.5854890159;6042.516598672184;6111.99152139892;6134.344744984565;6117.431594453999;6130.080487926846;6131.0708424985305;6136.549713441251;6152.138700581832;5009.023240468074;5950.2264705587995;5950.404959504015;5955.076963070731;5961.672108034099;5832.562557808266;5941.881162376815;5944.405559444021;5995.370486098896;5995.520358371407;5169.198774190007;5428.402875683751;5808.186853840381;5859.384764599826;5864.413558644102 14:26:18 INFO - PROCESS | 1648 | |1;ext.html;475.64564399522357;492.4558363008875;510.8429407392257;518.9896202075959;521.2806327268369;29641.555376669367;34119.82940085299;38005.80997095014;39058.21883562327;39273.01817454565;39880;40356.59643403565;40393.79803100981;40417.19165616686;40566.188676226455;8450.070492245879;9275.860862087091;9460.385074970105;9461.006594307568;9478.38390504615;8475.576221188947;11093.611723589676;11352.772944541104;11375.089992800598;11534.192606517547;11013.339199648006;11114.722131946684;11151.38667373294;11184.328940263611;11238.213325067249;11178.27341222818;11221.551137954471;11240.325580465116;11268.09855211581;11273.154513411486;11101.44492775358;11174.608888688937;11192.832107518394;11193.49629266685;11197.440127993568;37812.05469863248;38171.809140954116;38181.42727859056;38187;38196.80901595502;3119.1890108571706;3469.444888817777;3782.130110074671;3809.276237514863;3826.502554667903;29968.85015574908;30384.84807575948;30496;30586.235344116576;30955.535666965014;2709.888945532342;3178.8410579471165;3194.5048517479827;3219.404410184123;3237.093613788143;437912;510699;514150.86321115267;514626;524967;64475.67762161159;66120.33879661238;66144.66927665331;66189;66327.67344653177;35859.82070089633;36674.08314792151;36936.26127477489;37359.81320093382;37379.06552336214;15886.046837189806;16452;16623.750643740354;16632.918860274043;16671.749923751155;11646.767064658829;11801.645950621323;12427.502899884084;12501.312427816558;12673.873261267454;19811.801881980995;20109.290710289497;20136.899315503328;20146.194152233747;20156.59686806285;8149.266566009088;8272.793180170544;8281.337493000545;8308.335333173332;8363.665453381796;834.8664213725775;883.8320719063486;974.8196583632051;988.5106872098357;1014.4167103915278;28129.015484457945;29023.709762902523;29062.85468572686;29178.954524097597;29799.85100074529;1046.602291129366;1052.4369462337613;1129.3280498103616;1129.6046383765781;1170.8887655672697;12144.210626309437;14785.630359241106;15110.564496372826;15111.31999060045;15338.156401397808;684468;690601.5469922719;691788;694022;694399;52961.735191324566;56671.716641417355;56746.71626641841;56835.71582142063;56857.71571142118;1223.9694007649703;1253.7931241345075;1341.7584834729648;1369.5069774881035;1396.0995158123158;29674.109776706733;30021.24946876258;30340.544891826197;30532.694673053873;31056;46924.296135558674;47402.288965664855;47431.76284118558;47435.52564474449;47595.28607070827;542473;545219;546567;546952;643455;39070;40140.79577612676;40386.39420408638;40517.1896562073;40552.78341649755;39265.82202533929;41242.79378603088;43781.56218437902;43912.560874392126;44457.99878010995;29776.85111574428;35935;36864.815675922524;37029.074273143386;37036.25927481489;36271.455928161624;36455.817720911225;36484;36496;36516;35692.643073568936;35943.46084808782;36174;36218.73234436782;36384.27231455409;30980.69019309778;32295.51556726698;32454.837725812165;32535.837320814193;32962.5055624161;24425;24448.633270500603;24844.751552484966;25022.499550009263;25056 14:26:18 INFO - PROCESS | 1648 | |2;jquery.html;344.39902370363717;349.50544978854924;371.3223367354578;376.1142509390386;384.4387194695745;14832.258387080643;15115.697686046273;15738.92130539347;19542.120604572792;20220;19129.904350478228;20218.59562808743;20422.285220017304;20529;20706.27528036519;5605.803796867121;5850.414958504158;5977.5815692901515;5977.790777322794;5989.401059893998;4677.555632214951;5918.4969277611235;5982.820515384546;6003.909941350883;6013.218281623383;5134.614903882205;5621.437856214367;5911.201987731644;5920.911186332209;5928.555358348141;5737.856553586152;5834.562407819388;5845;5845.707714614252;5858.2677165354335;4851.417829860439;5092.974535127338;5093.516115968977;5096.79612815487;5115.411727651293;24153.758462415328;24509.87745061281;24654.753452465426;25104.37239069038;25285.49429011428;2164.8159906407973;2367.964480532776;2634.249238966885;3011.1568760747027;3024.833634150121;14427.639309017359;14512.274386280644;14554.053986490844;14938.253087345589;15210.543683689508;1799.7930238022664;1801.918913648889;1854.7665802241493;1863.813618638139;1868.8224618661206;106393.93606063917;113414;116589;121097.39451302687;121165;9549.952250238843;10589.788204235872;11698.649040528799;11706.648800535997;11759.882401175879;14767.966242363147;14795.852041479448;14803.629909252355;14826.629334266514;14836.184009879344;8390.286825619769;8612.48325100496;8645.870311945326;8687.739367818975;8746.69386571467;5999.520038396918;8488.278496327881;8492.575371231414;8548.957255213683;8622.655093796304;4460.620847228022;4552.385427967249;4562.794674239667;4613.100445413179;4620.399348084795;1872.5318670332417;1920.846332293441;1924.9422517324504;1928.845692344609;1932.719755635425;806.6208881825532;835.2900034970227;858.2018722588053;910.8887157667635;921.0841449784432;15881;20989.89505052465;21176;21279.468013299793;21465.57068858614;885.106042896679;908.8454962656365;919.3977944446399;930.5719369090159;932.3799673217235;10208.948955255177;10938.726531836608;10961.945190274157;10984.670459886056;11011.889881101086;593090;630052;640298;646899;647754;116036;117489.82510174789;119796;124545.37727311306;127803.36098319449;876.1807709791366;933.7992331648592;1003.8343673293824;1015.9441230732233;1023.9948800255951;18006.819931801037;19010.1445434953;19561.902190489527;19713.310034148737;19735.703964440832;36762.63237367699;38592.807035965765;54838.17742733942;55769;57096;77627.61186194033;84549;84816.45550633491;84906.30187396094;85768;6375.394337537927;7502.962485187539;7888.566128862968;8218.547979860927;8316.875246871423;11389.373584452935;12887.742245155232;12897.226166430044;12993.805092923425;13227.338633068115;9151.954240228757;10954.890451095705;11117.22179447431;11128.944355278172;11230.719232019264;8355.958220209102;10890.945545272223;10911.618093366695;11352.54589816398;11622.5350985963;11329.376884271443;11554.768904621693;11698.649040528799;11786.76426471483;11859;7870.921290787248;8021.358291336563;8242.670293188205;8384.035835878709;8422.368322375969;8986.370954033155;11007.724806879893;11167.832482512606;11258.66224013281;11305.095592352756 14:26:18 INFO - PROCESS | 1648 | |3;mootools.html;1256.4346044280076;1459.4599998000742;1468.0384348251896;1618.692448434798;1824.033262370944;7572.772816815497;7985.401094917883;8278.172182781711;8539.530325832093;8555.78610534737;8387.412881098311;8471.279941205004;8489.066202717711;8493;8502.659893604248;2305.688732021181;2348.7768661977084;2349.0955981946986;2349.600567903452;2353.399883029825;2128.648772952461;2193.824494040481;2387.1167667962877;2387.2002879035545;2388.379021454417;1921.5772530043369;2317.0963324303534;2337.0885354711677;2347.7417484076736;2348.989934328238;2298.8505747126383;2310.9422264443356;2330.6504024396477;2341.590221711194;2343.4492894169857;2320.431494283894;2347.0729062020578;2357.9528409431714;2361.480474295652;2361.4922791599715;7413.666385012633;8471.237588616992;8496.320294376435;8588.22705956467;8603.096674849114;5089.847304580869;5283.101872681654;5298.231756395301;5307.522322990911;5322.813701520428;3287.063186991696;3524.6475352464804;3531.3290474809705;3568.982155089208;3607.350676878161;968.9951550242204;1069.2996087562658;1076.8707755069363;1085.9728506787394;1111.6053800900725;11036.11711063121;11131.443427828575;11234.719132021683;11236.719082022933;11243.494042768096;4232.449781528443;4387.956120438755;4491.528389519053;4497.2129877271345;4508.661850361236;8542.957285213659;8694.521801300863;8716.825663486694;8740.650373984969;8768.868466973001;5097.464766199496;5097.8725531861255;5101.336826212569;5102.617303702237;5124.769385377593;4040.1313717550865;4278.272693642089;4420.005498762753;4437.866863994085;4773.164696178155;3632.146445585285;3663.578688450809;3739.8130093495215;3740.251949610089;3750.0999760057935;920.62254475666;1018.3889666200221;1126.6788965144895;1139.7036770439745;1140.1904647700226;659.6042374575217;898.820235952812;908.3868388837508;922.718570835888;926.2312280806914;5465.316835395575;5657.679026125735;5669.574781891375;5730.512906402919;5819.127130930395;894.4007514964859;922.9446233226028;943.8437913555977;974.7514383832078;1001.2290536287019;3640.7633503822167;3800.239952009609;3800.467934489174;3824.808759562011;3964.345882929342;10016.49917504122;10230.07929286353;10273.9486302568;10451.425171615481;10459.267851250492;10206.13247873939;10322.793544129076;10389.896101039045;10396.064354208158;10430.26988110825;7682.846343073219;7992.880106798402;8162.795930101795;8170.959145204236;8171.591420428955;5560.332760068656;5643.633163844337;5648.915266270927;5743.253377060955;5800.274965629296;8645.876036115265;9150.084991500797;9441.291903107433;9499.192568631745;9638.325317227727;8939.821203575762;9283.210927071275;9334.786477757849;9981.700548983543;9998;5341.278927344914;6511.153550038464;6520;6521.804345869631;6545.803625891231;7738.419618528746;7924.564148971862;7989.680412783656;8054.395920306118;8107.716229931924;6214.9067763982675;7207.891881621675;7212.603306817966;7308.707651693872;7357.043584334002;7046.541974771623;7130.286971303036;7138.821529461805;7140.642967851482;7151.4636402269;6628.270890201978;6774.661266936732;6778.186617605918;6792.898106528505;6803.7618683345845;4842;4895.1922932717125;4899.510048995072;4967.82612608557;5007.273945277987;4850.733209673502;4908.828191013297;4954.801807927643;5036.622253331088;5047.4700156484505 14:26:18 INFO - PROCESS | 1648 | |4;prototype.html;299.2085932707987;302.75023105937595;309.7212508742132;313.02960821453496;314.38537658876896;4610.469795973464;5078.568321692651;5154.8194064964655;5170.8448746537615;5252.422233554306;3848.9615103848955;4579.358889755437;4643.024964757405;4810.470848206694;5050.348567002326;3939.5075615548058;3949.289127956981;3988.2621714982674;3994.680425565962;4025.3898440623693;3937.5078115235597;3940.566537680853;3984.1434091670285;3985.003749062734;4000.239954408653;3542.6103128655827;3696.186838895452;3924.2543916655886;3937.8227979740836;3951.9209615807667;3105;3922.5489068757174;3934.488516492852;3935.3900145477496;3969.206158768229;3411.624721280657;3423.9315213695836;3428.777129486575;3431.8455669494933;3436.8281585920604;4766.308885211625;4802.639802014828;4967;4991.600671946236;5019.598432125421;4494.550544945512;4510.661700372485;4516.683832131752;4521.208788462039;4547.749873756942;5408.486193811621;5449.683884349136;5455.399906010336;5465.781368745245;5468.496163555014;3068.3709839483076;3758.699304055669;3807.447920051605;3820.2550502651993;3825.08198032473;5296.596401953486;5465.781368745245;5526.944730552683;5692.686902220335;5698.401667824819;3390.9152271192925;3839.884803455901;3858.9614103858603;3868.149007218464;3879.476270703419;3899.6100389961343;3905.8047097645003;3909.9413508797393;3913.2369188008634;3966.900827479336;4052.4326594276827;4329.328954012133;4529.54704529551;4543.61379282758;4557.95442045582;5487.475761149836;5505.917411238835;5508.6970216637665;5515.821250605988;5529.30883639545;1024.892386299428;1172.3141961952354;1255.4978008796372;1264.867188945166;1268.226381907036;834.6744769539885;844.9619767110495;851.595492141228;856.4647095565272;884.3014018925106;4328.165051891016;4454.28731402974;4676.931069969149;4967.975160124176;4977.676451030671;4889.948661037823;5222.817201397933;5367.409584945653;5374.811881584125;5379.65032272901;4355.695101342939;4875.536824001785;4878.902421951612;4882.145624515695;4882.853514394574;2610.9817171303207;2735.8358498490156;2799.7121324190693;2814.9859250704026;2833.504136776056;5306.204069389545;5508.807191748269;5514.911649476306;5547.7780888764;5548.112302031655;4494.4606647202545;4881.902361952741;4950.084234416644;5059.949400506021;5525.491313016183;4736.7631618418945;5476.808311709071;5549.528290095306;5568.916466253009;5572.442755724395;4534.410526631516;5322.920156197582;5323.547498462674;5337.97331013358;5381.546982314831;5026.924596131134;5035.748212589429;5267.710275934707;5326.467353264643;5331.253624492651;4983.626228032839;5321.787128514816;5451.809186678447;5584.301962254718;5642.71786410686;3339.5324654548867;3410.6930376266255;3639.8544058237376;3678.3378991781738;3703.962960370362;4280.20816149001;4786.2581299898575;5072.54347108762;5327.840164795062;5332.386775520865;4407.801648925742;4928.852134435972;4976.685841083913;5135.306733591066;5169.121249387688;3975.0062484378905;4721.7723391919035;4916.655834091723;4927.536231884058;5152.793888244429;4468.530804265505;4619.584237418649;4849.75751212445;5169.327987361619;5367.865803354791;5031.874203144951;5362.356517217959;5392.730363481889;5411.512963833274;5530.55755539564;4457.910841783081;5092.428323696509;5400.297961265168;5408.5673146149;5432.207371567294 14:26:18 INFO - PROCESS | 1648 | |5;yui.html;684.0628339175328;717.347214035228;750.7484992527504;751.5753599216442;756.2513112019103;14157.575272741822;14277.92861035696;14693.632659183526;14751.557453276406;14771.852281477182;10629.362238265709;11251.718707032329;12936.74126517469;12972.156809807357;13100.475980960773;658.4074333100212;671.4024518178825;673.8113328268092;806.5120602035772;832.1595188859266;807.4912804932884;816.0411516468156;820.8235229425673;821.8191997760483;822.1490757066407;874.422880898607;879.2394578689419;884.1291328041874;884.7566919097268;885.4642941020686;691.9342662447059;708.153756261268;712.006750582939;876.0582373947999;877.5699907045533;528.0864105098163;528.5137673340497;624.4785604020648;638.1544453598954;639.9840003999891;13713.314334283346;13734;13755.243461609607;13779.035467517278;13788.448462061506;12598.496060157582;12716.175481427948;12871.292078935654;12943.352832358438;12999.935000324938;11200.390306071688;11799.705007374798;11835.88164118365;11967.521299148024;12462;4266.935995960063;4313.374560688682;4323.913521729579;4468.329750537413;4482.8879278017985;92179;92822.53588732013;92935;94352.52823735838;96756.06487870273;9848.50757462124;10222.591096356211;10267.589296428208;10331;10676.359418434919;18520.444386668423;19104.426867194008;19189;19194;19307.90346048289;7544.8491030180185;7706.229377062249;7732.961335193288;7838.8432231355055;7940.602969851484;4172.958270417257;4214.346776249687;4318.848840290575;4336.6530677545725;4350.456192975878;3319;3432.2963792422493;3434.3818112739946;3436.5876094868777;3438.6561343865415;613.8465383654086;618.4835662222005;626.764963138823;627.0500192208807;637.9649119298483;536.1983834167944;548.7996881138356;578.5545130249768;590.5216774412739;673.6631684157921;8507.574621268912;8856.3800533962;9006.279497640173;9009.549522523848;9075.54622268884;625.5433533520464;627.419636835925;650.391883588846;652.4519403700914;660.1022609251414;4192.559781222928;4298.46269216348;4349.064951035542;4369.344598310216;4377;123949;125548;126239;127182.36408817896;127689.72310276778;109834;110585.44707276412;110747;113231;116755;98812;99228;100172;102688.97311027093;104615;8513.31893448535;8643.265322447662;8681.696140635047;8694.521801300736;8970.955145224232;60918.69540652268;61802.763944719954;62375;62580.68709656422;62947.6852615734;83999;87651;88737.66893496733;89470.65794012965;91964;10432;10833.295835770965;10901.945490272497;11131.666050018512;11308.378039207922;11765.705857353634;11993.020512159424;12001.85982331677;12047;12057.698557536132;11057.723556911142;11365.374904380338;11449.828252576384;11517.136214783757;11835.763284734085;10261.897381026094;11633.243839150766;12209.755804884031;12482.875171248172;12696.74606507883;11848.644540663794;12075.698107547381;12088.818667719815;12266.080043996557;12419.565315213924;9345;10049.949750251495;10333.534990925576;10386.065254126863;10397.42814145199;10258.28192026551;10712.8928710715;10726.892731072589;10731.40977246259;10770.73073173177 14:26:18 INFO - PROCESS | 1648 | __end_tp_report 14:26:18 INFO - PROCESS | 1648 | __start_cc_report 14:26:18 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1949 14:26:18 INFO - PROCESS | 1648 | __end_cc_report 14:26:18 INFO - PROCESS | 1648 | __startTimestamp1480112778250__endTimestamp 14:26:18 INFO - PROCESS | 1648 | 14:26:18 INFO - PROCESS | 1648 | ------- Summary: start ------- 14:26:18 INFO - PROCESS | 1648 | Number of tests: 6 14:26:18 INFO - PROCESS | 1648 | 14:26:18 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5709.11 Median:5859.38 stddev:652.58 (11.1%) stddev-sans-first:626.61 14:26:18 INFO - PROCESS | 1648 | Values: 3299.5 3309.2 3309.9 3322.6 3365.3 5638.5 5680.6 5694.6 5710.7 5730.2 5623.5 5684.0 5696.3 5729.6 5733.3 4342.2 5185.5 5188.7 5227.4 5249.4 5157.5 5159.8 5175.4 5313.3 5340.1 5177.9 5196.8 5223.5 5231.7 5253.7 5249.7 5291.4 5305.1 5314.6 5341.7 5067.4 5134.4 5216.6 5223.5 5232.4 5550.1 5580.2 5588.6 5632.4 5635.0 6126.3 6169.6 6182.3 6222.3 6227.0 6219.5 6243.9 6260.9 6265.4 6267.8 5658.6 5727.1 5731.3 5755.1 5897.0 6233.3 6273.7 6278.1 6291.6 6304.5 6150.0 6175.4 6188.2 6189.2 6193.1 6117.0 6142.5 6157.5 6182.9 6234.7 6173.1 6238.4 6243.7 6247.1 6264.8 6225.9 6260.9 6262.2 6269.9 6285.2 4851.7 4924.9 4968.6 4974.4 4992.9 4392.0 4396.1 4432.2 4449.5 4451.9 5780.9 5781.5 5803.4 5812.4 5816.6 6289.0 6305.7 6307.6 6308.3 6328.6 4743.6 5593.0 5639.5 5677.0 5746.8 5373.5 5391.1 5405.9 5428.7 5447.3 5296.9 6295.7 6305.9 6320.5 6342.9 5853.9 6162.7 6288.1 6296.8 6301.2 6224.5 6242.4 6277.6 6291.5 6304.7 6147.4 6173.9 6199.6 6205.9 6221.3 6082.8 6240.9 6275.1 6290.7 6298.0 5853.6 5921.6 6042.5 6112.0 6134.3 6117.4 6130.1 6131.1 6136.5 6152.1 5009.0 5950.2 5950.4 5955.1 5961.7 5832.6 5941.9 5944.4 5995.4 5995.5 5169.2 5428.4 5808.2 5859.4 5864.4 14:26:18 INFO - PROCESS | 1648 | 14:26:18 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:70832.51 Median:36245.09 stddev:158373.88 (437.0%) stddev-sans-first:158730.07 14:26:18 INFO - PROCESS | 1648 | Values: 475.6 492.5 510.8 519.0 521.3 29641.6 34119.8 38005.8 39058.2 39273.0 39880.0 40356.6 40393.8 40417.2 40566.2 8450.1 9275.9 9460.4 9461.0 9478.4 8475.6 11093.6 11352.8 11375.1 11534.2 11013.3 11114.7 11151.4 11184.3 11238.2 11178.3 11221.6 11240.3 11268.1 11273.2 11101.4 11174.6 11192.8 11193.5 11197.4 37812.1 38171.8 38181.4 38187.0 38196.8 3119.2 3469.4 3782.1 3809.3 3826.5 29968.9 30384.8 30496.0 30586.2 30955.5 2709.9 3178.8 3194.5 3219.4 3237.1 437912.0 510699.0 514150.9 514626.0 524967.0 64475.7 66120.3 66144.7 66189.0 66327.7 35859.8 36674.1 36936.3 37359.8 37379.1 15886.0 16452.0 16623.8 16632.9 16671.7 11646.8 11801.6 12427.5 12501.3 12673.9 19811.8 20109.3 20136.9 20146.2 20156.6 8149.3 8272.8 8281.3 8308.3 8363.7 834.9 883.8 974.8 988.5 1014.4 28129.0 29023.7 29062.9 29179.0 29799.9 1046.6 1052.4 1129.3 1129.6 1170.9 12144.2 14785.6 15110.6 15111.3 15338.2 684468.0 690601.5 691788.0 694022.0 694399.0 52961.7 56671.7 56746.7 56835.7 56857.7 1224.0 1253.8 1341.8 1369.5 1396.1 29674.1 30021.2 30340.5 30532.7 31056.0 46924.3 47402.3 47431.8 47435.5 47595.3 542473.0 545219.0 546567.0 546952.0 643455.0 39070.0 40140.8 40386.4 40517.2 40552.8 39265.8 41242.8 43781.6 43912.6 44458.0 29776.9 35935.0 36864.8 37029.1 37036.3 36271.5 36455.8 36484.0 36496.0 36516.0 35692.6 35943.5 36174.0 36218.7 36384.3 30980.7 32295.5 32454.8 32535.8 32962.5 24425.0 24448.6 24844.8 25022.5 25056.0 14:26:18 INFO - PROCESS | 1648 | 14:26:18 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:35683.42 Median:13959.87 stddev:105032.95 (752.4%) stddev-sans-first:105294.08 14:26:18 INFO - PROCESS | 1648 | Values: 344.4 349.5 371.3 376.1 384.4 14832.3 15115.7 15738.9 19542.1 20220.0 19129.9 20218.6 20422.3 20529.0 20706.3 5605.8 5850.4 5977.6 5977.8 5989.4 4677.6 5918.5 5982.8 6003.9 6013.2 5134.6 5621.4 5911.2 5920.9 5928.6 5737.9 5834.6 5845.0 5845.7 5858.3 4851.4 5093.0 5093.5 5096.8 5115.4 24153.8 24509.9 24654.8 25104.4 25285.5 2164.8 2368.0 2634.2 3011.2 3024.8 14427.6 14512.3 14554.1 14938.3 15210.5 1799.8 1801.9 1854.8 1863.8 1868.8 106393.9 113414.0 116589.0 121097.4 121165.0 9550.0 10589.8 11698.6 11706.6 11759.9 14768.0 14795.9 14803.6 14826.6 14836.2 8390.3 8612.5 8645.9 8687.7 8746.7 5999.5 8488.3 8492.6 8549.0 8622.7 4460.6 4552.4 4562.8 4613.1 4620.4 1872.5 1920.8 1924.9 1928.8 1932.7 806.6 835.3 858.2 910.9 921.1 15881.0 20989.9 21176.0 21279.5 21465.6 885.1 908.8 919.4 930.6 932.4 10208.9 10938.7 10961.9 10984.7 11011.9 593090.0 630052.0 640298.0 646899.0 647754.0 116036.0 117489.8 119796.0 124545.4 127803.4 876.2 933.8 1003.8 1015.9 1024.0 18006.8 19010.1 19561.9 19713.3 19735.7 36762.6 38592.8 54838.2 55769.0 57096.0 77627.6 84549.0 84816.5 84906.3 85768.0 6375.4 7503.0 7888.6 8218.5 8316.9 11389.4 12887.7 12897.2 12993.8 13227.3 9152.0 10954.9 11117.2 11128.9 11230.7 8356.0 10890.9 10911.6 11352.5 11622.5 11329.4 11554.8 11698.6 11786.8 11859.0 7870.9 8021.4 8242.7 8384.0 8422.4 8986.4 11007.7 11167.8 11258.7 11305.1 14:26:18 INFO - PROCESS | 1648 | 14:26:18 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5442.77 Median:5097.67 stddev:3015.83 (59.2%) stddev-sans-first:3007.88 14:26:18 INFO - PROCESS | 1648 | Values: 1256.4 1459.5 1468.0 1618.7 1824.0 7572.8 7985.4 8278.2 8539.5 8555.8 8387.4 8471.3 8489.1 8493.0 8502.7 2305.7 2348.8 2349.1 2349.6 2353.4 2128.6 2193.8 2387.1 2387.2 2388.4 1921.6 2317.1 2337.1 2347.7 2349.0 2298.9 2310.9 2330.7 2341.6 2343.4 2320.4 2347.1 2358.0 2361.5 2361.5 7413.7 8471.2 8496.3 8588.2 8603.1 5089.8 5283.1 5298.2 5307.5 5322.8 3287.1 3524.6 3531.3 3569.0 3607.4 969.0 1069.3 1076.9 1086.0 1111.6 11036.1 11131.4 11234.7 11236.7 11243.5 4232.4 4388.0 4491.5 4497.2 4508.7 8543.0 8694.5 8716.8 8740.7 8768.9 5097.5 5097.9 5101.3 5102.6 5124.8 4040.1 4278.3 4420.0 4437.9 4773.2 3632.1 3663.6 3739.8 3740.3 3750.1 920.6 1018.4 1126.7 1139.7 1140.2 659.6 898.8 908.4 922.7 926.2 5465.3 5657.7 5669.6 5730.5 5819.1 894.4 922.9 943.8 974.8 1001.2 3640.8 3800.2 3800.5 3824.8 3964.3 10016.5 10230.1 10273.9 10451.4 10459.3 10206.1 10322.8 10389.9 10396.1 10430.3 7682.8 7992.9 8162.8 8171.0 8171.6 5560.3 5643.6 5648.9 5743.3 5800.3 8645.9 9150.1 9441.3 9499.2 9638.3 8939.8 9283.2 9334.8 9981.7 9998.0 5341.3 6511.2 6520.0 6521.8 6545.8 7738.4 7924.6 7989.7 8054.4 8107.7 6214.9 7207.9 7212.6 7308.7 7357.0 7046.5 7130.3 7138.8 7140.6 7151.5 6628.3 6774.7 6778.2 6792.9 6803.8 4842.0 4895.2 4899.5 4967.8 5007.3 4850.7 4908.8 4954.8 5036.6 5047.5 14:26:18 INFO - PROCESS | 1648 | 14:26:18 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4313.53 Median:4806.56 stddev:1296.64 (27.0%) stddev-sans-first:1264.78 14:26:18 INFO - PROCESS | 1648 | Values: 299.2 302.8 309.7 313.0 314.4 4610.5 5078.6 5154.8 5170.8 5252.4 3849.0 4579.4 4643.0 4810.5 5050.3 3939.5 3949.3 3988.3 3994.7 4025.4 3937.5 3940.6 3984.1 3985.0 4000.2 3542.6 3696.2 3924.3 3937.8 3951.9 3105.0 3922.5 3934.5 3935.4 3969.2 3411.6 3423.9 3428.8 3431.8 3436.8 4766.3 4802.6 4967.0 4991.6 5019.6 4494.6 4510.7 4516.7 4521.2 4547.7 5408.5 5449.7 5455.4 5465.8 5468.5 3068.4 3758.7 3807.4 3820.3 3825.1 5296.6 5465.8 5526.9 5692.7 5698.4 3390.9 3839.9 3859.0 3868.1 3879.5 3899.6 3905.8 3909.9 3913.2 3966.9 4052.4 4329.3 4529.5 4543.6 4558.0 5487.5 5505.9 5508.7 5515.8 5529.3 1024.9 1172.3 1255.5 1264.9 1268.2 834.7 845.0 851.6 856.5 884.3 4328.2 4454.3 4676.9 4968.0 4977.7 4889.9 5222.8 5367.4 5374.8 5379.7 4355.7 4875.5 4878.9 4882.1 4882.9 2611.0 2735.8 2799.7 2815.0 2833.5 5306.2 5508.8 5514.9 5547.8 5548.1 4494.5 4881.9 4950.1 5059.9 5525.5 4736.8 5476.8 5549.5 5568.9 5572.4 4534.4 5322.9 5323.5 5338.0 5381.5 5026.9 5035.7 5267.7 5326.5 5331.3 4983.6 5321.8 5451.8 5584.3 5642.7 3339.5 3410.7 3639.9 3678.3 3704.0 4280.2 4786.3 5072.5 5327.8 5332.4 4407.8 4928.9 4976.7 5135.3 5169.1 3975.0 4721.8 4916.7 4927.5 5152.8 4468.5 4619.6 4849.8 5169.3 5367.9 5031.9 5362.4 5392.7 5411.5 5530.6 4457.9 5092.4 5400.3 5408.6 5432.2 14:26:18 INFO - PROCESS | 1648 | 14:26:18 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:22372.46 Median:4305.92 stddev:34940.25 (811.4%) stddev-sans-first:35000.32 14:26:18 INFO - PROCESS | 1648 | Values: 684.1 717.3 750.7 751.6 756.3 14157.6 14277.9 14693.6 14751.6 14771.9 10629.4 11251.7 12936.7 12972.2 13100.5 658.4 671.4 673.8 806.5 832.2 807.5 816.0 820.8 821.8 822.1 874.4 879.2 884.1 884.8 885.5 691.9 708.2 712.0 876.1 877.6 528.1 528.5 624.5 638.2 640.0 13713.3 13734.0 13755.2 13779.0 13788.4 12598.5 12716.2 12871.3 12943.4 12999.9 11200.4 11799.7 11835.9 11967.5 12462.0 4266.9 4313.4 4323.9 4468.3 4482.9 92179.0 92822.5 92935.0 94352.5 96756.1 9848.5 10222.6 10267.6 10331.0 10676.4 18520.4 19104.4 19189.0 19194.0 19307.9 7544.8 7706.2 7733.0 7838.8 7940.6 4173.0 4214.3 4318.8 4336.7 4350.5 3319.0 3432.3 3434.4 3436.6 3438.7 613.8 618.5 626.8 627.1 638.0 536.2 548.8 578.6 590.5 673.7 8507.6 8856.4 9006.3 9009.5 9075.5 625.5 627.4 650.4 652.5 660.1 4192.6 4298.5 4349.1 4369.3 4377.0 123949.0 125548.0 126239.0 127182.4 127689.7 109834.0 110585.4 110747.0 113231.0 116755.0 98812.0 99228.0 100172.0 102689.0 104615.0 8513.3 8643.3 8681.7 8694.5 8971.0 60918.7 61802.8 62375.0 62580.7 62947.7 83999.0 87651.0 88737.7 89470.7 91964.0 10432.0 10833.3 10901.9 11131.7 11308.4 11765.7 11993.0 12001.9 12047.0 12057.7 11057.7 11365.4 11449.8 11517.1 11835.8 10261.9 11633.2 12209.8 12482.9 12696.7 11848.6 12075.7 12088.8 12266.1 12419.6 9345.0 10049.9 10333.5 10386.1 10397.4 10258.3 10712.9 10726.9 10731.4 10770.7 14:26:18 INFO - PROCESS | 1648 | -------- Summary: end -------- 14:26:18 INFO - PROCESS | 1648 | 14:26:18 INFO - TEST-INFO | 1648: exit 0 14:26:18 INFO - TEST-OK | dromaeo_css | took 1119420ms 14:26:18 INFO - TEST-START | kraken 14:26:18 INFO - operating with platform_type : mac_ 14:26:18 INFO - Initialising browser for kraken test... 14:26:18 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpJlQ09C/profile http://localhost:49235/getInfo.html) 14:26:21 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 14:26:21 INFO - PROCESS | 1653 | colorDepth:24 14:26:21 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 14:26:21 INFO - PROCESS | 1653 | __metrics 14:26:21 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:26:21 INFO - PROCESS | 1653 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:26:22 INFO - TEST-INFO | 1653: exit 0 14:26:22 INFO - Browser initialized. 14:26:22 INFO - Running cycle 1/1 for kraken test... 14:26:22 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpJlQ09C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:26:34 INFO - PROCESS | 1654 | RSS: Main: 222785536 14:26:34 INFO - PROCESS | 1654 | 14:26:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 14:26:38 INFO - PROCESS | 1654 | RSS: Main: 273989632 14:26:38 INFO - PROCESS | 1654 | 14:26:41 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 14:26:41 INFO - PROCESS | 1654 | RSS: Main: 284520448 14:26:41 INFO - PROCESS | 1654 | 14:26:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 14:26:45 INFO - PROCESS | 1654 | RSS: Main: 379817984 14:26:45 INFO - PROCESS | 1654 | 14:26:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 14:26:48 INFO - PROCESS | 1654 | RSS: Main: 283590656 14:26:48 INFO - PROCESS | 1654 | 14:26:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:26:50 INFO - PROCESS | 1654 | RSS: Main: 285405184 14:26:50 INFO - PROCESS | 1654 | 14:26:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 14:26:54 INFO - PROCESS | 1654 | RSS: Main: 325550080 14:26:54 INFO - PROCESS | 1654 | 14:26:57 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 14:26:57 INFO - PROCESS | 1654 | RSS: Main: 326213632 14:26:57 INFO - PROCESS | 1654 | 14:27:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 14:27:01 INFO - PROCESS | 1654 | RSS: Main: 324149248 14:27:01 INFO - PROCESS | 1654 | 14:27:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:27:03 INFO - PROCESS | 1654 | RSS: Main: 301494272 14:27:03 INFO - PROCESS | 1654 | 14:27:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:27:05 INFO - PROCESS | 1654 | RSS: Main: 300638208 14:27:05 INFO - PROCESS | 1654 | 14:27:07 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:27:08 INFO - PROCESS | 1654 | RSS: Main: 302759936 14:27:08 INFO - PROCESS | 1654 | 14:27:11 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:27:12 INFO - PROCESS | 1654 | RSS: Main: 322641920 14:27:12 INFO - PROCESS | 1654 | 14:27:15 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:27:15 INFO - PROCESS | 1654 | RSS: Main: 316465152 14:27:15 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 14:27:17 INFO - PROCESS | 1654 | RSS: Main: 360747008 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | __start_tp_report 14:27:17 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 14:27:17 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 14:27:17 INFO - PROCESS | 1654 | |i|pagename|runs| 14:27:17 INFO - PROCESS | 1654 | |0;ai-astar;115;114;124;171;114;114;117;164;170;115 14:27:17 INFO - PROCESS | 1654 | |1;audio-beat-detection;142;112;110;144;116;115;110;110;151;111 14:27:17 INFO - PROCESS | 1654 | |2;audio-dft;183;187;181;184;184;181;183;185;183;182 14:27:17 INFO - PROCESS | 1654 | |3;audio-fft;79;60;58;90;78;82;82;61;83;58 14:27:17 INFO - PROCESS | 1654 | |4;audio-oscillator;87;70;94;69;68;68;90;68;69;92 14:27:17 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;88;86;88;86;89;85;90;87;88;86 14:27:17 INFO - PROCESS | 1654 | |6;imaging-darkroom;94;95;94;108;150;93;94;94;95;94 14:27:17 INFO - PROCESS | 1654 | |7;imaging-desaturate;84;82;82;81;83;81;85;86;83;81 14:27:17 INFO - PROCESS | 1654 | |8;json-parse-financial;57;54;54;56;57;57;57;56;55;57 14:27:17 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;63;75;72;76;73;69;69;69;70;72 14:27:17 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;84;83;83;84;85;83;84;83;83;84 14:27:17 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;135;159;152;156;162;158;154;150;189;164 14:27:17 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;167;169;169;171;169;166;166;168;166;167 14:27:17 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;66;64;65;67;66;64;63;65;66;65 14:27:17 INFO - PROCESS | 1654 | __end_tp_report 14:27:17 INFO - PROCESS | 1654 | __start_cc_report 14:27:17 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2020 14:27:17 INFO - PROCESS | 1654 | __end_cc_report 14:27:17 INFO - PROCESS | 1654 | __startTimestamp1480112837740__endTimestamp 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | ------- Summary: start ------- 14:27:17 INFO - PROCESS | 1654 | Number of tests: 14 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:131.80 Median:120.50 stddev:25.45 (21.1%) stddev-sans-first:26.25 14:27:17 INFO - PROCESS | 1654 | Values: 115.0 114.0 124.0 171.0 114.0 114.0 117.0 164.0 170.0 115.0 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:122.10 Median:115.50 stddev:16.54 (14.3%) stddev-sans-first:15.90 14:27:17 INFO - PROCESS | 1654 | Values: 142.0 112.0 110.0 144.0 116.0 115.0 110.0 110.0 151.0 111.0 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:1.83 (1.0%) stddev-sans-first:1.94 14:27:17 INFO - PROCESS | 1654 | Values: 183.0 187.0 181.0 184.0 184.0 181.0 183.0 185.0 183.0 182.0 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:73.10 Median:80.50 stddev:12.36 (15.4%) stddev-sans-first:12.92 14:27:17 INFO - PROCESS | 1654 | Values: 79.0 60.0 58.0 90.0 78.0 82.0 82.0 61.0 83.0 58.0 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.50 stddev:11.55 (14.7%) stddev-sans-first:11.73 14:27:17 INFO - PROCESS | 1654 | Values: 87.0 70.0 94.0 69.0 68.0 68.0 90.0 68.0 69.0 92.0 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:88.00 stddev:1.57 (1.8%) stddev-sans-first:1.64 14:27:17 INFO - PROCESS | 1654 | Values: 88.0 86.0 88.0 86.0 89.0 85.0 90.0 87.0 88.0 86.0 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:101.10 Median:94.00 stddev:17.74 (18.9%) stddev-sans-first:18.62 14:27:17 INFO - PROCESS | 1654 | Values: 94.0 95.0 94.0 108.0 150.0 93.0 94.0 94.0 95.0 94.0 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:82.80 Median:83.00 stddev:1.75 (2.1%) stddev-sans-first:1.80 14:27:17 INFO - PROCESS | 1654 | Values: 84.0 82.0 82.0 81.0 83.0 81.0 85.0 86.0 83.0 81.0 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:57.00 stddev:1.25 (2.2%) stddev-sans-first:1.27 14:27:17 INFO - PROCESS | 1654 | Values: 57.0 54.0 54.0 56.0 57.0 57.0 57.0 56.0 55.0 57.0 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:72.00 stddev:3.71 (5.1%) stddev-sans-first:2.65 14:27:17 INFO - PROCESS | 1654 | Values: 63.0 75.0 72.0 76.0 73.0 69.0 69.0 69.0 70.0 72.0 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 14:27:17 INFO - PROCESS | 1654 | Values: 84.0 83.0 83.0 84.0 85.0 83.0 84.0 83.0 83.0 84.0 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:157.90 Median:158.50 stddev:13.59 (8.6%) stddev-sans-first:11.62 14:27:17 INFO - PROCESS | 1654 | Values: 135.0 159.0 152.0 156.0 162.0 158.0 154.0 150.0 189.0 164.0 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:168.50 stddev:1.69 (1.0%) stddev-sans-first:1.76 14:27:17 INFO - PROCESS | 1654 | Values: 167.0 169.0 169.0 171.0 169.0 166.0 166.0 168.0 166.0 167.0 14:27:17 INFO - PROCESS | 1654 | 14:27:17 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.50 stddev:1.20 (1.8%) stddev-sans-first:1.22 14:27:17 INFO - PROCESS | 1654 | Values: 66.0 64.0 65.0 67.0 66.0 64.0 63.0 65.0 66.0 65.0 14:27:17 INFO - PROCESS | 1654 | -------- Summary: end -------- 14:27:17 INFO - PROCESS | 1654 | 14:27:18 INFO - TEST-INFO | 1654: exit 0 14:27:18 INFO - TEST-OK | kraken | took 59954ms 14:27:18 INFO - SUITE-END | took 1179s 14:27:18 INFO - Completed test suite (00:19:39) 14:27:18 INFO - javascript benchmark 14:27:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3299.521569372441, 3309.189248634085, 3309.9338013239735, 3322.601287845459, 3365.276465559904, 5638.492535671789, 5680.573956953224, 5694.601377903548, 5710.685912274823, 5730.197772311869, 5623.465770751783, 5683.99999999999, 5696.25948626679, 5729.598928075036, 5733.340665823432, 4342.196693611684, 5185.455527169649, 5188.740562971855, 5227.398849132342, 5249.396319423277, 5157.458466860982, 5159.845204643848, 5175.378954525443, 5313.326302214809, 5340.145576707728, 5177.896442071156, 5196.792128314882, 5223.529882310591, 5231.659942103751, 5253.7373131343475, 5249.685018898878, 5291.441922895272, 5305.071612467803, 5314.5748340132695, 5341.706206158659, 5067.417247016603, 5134.383873935151, 5216.634835561512, 5223.451537588575, 5232.372115346183, 5550.084236101038, 5580.246666699984, 5588.580856435784, 5632.436756324376, 5634.971825140889, 6126.29547602027, 6169.629822210637, 6182.319944806068, 6222.284437289724, 6227.0, 6219.471344935686, 6243.906341404882, 6260.906086408708, 6265.4361107500545, 6267.843303917393, 5658.632188907708, 5727.11229759388, 5731.254936858182, 5755.107958266475, 5896.997510423238, 6233.283172435228, 6273.686315684197, 6278.058291256348, 6291.591046582049, 6304.464120549713, 6149.96925015372, 6175.382461753788, 6188.164597779332, 6189.226346706661, 6193.1329613854095, 6116.97495384395, 6142.477889379364, 6157.476614487819, 6182.907256391158, 6234.688265586702, 6173.135760993375, 6238.376162383725, 6243.719032643542, 6247.125402443661, 6264.843378915563, 6225.937740622536, 6260.937390626035, 6262.1546091278005, 6269.8746025079245, 6285.182926219562, 4851.684640498356, 4924.901501969941, 4968.627352948543, 4974.403071631355, 4992.875178120504, 4392.0, 4396.054848207651, 4432.180046691373, 4449.532799056117, 4451.888702782457, 5780.855478612984, 5781.53747700183, 5803.390643982321, 5812.360640329561, 5816.563757718103, 6289.0, 6305.747770089237, 6307.621542707452, 6308.27454842699, 6328.557001009885, 4743.644226683013, 5593.049181639131, 5639.520640745583, 5677.006523858308, 5746.827595172152, 5373.516383525423, 5391.137418013099, 5405.945940540544, 5428.67427954324, 5447.319085114361, 5296.920546191887, 6295.748170073238, 6305.936940630535, 6320.525960552977, 6342.904856427065, 5853.94146058534, 6162.722677479434, 6288.11966324724, 6296.779612713716, 6301.243850737757, 6224.533160013108, 6242.438180563771, 6277.5605707600025, 6291.528135389955, 6304.747810087545, 6147.385261473817, 6173.938260617337, 6199.56603037783, 6205.944989351911, 6221.253449586078, 6082.847928801815, 6240.937590624036, 6275.090111933835, 6290.654014029273, 6298.02380631003, 5853.590248682552, 5921.5854890159, 6042.516598672184, 6111.99152139892, 6134.344744984565, 6117.431594453999, 6130.080487926846, 6131.0708424985305, 6136.549713441251, 6152.138700581832, 5009.023240468074, 5950.2264705587995, 5950.404959504015, 5955.076963070731, 5961.672108034099, 5832.562557808266, 5941.881162376815, 5944.405559444021, 5995.370486098896, 5995.520358371407, 5169.198774190007, 5428.402875683751, 5808.186853840381, 5859.384764599826, 5864.413558644102], "value": 5667.261766981217, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.64564399522357, 492.4558363008875, 510.8429407392257, 518.9896202075959, 521.2806327268369, 29641.555376669367, 34119.82940085299, 38005.80997095014, 39058.21883562327, 39273.01817454565, 39880.0, 40356.59643403565, 40393.79803100981, 40417.19165616686, 40566.188676226455, 8450.070492245879, 9275.860862087091, 9460.385074970105, 9461.006594307568, 9478.38390504615, 8475.576221188947, 11093.611723589676, 11352.772944541104, 11375.089992800598, 11534.192606517547, 11013.339199648006, 11114.722131946684, 11151.38667373294, 11184.328940263611, 11238.213325067249, 11178.27341222818, 11221.551137954471, 11240.325580465116, 11268.09855211581, 11273.154513411486, 11101.44492775358, 11174.608888688937, 11192.832107518394, 11193.49629266685, 11197.440127993568, 37812.05469863248, 38171.809140954116, 38181.42727859056, 38187.0, 38196.80901595502, 3119.1890108571706, 3469.444888817777, 3782.130110074671, 3809.276237514863, 3826.502554667903, 29968.85015574908, 30384.84807575948, 30496.0, 30586.235344116576, 30955.535666965014, 2709.888945532342, 3178.8410579471165, 3194.5048517479827, 3219.404410184123, 3237.093613788143, 437912.0, 510699.0, 514150.86321115267, 514626.0, 524967.0, 64475.67762161159, 66120.33879661238, 66144.66927665331, 66189.0, 66327.67344653177, 35859.82070089633, 36674.08314792151, 36936.26127477489, 37359.81320093382, 37379.06552336214, 15886.046837189806, 16452.0, 16623.750643740354, 16632.918860274043, 16671.749923751155, 11646.767064658829, 11801.645950621323, 12427.502899884084, 12501.312427816558, 12673.873261267454, 19811.801881980995, 20109.290710289497, 20136.899315503328, 20146.194152233747, 20156.59686806285, 8149.266566009088, 8272.793180170544, 8281.337493000545, 8308.335333173332, 8363.665453381796, 834.8664213725775, 883.8320719063486, 974.8196583632051, 988.5106872098357, 1014.4167103915278, 28129.015484457945, 29023.709762902523, 29062.85468572686, 29178.954524097597, 29799.85100074529, 1046.602291129366, 1052.4369462337613, 1129.3280498103616, 1129.6046383765781, 1170.8887655672697, 12144.210626309437, 14785.630359241106, 15110.564496372826, 15111.31999060045, 15338.156401397808, 684468.0, 690601.5469922719, 691788.0, 694022.0, 694399.0, 52961.735191324566, 56671.716641417355, 56746.71626641841, 56835.71582142063, 56857.71571142118, 1223.9694007649703, 1253.7931241345075, 1341.7584834729648, 1369.5069774881035, 1396.0995158123158, 29674.109776706733, 30021.24946876258, 30340.544891826197, 30532.694673053873, 31056.0, 46924.296135558674, 47402.288965664855, 47431.76284118558, 47435.52564474449, 47595.28607070827, 542473.0, 545219.0, 546567.0, 546952.0, 643455.0, 39070.0, 40140.79577612676, 40386.39420408638, 40517.1896562073, 40552.78341649755, 39265.82202533929, 41242.79378603088, 43781.56218437902, 43912.560874392126, 44457.99878010995, 29776.85111574428, 35935.0, 36864.815675922524, 37029.074273143386, 37036.25927481489, 36271.455928161624, 36455.817720911225, 36484.0, 36496.0, 36516.0, 35692.643073568936, 35943.46084808782, 36174.0, 36218.73234436782, 36384.27231455409, 30980.69019309778, 32295.51556726698, 32454.837725812165, 32535.837320814193, 32962.5055624161, 24425.0, 24448.633270500603, 24844.751552484966, 25022.499550009263, 25056.0], "value": 19939.359844983628, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.39902370363717, 349.50544978854924, 371.3223367354578, 376.1142509390386, 384.4387194695745, 14832.258387080643, 15115.697686046273, 15738.92130539347, 19542.120604572792, 20220.0, 19129.904350478228, 20218.59562808743, 20422.285220017304, 20529.0, 20706.27528036519, 5605.803796867121, 5850.414958504158, 5977.5815692901515, 5977.790777322794, 5989.401059893998, 4677.555632214951, 5918.4969277611235, 5982.820515384546, 6003.909941350883, 6013.218281623383, 5134.614903882205, 5621.437856214367, 5911.201987731644, 5920.911186332209, 5928.555358348141, 5737.856553586152, 5834.562407819388, 5845.0, 5845.707714614252, 5858.2677165354335, 4851.417829860439, 5092.974535127338, 5093.516115968977, 5096.79612815487, 5115.411727651293, 24153.758462415328, 24509.87745061281, 24654.753452465426, 25104.37239069038, 25285.49429011428, 2164.8159906407973, 2367.964480532776, 2634.249238966885, 3011.1568760747027, 3024.833634150121, 14427.639309017359, 14512.274386280644, 14554.053986490844, 14938.253087345589, 15210.543683689508, 1799.7930238022664, 1801.918913648889, 1854.7665802241493, 1863.813618638139, 1868.8224618661206, 106393.93606063917, 113414.0, 116589.0, 121097.39451302687, 121165.0, 9549.952250238843, 10589.788204235872, 11698.649040528799, 11706.648800535997, 11759.882401175879, 14767.966242363147, 14795.852041479448, 14803.629909252355, 14826.629334266514, 14836.184009879344, 8390.286825619769, 8612.48325100496, 8645.870311945326, 8687.739367818975, 8746.69386571467, 5999.520038396918, 8488.278496327881, 8492.575371231414, 8548.957255213683, 8622.655093796304, 4460.620847228022, 4552.385427967249, 4562.794674239667, 4613.100445413179, 4620.399348084795, 1872.5318670332417, 1920.846332293441, 1924.9422517324504, 1928.845692344609, 1932.719755635425, 806.6208881825532, 835.2900034970227, 858.2018722588053, 910.8887157667635, 921.0841449784432, 15881.0, 20989.89505052465, 21176.0, 21279.468013299793, 21465.57068858614, 885.106042896679, 908.8454962656365, 919.3977944446399, 930.5719369090159, 932.3799673217235, 10208.948955255177, 10938.726531836608, 10961.945190274157, 10984.670459886056, 11011.889881101086, 593090.0, 630052.0, 640298.0, 646899.0, 647754.0, 116036.0, 117489.82510174789, 119796.0, 124545.37727311306, 127803.36098319449, 876.1807709791366, 933.7992331648592, 1003.8343673293824, 1015.9441230732233, 1023.9948800255951, 18006.819931801037, 19010.1445434953, 19561.902190489527, 19713.310034148737, 19735.703964440832, 36762.63237367699, 38592.807035965765, 54838.17742733942, 55769.0, 57096.0, 77627.61186194033, 84549.0, 84816.45550633491, 84906.30187396094, 85768.0, 6375.394337537927, 7502.962485187539, 7888.566128862968, 8218.547979860927, 8316.875246871423, 11389.373584452935, 12887.742245155232, 12897.226166430044, 12993.805092923425, 13227.338633068115, 9151.954240228757, 10954.890451095705, 11117.22179447431, 11128.944355278172, 11230.719232019264, 8355.958220209102, 10890.945545272223, 10911.618093366695, 11352.54589816398, 11622.5350985963, 11329.376884271443, 11554.768904621693, 11698.649040528799, 11786.76426471483, 11859.0, 7870.921290787248, 8021.358291336563, 8242.670293188205, 8384.035835878709, 8422.368322375969, 8986.370954033155, 11007.724806879893, 11167.832482512606, 11258.66224013281, 11305.095592352756], "value": 9511.438379823932, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1256.4346044280076, 1459.4599998000742, 1468.0384348251896, 1618.692448434798, 1824.033262370944, 7572.772816815497, 7985.401094917883, 8278.172182781711, 8539.530325832093, 8555.78610534737, 8387.412881098311, 8471.279941205004, 8489.066202717711, 8493.0, 8502.659893604248, 2305.688732021181, 2348.7768661977084, 2349.0955981946986, 2349.600567903452, 2353.399883029825, 2128.648772952461, 2193.824494040481, 2387.1167667962877, 2387.2002879035545, 2388.379021454417, 1921.5772530043369, 2317.0963324303534, 2337.0885354711677, 2347.7417484076736, 2348.989934328238, 2298.8505747126383, 2310.9422264443356, 2330.6504024396477, 2341.590221711194, 2343.4492894169857, 2320.431494283894, 2347.0729062020578, 2357.9528409431714, 2361.480474295652, 2361.4922791599715, 7413.666385012633, 8471.237588616992, 8496.320294376435, 8588.22705956467, 8603.096674849114, 5089.847304580869, 5283.101872681654, 5298.231756395301, 5307.522322990911, 5322.813701520428, 3287.063186991696, 3524.6475352464804, 3531.3290474809705, 3568.982155089208, 3607.350676878161, 968.9951550242204, 1069.2996087562658, 1076.8707755069363, 1085.9728506787394, 1111.6053800900725, 11036.11711063121, 11131.443427828575, 11234.719132021683, 11236.719082022933, 11243.494042768096, 4232.449781528443, 4387.956120438755, 4491.528389519053, 4497.2129877271345, 4508.661850361236, 8542.957285213659, 8694.521801300863, 8716.825663486694, 8740.650373984969, 8768.868466973001, 5097.464766199496, 5097.8725531861255, 5101.336826212569, 5102.617303702237, 5124.769385377593, 4040.1313717550865, 4278.272693642089, 4420.005498762753, 4437.866863994085, 4773.164696178155, 3632.146445585285, 3663.578688450809, 3739.8130093495215, 3740.251949610089, 3750.0999760057935, 920.62254475666, 1018.3889666200221, 1126.6788965144895, 1139.7036770439745, 1140.1904647700226, 659.6042374575217, 898.820235952812, 908.3868388837508, 922.718570835888, 926.2312280806914, 5465.316835395575, 5657.679026125735, 5669.574781891375, 5730.512906402919, 5819.127130930395, 894.4007514964859, 922.9446233226028, 943.8437913555977, 974.7514383832078, 1001.2290536287019, 3640.7633503822167, 3800.239952009609, 3800.467934489174, 3824.808759562011, 3964.345882929342, 10016.49917504122, 10230.07929286353, 10273.9486302568, 10451.425171615481, 10459.267851250492, 10206.13247873939, 10322.793544129076, 10389.896101039045, 10396.064354208158, 10430.26988110825, 7682.846343073219, 7992.880106798402, 8162.795930101795, 8170.959145204236, 8171.591420428955, 5560.332760068656, 5643.633163844337, 5648.915266270927, 5743.253377060955, 5800.274965629296, 8645.876036115265, 9150.084991500797, 9441.291903107433, 9499.192568631745, 9638.325317227727, 8939.821203575762, 9283.210927071275, 9334.786477757849, 9981.700548983543, 9998.0, 5341.278927344914, 6511.153550038464, 6520.0, 6521.804345869631, 6545.803625891231, 7738.419618528746, 7924.564148971862, 7989.680412783656, 8054.395920306118, 8107.716229931924, 6214.9067763982675, 7207.891881621675, 7212.603306817966, 7308.707651693872, 7357.043584334002, 7046.541974771623, 7130.286971303036, 7138.821529461805, 7140.642967851482, 7151.4636402269, 6628.270890201978, 6774.661266936732, 6778.186617605918, 6792.898106528505, 6803.7618683345845, 4842.0, 4895.1922932717125, 4899.510048995072, 4967.82612608557, 5007.273945277987, 4850.733209673502, 4908.828191013297, 4954.801807927643, 5036.622253331088, 5047.4700156484505], "value": 4392.227290749472, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.2085932707987, 302.75023105937595, 309.7212508742132, 313.02960821453496, 314.38537658876896, 4610.469795973464, 5078.568321692651, 5154.8194064964655, 5170.8448746537615, 5252.422233554306, 3848.9615103848955, 4579.358889755437, 4643.024964757405, 4810.470848206694, 5050.348567002326, 3939.5075615548058, 3949.289127956981, 3988.2621714982674, 3994.680425565962, 4025.3898440623693, 3937.5078115235597, 3940.566537680853, 3984.1434091670285, 3985.003749062734, 4000.239954408653, 3542.6103128655827, 3696.186838895452, 3924.2543916655886, 3937.8227979740836, 3951.9209615807667, 3105.0, 3922.5489068757174, 3934.488516492852, 3935.3900145477496, 3969.206158768229, 3411.624721280657, 3423.9315213695836, 3428.777129486575, 3431.8455669494933, 3436.8281585920604, 4766.308885211625, 4802.639802014828, 4967.0, 4991.600671946236, 5019.598432125421, 4494.550544945512, 4510.661700372485, 4516.683832131752, 4521.208788462039, 4547.749873756942, 5408.486193811621, 5449.683884349136, 5455.399906010336, 5465.781368745245, 5468.496163555014, 3068.3709839483076, 3758.699304055669, 3807.447920051605, 3820.2550502651993, 3825.08198032473, 5296.596401953486, 5465.781368745245, 5526.944730552683, 5692.686902220335, 5698.401667824819, 3390.9152271192925, 3839.884803455901, 3858.9614103858603, 3868.149007218464, 3879.476270703419, 3899.6100389961343, 3905.8047097645003, 3909.9413508797393, 3913.2369188008634, 3966.900827479336, 4052.4326594276827, 4329.328954012133, 4529.54704529551, 4543.61379282758, 4557.95442045582, 5487.475761149836, 5505.917411238835, 5508.6970216637665, 5515.821250605988, 5529.30883639545, 1024.892386299428, 1172.3141961952354, 1255.4978008796372, 1264.867188945166, 1268.226381907036, 834.6744769539885, 844.9619767110495, 851.595492141228, 856.4647095565272, 884.3014018925106, 4328.165051891016, 4454.28731402974, 4676.931069969149, 4967.975160124176, 4977.676451030671, 4889.948661037823, 5222.817201397933, 5367.409584945653, 5374.811881584125, 5379.65032272901, 4355.695101342939, 4875.536824001785, 4878.902421951612, 4882.145624515695, 4882.853514394574, 2610.9817171303207, 2735.8358498490156, 2799.7121324190693, 2814.9859250704026, 2833.504136776056, 5306.204069389545, 5508.807191748269, 5514.911649476306, 5547.7780888764, 5548.112302031655, 4494.4606647202545, 4881.902361952741, 4950.084234416644, 5059.949400506021, 5525.491313016183, 4736.7631618418945, 5476.808311709071, 5549.528290095306, 5568.916466253009, 5572.442755724395, 4534.410526631516, 5322.920156197582, 5323.547498462674, 5337.97331013358, 5381.546982314831, 5026.924596131134, 5035.748212589429, 5267.710275934707, 5326.467353264643, 5331.253624492651, 4983.626228032839, 5321.787128514816, 5451.809186678447, 5584.301962254718, 5642.71786410686, 3339.5324654548867, 3410.6930376266255, 3639.8544058237376, 3678.3378991781738, 3703.962960370362, 4280.20816149001, 4786.2581299898575, 5072.54347108762, 5327.840164795062, 5332.386775520865, 4407.801648925742, 4928.852134435972, 4976.685841083913, 5135.306733591066, 5169.121249387688, 3975.0062484378905, 4721.7723391919035, 4916.655834091723, 4927.536231884058, 5152.793888244429, 4468.530804265505, 4619.584237418649, 4849.75751212445, 5169.327987361619, 5367.865803354791, 5031.874203144951, 5362.356517217959, 5392.730363481889, 5411.512963833274, 5530.55755539564, 4457.910841783081, 5092.428323696509, 5400.297961265168, 5408.5673146149, 5432.207371567294], "value": 3901.0566610611954, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.0628339175328, 717.347214035228, 750.7484992527504, 751.5753599216442, 756.2513112019103, 14157.575272741822, 14277.92861035696, 14693.632659183526, 14751.557453276406, 14771.852281477182, 10629.362238265709, 11251.718707032329, 12936.74126517469, 12972.156809807357, 13100.475980960773, 658.4074333100212, 671.4024518178825, 673.8113328268092, 806.5120602035772, 832.1595188859266, 807.4912804932884, 816.0411516468156, 820.8235229425673, 821.8191997760483, 822.1490757066407, 874.422880898607, 879.2394578689419, 884.1291328041874, 884.7566919097268, 885.4642941020686, 691.9342662447059, 708.153756261268, 712.006750582939, 876.0582373947999, 877.5699907045533, 528.0864105098163, 528.5137673340497, 624.4785604020648, 638.1544453598954, 639.9840003999891, 13713.314334283346, 13734.0, 13755.243461609607, 13779.035467517278, 13788.448462061506, 12598.496060157582, 12716.175481427948, 12871.292078935654, 12943.352832358438, 12999.935000324938, 11200.390306071688, 11799.705007374798, 11835.88164118365, 11967.521299148024, 12462.0, 4266.935995960063, 4313.374560688682, 4323.913521729579, 4468.329750537413, 4482.8879278017985, 92179.0, 92822.53588732013, 92935.0, 94352.52823735838, 96756.06487870273, 9848.50757462124, 10222.591096356211, 10267.589296428208, 10331.0, 10676.359418434919, 18520.444386668423, 19104.426867194008, 19189.0, 19194.0, 19307.90346048289, 7544.8491030180185, 7706.229377062249, 7732.961335193288, 7838.8432231355055, 7940.602969851484, 4172.958270417257, 4214.346776249687, 4318.848840290575, 4336.6530677545725, 4350.456192975878, 3319.0, 3432.2963792422493, 3434.3818112739946, 3436.5876094868777, 3438.6561343865415, 613.8465383654086, 618.4835662222005, 626.764963138823, 627.0500192208807, 637.9649119298483, 536.1983834167944, 548.7996881138356, 578.5545130249768, 590.5216774412739, 673.6631684157921, 8507.574621268912, 8856.3800533962, 9006.279497640173, 9009.549522523848, 9075.54622268884, 625.5433533520464, 627.419636835925, 650.391883588846, 652.4519403700914, 660.1022609251414, 4192.559781222928, 4298.46269216348, 4349.064951035542, 4369.344598310216, 4377.0, 123949.0, 125548.0, 126239.0, 127182.36408817896, 127689.72310276778, 109834.0, 110585.44707276412, 110747.0, 113231.0, 116755.0, 98812.0, 99228.0, 100172.0, 102688.97311027093, 104615.0, 8513.31893448535, 8643.265322447662, 8681.696140635047, 8694.521801300736, 8970.955145224232, 60918.69540652268, 61802.763944719954, 62375.0, 62580.68709656422, 62947.6852615734, 83999.0, 87651.0, 88737.66893496733, 89470.65794012965, 91964.0, 10432.0, 10833.295835770965, 10901.945490272497, 11131.666050018512, 11308.378039207922, 11765.705857353634, 11993.020512159424, 12001.85982331677, 12047.0, 12057.698557536132, 11057.723556911142, 11365.374904380338, 11449.828252576384, 11517.136214783757, 11835.763284734085, 10261.897381026094, 11633.243839150766, 12209.755804884031, 12482.875171248172, 12696.74606507883, 11848.644540663794, 12075.698107547381, 12088.818667719815, 12266.080043996557, 12419.565315213924, 9345.0, 10049.949750251495, 10333.534990925576, 10386.065254126863, 10397.42814145199, 10258.28192026551, 10712.8928710715, 10726.892731072589, 10731.40977246259, 10770.73073173177], "value": 7231.214310047526, "unit": "score"}], "name": "dromaeo_css", "value": 7146.221028048712, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 124.0, 171.0, 114.0, 114.0, 117.0, 164.0, 170.0, 115.0], "value": 131.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 110.0, 144.0, 116.0, 115.0, 110.0, 110.0, 151.0, 111.0], "value": 122.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 187.0, 181.0, 184.0, 184.0, 181.0, 183.0, 185.0, 183.0, 182.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 58.0, 90.0, 78.0, 82.0, 82.0, 61.0, 83.0, 58.0], "value": 73.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 94.0, 69.0, 68.0, 68.0, 90.0, 68.0, 69.0, 92.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 86.0, 89.0, 85.0, 90.0, 87.0, 88.0, 86.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 108.0, 150.0, 93.0, 94.0, 94.0, 95.0, 94.0], "value": 101.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 82.0, 81.0, 83.0, 81.0, 85.0, 86.0, 83.0, 81.0], "value": 82.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 54.0, 56.0, 57.0, 57.0, 57.0, 56.0, 55.0, 57.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 75.0, 72.0, 76.0, 73.0, 69.0, 69.0, 69.0, 70.0, 72.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 84.0, 85.0, 83.0, 84.0, 83.0, 83.0, 84.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 159.0, 152.0, 156.0, 162.0, 158.0, 154.0, 150.0, 189.0, 164.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 169.0, 171.0, 169.0, 166.0, 166.0, 168.0, 166.0, 167.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 67.0, 66.0, 64.0, 63.0, 65.0, 66.0, 65.0], "value": 65.1, "unit": "score"}], "name": "kraken", "value": 1460.1999999999998, "alertThreshold": 2.0}]} 14:27:18 INFO - Return code: 0 14:27:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:27:19 INFO - # TBPL SUCCESS # 14:27:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:27:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:27:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:27:19 INFO - 2016-11-25 14:27:19.077 screenresolution[1658:10293] starting screenresolution argv=screenresolution get 14:27:19 INFO - 2016-11-25 14:27:19.080 screenresolution[1658:10293] Display 0: 1600x1200x32@60 14:27:19 INFO - 2016-11-25 14:27:19.086 screenresolution[1659:10296] starting screenresolution argv=screenresolution list 14:27:19 INFO - Available Modes on Display 0 14:27:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:27:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:27:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:27:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:27:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:27:19 INFO - Intel Iris: 14:27:19 INFO - Chipset Model: Intel Iris 14:27:19 INFO - Type: GPU 14:27:19 INFO - Bus: Built-In 14:27:19 INFO - VRAM (Dynamic, Max): 1536 MB 14:27:19 INFO - Vendor: Intel (0x8086) 14:27:19 INFO - Device ID: 0x0a2e 14:27:19 INFO - Revision ID: 0x0009 14:27:19 INFO - Displays: 14:27:19 INFO - Display: 14:27:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:27:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:27:19 INFO - Display Serial Number: 1600x1200 60 14:27:19 INFO - Main Display: Yes 14:27:19 INFO - Mirror: Off 14:27:19 INFO - Online: Yes 14:27:19 INFO - Rotation: Supported 14:27:19 INFO - Return code: 0 14:27:19 INFO - Running post-action listener: _resource_record_post_action 14:27:19 INFO - [mozharness: 2016-11-25 22:27:19.328514Z] Finished run-tests step (success) 14:27:19 INFO - Running post-run listener: _resource_record_post_run 14:27:19 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31382528; Write bytes: 659329024; Read time: 191; Write time: 1924 14:27:19 INFO - TinderboxPrint: CPU usage
53.3% 14:27:19 INFO - TinderboxPrint: I/O read bytes / time
31,382,528 / 191 14:27:19 INFO - TinderboxPrint: I/O write bytes / time
659,329,024 / 1,924 14:27:19 INFO - TinderboxPrint: CPU idle
2,262.2 (46.7%) 14:27:19 INFO - TinderboxPrint: CPU system
963.6 (19.9%) 14:27:19 INFO - TinderboxPrint: CPU user
1,620.0 (33.4%) 14:27:19 INFO - TinderboxPrint: Swap in / out
375,406,592 / 0 14:27:19 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 347685376; Write bytes: 344623104; Read time: 26911; Write time: 719 14:27:19 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 29309952; Write bytes: 307517440; Read time: 171; Write time: 1180 14:27:19 INFO - Running post-run listener: _upload_blobber_files 14:27:19 INFO - Blob upload gear active. 14:27:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:27:19 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 14:27:19 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'] 14:27:19 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 14:27:20 INFO - (blobuploader) - INFO - Open directory for files ... 14:27:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:27:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:27:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:21 INFO - (blobuploader) - INFO - Done attempting. 14:27:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:27:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:27:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:22 INFO - (blobuploader) - INFO - Done attempting. 14:27:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:27:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:27:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:25 INFO - (blobuploader) - INFO - Done attempting. 14:27:25 INFO - (blobuploader) - INFO - Iteration through files over. 14:27:25 INFO - Return code: 0 14:27:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:27:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:27:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80fa57ba54bd2b6d18924813c97b60f2205a61eaa577b8ff6cdcd8719dbbd05190535f961fe016b0d8738bd26eccd03126333b6d62560fa768747ab8155f837c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5ebee6decdd40395e581ae6bd05f0123bd50434980ab496879079b5df5cdf6152c068ad9ac56052d8c5cf593145e7254b3f0a658416025645c3c09317f3e283", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/929bae91bf6c7327b97cb85f2544c426b86d5749c96dcd4b3135c49e6bd94ab689c893c487f454cdf2f3d3c9b1f33ce35d8d760bb12b17744339b3b591cc6181"} 14:27:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:27:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:27:25 INFO - Contents: 14:27:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80fa57ba54bd2b6d18924813c97b60f2205a61eaa577b8ff6cdcd8719dbbd05190535f961fe016b0d8738bd26eccd03126333b6d62560fa768747ab8155f837c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5ebee6decdd40395e581ae6bd05f0123bd50434980ab496879079b5df5cdf6152c068ad9ac56052d8c5cf593145e7254b3f0a658416025645c3c09317f3e283", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/929bae91bf6c7327b97cb85f2544c426b86d5749c96dcd4b3135c49e6bd94ab689c893c487f454cdf2f3d3c9b1f33ce35d8d760bb12b17744339b3b591cc6181"} 14:27:25 INFO - Running post-run listener: copy_logs_to_upload_dir 14:27:25 INFO - Copying logs to upload dir... 14:27:25 INFO - mkdir: /builds/slave/test/build/upload/logs 14:27:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.112609 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-25 14:27:25.803442) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 14:27:25.806794) ========= 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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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/80fa57ba54bd2b6d18924813c97b60f2205a61eaa577b8ff6cdcd8719dbbd05190535f961fe016b0d8738bd26eccd03126333b6d62560fa768747ab8155f837c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5ebee6decdd40395e581ae6bd05f0123bd50434980ab496879079b5df5cdf6152c068ad9ac56052d8c5cf593145e7254b3f0a658416025645c3c09317f3e283", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/929bae91bf6c7327b97cb85f2544c426b86d5749c96dcd4b3135c49e6bd94ab689c893c487f454cdf2f3d3c9b1f33ce35d8d760bb12b17744339b3b591cc6181"} build_url:https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010888 build_url: 'https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80fa57ba54bd2b6d18924813c97b60f2205a61eaa577b8ff6cdcd8719dbbd05190535f961fe016b0d8738bd26eccd03126333b6d62560fa768747ab8155f837c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5ebee6decdd40395e581ae6bd05f0123bd50434980ab496879079b5df5cdf6152c068ad9ac56052d8c5cf593145e7254b3f0a658416025645c3c09317f3e283", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/929bae91bf6c7327b97cb85f2544c426b86d5749c96dcd4b3135c49e6bd94ab689c893c487f454cdf2f3d3c9b1f33ce35d8d760bb12b17744339b3b591cc6181"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 14:27:25.840768) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 14:27:25.841079) ========= 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.7hSnLa1nXy/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.k9cBaJl6r6/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.005012 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 14:27:25.889147) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 14:27:25.889486) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 14:27:25.889814) ========= ========= Total master_lag: 0.20 =========