builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0123 starttime: 1480111569.47 results: success (0) buildid: 20161125122519 builduid: 78dbc2e280e34d38b045fbe066f3a206 revision: bafd3cf3970490e029351e6befa56667f568f85f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:09.466835) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:09.467304) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:09.467585) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:09.494778) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:09.495202) ========= 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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 151M=0s 2016-11-25 14:06:10 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.595861 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:10.117514) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:10.118075) ========= 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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.026485 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:10.167004) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 14:06:10.167380) ========= 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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:10,262 truncating revision to first 12 chars 2016-11-25 14:06:10,262 Setting DEBUG logging. 2016-11-25 14:06:10,262 attempt 1/10 2016-11-25 14:06:10,262 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:11,586 unpacking tar archive at: mozilla-aurora-bafd3cf39704/testing/mozharness/ program finished with exit code 0 elapsedTime=1.574044 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 14:06:11.764857) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:11.765189) ========= script_repo_revision: bafd3cf3970490e029351e6befa56667f568f85f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:11.765587) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:11.765847) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 14:06:11.785724) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-25 14:06:11.786212) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:11 INFO - MultiFileLogger online at 20161125 14:06:11 in /builds/slave/test 14:06:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:06:11 INFO - {'append_to_log': False, 14:06:11 INFO - 'base_work_dir': '/builds/slave/test', 14:06:11 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:06:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:06:11 INFO - 'branch': 'Mozilla-Aurora', 14:06:11 INFO - 'buildbot_json_path': 'buildprops.json', 14:06:11 INFO - 'config_files': ('talos/mac_config.py',), 14:06:11 INFO - 'default_actions': ('clobber', 14:06:11 INFO - 'read-buildbot-config', 14:06:11 INFO - 'download-and-extract', 14:06:11 INFO - 'populate-webroot', 14:06:11 INFO - 'create-virtualenv', 14:06:11 INFO - 'install', 14:06:11 INFO - 'run-tests'), 14:06:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:06:11 INFO - 'download_minidump_stackwalk': True, 14:06:11 INFO - 'download_symbols': 'ondemand', 14:06:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:06:11 INFO - 'tooltool.py': '/tools/tooltool.py', 14:06:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:06:11 INFO - '/tools/misc-python/virtualenv.py')}, 14:06:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:06:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:06:11 INFO - 'installer_path': 'installer.exe', 14:06:11 INFO - 'log_level': 'info', 14:06:11 INFO - 'log_name': 'talos', 14:06:11 INFO - 'log_to_console': True, 14:06:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:06:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:06:11 INFO - 'opt_config_files': (), 14:06:11 INFO - 'pip_index': False, 14:06:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:06:11 INFO - 'cmd': ('bash', 14:06:11 INFO - '-c', 14:06:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:06:11 INFO - 'enabled': True, 14:06:11 INFO - 'halt_on_failure': False, 14:06:11 INFO - 'name': 'check_screen_resolution'},), 14:06:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:06:11 INFO - 'cmd': ('bash', 14:06:11 INFO - '-c', 14:06:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:06:11 INFO - 'enabled': True, 14:06:11 INFO - 'halt_on_failure': False, 14:06:11 INFO - 'name': 'check_screen_resolution'},), 14:06:11 INFO - 'run_cmd_checks_enabled': True, 14:06:11 INFO - 'sps_profile': False, 14:06:11 INFO - 'sps_profile_interval': 0, 14:06:11 INFO - 'suite': 'dromaeojs-e10s', 14:06:11 INFO - 'system_bits': '32', 14:06:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:06:11 INFO - 'title': 't-yosemite-r7-0123', 14:06:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:06:11 INFO - 'use_talos_json': True, 14:06:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:06:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:06:11 INFO - 'work_dir': 'build'} 14:06:11 INFO - [mozharness: 2016-11-25 22:06:11.992037Z] Running clobber step. 14:06:11 INFO - Running pre-action listener: _resource_record_pre_action 14:06:11 INFO - Running main action method: clobber 14:06:11 INFO - rmtree: /builds/slave/test/build 14:06:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:06:14 INFO - Running post-action listener: _resource_record_post_action 14:06:14 INFO - [mozharness: 2016-11-25 22:06:14.510614Z] Finished clobber step (success) 14:06:14 INFO - [mozharness: 2016-11-25 22:06:14.510788Z] Running read-buildbot-config step. 14:06:14 INFO - Running pre-action listener: _resource_record_pre_action 14:06:14 INFO - Running main action method: read_buildbot_config 14:06:14 INFO - Using buildbot properties: 14:06:14 INFO - { 14:06:14 INFO - "project": "", 14:06:14 INFO - "product": "firefox", 14:06:14 INFO - "script_repo_revision": "production", 14:06:14 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 14:06:14 INFO - "repository": "", 14:06:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 14:06:14 INFO - "buildid": "20161125122519", 14:06:14 INFO - "pgo_build": "False", 14:06:14 INFO - "basedir": "/builds/slave/test", 14:06:14 INFO - "buildnumber": 30, 14:06:14 INFO - "slavename": "t-yosemite-r7-0123", 14:06:14 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 14:06:14 INFO - "platform": "yosemite_r7", 14:06:14 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:06:14 INFO - "branch": "mozilla-aurora", 14:06:14 INFO - "repo_path": "releases/mozilla-aurora", 14:06:14 INFO - "revision": "bafd3cf3970490e029351e6befa56667f568f85f", 14:06:14 INFO - "stage_platform": "macosx64", 14:06:14 INFO - "builduid": "78dbc2e280e34d38b045fbe066f3a206", 14:06:14 INFO - "slavebuilddir": "test" 14:06:14 INFO - } 14:06:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 14:06:14 INFO - Running post-action listener: _resource_record_post_action 14:06:14 INFO - [mozharness: 2016-11-25 22:06:14.514086Z] Finished read-buildbot-config step (success) 14:06:14 INFO - [mozharness: 2016-11-25 22:06:14.514190Z] Running download-and-extract step. 14:06:14 INFO - Running pre-action listener: _resource_record_pre_action 14:06:14 INFO - Running main action method: download_and_extract 14:06:14 INFO - mkdir: /builds/slave/test/build/tests 14:06:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:06:14 INFO - trying https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 14:06:14 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:14 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:15 INFO - Downloaded 1199 bytes. 14:06:15 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 14:06:15 INFO - Using the following test package requirements: 14:06:15 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:06:15 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 14:06:15 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 14:06:15 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'jsshell-mac.zip'], 14:06:15 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 14:06:15 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:06:15 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 14:06:15 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 14:06:15 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 14:06:15 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 14:06:15 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:06:15 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:15 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:15 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 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 14:06:16 INFO - x-amz-cf-id: DOFtdXJL_U2HztbljrPO7VQbUl2Ub2ueFKv7vgy7dmegcWo6meFGxg== 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:23 INFO - Http code: 200 14:06:23 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 14:06:23 INFO - x-amz-cf-id: LEFvFeiqv52pLmXOoqbqrsD7fe6dnexfzZTy4rRBlVWIGmhbNH8vSQ== 14:06:23 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 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 14:06:31 INFO - x-amz-cf-id: gzHsuX9SPFHQZHm_E6COZAWurQpR0Nt6oC_7vGVuxd6TWbfjBOnE_w== 14:06:31 INFO - x-amz-version-id: nUeaCLjTADObzprT99S4fcRH.wxQ4xcN 14:06:31 INFO - Expected file size: 14409038 14:06:31 INFO - Obtained file size: 14409038 14:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:06:31 INFO - trying https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:06:31 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:31 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:34 INFO - Downloaded 109742471 bytes. 14:06:34 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:34 INFO - mkdir: /builds/slave/test/properties 14:06:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:06:34 INFO - Writing to file /builds/slave/test/properties/build_url 14:06:34 INFO - Contents: 14:06:34 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.302205Z] Finished download-and-extract step (success) 14:06:35 INFO - [mozharness: 2016-11-25 22:06:35.302331Z] 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.321378Z] Finished populate-webroot step (success) 14:06:35 INFO - [mozharness: 2016-11-25 22:06:35.321517Z] 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 0x106c46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bbc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4d05866e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a5ad30>, '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 0x1068c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a52b20>, '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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 14:06:39 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:39 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:39 INFO - Cleaning up... 14:06:39 INFO - Return code: 0 14:06:39 INFO - Installing psutil>=3.1.1 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', '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 0x106c46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bbc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4d05866e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a5ad30>, '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 0x1068c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a52b20>, '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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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', 'psutil>=3.1.1'] 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 psutil>=3.1.1 14:06:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 14:06:39 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:39 INFO - Downloading/unpacking psutil>=3.1.1 14:06:39 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:39 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:39 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:39 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:39 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:39 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:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:06:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:06:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:06:43 INFO - Installing collected packages: psutil 14:06:43 INFO - Running setup.py install for psutil 14:06:43 INFO - building 'psutil._psutil_osx' extension 14:06:43 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:44 INFO - building 'psutil._psutil_posix' extension 14:06:44 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:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:06:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:06:44 INFO - ^ 14:06:44 INFO - 1 warning generated. 14:06:44 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: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 - Successfully installed psutil 14:06:44 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 0x106c46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bbc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4d05866e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a5ad30>, '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 0x1068c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a52b20>, '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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 14:06:45 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:06:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:06:47 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:47 INFO - Installing collected packages: mozsystemmonitor 14:06:47 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 0x106c46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bbc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4d05866e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a5ad30>, '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 0x1068c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a52b20>, '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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 14:06:48 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:06:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 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 0x106c46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bbc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4d05866e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a5ad30>, '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 0x1068c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a52b20>, '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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 14:06:51 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:06:51 INFO - Cleaning up... 14:06:51 INFO - Return code: 0 14:06:51 INFO - Installing blobuploader==1.2.4 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', '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 0x106c46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bbc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4d05866e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a5ad30>, '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 0x1068c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a52b20>, '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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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', 'blobuploader==1.2.4'] 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 blobuploader==1.2.4 14:06:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 14:06:51 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 - 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:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:06:54 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:54 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 - 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:54 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:54 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:54 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:55 INFO - Downloading docopt-0.6.1.tar.gz 14:06:55 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:56 INFO - Successfully installed blobuploader requests docopt 14:06:56 INFO - Cleaning up... 14:06:56 INFO - Return code: 0 14:06:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:06:56 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:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:56 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:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:56 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 0x106c46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bbc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4d05866e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a5ad30>, '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 0x1068c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a52b20>, '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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:56 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:56 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:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPAG0ScN4E/Render', 14:06:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:56 INFO - 'HOME': '/Users/cltbld', 14:06:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:56 INFO - 'LOGNAME': 'cltbld', 14:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:56 INFO - 'MOZ_NO_REMOTE': '1', 14:06:56 INFO - 'NO_EM_RESTART': '1', 14:06:56 INFO - 'PAGER': '/bin/cat', 14:06:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:56 INFO - 'PWD': '/builds/slave/test', 14:06:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:56 INFO - 'SHELL': '/bin/bash', 14:06:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dj9Cj7P7Dm/Listeners', 14:06:56 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 14:06:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:56 INFO - 'USER': 'cltbld', 14:06:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:56 INFO - 'XPC_FLAGS': '0x0', 14:06:56 INFO - 'XPC_SERVICE_NAME': '0', 14:06:56 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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-prikjx-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-J_o7iY-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-uLn4L6-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-WGQqDO-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ahxEEv-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-5EbOjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:06:57 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-MiSdfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:06:57 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-uyNMnY-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-PUBx2m-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-h7QulX-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-OHJulH-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-IFZwD1-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Iw7v4I-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-lSeoZm-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-LGYEdM-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-DEsJpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:06:58 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-u2ugsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:06:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:06:58 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:06:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:06:59 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:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:07:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:07:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:07:00 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 0x106c46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bbc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4d05866e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a5ad30>, '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 0x1068c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a52b20>, '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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 14:07:01 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:07:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-d9S17Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:07:01 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:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:07:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ygBjzy-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-aHJL9L-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-lswgdx-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-LhRTy4-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-BPCsNo-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-6luioC-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-EjDM3_-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-vGwf_B-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Wx3dTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:07:02 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:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:07:02 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-L1pD7Y-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-D5Ny0t-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-QK7x6q-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-kMMDVy-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-hvTdCc-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-M1u315-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/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-USMT50-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 0x106c46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bbc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4d05866e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a5ad30>, '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 0x1068c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a52b20>, '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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 14:07:06 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 0x106c46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bbc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4d05866e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a5ad30>, '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 0x1068c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a52b20>, '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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners', 14:07:07 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.427994Z] Finished create-virtualenv step (success) 14:07:07 INFO - [mozharness: 2016-11-25 22:07:07.429050Z] 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.587872Z] Finished install step (success) 14:07:38 INFO - [mozharness: 2016-11-25 22:07:38.587982Z] 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.600 screenresolution[1628:8412] starting screenresolution argv=screenresolution get 14:07:38 INFO - 2016-11-25 14:07:38.603 screenresolution[1628:8412] Display 0: 1600x1200x32@60 14:07:38 INFO - 2016-11-25 14:07:38.608 screenresolution[1629:8414] 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:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:07:38 INFO - Intel Iris: 14:07:38 INFO - Chipset Model: Intel Iris 14:07:38 INFO - Type: GPU 14:07:38 INFO - Bus: Built-In 14:07:38 INFO - VRAM (Dynamic, Max): 1536 MB 14:07:38 INFO - Vendor: Intel (0x8086) 14:07:38 INFO - Device ID: 0x0a2e 14:07:38 INFO - Revision ID: 0x0009 14:07:38 INFO - Displays: 14:07:38 INFO - Display: 14:07:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:07:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:07:38 INFO - Display Serial Number: 1600x1200 60 14:07:38 INFO - Main Display: Yes 14:07:38 INFO - Mirror: Off 14:07:38 INFO - Online: Yes 14:07:38 INFO - Rotation: Supported 14:07:38 INFO - Return code: 0 14:07:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:07:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:07:38 INFO - Python 2.7.3 14:07:38 INFO - Return code: 0 14:07:38 INFO - grabbing minidump binary from tooltool 14:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:07:38 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 0x106a5ad30>, '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 0x1068c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a52b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:07:38 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:38 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:38 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:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:07:38 INFO - Return code: 0 14:07:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:07:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:07:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:07:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:07:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:07:38 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-e10s', '--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-0123', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:07:38 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-e10s --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-0123 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:07:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPAG0ScN4E/Render', 14:07:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:07:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:07:38 INFO - 'HOME': '/Users/cltbld', 14:07:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:07:38 INFO - 'LOGNAME': 'cltbld', 14:07:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:07:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:07:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:38 INFO - 'MOZ_NO_REMOTE': '1', 14:07:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:07:38 INFO - 'NO_EM_RESTART': '1', 14:07:38 INFO - 'PAGER': '/bin/cat', 14:07:38 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:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:38 INFO - 'PWD': '/builds/slave/test', 14:07:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:07:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:07:38 INFO - 'SHELL': '/bin/bash', 14:07:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dj9Cj7P7Dm/Listeners', 14:07:38 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 14:07:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:07:38 INFO - 'USER': 'cltbld', 14:07:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:07:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:07:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:38 INFO - 'XPC_FLAGS': '0x0', 14:07:38 INFO - 'XPC_SERVICE_NAME': '0', 14:07:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:07:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--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-0123', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_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 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpenNCbu/profile http://localhost:49235/getInfo.html) 14:07:42 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 14:07:42 INFO - PROCESS | 1639 | colorDepth:24 14:07:42 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 14:07:42 INFO - PROCESS | 1639 | __metrics 14:07:42 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 14:07:42 INFO - TEST-INFO | 1639: exit 0 14:07:42 INFO - Browser initialized. 14:07:42 INFO - Running cycle 1/1 for dromaeo_css test... 14:07:42 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpenNCbu/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 | 1643 | RSS: Main: 230043648 14:07:55 INFO - PROCESS | 1643 | 14:08:44 INFO - PROCESS | 1643 | 1480111724849 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:58 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 14:11:00 INFO - PROCESS | 1643 | RSS: Main: 234164224 14:11:00 INFO - PROCESS | 1643 | 14:14:03 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 14:14:03 INFO - PROCESS | 1643 | RSS: Main: 243720192 14:14:03 INFO - PROCESS | 1643 | 14:17:05 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 14:17:06 INFO - PROCESS | 1643 | RSS: Main: 246632448 14:17:06 INFO - PROCESS | 1643 | 14:20:08 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 14:20:08 INFO - PROCESS | 1643 | RSS: Main: 245952512 14:20:08 INFO - PROCESS | 1643 | 14:23:11 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 14:23:12 INFO - PROCESS | 1643 | RSS: Main: 250241024 14:23:12 INFO - PROCESS | 1643 | 14:26:15 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 14:26:15 INFO - PROCESS | 1643 | RSS: Main: 251944960 14:26:15 INFO - PROCESS | 1643 | 14:26:15 INFO - PROCESS | 1643 | __start_tp_report 14:26:15 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 14:26:15 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 14:26:15 INFO - PROCESS | 1643 | |i|pagename|runs| 14:26:15 INFO - PROCESS | 1643 | |0;dojo.html;3419.3503234385466;3431.9485207721887;3454.7236221102316;3458;3467.739919506037;5782.045962416201;5895.88208235835;5905.2618422697105;5922.3485416604135;5930.140129681205;5889.793857214999;5972.552058595601;5975.312839023502;5985.34161242263;6005.099235114735;5310.681359118446;5395.136778115502;5416.245751451841;5428.321459817523;5436.6194366394175;5371.382291036542;5429.294191755086;5443.401225865152;5449.782008719647;5451.918221226685;5268.104422248208;5303.920441193385;5430.402655707889;5435.701036442994;5491.835244942638;4692.953070469286;4797.8320758773625;5308.787648494055;5348.732563371816;5388.084025715638;5245.737713114329;5381.408045115034;5406.351237851483;5408.486193811582;5458.2904222451225;5950.226470558843;5962.284525856925;6003.309619393782;6003.369646187131;6028.638281703112;6130.306415576139;6189.87620247597;6280;6359.268684101295;6382.138411314506;6230.127782110508;6259.155014073133;6315.526335524854;6325.272593651743;6341.04884267359;6003.159557661887;6020.217371741645;6054.3945605439985;6061.515078793774;6107.541934354852;6264.968675156595;6278.277998030194;6284.183056202664;6340.714667839959;6397.840053998687;6283.842903927347;6295.307516173218;6300.21247344082;6301.369863013753;6312.968435157794;6347.333529979378;6348.523860710465;6352.587081839665;6359.745610175633;6368.617882927039;5377.516023557899;6166.414190651881;6247.00047992319;6291.1192433059405;6368.331325210879;5233.528982391527;6269.686515674198;6315.873682526324;6315.905261421082;6333.1766870306565;4061.045654271244;4449.555044495524;5019.272205530251;5022.9748851256245;5027.974860125676;4449.57729015743;4489.281714925596;4489.7979590918485;4534.070515544306;4542.500324964251;5863.120531920161;5872.677002764889;5905.6161349513;5924.407559244128;5979.4917432018765;6283.560150789493;6288.591241569284;6294.118823364733;6321.241451025814;6339.4611458025665;5892.499137573269;5913.58604897653;6028.668423236764;6028.728707208186;6051.273847138372;5394.352677678625;5436.510714035756;5482.013237617267;5499.065158922993;5503.532199762985;5368.355797304349;6428.0357946307495;6434;6449.967750161313;6516.446102081376;5150.433452320167;6207.379262073757;6243.500519958483;6257.217847769029;6306.684665766785;5166.121759301003;6380.553361264667;6411.294757576756;6465.127207826883;6467.450266727381;5584.888302233851;6255.593386429868;6295.213098362705;6353.650549219652;6354.364563543609;6138.355472675305;6171.259448866164;6175.6912154393;6178;6326.4622507087415;5735.8566035849435;6164.260288765377;6214.067889816563;6225;6277.403646653561;6171.907421388593;6244.126761971481;6244.344343844013;6271.24745030581;6322.620642761265;5929.1995580597795;6027.758889644365;6078.270607527125;6175.536834737323;6208.2860471044605;5037.571806396498;6122.5811271886105;6191.288001879841;6219.782307619212;6245.219347581552;6112.633242005494;6159.5072394209255;6160.44555989963;6160.784372546939;6183.505319574333 14:26:15 INFO - PROCESS | 1643 | |1;ext.html;476.96498598042245;496.2531390257663;520.6927912531605;533.6637918111592;541.9891901603505;29634.85182574087;38949.80525097374;40069.39895901557;40398.99002524938;42356;30326;33083.66916330836;40879.77360679177;41214.96962575937;41617.79191104048;9273.211776998964;9344.299177561676;9357.391769534992;9368.90631093687;9371.906280937172;9338.859917101216;9350.485723285217;9397.248220142406;9424;9464.716058518221;8229.012518497788;9179.357444978854;9373.578188981512;9428.575714092847;9442.433453992748;7834.863944728011;7908.248716371935;8330.166983301622;9008.864867027;9340.252779777602;8163.714270000581;9139.771505712344;9177.908220917772;9231.169194772503;9236.72289831306;38845.02887427808;38933.02667433309;38946.41580376297;39122.60877391246;39170.60829391698;3084.8766049358;3203.7224958542774;3411.0790086676707;3440.6215316315183;3626.564812222524;28129.156125316273;28435.431291374058;30020.399592008256;30536.54195187074;30598.082057538308;3137.4038932602734;3167.7624178186497;3179.871145743274;3206.3106432116974;3222.742180625568;494532;500635;501924.4518887057;503151;504080.4795976033;57254.7137264311;57549;57566.7121664389;57635.42364576385;57799.711001444724;59101;59780;59782;59879;59972.700136499036;15767.369305227663;15891.761623575656;16048.75926861098;16054.117023563816;16119.919400403158;12479.376031198404;12527.874721252852;12571.24572525633;12598.622041338775;12629.936850315873;13656.590302290948;15276.388944442098;15694.921525392529;15708.842911570966;15786.842131578536;3085.9382812344074;3194.8242846643193;5795.246617939641;5965.717370765306;5989.221401217814;873.1792115411492;940.5532372123187;981.8036392721484;1037.201354956679;1045.1817123062674;29647;29664.851675741484;29704.257393565334;29866.552001719992;30212.69787302099;855.8630619100913;911.8905731312152;940.0317672796893;1069.4813015687384;1097.2483848563759;14716.484923027643;14979.625509362353;15108.39566417353;15357.692846143014;15468.458603948808;677519;736115;988776;993505;997019;56066;56543.43456565464;56693.71653141708;56803;56934.7153264231;1326.2307861440343;1339.437436276766;1345.085341967452;1390.3743315508061;1391.1931079973792;26571.07001255024;28674.713252867205;28948.710512894602;29543.704562954954;29788.404231915672;48030.75984620055;48801.5119848797;48807;48864.75567622139;48902.75548622234;544386.2780686229;625344;642557;645480;646353;21761.564768704222;22244.555108898054;25601.61597576075;26417.60373594436;26444.471110577295;38433.4234986481;42839.785801072045;44613.884652883935;45519.31721024121;45791.77104114458;36945.07637309089;36967.890963271144;37139.257214856094;37709.81145094257;38581.807090964365;37220;37508;37798;38233.617663823;38396.61603384042;35980.640193598774;36148.91553253407;36741.71404000847;36819.26361472702;36915.81542092272;31009.379812404077;33724.662753372155;33732;33781.49327760036;33931.47308371179;24650.890709917738;24841.25476235716;24881.62677559802;24973;25266 14:26:15 INFO - PROCESS | 1643 | |2;jquery.html;366.3552148219134;381.36693089471464;382.41681435810375;388.1983703651957;407.1389012239115;15091.773623395631;15441.382344706226;17703.645927081452;19863.801361986374;20153;16172.676546469063;16836.158192090377;19421.514462138453;19774.30789922353;20083.09626066827;5505.366882808488;5758.251427314443;5759.4816466518005;5781.537477001851;5790.160426738121;5328.280682107924;5675.489205971462;5725.770969161229;5751.194832723401;5759.856003599923;5476.780928762844;5670.659760414368;5796.07262837946;5843.561732870031;5894.528437724972;4697.765111744399;5711.657300561958;5769.5672824538115;5789.218455508516;5805.158252053471;5615.775368985236;5678.063119585264;5705.172749951275;5707.343655479632;5718.1136923776885;12829.61511154667;13063.34683265833;17474.47576572705;23955;24108;2079.916803327865;2099.6220680277543;2149.473379022134;2280.361498780344;2626.369671278879;8290.751277461686;9112.772180695469;9421.340506164572;10062.044105809937;10109.49452527378;1844.6495165918432;1874.709420039896;1884.5288677830542;1885.679434496139;1895.4882181810833;85804;103079.4846025765;104938;109019;118048.8195118055;11362.147838912115;11434.942825285987;11498.827517587242;11573.074154067654;11660.475278612314;14639.268036598127;14648.633784155481;14651.633709157357;14664.560063198121;14701.264936753334;6889.724411023603;7238.92761072393;8326;8598.183172598594;8627.91372086271;8477.152284771473;8491.83016339682;8523.786905327417;8640.611172497252;8641.006284277388;3856.2287542491613;4494.977525112419;4525.886852828706;4530.22986092365;4544.227481328182;1898.8480921526245;1925.1721759643208;1932.4106147624827;1937.321937321926;1939.2533874458154;777.5334799120595;894.1326912894482;907.9364444488822;920.7513971227731;934.8550974598948;14713.337899794535;19636.21455141807;20173;20321.89839050795;20445;715.4741265170096;733.1129333506517;751.691806359401;852.820907609409;871.6121326009865;9371.390859594241;10684.25210235276;10728.73178170552;10762.461876906124;10771.946140269249;532896;607914;633735;635520;646707;106082;113787;117064;125386.37306813408;134579;913.7669894177076;929.6049179098856;938.5213541094091;981.425865868475;1003.7340104872214;16465.25906334242;17468.03925784094;18600.813991859908;18732.7190092146;19014.524636884187;30273.09180724582;39595.60404395919;52316.73841630767;53248.73375633097;55900;71812.28187718056;78311.60844195743;78741.60629196817;79039.81440278285;79526.6260355686;6590.901136483052;7511.812204694927;7898.170692077479;7957.442978991415;8104.878426823484;9903.95048024784;11867.643970680894;12027.75944481088;12322.691932701755;12339.13626046204;10071.949640251752;10257.692269231935;10386.169106471312;10709.57161713554;10742.946285268523;9195.908040919772;10180.694579162637;10409.271351005356;10517.474126293808;10681.305715128803;10642.89357106419;10850.945745271223;10857.402842843403;10956.959088886542;10998.790133085195;6900.7929762107215;7332.193458719643;7345.853082938418;8060.15368386311;8076.152004039726;9794.65718699842;9880.308378413441;10580.100691441314;10817.729556761144;10943.288686235688 14:26:15 INFO - PROCESS | 1643 | |3;mootools.html;1500.0399744163733;1523.314508471188;1714.8542373898217;1800.3698705453085;1829.4603092087832;6575.276719560845;7641.961790191055;8176.836463270738;8643.308535317175;8665.090165532623;8198.754037378872;8487.193716596928;8510.361722870779;8601.096884827097;8610.61252243649;2306.0890948075544;2334.078039174525;2341.7892389684926;2346.2843832631042;2355.1639168095335;2355.316958082154;2373.0151986925493;2383.9642405363934;2398.388410955204;2400;2330.2426711318803;2337.1352599538195;2360.3862995621175;2365.751596082404;2366.290112966112;2316.1777568962943;2318.211807985285;2338.286822519131;2342.0163531316803;2355.187460326193;2345.0854166874933;2347.272345572861;2370.2415227127326;2371.34787933318;2383.368407372048;8459.238668519802;8505.23452889243;8516.23353898146;8520.233179013856;8527.786805329853;5091.694498330112;5270;5270.130428479296;5270.710110943896;5278.102722537178;3482.4776283557417;3507.859685612598;3534.3638145133864;3581.176329444216;3624.818759062063;1044.3785947361307;1064.2177999170603;1066.1044722433116;1111.4831603304501;1113.0705860606404;9437.811243775086;10108.545115469822;11054.613088541862;11132.777344453065;11193.888061119365;3782.659560639556;4465.419495465569;4716.693414928087;4751.311059896296;4792.0895029944195;9586.28102892272;9643;9648.71053868385;9653.276004299565;9660.32377733566;5228.712420816892;5233.319668443078;5234.607404444682;5241.528262456321;5258.053550360895;4248.235317642792;4291.957080429156;4760.500147484534;4764.904701906012;4797.520247975244;3828.3874580067054;3854.903627409281;3930.1746633206776;3943.2507823513442;3970.305196590585;1267.88589026988;1272.5482453728978;1278.3033246880473;1279.923204607708;1283.0569531394417;751.6392131776778;862.2972277593741;904.3488688144525;907.3603109807569;916.812053529025;5542.695151766691;5583.581231407661;5623.297087864017;5627.155926611041;5923.526117910556;884.951327676984;896.068089187238;936.1153709744226;957.4781743949565;1056.2131212246925;3605.801680907523;3761.4169803680475;3838.654521093115;3864.6908247340143;3887.4946256986977;8054.355651547862;10349.223808214414;10479.266451348485;10481.475926203811;10487;10179.898201018043;10340.741481462874;10400.739981500372;10459.00639439252;10469.267151299337;7914.485558438683;8078.111407745143;8110.63502142405;8164.183581641907;8226.794330141794;5499.560035197254;5540.224368588481;5588.189712491585;5649.4633009864;5684.943150568607;9522.904770952478;9585.329026968046;9653.662121825702;9674.70975870725;9724.076212759777;8910.331725120512;9664.71005869825;9765.218782497524;9922.00779922031;9951.751206219902;6721.462283017249;6808.523403361717;6821.2155602106395;6891.75878844238;6914.723411063501;7903.249191326815;8006.679732810622;8033.638486268015;8037.156098609562;8045.879311810445;6092.664903430176;7022;7268.909663550509;7317.378022868116;7332.78001659951;7007.299270073156;7027.4378049756915;7057.505974581935;7062.1878483975;7138.607376594129;6692.297308782508;6869.931300686929;6870.20992585859;6874.415674667509;6876.828079298058;4864.652805021527;4901.264810278344;4948.257761335828;4977.875553111201;5007.899842003212;4805.255185446262;4896.167651499182;4897.191963326011;4995.675281106716;5064.797408103635 14:26:15 INFO - PROCESS | 1643 | |4;prototype.html;259.5483858086928;268.9273896048067;291.7126630269185;292.3436884194983;298.30941370726765;4174.958250417495;4961.801527938887;5076.451682236916;5507;5545.9445405545985;4748.5726284634375;4806.5193480651915;4835.371401717772;5102.948970510294;5413.810516631918;4108.938365924506;4128.339465685491;4155.750654960697;4158.979205103986;4170.978110362966;3401.4047541680106;3746.4452252315255;4023.295923213426;4041.717079804415;4173.3114036184;4092.631663150316;4104.774237416941;4114.835406583748;4114.958850411503;4125.979370103145;3579.480975258586;3594.370985077627;4093.079057212118;4127.484064491939;4135;3589.6589823966683;3605.5673319201865;3622.474741162543;3627.6553727396117;3631.365885351576;4106.815193316308;4675.667434781105;5230.895382092337;5257.395399529065;5272.630915835893;3984.980075099635;4177.22721296558;4647.256438969783;4733.408323959505;4758.286257061469;4721;5517.675757818135;5718.313802343745;5740.138979153118;5749.195112684228;3019.0791808498398;3383.4924761285756;3892.630200130983;3930.371140617516;3932.6264004919485;6008.639481631116;6018.3981601839905;6042.365551617105;6042.456178943872;6068.969655151696;3956.643902048829;3959.2675355059405;3965.2466031453932;3975.940360894589;4026.617471340218;3762.6989840813217;3981.9402708959387;4039.6364327210104;4050.6151915567975;4058.2492238935893;4279.753129606981;4680.602148817321;4686.390769199982;4689.976550117228;4718.976405117953;5475.028989563754;5795.971020144957;5811.0121279381765;5824.475797178275;5877.647341159544;1175.3241885915634;1185.9229150105216;1255.8681338459512;1265.639292801547;1273.7834568123255;735.312482828557;834.5910503853069;881.4270724029304;887.933404994628;904.3307952115388;5136.8202112925865;5142.897142057138;5184.714840683799;5187.948120518822;5191.22131680251;4943.184374578226;5031.371078615173;5115.368088657021;5311.468853114735;5649.711866693996;4347.891302717458;4695.3426520287185;4796.37647105881;4951.628627852925;5135.178371460548;2623.829451085673;2894.6526416830116;2925.1224632610133;2932.0794879354057;2958.896438624638;4961.603071754251;5072.771725272371;5617.21359009739;5714.914276285859;5771.066692657922;4942.443130413908;5428.619996600279;5660.349059858087;5709.7716091356715;5859.501942334864;4924.675843805417;5117.513836185556;5381.67709937405;5748.6550806952555;5755.769769209269;4665.930011049905;4971.428285747185;5068.389779263756;5300.3639563252655;5407.593216273723;5133;5464.207689885024;5510.724463776715;5515;5535.982889325247;4778;5145.485451454824;5727.7422515986045;5775.6823374714795;5788.913166302593;3495.877644282438;3738.6540845295126;3740.3454395481226;3751.7748935063983;3789.962100379071;4451.844185453494;4770.570648641639;5022.296878437095;5490.450954904478;5512.944870551243;5477.508443076798;5551.861203469784;5627.915581266202;5632.662040277596;5752.971235143965;5039.798408063783;5040.697558146523;5749.281339832521;5754.8849023018465;5821.970890145522;5310.707911064928;5610;5633.690147041788;5637.379888212211;5658.009848276617;5046.116929537243;5084.6440749147205;5716.342620598684;5763.51010164124;5863.912041319469;4977.875553111201;5653.180288858175;5708;5745;5761.884762304647 14:26:15 INFO - PROCESS | 1643 | |5;yui.html;677.8610384871101;678.3386198456506;752.7403045949153;766.3983772738397;782.8629989751794;14327.068740531871;14894.553163405088;14896.925515372408;14981.775273370908;14982.92508537457;13399.397027133778;13501.392437340342;13518.797218041713;13529.661758456019;13618.795718064212;641.387474961412;669.6049330894771;797.6011994002998;809.2352726673296;828.8176601147737;796.5738329993454;811.111832543367;811.4238890387832;811.736185739637;811.995940020302;872.707642939616;873.4148120759074;876.3427429427929;880.2429910277157;880.4453194487496;860.2945584620613;867.7917299848054;870.0820634230884;872.310874409216;872.4503562755424;639.1116348275854;643.4369926314475;644.2108417188953;644.5133923887482;645.0324513230155;13403.865961340456;13633.182009079388;13646.86353136466;13672.248026358546;14219;11026.779464410667;11041.39272340021;11058.11535077192;11063.668089957315;11350.886491135065;10973.725656858642;10977;11290.379029153393;11298.039666628347;11330.433478326051;3593.568771747381;3618.185908170667;3621.674049335546;3624.655657712513;3631.7457777955556;76998.99999999945;87646.12353876444;90306;91291.54354228187;91324.63013054743;10268.794624107626;10388.324758890649;10420;10435.269531132899;10494.298094710959;16781.58046048834;20111.994400279928;20352.18591256363;20484.487887802625;20560.897195513928;7502.2122677119205;7523.473356865076;7574.204708505637;7615.428842836809;7646.694132234759;4148.377743338523;4150.252954468165;4185.644220241253;4236.766977816188;4352.39066530686;2666.9333266668486;2728.4133911209187;2749.560070388728;2753.6695596528143;2754.848483333396;508.7252883442973;565.4995329133664;580.5819809736983;601.2184160591302;603.8279090459198;524.7586110389263;540.0656863625949;571.6855729348841;597.9282486101696;610.356074341565;8380.078191398941;8385.329173666092;8516.148385161434;8678.522681252593;8755.474671519729;531.1369025333832;560.5963706131579;589.4959809363019;599.4634801852318;633.7274971762095;3312.271300313927;3695.667389934919;3983.521977362735;3984.4620976168426;4041.8383264669674;105782;107528;110590;113525;113855;82645;84468;105525.47237263764;106295;107060.46469767601;75617;93914;95064.04935950552;95785.9999999986;100502.49748751355;7668.654910528928;7694.846103077795;7983.281504664608;8046.798830029062;8191.877121843297;45375.54624453713;56588.15117773319;57777;58108;60583.09125363211;69865;79322.20677793148;82379;88121.55939220263;88620.5568972151;11668.94165529167;11737.941310293736;11761.823572646246;11883.346415947206;12255.32595707209;13455.663608409868;13628.454861805416;13747.381367838272;13995.23026233567;14224.288785560888;12571.559995400117;12881.48474061027;12896.613101606967;12910.419031143809;13095.738085238052;12066.336351500751;12407.193532420364;12812.935935320264;12991.220526768424;13106.60680179596;11492.310461372344;11495.137864660017;11543.711407214887;11807.527698891949;12048.096392770402;10215.489225538842;10490.370577765358;10877.891221087688;11052.502637381176;11371.260868043551;10666.146708263164;11119.332840029625;11423.600173993871;11424.885751142383;11444.885551144382 14:26:15 INFO - PROCESS | 1643 | __end_tp_report 14:26:15 INFO - PROCESS | 1643 | __start_cc_report 14:26:15 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,368 14:26:15 INFO - PROCESS | 1643 | __end_cc_report 14:26:15 INFO - PROCESS | 1643 | __startTimestamp1480112775288__endTimestamp 14:26:15 INFO - PROCESS | 1643 | 14:26:15 INFO - PROCESS | 1643 | ------- Summary: start ------- 14:26:15 INFO - PROCESS | 1643 | Number of tests: 6 14:26:15 INFO - PROCESS | 1643 | 14:26:15 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5818.88 Median:6054.39 stddev:661.56 (10.9%) stddev-sans-first:636.24 14:26:15 INFO - PROCESS | 1643 | Values: 3419.4 3431.9 3454.7 3458.0 3467.7 5782.0 5895.9 5905.3 5922.3 5930.1 5889.8 5972.6 5975.3 5985.3 6005.1 5310.7 5395.1 5416.2 5428.3 5436.6 5371.4 5429.3 5443.4 5449.8 5451.9 5268.1 5303.9 5430.4 5435.7 5491.8 4693.0 4797.8 5308.8 5348.7 5388.1 5245.7 5381.4 5406.4 5408.5 5458.3 5950.2 5962.3 6003.3 6003.4 6028.6 6130.3 6189.9 6280.0 6359.3 6382.1 6230.1 6259.2 6315.5 6325.3 6341.0 6003.2 6020.2 6054.4 6061.5 6107.5 6265.0 6278.3 6284.2 6340.7 6397.8 6283.8 6295.3 6300.2 6301.4 6313.0 6347.3 6348.5 6352.6 6359.7 6368.6 5377.5 6166.4 6247.0 6291.1 6368.3 5233.5 6269.7 6315.9 6315.9 6333.2 4061.0 4449.6 5019.3 5023.0 5028.0 4449.6 4489.3 4489.8 4534.1 4542.5 5863.1 5872.7 5905.6 5924.4 5979.5 6283.6 6288.6 6294.1 6321.2 6339.5 5892.5 5913.6 6028.7 6028.7 6051.3 5394.4 5436.5 5482.0 5499.1 5503.5 5368.4 6428.0 6434.0 6450.0 6516.4 5150.4 6207.4 6243.5 6257.2 6306.7 5166.1 6380.6 6411.3 6465.1 6467.5 5584.9 6255.6 6295.2 6353.7 6354.4 6138.4 6171.3 6175.7 6178.0 6326.5 5735.9 6164.3 6214.1 6225.0 6277.4 6171.9 6244.1 6244.3 6271.2 6322.6 5929.2 6027.8 6078.3 6175.5 6208.3 5037.6 6122.6 6191.3 6219.8 6245.2 6112.6 6159.5 6160.4 6160.8 6183.5 14:26:15 INFO - PROCESS | 1643 | 14:26:15 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:77303.26 Median:38889.03 stddev:186396.87 (479.3%) stddev-sans-first:186830.50 14:26:15 INFO - PROCESS | 1643 | Values: 477.0 496.3 520.7 533.7 542.0 29634.9 38949.8 40069.4 40399.0 42356.0 30326.0 33083.7 40879.8 41215.0 41617.8 9273.2 9344.3 9357.4 9368.9 9371.9 9338.9 9350.5 9397.2 9424.0 9464.7 8229.0 9179.4 9373.6 9428.6 9442.4 7834.9 7908.2 8330.2 9008.9 9340.3 8163.7 9139.8 9177.9 9231.2 9236.7 38845.0 38933.0 38946.4 39122.6 39170.6 3084.9 3203.7 3411.1 3440.6 3626.6 28129.2 28435.4 30020.4 30536.5 30598.1 3137.4 3167.8 3179.9 3206.3 3222.7 494532.0 500635.0 501924.5 503151.0 504080.5 57254.7 57549.0 57566.7 57635.4 57799.7 59101.0 59780.0 59782.0 59879.0 59972.7 15767.4 15891.8 16048.8 16054.1 16119.9 12479.4 12527.9 12571.2 12598.6 12629.9 13656.6 15276.4 15694.9 15708.8 15786.8 3085.9 3194.8 5795.2 5965.7 5989.2 873.2 940.6 981.8 1037.2 1045.2 29647.0 29664.9 29704.3 29866.6 30212.7 855.9 911.9 940.0 1069.5 1097.2 14716.5 14979.6 15108.4 15357.7 15468.5 677519.0 736115.0 988776.0 993505.0 997019.0 56066.0 56543.4 56693.7 56803.0 56934.7 1326.2 1339.4 1345.1 1390.4 1391.2 26571.1 28674.7 28948.7 29543.7 29788.4 48030.8 48801.5 48807.0 48864.8 48902.8 544386.3 625344.0 642557.0 645480.0 646353.0 21761.6 22244.6 25601.6 26417.6 26444.5 38433.4 42839.8 44613.9 45519.3 45791.8 36945.1 36967.9 37139.3 37709.8 38581.8 37220.0 37508.0 37798.0 38233.6 38396.6 35980.6 36148.9 36741.7 36819.3 36915.8 31009.4 33724.7 33732.0 33781.5 33931.5 24650.9 24841.3 24881.6 24973.0 25266.0 14:26:15 INFO - PROCESS | 1643 | 14:26:15 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:33978.68 Median:2119.32 stddev:101763.07 (4801.7%) stddev-sans-first:102017.24 14:26:15 INFO - PROCESS | 1643 | Values: 366.4 381.4 382.4 388.2 407.1 15091.8 15441.4 17703.6 19863.8 20153.0 16172.7 16836.2 19421.5 19774.3 20083.1 5505.4 5758.3 5759.5 5781.5 5790.2 5328.3 5675.5 5725.8 5751.2 5759.9 5476.8 5670.7 5796.1 5843.6 5894.5 4697.8 5711.7 5769.6 5789.2 5805.2 5615.8 5678.1 5705.2 5707.3 5718.1 12829.6 13063.3 17474.5 23955.0 24108.0 2079.9 2099.6 2149.5 2280.4 2626.4 8290.8 9112.8 9421.3 10062.0 10109.5 1844.6 1874.7 1884.5 1885.7 1895.5 85804.0 103079.5 104938.0 109019.0 118048.8 11362.1 11434.9 11498.8 11573.1 11660.5 14639.3 14648.6 14651.6 14664.6 14701.3 6889.7 7238.9 8326.0 8598.2 8627.9 8477.2 8491.8 8523.8 8640.6 8641.0 3856.2 4495.0 4525.9 4530.2 4544.2 1898.8 1925.2 1932.4 1937.3 1939.3 777.5 894.1 907.9 920.8 934.9 14713.3 19636.2 20173.0 20321.9 20445.0 715.5 733.1 751.7 852.8 871.6 9371.4 10684.3 10728.7 10762.5 10771.9 532896.0 607914.0 633735.0 635520.0 646707.0 106082.0 113787.0 117064.0 125386.4 134579.0 913.8 929.6 938.5 981.4 1003.7 16465.3 17468.0 18600.8 18732.7 19014.5 30273.1 39595.6 52316.7 53248.7 55900.0 71812.3 78311.6 78741.6 79039.8 79526.6 6590.9 7511.8 7898.2 7957.4 8104.9 9904.0 11867.6 12027.8 12322.7 12339.1 10071.9 10257.7 10386.2 10709.6 10742.9 9195.9 10180.7 10409.3 10517.5 10681.3 10642.9 10850.9 10857.4 10957.0 10998.8 6900.8 7332.2 7345.9 8060.2 8076.2 9794.7 9880.3 10580.1 10817.7 10943.3 14:26:15 INFO - PROCESS | 1643 | 14:26:15 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5503.73 Median:5231.02 stddev:3010.58 (57.6%) stddev-sans-first:3003.99 14:26:15 INFO - PROCESS | 1643 | Values: 1500.0 1523.3 1714.9 1800.4 1829.5 6575.3 7642.0 8176.8 8643.3 8665.1 8198.8 8487.2 8510.4 8601.1 8610.6 2306.1 2334.1 2341.8 2346.3 2355.2 2355.3 2373.0 2384.0 2398.4 2400.0 2330.2 2337.1 2360.4 2365.8 2366.3 2316.2 2318.2 2338.3 2342.0 2355.2 2345.1 2347.3 2370.2 2371.3 2383.4 8459.2 8505.2 8516.2 8520.2 8527.8 5091.7 5270.0 5270.1 5270.7 5278.1 3482.5 3507.9 3534.4 3581.2 3624.8 1044.4 1064.2 1066.1 1111.5 1113.1 9437.8 10108.5 11054.6 11132.8 11193.9 3782.7 4465.4 4716.7 4751.3 4792.1 9586.3 9643.0 9648.7 9653.3 9660.3 5228.7 5233.3 5234.6 5241.5 5258.1 4248.2 4292.0 4760.5 4764.9 4797.5 3828.4 3854.9 3930.2 3943.3 3970.3 1267.9 1272.5 1278.3 1279.9 1283.1 751.6 862.3 904.3 907.4 916.8 5542.7 5583.6 5623.3 5627.2 5923.5 885.0 896.1 936.1 957.5 1056.2 3605.8 3761.4 3838.7 3864.7 3887.5 8054.4 10349.2 10479.3 10481.5 10487.0 10179.9 10340.7 10400.7 10459.0 10469.3 7914.5 8078.1 8110.6 8164.2 8226.8 5499.6 5540.2 5588.2 5649.5 5684.9 9522.9 9585.3 9653.7 9674.7 9724.1 8910.3 9664.7 9765.2 9922.0 9951.8 6721.5 6808.5 6821.2 6891.8 6914.7 7903.2 8006.7 8033.6 8037.2 8045.9 6092.7 7022.0 7268.9 7317.4 7332.8 7007.3 7027.4 7057.5 7062.2 7138.6 6692.3 6869.9 6870.2 6874.4 6876.8 4864.7 4901.3 4948.3 4977.9 5007.9 4805.3 4896.2 4897.2 4995.7 5064.8 14:26:15 INFO - PROCESS | 1643 | 14:26:15 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4491.64 Median:4974.65 stddev:1376.59 (27.7%) stddev-sans-first:1343.31 14:26:15 INFO - PROCESS | 1643 | Values: 259.5 268.9 291.7 292.3 298.3 4175.0 4961.8 5076.5 5507.0 5545.9 4748.6 4806.5 4835.4 5102.9 5413.8 4108.9 4128.3 4155.8 4159.0 4171.0 3401.4 3746.4 4023.3 4041.7 4173.3 4092.6 4104.8 4114.8 4115.0 4126.0 3579.5 3594.4 4093.1 4127.5 4135.0 3589.7 3605.6 3622.5 3627.7 3631.4 4106.8 4675.7 5230.9 5257.4 5272.6 3985.0 4177.2 4647.3 4733.4 4758.3 4721.0 5517.7 5718.3 5740.1 5749.2 3019.1 3383.5 3892.6 3930.4 3932.6 6008.6 6018.4 6042.4 6042.5 6069.0 3956.6 3959.3 3965.2 3975.9 4026.6 3762.7 3981.9 4039.6 4050.6 4058.2 4279.8 4680.6 4686.4 4690.0 4719.0 5475.0 5796.0 5811.0 5824.5 5877.6 1175.3 1185.9 1255.9 1265.6 1273.8 735.3 834.6 881.4 887.9 904.3 5136.8 5142.9 5184.7 5187.9 5191.2 4943.2 5031.4 5115.4 5311.5 5649.7 4347.9 4695.3 4796.4 4951.6 5135.2 2623.8 2894.7 2925.1 2932.1 2958.9 4961.6 5072.8 5617.2 5714.9 5771.1 4942.4 5428.6 5660.3 5709.8 5859.5 4924.7 5117.5 5381.7 5748.7 5755.8 4665.9 4971.4 5068.4 5300.4 5407.6 5133.0 5464.2 5510.7 5515.0 5536.0 4778.0 5145.5 5727.7 5775.7 5788.9 3495.9 3738.7 3740.3 3751.8 3790.0 4451.8 4770.6 5022.3 5490.5 5512.9 5477.5 5551.9 5627.9 5632.7 5753.0 5039.8 5040.7 5749.3 5754.9 5822.0 5310.7 5610.0 5633.7 5637.4 5658.0 5046.1 5084.6 5716.3 5763.5 5863.9 4977.9 5653.2 5708.0 5745.0 5761.9 14:26:15 INFO - PROCESS | 1643 | 14:26:15 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20794.32 Median:3839.59 stddev:31222.16 (813.2%) stddev-sans-first:31273.21 14:26:15 INFO - PROCESS | 1643 | Values: 677.9 678.3 752.7 766.4 782.9 14327.1 14894.6 14896.9 14981.8 14982.9 13399.4 13501.4 13518.8 13529.7 13618.8 641.4 669.6 797.6 809.2 828.8 796.6 811.1 811.4 811.7 812.0 872.7 873.4 876.3 880.2 880.4 860.3 867.8 870.1 872.3 872.5 639.1 643.4 644.2 644.5 645.0 13403.9 13633.2 13646.9 13672.2 14219.0 11026.8 11041.4 11058.1 11063.7 11350.9 10973.7 10977.0 11290.4 11298.0 11330.4 3593.6 3618.2 3621.7 3624.7 3631.7 76999.0 87646.1 90306.0 91291.5 91324.6 10268.8 10388.3 10420.0 10435.3 10494.3 16781.6 20112.0 20352.2 20484.5 20560.9 7502.2 7523.5 7574.2 7615.4 7646.7 4148.4 4150.3 4185.6 4236.8 4352.4 2666.9 2728.4 2749.6 2753.7 2754.8 508.7 565.5 580.6 601.2 603.8 524.8 540.1 571.7 597.9 610.4 8380.1 8385.3 8516.1 8678.5 8755.5 531.1 560.6 589.5 599.5 633.7 3312.3 3695.7 3983.5 3984.5 4041.8 105782.0 107528.0 110590.0 113525.0 113855.0 82645.0 84468.0 105525.5 106295.0 107060.5 75617.0 93914.0 95064.0 95786.0 100502.5 7668.7 7694.8 7983.3 8046.8 8191.9 45375.5 56588.2 57777.0 58108.0 60583.1 69865.0 79322.2 82379.0 88121.6 88620.6 11668.9 11737.9 11761.8 11883.3 12255.3 13455.7 13628.5 13747.4 13995.2 14224.3 12571.6 12881.5 12896.6 12910.4 13095.7 12066.3 12407.2 12812.9 12991.2 13106.6 11492.3 11495.1 11543.7 11807.5 12048.1 10215.5 10490.4 10877.9 11052.5 11371.3 10666.1 11119.3 11423.6 11424.9 11444.9 14:26:15 INFO - PROCESS | 1643 | -------- Summary: end -------- 14:26:15 INFO - PROCESS | 1643 | 14:26:15 INFO - TEST-INFO | 1643: exit 0 14:26:15 INFO - TEST-OK | dromaeo_css | took 1116790ms 14:26:15 INFO - TEST-START | kraken 14:26:15 INFO - operating with platform_type : mac_ 14:26:15 INFO - Initialising browser for kraken test... 14:26:15 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpYLS3MZ/profile http://localhost:49235/getInfo.html) 14:26:18 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 14:26:18 INFO - PROCESS | 1650 | colorDepth:24 14:26:18 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 14:26:18 INFO - PROCESS | 1650 | __metrics 14:26:18 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 14:26:19 INFO - TEST-INFO | 1650: exit 0 14:26:19 INFO - Browser initialized. 14:26:19 INFO - Running cycle 1/1 for kraken test... 14:26:19 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpYLS3MZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:26:31 INFO - PROCESS | 1652 | RSS: Main: 230137856 14:26:31 INFO - PROCESS | 1652 | 14:26:34 INFO - PROCESS | 1652 | 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:35 INFO - PROCESS | 1652 | RSS: Main: 242069504 14:26:35 INFO - PROCESS | 1652 | 14:26:38 INFO - PROCESS | 1652 | 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:39 INFO - PROCESS | 1652 | RSS: Main: 249589760 14:26:39 INFO - PROCESS | 1652 | 14:26:42 INFO - PROCESS | 1652 | 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:43 INFO - PROCESS | 1652 | RSS: Main: 251514880 14:26:43 INFO - PROCESS | 1652 | 14:26:45 INFO - PROCESS | 1652 | 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:45 INFO - PROCESS | 1652 | RSS: Main: 254136320 14:26:45 INFO - PROCESS | 1652 | 14:26:47 INFO - PROCESS | 1652 | 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:48 INFO - PROCESS | 1652 | RSS: Main: 254451712 14:26:48 INFO - PROCESS | 1652 | 14:26:51 INFO - PROCESS | 1652 | 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:52 INFO - PROCESS | 1652 | RSS: Main: 259051520 14:26:52 INFO - PROCESS | 1652 | 14:26:55 INFO - PROCESS | 1652 | 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:56 INFO - PROCESS | 1652 | RSS: Main: 258412544 14:26:56 INFO - PROCESS | 1652 | 14:26:59 INFO - PROCESS | 1652 | 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:26:59 INFO - PROCESS | 1652 | RSS: Main: 258277376 14:26:59 INFO - PROCESS | 1652 | 14:27:01 INFO - PROCESS | 1652 | 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:02 INFO - PROCESS | 1652 | RSS: Main: 257400832 14:27:02 INFO - PROCESS | 1652 | 14:27:04 INFO - PROCESS | 1652 | 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:04 INFO - PROCESS | 1652 | RSS: Main: 257527808 14:27:04 INFO - PROCESS | 1652 | 14:27:06 INFO - PROCESS | 1652 | 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:07 INFO - PROCESS | 1652 | RSS: Main: 257519616 14:27:07 INFO - PROCESS | 1652 | 14:27:10 INFO - PROCESS | 1652 | 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:11 INFO - PROCESS | 1652 | RSS: Main: 257814528 14:27:11 INFO - PROCESS | 1652 | 14:27:14 INFO - PROCESS | 1652 | 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:14 INFO - PROCESS | 1652 | RSS: Main: 257630208 14:27:14 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | 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:16 INFO - PROCESS | 1652 | RSS: Main: 259452928 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | __start_tp_report 14:27:16 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 14:27:16 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 14:27:16 INFO - PROCESS | 1652 | |i|pagename|runs| 14:27:16 INFO - PROCESS | 1652 | |0;ai-astar;105;144;178;117;115;118;116;117;115;118 14:27:16 INFO - PROCESS | 1652 | |1;audio-beat-detection;146;123;146;141;145;146;143;121;141;114 14:27:16 INFO - PROCESS | 1652 | |2;audio-dft;198;178;206;241;278;198;275;289;191;274 14:27:16 INFO - PROCESS | 1652 | |3;audio-fft;82;62;60;81;63;83;81;72;67;79 14:27:16 INFO - PROCESS | 1652 | |4;audio-oscillator;81;70;89;71;69;58;110;63;80;72 14:27:16 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;89;133;124;114;130;113;137;97;139;133 14:27:16 INFO - PROCESS | 1652 | |6;imaging-darkroom;117;99;155;95;95;95;98;95;153;96 14:27:16 INFO - PROCESS | 1652 | |7;imaging-desaturate;138;133;87;89;131;139;128;92;104;137 14:27:16 INFO - PROCESS | 1652 | |8;json-parse-financial;55;57;55;59;64;59;57;52;51;91 14:27:16 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;68;69;75;79;93;104;107;107;72;70 14:27:16 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;86;84;87;84;85;87;98;78;104;81 14:27:16 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;147;171;166;166;147;152;150;167;192;172 14:27:16 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;170;168;171;177;181;168;176;169;169;200 14:27:16 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;67;68;67;66;71;68;82;83;68;64 14:27:16 INFO - PROCESS | 1652 | __end_tp_report 14:27:16 INFO - PROCESS | 1652 | __start_cc_report 14:27:16 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,967 14:27:16 INFO - PROCESS | 1652 | __end_cc_report 14:27:16 INFO - PROCESS | 1652 | __startTimestamp1480112836943__endTimestamp 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | ------- Summary: start ------- 14:27:16 INFO - PROCESS | 1652 | Number of tests: 14 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:124.30 Median:117.50 stddev:21.27 (18.1%) stddev-sans-first:21.38 14:27:16 INFO - PROCESS | 1652 | Values: 105.0 144.0 178.0 117.0 115.0 118.0 116.0 117.0 115.0 118.0 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:136.60 Median:144.00 stddev:12.27 (8.5%) stddev-sans-first:12.53 14:27:16 INFO - PROCESS | 1652 | Values: 146.0 123.0 146.0 141.0 145.0 146.0 143.0 121.0 141.0 114.0 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:232.80 Median:257.50 stddev:43.00 (16.7%) stddev-sans-first:43.72 14:27:16 INFO - PROCESS | 1652 | Values: 198.0 178.0 206.0 241.0 278.0 198.0 275.0 289.0 191.0 274.0 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:73.00 Median:80.00 stddev:9.26 (11.6%) stddev-sans-first:9.23 14:27:16 INFO - PROCESS | 1652 | Values: 82.0 62.0 60.0 81.0 63.0 83.0 81.0 72.0 67.0 79.0 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:71.50 stddev:14.85 (20.8%) stddev-sans-first:15.65 14:27:16 INFO - PROCESS | 1652 | Values: 81.0 70.0 89.0 71.0 69.0 58.0 110.0 63.0 80.0 72.0 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:120.90 Median:135.00 stddev:17.23 (12.8%) stddev-sans-first:13.88 14:27:16 INFO - PROCESS | 1652 | Values: 89.0 133.0 124.0 114.0 130.0 113.0 137.0 97.0 139.0 133.0 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:109.80 Median:95.00 stddev:24.23 (25.5%) stddev-sans-first:25.56 14:27:16 INFO - PROCESS | 1652 | Values: 117.0 99.0 155.0 95.0 95.0 95.0 98.0 95.0 153.0 96.0 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:117.80 Median:138.50 stddev:22.03 (15.9%) stddev-sans-first:22.13 14:27:16 INFO - PROCESS | 1652 | Values: 138.0 133.0 87.0 89.0 131.0 139.0 128.0 92.0 104.0 137.0 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:60.00 Median:58.00 stddev:11.51 (19.8%) stddev-sans-first:12.06 14:27:16 INFO - PROCESS | 1652 | Values: 55.0 57.0 55.0 59.0 64.0 59.0 57.0 52.0 51.0 91.0 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:84.40 Median:71.00 stddev:16.55 (23.3%) stddev-sans-first:16.45 14:27:16 INFO - PROCESS | 1652 | Values: 68.0 69.0 75.0 79.0 93.0 104.0 107.0 107.0 72.0 70.0 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:85.50 stddev:7.81 (9.1%) stddev-sans-first:8.26 14:27:16 INFO - PROCESS | 1652 | Values: 86.0 84.0 87.0 84.0 85.0 87.0 98.0 78.0 104.0 81.0 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:163.00 Median:166.50 stddev:14.23 (8.5%) stddev-sans-first:13.86 14:27:16 INFO - PROCESS | 1652 | Values: 147.0 171.0 166.0 166.0 147.0 152.0 150.0 167.0 192.0 172.0 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.90 Median:173.50 stddev:9.87 (5.7%) stddev-sans-first:10.31 14:27:16 INFO - PROCESS | 1652 | Values: 170.0 168.0 171.0 177.0 181.0 168.0 176.0 169.0 169.0 200.0 14:27:16 INFO - PROCESS | 1652 | 14:27:16 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:68.00 stddev:6.62 (9.7%) stddev-sans-first:6.91 14:27:16 INFO - PROCESS | 1652 | Values: 67.0 68.0 67.0 66.0 71.0 68.0 82.0 83.0 68.0 64.0 14:27:16 INFO - PROCESS | 1652 | -------- Summary: end -------- 14:27:16 INFO - PROCESS | 1652 | 14:27:17 INFO - TEST-INFO | 1652: exit 0 14:27:17 INFO - TEST-OK | kraken | took 62070ms 14:27:17 INFO - SUITE-END | took 1178s 14:27:18 INFO - Completed test suite (00:19:38) 14:27:18 INFO - javascript benchmark 14:27:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7099.5569059229165, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3419.3503234385466, 3431.9485207721887, 3454.7236221102316, 3458.0, 3467.739919506037, 5782.045962416201, 5895.88208235835, 5905.2618422697105, 5922.3485416604135, 5930.140129681205, 5889.793857214999, 5972.552058595601, 5975.312839023502, 5985.34161242263, 6005.099235114735, 5310.681359118446, 5395.136778115502, 5416.245751451841, 5428.321459817523, 5436.6194366394175, 5371.382291036542, 5429.294191755086, 5443.401225865152, 5449.782008719647, 5451.918221226685, 5268.104422248208, 5303.920441193385, 5430.402655707889, 5435.701036442994, 5491.835244942638, 4692.953070469286, 4797.8320758773625, 5308.787648494055, 5348.732563371816, 5388.084025715638, 5245.737713114329, 5381.408045115034, 5406.351237851483, 5408.486193811582, 5458.2904222451225, 5950.226470558843, 5962.284525856925, 6003.309619393782, 6003.369646187131, 6028.638281703112, 6130.306415576139, 6189.87620247597, 6280.0, 6359.268684101295, 6382.138411314506, 6230.127782110508, 6259.155014073133, 6315.526335524854, 6325.272593651743, 6341.04884267359, 6003.159557661887, 6020.217371741645, 6054.3945605439985, 6061.515078793774, 6107.541934354852, 6264.968675156595, 6278.277998030194, 6284.183056202664, 6340.714667839959, 6397.840053998687, 6283.842903927347, 6295.307516173218, 6300.21247344082, 6301.369863013753, 6312.968435157794, 6347.333529979378, 6348.523860710465, 6352.587081839665, 6359.745610175633, 6368.617882927039, 5377.516023557899, 6166.414190651881, 6247.00047992319, 6291.1192433059405, 6368.331325210879, 5233.528982391527, 6269.686515674198, 6315.873682526324, 6315.905261421082, 6333.1766870306565, 4061.045654271244, 4449.555044495524, 5019.272205530251, 5022.9748851256245, 5027.974860125676, 4449.57729015743, 4489.281714925596, 4489.7979590918485, 4534.070515544306, 4542.500324964251, 5863.120531920161, 5872.677002764889, 5905.6161349513, 5924.407559244128, 5979.4917432018765, 6283.560150789493, 6288.591241569284, 6294.118823364733, 6321.241451025814, 6339.4611458025665, 5892.499137573269, 5913.58604897653, 6028.668423236764, 6028.728707208186, 6051.273847138372, 5394.352677678625, 5436.510714035756, 5482.013237617267, 5499.065158922993, 5503.532199762985, 5368.355797304349, 6428.0357946307495, 6434.0, 6449.967750161313, 6516.446102081376, 5150.433452320167, 6207.379262073757, 6243.500519958483, 6257.217847769029, 6306.684665766785, 5166.121759301003, 6380.553361264667, 6411.294757576756, 6465.127207826883, 6467.450266727381, 5584.888302233851, 6255.593386429868, 6295.213098362705, 6353.650549219652, 6354.364563543609, 6138.355472675305, 6171.259448866164, 6175.6912154393, 6178.0, 6326.4622507087415, 5735.8566035849435, 6164.260288765377, 6214.067889816563, 6225.0, 6277.403646653561, 6171.907421388593, 6244.126761971481, 6244.344343844013, 6271.24745030581, 6322.620642761265, 5929.1995580597795, 6027.758889644365, 6078.270607527125, 6175.536834737323, 6208.2860471044605, 5037.571806396498, 6122.5811271886105, 6191.288001879841, 6219.782307619212, 6245.219347581552, 6112.633242005494, 6159.5072394209255, 6160.44555989963, 6160.784372546939, 6183.505319574333], "value": 5778.841852037512, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.96498598042245, 496.2531390257663, 520.6927912531605, 533.6637918111592, 541.9891901603505, 29634.85182574087, 38949.80525097374, 40069.39895901557, 40398.99002524938, 42356.0, 30326.0, 33083.66916330836, 40879.77360679177, 41214.96962575937, 41617.79191104048, 9273.211776998964, 9344.299177561676, 9357.391769534992, 9368.90631093687, 9371.906280937172, 9338.859917101216, 9350.485723285217, 9397.248220142406, 9424.0, 9464.716058518221, 8229.012518497788, 9179.357444978854, 9373.578188981512, 9428.575714092847, 9442.433453992748, 7834.863944728011, 7908.248716371935, 8330.166983301622, 9008.864867027, 9340.252779777602, 8163.714270000581, 9139.771505712344, 9177.908220917772, 9231.169194772503, 9236.72289831306, 38845.02887427808, 38933.02667433309, 38946.41580376297, 39122.60877391246, 39170.60829391698, 3084.8766049358, 3203.7224958542774, 3411.0790086676707, 3440.6215316315183, 3626.564812222524, 28129.156125316273, 28435.431291374058, 30020.399592008256, 30536.54195187074, 30598.082057538308, 3137.4038932602734, 3167.7624178186497, 3179.871145743274, 3206.3106432116974, 3222.742180625568, 494532.0, 500635.0, 501924.4518887057, 503151.0, 504080.4795976033, 57254.7137264311, 57549.0, 57566.7121664389, 57635.42364576385, 57799.711001444724, 59101.0, 59780.0, 59782.0, 59879.0, 59972.700136499036, 15767.369305227663, 15891.761623575656, 16048.75926861098, 16054.117023563816, 16119.919400403158, 12479.376031198404, 12527.874721252852, 12571.24572525633, 12598.622041338775, 12629.936850315873, 13656.590302290948, 15276.388944442098, 15694.921525392529, 15708.842911570966, 15786.842131578536, 3085.9382812344074, 3194.8242846643193, 5795.246617939641, 5965.717370765306, 5989.221401217814, 873.1792115411492, 940.5532372123187, 981.8036392721484, 1037.201354956679, 1045.1817123062674, 29647.0, 29664.851675741484, 29704.257393565334, 29866.552001719992, 30212.69787302099, 855.8630619100913, 911.8905731312152, 940.0317672796893, 1069.4813015687384, 1097.2483848563759, 14716.484923027643, 14979.625509362353, 15108.39566417353, 15357.692846143014, 15468.458603948808, 677519.0, 736115.0, 988776.0, 993505.0, 997019.0, 56066.0, 56543.43456565464, 56693.71653141708, 56803.0, 56934.7153264231, 1326.2307861440343, 1339.437436276766, 1345.085341967452, 1390.3743315508061, 1391.1931079973792, 26571.07001255024, 28674.713252867205, 28948.710512894602, 29543.704562954954, 29788.404231915672, 48030.75984620055, 48801.5119848797, 48807.0, 48864.75567622139, 48902.75548622234, 544386.2780686229, 625344.0, 642557.0, 645480.0, 646353.0, 21761.564768704222, 22244.555108898054, 25601.61597576075, 26417.60373594436, 26444.471110577295, 38433.4234986481, 42839.785801072045, 44613.884652883935, 45519.31721024121, 45791.77104114458, 36945.07637309089, 36967.890963271144, 37139.257214856094, 37709.81145094257, 38581.807090964365, 37220.0, 37508.0, 37798.0, 38233.617663823, 38396.61603384042, 35980.640193598774, 36148.91553253407, 36741.71404000847, 36819.26361472702, 36915.81542092272, 31009.379812404077, 33724.662753372155, 33732.0, 33781.49327760036, 33931.47308371179, 24650.890709917738, 24841.25476235716, 24881.62677559802, 24973.0, 25266.0], "value": 19210.88907213096, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.3552148219134, 381.36693089471464, 382.41681435810375, 388.1983703651957, 407.1389012239115, 15091.773623395631, 15441.382344706226, 17703.645927081452, 19863.801361986374, 20153.0, 16172.676546469063, 16836.158192090377, 19421.514462138453, 19774.30789922353, 20083.09626066827, 5505.366882808488, 5758.251427314443, 5759.4816466518005, 5781.537477001851, 5790.160426738121, 5328.280682107924, 5675.489205971462, 5725.770969161229, 5751.194832723401, 5759.856003599923, 5476.780928762844, 5670.659760414368, 5796.07262837946, 5843.561732870031, 5894.528437724972, 4697.765111744399, 5711.657300561958, 5769.5672824538115, 5789.218455508516, 5805.158252053471, 5615.775368985236, 5678.063119585264, 5705.172749951275, 5707.343655479632, 5718.1136923776885, 12829.61511154667, 13063.34683265833, 17474.47576572705, 23955.0, 24108.0, 2079.916803327865, 2099.6220680277543, 2149.473379022134, 2280.361498780344, 2626.369671278879, 8290.751277461686, 9112.772180695469, 9421.340506164572, 10062.044105809937, 10109.49452527378, 1844.6495165918432, 1874.709420039896, 1884.5288677830542, 1885.679434496139, 1895.4882181810833, 85804.0, 103079.4846025765, 104938.0, 109019.0, 118048.8195118055, 11362.147838912115, 11434.942825285987, 11498.827517587242, 11573.074154067654, 11660.475278612314, 14639.268036598127, 14648.633784155481, 14651.633709157357, 14664.560063198121, 14701.264936753334, 6889.724411023603, 7238.92761072393, 8326.0, 8598.183172598594, 8627.91372086271, 8477.152284771473, 8491.83016339682, 8523.786905327417, 8640.611172497252, 8641.006284277388, 3856.2287542491613, 4494.977525112419, 4525.886852828706, 4530.22986092365, 4544.227481328182, 1898.8480921526245, 1925.1721759643208, 1932.4106147624827, 1937.321937321926, 1939.2533874458154, 777.5334799120595, 894.1326912894482, 907.9364444488822, 920.7513971227731, 934.8550974598948, 14713.337899794535, 19636.21455141807, 20173.0, 20321.89839050795, 20445.0, 715.4741265170096, 733.1129333506517, 751.691806359401, 852.820907609409, 871.6121326009865, 9371.390859594241, 10684.25210235276, 10728.73178170552, 10762.461876906124, 10771.946140269249, 532896.0, 607914.0, 633735.0, 635520.0, 646707.0, 106082.0, 113787.0, 117064.0, 125386.37306813408, 134579.0, 913.7669894177076, 929.6049179098856, 938.5213541094091, 981.425865868475, 1003.7340104872214, 16465.25906334242, 17468.03925784094, 18600.813991859908, 18732.7190092146, 19014.524636884187, 30273.09180724582, 39595.60404395919, 52316.73841630767, 53248.73375633097, 55900.0, 71812.28187718056, 78311.60844195743, 78741.60629196817, 79039.81440278285, 79526.6260355686, 6590.901136483052, 7511.812204694927, 7898.170692077479, 7957.442978991415, 8104.878426823484, 9903.95048024784, 11867.643970680894, 12027.75944481088, 12322.691932701755, 12339.13626046204, 10071.949640251752, 10257.692269231935, 10386.169106471312, 10709.57161713554, 10742.946285268523, 9195.908040919772, 10180.694579162637, 10409.271351005356, 10517.474126293808, 10681.305715128803, 10642.89357106419, 10850.945745271223, 10857.402842843403, 10956.959088886542, 10998.790133085195, 6900.7929762107215, 7332.193458719643, 7345.853082938418, 8060.15368386311, 8076.152004039726, 9794.65718699842, 9880.308378413441, 10580.100691441314, 10817.729556761144, 10943.288686235688], "value": 9047.9945350481, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1500.0399744163733, 1523.314508471188, 1714.8542373898217, 1800.3698705453085, 1829.4603092087832, 6575.276719560845, 7641.961790191055, 8176.836463270738, 8643.308535317175, 8665.090165532623, 8198.754037378872, 8487.193716596928, 8510.361722870779, 8601.096884827097, 8610.61252243649, 2306.0890948075544, 2334.078039174525, 2341.7892389684926, 2346.2843832631042, 2355.1639168095335, 2355.316958082154, 2373.0151986925493, 2383.9642405363934, 2398.388410955204, 2400.0, 2330.2426711318803, 2337.1352599538195, 2360.3862995621175, 2365.751596082404, 2366.290112966112, 2316.1777568962943, 2318.211807985285, 2338.286822519131, 2342.0163531316803, 2355.187460326193, 2345.0854166874933, 2347.272345572861, 2370.2415227127326, 2371.34787933318, 2383.368407372048, 8459.238668519802, 8505.23452889243, 8516.23353898146, 8520.233179013856, 8527.786805329853, 5091.694498330112, 5270.0, 5270.130428479296, 5270.710110943896, 5278.102722537178, 3482.4776283557417, 3507.859685612598, 3534.3638145133864, 3581.176329444216, 3624.818759062063, 1044.3785947361307, 1064.2177999170603, 1066.1044722433116, 1111.4831603304501, 1113.0705860606404, 9437.811243775086, 10108.545115469822, 11054.613088541862, 11132.777344453065, 11193.888061119365, 3782.659560639556, 4465.419495465569, 4716.693414928087, 4751.311059896296, 4792.0895029944195, 9586.28102892272, 9643.0, 9648.71053868385, 9653.276004299565, 9660.32377733566, 5228.712420816892, 5233.319668443078, 5234.607404444682, 5241.528262456321, 5258.053550360895, 4248.235317642792, 4291.957080429156, 4760.500147484534, 4764.904701906012, 4797.520247975244, 3828.3874580067054, 3854.903627409281, 3930.1746633206776, 3943.2507823513442, 3970.305196590585, 1267.88589026988, 1272.5482453728978, 1278.3033246880473, 1279.923204607708, 1283.0569531394417, 751.6392131776778, 862.2972277593741, 904.3488688144525, 907.3603109807569, 916.812053529025, 5542.695151766691, 5583.581231407661, 5623.297087864017, 5627.155926611041, 5923.526117910556, 884.951327676984, 896.068089187238, 936.1153709744226, 957.4781743949565, 1056.2131212246925, 3605.801680907523, 3761.4169803680475, 3838.654521093115, 3864.6908247340143, 3887.4946256986977, 8054.355651547862, 10349.223808214414, 10479.266451348485, 10481.475926203811, 10487.0, 10179.898201018043, 10340.741481462874, 10400.739981500372, 10459.00639439252, 10469.267151299337, 7914.485558438683, 8078.111407745143, 8110.63502142405, 8164.183581641907, 8226.794330141794, 5499.560035197254, 5540.224368588481, 5588.189712491585, 5649.4633009864, 5684.943150568607, 9522.904770952478, 9585.329026968046, 9653.662121825702, 9674.70975870725, 9724.076212759777, 8910.331725120512, 9664.71005869825, 9765.218782497524, 9922.00779922031, 9951.751206219902, 6721.462283017249, 6808.523403361717, 6821.2155602106395, 6891.75878844238, 6914.723411063501, 7903.249191326815, 8006.679732810622, 8033.638486268015, 8037.156098609562, 8045.879311810445, 6092.664903430176, 7022.0, 7268.909663550509, 7317.378022868116, 7332.78001659951, 7007.299270073156, 7027.4378049756915, 7057.505974581935, 7062.1878483975, 7138.607376594129, 6692.297308782508, 6869.931300686929, 6870.20992585859, 6874.415674667509, 6876.828079298058, 4864.652805021527, 4901.264810278344, 4948.257761335828, 4977.875553111201, 5007.899842003212, 4805.255185446262, 4896.167651499182, 4897.191963326011, 4995.675281106716, 5064.797408103635], "value": 4472.937463556284, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [259.5483858086928, 268.9273896048067, 291.7126630269185, 292.3436884194983, 298.30941370726765, 4174.958250417495, 4961.801527938887, 5076.451682236916, 5507.0, 5545.9445405545985, 4748.5726284634375, 4806.5193480651915, 4835.371401717772, 5102.948970510294, 5413.810516631918, 4108.938365924506, 4128.339465685491, 4155.750654960697, 4158.979205103986, 4170.978110362966, 3401.4047541680106, 3746.4452252315255, 4023.295923213426, 4041.717079804415, 4173.3114036184, 4092.631663150316, 4104.774237416941, 4114.835406583748, 4114.958850411503, 4125.979370103145, 3579.480975258586, 3594.370985077627, 4093.079057212118, 4127.484064491939, 4135.0, 3589.6589823966683, 3605.5673319201865, 3622.474741162543, 3627.6553727396117, 3631.365885351576, 4106.815193316308, 4675.667434781105, 5230.895382092337, 5257.395399529065, 5272.630915835893, 3984.980075099635, 4177.22721296558, 4647.256438969783, 4733.408323959505, 4758.286257061469, 4721.0, 5517.675757818135, 5718.313802343745, 5740.138979153118, 5749.195112684228, 3019.0791808498398, 3383.4924761285756, 3892.630200130983, 3930.371140617516, 3932.6264004919485, 6008.639481631116, 6018.3981601839905, 6042.365551617105, 6042.456178943872, 6068.969655151696, 3956.643902048829, 3959.2675355059405, 3965.2466031453932, 3975.940360894589, 4026.617471340218, 3762.6989840813217, 3981.9402708959387, 4039.6364327210104, 4050.6151915567975, 4058.2492238935893, 4279.753129606981, 4680.602148817321, 4686.390769199982, 4689.976550117228, 4718.976405117953, 5475.028989563754, 5795.971020144957, 5811.0121279381765, 5824.475797178275, 5877.647341159544, 1175.3241885915634, 1185.9229150105216, 1255.8681338459512, 1265.639292801547, 1273.7834568123255, 735.312482828557, 834.5910503853069, 881.4270724029304, 887.933404994628, 904.3307952115388, 5136.8202112925865, 5142.897142057138, 5184.714840683799, 5187.948120518822, 5191.22131680251, 4943.184374578226, 5031.371078615173, 5115.368088657021, 5311.468853114735, 5649.711866693996, 4347.891302717458, 4695.3426520287185, 4796.37647105881, 4951.628627852925, 5135.178371460548, 2623.829451085673, 2894.6526416830116, 2925.1224632610133, 2932.0794879354057, 2958.896438624638, 4961.603071754251, 5072.771725272371, 5617.21359009739, 5714.914276285859, 5771.066692657922, 4942.443130413908, 5428.619996600279, 5660.349059858087, 5709.7716091356715, 5859.501942334864, 4924.675843805417, 5117.513836185556, 5381.67709937405, 5748.6550806952555, 5755.769769209269, 4665.930011049905, 4971.428285747185, 5068.389779263756, 5300.3639563252655, 5407.593216273723, 5133.0, 5464.207689885024, 5510.724463776715, 5515.0, 5535.982889325247, 4778.0, 5145.485451454824, 5727.7422515986045, 5775.6823374714795, 5788.913166302593, 3495.877644282438, 3738.6540845295126, 3740.3454395481226, 3751.7748935063983, 3789.962100379071, 4451.844185453494, 4770.570648641639, 5022.296878437095, 5490.450954904478, 5512.944870551243, 5477.508443076798, 5551.861203469784, 5627.915581266202, 5632.662040277596, 5752.971235143965, 5039.798408063783, 5040.697558146523, 5749.281339832521, 5754.8849023018465, 5821.970890145522, 5310.707911064928, 5610.0, 5633.690147041788, 5637.379888212211, 5658.009848276617, 5046.116929537243, 5084.6440749147205, 5716.342620598684, 5763.51010164124, 5863.912041319469, 4977.875553111201, 5653.180288858175, 5708.0, 5745.0, 5761.884762304647], "value": 4039.895746907843, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.8610384871101, 678.3386198456506, 752.7403045949153, 766.3983772738397, 782.8629989751794, 14327.068740531871, 14894.553163405088, 14896.925515372408, 14981.775273370908, 14982.92508537457, 13399.397027133778, 13501.392437340342, 13518.797218041713, 13529.661758456019, 13618.795718064212, 641.387474961412, 669.6049330894771, 797.6011994002998, 809.2352726673296, 828.8176601147737, 796.5738329993454, 811.111832543367, 811.4238890387832, 811.736185739637, 811.995940020302, 872.707642939616, 873.4148120759074, 876.3427429427929, 880.2429910277157, 880.4453194487496, 860.2945584620613, 867.7917299848054, 870.0820634230884, 872.310874409216, 872.4503562755424, 639.1116348275854, 643.4369926314475, 644.2108417188953, 644.5133923887482, 645.0324513230155, 13403.865961340456, 13633.182009079388, 13646.86353136466, 13672.248026358546, 14219.0, 11026.779464410667, 11041.39272340021, 11058.11535077192, 11063.668089957315, 11350.886491135065, 10973.725656858642, 10977.0, 11290.379029153393, 11298.039666628347, 11330.433478326051, 3593.568771747381, 3618.185908170667, 3621.674049335546, 3624.655657712513, 3631.7457777955556, 76998.99999999945, 87646.12353876444, 90306.0, 91291.54354228187, 91324.63013054743, 10268.794624107626, 10388.324758890649, 10420.0, 10435.269531132899, 10494.298094710959, 16781.58046048834, 20111.994400279928, 20352.18591256363, 20484.487887802625, 20560.897195513928, 7502.2122677119205, 7523.473356865076, 7574.204708505637, 7615.428842836809, 7646.694132234759, 4148.377743338523, 4150.252954468165, 4185.644220241253, 4236.766977816188, 4352.39066530686, 2666.9333266668486, 2728.4133911209187, 2749.560070388728, 2753.6695596528143, 2754.848483333396, 508.7252883442973, 565.4995329133664, 580.5819809736983, 601.2184160591302, 603.8279090459198, 524.7586110389263, 540.0656863625949, 571.6855729348841, 597.9282486101696, 610.356074341565, 8380.078191398941, 8385.329173666092, 8516.148385161434, 8678.522681252593, 8755.474671519729, 531.1369025333832, 560.5963706131579, 589.4959809363019, 599.4634801852318, 633.7274971762095, 3312.271300313927, 3695.667389934919, 3983.521977362735, 3984.4620976168426, 4041.8383264669674, 105782.0, 107528.0, 110590.0, 113525.0, 113855.0, 82645.0, 84468.0, 105525.47237263764, 106295.0, 107060.46469767601, 75617.0, 93914.0, 95064.04935950552, 95785.9999999986, 100502.49748751355, 7668.654910528928, 7694.846103077795, 7983.281504664608, 8046.798830029062, 8191.877121843297, 45375.54624453713, 56588.15117773319, 57777.0, 58108.0, 60583.09125363211, 69865.0, 79322.20677793148, 82379.0, 88121.55939220263, 88620.5568972151, 11668.94165529167, 11737.941310293736, 11761.823572646246, 11883.346415947206, 12255.32595707209, 13455.663608409868, 13628.454861805416, 13747.381367838272, 13995.23026233567, 14224.288785560888, 12571.559995400117, 12881.48474061027, 12896.613101606967, 12910.419031143809, 13095.738085238052, 12066.336351500751, 12407.193532420364, 12812.935935320264, 12991.220526768424, 13106.60680179596, 11492.310461372344, 11495.137864660017, 11543.711407214887, 11807.527698891949, 12048.096392770402, 10215.489225538842, 10490.370577765358, 10877.891221087688, 11052.502637381176, 11371.260868043551, 10666.146708263164, 11119.332840029625, 11423.600173993871, 11424.885751142383, 11444.885551144382], "value": 7054.057651458556, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1631.6000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 144.0, 178.0, 117.0, 115.0, 118.0, 116.0, 117.0, 115.0, 118.0], "value": 124.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 123.0, 146.0, 141.0, 145.0, 146.0, 143.0, 121.0, 141.0, 114.0], "value": 136.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 178.0, 206.0, 241.0, 278.0, 198.0, 275.0, 289.0, 191.0, 274.0], "value": 232.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 60.0, 81.0, 63.0, 83.0, 81.0, 72.0, 67.0, 79.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 70.0, 89.0, 71.0, 69.0, 58.0, 110.0, 63.0, 80.0, 72.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 133.0, 124.0, 114.0, 130.0, 113.0, 137.0, 97.0, 139.0, 133.0], "value": 120.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 99.0, 155.0, 95.0, 95.0, 95.0, 98.0, 95.0, 153.0, 96.0], "value": 109.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 133.0, 87.0, 89.0, 131.0, 139.0, 128.0, 92.0, 104.0, 137.0], "value": 117.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 55.0, 59.0, 64.0, 59.0, 57.0, 52.0, 51.0, 91.0], "value": 60.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 75.0, 79.0, 93.0, 104.0, 107.0, 107.0, 72.0, 70.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 84.0, 85.0, 87.0, 98.0, 78.0, 104.0, 81.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 171.0, 166.0, 166.0, 147.0, 152.0, 150.0, 167.0, 192.0, 172.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 171.0, 177.0, 181.0, 168.0, 176.0, 169.0, 169.0, 200.0], "value": 174.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 66.0, 71.0, 68.0, 82.0, 83.0, 68.0, 64.0], "value": 70.4, "unit": "score"}]}]} 14:27:18 INFO - Return code: 0 14:27:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:27:18 ERROR - # TBPL SUCCESS # 14:27:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:27:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:27:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:27:18 INFO - 2016-11-25 14:27:18.246 screenresolution[1657:11372] starting screenresolution argv=screenresolution get 14:27:18 INFO - 2016-11-25 14:27:18.248 screenresolution[1657:11372] Display 0: 1600x1200x32@60 14:27:18 INFO - 2016-11-25 14:27:18.254 screenresolution[1658:11375] starting screenresolution argv=screenresolution list 14:27:18 INFO - Available Modes on Display 0 14:27:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:27:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:27:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:27:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:27:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:27:18 INFO - Intel Iris: 14:27:18 INFO - Chipset Model: Intel Iris 14:27:18 INFO - Type: GPU 14:27:18 INFO - Bus: Built-In 14:27:18 INFO - VRAM (Dynamic, Max): 1536 MB 14:27:18 INFO - Vendor: Intel (0x8086) 14:27:18 INFO - Device ID: 0x0a2e 14:27:18 INFO - Revision ID: 0x0009 14:27:18 INFO - Displays: 14:27:18 INFO - Display: 14:27:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:27:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:27:18 INFO - Display Serial Number: 1600x1200 60 14:27:18 INFO - Main Display: Yes 14:27:18 INFO - Mirror: Off 14:27:18 INFO - Online: Yes 14:27:18 INFO - Rotation: Supported 14:27:18 INFO - Return code: 0 14:27:18 INFO - Running post-action listener: _resource_record_post_action 14:27:18 INFO - [mozharness: 2016-11-25 22:27:18.498140Z] Finished run-tests step (success) 14:27:18 INFO - Running post-run listener: _resource_record_post_run 14:27:18 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 33188864; Write bytes: 662751744; Read time: 304; Write time: 2144 14:27:18 INFO - TinderboxPrint: CPU usage
54.4% 14:27:18 INFO - TinderboxPrint: I/O read bytes / time
33,188,864 / 304 14:27:18 INFO - TinderboxPrint: I/O write bytes / time
662,751,744 / 2,144 14:27:18 INFO - TinderboxPrint: CPU idle
2,205.4 (45.5%) 14:27:18 INFO - TinderboxPrint: CPU system
981.7 (20.3%) 14:27:18 INFO - TinderboxPrint: CPU user
1,654.7 (34.2%) 14:27:18 INFO - TinderboxPrint: Swap in / out
377,335,808 / 0 14:27:18 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349385216; Write bytes: 351184384; Read time: 26904; Write time: 960 14:27:18 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29051904; Write bytes: 310113280; Read time: 273; Write time: 1178 14:27:18 INFO - Running post-run listener: _upload_blobber_files 14:27:18 INFO - Blob upload gear active. 14:27:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:27:18 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:18 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:18 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:19 INFO - (blobuploader) - INFO - Open directory for files ... 14:27:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:27:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:27:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:20 INFO - (blobuploader) - INFO - Done attempting. 14:27:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.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-e10s_raw.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/resource-usage.json ... 14:27:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:27:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:24 INFO - (blobuploader) - INFO - Done attempting. 14:27:24 INFO - (blobuploader) - INFO - Iteration through files over. 14:27:24 INFO - Return code: 0 14:27:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:27:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:27:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f847fd2bc341e5cc8bc60e431266e84c0723b408df4165402b0f7e7162305980e79fcd3aa4577f98a64d3b517df70d882f127acd026ace2b9149b6648a68eb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a76a3ad83fefa2f792a46492dcba68798766e95a49c8ef2a33c9fd96e7e31add2edce885e3df6441dc65d96ffc3f20fa2fa7bbb6d5c7cc375c4ebd8614b13a82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5b969154fee12e31cc5f0c13754a6254a60625a8ec53200f8a60144963eb6b23fd5019c365003a960a1a8c6b4e229dcfe88fe439616c69ad05577d57bf597fe"} 14:27:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:27:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:27:24 INFO - Contents: 14:27:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f847fd2bc341e5cc8bc60e431266e84c0723b408df4165402b0f7e7162305980e79fcd3aa4577f98a64d3b517df70d882f127acd026ace2b9149b6648a68eb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a76a3ad83fefa2f792a46492dcba68798766e95a49c8ef2a33c9fd96e7e31add2edce885e3df6441dc65d96ffc3f20fa2fa7bbb6d5c7cc375c4ebd8614b13a82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5b969154fee12e31cc5f0c13754a6254a60625a8ec53200f8a60144963eb6b23fd5019c365003a960a1a8c6b4e229dcfe88fe439616c69ad05577d57bf597fe"} 14:27:24 INFO - Running post-run listener: copy_logs_to_upload_dir 14:27:24 INFO - Copying logs to upload dir... 14:27:24 INFO - mkdir: /builds/slave/test/build/upload/logs 14:27:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.279934 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-25 14:27:24.104347) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 14:27:24.108391) ========= 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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f847fd2bc341e5cc8bc60e431266e84c0723b408df4165402b0f7e7162305980e79fcd3aa4577f98a64d3b517df70d882f127acd026ace2b9149b6648a68eb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a76a3ad83fefa2f792a46492dcba68798766e95a49c8ef2a33c9fd96e7e31add2edce885e3df6441dc65d96ffc3f20fa2fa7bbb6d5c7cc375c4ebd8614b13a82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5b969154fee12e31cc5f0c13754a6254a60625a8ec53200f8a60144963eb6b23fd5019c365003a960a1a8c6b4e229dcfe88fe439616c69ad05577d57bf597fe"} 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.011083 build_url: 'https://queue.taskcluster.net/v1/task/Dg9oOt3YRXGqZ6XkWmyfNA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f847fd2bc341e5cc8bc60e431266e84c0723b408df4165402b0f7e7162305980e79fcd3aa4577f98a64d3b517df70d882f127acd026ace2b9149b6648a68eb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a76a3ad83fefa2f792a46492dcba68798766e95a49c8ef2a33c9fd96e7e31add2edce885e3df6441dc65d96ffc3f20fa2fa7bbb6d5c7cc375c4ebd8614b13a82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5b969154fee12e31cc5f0c13754a6254a60625a8ec53200f8a60144963eb6b23fd5019c365003a960a1a8c6b4e229dcfe88fe439616c69ad05577d57bf597fe"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 14:27:24.142182) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 14:27:24.142500) ========= 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.NPAG0ScN4E/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.dj9Cj7P7Dm/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.005091 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 14:27:24.190747) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 14:27:24.191090) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 14:27:24.191405) ========= ========= Total master_lag: 0.18 =========