From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: from mp12.migadu.com ([2001:41d0:2:bcc0::]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits)) by ms5.migadu.com with LMTPS id QHg1DZuKk2LMhQAAbAwnHQ (envelope-from ) for ; Sun, 29 May 2022 17:00:43 +0200 Received: from aspmx1.migadu.com ([2001:41d0:2:bcc0::]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits)) by mp12.migadu.com with LMTPS id 8EQdDZuKk2LqmAAAauVa8A (envelope-from ) for ; Sun, 29 May 2022 17:00:43 +0200 Received: from lists.gnu.org (lists.gnu.org [209.51.188.17]) (using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)) (No client certificate requested) by aspmx1.migadu.com (Postfix) with ESMTPS id 244EF16CA9 for ; Sun, 29 May 2022 17:00:42 +0200 (CEST) Received: from localhost ([::1]:41954 helo=lists1p.gnu.org) by lists.gnu.org with esmtp (Exim 4.90_1) (envelope-from ) id 1nvKPI-0003wd-ST for larch@yhetil.org; Sun, 29 May 2022 11:00:40 -0400 Received: from eggs.gnu.org ([2001:470:142:3::10]:54000) by lists.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1nvKOh-0003wU-2v for bug-guix@gnu.org; Sun, 29 May 2022 11:00:03 -0400 Received: from debbugs.gnu.org ([209.51.188.43]:47709) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_128_GCM_SHA256:128) (Exim 4.90_1) (envelope-from ) id 1nvKOg-0005N0-P3 for bug-guix@gnu.org; Sun, 29 May 2022 11:00:02 -0400 Received: from Debian-debbugs by debbugs.gnu.org with local (Exim 4.84_2) (envelope-from ) id 1nvKOg-00045k-Lp for bug-guix@gnu.org; Sun, 29 May 2022 11:00:02 -0400 X-Loop: help-debbugs@gnu.org Subject: bug#55707: syslogd logging kernel messages slowly? Resent-From: Ludovic =?UTF-8?Q?Court=C3=A8s?= Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Sun, 29 May 2022 15:00:02 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: report 55707 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: 55707@debbugs.gnu.org X-Debbugs-Original-To: bug-guix@gnu.org Received: via spool by submit@debbugs.gnu.org id=B.165383635115635 (code B ref -1); Sun, 29 May 2022 15:00:02 +0000 Received: (at submit) by debbugs.gnu.org; 29 May 2022 14:59:11 +0000 Received: from localhost ([127.0.0.1]:41606 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1nvKNq-000446-VK for submit@debbugs.gnu.org; Sun, 29 May 2022 10:59:11 -0400 Received: from lists.gnu.org ([209.51.188.17]:49426) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1nvKNo-00043x-Qk for submit@debbugs.gnu.org; Sun, 29 May 2022 10:59:10 -0400 Received: from eggs.gnu.org ([2001:470:142:3::10]:53840) by lists.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1nvKNo-0003rW-Cy for bug-guix@gnu.org; Sun, 29 May 2022 10:59:08 -0400 Received: from fencepost.gnu.org ([2001:470:142:3::e]:47182) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1nvKNo-0005HM-2Y for bug-guix@gnu.org; Sun, 29 May 2022 10:59:08 -0400 DKIM-Signature: v=1; a=rsa-sha256; q=dns/txt; c=relaxed/relaxed; d=gnu.org; s=fencepost-gnu-org; h=MIME-Version:Date:Subject:To:From:in-reply-to: references; bh=GTk6o/z9VVhiHqPF0H5x6BYx5Geqopy9y8G7y1fLdr0=; b=eJHG/3ABg/KiuL 2oH74xb1EhSi/4OcA1oflPiXUqgvVTFtz99MWpvlgltsBQMyTrG3UL6SgCcDkDSq9dMkVKmTlFFif BM9JPT1bVp8wc2t8iC8TW96hNwFs92FC5ts4taIZSwNeAxaK8lZ/ii5UL5rxu+J771bUEp8Hrr7dy X0LvhU9xS2wJvj0kjAptn4YGbCXRyLn82AgKMSFcPWQxhHiAGKTFAL7tq1lVJc9lutSKOhYni58qe 3jMgxBuGmZstzHKZdPvL34ocx4aDcuZUQmt2a2DJ+Z38E4ONj4rSuLXwTfwL8r8rThgMJdC722/ng aiI1FxVI7btAjKNXqNxg==; Received: from 91-160-117-201.subs.proxad.net ([91.160.117.201]:56766 helo=ribbon) by fencepost.gnu.org with esmtpsa (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1nvKNn-0000Kj-Kh for bug-guix@gnu.org; Sun, 29 May 2022 10:59:07 -0400 From: Ludovic =?UTF-8?Q?Court=C3=A8s?= X-URL: http://www.fdn.fr/~lcourtes/ X-Revolutionary-Date: 10 Prairial an 230 de la =?UTF-8?Q?R=C3=A9volution?= X-PGP-Key-ID: 0x090B11993D9AEBB5 X-PGP-Key: http://www.fdn.fr/~lcourtes/ludovic.asc X-PGP-Fingerprint: 3CE4 6455 8A84 FDC6 9DB4 0CFB 090B 1199 3D9A EBB5 X-OS: x86_64-pc-linux-gnu Date: Sun, 29 May 2022 16:59:05 +0200 Message-ID: <87y1yk8jwm.fsf@inria.fr> User-Agent: Gnus/5.13 (Gnus v5.13) Emacs/27.2 (gnu/linux) MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list X-BeenThere: bug-guix@gnu.org List-Id: Bug reports for GNU Guix List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: bug-guix-bounces+larch=yhetil.org@gnu.org Sender: "bug-Guix" X-Migadu-Flow: FLOW_IN X-Migadu-To: larch@yhetil.org X-Migadu-Country: US ARC-Message-Signature: i=1; a=rsa-sha256; c=relaxed/relaxed; d=yhetil.org; s=key1; t=1653836442; h=from:from:sender:sender:reply-to:subject:subject:date:date: message-id:message-id:to:to:cc:mime-version:mime-version: content-type:content-type: content-transfer-encoding:content-transfer-encoding:resent-cc: resent-from:resent-sender:resent-message-id:list-id:list-help: list-unsubscribe:list-subscribe:list-post:dkim-signature; bh=GTk6o/z9VVhiHqPF0H5x6BYx5Geqopy9y8G7y1fLdr0=; b=jW/EqElxyG/ki2tfwr02KOqoUF/y8TfDeycwueOeH23fjXphOfe03KlKZcnUa5tnmzzi24 z0lQ4hkCrlODyUqqQ5uVzJWr5j5L6O39ofN/A2TVwXw1JdXjnWL+W4jKRbNu0dPfPw0sWj 18SXo/YYN/ruT4Qvmrfo1q2aS13MbkimQoCe7k9l6eU2R1hLtHK8JpwVxSgWOHV2QQ58Pi uk1sPGLtHEvvF0KRi1I7UvcZzbc3wPmV9B5Lfx6xlhmMVeMiahayw+kM+zpvBl+h+0ilk9 ktlsk3vUxd72XECOj7FJN9/Sf/Am4V4yu4xG1rnSR2gZtBX+SMJF1nHQqiJwkQ== ARC-Seal: i=1; s=key1; d=yhetil.org; t=1653836442; a=rsa-sha256; cv=none; b=Z9Jz7BZJz4Xrg+S0Bt0QAhHpFfgEp6mnk8q918QCj1+L6v5A7C30Y8T8Ove4egw5F20GzI 8LwJsVpPQrHptxmDsV9fOf/bZSVbxeAtaqpfKaR/2CznGWMoQGpFvvbE2HGcp8BjQC1B3g SX28rwfLONaJ2m0jytFSz6oYBnvu/fSOgTRnXwWVIz5uAQ7fEcerztmOjmP2x8dKGuir0b uDCAnzxboQK+zSYVr+6xsgslouXkLXiuqLRosNPDXKxMjt0tkNPZdzkzOHyu3x8YZgnooD gqQgNBXWfJtfu+dL8UjZ0wovtMzE+2D5nXR5h5AEIIyXvnDCgz/upAC8l/5x8g== ARC-Authentication-Results: i=1; aspmx1.migadu.com; dkim=fail ("headers rsa verify failed") header.d=gnu.org header.s=fencepost-gnu-org header.b="eJHG/3AB"; dmarc=pass (policy=none) header.from=gnu.org; spf=pass (aspmx1.migadu.com: domain of "bug-guix-bounces+larch=yhetil.org@gnu.org" designates 209.51.188.17 as permitted sender) smtp.mailfrom="bug-guix-bounces+larch=yhetil.org@gnu.org" X-Migadu-Spam-Score: -2.94 Authentication-Results: aspmx1.migadu.com; dkim=fail ("headers rsa verify failed") header.d=gnu.org header.s=fencepost-gnu-org header.b="eJHG/3AB"; dmarc=pass (policy=none) header.from=gnu.org; spf=pass (aspmx1.migadu.com: domain of "bug-guix-bounces+larch=yhetil.org@gnu.org" designates 209.51.188.17 as permitted sender) smtp.mailfrom="bug-guix-bounces+larch=yhetil.org@gnu.org" X-Migadu-Queue-Id: 244EF16CA9 X-Spam-Score: -2.94 X-Migadu-Scanner: scn1.migadu.com X-TUID: 1/d3LgMD2NPE Hello, I noticed this weird phenomenon on an x86_64 machine (a 2GHz Celeron), seen in an excerpt of /var/log/messages: --8<---------------cut here---------------start------------->8--- May 29 12:34:49 localhost avahi-daemon[349]: avahi-daemon 0.8 starting up. May 29 12:35:10 localhost vmunix: [ 1.391673] xhci_hcd 0000:00:14.0: new= USB bus registered, assigned bus number 1 May 29 12:35:10 localhost vmunix: [ 1.392751] xhci_hcd 0000:00:14.0: hcc= params 0x200077c1 hci version 0x100 quirks 0x0000000000009810 May 29 12:35:10 localhost vmunix: [ 1.393050] usb usb1: New USB device f= ound, idVendor=3D1d6b, idProduct=3D0002, bcdDevice=3D 5.17 May 29 12:35:10 localhost vmunix: [ 1.393057] usb usb1: New USB device s= trings: Mfr=3D3, Product=3D2, SerialNumber=3D1 May 29 12:35:10 localhost vmunix: [ 1.393062] usb usb1: Product: xHCI Ho= st Controller May 29 12:35:10 localhost vmunix: [ 1.393065] usb usb1: Manufacturer: Li= nux 5.17.11-gnu xhci-hcd May 29 12:35:10 localhost vmunix: [ 1.393069] usb usb1: SerialNumber: 00= 00:00:14.0 May 29 12:35:11 localhost vmunix: [ 1.393645] hub 1-0:1.0: USB hub found May 29 12:35:11 localhost vmunix: [ 1.393669] hub 1-0:1.0: 6 ports detec= ted May 29 12:35:11 localhost vmunix: [ 1.394524] xhci_hcd 0000:00:14.0: xHC= I Host Controller May 29 12:35:11 localhost vmunix: [ 1.394534] xhci_hcd 0000:00:14.0: new= USB bus registered, assigned bus number 2 May 29 12:35:11 localhost vmunix: [ 1.394543] xhci_hcd 0000:00:14.0: Hos= t supports USB 3.0 SuperSpeed May 29 12:35:11 localhost vmunix: [ 1.394652] usb usb2: New USB device f= ound, idVendor=3D1d6b, idProduct=3D0003, bcdDevice=3D 5.17 May 29 12:34:50 localhost elogind[341]: Watching system buttons on /dev/inp= ut/event4 (Darfon HP USB Keyboard) May 29 12:35:11 localhost vmunix: [ 1.394659] usb usb2: New USB device s= trings: Mfr=3D3, Product=3D2, SerialNumber=3D1 May 29 12:35:11 localhost vmunix: [ 1.394664] usb usb2: Product: xHCI Ho= st Controller May 29 12:35:11 localhost vmunix: [ 1.394667] usb usb2: Manufacturer: Li= nux 5.17.11-gnu xhci-hcd May 29 12:35:11 localhost vmunix: [ 1.394670] usb usb2: SerialNumber: 00= 00:00:14.0 May 29 12:35:11 localhost vmunix: [ 1.395217] hub 2-0:1.0: USB hub found May 29 12:35:12 localhost vmunix: [ 1.395243] hub 2-0:1.0: 1 port detect= ed May 29 12:35:12 localhost vmunix: [ 1.395581] i8042: PNP: No PS/2 contro= ller found. May 29 12:35:12 localhost vmunix: [ 1.395584] i8042: Probing ports direc= tly. May 29 12:35:12 localhost vmunix: [ 1.396141] serio: i8042 KBD port at 0= x60,0x64 irq 1 May 29 12:35:12 localhost vmunix: [ 1.396153] serio: i8042 AUX port at 0= x60,0x64 irq 12 May 29 12:35:12 localhost vmunix: [ 1.396481] mousedev: PS/2 mouse devic= e common for all mice May 29 12:35:12 localhost vmunix: [ 1.396980] rtc_cmos 00:00: RTC can wa= ke from S4 May 29 12:35:12 localhost vmunix: [ 1.397736] rtc_cmos 00:00: registered= as rtc0 May 29 12:35:12 localhost vmunix: [ 1.397770] rtc_cmos 00:00: setting sy= stem clock to 2022-05-29T10:33:31 UTC (1653820411) May 29 12:35:13 localhost vmunix: [ 1.397817] rtc_cmos 00:00: alarms up = to one month, y3k, 242 bytes nvram May 29 12:35:13 localhost vmunix: [ 1.397843] i2c_dev: i2c /dev entries = driver May 29 12:34:52 localhost NetworkManager[339]: [1653820492.6955] ma= nager[0xfdb000]: monitoring kernel firmware directory '/lib/firmware'. --8<---------------cut here---------------end--------------->8--- The =E2=80=9Cvmunix=E2=80=9D messages take 3 seconds to get logged, althoug= h they correspond to events that occurred in ~0.5 second before syslogd was started. During boot up on that machine, if you go to tty12, where syslogd relays messages, you can indeed see those lines getting displayed pretty slowly. Has anyone experienced that? Thanks, Ludo=E2=80=99.