builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0372 starttime: 1492731737.97 results: success (0) buildid: 20170420141328 builduid: dddfca68fe8242b498cbd19616e44a59 revision: ab8f7daf76ded67323f481b97dc146494f237a60 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 16:42:17.968216) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 16:42:17.968699) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 16:42:17.969008) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 16:42:17.995296) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 16:42:17.995648) ========= 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.UtVJn4mFEb/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.uR3wQunLer/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-20 16:42:18-- 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% 18.8M=0.001s 2017-04-20 16:42:18 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085124 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 16:42:18.104634) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 16:42:18.104956) ========= 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.UtVJn4mFEb/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.uR3wQunLer/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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.031914 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 16:42:18.159229) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-04-20 16:42:18.159588) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev ab8f7daf76ded67323f481b97dc146494f237a60 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev ab8f7daf76ded67323f481b97dc146494f237a60 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UtVJn4mFEb/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.uR3wQunLer/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-20 16:42:18,267 truncating revision to first 12 chars 2017-04-20 16:42:18,267 Setting DEBUG logging. 2017-04-20 16:42:18,268 attempt 1/10 2017-04-20 16:42:18,268 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/ab8f7daf76de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-20 16:42:20,469 unpacking tar archive at: pine-ab8f7daf76de/testing/mozharness/ program finished with exit code 0 elapsedTime=2.471826 ========= master_lag: 0.66 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-04-20 16:42:21.294929) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 16:42:21.295339) ========= script_repo_revision: ab8f7daf76ded67323f481b97dc146494f237a60 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 16:42:21.295877) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 16:42:21.296367) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 16:42:21.416638) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-20 16:42:21.417053) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UtVJn4mFEb/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.uR3wQunLer/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 16:42:21 INFO - MultiFileLogger online at 20170420 16:42:21 in /builds/slave/test 16:42:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 16:42:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:42:21 INFO - {'append_to_log': False, 16:42:21 INFO - 'base_work_dir': '/builds/slave/test', 16:42:21 INFO - 'blob_upload_branch': 'Pine', 16:42:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:42:21 INFO - 'branch': 'Pine', 16:42:21 INFO - 'buildbot_json_path': 'buildprops.json', 16:42:21 INFO - 'config_files': ('talos/mac_config.py',), 16:42:21 INFO - 'default_actions': ('clobber', 16:42:21 INFO - 'read-buildbot-config', 16:42:21 INFO - 'download-and-extract', 16:42:21 INFO - 'populate-webroot', 16:42:21 INFO - 'create-virtualenv', 16:42:21 INFO - 'install', 16:42:21 INFO - 'run-tests'), 16:42:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:42:21 INFO - 'download_minidump_stackwalk': True, 16:42:21 INFO - 'download_symbols': 'ondemand', 16:42:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:42:21 INFO - 'tooltool.py': '/tools/tooltool.py', 16:42:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:42:21 INFO - '/tools/misc-python/virtualenv.py')}, 16:42:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:42:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:42:21 INFO - 'gecko_profile': False, 16:42:21 INFO - 'gecko_profile_interval': 0, 16:42:21 INFO - 'installer_path': 'installer.exe', 16:42:21 INFO - 'log_level': 'info', 16:42:21 INFO - 'log_name': 'talos', 16:42:21 INFO - 'log_to_console': True, 16:42:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:42:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:42:21 INFO - 'opt_config_files': (), 16:42:21 INFO - 'pip_index': False, 16:42:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:42:21 INFO - 'cmd': ('bash', 16:42:21 INFO - '-c', 16:42:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:42:21 INFO - 'enabled': True, 16:42:21 INFO - 'halt_on_failure': False, 16:42:21 INFO - 'name': 'check_screen_resolution'},), 16:42:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:42:21 INFO - 'cmd': ('bash', 16:42:21 INFO - '-c', 16:42:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:42:21 INFO - 'enabled': True, 16:42:21 INFO - 'halt_on_failure': False, 16:42:21 INFO - 'name': 'check_screen_resolution'},), 16:42:21 INFO - 'run_cmd_checks_enabled': True, 16:42:21 INFO - 'suite': 'dromaeojs-e10s', 16:42:21 INFO - 'system_bits': '32', 16:42:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:42:21 INFO - 'title': 't-yosemite-r7-0372', 16:42:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:42:21 INFO - 'use_talos_json': True, 16:42:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:42:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:42:21 INFO - 'work_dir': 'build'} 16:42:21 INFO - [mozharness: 2017-04-20 23:42:21.631195Z] Running clobber step. 16:42:21 INFO - Running pre-action listener: _resource_record_pre_action 16:42:21 INFO - Running main action method: clobber 16:42:21 INFO - rmtree: /builds/slave/test/build 16:42:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:42:24 INFO - Running post-action listener: _resource_record_post_action 16:42:24 INFO - [mozharness: 2017-04-20 23:42:24.162490Z] Finished clobber step (success) 16:42:24 INFO - [mozharness: 2017-04-20 23:42:24.162628Z] Running read-buildbot-config step. 16:42:24 INFO - Running pre-action listener: _resource_record_pre_action 16:42:24 INFO - Running main action method: read_buildbot_config 16:42:24 INFO - Using buildbot properties: 16:42:24 INFO - { 16:42:24 INFO - "project": "", 16:42:24 INFO - "product": "firefox", 16:42:24 INFO - "script_repo_revision": "default", 16:42:24 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 16:42:24 INFO - "repository": "", 16:42:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 16:42:24 INFO - "buildid": "20170420141328", 16:42:24 INFO - "pgo_build": "False", 16:42:24 INFO - "basedir": "/builds/slave/test", 16:42:24 INFO - "buildnumber": 1, 16:42:24 INFO - "slavename": "t-yosemite-r7-0372", 16:42:24 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 16:42:24 INFO - "platform": "yosemite_r7", 16:42:24 INFO - "scheduler": "tests-pine-macosx64-talos", 16:42:24 INFO - "branch": "pine", 16:42:24 INFO - "repo_path": "projects/pine", 16:42:24 INFO - "revision": "ab8f7daf76ded67323f481b97dc146494f237a60", 16:42:24 INFO - "stage_platform": "macosx64", 16:42:24 INFO - "builduid": "dddfca68fe8242b498cbd19616e44a59", 16:42:24 INFO - "slavebuilddir": "test" 16:42:24 INFO - } 16:42:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:42:24 INFO - Running post-action listener: _resource_record_post_action 16:42:24 INFO - [mozharness: 2017-04-20 23:42:24.165913Z] Finished read-buildbot-config step (success) 16:42:24 INFO - [mozharness: 2017-04-20 23:42:24.166014Z] Running download-and-extract step. 16:42:24 INFO - Running pre-action listener: _resource_record_pre_action 16:42:24 INFO - Running main action method: download_and_extract 16:42:24 INFO - mkdir: /builds/slave/test/build/tests 16:42:24 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')]} 16:42:24 INFO - trying https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:42:24 INFO - Downloading https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:42:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 16:42:25 INFO - Downloaded 1325 bytes. 16:42:25 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:42:25 INFO - Using the following test package requirements: 16:42:25 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:42:25 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:42:25 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:42:25 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:42:25 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:42:25 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:42:25 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:42:25 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:42:25 INFO - u'jsshell-mac.zip'], 16:42:25 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:42:25 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:42:25 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:42:25 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:42:25 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:42:25 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:42:25 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:42:25 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:42:25 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:42:25 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:42:25 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:42:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:42:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:42:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:42:25 INFO - Fetch https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:42:27 INFO - Content-Length response header: 52521646 16:42:27 INFO - Bytes received: 52521646 16:42:43 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:42:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:42:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:42:43 INFO - Fetch https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:42:45 INFO - Content-Length response header: 52521646 16:42:45 INFO - Bytes received: 52521646 16:43:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:43:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:43:00 INFO - Fetch https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:43:01 INFO - Content-Length response header: 14408811 16:43:01 INFO - Bytes received: 14408811 16:43: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')]} 16:43:01 INFO - trying https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:43:01 INFO - Downloading https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:43:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 16:43:03 INFO - Downloaded 71093312 bytes. 16:43:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:43:03 INFO - mkdir: /builds/slave/test/properties 16:43:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:43:03 INFO - Writing to file /builds/slave/test/properties/build_url 16:43:03 INFO - Contents: 16:43:03 INFO - build_url:https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:43:04 INFO - Running post-action listener: _resource_record_post_action 16:43:04 INFO - Running post-action listener: set_extra_try_arguments 16:43:04 INFO - [mozharness: 2017-04-20 23:43:04.577759Z] Finished download-and-extract step (success) 16:43:04 INFO - [mozharness: 2017-04-20 23:43:04.577886Z] Running populate-webroot step. 16:43:04 INFO - Running pre-action listener: _resource_record_pre_action 16:43:04 INFO - Running main action method: populate_webroot 16:43:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:43:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:43:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:43:04 INFO - '--tppagecycles', 16:43:04 INFO - '7'], 16:43:04 INFO - 'tests': ['tsvgm']}}, 16:43:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:43:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:43:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:43:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:43:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:43:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:43:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:43:04 INFO - 'talos_options': ['--disable-e10s'], 16:43:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:43:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:43:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:43:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:43:04 INFO - 'talos_options': ['--disable-e10s'], 16:43:04 INFO - 'tests': ['damp', 'tps']}, 16:43:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:43:04 INFO - 'tests': ['damp', 'tps']}, 16:43:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:43:04 INFO - 'tests': ['dromaeo_dom']}, 16:43:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:43:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:43:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:43:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:43:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:43:04 INFO - 'tests': ['a11yr', 16:43:04 INFO - 'ts_paint', 16:43:04 INFO - 'tpaint', 16:43:04 INFO - 'sessionrestore', 16:43:04 INFO - 'sessionrestore_no_auto_restore', 16:43:04 INFO - 'tabpaint']}, 16:43:04 INFO - 'other-e10s': {'tests': ['a11yr', 16:43:04 INFO - 'ts_paint', 16:43:04 INFO - 'tpaint', 16:43:04 INFO - 'sessionrestore', 16:43:04 INFO - 'sessionrestore_no_auto_restore', 16:43:04 INFO - 'tabpaint']}, 16:43:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:43:04 INFO - 'ts_paint', 16:43:04 INFO - 'tpaint', 16:43:04 INFO - 'sessionrestore', 16:43:04 INFO - 'sessionrestore_no_auto_restore', 16:43:04 INFO - 'tabpaint']}, 16:43:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:43:04 INFO - 'ts_paint', 16:43:04 INFO - 'tpaint', 16:43:04 INFO - 'sessionrestore', 16:43:04 INFO - 'sessionrestore_no_auto_restore', 16:43:04 INFO - 'tabpaint']}, 16:43:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:43:04 INFO - 'tests': ['a11yr', 16:43:04 INFO - 'ts_paint', 16:43:04 INFO - 'tpaint', 16:43:04 INFO - 'sessionrestore', 16:43:04 INFO - 'sessionrestore_no_auto_restore', 16:43:04 INFO - 'tabpaint']}, 16:43:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:43:04 INFO - 'tests': ['a11yr', 16:43:04 INFO - 'ts_paint', 16:43:04 INFO - 'tpaint', 16:43:04 INFO - 'sessionrestore', 16:43:04 INFO - 'sessionrestore_no_auto_restore', 16:43:04 INFO - 'tabpaint']}, 16:43:04 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 16:43:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 16:43:04 INFO - 'bloom_basic_ref']}, 16:43:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:43:04 INFO - 'tests': ['tsvgx', 16:43:04 INFO - 'tsvgr_opacity', 16:43:04 INFO - 'tart', 16:43:04 INFO - 'tscrollx', 16:43:04 INFO - 'cart', 16:43:04 INFO - 'tsvg_static']}, 16:43:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:43:04 INFO - 'tsvgr_opacity', 16:43:04 INFO - 'tart', 16:43:04 INFO - 'tscrollx', 16:43:04 INFO - 'cart', 16:43:04 INFO - 'tsvg_static']}, 16:43:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:43:04 INFO - 'talos_options': ['--disable-e10s'], 16:43:04 INFO - 'tests': ['tp5o']}, 16:43:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:43:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:43:04 INFO - 'talos_options': ['--disable-e10s', 16:43:04 INFO - '--xperf_path', 16:43:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:43:04 INFO - 'tests': ['tp5n']}, 16:43:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:43:04 INFO - 'talos_options': ['--xperf_path', 16:43:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:43:04 INFO - 'tests': ['tp5n']}}, 16:43:04 INFO - 'talos.zip': {'path': '', 16:43:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:43:04 INFO - Running post-action listener: _resource_record_post_action 16:43:04 INFO - [mozharness: 2017-04-20 23:43:04.597094Z] Finished populate-webroot step (success) 16:43:04 INFO - [mozharness: 2017-04-20 23:43:04.597223Z] Running create-virtualenv step. 16:43:04 INFO - Running pre-action listener: _resource_record_pre_action 16:43:04 INFO - Running main action method: create_virtualenv 16:43:04 INFO - Creating virtualenv /builds/slave/test/build/venv 16:43:04 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 16:43:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:43:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:43:04 INFO - Using real prefix '/tools/python27' 16:43:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:43:05 INFO - Installing distribute.............................................................................................................................................................................................done. 16:43:08 INFO - Installing pip.................done. 16:43:08 INFO - Return code: 0 16:43:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:43:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:43:08 INFO - Reading from file tmpfile_stdout 16:43:08 INFO - Output received: 16:43:08 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) 16:43:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:43:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bed8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e149cce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb6d30>, '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 0x10bbdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcabbe8>, '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.UtVJn4mFEb/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.uR3wQunLer/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 16:43:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:43:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:43:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UtVJn4mFEb/Render', 16:43:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:08 INFO - 'HOME': '/Users/cltbld', 16:43:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:08 INFO - 'LOGNAME': 'cltbld', 16:43:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:08 INFO - 'MOZ_NO_REMOTE': '1', 16:43:08 INFO - 'NO_EM_RESTART': '1', 16:43:08 INFO - 'PAGER': '/bin/cat', 16:43:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:08 INFO - 'PWD': '/builds/slave/test', 16:43:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:08 INFO - 'SHELL': '/bin/bash', 16:43:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uR3wQunLer/Listeners', 16:43:08 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 16:43:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:08 INFO - 'USER': 'cltbld', 16:43:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:08 INFO - 'XPC_FLAGS': '0x0', 16:43:08 INFO - 'XPC_SERVICE_NAME': '0', 16:43:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:08 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 16:43:08 INFO - Cleaning up... 16:43:08 INFO - Return code: 0 16:43:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:43:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bed8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e149cce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb6d30>, '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 0x10bbdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcabbe8>, '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.UtVJn4mFEb/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.uR3wQunLer/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 16:43:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:43:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:43:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UtVJn4mFEb/Render', 16:43:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:08 INFO - 'HOME': '/Users/cltbld', 16:43:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:08 INFO - 'LOGNAME': 'cltbld', 16:43:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:08 INFO - 'MOZ_NO_REMOTE': '1', 16:43:08 INFO - 'NO_EM_RESTART': '1', 16:43:08 INFO - 'PAGER': '/bin/cat', 16:43:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:08 INFO - 'PWD': '/builds/slave/test', 16:43:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:08 INFO - 'SHELL': '/bin/bash', 16:43:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uR3wQunLer/Listeners', 16:43:08 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 16:43:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:08 INFO - 'USER': 'cltbld', 16:43:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:08 INFO - 'XPC_FLAGS': '0x0', 16:43:08 INFO - 'XPC_SERVICE_NAME': '0', 16:43:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:09 INFO - Downloading/unpacking psutil>=3.1.1 16:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:43:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:43:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:43:12 INFO - Installing collected packages: psutil 16:43:12 INFO - Running setup.py install for psutil 16:43:12 INFO - building 'psutil._psutil_osx' extension 16:43:12 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 16:43:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:43:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:43:13 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 16:43:13 INFO - building 'psutil._psutil_posix' extension 16:43:13 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 16:43:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:43:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:43:13 INFO - ^ 16:43:13 INFO - 1 warning generated. 16:43:13 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 16:43:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:43:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:43:13 INFO - Successfully installed psutil 16:43:13 INFO - Cleaning up... 16:43:13 INFO - Return code: 0 16:43:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:43:13 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')]} 16:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:13 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')]} 16:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:13 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 0x10bed8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e149cce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb6d30>, '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 0x10bbdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcabbe8>, '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.UtVJn4mFEb/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.uR3wQunLer/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 16:43:13 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 16:43:13 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 16:43:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UtVJn4mFEb/Render', 16:43:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:13 INFO - 'HOME': '/Users/cltbld', 16:43:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:13 INFO - 'LOGNAME': 'cltbld', 16:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:13 INFO - 'MOZ_NO_REMOTE': '1', 16:43:13 INFO - 'NO_EM_RESTART': '1', 16:43:13 INFO - 'PAGER': '/bin/cat', 16:43:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:13 INFO - 'PWD': '/builds/slave/test', 16:43:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:13 INFO - 'SHELL': '/bin/bash', 16:43:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uR3wQunLer/Listeners', 16:43:13 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 16:43:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:13 INFO - 'USER': 'cltbld', 16:43:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:13 INFO - 'XPC_FLAGS': '0x0', 16:43:13 INFO - 'XPC_SERVICE_NAME': '0', 16:43:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:43:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:43:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:43:17 INFO - Installing collected packages: mozsystemmonitor 16:43:17 INFO - Running setup.py install for mozsystemmonitor 16:43:17 INFO - Successfully installed mozsystemmonitor 16:43:17 INFO - Cleaning up... 16:43:17 INFO - Return code: 0 16:43:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bed8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e149cce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb6d30>, '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 0x10bbdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcabbe8>, '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.UtVJn4mFEb/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.uR3wQunLer/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 16:43:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:43:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:43:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UtVJn4mFEb/Render', 16:43:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:17 INFO - 'HOME': '/Users/cltbld', 16:43:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:17 INFO - 'LOGNAME': 'cltbld', 16:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:17 INFO - 'MOZ_NO_REMOTE': '1', 16:43:17 INFO - 'NO_EM_RESTART': '1', 16:43:17 INFO - 'PAGER': '/bin/cat', 16:43:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:17 INFO - 'PWD': '/builds/slave/test', 16:43:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:17 INFO - 'SHELL': '/bin/bash', 16:43:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uR3wQunLer/Listeners', 16:43:17 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 16:43:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:17 INFO - 'USER': 'cltbld', 16:43:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:17 INFO - 'XPC_FLAGS': '0x0', 16:43:17 INFO - 'XPC_SERVICE_NAME': '0', 16:43:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:18 INFO - Downloading/unpacking jsonschema==2.5.1 16:43:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:43:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:21 INFO - Downloading functools32-3.2.3-2.tar.gz 16:43:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:43:21 INFO - warning: no files found matching '*.txt' 16:43:21 INFO - no previously-included directories found matching 'build' 16:43:21 INFO - no previously-included directories found matching 'dist' 16:43:21 INFO - no previously-included directories found matching '.git*' 16:43:21 INFO - Installing collected packages: jsonschema, functools32 16:43:21 INFO - Running setup.py install for functools32 16:43:21 INFO - warning: no files found matching '*.txt' 16:43:21 INFO - no previously-included directories found matching 'build' 16:43:21 INFO - no previously-included directories found matching 'dist' 16:43:21 INFO - no previously-included directories found matching '.git*' 16:43:21 INFO - Successfully installed jsonschema functools32 16:43:21 INFO - Cleaning up... 16:43:21 INFO - Return code: 0 16:43:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:43:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bed8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e149cce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb6d30>, '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 0x10bbdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcabbe8>, '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.UtVJn4mFEb/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.uR3wQunLer/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 16:43:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:43:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:43:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UtVJn4mFEb/Render', 16:43:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:21 INFO - 'HOME': '/Users/cltbld', 16:43:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:21 INFO - 'LOGNAME': 'cltbld', 16:43:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:21 INFO - 'MOZ_NO_REMOTE': '1', 16:43:21 INFO - 'NO_EM_RESTART': '1', 16:43:21 INFO - 'PAGER': '/bin/cat', 16:43:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:21 INFO - 'PWD': '/builds/slave/test', 16:43:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:21 INFO - 'SHELL': '/bin/bash', 16:43:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uR3wQunLer/Listeners', 16:43:21 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 16:43:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:21 INFO - 'USER': 'cltbld', 16:43:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:21 INFO - 'XPC_FLAGS': '0x0', 16:43:21 INFO - 'XPC_SERVICE_NAME': '0', 16:43:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:43:21 INFO - Cleaning up... 16:43:21 INFO - Return code: 0 16:43:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:43:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bed8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e149cce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb6d30>, '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 0x10bbdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcabbe8>, '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.UtVJn4mFEb/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.uR3wQunLer/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 16:43:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:43:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:43:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UtVJn4mFEb/Render', 16:43:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:21 INFO - 'HOME': '/Users/cltbld', 16:43:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:21 INFO - 'LOGNAME': 'cltbld', 16:43:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:21 INFO - 'MOZ_NO_REMOTE': '1', 16:43:21 INFO - 'NO_EM_RESTART': '1', 16:43:21 INFO - 'PAGER': '/bin/cat', 16:43:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:21 INFO - 'PWD': '/builds/slave/test', 16:43:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:21 INFO - 'SHELL': '/bin/bash', 16:43:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uR3wQunLer/Listeners', 16:43:21 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 16:43:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:21 INFO - 'USER': 'cltbld', 16:43:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:21 INFO - 'XPC_FLAGS': '0x0', 16:43:21 INFO - 'XPC_SERVICE_NAME': '0', 16:43:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:22 INFO - Downloading/unpacking blobuploader==1.2.4 16:43:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:24 INFO - Downloading blobuploader-1.2.4.tar.gz 16:43:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:43:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:43:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:25 INFO - Downloading docopt-0.6.1.tar.gz 16:43:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:43:26 INFO - Installing collected packages: blobuploader, requests, docopt 16:43:26 INFO - Running setup.py install for blobuploader 16:43:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:43:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:43:26 INFO - Running setup.py install for requests 16:43:26 INFO - Running setup.py install for docopt 16:43:26 INFO - Successfully installed blobuploader requests docopt 16:43:26 INFO - Cleaning up... 16:43:26 INFO - Return code: 0 16:43:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bed8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e149cce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb6d30>, '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 0x10bbdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcabbe8>, '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.UtVJn4mFEb/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.uR3wQunLer/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 16:43:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:43:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:43:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UtVJn4mFEb/Render', 16:43:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:26 INFO - 'HOME': '/Users/cltbld', 16:43:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:26 INFO - 'LOGNAME': 'cltbld', 16:43:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:26 INFO - 'MOZ_NO_REMOTE': '1', 16:43:26 INFO - 'NO_EM_RESTART': '1', 16:43:26 INFO - 'PAGER': '/bin/cat', 16:43:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:26 INFO - 'PWD': '/builds/slave/test', 16:43:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:26 INFO - 'SHELL': '/bin/bash', 16:43:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uR3wQunLer/Listeners', 16:43:26 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 16:43:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:26 INFO - 'USER': 'cltbld', 16:43:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:26 INFO - 'XPC_FLAGS': '0x0', 16:43:26 INFO - 'XPC_SERVICE_NAME': '0', 16:43:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:43:27 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-zvWS1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:43:27 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-q4HHja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:43:27 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-B9ysEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:43:27 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-cbPQ63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:43:27 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-R2NkTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:43:27 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-6yjfRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:43:27 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-tzYoCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:43:27 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-pZwW16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:43:28 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-KWb8Jg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:43:28 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-8XCUGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:43:28 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-V27RIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:43:28 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-hjmvOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:43:28 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-u1lk_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:43:28 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-6pgvNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:43:28 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-dZKAea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:43:28 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-39tTcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:43:28 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-HfBMlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:43:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:43:28 INFO - Running setup.py install for manifestparser 16:43:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:43:29 INFO - Running setup.py install for mozcrash 16:43:29 INFO - Running setup.py install for mozdebug 16:43:29 INFO - Running setup.py install for mozdevice 16:43:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:43:29 INFO - Running setup.py install for mozfile 16:43:29 INFO - Running setup.py install for mozhttpd 16:43:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:43:29 INFO - Running setup.py install for mozinfo 16:43:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:43:30 INFO - Running setup.py install for mozInstall 16:43:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:43:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:43:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:43:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:43:30 INFO - Running setup.py install for mozleak 16:43:30 INFO - Running setup.py install for mozlog 16:43:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:43:30 INFO - Running setup.py install for moznetwork 16:43:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:43:30 INFO - Running setup.py install for mozprocess 16:43:30 INFO - Running setup.py install for mozprofile 16:43:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:43:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:43:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:43:30 INFO - Running setup.py install for mozrunner 16:43:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:43:31 INFO - Running setup.py install for mozscreenshot 16:43:31 INFO - Running setup.py install for moztest 16:43:31 INFO - Running setup.py install for mozversion 16:43:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:43:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:43:31 INFO - Cleaning up... 16:43:31 INFO - Return code: 0 16:43:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:43: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')]} 16:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43: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')]} 16:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bed8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e149cce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb6d30>, '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 0x10bbdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcabbe8>, '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.UtVJn4mFEb/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.uR3wQunLer/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 16:43:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:43:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:43:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UtVJn4mFEb/Render', 16:43:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:31 INFO - 'HOME': '/Users/cltbld', 16:43:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:31 INFO - 'LOGNAME': 'cltbld', 16:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:31 INFO - 'MOZ_NO_REMOTE': '1', 16:43:31 INFO - 'NO_EM_RESTART': '1', 16:43:31 INFO - 'PAGER': '/bin/cat', 16:43:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:31 INFO - 'PWD': '/builds/slave/test', 16:43:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:31 INFO - 'SHELL': '/bin/bash', 16:43:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uR3wQunLer/Listeners', 16:43:31 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 16:43:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:31 INFO - 'USER': 'cltbld', 16:43:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:31 INFO - 'XPC_FLAGS': '0x0', 16:43:31 INFO - 'XPC_SERVICE_NAME': '0', 16:43:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:43:31 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-5wmX_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:43:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:43:31 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-BIN3M1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:43:32 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)) 16:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:43:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-17ZBxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:43:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:43:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-aSbq4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:43:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 16:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:43:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-ebxJH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:43:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:43:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-4TQYaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:43:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:43:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-x71zCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:43:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:43:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-VPYYuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:43:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:43:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-dshwiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:43:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:43:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-MV5yj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:43:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:43:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-p_FsG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:43:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:43:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-tf0HYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:43:33 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-4ckfR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:43:33 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-8Y170H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:43:33 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-P8VhHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:43:33 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-oWWSmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:43:33 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-tGtdZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:43:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:36 INFO - Downloading blessings-1.6.tar.gz 16:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:43:36 INFO - Installing collected packages: blessings 16:43:36 INFO - Running setup.py install for blessings 16:43:36 INFO - Successfully installed blessings 16:43:36 INFO - Cleaning up... 16:43:36 INFO - Return code: 0 16:43:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:43:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:43:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:43:36 INFO - Reading from file tmpfile_stdout 16:43:36 INFO - Current package versions: 16:43:36 INFO - blessings == 1.6 16:43:36 INFO - blobuploader == 1.2.4 16:43:36 INFO - docopt == 0.6.1 16:43:36 INFO - functools32 == 3.2.3-2 16:43:36 INFO - jsonschema == 2.5.1 16:43:36 INFO - manifestparser == 1.1 16:43:36 INFO - mozInstall == 1.12 16:43:36 INFO - mozcrash == 1.0 16:43:36 INFO - mozdebug == 0.1 16:43:36 INFO - mozdevice == 0.50 16:43:36 INFO - mozfile == 1.2 16:43:36 INFO - mozhttpd == 0.7 16:43:36 INFO - mozinfo == 0.9 16:43:36 INFO - mozleak == 0.1 16:43:36 INFO - mozlog == 3.4 16:43:36 INFO - moznetwork == 0.27 16:43:36 INFO - mozprocess == 0.25 16:43:36 INFO - mozprofile == 0.28 16:43:36 INFO - mozrunner == 6.13 16:43:36 INFO - mozscreenshot == 0.1 16:43:36 INFO - mozsystemmonitor == 0.3 16:43:36 INFO - moztest == 0.8 16:43:36 INFO - mozversion == 1.4 16:43:36 INFO - psutil == 3.1.1 16:43:36 INFO - requests == 1.2.3 16:43:36 INFO - wsgiref == 0.1.2 16:43:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:36 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 0x10bed8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e149cce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb6d30>, '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 0x10bbdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcabbe8>, '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.UtVJn4mFEb/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.uR3wQunLer/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 16:43:36 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 16:43:36 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 16:43:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UtVJn4mFEb/Render', 16:43:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:36 INFO - 'HOME': '/Users/cltbld', 16:43:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:36 INFO - 'LOGNAME': 'cltbld', 16:43:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:36 INFO - 'MOZ_NO_REMOTE': '1', 16:43:36 INFO - 'NO_EM_RESTART': '1', 16:43:36 INFO - 'PAGER': '/bin/cat', 16:43:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:36 INFO - 'PWD': '/builds/slave/test', 16:43:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:36 INFO - 'SHELL': '/bin/bash', 16:43:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uR3wQunLer/Listeners', 16:43:36 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 16:43:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:36 INFO - 'USER': 'cltbld', 16:43:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:36 INFO - 'XPC_FLAGS': '0x0', 16:43:36 INFO - 'XPC_SERVICE_NAME': '0', 16:43:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:43:37 INFO - Cleaning up... 16:43:37 INFO - Return code: 0 16:43:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bed8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e149cce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb6d30>, '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 0x10bbdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcabbe8>, '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.UtVJn4mFEb/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.uR3wQunLer/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 16:43:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:43:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UtVJn4mFEb/Render', 16:43:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:37 INFO - 'HOME': '/Users/cltbld', 16:43:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:37 INFO - 'LOGNAME': 'cltbld', 16:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:37 INFO - 'MOZ_NO_REMOTE': '1', 16:43:37 INFO - 'NO_EM_RESTART': '1', 16:43:37 INFO - 'PAGER': '/bin/cat', 16:43:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:37 INFO - 'PWD': '/builds/slave/test', 16:43:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:37 INFO - 'SHELL': '/bin/bash', 16:43:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uR3wQunLer/Listeners', 16:43:37 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 16:43:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:37 INFO - 'USER': 'cltbld', 16:43:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:37 INFO - 'XPC_FLAGS': '0x0', 16:43:37 INFO - 'XPC_SERVICE_NAME': '0', 16:43:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:43:37 INFO - Cleaning up... 16:43:37 INFO - Return code: 0 16:43:37 INFO - Running post-action listener: _resource_record_post_action 16:43:37 INFO - Running post-action listener: _start_resource_monitoring 16:43:37 INFO - Starting resource monitoring. 16:43:37 INFO - [mozharness: 2017-04-20 23:43:37.467190Z] Finished create-virtualenv step (success) 16:43:37 INFO - [mozharness: 2017-04-20 23:43:37.468123Z] Running install step. 16:43:37 INFO - Running pre-action listener: _resource_record_pre_action 16:43:37 INFO - Running main action method: install 16:43:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:43:37 INFO - Reading from file tmpfile_stdout 16:43:37 INFO - Detecting whether we're running mozinstall >=1.0... 16:43:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:43:37 INFO - Reading from file tmpfile_stdout 16:43:37 INFO - Output received: 16:43:37 INFO - Usage: mozinstall [options] installer 16:43:37 INFO - Options: 16:43:37 INFO - -h, --help show this help message and exit 16:43:37 INFO - -d DEST, --destination=DEST 16:43:37 INFO - Directory to install application into. [default: 16:43:37 INFO - "/builds/slave/test"] 16:43:37 INFO - --app=APP Application being installed. [default: firefox] 16:43:37 INFO - mkdir: /builds/slave/test/build/application 16:43:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:43:57 INFO - Reading from file tmpfile_stdout 16:43:57 INFO - Output received: 16:43:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:43:57 INFO - Running post-action listener: _resource_record_post_action 16:43:57 INFO - [mozharness: 2017-04-20 23:43:57.613778Z] Finished install step (success) 16:43:57 INFO - [mozharness: 2017-04-20 23:43:57.613925Z] Running run-tests step. 16:43:57 INFO - Running pre-action listener: _resource_record_pre_action 16:43:57 INFO - Running main action method: run_tests 16:43:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:43:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:43:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:43:57 INFO - 2017-04-20 16:43:57.628 screenresolution[1626:7320] starting screenresolution argv=screenresolution get 16:43:57 INFO - 2017-04-20 16:43:57.631 screenresolution[1626:7320] Display 0: 1600x1200x32@60 16:43:57 INFO - 2017-04-20 16:43:57.636 screenresolution[1627:7323] starting screenresolution argv=screenresolution list 16:43:57 INFO - Available Modes on Display 0 16:43:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:43:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:43:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:43:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:43:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:43:57 INFO - Intel Iris: 16:43:57 INFO - Chipset Model: Intel Iris 16:43:57 INFO - Type: GPU 16:43:57 INFO - Bus: Built-In 16:43:57 INFO - VRAM (Dynamic, Max): 1536 MB 16:43:57 INFO - Vendor: Intel (0x8086) 16:43:57 INFO - Device ID: 0x0a2e 16:43:57 INFO - Revision ID: 0x0009 16:43:57 INFO - Displays: 16:43:57 INFO - Display: 16:43:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:43:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:43:57 INFO - Display Serial Number: 1600x1200 60 16:43:57 INFO - Main Display: Yes 16:43:57 INFO - Mirror: Off 16:43:57 INFO - Online: Yes 16:43:57 INFO - Rotation: Supported 16:43:57 INFO - Return code: 0 16:43:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:43:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:43:57 INFO - Python 2.7.3 16:43:57 INFO - Return code: 0 16:43:57 INFO - grabbing minidump binary from tooltool 16:43:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:57 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 0x10bcb6d30>, '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 0x10bbdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcabbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:43:57 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 16:43:57 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 16:43:57 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 16:43:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:43:57 INFO - Return code: 0 16:43:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:43:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:43:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:43:57 INFO - ENV: RUST_BACKTRACE is now 1 16:43:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:43:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:43:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0372', '--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 16:43:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0372 --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 16:43:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UtVJn4mFEb/Render', 16:43:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:57 INFO - 'HOME': '/Users/cltbld', 16:43:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:57 INFO - 'LOGNAME': 'cltbld', 16:43:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:43:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:57 INFO - 'MOZ_NO_REMOTE': '1', 16:43:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:43:57 INFO - 'NO_EM_RESTART': '1', 16:43:57 INFO - 'PAGER': '/bin/cat', 16:43:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:57 INFO - 'PWD': '/builds/slave/test', 16:43:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:43:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:57 INFO - 'RUST_BACKTRACE': '1', 16:43:57 INFO - 'SHELL': '/bin/bash', 16:43:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uR3wQunLer/Listeners', 16:43:57 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 16:43:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:57 INFO - 'USER': 'cltbld', 16:43:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:57 INFO - 'XPC_FLAGS': '0x0', 16:43:57 INFO - 'XPC_SERVICE_NAME': '0', 16:43:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0372', '--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 16:43:58 INFO - mozversion application_buildid: 20170420141328 16:43:58 INFO - mozversion application_changeset: ab8f7daf76ded67323f481b97dc146494f237a60 16:43:58 INFO - mozversion application_display_name: Nightly 16:43:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:43:58 INFO - mozversion application_name: Firefox 16:43:58 INFO - mozversion application_remotingname: firefox 16:43:58 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 16:43:58 INFO - mozversion application_vendor: Mozilla 16:43:58 INFO - mozversion application_version: 55.0a1 16:43:58 INFO - mozversion platform_buildid: 20170420141328 16:43:58 INFO - mozversion platform_changeset: ab8f7daf76ded67323f481b97dc146494f237a60 16:43:58 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 16:43:58 INFO - mozversion platform_version: 55.0a1 16:43:58 INFO - using testdate: 1492731838 16:43:58 INFO - actual date: 1492731838 16:43:58 INFO - starting webserver on 'localhost:49236' 16:43:58 INFO - SUITE-START | Running 2 tests 16:43:58 INFO - TEST-START | dromaeo_css 16:43:58 INFO - operating with platform_type : mac_ 16:43:58 INFO - Initialising browser for dromaeo_css test... 16:43:58 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpOuLqxw/profile http://localhost:49236/getInfo.html) 16:44:01 INFO - PID 1637 | 2017-04-20 16:44:01.025 plugin-container[1639:7511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 16:44:01 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:44:01 INFO - PID 1637 | 2017-04-20 16:44:01.027 plugin-container[1639:7511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 16:44:01 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:44:01 INFO - PID 1637 | 2017-04-20 16:44:01.027 plugin-container[1639:7511] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:44:01 INFO - PID 1637 | __metrics Screen width/height:1600/1200 16:44:01 INFO - PID 1637 | colorDepth:24 16:44:01 INFO - PID 1637 | Browser inner width/height: 1024/689 16:44:01 INFO - PID 1637 | __metrics 16:44:01 ERROR - PID 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3403: TypeError: this.tabs is undefined 16:44:02 INFO - TEST-INFO | 1637: exit 0 16:44:02 INFO - Browser initialized. 16:44:02 INFO - Running cycle 1/1 for dromaeo_css test... 16:44:02 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpOuLqxw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:44:04 INFO - PID 1640 | 2017-04-20 16:44:04.648 plugin-container[1641:7646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 16:44:04 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:44:04 INFO - PID 1640 | 2017-04-20 16:44:04.649 plugin-container[1641:7646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 16:44:04 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:44:04 INFO - PID 1640 | 2017-04-20 16:44:04.649 plugin-container[1641:7646] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:44:14 INFO - PID 1640 | RSS: Main: 236281856 16:44:14 INFO - PID 1640 | 16:47:18 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 16:47:20 INFO - PID 1640 | RSS: Main: 252768256 16:47:20 INFO - PID 1640 | 16:49:02 INFO - PID 1640 | ************************* 16:49:02 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 16:49:02 INFO - PID 1640 | 16:49:02 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:49:02 INFO - PID 1640 | Full stack: 16:49:02 INFO - PID 1640 | ************************* 16:49:02 INFO - PID 1640 | ************************* 16:49:02 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 16:49:02 INFO - PID 1640 | 16:49:02 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:49:02 INFO - PID 1640 | Full stack: 16:49:02 INFO - PID 1640 | ************************* 16:49:02 INFO - PID 1640 | ************************* 16:49:02 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 16:49:02 INFO - PID 1640 | 16:49:02 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:49:02 INFO - PID 1640 | Full stack: 16:49:02 INFO - PID 1640 | ************************* 16:49:02 INFO - PID 1640 | ************************* 16:49:02 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 16:49:02 INFO - PID 1640 | 16:49:02 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:49:02 INFO - PID 1640 | Full stack: 16:49:02 INFO - PID 1640 | ************************* 16:50:22 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 16:50:23 INFO - PID 1640 | RSS: Main: 262012928 16:50:23 INFO - PID 1640 | 16:53:24 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 16:53:25 INFO - PID 1640 | RSS: Main: 270979072 16:53:25 INFO - PID 1640 | 16:56:27 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 16:56:27 INFO - PID 1640 | RSS: Main: 264220672 16:56:27 INFO - PID 1640 | 16:59:30 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 16:59:31 INFO - PID 1640 | RSS: Main: 273461248 16:59:31 INFO - PID 1640 | 17:02:33 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 17:02:33 INFO - PID 1640 | RSS: Main: 268062720 17:02:33 INFO - PID 1640 | 17:02:33 INFO - PID 1640 | __start_tp_report 17:02:33 INFO - PID 1640 | _x_x_mozilla_page_load 17:02:33 INFO - PID 1640 | _x_x_mozilla_page_load_details 17:02:33 INFO - PID 1640 | |i|pagename|runs| 17:02:33 INFO - PID 1640 | |0;dojo.html;3217.8551965161564;3628.219932714466;3643.96147098077;3661.9084522886906;3678.6873115785156;6102.095423428424;6215.254169499661;6250.562460627752;6260.718267677954;6295.748170073191;6189.876202475948;6228.501719862412;6240.563160578761;6268.216472940882;6272.9372706272925;5467.343918729738;5496.670199788005;5563.638363506379;5568.303962004749;5612.550995920316;5435.510804027636;5518.255035570207;5606.943930560703;5615.691136987464;5617.325920889479;5425.782968681248;5497.47773961477;5528.695921724304;5540.307461567304;5596.300462442195;5473.370562385337;5489.039418101856;5502.80740174092;5509.531689806371;5545;5409.215663728737;5437.782488700447;5443.836684899459;5447.12845944647;5485.753141108659;6037.180926641692;6138.57232748375;6180.443760061571;6214.689265536705;6276.686165691743;6703.597784132919;6746.224184218829;6760.898586521206;6761.898571521431;6790.490713196481;6816.216135144472;6822.624755638438;6846.349596788297;6852.5203235773515;6857.759978400782;5120.564751996122;6200.968995154995;6227.501799856001;6305.968470157666;6326.367363263636;6090.756369745161;6975.058367120374;6990.056342393712;7007;7030.6484675766005;6335.524835637346;6394.328595497405;6517.120188774549;6595.70319335631;6617.04052912325;6814.795556133225;6820.181578210547;6832.419244364285;6839.452843772487;6850.965745171242;6354.8411289717205;6445.45213656835;6463.4506066983895;6697.866042679119;6728.529002969843;6533.640649764308;6592.670366481657;6602.570832895846;6697.564658297195;6718.899216511756;5364.195370694427;5364.91952620711;5365.409804921455;5369.167778994262;5397.973010134924;4407.338899165151;4634.490206077328;4771.5467030632035;4787.473377928425;4802.903941921142;5326.8934621308135;5549.9177660355845;6177;6311.810645680545;6345.460635846005;6726.058351830748;6797.762078327334;6827.385535301847;6874.381305682413;6878.65606719672;6305.211848518935;6317.241930968313;6407.615543067431;6424.229092508929;6428.260750013808;5832.737526811304;5880.676562789002;5909.084091965752;5911.379305172981;5992.191054207713;6997.965010174917;7035.452200515879;7051.048108505492;7056;7077.186123595852;6765.797026089225;6798.150231221098;6858.074159988338;6863.965680171567;6876.415504682158;6701.731930722912;6784.491163162881;7033.191183014019;7049.189343225595;7069.575825450489;5464.590155738256;6600.735970561123;6670.433013193933;6693;6700.229473610597;6442.8389290268115;6727.899081513683;6836.5214434989075;6850.554713943578;6857.5199736018;6370.1718776558755;6498.3826536478955;6534.705938232696;6562.80311590653;6575.013747937847;6285.5600107992;6572.638504882278;6583.341665833378;6586.077949087227;6591.604503729792;6186.443220110212;6393.2328120625825;6398.5521013528605;6429.678516074084;6488.935110649022;6552.8361790955605;6572.7042283098335;6588.077669126422;6602.240742314695;6625.271220165874;6418.903716444163;6442.581232219891;6449.097126402214;6450.290468048417;6452.7741529048135 17:02:33 INFO - PID 1640 | |1;ext.html;733.8018734941566;734.5886303669944;743.6876511865016;1251.899848012159;1265.784816581181;29597.55603665947;37825.43261851068;37835.054123646885;37863;37940.67207647733;37861.864144075655;38163.61836381636;38224.80887595565;38250;38517.42223866644;6951.6871740771785;7223.711051557932;7754.534727916315;7904.170062143478;8544.316454683641;7403.481756277063;8532.189442003031;8558.443701159405;8649;8654.177853103945;8546.145385461467;8571.95714021429;8649.65401383944;8662.30701543875;8664.566771661423;8610.483370997728;8619.827603447928;8632.482051076955;8657.523836189008;8658.696945606936;8398.79003024923;8434.746957591282;8447.873281900776;8451.788705282355;8475.703350382708;31960.72157113713;35870.64129358699;37190.44214336787;37243.62756372429;37302;3651.488791569156;3659.79871107089;3703.5370578677666;3711.795851228181;3714.1086139326635;30502.847485762428;30511;30736.616852241703;30771.69228307733;30829.7668093276;3143.167060728909;3187.3306605612847;3195.201199700075;3202.951955720666;3221.1785994571233;240530;241930;291417.5429122862;296935.0306496929;299467;49344.75327623411;49799;49843;49865.50134498681;50059.49940500621;42092;43476.130477390274;52447;52582;52808.207876881876;14016;14099.647508812363;14207.36066876993;14220.004599677923;14237.003409761217;11523.135764817505;11525.135614828921;11526.827097593543;11543.365114918592;11611;13859.099158554662;13863.792043119362;13878.861211387959;13889.722205555832;13959.930200349136;5575.191597218381;5637.069883469264;6058.606190597597;6086;6105.786297479655;996.4370130876032;1004.8090862736057;1014.4116412480744;1039.6205385034407;1057.154276578734;19545.07750272333;19604.313849015216;21102.47243818917;22541.323760287218;22920.656190157162;1127.3855748617023;1147.260017288844;1157.2304417562395;1158.8493495845487;1167.8832116788424;12173.504431591502;12537.43581538833;13916.234607096707;15530.767038494432;15800.446984355493;291688.0831191661;330691;332976.67023329454;333297;334192.32903835323;54446.72776636091;56073;56191.78561607896;56412;58661.70669146627;1372.7048684532874;1475.4909556203309;1480.9555713328618;1517.7495713207418;1523.2155439948435;29102.27244318909;29484.705152949053;29726.405471890874;30003.54994675038;30158.095257142322;38323.425148622235;38377.80811095927;38442.615573845025;38666.42000370053;38691.613083868804;292196;293441;294467;294933;295655;33999.32001359909;37104.81447592745;37135.81432092822;37245.882623521335;37625.81187094047;48066.75966620145;48085.75957120192;48085.797855053905;48206.5179348202;48637.51362486471;31917.042488725376;32191.35617287688;32889.67110328866;33043.008709738744;33406.83296583599;32127.196820079684;32313.838430807697;32851;33006.33987320288;33008;39354.60645393624;39624.603753963245;39658.603413966644;39731.80134099311;39931;28038.859805701657;31569.526457102704;31931.840340798146;32498;32582.34835303328;22138.55722885501;24623.261302160965;25274.36814079663;25776.84004219777;26029.349266268495 17:02:33 INFO - PID 1640 | |2;jquery.html;428.5920750335148;429.92691242488775;440.1702790240397;440.74877319927646;455.7129008724503;21176.364709058733;21574.784252157493;21707.782922170813;21760;22437.3268801936;21583.568328633457;22128.22551210708;23367.71477568733;23513.882430587866;23793.643095353542;5676.347220069693;5704.201411802351;5797.536197104221;6216.658083805389;6247.718852651642;5521.806736764214;5909.4090590941;6167.969160154193;6168.969155154218;6173.228346456693;5885.558583106263;6024.728887200086;6096.542759293048;6101.816945491642;6138.894998900196;6039.365866584011;6106.786262480792;6115.724792384353;6116.724747386356;6124.846878828021;5368;5378.273933019032;5417.295751552312;5446.183072539111;5448.45515448456;23521.29436116919;26813.731862681318;26932.59601105985;26946.865265673547;27110.728892711017;2253.9436514087115;2300.4363930836885;2314.155333303332;2316.4440534271653;2322.175627652176;18158;18167.636647267114;18368.173432195585;18526.907365463223;18779.53051173718;2183.748868880083;2201.317591546626;2258.3337915315024;2259.9322020339414;2260.242818655752;105753;131993;132104.99999999904;134192;134517;12528.373581320897;12821.423035963404;12828.80756788649;12830.16603920742;12993.610191694264;16824.242909069122;16877.240524176443;16907.07011114376;16947.067911264752;16953.745693814602;7812.921870781333;8570.571471426403;8887.066857979948;8924.553772311489;8936.32977526688;8511.65953361871;8518.275946544612;8530.573471326408;8659.52372619512;8664.8700269496;8045.637946292314;8169.978752655918;8186.631601577943;8202.917970820336;8229.753107406787;1791.3013924569427;2053.849844087286;2212.8008479236623;2279.760625134337;2431.4529230923254;937.8358787212211;969.0793745941243;975.9609615615437;1009.9495025248708;1016.5933626549291;20288.898555507127;21599.892000539898;21602.13591456324;21608.891955540435;21856.89071554632;986.0829428631298;986.6891929042329;1004.4726518577659;1015.918726501878;1019.9439030853226;10179.083882450615;10824;11131.777364452666;11135.721606959729;11139.721506962229;496145;498008;560120;567252;584169;103703.44444833334;126951;132261;132654;133218;1131.6774719204986;1158.8514971345912;1197.8263151843107;1207.939603019828;1210.3101232297506;22091.226807061677;22230.333090007327;22268.777312227317;22474.101035959033;22533.98597063169;51663.74168129135;52344.47655523396;53148;53693.463065368844;54248;73782.26217737969;74221.6288918552;74689.6265518669;75061.11858254805;75302.71670116173;8654.913450865412;8836.823263534821;8913.688020919497;9076.729257903768;9157.542122893961;13789.448422063406;14469.638259043188;14471.49349772753;14713.411463541339;14930.552083437515;12070.517179312728;12093.153479256367;12186.51253949832;12351.629451116482;12399.938000309941;9781.462019588991;10928.235023548275;11252.66242012741;11581.478833452345;11669.106443999615;12173.817392738938;12297.754044919231;12391.380430978596;12522.624321270376;12582.05634577392;9040.547972601475;9051.59267832936;9076.41003334781;9123.680671176477;9202.677906273248;10873.78252434931;10954.835677464685;11041.227114102257;11058.170637202082;11110.722231944266 17:02:33 INFO - PID 1640 | |3;mootools.html;1413.4840783114166;1420.502824011596;1429.778384350426;1444.5594093801392;1470.1399681186501;10605.204609654269;11528.481218345174;11921.642350729471;12037.157398982064;12038.458269377878;10092.24308176886;11952.402379881014;12080.275183488997;12086.395680215997;12134.332611706353;2031.2687432524317;2526.9241922742253;2540.2379286214154;2541.8983240670445;2545.1982625472915;2465.2255132209243;2479.925602231936;2533.8606376649277;2544.5419824431597;2551.0306083688174;2505.5991041433376;2507.3480894967347;2531.354504601324;2546.5161619292367;2548.0826902315152;2447.424855159045;2506.8119891008155;2526.810489213307;2533.5439620868237;2541.186820217531;2438.5976313908186;2527.0776166699206;2531.354504601315;2537.479816637585;2554.4891021795715;11840.881591184065;11909.821352679717;11999.460024298927;12000.63998080059;12013.279203247834;5974.834907193099;6628.635425051621;6904.275051119661;6921.342472465108;6923.930760692429;4151.27352713274;4249.68127390447;4344.543822898581;4416.205083085043;4427.690061695683;1169.8771628979005;1195.3664557784389;1201.9338936358497;1203.9036877049903;1266.4169787765293;15819.60450988723;16415.42546010884;16664.16679166037;16752.748708769377;16780.077095759727;5087.847364579068;5429.891402171935;5773.191753154561;5830.154627579062;6154.476869466145;13884.305784710725;13932.512362067482;13999.230042347768;13999.720005600035;14033.298335083206;6877.552959057645;6891.827704307432;6926.238113807477;6941.201761797458;6969.54697944632;6085.482733967662;6292.8426789329715;6300.621962682254;6354.332795056453;6362.522810789209;4577.748223847654;4738.360321356642;4744.098621261949;4791.592714619227;4793.161196790547;1343.9529616463572;1435.0815478094028;1466.4647403697575;1484.814398200225;1515.9317830697646;795;800.2077942836667;836.381078002274;837.0917554453365;863.2403484933219;4658.720476771405;5701.572382071361;6068.423499767515;6103.42017508336;6478.384553467413;477.8375352380138;591.4735885062298;603.3212635784743;605.6426708242159;620.8230654263514;3428.468587368962;3629.528161339009;3655.4151335786146;4314.460692413449;4382.320740285261;11058.944705276583;15062.924685376503;15108.54674359771;15174.848251517564;15201.923990380199;14508.492202772852;15078.849211507744;15121.848781512264;15143.668392953532;15151.848481515264;7522.811929701911;8581.95709021451;8819.206271435602;8856.64573417069;8860.556972151368;6559.484889562244;7182.2099569048405;7243.855122897618;7246.673899674633;7271.381932535794;11415.771684566096;12955.611331660066;13263;13279.269640169498;13657.044006919421;13351.465941362625;13595.86404135946;13652.726945461234;13654.726905462034;13685.589432317047;9373.906260937303;9658.806823863342;9664.9033509664;9669.70990870275;9674.516274186404;10630.124990048515;10636.14910807118;10639.574417023232;10698.83951740709;10729.409882456539;9141.314401419788;9152.496612686366;9164.725058248263;9186.724398268063;9329.906700933176;8894.644214231359;8939.374243803131;8939.687110951085;8950.328725345755;8972.057933917105;8141.674333026613;8242.340612751086;8257;8272.17278272168;8288.378371622273;6018;6083.6349819011;6144.907826382519;6182.876342473035;6184.226971628546;6054.545909056927;6086.087086936995;6168.35232300602;6271.843203919756;6343.143675603735 17:02:33 INFO - PID 1640 | |4;prototype.html;392.4878034165413;401.6886912642702;404.5933242101505;406.5548224693961;407.91025974285645;6271.404216599427;6286.842828929279;6298.9921612542;6339.587926784761;6340.985442329228;4082.5305089914596;5222.903190330026;6085.300190478097;6099.298580663225;6154.784582539612;4648.74431906245;4679.578837904596;4694.624430045606;4695.3191787190835;4704.694194877339;4690.484046754854;4709.740964246965;4717.207461164752;4720.952790472085;4730.763461826912;3888.105735680795;4116.691248156385;4285;4475.597196252336;4659.949622166236;4188.727732697395;4628.884277893063;4669.229577119771;4673.415823022122;4689.343491911135;3607.3687104756564;3786.7159963002887;4053.027273454379;4161.979190104061;4174.853880114212;5583.860403489945;5751.4536119068625;5893.292804863401;5970.522358211333;6015.909761353583;5507.394186639466;5519.39286678465;5525.585581081395;5527.640703354309;5538.870538145975;6330.241114764376;6331.366863313678;6596.769113081067;6600.976848588476;6601.207855057376;4656.278276867091;4704.929426058612;4711.293306004127;4713.858584242478;4724.527547245282;5654.830355089354;6740.224874139488;6805.931940680629;6829.760958366433;6972.363378353389;4419.712718673276;4468.262736648417;4474.373587697725;4481.126180394792;4507.4365704286965;3634.9276963295893;3684.1526448916898;4384.013596940663;4547.886302842456;4558.726476411426;4428.82284708608;4528.547145285445;5187.4553171916405;5351.812686555952;5358.624896257303;5154.252633368141;5614.708617018108;6401.327860574573;6568.540202185896;6572;1530.2348825587205;1534.96162595936;1545.8995165314218;1547.5125335519276;1551.7827504149427;960.7165886063628;1019.1337363241186;1022.262154337629;1025.3847691385258;1038.1746511523356;6265.530085243625;6265.71804268809;6275.780347687809;6287.622742635456;6341.23905131378;6808.148981377328;6808.557443766139;6815.114035175495;6836.282190370039;6863.897041544381;4393.780310984438;5237.895242095213;6140.570160088841;6179.752809887555;6270.874582508415;2723.836569805818;3448.20691241016;3474.183566861836;3502.2470168913806;3576.946345804815;6816.670749203957;6826.965865170741;6938.340857618518;6955.130608673916;7034.045700805674;5485.945140548623;5843.503302219274;6224;7014.684339204748;7023.719051237996;6832.996178916584;6879.690413931385;7059.82350441243;7087.468439866928;7148.749793757193;6018;6383.457406120532;6489.896717558121;6843.076184715199;6940.9652951734915;6460.935390646034;6483.546151769331;6547.166793297884;6748.93251067483;6782.220044694726;6519.445847103049;6866.382025617719;6885.449164066963;7050.224475307609;7125.5821390736;4463.419755431773;4464.732116073046;4467.7319360838455;4491.685582009228;4502.954970450384;6287.648155646558;6446.7098980546925;6862.9313706862295;6937.653117344012;6947.4789390796905;6515.804525864042;6544.574602650812;6589.242237142598;6597.010448432773;6608.867822643424;5977.3424923257535;6049.51603871698;6309.012661011767;6610.3720146586;6647.368499992493;5653.802116925888;6413.7113829876835;6454.0641606967665;6460.3862633049785;6516.771912983023;5957.851053723692;6429.903551446826;6535.248446428532;6562.573432726857;6597;5576.693281869536;6245.344238854855;6354.23749150105;6372.299047104722;6426.935730642634 17:02:33 INFO - PID 1640 | |5;yui.html;653.160688515258;718.1059580821876;737.2848337112998;820.6635279535392;828.3662997806679;13693.315334233273;14362.712745745104;15943.840561594381;16879.57801054973;16965.06692131934;13048.412821423035;13654.112482688643;16948;16959.236834342453;16993.83006169938;754.6905768634861;921.9723408297729;934.0612684252317;946.7491114854569;946.7917058247209;819.971301004465;958.6548842416724;958.6836344006462;960.135877710063;962.2350231565887;1003.3528374198637;1007.2948935744971;1007.9596816127346;1009.6415772400802;1011.3982180602529;976.5800705696583;978.3102912446708;981.7251169672503;986.3736527305141;1002.9247806414548;719.5071376107384;724.478375569589;725.4305370284302;725.5900416264793;726.3245181980756;14747.672709456094;15744.685106297924;16179.514614561582;16475.91762041194;16658;16465.176741162893;16543;16565.83434165667;16858.409955651492;16859.325626974907;13648.181109133484;13770.65573360664;13860.722785544232;13932.303384830819;13935.442582296797;3126.249700071989;3385.3567822113964;3386;3423.1271025888486;3426.8286585670867;117708.82291177064;118437;119462;119947.40026299813;120913.39543302315;10854.45727713611;11187.105031597453;12679.683007924876;13149.60551183466;13259.801102983463;23411.414714632272;23805.642915356282;24177.51644967126;24224;24355;7633.809154771176;7649.196834332426;7752.5348479091435;7769.223077692185;7774.105977812511;3799.3161230978135;3866.2267546490816;3884.6115388461494;3894.7078969077434;3906.7655940643654;4212.283911734951;4688.882777930579;4797.2564252540915;4850.296706977469;4868.9269660955115;937.5265490927175;968.2109081098882;970.5729479029204;974.5565767575736;975.3660120921458;636.4812677667778;646.5538778242998;666.4967949198324;668.2448832818884;677.2381071294793;9902;11262;12131.33277669719;12144.939275303743;12173.147879648339;655.5476721062452;669.1802541886248;671.0337114555123;672.9360710732473;687.7214728035153;5269.314989051399;5448.134497703354;5555.2500412444615;5673.609965558465;5731.541476681856;71573;97209;99628;100069;100926.49536752269;88552.55723721469;89215.10784892068;89953.55023224842;90004.54997725101;91050;70021;76579.08552286237;76869.07827304362;76869.23130768733;77003.99999999888;11161.49773260189;11362.829557556477;11380.658580242605;11429.053768886692;11487.655370338904;39147.80426097851;39156;39254;39462.40806387964;39489.60510394859;50486.16492113792;61510;62045;62546;63191.052134217105;16308.184590770652;16310.673786524441;17132.229049692545;17175.312987480862;17211.31154753796;17896.55258618545;17947.820521795136;18414;18583.163757630668;18824.623507530046;16545.338186472407;16954.406595769087;17070.061146637054;17165.31338746436;17177.31290748406;13592.388342524808;13899.652508687364;14726.337314820645;17036.574085647957;17170.484885453458;15895;15944.043357398594;16178.757318640466;16459.423920162735;16670;11393.487293071998;11774;11850.76298474043;11995.280283183038;12082.818757718816;12807.871921280668;13266.27035513056;13461.057725959088;13678.283032325366;13819.792703109662 17:02:33 INFO - PID 1640 | __end_tp_report 17:02:33 INFO - PID 1640 | __start_cc_report 17:02:33 INFO - PID 1640 | _x_x_mozilla_cycle_collect,429 17:02:33 INFO - PID 1640 | __end_cc_report 17:02:33 INFO - PID 1640 | __startTimestamp1492732953289__endTimestamp 17:02:33 INFO - PID 1640 | 17:02:33 INFO - PID 1640 | ------- Summary: start ------- 17:02:33 INFO - PID 1640 | Number of tests: 6 17:02:33 INFO - PID 1640 | 17:02:33 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6188.41 Median:6407.62 stddev:744.09 (11.6%) stddev-sans-first:708.95 17:02:33 INFO - PID 1640 | Values: 3217.9 3628.2 3644.0 3661.9 3678.7 6102.1 6215.3 6250.6 6260.7 6295.7 6189.9 6228.5 6240.6 6268.2 6272.9 5467.3 5496.7 5563.6 5568.3 5612.6 5435.5 5518.3 5606.9 5615.7 5617.3 5425.8 5497.5 5528.7 5540.3 5596.3 5473.4 5489.0 5502.8 5509.5 5545.0 5409.2 5437.8 5443.8 5447.1 5485.8 6037.2 6138.6 6180.4 6214.7 6276.7 6703.6 6746.2 6760.9 6761.9 6790.5 6816.2 6822.6 6846.3 6852.5 6857.8 5120.6 6201.0 6227.5 6306.0 6326.4 6090.8 6975.1 6990.1 7007.0 7030.6 6335.5 6394.3 6517.1 6595.7 6617.0 6814.8 6820.2 6832.4 6839.5 6851.0 6354.8 6445.5 6463.5 6697.9 6728.5 6533.6 6592.7 6602.6 6697.6 6718.9 5364.2 5364.9 5365.4 5369.2 5398.0 4407.3 4634.5 4771.5 4787.5 4802.9 5326.9 5549.9 6177.0 6311.8 6345.5 6726.1 6797.8 6827.4 6874.4 6878.7 6305.2 6317.2 6407.6 6424.2 6428.3 5832.7 5880.7 5909.1 5911.4 5992.2 6998.0 7035.5 7051.0 7056.0 7077.2 6765.8 6798.2 6858.1 6864.0 6876.4 6701.7 6784.5 7033.2 7049.2 7069.6 5464.6 6600.7 6670.4 6693.0 6700.2 6442.8 6727.9 6836.5 6850.6 6857.5 6370.2 6498.4 6534.7 6562.8 6575.0 6285.6 6572.6 6583.3 6586.1 6591.6 6186.4 6393.2 6398.6 6429.7 6488.9 6552.8 6572.7 6588.1 6602.2 6625.3 6418.9 6442.6 6449.1 6450.3 6452.8 17:02:33 INFO - PID 1640 | 17:02:33 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:45459.57 Median:183351.92 stddev:78314.01 (42.7%) stddev-sans-first:78461.70 17:02:33 INFO - PID 1640 | Values: 733.8 734.6 743.7 1251.9 1265.8 29597.6 37825.4 37835.1 37863.0 37940.7 37861.9 38163.6 38224.8 38250.0 38517.4 6951.7 7223.7 7754.5 7904.2 8544.3 7403.5 8532.2 8558.4 8649.0 8654.2 8546.1 8572.0 8649.7 8662.3 8664.6 8610.5 8619.8 8632.5 8657.5 8658.7 8398.8 8434.7 8447.9 8451.8 8475.7 31960.7 35870.6 37190.4 37243.6 37302.0 3651.5 3659.8 3703.5 3711.8 3714.1 30502.8 30511.0 30736.6 30771.7 30829.8 3143.2 3187.3 3195.2 3203.0 3221.2 240530.0 241930.0 291417.5 296935.0 299467.0 49344.8 49799.0 49843.0 49865.5 50059.5 42092.0 43476.1 52447.0 52582.0 52808.2 14016.0 14099.6 14207.4 14220.0 14237.0 11523.1 11525.1 11526.8 11543.4 11611.0 13859.1 13863.8 13878.9 13889.7 13959.9 5575.2 5637.1 6058.6 6086.0 6105.8 996.4 1004.8 1014.4 1039.6 1057.2 19545.1 19604.3 21102.5 22541.3 22920.7 1127.4 1147.3 1157.2 1158.8 1167.9 12173.5 12537.4 13916.2 15530.8 15800.4 291688.1 330691.0 332976.7 333297.0 334192.3 54446.7 56073.0 56191.8 56412.0 58661.7 1372.7 1475.5 1481.0 1517.7 1523.2 29102.3 29484.7 29726.4 30003.5 30158.1 38323.4 38377.8 38442.6 38666.4 38691.6 292196.0 293441.0 294467.0 294933.0 295655.0 33999.3 37104.8 37135.8 37245.9 37625.8 48066.8 48085.8 48085.8 48206.5 48637.5 31917.0 32191.4 32889.7 33043.0 33406.8 32127.2 32313.8 32851.0 33006.3 33008.0 39354.6 39624.6 39658.6 39731.8 39931.0 28038.9 31569.5 31931.8 32498.0 32582.3 22138.6 24623.3 25274.4 25776.8 26029.3 17:02:33 INFO - PID 1640 | 17:02:33 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:34447.67 Median:2290.10 stddev:91097.89 (3977.9%) stddev-sans-first:91317.64 17:02:33 INFO - PID 1640 | Values: 428.6 429.9 440.2 440.7 455.7 21176.4 21574.8 21707.8 21760.0 22437.3 21583.6 22128.2 23367.7 23513.9 23793.6 5676.3 5704.2 5797.5 6216.7 6247.7 5521.8 5909.4 6168.0 6169.0 6173.2 5885.6 6024.7 6096.5 6101.8 6138.9 6039.4 6106.8 6115.7 6116.7 6124.8 5368.0 5378.3 5417.3 5446.2 5448.5 23521.3 26813.7 26932.6 26946.9 27110.7 2253.9 2300.4 2314.2 2316.4 2322.2 18158.0 18167.6 18368.2 18526.9 18779.5 2183.7 2201.3 2258.3 2259.9 2260.2 105753.0 131993.0 132105.0 134192.0 134517.0 12528.4 12821.4 12828.8 12830.2 12993.6 16824.2 16877.2 16907.1 16947.1 16953.7 7812.9 8570.6 8887.1 8924.6 8936.3 8511.7 8518.3 8530.6 8659.5 8664.9 8045.6 8170.0 8186.6 8202.9 8229.8 1791.3 2053.8 2212.8 2279.8 2431.5 937.8 969.1 976.0 1009.9 1016.6 20288.9 21599.9 21602.1 21608.9 21856.9 986.1 986.7 1004.5 1015.9 1019.9 10179.1 10824.0 11131.8 11135.7 11139.7 496145.0 498008.0 560120.0 567252.0 584169.0 103703.4 126951.0 132261.0 132654.0 133218.0 1131.7 1158.9 1197.8 1207.9 1210.3 22091.2 22230.3 22268.8 22474.1 22534.0 51663.7 52344.5 53148.0 53693.5 54248.0 73782.3 74221.6 74689.6 75061.1 75302.7 8654.9 8836.8 8913.7 9076.7 9157.5 13789.4 14469.6 14471.5 14713.4 14930.6 12070.5 12093.2 12186.5 12351.6 12399.9 9781.5 10928.2 11252.7 11581.5 11669.1 12173.8 12297.8 12391.4 12522.6 12582.1 9040.5 9051.6 9076.4 9123.7 9202.7 10873.8 10954.8 11041.2 11058.2 11110.7 17:02:33 INFO - PID 1640 | 17:02:33 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:7177.22 Median:3529.00 stddev:4554.98 (129.1%) stddev-sans-first:4547.17 17:02:33 INFO - PID 1640 | Values: 1413.5 1420.5 1429.8 1444.6 1470.1 10605.2 11528.5 11921.6 12037.2 12038.5 10092.2 11952.4 12080.3 12086.4 12134.3 2031.3 2526.9 2540.2 2541.9 2545.2 2465.2 2479.9 2533.9 2544.5 2551.0 2505.6 2507.3 2531.4 2546.5 2548.1 2447.4 2506.8 2526.8 2533.5 2541.2 2438.6 2527.1 2531.4 2537.5 2554.5 11840.9 11909.8 11999.5 12000.6 12013.3 5974.8 6628.6 6904.3 6921.3 6923.9 4151.3 4249.7 4344.5 4416.2 4427.7 1169.9 1195.4 1201.9 1203.9 1266.4 15819.6 16415.4 16664.2 16752.7 16780.1 5087.8 5429.9 5773.2 5830.2 6154.5 13884.3 13932.5 13999.2 13999.7 14033.3 6877.6 6891.8 6926.2 6941.2 6969.5 6085.5 6292.8 6300.6 6354.3 6362.5 4577.7 4738.4 4744.1 4791.6 4793.2 1344.0 1435.1 1466.5 1484.8 1515.9 795.0 800.2 836.4 837.1 863.2 4658.7 5701.6 6068.4 6103.4 6478.4 477.8 591.5 603.3 605.6 620.8 3428.5 3629.5 3655.4 4314.5 4382.3 11058.9 15062.9 15108.5 15174.8 15201.9 14508.5 15078.8 15121.8 15143.7 15151.8 7522.8 8582.0 8819.2 8856.6 8860.6 6559.5 7182.2 7243.9 7246.7 7271.4 11415.8 12955.6 13263.0 13279.3 13657.0 13351.5 13595.9 13652.7 13654.7 13685.6 9373.9 9658.8 9664.9 9669.7 9674.5 10630.1 10636.1 10639.6 10698.8 10729.4 9141.3 9152.5 9164.7 9186.7 9329.9 8894.6 8939.4 8939.7 8950.3 8972.1 8141.7 8242.3 8257.0 8272.2 8288.4 6018.0 6083.6 6144.9 6182.9 6184.2 6054.5 6086.1 6168.4 6271.8 6343.1 17:02:33 INFO - PID 1640 | 17:02:33 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5310.39 Median:5996.63 stddev:1665.92 (27.8%) stddev-sans-first:1629.19 17:02:33 INFO - PID 1640 | Values: 392.5 401.7 404.6 406.6 407.9 6271.4 6286.8 6299.0 6339.6 6341.0 4082.5 5222.9 6085.3 6099.3 6154.8 4648.7 4679.6 4694.6 4695.3 4704.7 4690.5 4709.7 4717.2 4721.0 4730.8 3888.1 4116.7 4285.0 4475.6 4659.9 4188.7 4628.9 4669.2 4673.4 4689.3 3607.4 3786.7 4053.0 4162.0 4174.9 5583.9 5751.5 5893.3 5970.5 6015.9 5507.4 5519.4 5525.6 5527.6 5538.9 6330.2 6331.4 6596.8 6601.0 6601.2 4656.3 4704.9 4711.3 4713.9 4724.5 5654.8 6740.2 6805.9 6829.8 6972.4 4419.7 4468.3 4474.4 4481.1 4507.4 3634.9 3684.2 4384.0 4547.9 4558.7 4428.8 4528.5 5187.5 5351.8 5358.6 5154.3 5614.7 6401.3 6568.5 6572.0 1530.2 1535.0 1545.9 1547.5 1551.8 960.7 1019.1 1022.3 1025.4 1038.2 6265.5 6265.7 6275.8 6287.6 6341.2 6808.1 6808.6 6815.1 6836.3 6863.9 4393.8 5237.9 6140.6 6179.8 6270.9 2723.8 3448.2 3474.2 3502.2 3576.9 6816.7 6827.0 6938.3 6955.1 7034.0 5485.9 5843.5 6224.0 7014.7 7023.7 6833.0 6879.7 7059.8 7087.5 7148.7 6018.0 6383.5 6489.9 6843.1 6941.0 6460.9 6483.5 6547.2 6748.9 6782.2 6519.4 6866.4 6885.4 7050.2 7125.6 4463.4 4464.7 4467.7 4491.7 4503.0 6287.6 6446.7 6862.9 6937.7 6947.5 6515.8 6544.6 6589.2 6597.0 6608.9 5977.3 6049.5 6309.0 6610.4 6647.4 5653.8 6413.7 6454.1 6460.4 6516.8 5957.9 6429.9 6535.2 6562.6 6597.0 5576.7 6245.3 6354.2 6372.3 6426.9 17:02:33 INFO - PID 1640 | 17:02:33 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:20998.28 Median:24289.50 stddev:29089.33 (119.8%) stddev-sans-first:29130.81 17:02:33 INFO - PID 1640 | Values: 653.2 718.1 737.3 820.7 828.4 13693.3 14362.7 15943.8 16879.6 16965.1 13048.4 13654.1 16948.0 16959.2 16993.8 754.7 922.0 934.1 946.7 946.8 820.0 958.7 958.7 960.1 962.2 1003.4 1007.3 1008.0 1009.6 1011.4 976.6 978.3 981.7 986.4 1002.9 719.5 724.5 725.4 725.6 726.3 14747.7 15744.7 16179.5 16475.9 16658.0 16465.2 16543.0 16565.8 16858.4 16859.3 13648.2 13770.7 13860.7 13932.3 13935.4 3126.2 3385.4 3386.0 3423.1 3426.8 117708.8 118437.0 119462.0 119947.4 120913.4 10854.5 11187.1 12679.7 13149.6 13259.8 23411.4 23805.6 24177.5 24224.0 24355.0 7633.8 7649.2 7752.5 7769.2 7774.1 3799.3 3866.2 3884.6 3894.7 3906.8 4212.3 4688.9 4797.3 4850.3 4868.9 937.5 968.2 970.6 974.6 975.4 636.5 646.6 666.5 668.2 677.2 9902.0 11262.0 12131.3 12144.9 12173.1 655.5 669.2 671.0 672.9 687.7 5269.3 5448.1 5555.3 5673.6 5731.5 71573.0 97209.0 99628.0 100069.0 100926.5 88552.6 89215.1 89953.6 90004.5 91050.0 70021.0 76579.1 76869.1 76869.2 77004.0 11161.5 11362.8 11380.7 11429.1 11487.7 39147.8 39156.0 39254.0 39462.4 39489.6 50486.2 61510.0 62045.0 62546.0 63191.1 16308.2 16310.7 17132.2 17175.3 17211.3 17896.6 17947.8 18414.0 18583.2 18824.6 16545.3 16954.4 17070.1 17165.3 17177.3 13592.4 13899.7 14726.3 17036.6 17170.5 15895.0 15944.0 16178.8 16459.4 16670.0 11393.5 11774.0 11850.8 11995.3 12082.8 12807.9 13266.3 13461.1 13678.3 13819.8 17:02:33 INFO - PID 1640 | -------- Summary: end -------- 17:02:33 INFO - PID 1640 | 17:02:33 INFO - TEST-INFO | 1640: exit 0 17:02:33 INFO - TEST-OK | dromaeo_css | took 1115732ms 17:02:33 INFO - TEST-START | kraken 17:02:33 INFO - operating with platform_type : mac_ 17:02:33 INFO - Initialising browser for kraken test... 17:02:33 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpTlag0H/profile http://localhost:49236/getInfo.html) 17:02:36 INFO - PID 1652 | 2017-04-20 17:02:36.774 plugin-container[1653:10072] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 17:02:36 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:02:36 INFO - PID 1652 | 2017-04-20 17:02:36.777 plugin-container[1653:10072] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 17:02:36 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:02:36 INFO - PID 1652 | 2017-04-20 17:02:36.777 plugin-container[1653:10072] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:02:36 INFO - PID 1652 | __metrics Screen width/height:1600/1200 17:02:36 INFO - PID 1652 | colorDepth:24 17:02:36 INFO - PID 1652 | Browser inner width/height: 1024/689 17:02:36 INFO - PID 1652 | __metrics 17:02:37 ERROR - PID 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3403: TypeError: this.tabs is undefined 17:02:37 INFO - TEST-INFO | 1652: exit 0 17:02:37 INFO - Browser initialized. 17:02:37 INFO - Running cycle 1/1 for kraken test... 17:02:37 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpTlag0H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:02:40 INFO - PID 1654 | 2017-04-20 17:02:40.651 plugin-container[1655:10197] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 17:02:40 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:02:40 INFO - PID 1654 | 2017-04-20 17:02:40.652 plugin-container[1655:10197] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 17:02:40 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:02:40 INFO - PID 1654 | 2017-04-20 17:02:40.652 plugin-container[1655:10197] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:02:50 INFO - PID 1654 | RSS: Main: 236859392 17:02:50 INFO - PID 1654 | 17:02:53 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 17:02:54 INFO - PID 1654 | RSS: Main: 249393152 17:02:54 INFO - PID 1654 | 17:02:56 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 17:02:57 INFO - PID 1654 | RSS: Main: 257503232 17:02:57 INFO - PID 1654 | 17:03:00 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 17:03:01 INFO - PID 1654 | RSS: Main: 260153344 17:03:01 INFO - PID 1654 | 17:03:03 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 17:03:03 INFO - PID 1654 | RSS: Main: 261320704 17:03:03 INFO - PID 1654 | 17:03:05 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:03:06 INFO - PID 1654 | RSS: Main: 263041024 17:03:06 INFO - PID 1654 | 17:03:09 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 17:03:09 INFO - PID 1654 | RSS: Main: 268161024 17:03:09 INFO - PID 1654 | 17:03:12 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 17:03:13 INFO - PID 1654 | RSS: Main: 269488128 17:03:13 INFO - PID 1654 | 17:03:16 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 17:03:17 INFO - PID 1654 | RSS: Main: 269737984 17:03:17 INFO - PID 1654 | 17:03:18 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:03:19 INFO - PID 1654 | RSS: Main: 268685312 17:03:19 INFO - PID 1654 | 17:03:21 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:03:21 INFO - PID 1654 | RSS: Main: 268976128 17:03:21 INFO - PID 1654 | 17:03:23 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:03:24 INFO - PID 1654 | RSS: Main: 268615680 17:03:24 INFO - PID 1654 | 17:03:27 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:03:28 INFO - PID 1654 | RSS: Main: 268451840 17:03:28 INFO - PID 1654 | 17:03:31 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:03:32 INFO - PID 1654 | RSS: Main: 265900032 17:03:32 INFO - PID 1654 | 17:03:33 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 17:03:34 INFO - PID 1654 | RSS: Main: 267653120 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | __start_tp_report 17:03:34 INFO - PID 1654 | _x_x_mozilla_page_load 17:03:34 INFO - PID 1654 | _x_x_mozilla_page_load_details 17:03:34 INFO - PID 1654 | |i|pagename|runs| 17:03:34 INFO - PID 1654 | |0;ai-astar;103;115;115;116;116;116;122;162;167;114 17:03:34 INFO - PID 1654 | |1;audio-beat-detection;146;115;115;149;114;115;121;130;160;149 17:03:34 INFO - PID 1654 | |2;audio-dft;202;209;207;227;198;199;198;197;200;196 17:03:34 INFO - PID 1654 | |3;audio-fft;86;57;59;87;58;59;58;62;88;89 17:03:34 INFO - PID 1654 | |4;audio-oscillator;90;69;84;74;72;70;89;69;71;91 17:03:34 INFO - PID 1654 | |5;imaging-gaussian-blur;90;89;90;90;90;91;91;89;90;90 17:03:34 INFO - PID 1654 | |6;imaging-darkroom;97;98;97;97;132;109;99;97;109;98 17:03:34 INFO - PID 1654 | |7;imaging-desaturate;93;94;89;92;92;89;91;90;91;89 17:03:34 INFO - PID 1654 | |8;json-parse-financial;48;51;56;81;78;78;56;51;50;50 17:03:34 INFO - PID 1654 | |9;json-stringify-tinderbox;55;60;56;57;61;60;56;56;59;56 17:03:34 INFO - PID 1654 | |10;stanford-crypto-aes;87;84;82;85;83;83;83;80;89;83 17:03:34 INFO - PID 1654 | |11;stanford-crypto-ccm;141;138;136;153;135;144;155;160;192;146 17:03:34 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;184;180;227;166;216;190;237;245;187;185 17:03:34 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;70;68;68;69;68;68;72;71;67;63 17:03:34 INFO - PID 1654 | __end_tp_report 17:03:34 INFO - PID 1654 | __start_cc_report 17:03:34 INFO - PID 1654 | _x_x_mozilla_cycle_collect,1025 17:03:34 INFO - PID 1654 | __end_cc_report 17:03:34 INFO - PID 1654 | __startTimestamp1492733014013__endTimestamp 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | ------- Summary: start ------- 17:03:34 INFO - PID 1654 | Number of tests: 14 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:124.60 Median:116.00 stddev:21.57 (18.6%) stddev-sans-first:21.42 17:03:34 INFO - PID 1654 | Values: 103.0 115.0 115.0 116.0 116.0 116.0 122.0 162.0 167.0 114.0 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:138.00 stddev:17.86 (12.9%) stddev-sans-first:18.14 17:03:34 INFO - PID 1654 | Values: 146.0 115.0 115.0 149.0 114.0 115.0 121.0 130.0 160.0 149.0 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:203.30 Median:201.00 stddev:9.36 (4.7%) stddev-sans-first:9.91 17:03:34 INFO - PID 1654 | Values: 202.0 209.0 207.0 227.0 198.0 199.0 198.0 197.0 200.0 196.0 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:70.30 Median:74.00 stddev:14.88 (20.1%) stddev-sans-first:14.66 17:03:34 INFO - PID 1654 | Values: 86.0 57.0 59.0 87.0 58.0 59.0 58.0 62.0 88.0 89.0 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:79.00 stddev:9.41 (11.9%) stddev-sans-first:8.90 17:03:34 INFO - PID 1654 | Values: 90.0 69.0 84.0 74.0 72.0 70.0 89.0 69.0 71.0 91.0 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:90.00 Median:90.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 17:03:34 INFO - PID 1654 | Values: 90.0 89.0 90.0 90.0 90.0 91.0 91.0 89.0 90.0 90.0 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:103.30 Median:97.00 stddev:11.17 (11.5%) stddev-sans-first:11.61 17:03:34 INFO - PID 1654 | Values: 97.0 98.0 97.0 97.0 132.0 109.0 99.0 97.0 109.0 98.0 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:91.00 Median:91.50 stddev:1.76 (1.9%) stddev-sans-first:1.72 17:03:34 INFO - PID 1654 | Values: 93.0 94.0 89.0 92.0 92.0 89.0 91.0 90.0 91.0 89.0 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:56.00 stddev:13.44 (24.0%) stddev-sans-first:13.55 17:03:34 INFO - PID 1654 | Values: 48.0 51.0 56.0 81.0 78.0 78.0 56.0 51.0 50.0 50.0 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:57.60 Median:58.00 stddev:2.17 (3.7%) stddev-sans-first:2.09 17:03:34 INFO - PID 1654 | Values: 55.0 60.0 56.0 57.0 61.0 60.0 56.0 56.0 59.0 56.0 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:83.50 stddev:2.56 (3.1%) stddev-sans-first:2.46 17:03:34 INFO - PID 1654 | Values: 87.0 84.0 82.0 85.0 83.0 83.0 83.0 80.0 89.0 83.0 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:149.50 stddev:16.98 (11.4%) stddev-sans-first:17.70 17:03:34 INFO - PID 1654 | Values: 141.0 138.0 136.0 153.0 135.0 144.0 155.0 160.0 192.0 146.0 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.70 Median:203.00 stddev:27.19 (13.4%) stddev-sans-first:28.08 17:03:34 INFO - PID 1654 | Values: 184.0 180.0 227.0 166.0 216.0 190.0 237.0 245.0 187.0 185.0 17:03:34 INFO - PID 1654 | 17:03:34 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.50 stddev:2.46 (3.6%) stddev-sans-first:2.54 17:03:34 INFO - PID 1654 | Values: 70.0 68.0 68.0 69.0 68.0 68.0 72.0 71.0 67.0 63.0 17:03:34 INFO - PID 1654 | -------- Summary: end -------- 17:03:34 INFO - PID 1654 | 17:03:35 INFO - TEST-INFO | 1654: exit 0 17:03:35 INFO - TEST-OK | kraken | took 61179ms 17:03:35 INFO - SUITE-END | took 1176s 17:03:35 INFO - Completed test suite (00:19:36) 17:03:35 INFO - javascript benchmark 17:03:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7885.323051037679, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3217.8551965161564, 3628.219932714466, 3643.96147098077, 3661.9084522886906, 3678.6873115785156, 6102.095423428424, 6215.254169499661, 6250.562460627752, 6260.718267677954, 6295.748170073191, 6189.876202475948, 6228.501719862412, 6240.563160578761, 6268.216472940882, 6272.9372706272925, 5467.343918729738, 5496.670199788005, 5563.638363506379, 5568.303962004749, 5612.550995920316, 5435.510804027636, 5518.255035570207, 5606.943930560703, 5615.691136987464, 5617.325920889479, 5425.782968681248, 5497.47773961477, 5528.695921724304, 5540.307461567304, 5596.300462442195, 5473.370562385337, 5489.039418101856, 5502.80740174092, 5509.531689806371, 5545.0, 5409.215663728737, 5437.782488700447, 5443.836684899459, 5447.12845944647, 5485.753141108659, 6037.180926641692, 6138.57232748375, 6180.443760061571, 6214.689265536705, 6276.686165691743, 6703.597784132919, 6746.224184218829, 6760.898586521206, 6761.898571521431, 6790.490713196481, 6816.216135144472, 6822.624755638438, 6846.349596788297, 6852.5203235773515, 6857.759978400782, 5120.564751996122, 6200.968995154995, 6227.501799856001, 6305.968470157666, 6326.367363263636, 6090.756369745161, 6975.058367120374, 6990.056342393712, 7007.0, 7030.6484675766005, 6335.524835637346, 6394.328595497405, 6517.120188774549, 6595.70319335631, 6617.04052912325, 6814.795556133225, 6820.181578210547, 6832.419244364285, 6839.452843772487, 6850.965745171242, 6354.8411289717205, 6445.45213656835, 6463.4506066983895, 6697.866042679119, 6728.529002969843, 6533.640649764308, 6592.670366481657, 6602.570832895846, 6697.564658297195, 6718.899216511756, 5364.195370694427, 5364.91952620711, 5365.409804921455, 5369.167778994262, 5397.973010134924, 4407.338899165151, 4634.490206077328, 4771.5467030632035, 4787.473377928425, 4802.903941921142, 5326.8934621308135, 5549.9177660355845, 6177.0, 6311.810645680545, 6345.460635846005, 6726.058351830748, 6797.762078327334, 6827.385535301847, 6874.381305682413, 6878.65606719672, 6305.211848518935, 6317.241930968313, 6407.615543067431, 6424.229092508929, 6428.260750013808, 5832.737526811304, 5880.676562789002, 5909.084091965752, 5911.379305172981, 5992.191054207713, 6997.965010174917, 7035.452200515879, 7051.048108505492, 7056.0, 7077.186123595852, 6765.797026089225, 6798.150231221098, 6858.074159988338, 6863.965680171567, 6876.415504682158, 6701.731930722912, 6784.491163162881, 7033.191183014019, 7049.189343225595, 7069.575825450489, 5464.590155738256, 6600.735970561123, 6670.433013193933, 6693.0, 6700.229473610597, 6442.8389290268115, 6727.899081513683, 6836.5214434989075, 6850.554713943578, 6857.5199736018, 6370.1718776558755, 6498.3826536478955, 6534.705938232696, 6562.80311590653, 6575.013747937847, 6285.5600107992, 6572.638504882278, 6583.341665833378, 6586.077949087227, 6591.604503729792, 6186.443220110212, 6393.2328120625825, 6398.5521013528605, 6429.678516074084, 6488.935110649022, 6552.8361790955605, 6572.7042283098335, 6588.077669126422, 6602.240742314695, 6625.271220165874, 6418.903716444163, 6442.581232219891, 6449.097126402214, 6450.290468048417, 6452.7741529048135], "value": 6138.458786797127, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.8018734941566, 734.5886303669944, 743.6876511865016, 1251.899848012159, 1265.784816581181, 29597.55603665947, 37825.43261851068, 37835.054123646885, 37863.0, 37940.67207647733, 37861.864144075655, 38163.61836381636, 38224.80887595565, 38250.0, 38517.42223866644, 6951.6871740771785, 7223.711051557932, 7754.534727916315, 7904.170062143478, 8544.316454683641, 7403.481756277063, 8532.189442003031, 8558.443701159405, 8649.0, 8654.177853103945, 8546.145385461467, 8571.95714021429, 8649.65401383944, 8662.30701543875, 8664.566771661423, 8610.483370997728, 8619.827603447928, 8632.482051076955, 8657.523836189008, 8658.696945606936, 8398.79003024923, 8434.746957591282, 8447.873281900776, 8451.788705282355, 8475.703350382708, 31960.72157113713, 35870.64129358699, 37190.44214336787, 37243.62756372429, 37302.0, 3651.488791569156, 3659.79871107089, 3703.5370578677666, 3711.795851228181, 3714.1086139326635, 30502.847485762428, 30511.0, 30736.616852241703, 30771.69228307733, 30829.7668093276, 3143.167060728909, 3187.3306605612847, 3195.201199700075, 3202.951955720666, 3221.1785994571233, 240530.0, 241930.0, 291417.5429122862, 296935.0306496929, 299467.0, 49344.75327623411, 49799.0, 49843.0, 49865.50134498681, 50059.49940500621, 42092.0, 43476.130477390274, 52447.0, 52582.0, 52808.207876881876, 14016.0, 14099.647508812363, 14207.36066876993, 14220.004599677923, 14237.003409761217, 11523.135764817505, 11525.135614828921, 11526.827097593543, 11543.365114918592, 11611.0, 13859.099158554662, 13863.792043119362, 13878.861211387959, 13889.722205555832, 13959.930200349136, 5575.191597218381, 5637.069883469264, 6058.606190597597, 6086.0, 6105.786297479655, 996.4370130876032, 1004.8090862736057, 1014.4116412480744, 1039.6205385034407, 1057.154276578734, 19545.07750272333, 19604.313849015216, 21102.47243818917, 22541.323760287218, 22920.656190157162, 1127.3855748617023, 1147.260017288844, 1157.2304417562395, 1158.8493495845487, 1167.8832116788424, 12173.504431591502, 12537.43581538833, 13916.234607096707, 15530.767038494432, 15800.446984355493, 291688.0831191661, 330691.0, 332976.67023329454, 333297.0, 334192.32903835323, 54446.72776636091, 56073.0, 56191.78561607896, 56412.0, 58661.70669146627, 1372.7048684532874, 1475.4909556203309, 1480.9555713328618, 1517.7495713207418, 1523.2155439948435, 29102.27244318909, 29484.705152949053, 29726.405471890874, 30003.54994675038, 30158.095257142322, 38323.425148622235, 38377.80811095927, 38442.615573845025, 38666.42000370053, 38691.613083868804, 292196.0, 293441.0, 294467.0, 294933.0, 295655.0, 33999.32001359909, 37104.81447592745, 37135.81432092822, 37245.882623521335, 37625.81187094047, 48066.75966620145, 48085.75957120192, 48085.797855053905, 48206.5179348202, 48637.51362486471, 31917.042488725376, 32191.35617287688, 32889.67110328866, 33043.008709738744, 33406.83296583599, 32127.196820079684, 32313.838430807697, 32851.0, 33006.33987320288, 33008.0, 39354.60645393624, 39624.603753963245, 39658.603413966644, 39731.80134099311, 39931.0, 28038.859805701657, 31569.526457102704, 31931.840340798146, 32498.0, 32582.34835303328, 22138.55722885501, 24623.261302160965, 25274.36814079663, 25776.84004219777, 26029.349266268495], "value": 17837.663426145427, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.5920750335148, 429.92691242488775, 440.1702790240397, 440.74877319927646, 455.7129008724503, 21176.364709058733, 21574.784252157493, 21707.782922170813, 21760.0, 22437.3268801936, 21583.568328633457, 22128.22551210708, 23367.71477568733, 23513.882430587866, 23793.643095353542, 5676.347220069693, 5704.201411802351, 5797.536197104221, 6216.658083805389, 6247.718852651642, 5521.806736764214, 5909.4090590941, 6167.969160154193, 6168.969155154218, 6173.228346456693, 5885.558583106263, 6024.728887200086, 6096.542759293048, 6101.816945491642, 6138.894998900196, 6039.365866584011, 6106.786262480792, 6115.724792384353, 6116.724747386356, 6124.846878828021, 5368.0, 5378.273933019032, 5417.295751552312, 5446.183072539111, 5448.45515448456, 23521.29436116919, 26813.731862681318, 26932.59601105985, 26946.865265673547, 27110.728892711017, 2253.9436514087115, 2300.4363930836885, 2314.155333303332, 2316.4440534271653, 2322.175627652176, 18158.0, 18167.636647267114, 18368.173432195585, 18526.907365463223, 18779.53051173718, 2183.748868880083, 2201.317591546626, 2258.3337915315024, 2259.9322020339414, 2260.242818655752, 105753.0, 131993.0, 132104.99999999904, 134192.0, 134517.0, 12528.373581320897, 12821.423035963404, 12828.80756788649, 12830.16603920742, 12993.610191694264, 16824.242909069122, 16877.240524176443, 16907.07011114376, 16947.067911264752, 16953.745693814602, 7812.921870781333, 8570.571471426403, 8887.066857979948, 8924.553772311489, 8936.32977526688, 8511.65953361871, 8518.275946544612, 8530.573471326408, 8659.52372619512, 8664.8700269496, 8045.637946292314, 8169.978752655918, 8186.631601577943, 8202.917970820336, 8229.753107406787, 1791.3013924569427, 2053.849844087286, 2212.8008479236623, 2279.760625134337, 2431.4529230923254, 937.8358787212211, 969.0793745941243, 975.9609615615437, 1009.9495025248708, 1016.5933626549291, 20288.898555507127, 21599.892000539898, 21602.13591456324, 21608.891955540435, 21856.89071554632, 986.0829428631298, 986.6891929042329, 1004.4726518577659, 1015.918726501878, 1019.9439030853226, 10179.083882450615, 10824.0, 11131.777364452666, 11135.721606959729, 11139.721506962229, 496145.0, 498008.0, 560120.0, 567252.0, 584169.0, 103703.44444833334, 126951.0, 132261.0, 132654.0, 133218.0, 1131.6774719204986, 1158.8514971345912, 1197.8263151843107, 1207.939603019828, 1210.3101232297506, 22091.226807061677, 22230.333090007327, 22268.777312227317, 22474.101035959033, 22533.98597063169, 51663.74168129135, 52344.47655523396, 53148.0, 53693.463065368844, 54248.0, 73782.26217737969, 74221.6288918552, 74689.6265518669, 75061.11858254805, 75302.71670116173, 8654.913450865412, 8836.823263534821, 8913.688020919497, 9076.729257903768, 9157.542122893961, 13789.448422063406, 14469.638259043188, 14471.49349772753, 14713.411463541339, 14930.552083437515, 12070.517179312728, 12093.153479256367, 12186.51253949832, 12351.629451116482, 12399.938000309941, 9781.462019588991, 10928.235023548275, 11252.66242012741, 11581.478833452345, 11669.106443999615, 12173.817392738938, 12297.754044919231, 12391.380430978596, 12522.624321270376, 12582.05634577392, 9040.547972601475, 9051.59267832936, 9076.41003334781, 9123.680671176477, 9202.677906273248, 10873.78252434931, 10954.835677464685, 11041.227114102257, 11058.170637202082, 11110.722231944266], "value": 10450.921914392227, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.4840783114166, 1420.502824011596, 1429.778384350426, 1444.5594093801392, 1470.1399681186501, 10605.204609654269, 11528.481218345174, 11921.642350729471, 12037.157398982064, 12038.458269377878, 10092.24308176886, 11952.402379881014, 12080.275183488997, 12086.395680215997, 12134.332611706353, 2031.2687432524317, 2526.9241922742253, 2540.2379286214154, 2541.8983240670445, 2545.1982625472915, 2465.2255132209243, 2479.925602231936, 2533.8606376649277, 2544.5419824431597, 2551.0306083688174, 2505.5991041433376, 2507.3480894967347, 2531.354504601324, 2546.5161619292367, 2548.0826902315152, 2447.424855159045, 2506.8119891008155, 2526.810489213307, 2533.5439620868237, 2541.186820217531, 2438.5976313908186, 2527.0776166699206, 2531.354504601315, 2537.479816637585, 2554.4891021795715, 11840.881591184065, 11909.821352679717, 11999.460024298927, 12000.63998080059, 12013.279203247834, 5974.834907193099, 6628.635425051621, 6904.275051119661, 6921.342472465108, 6923.930760692429, 4151.27352713274, 4249.68127390447, 4344.543822898581, 4416.205083085043, 4427.690061695683, 1169.8771628979005, 1195.3664557784389, 1201.9338936358497, 1203.9036877049903, 1266.4169787765293, 15819.60450988723, 16415.42546010884, 16664.16679166037, 16752.748708769377, 16780.077095759727, 5087.847364579068, 5429.891402171935, 5773.191753154561, 5830.154627579062, 6154.476869466145, 13884.305784710725, 13932.512362067482, 13999.230042347768, 13999.720005600035, 14033.298335083206, 6877.552959057645, 6891.827704307432, 6926.238113807477, 6941.201761797458, 6969.54697944632, 6085.482733967662, 6292.8426789329715, 6300.621962682254, 6354.332795056453, 6362.522810789209, 4577.748223847654, 4738.360321356642, 4744.098621261949, 4791.592714619227, 4793.161196790547, 1343.9529616463572, 1435.0815478094028, 1466.4647403697575, 1484.814398200225, 1515.9317830697646, 795.0, 800.2077942836667, 836.381078002274, 837.0917554453365, 863.2403484933219, 4658.720476771405, 5701.572382071361, 6068.423499767515, 6103.42017508336, 6478.384553467413, 477.8375352380138, 591.4735885062298, 603.3212635784743, 605.6426708242159, 620.8230654263514, 3428.468587368962, 3629.528161339009, 3655.4151335786146, 4314.460692413449, 4382.320740285261, 11058.944705276583, 15062.924685376503, 15108.54674359771, 15174.848251517564, 15201.923990380199, 14508.492202772852, 15078.849211507744, 15121.848781512264, 15143.668392953532, 15151.848481515264, 7522.811929701911, 8581.95709021451, 8819.206271435602, 8856.64573417069, 8860.556972151368, 6559.484889562244, 7182.2099569048405, 7243.855122897618, 7246.673899674633, 7271.381932535794, 11415.771684566096, 12955.611331660066, 13263.0, 13279.269640169498, 13657.044006919421, 13351.465941362625, 13595.86404135946, 13652.726945461234, 13654.726905462034, 13685.589432317047, 9373.906260937303, 9658.806823863342, 9664.9033509664, 9669.70990870275, 9674.516274186404, 10630.124990048515, 10636.14910807118, 10639.574417023232, 10698.83951740709, 10729.409882456539, 9141.314401419788, 9152.496612686366, 9164.725058248263, 9186.724398268063, 9329.906700933176, 8894.644214231359, 8939.374243803131, 8939.687110951085, 8950.328725345755, 8972.057933917105, 8141.674333026613, 8242.340612751086, 8257.0, 8272.17278272168, 8288.378371622273, 6018.0, 6083.6349819011, 6144.907826382519, 6182.876342473035, 6184.226971628546, 6054.545909056927, 6086.087086936995, 6168.35232300602, 6271.843203919756, 6343.143675603735], "value": 5377.574250842558, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.4878034165413, 401.6886912642702, 404.5933242101505, 406.5548224693961, 407.91025974285645, 6271.404216599427, 6286.842828929279, 6298.9921612542, 6339.587926784761, 6340.985442329228, 4082.5305089914596, 5222.903190330026, 6085.300190478097, 6099.298580663225, 6154.784582539612, 4648.74431906245, 4679.578837904596, 4694.624430045606, 4695.3191787190835, 4704.694194877339, 4690.484046754854, 4709.740964246965, 4717.207461164752, 4720.952790472085, 4730.763461826912, 3888.105735680795, 4116.691248156385, 4285.0, 4475.597196252336, 4659.949622166236, 4188.727732697395, 4628.884277893063, 4669.229577119771, 4673.415823022122, 4689.343491911135, 3607.3687104756564, 3786.7159963002887, 4053.027273454379, 4161.979190104061, 4174.853880114212, 5583.860403489945, 5751.4536119068625, 5893.292804863401, 5970.522358211333, 6015.909761353583, 5507.394186639466, 5519.39286678465, 5525.585581081395, 5527.640703354309, 5538.870538145975, 6330.241114764376, 6331.366863313678, 6596.769113081067, 6600.976848588476, 6601.207855057376, 4656.278276867091, 4704.929426058612, 4711.293306004127, 4713.858584242478, 4724.527547245282, 5654.830355089354, 6740.224874139488, 6805.931940680629, 6829.760958366433, 6972.363378353389, 4419.712718673276, 4468.262736648417, 4474.373587697725, 4481.126180394792, 4507.4365704286965, 3634.9276963295893, 3684.1526448916898, 4384.013596940663, 4547.886302842456, 4558.726476411426, 4428.82284708608, 4528.547145285445, 5187.4553171916405, 5351.812686555952, 5358.624896257303, 5154.252633368141, 5614.708617018108, 6401.327860574573, 6568.540202185896, 6572.0, 1530.2348825587205, 1534.96162595936, 1545.8995165314218, 1547.5125335519276, 1551.7827504149427, 960.7165886063628, 1019.1337363241186, 1022.262154337629, 1025.3847691385258, 1038.1746511523356, 6265.530085243625, 6265.71804268809, 6275.780347687809, 6287.622742635456, 6341.23905131378, 6808.148981377328, 6808.557443766139, 6815.114035175495, 6836.282190370039, 6863.897041544381, 4393.780310984438, 5237.895242095213, 6140.570160088841, 6179.752809887555, 6270.874582508415, 2723.836569805818, 3448.20691241016, 3474.183566861836, 3502.2470168913806, 3576.946345804815, 6816.670749203957, 6826.965865170741, 6938.340857618518, 6955.130608673916, 7034.045700805674, 5485.945140548623, 5843.503302219274, 6224.0, 7014.684339204748, 7023.719051237996, 6832.996178916584, 6879.690413931385, 7059.82350441243, 7087.468439866928, 7148.749793757193, 6018.0, 6383.457406120532, 6489.896717558121, 6843.076184715199, 6940.9652951734915, 6460.935390646034, 6483.546151769331, 6547.166793297884, 6748.93251067483, 6782.220044694726, 6519.445847103049, 6866.382025617719, 6885.449164066963, 7050.224475307609, 7125.5821390736, 4463.419755431773, 4464.732116073046, 4467.7319360838455, 4491.685582009228, 4502.954970450384, 6287.648155646558, 6446.7098980546925, 6862.9313706862295, 6937.653117344012, 6947.4789390796905, 6515.804525864042, 6544.574602650812, 6589.242237142598, 6597.010448432773, 6608.867822643424, 5977.3424923257535, 6049.51603871698, 6309.012661011767, 6610.3720146586, 6647.368499992493, 5653.802116925888, 6413.7113829876835, 6454.0641606967665, 6460.3862633049785, 6516.771912983023, 5957.851053723692, 6429.903551446826, 6535.248446428532, 6562.573432726857, 6597.0, 5576.693281869536, 6245.344238854855, 6354.23749150105, 6372.299047104722, 6426.935730642634], "value": 4792.068574670737, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.160688515258, 718.1059580821876, 737.2848337112998, 820.6635279535392, 828.3662997806679, 13693.315334233273, 14362.712745745104, 15943.840561594381, 16879.57801054973, 16965.06692131934, 13048.412821423035, 13654.112482688643, 16948.0, 16959.236834342453, 16993.83006169938, 754.6905768634861, 921.9723408297729, 934.0612684252317, 946.7491114854569, 946.7917058247209, 819.971301004465, 958.6548842416724, 958.6836344006462, 960.135877710063, 962.2350231565887, 1003.3528374198637, 1007.2948935744971, 1007.9596816127346, 1009.6415772400802, 1011.3982180602529, 976.5800705696583, 978.3102912446708, 981.7251169672503, 986.3736527305141, 1002.9247806414548, 719.5071376107384, 724.478375569589, 725.4305370284302, 725.5900416264793, 726.3245181980756, 14747.672709456094, 15744.685106297924, 16179.514614561582, 16475.91762041194, 16658.0, 16465.176741162893, 16543.0, 16565.83434165667, 16858.409955651492, 16859.325626974907, 13648.181109133484, 13770.65573360664, 13860.722785544232, 13932.303384830819, 13935.442582296797, 3126.249700071989, 3385.3567822113964, 3386.0, 3423.1271025888486, 3426.8286585670867, 117708.82291177064, 118437.0, 119462.0, 119947.40026299813, 120913.39543302315, 10854.45727713611, 11187.105031597453, 12679.683007924876, 13149.60551183466, 13259.801102983463, 23411.414714632272, 23805.642915356282, 24177.51644967126, 24224.0, 24355.0, 7633.809154771176, 7649.196834332426, 7752.5348479091435, 7769.223077692185, 7774.105977812511, 3799.3161230978135, 3866.2267546490816, 3884.6115388461494, 3894.7078969077434, 3906.7655940643654, 4212.283911734951, 4688.882777930579, 4797.2564252540915, 4850.296706977469, 4868.9269660955115, 937.5265490927175, 968.2109081098882, 970.5729479029204, 974.5565767575736, 975.3660120921458, 636.4812677667778, 646.5538778242998, 666.4967949198324, 668.2448832818884, 677.2381071294793, 9902.0, 11262.0, 12131.33277669719, 12144.939275303743, 12173.147879648339, 655.5476721062452, 669.1802541886248, 671.0337114555123, 672.9360710732473, 687.7214728035153, 5269.314989051399, 5448.134497703354, 5555.2500412444615, 5673.609965558465, 5731.541476681856, 71573.0, 97209.0, 99628.0, 100069.0, 100926.49536752269, 88552.55723721469, 89215.10784892068, 89953.55023224842, 90004.54997725101, 91050.0, 70021.0, 76579.08552286237, 76869.07827304362, 76869.23130768733, 77003.99999999888, 11161.49773260189, 11362.829557556477, 11380.658580242605, 11429.053768886692, 11487.655370338904, 39147.80426097851, 39156.0, 39254.0, 39462.40806387964, 39489.60510394859, 50486.16492113792, 61510.0, 62045.0, 62546.0, 63191.052134217105, 16308.184590770652, 16310.673786524441, 17132.229049692545, 17175.312987480862, 17211.31154753796, 17896.55258618545, 17947.820521795136, 18414.0, 18583.163757630668, 18824.623507530046, 16545.338186472407, 16954.406595769087, 17070.061146637054, 17165.31338746436, 17177.31290748406, 13592.388342524808, 13899.652508687364, 14726.337314820645, 17036.574085647957, 17170.484885453458, 15895.0, 15944.043357398594, 16178.757318640466, 16459.423920162735, 16670.0, 11393.487293071998, 11774.0, 11850.76298474043, 11995.280283183038, 12082.818757718816, 12807.871921280668, 13266.27035513056, 13461.057725959088, 13678.283032325366, 13819.792703109662], "value": 8151.259820520647, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1513.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 115.0, 116.0, 116.0, 116.0, 122.0, 162.0, 167.0, 114.0], "value": 124.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 115.0, 149.0, 114.0, 115.0, 121.0, 130.0, 160.0, 149.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 209.0, 207.0, 227.0, 198.0, 199.0, 198.0, 197.0, 200.0, 196.0], "value": 203.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 57.0, 59.0, 87.0, 58.0, 59.0, 58.0, 62.0, 88.0, 89.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 84.0, 74.0, 72.0, 70.0, 89.0, 69.0, 71.0, 91.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 90.0, 90.0, 90.0, 91.0, 91.0, 89.0, 90.0, 90.0], "value": 90.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 97.0, 132.0, 109.0, 99.0, 97.0, 109.0, 98.0], "value": 103.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 89.0, 92.0, 92.0, 89.0, 91.0, 90.0, 91.0, 89.0], "value": 91.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 51.0, 56.0, 81.0, 78.0, 78.0, 56.0, 51.0, 50.0, 50.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 60.0, 56.0, 57.0, 61.0, 60.0, 56.0, 56.0, 59.0, 56.0], "value": 57.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 82.0, 85.0, 83.0, 83.0, 83.0, 80.0, 89.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 138.0, 136.0, 153.0, 135.0, 144.0, 155.0, 160.0, 192.0, 146.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 180.0, 227.0, 166.0, 216.0, 190.0, 237.0, 245.0, 187.0, 185.0], "value": 201.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 68.0, 69.0, 68.0, 68.0, 72.0, 71.0, 67.0, 63.0], "value": 68.4, "unit": "score"}]}]} 17:03:35 INFO - Return code: 0 17:03:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:03:35 ERROR - # TBPL SUCCESS # 17:03:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:03:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:03:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:03:35 INFO - 2017-04-20 17:03:35.335 screenresolution[1659:10588] starting screenresolution argv=screenresolution get 17:03:35 INFO - 2017-04-20 17:03:35.338 screenresolution[1659:10588] Display 0: 1600x1200x32@60 17:03:35 INFO - 2017-04-20 17:03:35.345 screenresolution[1660:10591] starting screenresolution argv=screenresolution list 17:03:35 INFO - Available Modes on Display 0 17:03:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:03:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:03:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:03:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:03:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:03:35 INFO - Intel Iris: 17:03:35 INFO - Chipset Model: Intel Iris 17:03:35 INFO - Type: GPU 17:03:35 INFO - Bus: Built-In 17:03:35 INFO - VRAM (Dynamic, Max): 1536 MB 17:03:35 INFO - Vendor: Intel (0x8086) 17:03:35 INFO - Device ID: 0x0a2e 17:03:35 INFO - Revision ID: 0x0009 17:03:35 INFO - Displays: 17:03:35 INFO - Display: 17:03:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:03:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:03:35 INFO - Display Serial Number: 1600x1200 60 17:03:35 INFO - Main Display: Yes 17:03:35 INFO - Mirror: Off 17:03:35 INFO - Online: Yes 17:03:35 INFO - Rotation: Supported 17:03:35 INFO - Return code: 0 17:03:35 INFO - Running post-action listener: _resource_record_post_action 17:03:35 INFO - [mozharness: 2017-04-21 00:03:35.592387Z] Finished run-tests step (success) 17:03:35 INFO - Running post-run listener: _resource_record_post_run 17:03:35 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 39438848; Write bytes: 516002816; Read time: 252; Write time: 1643 17:03:35 INFO - TinderboxPrint: CPU usage
54.1% 17:03:35 INFO - TinderboxPrint: I/O read bytes / time
39,438,848 / 252 17:03:35 INFO - TinderboxPrint: I/O write bytes / time
516,002,816 / 1,643 17:03:35 INFO - TinderboxPrint: CPU idle
2,198.6 (45.9%) 17:03:35 INFO - TinderboxPrint: CPU system
961.2 (20.1%) 17:03:35 INFO - TinderboxPrint: CPU user
1,629.9 (34.0%) 17:03:35 INFO - TinderboxPrint: Swap in / out
247,541,760 / 0 17:03:35 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207651328; Write bytes: 206010368; Read time: 15909; Write time: 444 17:03:35 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35437056; Write bytes: 302763008; Read time: 226; Write time: 1169 17:03:36 INFO - Running post-run listener: _upload_blobber_files 17:03:36 INFO - Blob upload gear active. 17:03:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:03:36 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:03:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:03:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:03:36 INFO - (blobuploader) - INFO - Open directory for files ... 17:03:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:03:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:03:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:03:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:03:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:03:37 INFO - (blobuploader) - INFO - Done attempting. 17:03:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:03:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:03:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:03:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:03:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:03:38 INFO - (blobuploader) - INFO - Done attempting. 17:03:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:03:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:03:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:03:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:03:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:03:39 INFO - (blobuploader) - INFO - Done attempting. 17:03:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:03:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:03:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:03:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:03:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:03:41 INFO - (blobuploader) - INFO - Done attempting. 17:03:41 INFO - (blobuploader) - INFO - Iteration through files over. 17:03:41 INFO - Return code: 0 17:03:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:03:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:03:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b246f7d57d746b8de1f41226985cc1f9c9ce81ca39855a78d98ecf616ead4b5a3779e3d4f2698c07437cf79986ec30cfa4b8326e9ae20ca52c4cf119dab1592d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9278798ecb71b12d56a803c81f6a7b419a95a60accc8957233d877652016b0903b4db8305a8d63b22a6ac0dde09ff071da105bac8d9995d690719eea97e6ae24", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/37784b1a07d527d2c306ebb051d55caf2715f8bf40e5f767b89276e129ca8649a8fd1f0115a8d408b7de38123ce7a59f7f3b4ad7a66773bb3c53a83d34e362fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c48e40fec0ebd0eb5b7ca92671bdc16627cba2d0ad22fc0288adf0ab7d9182343b4c4cf3ea68b4bea24386ef963ad9af3598ea1f7400b2efda91a428f076940c"} 17:03:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:03:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:03:41 INFO - Contents: 17:03:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b246f7d57d746b8de1f41226985cc1f9c9ce81ca39855a78d98ecf616ead4b5a3779e3d4f2698c07437cf79986ec30cfa4b8326e9ae20ca52c4cf119dab1592d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9278798ecb71b12d56a803c81f6a7b419a95a60accc8957233d877652016b0903b4db8305a8d63b22a6ac0dde09ff071da105bac8d9995d690719eea97e6ae24", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/37784b1a07d527d2c306ebb051d55caf2715f8bf40e5f767b89276e129ca8649a8fd1f0115a8d408b7de38123ce7a59f7f3b4ad7a66773bb3c53a83d34e362fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c48e40fec0ebd0eb5b7ca92671bdc16627cba2d0ad22fc0288adf0ab7d9182343b4c4cf3ea68b4bea24386ef963ad9af3598ea1f7400b2efda91a428f076940c"} 17:03:41 INFO - Running post-run listener: copy_logs_to_upload_dir 17:03:41 INFO - Copying logs to upload dir... 17:03:41 INFO - mkdir: /builds/slave/test/build/upload/logs 17:03:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.524662 ========= master_lag: 0.23 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-20 17:03:42.168646) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 17:03:42.172492) ========= 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.UtVJn4mFEb/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.uR3wQunLer/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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/Pine/sha512/b246f7d57d746b8de1f41226985cc1f9c9ce81ca39855a78d98ecf616ead4b5a3779e3d4f2698c07437cf79986ec30cfa4b8326e9ae20ca52c4cf119dab1592d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9278798ecb71b12d56a803c81f6a7b419a95a60accc8957233d877652016b0903b4db8305a8d63b22a6ac0dde09ff071da105bac8d9995d690719eea97e6ae24", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/37784b1a07d527d2c306ebb051d55caf2715f8bf40e5f767b89276e129ca8649a8fd1f0115a8d408b7de38123ce7a59f7f3b4ad7a66773bb3c53a83d34e362fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c48e40fec0ebd0eb5b7ca92671bdc16627cba2d0ad22fc0288adf0ab7d9182343b4c4cf3ea68b4bea24386ef963ad9af3598ea1f7400b2efda91a428f076940c"} build_url:https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010994 build_url: 'https://queue.taskcluster.net/v1/task/E8PfVeWES2W7mYw7AYIkbQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b246f7d57d746b8de1f41226985cc1f9c9ce81ca39855a78d98ecf616ead4b5a3779e3d4f2698c07437cf79986ec30cfa4b8326e9ae20ca52c4cf119dab1592d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9278798ecb71b12d56a803c81f6a7b419a95a60accc8957233d877652016b0903b4db8305a8d63b22a6ac0dde09ff071da105bac8d9995d690719eea97e6ae24", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/37784b1a07d527d2c306ebb051d55caf2715f8bf40e5f767b89276e129ca8649a8fd1f0115a8d408b7de38123ce7a59f7f3b4ad7a66773bb3c53a83d34e362fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c48e40fec0ebd0eb5b7ca92671bdc16627cba2d0ad22fc0288adf0ab7d9182343b4c4cf3ea68b4bea24386ef963ad9af3598ea1f7400b2efda91a428f076940c"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 17:03:42.220000) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2017-04-20 17:03:42.220538) ========= 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.UtVJn4mFEb/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.uR3wQunLer/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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.005055 ========= master_lag: 2.77 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2017-04-20 17:03:44.992036) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 17:03:44.996411) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 17:03:45.002191) ========= ========= Total master_lag: 3.74 =========