From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: from mp12.migadu.com ([2001:41d0:8:6d80::]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits)) by ms5.migadu.com with LMTPS id oGJBIIiRm2LQTgEAbAwnHQ (envelope-from ) for ; Sat, 04 Jun 2022 19:08:24 +0200 Received: from aspmx1.migadu.com ([2001:41d0:8:6d80::]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits)) by mp12.migadu.com with LMTPS id 2LMrIIiRm2IbdAAAauVa8A (envelope-from ) for ; Sat, 04 Jun 2022 19:08:24 +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 A18B11C84E for ; Sat, 4 Jun 2022 19:08:23 +0200 (CEST) Received: from localhost ([::1]:60942 helo=lists1p.gnu.org) by lists.gnu.org with esmtp (Exim 4.90_1) (envelope-from ) id 1nxXGA-0003tB-DO for larch@yhetil.org; Sat, 04 Jun 2022 13:08:22 -0400 Received: from eggs.gnu.org ([2001:470:142:3::10]:40592) by lists.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1nxXFq-0003sO-I0 for bug-guix@gnu.org; Sat, 04 Jun 2022 13:08:02 -0400 Received: from debbugs.gnu.org ([209.51.188.43]:37459) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_128_GCM_SHA256:128) (Exim 4.90_1) (envelope-from ) id 1nxXFq-0008Rv-5u for bug-guix@gnu.org; Sat, 04 Jun 2022 13:08:02 -0400 Received: from Debian-debbugs by debbugs.gnu.org with local (Exim 4.84_2) (envelope-from ) id 1nxXFq-0004ov-0h for bug-guix@gnu.org; Sat, 04 Jun 2022 13:08:02 -0400 X-Loop: help-debbugs@gnu.org Subject: bug#55707: bug#55488: GDM, GNOME: Can't start desktop session after upgrade Resent-From: Ludovic =?UTF-8?Q?Court=C3=A8s?= Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Sat, 04 Jun 2022 17:08:01 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: followup 55707 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: Luis Felipe Cc: 55707@debbugs.gnu.org, 55488@debbugs.gnu.org Received: via spool by 55707-submit@debbugs.gnu.org id=B55707.165436245618490 (code B ref 55707); Sat, 04 Jun 2022 17:08:01 +0000 Received: (at 55707) by debbugs.gnu.org; 4 Jun 2022 17:07:36 +0000 Received: from localhost ([127.0.0.1]:59588 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1nxXFP-0004o4-3Z for submit@debbugs.gnu.org; Sat, 04 Jun 2022 13:07:36 -0400 Received: from eggs.gnu.org ([209.51.188.92]:55582) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1nxXFJ-0004ni-TO; Sat, 04 Jun 2022 13:07:33 -0400 Received: from fencepost.gnu.org ([2001:470:142:3::e]:55334) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1nxXFE-0008QJ-Fo; Sat, 04 Jun 2022 13:07:24 -0400 DKIM-Signature: v=1; a=rsa-sha256; q=dns/txt; c=relaxed/relaxed; d=gnu.org; s=fencepost-gnu-org; h=MIME-Version:In-Reply-To:Date:References:Subject:To: From; bh=TJEgiws8xD12PHbsKcDYwbGMqD3sP7MLJkKFjzrbBMM=; b=Qie3QJfiYfMwFZO9U2/h CIA8FOMN/kZ1XTPYR2N6ZXpReLWXO8MAlywKQmxNrcQVQf1ODOSjDIUWr0/Y5g+xQPFanyElFg6iJ SDD/gTIKv3Aiwf+q6vrlxoCyucUJbPh7hqyEpG+FareWy2/Dh05fGexJZMtbczt/4mQR8hKNCXEmr PKKUFvbXx+Zme2gFZQiVHm54TTDf/dRDmVBgVLYAKDJQSlNX5JqOuneLzF6Mcrjid15Ycg831GwuS YA6JHxgkWQpzX8xgi/h8AXeZpB09VXG7EPaBZAJuDYz+qlAjXYe8tC1+Vf2Y/iG4nLT2KWgEgouJc PPrTGfysQvSgvg==; Received: from 91-160-117-201.subs.proxad.net ([91.160.117.201]:58616 helo=ribbon) by fencepost.gnu.org with esmtpsa (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1nxXFD-0004W7-1J; Sat, 04 Jun 2022 13:07:24 -0400 From: Ludovic =?UTF-8?Q?Court=C3=A8s?= References: <-ERAqngkTp_rn5o9bawg5LQaV0bIQaYkKKbA55nOcyAHmZA88EsuHxKdIrt7k6pauSeGlZH47uE68OXt-xpG_z6yZPs_VCu1qLw_N3Yp9WY=@protonmail.com> <87k0aj44dd.fsf@gnu.org> <87ilpmyrr1.fsf@gnu.org> <87sfootayu.fsf@gnu.org> <87o7zalqxw.fsf@gnu.org> <87wndxfjcw.fsf@gnu.org> Date: Sat, 04 Jun 2022 19:07:20 +0200 In-Reply-To: <87wndxfjcw.fsf@gnu.org> ("Ludovic =?UTF-8?Q?Court=C3=A8s?="'s message of "Sat, 04 Jun 2022 00:54:55 +0200") Message-ID: <878rqcfjcn.fsf@gnu.org> User-Agent: Gnus/5.13 (Gnus v5.13) Emacs/28.1 (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=1654362503; h=from:from:sender:sender:reply-to:subject:subject:date:date: message-id:message-id:to:to:cc:cc:mime-version:mime-version: content-type:content-type: content-transfer-encoding:content-transfer-encoding:resent-cc: resent-from:resent-sender:resent-message-id:in-reply-to:in-reply-to: references:references:list-id:list-help:list-unsubscribe: list-subscribe:list-post:dkim-signature; bh=TJEgiws8xD12PHbsKcDYwbGMqD3sP7MLJkKFjzrbBMM=; b=RNMnn4/+LpMYWeA5rMUbS57TtBstb/0yi4hn1CQUCCupgSSgxOn5rz6iKaP3/v4kWRgoc1 a3m8uufaO/C6RY5Mrvc+6sbSigkZCeFEM4ppJvMkzEHgggiKs5mDndCfJ4iYhBKBMjBlwT btbZVx9LwphMXO/m6hVtMloSCDSptVSfLf3bdpd9G0lVJxzCPQdz8STTF55riIiZYtFJt8 mcUa9hqoMV6Amkka2XxgXuMJhZNiN7HmvayqykcSYp5TPMwIlcMiwlT+oVHtJNk03rOYUu pyXr8CNe3rHno0Hv5p/NLcWc1H7eBNiHdKuAUo8NUUlrjnD1WBsaNeXKAbwOEw== ARC-Seal: i=1; s=key1; d=yhetil.org; t=1654362503; a=rsa-sha256; cv=none; b=I5RHpGgqVclhFOVl2mixSAz+rfii8o+vlA9+gpAF8XWUBmPpVXYpzL4xKmW7R7Bdkn3oHD kRbXW7ZD7NBzNFqnXY39y6TOvdQm66OwKECRD6u5CLKOesJ0qZyel4ujP37GqnvW1sjuK0 R4gKu9OXn/qjwpIIArOClzr3HiPh92t5+r1MUlKa5LW94+jLAQ+UiQ5ym/onDoGDalOR3l 1fXeSiF7dG1+mWnvKsP9YVzaotWlNcNRtfxRUC5i8ywVZjnNEGMLdBJFL8Hh4ZQ6g3wV4n mSPJXwp9TB1I0tlqnAYkCcNdp5tZAxXo+MCSe2wKgcOS/LAl+IsKj8F5Jx4otw== 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=Qie3QJfi; 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: -4.52 Authentication-Results: aspmx1.migadu.com; dkim=fail ("headers rsa verify failed") header.d=gnu.org header.s=fencepost-gnu-org header.b=Qie3QJfi; 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: A18B11C84E X-Spam-Score: -4.52 X-Migadu-Scanner: scn0.migadu.com X-TUID: xXl7MZBXS2f5 Hi, Ludovic Court=C3=A8s skribis: > Here=E2=80=99s another debugging trick; would be great if you could try t= his: > > (define strace-syslogd > (program-file "strace-syslogd" > #~(apply execl #$(file-append strace "/bin/strace") > "strace" ;argv[0] > "-f" "-Tt" "-o" "/syslogd.log" "-s" "80" > #$(file-append inetutils "/libexec/syslogd") > (cdr (command-line))))) > > > and then: > > (modify-services %desktop-services > (syslog-service-type > _ =3D> (syslog-configuration > (syslogd strace-syslogd)))) > > This creates a log file, /syslogd.log, which will allow us to see the > time it takes syslogd to read from /proc/kmsg and hopefully to determine > the origin of delays. I tried this on a machine I have access to that exhibits this slowness, and here=E2=80=99s what I get (excerpt that spans 2+ seconds of syslogd activity): --8<---------------cut here---------------start------------->8--- 328 18:46:13 openat(AT_FDCWD, "/dev/console", O_WRONLY|O_CREAT|O_APPEND, = 0644) =3D 4 <0.000099> 328 18:46:13 openat(AT_FDCWD, "/var/log/messages", O_WRONLY|O_CREAT|O_APP= END, 0644) =3D 5 <0.000075> 328 18:46:13 ioctl(5, TCGETS, 0x7ffe23d6c930) =3D -1 ENOTTY (Inappropriat= e ioctl for device) <0.000261> 328 18:46:13 openat(AT_FDCWD, "/var/log/debug", O_WRONLY|O_CREAT|O_APPEND= , 0644) =3D 6 <0.000201> 328 18:46:13 ioctl(6, TCGETS, 0x7ffe23d6c930) =3D -1 ENOTTY (Inappropriat= e ioctl for device) <0.000138> 328 18:46:13 openat(AT_FDCWD, "/dev/tty12", O_WRONLY|O_CREAT|O_APPEND, 06= 44) =3D 7 <0.001059> 328 18:46:13 ioctl(7, TCGETS, {B38400 opost isig icanon echo ...}) =3D 0 = <0.000101> 328 18:46:13 openat(AT_FDCWD, "/var/log/secure", O_WRONLY|O_CREAT|O_APPEN= D, 0644) =3D 8 <0.000077> 328 18:46:13 ioctl(8, TCGETS, 0x7ffe23d6c930) =3D -1 ENOTTY (Inappropriat= e ioctl for device) <0.000039> 328 18:46:13 openat(AT_FDCWD, "/var/log/maillog", O_WRONLY|O_CREAT|O_APPE= ND, 0644) =3D 9 <0.000070> [=E2=80=A6] 328 18:46:13 read(3, "<5>[ 0.000000] Linux version 5.17.11-gnu (guix@g= uix) (gcc (GCC) 10.3.0, GNU l"..., 1024) =3D 981 <0.000083> 328 18:46:13 rt_sigprocmask(SIG_BLOCK, [HUP ALRM], [], 8) =3D 0 <0.000059> 322 18:46:13 +++ exited with 0 +++ 328 18:46:13 newfstatat(AT_FDCWD, "/etc/localtime", {st_mode=3DS_IFREG|04= 44, st_size=3D2962, ...}, 0) =3D 0 <0.000049> 328 18:46:13 writev(7, [{iov_base=3D"Jun 4 18:46:13", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Linux version 5.= 17.11-gnu (guix@guix) (gcc (GCC) 10.3.0, "..., iov_len=3D124}, {iov_base=3D= "\r\n", iov_len=3D2}], 6) =3D 152 <0.000086> 328 18:46:13 fsync(7) =3D -1 EINVAL (Invalid argument) <0= .000036> 328 18:46:13 writev(6, [{iov_base=3D"Jun 4 18:46:13", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Linux version 5.= 17.11-gnu (guix@guix) (gcc (GCC) 10.3.0, "..., iov_len=3D124}, {iov_base=3D= "\n", iov_len=3D1}], 6) =3D 151 <0.000063> 328 18:46:13 fsync(6) =3D 0 <0.380857> 328 18:46:13 writev(5, [{iov_base=3D"Jun 4 18:46:13", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Linux version 5.= 17.11-gnu (guix@guix) (gcc (GCC) 10.3.0, "..., iov_len=3D124}, {iov_base=3D= "\n", iov_len=3D1}], 6) =3D 151 <0.000079> 328 18:46:13 fsync(5) =3D 0 <0.131763> 328 18:46:13 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) =3D 0 <0.000213> 328 18:46:13 rt_sigprocmask(SIG_BLOCK, [HUP ALRM], [], 8) =3D 0 <0.000160> 328 18:46:13 newfstatat(AT_FDCWD, "/etc/localtime", {st_mode=3DS_IFREG|04= 44, st_size=3D2962, ...}, 0) =3D 0 <0.000049> 328 18:46:13 writev(7, [{iov_base=3D"Jun 4 18:46:13", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Command line: BO= OT_IMAGE=3D/gnu/store/w8py29cnikbg69jvxhxb3"..., iov_len=3D314}, {iov_base= =3D"\r\n", iov_len=3D2}], 6) =3D 342 <0.000123> 328 18:46:13 fsync(7) =3D -1 EINVAL (Invalid argument) <0= .000040> 328 18:46:13 writev(6, [{iov_base=3D"Jun 4 18:46:13", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Command line: BO= OT_IMAGE=3D/gnu/store/w8py29cnikbg69jvxhxb3"..., iov_len=3D314}, {iov_base= =3D"\n", iov_len=3D1}], 6) =3D 341 <0.000074> 328 18:46:13 fsync(6) =3D 0 <0.239999> 328 18:46:13 writev(5, [{iov_base=3D"Jun 4 18:46:13", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Command line: BO= OT_IMAGE=3D/gnu/store/w8py29cnikbg69jvxhxb3"..., iov_len=3D314}, {iov_base= =3D"\n", iov_len=3D1}], 6) =3D 341 <0.000156> 328 18:46:13 fsync(5) =3D 0 <0.099143> 328 18:46:14 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) =3D 0 <0.000128> 328 18:46:14 rt_sigprocmask(SIG_BLOCK, [HUP ALRM], [], 8) =3D 0 <0.000180> 328 18:46:14 newfstatat(AT_FDCWD, "/etc/localtime", {st_mode=3DS_IFREG|04= 44, st_size=3D2962, ...}, 0) =3D 0 <0.000100> 328 18:46:14 writev(7, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] KERNEL supported= cpus:", iov_len=3D45}, {iov_base=3D"\r\n", iov_len=3D2}], 6) =3D 73 <0.000= 189> 328 18:46:14 fsync(7) =3D -1 EINVAL (Invalid argument) <0= .000039> 328 18:46:14 writev(6, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] KERNEL supported= cpus:", iov_len=3D45}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 72 <0.00009= 7> 328 18:46:14 fsync(6) =3D 0 <0.198157> 328 18:46:14 writev(5, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] KERNEL supported= cpus:", iov_len=3D45}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 72 <0.00028= 4> 328 18:46:14 fsync(5) =3D 0 <0.098527> 328 18:46:14 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) =3D 0 <0.000136> 328 18:46:14 rt_sigprocmask(SIG_BLOCK, [HUP ALRM], [], 8) =3D 0 <0.000133> 328 18:46:14 newfstatat(AT_FDCWD, "/etc/localtime", {st_mode=3DS_IFREG|04= 44, st_size=3D2962, ...}, 0) =3D 0 <0.000488> 328 18:46:14 writev(7, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Intel GenuineI= ntel", iov_len=3D43}, {iov_base=3D"\r\n", iov_len=3D2}], 6) =3D 71 <0.00021= 3> 328 18:46:14 fsync(7) =3D -1 EINVAL (Invalid argument) <0= .000141> 328 18:46:14 writev(6, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Intel GenuineI= ntel", iov_len=3D43}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 70 <0.000219> 328 18:46:14 fsync(6) =3D 0 <0.096564> 328 18:46:14 writev(5, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Intel GenuineI= ntel", iov_len=3D43}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 70 <0.000215> 328 18:46:14 fsync(5) =3D 0 <0.125492> 328 18:46:14 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) =3D 0 <0.000128> 328 18:46:14 rt_sigprocmask(SIG_BLOCK, [HUP ALRM], [], 8) =3D 0 <0.000130> 328 18:46:14 newfstatat(AT_FDCWD, "/etc/localtime", {st_mode=3DS_IFREG|04= 44, st_size=3D2962, ...}, 0) =3D 0 <0.000252> 328 18:46:14 writev(7, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] AMD AuthenticA= MD", iov_len=3D41}, {iov_base=3D"\r\n", iov_len=3D2}], 6) =3D 69 <0.000368> 328 18:46:14 fsync(7) =3D -1 EINVAL (Invalid argument) <0= .000130> 328 18:46:14 writev(6, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] AMD AuthenticA= MD", iov_len=3D41}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 68 <0.000402> 328 18:46:14 fsync(6) =3D 0 <0.112798> 328 18:46:14 writev(5, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] AMD AuthenticA= MD", iov_len=3D41}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 68 <0.000199> 328 18:46:14 fsync(5) =3D 0 <0.098952> 328 18:46:14 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) =3D 0 <0.000244> 328 18:46:14 rt_sigprocmask(SIG_BLOCK, [HUP ALRM], [], 8) =3D 0 <0.000128> 328 18:46:14 newfstatat(AT_FDCWD, "/etc/localtime", {st_mode=3DS_IFREG|04= 44, st_size=3D2962, ...}, 0) =3D 0 <0.000252> 328 18:46:14 writev(7, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Hygon HygonGen= uine", iov_len=3D43}, {iov_base=3D"\r\n", iov_len=3D2}], 6) =3D 71 <0.00037= 5> 328 18:46:14 fsync(7) =3D -1 EINVAL (Invalid argument) <0= .000039> 328 18:46:14 writev(6, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Hygon HygonGen= uine", iov_len=3D43}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 70 <0.000099> 328 18:46:14 fsync(6) =3D 0 <0.113492> 328 18:46:14 writev(5, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Hygon HygonGen= uine", iov_len=3D43}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 70 <0.000998> 328 18:46:14 fsync(5) =3D 0 <0.106071> 328 18:46:15 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) =3D 0 <0.000220> 328 18:46:15 rt_sigprocmask(SIG_BLOCK, [HUP ALRM], [], 8) =3D 0 <0.000115> 328 18:46:15 newfstatat(AT_FDCWD, "/etc/localtime", {st_mode=3DS_IFREG|04= 44, st_size=3D2962, ...}, 0) =3D 0 <0.000115> 328 18:46:15 writev(7, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Centaur Centau= rHauls", iov_len=3D45}, {iov_base=3D"\r\n", iov_len=3D2}], 6) =3D 73 <0.000= 262> 328 18:46:15 fsync(7) =3D -1 EINVAL (Invalid argument) <0= .000048> 328 18:46:15 writev(6, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Centaur Centau= rHauls", iov_len=3D45}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 72 <0.00007= 6> 328 18:46:15 fsync(6) =3D 0 <0.080533> 328 18:46:15 writev(5, [{iov_base=3D"Jun 4 18:46:14", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] Centaur Centau= rHauls", iov_len=3D45}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 72 <0.00019= 8> 328 18:46:15 fsync(5) =3D 0 <0.107653> 328 18:46:15 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) =3D 0 <0.000051> 328 18:46:15 rt_sigprocmask(SIG_BLOCK, [HUP ALRM], [], 8) =3D 0 <0.000055> 328 18:46:15 newfstatat(AT_FDCWD, "/etc/localtime", {st_mode=3DS_IFREG|04= 44, st_size=3D2962, ...}, 0) =3D 0 <0.000093> 328 18:46:15 writev(7, [{iov_base=3D"Jun 4 18:46:15", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] zhaoxin Shan= ghai ", iov_len=3D45}, {iov_base=3D"\r\n", iov_len=3D2}], 6) =3D 73 <0.000= 197> 328 18:46:15 fsync(7) =3D -1 EINVAL (Invalid argument) <0= .000042> 328 18:46:15 writev(6, [{iov_base=3D"Jun 4 18:46:15", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] zhaoxin Shan= ghai ", iov_len=3D45}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 72 <0.00012= 3> 328 18:46:15 fsync(6) =3D 0 <0.123659> 328 18:46:15 writev(5, [{iov_base=3D"Jun 4 18:46:15", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] zhaoxin Shan= ghai ", iov_len=3D45}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 72 <0.00037= 0> 328 18:46:15 fsync(5) =3D 0 <0.098383> 328 18:46:15 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) =3D 0 <0.000138> 328 18:46:15 rt_sigprocmask(SIG_BLOCK, [HUP ALRM], [], 8) =3D 0 <0.000128> 328 18:46:15 newfstatat(AT_FDCWD, "/etc/localtime", {st_mode=3DS_IFREG|04= 44, st_size=3D2962, ...}, 0) =3D 0 <0.000254> 328 18:46:15 writev(7, [{iov_base=3D"Jun 4 18:46:15", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] x86/fpu: x87 FPU= will use FXSAVE", iov_len=3D55}, {iov_base=3D"\r\n", iov_len=3D2}], 6) =3D= 83 <0.000383> 328 18:46:15 fsync(7) =3D -1 EINVAL (Invalid argument) <0= .000135> 328 18:46:15 writev(6, [{iov_base=3D"Jun 4 18:46:15", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] x86/fpu: x87 FPU= will use FXSAVE", iov_len=3D55}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 8= 2 <0.000209> 328 18:46:15 fsync(6) =3D 0 <0.122001> 328 18:46:15 writev(5, [{iov_base=3D"Jun 4 18:46:15", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] x86/fpu: x87 FPU= will use FXSAVE", iov_len=3D55}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 8= 2 <0.000197> 328 18:46:15 fsync(5) =3D 0 <0.123772> 328 18:46:15 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) =3D 0 <0.000102> 328 18:46:15 rt_sigprocmask(SIG_BLOCK, [HUP ALRM], [], 8) =3D 0 <0.000140> 328 18:46:15 newfstatat(AT_FDCWD, "/etc/localtime", {st_mode=3DS_IFREG|04= 44, st_size=3D2962, ...}, 0) =3D 0 <0.000167> 328 18:46:15 writev(7, [{iov_base=3D"Jun 4 18:46:15", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] signal: max sigf= rame size: 1440", iov_len=3D54}, {iov_base=3D"\r\n", iov_len=3D2}], 6) =3D = 82 <0.000273> 328 18:46:15 fsync(7) =3D -1 EINVAL (Invalid argument) <0= .000129> 328 18:46:15 writev(6, [{iov_base=3D"Jun 4 18:46:15", iov_len=3D15}, {io= v_base=3D" ", iov_len=3D1}, {iov_base=3D"localhost", iov_len=3D9}, {iov_bas= e=3D" ", iov_len=3D1}, {iov_base=3D"vmunix: [ 0.000000] signal: max sigf= rame size: 1440", iov_len=3D54}, {iov_base=3D"\n", iov_len=3D1}], 6) =3D 81= <0.000099> 328 18:46:15 fsync(6) =3D 0 <0.121488> --8<---------------cut here---------------end--------------->8--- During that time span, syslogd makes no less than 19 =E2=80=98fsync=E2=80= =99 calls (not counting the EINVAL ones), each of which takes between 100ms and 400ms=E2=80=94no wonder it=E2=80=99s slow. (This machine has a low-grade s= pinning HDD.) There are two things we can do: 1. Use =E2=80=98fdatasync=E2=80=99 rather than =E2=80=98fsync=E2=80=99; 2. Explicitly disable syncing for some of the log files by prepending a hyphen right before the file name in syslogd.conf (info "(inetutils) syslogd invocation"). I=E2=80=99ll give that a try and report back. Ludo=E2=80=99.