Groups | Search | Server Info | Keyboard shortcuts | Login | Register [http] [https] [nntp] [nntps]


Groups > linux.kernel > #1551579

Re: [PATCH 2/2] printk: always report lost messages on serial console

Path csiph.com!aioe.org!bofh.it!news.nic.it!robomod
From Sergey Senozhatsky <sergey.senozhatsky.work@gmail.com>
Newsgroups linux.kernel
Subject Re: [PATCH 2/2] printk: always report lost messages on serial console
Date Thu, 05 Jan 2017 03:40:01 +0100
Message-ID <sW689-4TF-5@gated-at.bofh.it> (permalink)
References <sRVkZ-7ZZ-1@gated-at.bofh.it> <sRVkZ-7ZZ-9@gated-at.bofh.it> <sVyJh-7Mp-19@gated-at.bofh.it> <sVzvz-8oo-17@gated-at.bofh.it> <sVAUF-16K-19@gated-at.bofh.it> <sVJOi-6Nw-23@gated-at.bofh.it> <sVRsu-3zH-31@gated-at.bofh.it> <sVTXj-5hJ-1@gated-at.bofh.it> <sVVFM-6tl-35@gated-at.bofh.it>
Dkim-Signature v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20161025; h=date:from:to:cc:subject:message-id:references:mime-version :content-disposition:in-reply-to:user-agent; bh=1r3NtD6EpYaa2MDR7mY4O8p6kzuaLy3AhVO1XYwyNQE=; b=flyyb0R21nBKg1BxDwni4+Yja/0OBYEdQ9VZQ4Y/ezSeO4L1ngs2u0SPar7xxElDrR MGOp+NH2hFN25EHCiWun5oMvDdrQRk7XIQxt59dzzNGyp1mpTyVIfXMc7w5Q/7gX/EqO pNolGQyXzeIsKbUvtvmJW1Fmqllc+ipy5zJpt9HaHdlo2PAO7aRT8doUDbbzx0AFIRN7 Gr+aMDmT23qCgnMgOADM+exmKLCvQEieL3Ru8FFulm5rDnZPsXTfLOY9CRlAC1FZYit2 VO9SbkmKDt/KzYW4GWvQuniJwVLPizxkzPHLV5Q1MJopnE4NTA17n0WUeM0vNNE/2Tr8 jl/g==
X-Google-Dkim-Signature v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20161025; h=x-gm-message-state:date:from:to:cc:subject:message-id:references :mime-version:content-disposition:in-reply-to:user-agent; bh=1r3NtD6EpYaa2MDR7mY4O8p6kzuaLy3AhVO1XYwyNQE=; b=LZV8sSirvmKG9pBcJyOLOdOyIlK/WiGvDldgu1zQzmQ+JvTwgr2fRBWOtWcyxIk1cb AryBk8mkAmb8XusGY8ZfRExRIgtyO84vJlSig2MXntK6Th/jN+o3kLnTk3siz2NRVV3K rutbp8N6C6fnnS4EiWEffAndJRu50cMEjEC0vlcO0QWPDn/2XBXXncK7y+3k9wq0JxBG fnNl+GU7I5BzX572eGhgOStovXdH5kHUPPcyLjjD2Edm8D4YU++GBWK5O3T12v/RhD4o Cjsufe8CtTk2CkPMrL5nqBxFDho0l2x3O7X1Db8MwMrubhrJVAKLOnUFb9e10oEDTzRL owJQ==
X-Gm-Message-State AIkVDXIQHu3Ss5hGnViqF6vxMllMWfndVWV5ppNlNPXYy63vqONbN9v3eRK/azI0vzLLRQ==
X-Received by 10.84.137.1 with SMTP id 1mr3335105plm.132.1483583438743; Wed, 04 Jan 2017 18:30:38 -0800 (PST)
MIME-Version 1.0
Content-Type text/plain; charset=us-ascii
Content-Disposition inline
User-Agent Mutt/1.7.2 (2016-11-26)
Sender robomod@news.nic.it
List-ID <linux-kernel.vger.kernel.org>
X-Mailing-List linux-kernel@vger.kernel.org
Approved robomod@news.nic.it
Lines 82
Organization linux.* mail to news gateway
X-Original-Cc Sergey Senozhatsky <sergey.senozhatsky@gmail.com>, Sergey Senozhatsky <sergey.senozhatsky.work@gmail.com>, Andrew Morton <akpm@linux-foundation.org>, Steven Rostedt <rostedt@goodmis.org>, Peter Hurley <peter@hurleysoftware.com>, linux-kernel@vger.kernel.org
X-Original-Date Thu, 5 Jan 2017 11:30:47 +0900
X-Original-Message-ID <20170105022706.GD480@jagdpanzerIV.localdomain>
X-Original-References <20161224140902.1962-1-sergey.senozhatsky@gmail.com> <20161224140902.1962-3-sergey.senozhatsky@gmail.com> <20170103145532.GL14894@pathway.suse.cz> <20170103154745.GA496@tigerII.localdomain> <20170103165356.GN14894@pathway.suse.cz> <20170104024649.GA2362@jagdpanzerIV.localdomain> <20170104105235.GP14894@pathway.suse.cz> <20170104133448.GA384@tigerII.localdomain> <20170104152627.GT14894@pathway.suse.cz>
X-Original-Sender linux-kernel-owner@vger.kernel.org
Xref csiph.com linux.kernel:1551579

