From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: from lists.gentoo.org (pigeon.gentoo.org [208.92.234.80]) by finch.gentoo.org (Postfix) with ESMTP id E3E0D138A87 for ; Wed, 25 Feb 2015 22:31:01 +0000 (UTC) Received: from pigeon.gentoo.org (localhost [127.0.0.1]) by pigeon.gentoo.org (Postfix) with SMTP id D0BC0E085B; Wed, 25 Feb 2015 22:30:59 +0000 (UTC) Received: from mout.gmx.net (mout.gmx.net [212.227.15.19]) (using TLSv1.2 with cipher DHE-RSA-AES256-GCM-SHA384 (256/256 bits)) (No client certificate requested) by pigeon.gentoo.org (Postfix) with ESMTPS id 0A344E0830 for ; Wed, 25 Feb 2015 22:30:59 +0000 (UTC) Received: from marcec.fritz.box ([93.181.44.4]) by mail.gmx.com (mrgmx002) with ESMTPSA (Nemesis) id 0LuxG5-1XRWwT1Hhx-0102Sp for ; Wed, 25 Feb 2015 23:30:56 +0100 Date: Wed, 25 Feb 2015 23:30:40 +0100 From: Marc Joliet To: gentoo-amd64@lists.gentoo.org Subject: Re: [gentoo-amd64] Systemd migration: opinion and questions Message-ID: <20150225233040.6129dd94@marcec.fritz.box> In-Reply-To: <20150224211545.50495cc0@marcec.fritz.box> References: <20150224211545.50495cc0@marcec.fritz.box> X-Mailer: Claws Mail 3.11.1 (GTK+ 2.24.25; x86_64-pc-linux-gnu) Precedence: bulk List-Post: List-Help: List-Unsubscribe: List-Subscribe: List-Id: Gentoo Linux mail X-BeenThere: gentoo-amd64@lists.gentoo.org Reply-to: gentoo-amd64@lists.gentoo.org MIME-Version: 1.0 Content-Type: multipart/signed; micalg=pgp-sha1; boundary="Sig_/i+cfuWmviD0dBWcUKf5zKbJ"; protocol="application/pgp-signature" X-Provags-ID: V03:K0:3L+RjWOc9nO6TfdFH+rl8/xZ34vISYXbXSa6gQTcRzUb0k1Ljey sPaC52oQMlfxhdf1NmxQAXZs7wiRLEr09uOdzbZg5srdMiZgFEWPg8fA4MZupaLSpdBByo+ sTS9nVTciCtL0hrLn+EFjggtqRh0oUTuAHzbYnjMCEkzU2mVS2scINszdS6OdH1cmjPaXac bHHWwin+HsMwJSngRRfeA== X-UI-Out-Filterresults: notjunk:1; X-Archives-Salt: f69950fc-f21f-4d51-9787-f86751df75fb X-Archives-Hash: 68b99f63e787a07a3906084f6f68c3ef --Sig_/i+cfuWmviD0dBWcUKf5zKbJ Content-Type: text/plain; charset=US-ASCII Content-Transfer-Encoding: quoted-printable Am Tue, 24 Feb 2015 21:15:45 +0100 schrieb Marc Joliet : > However, I did find something unexpected. I haven't looked deeply yet, b= ut I > noticed some dmesg entries not ending up in the journal (i.e., a bunch of= "-" > lines in-between the "+" lines in the diff output). Did I do something wr= ong, or > is this known behaviour when running the journal and syslog-ng simultaneo= usly? > I'll look more closely tomorrow, but wondered if anybody here noticed any= thing > similar. OK, I looked at the logs. It turns out that it was simply that various log entries (mostly dmesg logs) were positioned differently or given different = time stamps. For example, this pinentry segfault: Feb 15 01:11:03 arthur lightdm[1445]: ** (lightdm:1445): WARNING **: Co= uld not create user data directory /var/lib/lightdm-data/marcec: Error crea= ting directory: No such file or directory -Feb 15 01:12:13 arthur kernel: [ 159.526696] pinentry[2323]: segfault = at 18 ip 00007fe8e025965c sp 00007fffa2ba4678 error 4 in libncursesw.so.5.9= [7fe8e023e000+5d000] +Feb 15 01:11:03 arthur lightdm[1891]: pam_unix(lightdm:session): sessio= n opened for user marcec by (uid=3D0) [...] +Feb 15 01:12:13 arthur kernel: pinentry[2323]: segfault at 18 ip 00007f= e8e025965c sp 00007fffa2ba4678 error 4 in libncursesw.so.5.9[7fe8e023e000+5= d000] Notice that the journal entry is missing the dmesg time stamp. The reason = for that (whatever it is) is probably also the reason why some entries have sli= ghtly different time stamps (e.g., 01:09:54 vs. 01:09:40). I didn't look at every single difference, but it looks like no messages was missed. Ah, no, there is one class of messages that only went to syslog-ng: -Feb 18 19:48:27 arthur kernel: 6.803784] systemd-journald[132]: Rec= eived request to flush runtime journal from PID 1 -Feb 18 19:52:14 arthur kernel: 245.293713] systemd-journald[132]: Fai= led to read ACL on /var/log/journal/b3a495d35e890b80816684a4521fc1cc/user-1= 000.journal, ignoring: Operation not supported (Note that the ACL thing is fixed, I simply did't have btrfs POSIX ACL supp= ort activated before the migration.) That's... interesting. Why does the journal not contain these entries relating to itself? All I can find in the journal related to flushing are l= ots of "Time spent on flushing" entries: % journalctl|grep flush Feb 15 00:19:45 arthur systemd-journal[131]: Time spent on flushing to = /var is 20.220ms for 798 entries. Feb 15 00:25:20 arthur kernel: nr_pdflush_threads exported in /proc is = scheduled for removal Feb 15 00:32:36 arthur systemd-journal[131]: Time spent on flushing to = /var is 570.566ms for 775 entries. [...] Grepping for ACL also turns up empty. What gives? --=20 Marc Joliet -- "People who think they know everything really annoy those of us who know we don't" - Bjarne Stroustrup --Sig_/i+cfuWmviD0dBWcUKf5zKbJ Content-Type: application/pgp-signature Content-Description: Digitale Signatur von OpenPGP -----BEGIN PGP SIGNATURE----- Version: GnuPG v2 iQIcBAEBAgAGBQJU7k0eAAoJEL/Q5oYsiHj0Yw4QANAi5ll5Hufmxw+aRRgc1m0d KGpAQvPMmMpcvPqxAbJb2Snx96iY5ozW/ameWRmVKm7rCUM87zx6ubM2WX6CRz2w g7Klh8xAwNrk/k3kluGzIMF7L11MWaWjL4PMPqAYn6O6O5LqUFcfSBrFRyFy3uEh CPjY51Zheg86fRkg9QuxNn7KoXX6aeXmFl894nIz+HM3rasvZBySphUdrtJ6xmYn 95QV6c11908KBUvRjAKjmPZmgY3JV0mGThN6PCDnVewmw6H6ZLAoBKZivzU+g0aX tCPc8HqyJRxNdwo/Cl2+N2mKI1yN6yVo9wyquITIxmLZFUA0fN5oe8Iv0MC2Gz4J fXTD6mbi8sdmz26PvmIur//CSDlk7mcjMqcxlxHAnMWKCPZtQj+ReLPLB8pYvHCD 0g+bbUi41QlUqcqV3Jd2AdRY8U/l4gsDjGXW6wVZ1OLnhPMqRCCeBaeyW098WoZJ 7OJDj6uB1o0PV8lDtuvS+DdabUm4yTq4kjX7q+nmqom7xcxfNIhoDAqK40Ds3ITh ljGHs8k4uQAIv/3MGwEApXfcXrSffJaG+KIv2ewe5ZMfKvoo1933+CP/RjbSgIy/ vryQSZ3811V0tnB0+ufsVbMy3P4KqhrzvhIxcB/hSkxSSdbYuwMVMaL7O3N4L26Y vxQROSM9jT4uuqzAfYSZ =YIOn -----END PGP SIGNATURE----- --Sig_/i+cfuWmviD0dBWcUKf5zKbJ--