From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: X-Spam-Checker-Version: SpamAssassin 3.4.0 (2014-02-07) on aws-us-west-2-korg-lkml-1.web.codeaurora.org X-Spam-Level: X-Spam-Status: No, score=-8.4 required=3.0 tests=DKIMWL_WL_MED,DKIM_SIGNED, DKIM_VALID,DKIM_VALID_AU,HEADER_FROM_DIFFERENT_DOMAINS,MAILING_LIST_MULTI, SPF_HELO_NONE,SPF_PASS,URIBL_BLOCKED,USER_IN_DEF_DKIM_WL autolearn=no autolearn_force=no version=3.4.0 Received: from mail.kernel.org (mail.kernel.org [198.145.29.99]) by smtp.lore.kernel.org (Postfix) with ESMTP id D0513C19759 for ; Thu, 1 Aug 2019 18:55:43 +0000 (UTC) Received: from vger.kernel.org (vger.kernel.org [209.132.180.67]) by mail.kernel.org (Postfix) with ESMTP id A8D5B20838 for ; Thu, 1 Aug 2019 18:55:43 +0000 (UTC) Authentication-Results: mail.kernel.org; dkim=pass (2048-bit key) header.d=google.com header.i=@google.com header.b="AXEJGSbz" Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S2387921AbfHASzm (ORCPT ); Thu, 1 Aug 2019 14:55:42 -0400 Received: from mail-pf1-f195.google.com ([209.85.210.195]:33617 "EHLO mail-pf1-f195.google.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S2387912AbfHASzd (ORCPT ); Thu, 1 Aug 2019 14:55:33 -0400 Received: by mail-pf1-f195.google.com with SMTP id g2so34594509pfq.0 for ; Thu, 01 Aug 2019 11:55:32 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=google.com; s=20161025; h=mime-version:references:in-reply-to:from:date:message-id:subject:to :cc; bh=yanPVyyCo7aV5PHJPTHnUF5WwSupWh42aAlO/2TU4qM=; b=AXEJGSbzy2nisZCuQomwcAJjsmAS3tsN47D57OouJ7agEzm6inwYRVuQHNR+bPjtqR 9UzQ05+qRlUInKBsk4vQH8hvJVlwufmneBdZeObqoysE3ZQd/BS43SlI+a070qUA+GFP zGdObxqzuaBUkQi+613lUJ+GcPdnTPQ8max5aW44qTMSrTr7ootlST/1JXY0DWLChkPU ZltkXhvybDqjkXR6mrxErttgqxF4cTX9l2/i4pArlHsqyX8ykeUJsS6FZqsYTc1h6k5J Bwnn1W+citAhlj45JYHcp8CJHpN/h+1KGAAyVB5oHCOQgrPo5/XgonZSlSrX+PfHOS4i YaJw== X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20161025; h=x-gm-message-state:mime-version:references:in-reply-to:from:date :message-id:subject:to:cc; bh=yanPVyyCo7aV5PHJPTHnUF5WwSupWh42aAlO/2TU4qM=; b=rWFmNW7MChs14Dc8Vrz5tPxysixYnyClKgNMiHxGfgG4AmhDVPwrs10Gs1qtQhdWrQ FVNBbRq/Wq2edRWRpbyLeK9JhUy/1ySRhSk0XHOcqhmEMgvgE8fesGYBijDXDSmBgzBO A+3rAbKZFc2dAhIOGwTqqo71ZrhWtvYpDix+nLRlL4AunqrLJMCBP6BzWlpgYa9nbHlV 0h6d8eVJuVm++WORlHFazYyEH+kSUgtqMFn+4E11xq7UebijDhQLZQzBH03ttiUIZLWR OwDa0RgvX8QJzzxswM+FO9UnPAGZ9D8Qa7ChwVH0Xw07bXo77+dAnzRxAHmCMXKgYBWT 2sRw== X-Gm-Message-State: APjAAAWp6jqJMAGg1iKN6v28U8pRvARQM73neMrazYAPLqkii7e5YvwB atd5/3aQ+CDURupbK7v5IF4p0GtghvGqvSlpjzUNzQ== X-Google-Smtp-Source: APXvYqzoFLt8JjfyNttcO+f/0oII0xixTgSXhZRfLC4Blo6PZ1XHxN/CDmHW1v1/KBomRNqbKOVo9+SEvV2fnQP9DnA= X-Received: by 2002:aa7:81ca:: with SMTP id c10mr55681458pfn.185.1564685731859; Thu, 01 Aug 2019 11:55:31 -0700 (PDT) MIME-Version: 1.0 References: <20190716175021.9CA412173C@mail.kernel.org> <20190718175024.C3EC421019@mail.kernel.org> <20190719000834.GA3228@google.com> <20190722200347.261D3218C9@mail.kernel.org> <20190722235411.06C1320840@mail.kernel.org> <20190724073125.xyzfywctrcvg6fmh@pathway.suse.cz> <20190726083148.d4gf57w2nt5k7t6n@pathway.suse.cz> In-Reply-To: <20190726083148.d4gf57w2nt5k7t6n@pathway.suse.cz> From: Brendan Higgins Date: Thu, 1 Aug 2019 11:55:20 -0700 Message-ID: Subject: Re: [PATCH v9 04/18] kunit: test: add kunit_stream a std::stream like logger To: Petr Mladek Cc: Jeff Dike , Kevin Hilman , Logan Gunthorpe , Michael Ellerman , Daniel Vetter , Amir Goldstein , Frank Rowand , Steven Rostedt , Kees Cook , David Rientjes , kunit-dev@googlegroups.com, Kieran Bingham , Peter Zijlstra , Randy Dunlap , Joel Stanley , Luis Chamberlain , Rob Herring , Stephen Boyd , shuah , wfg@linux.intel.com, Greg KH , Julia Lawall , linux-nvdimm , dri-devel , linux-um@lists.infradead.org, Sasha Levin , "Theodore Ts'o" , Richard Weinberger , Dan Carpenter , Knut Omang , Josh Poimboeuf , Masahiro Yamada , Timothy Bird , John Ogness , devicetree , "open list:DOCUMENTATION" , linux-fsdevel@vger.kernel.org, linux-kbuild , Linux Kernel Mailing List , "open list:KERNEL SELFTEST FRAMEWORK" Content-Type: text/plain; charset="UTF-8" Sender: linux-kselftest-owner@vger.kernel.org Precedence: bulk List-ID: X-Mailing-List: linux-kselftest@vger.kernel.org On Fri, Jul 26, 2019 at 1:31 AM Petr Mladek wrote: > > On Thu 2019-07-25 13:21:12, Brendan Higgins wrote: > > On Wed, Jul 24, 2019 at 12:31 AM Petr Mladek wrote: > > > > > > On Mon 2019-07-22 16:54:10, Stephen Boyd wrote: > > > > Quoting Brendan Higgins (2019-07-22 15:30:49) > > > > > On Mon, Jul 22, 2019 at 1:03 PM Stephen Boyd wrote: > > > > > > > > > > > > > > > > > > What's the calling context of the assertions and expectations? I still > > > > > > don't like the fact that string stream needs to allocate buffers and > > > > > > throw them into a list somewhere because the calling context matters > > > > > > there. > > > > > > > > > > The calling context is the same as before, which is anywhere. > > > > > > > > Ok. That's concerning then. > > > > > > > > > > > > > > > I'd prefer we just wrote directly to the console/log via printk > > > > > > instead. That way things are simple because we use the existing > > > > > > buffering path of printk, but maybe there's some benefit to the string > > > > > > stream that I don't see? Right now it looks like it builds a string and > > > > > > then dumps it to printk so I'm sort of lost what the benefit is over > > > > > > just writing directly with printk. > > > > > > > > > > It's just buffering it so the whole string gets printed uninterrupted. > > > > > If we were to print out piecemeal to printk, couldn't we have another > > > > > call to printk come in causing it to garble the KUnit message we are > > > > > in the middle of printing? > > > > > > > > Yes, printing piecemeal by calling printk many times could lead to > > > > interleaving of messages if something else comes in such as an interrupt > > > > printing something. Printk has some support to hold "records" but I'm > > > > not sure how that would work here because KERN_CONT talks about only > > > > being used early on in boot code. I haven't looked at printk in detail > > > > though so maybe I'm all wrong and KERN_CONT just works? > > > > > > KERN_CONT does not guarantee that the message will get printed > > > together. The pieces get interleaved with messages printed in > > > parallel. > > > > > > Note that KERN_CONT was originally really meant to be used only during > > > boot. It was later used more widely and ended in the best effort category. > > > > > > There were several attempts to make it more reliable. But it was > > > always either too complicated or error prone or both. > > > > > > You need to use your own buffering if you rely want perfect output. > > > The question is if it is really worth the complexity. Also note that > > > any buffering reduces the chance that the messages will reach > > > the console. > > > > Seems like that settles it then. Thanks! > > > > > BTW: There is a work in progress on a lockless printk ring buffer. > > > It will make printk() more secure regarding deadlocks. But it might > > > make transparent handling of continuous lines even more tricky. > > > > > > I guess that local buffering, before calling printk(), will be > > > even more important then. Well, it might really force us to create > > > an API for it. > > > > Cool! Can you CC me on that discussion? > > Adding John Oggness into CC. > > John, please CC Brendan Higgins on the patchsets eventually switching > printk() into the lockless buffer. The test framework is going to > do its own buffering to keep the related messages together. > > The lockless ringbuffer might make handling of related (partial) > lines worse or better. It might justify KUnit's extra buffering > or it might allow to get rid of it. Thanks for CC'ing me on the printk ringbuffer thread. It looks like it actually probably won't affect my needs for KUnit logging. The biggest reason I need some sort of buffering system is to be able to compose messages piece meal into a single message that will be printed out to the user as a single message with no messages from other printk callers printed out in the middle of mine. The prb does look interesting; however, it appears that to get the semantics that I need, I would have to put my entire message in a single data block and would consequently need to know the size of my message a priori, which is problematic. Consequently, it seems as though I will probably need to compose my entire message using my own buffering system. > > > Note that stroring the messages into the printk log is basically safe in any > > > context. It uses temporary per-CPU buffers for recursive messages and > > > in NMI. The only problem is panic() when some CPU gets stuck with the > > > lock taken. This will get solved by the lockless ringbuffer. Also > > > the temporary buffers will not be necessary any longer. > > > > Sure, I think Stephen's concern is all the supporting code that is > > involved. Not printk specifically. It just means a lot more of KUnit > > has to be IRQ safe. > > I see. > > BTW: I wonder if KUnit could reuse the existing seq_buf implementation > for buffering messages. > > I am sorry if it has already been proposed and rejected for some > reason. I might have missed it. Feel free to just point me to > same older mail. Yeah, we discussed it briefly here: https://lkml.org/lkml/2019/5/17/497 Looks like I forgot to include my reasoning in the commit text, sorry about that. > > > Much bigger problems are with consoles. There are many of them. It > > > means a lot of code and more locks involved, including scheduler > > > locks. Note that console lock is a semaphore. > > > > That shouldn't affect us though, right? As long as we continue to use > > the printk interface? > > I guess that it should not affect KUnit. > > The only problem might be if the testing framework calls printk() > inside scheduler or console code. And only when the tested code > uses the same locks that will be used by the called printk(). Yeah, well printk will not be our only problem in those instances. > To be honest I do not fully understand KUnit design. I am not > completely sure how the tested code is isolated from the running > system. Namely, I do not know if the tested code shares > the same locks with the system running the test. No worries, I don't expect printk to be the hang up in those cases. It sounds like KUnit has a long way to evolve before printk is going to be a limitation. Thanks!