Show key headers only | View raw


On (01/04/17 16:26), Petr Mladek wrote:
> On Wed 2017-01-04 22:34:48, Sergey Senozhatsky wrote:
> > On (01/04/17 11:52), Petr Mladek wrote:
> > [..]
> > > > this is from the real serial logs I'm looking at right now. we attach
> > > > "bad news" to 'critical' messages only:
> > > > 
> > > > ...
> > > > [   32.941061] bc00: b65dc0d8 b65dc6d0 ae1fbc7c b65c11c5 b563c9c4 00000001 b65dc6d0 b0a62f74
> > > > ** 150 printk messages dropped ** [   32.941614] cee0: 00000081 ae1fcef0 00000038 00000000 b5369000 ae1fcf00 ae1fd0f0 00000000
> > > > ** 75 printk messages dropped ** [   32.941892] d860: 00056608 af203848 00000000 0004088c 000000d0 00000000 00000000 00000000
> > > > ** 12 printk messages dropped ** [   32.941940] ..
> > > > ** 2 printk messages dropped ** [   32.941951] ..
> > > > ** 10 printk messages dropped ** [   32.941992] ..
> > > > ** 1 printk messages dropped ** [   32.941999] ..
> > > > ...
> 
> OK, it is possible that I miss-interpreted the message. It looked like
> a random memory dump that did not make sense without a context.
> 
> 
> > > Do you see how useless the above messages are, please?
> > 
> > what... these lost messages were of extreme importance. I can't tell
> > the exactly the loglevel, but I'm sure it was at least pr_err() level.
> > these were like really important messages, unlike the ones that got
> > suppressed/filtered-out.
> 
> It means that you were lucky and you saw critical messages instead
> of some random debugging ones.

this is funny. ok... let me tell you my version.
I saw an incomplete serial log with lost important messages. the serial
log was nothing but garbage. zero value. I could simply `rm screenlog.0'
it. end of story.

and no matter what loglevel we attach the "printk messages dropped"
to we always will lose XYZ important messages in the given circumstances.
and that "let's print 1 out of thousands lost critical messages, so the
serial log will make sense" is a little bit far from being true. sorry,
it is what it is.


> > and the options here are
> > 	"print 1 random message out of XXX or XXXX lost messages"
> > vs
> > 	"print 1 random message out of XXX or XXXX lost messages"
> 
> And this is not fully correct and probably the root of
> the misunderstanding. The difference between your patch
> and mine patch is:
> 
> 	"always print '%u printk messages dropped'" +
> 	"print 1 random message out of XXX or XXXX lost messages"
> 
> vs
> 
> 	"always print '%u printk messages dropped'" +
> 	"print 1 random message with level under console_level
> 	 out of XXX or XXXX lost messages"
> 
> and that's it. I am sorry if I was not able to explain this
> a more clear way.


... I understand what your patch is doing. see the serial log atop of
this message, this is from the 'attach "printk messages dropped to a
visible loglevel"' approach. what I don't understand is why do you
claim that it produces significantly more meaningful/useful serial logs.
because it does not. it produces the 'rm screenlog.0' material. we
can't protect/save/take care of/whatever the logbuf messages once we
unlock the logbuf lock. and the point is - for a guy who reads the
incomplete serial log 'print 1 random message out of XXX or XXXX lost
messages' is pretty much the same as 'print 1 random message of visible
loglevel out of XXX or XXXX lost messages'. because the really important
part here is 'you see 1 message out of XXXX', and there is no way to
reconstruct those XXXX lost messages, no matter how small the XXXX is:

[   x.xxxx] Call Trace:
** 9 printk messages dropped ** [   x.xxxx] ---[ end trace ]---

	-ss

Back to linux.kernel | Previous | Next — Previous in thread | Next in thread | Find similar | Unroll thread


Thread

Re: [PATCH 2/2] printk: always report lost messages on serial console Sergey Senozhatsky <sergey.senozhatsky.work@gmail.com> - 2017-01-04 03:50 +0100
  Re: [PATCH 2/2] printk: always report lost messages on serial console Petr Mladek <pmladek@suse.com> - 2017-01-04 12:00 +0100
    Re: [PATCH 2/2] printk: always report lost messages on serial console Sergey Senozhatsky <sergey.senozhatsky@gmail.com> - 2017-01-04 14:40 +0100
      Re: [PATCH 2/2] printk: always report lost messages on serial console Petr Mladek <pmladek@suse.com> - 2017-01-04 16:30 +0100
        Re: [PATCH 2/2] printk: always report lost messages on serial console Sergey Senozhatsky <sergey.senozhatsky.work@gmail.com> - 2017-01-05 03:40 +0100
          Re: [PATCH 2/2] printk: always report lost messages on serial console Petr Mladek <pmladek@suse.com> - 2017-01-05 12:20 +0100

csiph-web