From unknown Sun Jun 15 13:02:11 2025 X-Loop: help-debbugs@gnu.org Subject: bug#77634: [shepherd] Test failures on GNU/Hurd Resent-From: yelninei@tutamail.com Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Tue, 08 Apr 2025 08:44:02 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: report 77634 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: 77634@debbugs.gnu.org X-Debbugs-Original-To: bug-guix@gnu.org Received: via spool by submit@debbugs.gnu.org id=B.174410181922705 (code B ref -1); Tue, 08 Apr 2025 08:44:02 +0000 Received: (at submit) by debbugs.gnu.org; 8 Apr 2025 08:43:39 +0000 Received: from localhost ([127.0.0.1]:58938 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1u24YQ-0005tw-Jl for submit@debbugs.gnu.org; Tue, 08 Apr 2025 04:43:39 -0400 Received: from lists.gnu.org ([2001:470:142::17]:43386) by debbugs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.84_2) (envelope-from ) id 1u24YL-0005tJ-UR for submit@debbugs.gnu.org; Tue, 08 Apr 2025 04:43:32 -0400 Received: from eggs.gnu.org ([2001:470:142:3::10]) by lists.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1u24YG-0000Sb-72 for bug-guix@gnu.org; Tue, 08 Apr 2025 04:43:24 -0400 Received: from mail.w13.tutanota.de ([185.205.69.213]) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1u24YC-0002VW-2D for bug-guix@gnu.org; Tue, 08 Apr 2025 04:43:23 -0400 Received: from tutadb.w10.tutanota.de (w10.api.tuta.com [IPv6:fd:ac::d:10]) by mail.w13.tutanota.de (Postfix) with ESMTP id DB613774EDCE for ; Tue, 8 Apr 2025 10:43:17 +0200 (CEST) DKIM-Signature: v=1; a=rsa-sha256; q=dns/txt; c=relaxed/relaxed; t=1744101797; s=s1; d=tutamail.com; h=From:From:To:To:Subject:Subject:Content-Description:Content-ID:Content-Type:Content-Type:Content-Transfer-Encoding:Content-Transfer-Encoding:Cc:Date:Date:In-Reply-To:MIME-Version:MIME-Version:Message-ID:Message-ID:Reply-To:References:Sender; bh=ugBKNj5xK/87EKAxDfEtn+Iee/ZDeIVKwrCtAlzhqq4=; b=AA50LsEgttMAiBaeaO1MiQOZpz/R8q6SU0AN+9mVhEp/i9ofDIP4QqrSceLmlNQ7 ADUpVSzBDQ334ilHj4EVcbYROImGpeHqD3AFNUHYzYbYIQoepLDItWfyESUzyKKsGZf +FRkV1o9+saLIQGuteirYWK8cOpGgdWtQJAwdd7oHkEr/EV1/CsIPzlCd5fmhT2ML3A JvKybTRNM53OkbzhIoeSyY0LdMIm3QK+xc2C1JBrVzQQ6rNGW+pc3tSK0EjUlAqD4gQ Dm6xz39YT1Ho3kBwnsga5I73itzXNpM1lcjIWx2u0TBXaPJ2vIcdIKf0nvVFGXogyZ5 YlFQC78afw== Date: Tue, 8 Apr 2025 10:43:17 +0200 (CEST) From: yelninei@tutamail.com Message-ID: MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Received-SPF: pass client-ip=185.205.69.213; envelope-from=yelninei@tutamail.com; helo=mail.w13.tutanota.de X-Spam_score_int: -27 X-Spam_score: -2.8 X-Spam_bar: -- X-Spam_report: (-2.8 / 5.0 requ) BAYES_00=-1.9, DKIM_SIGNED=0.1, DKIM_VALID=-0.1, DKIM_VALID_AU=-0.1, DKIM_VALID_EF=-0.1, RCVD_IN_DNSWL_LOW=-0.7, RCVD_IN_MSPIKE_H2=0.001, RCVD_IN_VALIDITY_CERTIFIED_BLOCKED=0.001, RCVD_IN_VALIDITY_RPBL_BLOCKED=0.001, SPF_HELO_NONE=0.001, SPF_PASS=-0.001 autolearn=ham autolearn_force=no X-Spam_action: no action X-Spam-Score: 0.9 (/) X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -0.1 (/) guix --version guix (GNU Guix) 812f972f046e521eabc3ddd76e790d7a69d426b5 fibers test suite is hanging so I am skipping it for now. Also I had to cheat a bit to get past automakes "t/output-order.sh" test wh= ere the guile build driver is leaking "GC Warning: Repeated allocation of v= ery large block" onto stderr confusing the test (similar problem to https:/= /issues.guix.gnu.org/73181). I added env["GC_LARGE_ALLOC_WARN_INTERVAL"] = =3D "100" to=C2=A0DerivationGoal::startBuilder() and used the slightly patc= hed daemon to build automake without changing the derivation. guix build shepherd --without-tests=3Dguile-fibers -s i586-gnu These 3 tests are failing: FAIL: tests/logging-failure.sh FAIL: tests/forking-service.sh FAIL: tests/services/system-log.sh Some others are being skipped, looking at the shepherd code this seems to b= e expected. SKIP: tests/logging-ENOSPC.sh SKIP: tests/close-on-exec.sh SKIP: tests/daemonize.sh Here is the output of the failing ones: FAIL: tests/logging-failure =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D + shepherd --version System lacks support for 'signalfd'; using fallback mechanism. shepherd (GNU Shepherd) 1.0.3 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + herd --version herd (GNU Shepherd) 1.0.3 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + log_directory=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log= -directory-8783 + socket=3Dt-socket-8783 + conf=3Dt-conf-8783 + log=3Dt-log-8783 + pid=3Dt-pid-8783 + herd=3D'herd -s t-socket-8783' + trap 'cat t-log-8783 || true; rm -f t-log-8783 t-socket-8783 t-conf-8783 = t-pid-8783; rm -rf /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-lo= g-directory-8783; =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 kill $main_pid || true' EXIT + cat + rm -f t-pid-8783 + main_pid=3D8788 + test -f t-pid-8783 + shepherd -I -s t-socket-8783 -c t-conf-8783 -l t-log-8783 --pid=3Dt-pid-8= 783 + sleep 0.3 System lacks support for 'signalfd'; using fallback mechanism. GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) Starting service root... Service root started. Service root running with value #< id: 8788 command: #f>. Service root has been started. Configuration successfully loaded from 't-conf-8783'. + test -f t-pid-8783 + herd -s t-socket-8783 status Started: + root Stopped: - log-directory-does-not-exist - log-directory-not-writable + test -f /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log-directo= ry-8783 + herd -s t-socket-8783 start log-directory-does-not-exist Service log-directory-does-not-exist has been started. + herd -s t-socket-8783 status log-directory-does-not-exist + grep running =C2=A0 It is running since 07:53:13 (0 seconds ago). + herd -s t-socket-8783 status log-directory-does-not-exist + grep /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log-directory-= 8783/service.log =C2=A0 Log file: /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log-= directory-8783/service.log + grep logging /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log-di= rectory-8783/service.log 2025-04-08 07:53:13 logging + herd -s t-socket-8783 start log-directory-not-writable ++ cat t-log-8783 2025-04-08 07:53:13 GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) 2025-04-08 07:53:13 Starting service root... 2025-04-08 07:53:13 Service root started. 2025-04-08 07:53:13 Service root running with value #< id: 8788 co= mmand: #f>. 2025-04-08 07:53:13 Service root has been started. 2025-04-08 07:53:13 Configuration successfully loaded from 't-conf-8783'. 2025-04-08 07:53:13 Starting service log-directory-does-not-exist... 2025-04-08 07:53:13 Service log-directory-does-not-exist has been started. 2025-04-08 07:53:13 Service log-directory-does-not-exist started. 2025-04-08 07:53:13 Service log-directory-does-not-exist running with value= #< id: 8792 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6= r-bash-minimal-5.1.16/bin/bash" "-c" "while true; do echo logging; sleep 0.= 2; done")>. 2025-04-08 07:53:13 Starting service log-directory-not-writable... Terminated ++ rm -f t-log-8783 t-socket-8783 t-conf-8783 t-pid-8783 ++ rm -rf /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log-directo= ry-8783 ++ kill 8788 FAIL tests/logging-failure.sh (exit status: 124) FAIL: tests/forking-service =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D + shepherd --version System lacks support for 'signalfd'; using fallback mechanism. shepherd (GNU Shepherd) 1.0.3 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + herd --version herd (GNU Shepherd) 1.0.3 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + socket=3Dt-socket-10595 + conf=3Dt-conf-10595 + log=3Dt-log-10595 + pid=3Dt-pid-10595 + service_pid=3Dt-service-pid-10595 + service_nofiles=3Dt-service-nofiles-10595 + service2_pid=3Dt-service2-pid-10595 + service2_started=3Dt-service2-starts-10595 + herd=3D'herd -s t-socket-10595' + trap cleanup EXIT + cat + cat t-conf-10595 ;; Leave a timeout long enough for slow machines. (default-pid-file-timeout 10) (define %command =C2=A0 '("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/b= in/bash" "-c" "ulimit -n >/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0= .3/t-service-nofiles-10595; sleep 600 & echo $! > /tmp/guix-build-shepherd-= 1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")) (register-services (list (service ;; A service that forks into a different process. '(test) #:start (make-forkexec-constructor =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2= =A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 %command =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2= =A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 #:pid-file "/tmp/guix-build-shepherd-1.0.= 3.drv-0/shepherd-1.0.3/t-service-pid-10595" =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2= =A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 #:resource-limits '((nofile 1567 1567))) #:stop=C2=A0 (make-kill-destructor) #:respawn? #f))) (define %command2 =C2=A0 '("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/b= in/bash" "-c" "echo started >> /tmp/guix-build-shepherd-1.0.3.drv-0/shepher= d-1.0.3/t-service2-starts-10595; sleep 600 & echo $! > /tmp/guix-build-shep= herd-1.0.3.drv-0/shepherd-1.0.3/t-service2-pid-10595")) (register-services (list (service ;; A service that forks into a different process. '(test2) #:start (make-forkexec-constructor %command2 =C2=A0=C2=A0 #:pid-file "/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.= 3/t-service2-pid-10595") #:stop=C2=A0 (make-kill-destructor) =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 #:respawn-delay 0 #:respawn? #t))) (define %command3 =C2=A0 '("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/b= in/bash" "-c" "sleep 600")) (register-services (list (service ;; A service that forks into a different process. '(test3) #:start (make-forkexec-constructor %command3) #:stop=C2=A0 (make-kill-destructor) #:respawn? #t))) (define %command4 =C2=A0 '("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/b= in/bash" "-c" "trap 'echo ignoring SIGTERM' SIGTERM; while true ; do : ; do= ne")) (register-services (list (service ;; A service that ignores SIGTERM. '(test4) #:requirement '(test3) #:start (make-forkexec-constructor %command4) #:stop=C2=A0 (make-kill-destructor SIGTERM #:grace-period 3)))) + rm -f t-pid-10595 + test -f t-pid-10595 + shepherd -I -s t-socket-10595 -c t-conf-10595 -l t-log-10595 --pid=3Dt-pi= d-10595 + sleep 0.3 System lacks support for 'signalfd'; using fallback mechanism. GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) Starting service root... Service root started. Service root running with value #< id: 10602 command: #f>. Service root has been started. Configuration successfully loaded from 't-conf-10595'. + test -f t-pid-10595 ++ cat t-pid-10595 + shepherd_pid=3D10602 + herd -s t-socket-10595 start test Starting service test... Service test has been started. + herd -s t-socket-10595 start test2 Starting service test2... Service test2 started. Service test2 running with value #< id: 10623 command: ("/gnu/stor= e/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "echo= started >> /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service2-= starts-10595; sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/sh= epherd-1.0.3/t-service2-pid-10595")>. Service test2 has been started. + herd -s t-socket-10595 status test + grep running =C2=A0 It is running since 07:56:16 (1 second ago). + test -f t-service-pid-10595 ++ cat t-service-pid-10595 + service_pid_value=3D10610 + kill 10610 + kill -0 10610 ./tests/forking-service.sh: line 133: kill: (10610) - No such process + within_seconds 10 'herd -s t-socket-10595 status test | grep stopped' + seconds=3D10 + '[' 10 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! Uncaught exception in essential task process-monitor: (wrong-type-arg "=3D"= "Wrong type argument in position ~A: ~S" (1 #< id: 10610 command:= ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash= " "-c" "ulimit -n >/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-se= rvice-nofiles-10595; sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.d= rv-0/shepherd-1.0.3/t-service-pid-10595")>) (#< id: 10610 command:= ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash= " "-c" "ulimit -n >/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-se= rvice-nofiles-10595; sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.d= rv-0/shepherd-1.0.3/t-service-pid-10595")>)) PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 10 - 1 + seconds=3D9 + '[' 9 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 9 - 1 + seconds=3D8 + '[' 8 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 8 - 1 + seconds=3D7 + '[' 7 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 7 - 1 + seconds=3D6 + '[' 6 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 6 - 1 + seconds=3D5 + '[' 5 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 5 - 1 + seconds=3D4 + '[' 4 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 4 - 1 + seconds=3D3 + '[' 3 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 3 - 1 + seconds=3D2 + '[' 2 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 2 - 1 + seconds=3D1 + '[' 1 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 1 - 1 + seconds=3D0 + cleanup + cat t-log-10595 2025-04-08 07:56:14 GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) 2025-04-08 07:56:14 Starting service root... 2025-04-08 07:56:14 Service root started. 2025-04-08 07:56:14 Service root running with value #< id: 10602 c= ommand: #f>. 2025-04-08 07:56:14 Service root has been started. 2025-04-08 07:56:14 Configuration successfully loaded from 't-conf-10595'. 2025-04-08 07:56:15 Starting service test... 2025-04-08 07:56:16 Service test has been started. 2025-04-08 07:56:16 Service test started. 2025-04-08 07:56:16 Service test running with value #< id: 10610 c= ommand: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/b= in/bash" "-c" "ulimit -n >/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0= .3/t-service-nofiles-10595; sleep 600 & echo $! > /tmp/guix-build-shepherd-= 1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")>. 2025-04-08 07:56:16 Starting service test2... 2025-04-08 07:56:17 Service test2 has been started. 2025-04-08 07:56:17 Service test2 started. 2025-04-08 07:56:17 Service test2 running with value #< id: 10623 = command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/= bin/bash" "-c" "echo started >> /tmp/guix-build-shepherd-1.0.3.drv-0/shephe= rd-1.0.3/t-service2-starts-10595; sleep 600 & echo $! > /tmp/guix-build-she= pherd-1.0.3.drv-0/shepherd-1.0.3/t-service2-pid-10595")>. 2025-04-08 07:56:17 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:17 Uncaught exception in essential task process-monitor: (= wrong-type-arg "=3D" "Wrong type argument in position ~A: ~S" (1 #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-min= imal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shepherd-1.0.3.drv-0= /shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! > /tmp/guix-bu= ild-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")>) (#< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-min= imal-5.1. 2025-04-08 07:56:17 16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shepherd-= 1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! > /= tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")>)) 2025-04-08 07:56:18 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:18 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:19 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:19 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:20 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:20 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:21 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:21 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:22 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:22 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:23 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:23 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:24 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:24 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:25 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:25 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:26 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:26 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:27 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! + rm -f t-socket-10595 t-conf-10595 t-log-10595 t-service2-starts-10595 t-s= ervice-nofiles-10595 + test -f t-pid-10595 ++ cat t-pid-10595 + kill 10602 + rm -f t-pid-10595 Stopping service root... Exiting shepherd... Ignoring error while stopping test: (system-error "getpgid" "~A" ("No such = process") (1073741827)) Stopping service test2... Awaiting PID 10622, which is already gone. Service test2 stopped. Service test2 is now stopped. Service test3 is not running. Service test4 is not running. Exiting. + test -f t-service-pid-10595 ++ cat t-service-pid-10595 + kill 10610 ./tests/forking-service.sh: line 40: kill: (10610) - No such process + true + rm -f t-service-pid-10595 + test -f t-service2-pid-10595 ++ cat t-service2-pid-10595 + kill 10623 ./tests/forking-service.sh: line 42: kill: (10623) - No such process + true + rm -f t-service2-pid-10595 FAIL tests/forking-service.sh (exit status: 1) FAIL: tests/services/system-log =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D + shepherd --version System lacks support for 'signalfd'; using fallback mechanism. shepherd (GNU Shepherd) 1.0.3 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + herd --version herd (GNU Shepherd) 1.0.3 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + socket=3Dt-socket-12440 + conf=3Dt-conf-12440 + log=3Dt-log-12440 + pid=3Dt-pid-12440 + logger=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-log= ger-12440.scm + kmsg=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-kmsg-= 12440 + syslog_file=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslo= g-12440 + syslog_auth_file=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-= syslog-auth-12440 + syslog_debug_file=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -syslog-debug-12440 + syslog_remote_file=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/= t-syslog-remote-12440 + syslog_socket=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-sys= log-socket-12440 + herd=3D'herd -s t-socket-12440' + trap 'zcat t-log-12440.* || true; cat t-log-12440 || true; =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 rm -f t-socket-12440 t-conf-12440 t-log-1244= 0* /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-logger-1244= 0.scm /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-kmsg-124= 40 /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-socket-1244= 0; =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 rm -f /tmp/guix-build-shepherd-1.0.3.drv-0/s= hepherd-1.0.3/t-syslog-12440* /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd= -1.0.3/t-syslog-auth-12440* /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1= .0.3/t-syslog-debug-12440* /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.= 0.3/t-syslog-remote-12440*; =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 test -f t-pid-12440 && kill `cat t-pid-12440= ` || true; rm -f t-pid-12440' EXIT + cat + cat + chmod +x /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-log= ger-12440.scm + cat + rm -f t-pid-12440 + test -f t-pid-12440 + shepherd -I -s t-socket-12440 -c t-conf-12440 -l t-log-12440 --pid=3Dt-pi= d-12440 + sleep 0.3 System lacks support for 'signalfd'; using fallback mechanism. GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) Starting service root... Service root started. Service root running with value #< id: 12448 command: #f>. Service root has been started. Configuration successfully loaded from 't-conf-12440'. + test -f t-pid-12440 + herd -s t-socket-12440 eval root '(gc)' Evaluating user expression (gc). "#" ++ file_descriptor_count ++ wc -l +++ cat t-pid-12440 ++ ls -l '/proc/12448/fd/[0-9]*' ls: cannot access '/proc/12448/fd/[0-9]*': No such file or directory + initial_fd_count=3D0 + herd -s t-socket-12440 start logger Starting service system-log... Service system-log has been started. Service system-log started. ;;; (message-destination-> ("/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-= 1.0.3/t-syslog-12440")) Service system-log running with value #. Starting service logger... Service logger has been started. + herd -s t-socket-12440 status logger + grep stopped ++ zcat 't-log-12440.*' gzip: t-log-12440.*.gz: No such file or directory Terminated ++ true ++ cat t-log-12440 2025-04-08 07:57:32 GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) 2025-04-08 07:57:32 Starting service root... 2025-04-08 07:57:32 Service root started. 2025-04-08 07:57:32 Service root running with value #< id: 12448 c= ommand: #f>. 2025-04-08 07:57:32 Service root has been started. 2025-04-08 07:57:32 Configuration successfully loaded from 't-conf-12440'. 2025-04-08 07:57:32 Evaluating user expression (gc). 2025-04-08 07:57:32 Starting service system-log... 2025-04-08 07:57:59 Service system-log has been started. 2025-04-08 07:57:59 Service system-log started. 2025-04-08 07:58:32=C2=A0 2025-04-08 07:58:32 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-12440")) 2025-04-08 07:58:32 Service system-log running with value #. 2025-04-08 07:58:32 Starting service logger... 2025-04-08 07:58:32 Service logger has been started. 2025-04-08 07:58:32 Service logger started. 2025-04-08 07:58:32=C2=A0 2025-04-08 07:58:32 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:58:32=C2=A0 2025-04-08 07:58:32 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:58:32 Service logger running with value #< id: 12978= command: ("/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-lo= gger-12440.scm")>. 2025-04-08 07:58:32=C2=A0 2025-04-08 07:58:32 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:58:32=C2=A0 2025-04-08 07:58:43 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:58:43=C2=A0 2025-04-08 07:58:45 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:58:45=C2=A0 2025-04-08 07:58:59 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:58:59=C2=A0 2025-04-08 07:59:00 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:59:00=C2=A0 2025-04-08 07:59:05 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:59:05=C2=A0 2025-04-08 07:59:05 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:59:05=C2=A0 2025-04-08 07:59:06 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:59:06 t-syslog-logger-12440.scm[12978] starting logger 2025-04-08 07:59:07 Service logger has been disabled. ++ rm -f t-socket-12440 t-conf-12440 't-log-12440*' /tmp/guix-build-shepher= d-1.0.3.drv-0/shepherd-1.0.3/t-syslog-logger-12440.scm /tmp/guix-build-shep= herd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-kmsg-12440 /tmp/guix-build-shepher= d-1.0.3.drv-0/shepherd-1.0.3/t-syslog-socket-12440 ++ rm -f '/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-1244= 0*' '/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-auth-1244= 0*' '/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-debug-124= 40*' '/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-remote-1= 2440*' ++ test -f t-pid-12440 +++ cat t-pid-12440 ++ kill 12448 ++ rm -f t-pid-12440 FAIL tests/services/system-log.sh (exit status: 124) yelninei From unknown Sun Jun 15 13:02:11 2025 X-Loop: help-debbugs@gnu.org Subject: bug#77634: [shepherd] Test failures on GNU/Hurd Resent-From: Ludovic =?UTF-8?Q?Court=C3=A8s?= Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Sun, 13 Apr 2025 21:10:01 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: followup 77634 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: 77634@debbugs.gnu.org Cc: yelninei@tutamail.com X-Debbugs-Original-To: yelninei--- via Bug reports for GNU Guix X-Debbugs-Original-Cc: 77634@debbugs.gnu.org, yelninei@tutamail.com Received: via spool by submit@debbugs.gnu.org id=B.174457857511556 (code B ref -1); Sun, 13 Apr 2025 21:10:01 +0000 Received: (at submit) by debbugs.gnu.org; 13 Apr 2025 21:09:35 +0000 Received: from localhost ([127.0.0.1]:44162 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1u44a6-00030I-G6 for submit@debbugs.gnu.org; Sun, 13 Apr 2025 17:09:35 -0400 Received: from lists.gnu.org ([2001:470:142::17]:38652) by debbugs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.84_2) (envelope-from ) id 1u44Zr-0002zT-4c for submit@debbugs.gnu.org; Sun, 13 Apr 2025 17:09:19 -0400 Received: from eggs.gnu.org ([2001:470:142:3::10]) by lists.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1u44Zk-0005yw-Mx for bug-guix@gnu.org; Sun, 13 Apr 2025 17:09:13 -0400 Received: from fencepost.gnu.org ([2001:470:142:3::e]) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1u44Zk-0000aS-Bs; Sun, 13 Apr 2025 17:09:12 -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:References:In-Reply-To:Subject:To: From; bh=M6ybCEP7Luu3TsPIjqQgwY8frOEaG+n+wVtQaaiSu6Y=; b=iY3F6gu+SQm99amuXg5w g1vTsSdyfWyUTZRQWlsxjk+Q6gmVpFN/2S9/AcmSDk8RmAdmdzOdE29wBhA7yG60JPey0Vih+JZVB unM5aewL/6/IlgXq7TUy8F+zZkSPQZF6ScfMPFb8HcW+ZG/Pobq9PL8PV8euodZksG8JVgZreXUUn 5UwXZiPPEok7ECbsnR3KgXa2ztzaAgYgu66grIOIyUpfRAwiAMvcWrAZSullCq2mYgfcygBNwq/5q e1pzlFrxlDKeudbDw/7wS16Yl3tNQFn9LwIuWo0Bi67dA2uYd4uTixRdmwGiCmJWeRg4Jgk4leniu Rqf4i60GgVFKZQ==; From: Ludovic =?UTF-8?Q?Court=C3=A8s?= In-Reply-To: (yelninei's message of "Tue, 8 Apr 2025 10:43:17 +0200 (CEST)") References: User-Agent: mu4e 1.12.9; emacs 29.4 X-URL: https://people.bordeaux.inria.fr/lcourtes/ X-PGP-Fingerprint: 3CE4 6455 8A84 FDC6 9DB4 0CFB 090B 1199 3D9A EBB5 X-OS: x86_64-pc-linux-gnu X-Revolutionary-Date: Quartidi 24 Germinal an 233 de la =?UTF-8?Q?R=C3=A9volution,?= jour de la Roquette Date: Sun, 13 Apr 2025 23:09:07 +0200 Message-ID: <8734ebdb0s.fsf@gnu.org> MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-Spam-Score: -0.0 (/) X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -1.0 (-) Hello, Thanks for the bug report! yelninei--- via Bug reports for GNU Guix skribis: > Also I had to cheat a bit to get past automakes "t/output-order.sh" test = where the guile build driver is leaking "GC Warning: Repeated allocation of= very large block" onto stderr confusing the test (similar problem to https= ://issues.guix.gnu.org/73181). I added env["GC_LARGE_ALLOC_WARN_INTERVAL"] = =3D "100" to=C2=A0DerivationGoal::startBuilder() and used the slightly patc= hed daemon to build automake without changing the derivation. Uh, sounds terrible. > + herd -s t-socket-8783 start log-directory-not-writable > ++ cat t-log-8783 > 2025-04-08 07:53:13 GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) > 2025-04-08 07:53:13 Starting service root... > 2025-04-08 07:53:13 Service root started. > 2025-04-08 07:53:13 Service root running with value #< id: 8788 = command: #f>. > 2025-04-08 07:53:13 Service root has been started. > 2025-04-08 07:53:13 Configuration successfully loaded from 't-conf-8783'. > 2025-04-08 07:53:13 Starting service log-directory-does-not-exist... > 2025-04-08 07:53:13 Service log-directory-does-not-exist has been started. > 2025-04-08 07:53:13 Service log-directory-does-not-exist started. > 2025-04-08 07:53:13 Service log-directory-does-not-exist running with val= ue #< id: 8792 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9j= d6r-bash-minimal-5.1.16/bin/bash" "-c" "while true; do echo logging; sleep = 0.2; done")>. > 2025-04-08 07:53:13 Starting service log-directory-not-writable... > Terminated > ++ rm -f t-log-8783 t-socket-8783 t-conf-8783 t-pid-8783 > ++ rm -rf /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log-direc= tory-8783 > ++ kill 8788 > FAIL tests/logging-failure.sh (exit status: 124) Apparently the =E2=80=98shepherd=E2=80=99 process was terminated by the =E2= =80=98timeout=E2=80=99 command, meaning that the test hanged for more than 3 minutes. I failed to reproduce it on Linux (without =E2=80=98signalfd=E2=80=99 and w= ithout =E2=80=98PR_SET_CHILD_SUBREAPER=E2=80=99), and I have yet to build dependen= cies in my childhurd to try it natively=E2=80=A6 > 2025-04-08 07:56:27 PID #< id: 10610 command: ("/gnu/store/45ssy= l3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >= /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595= ; sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3= /t-service-pid-10595")> (test) is dead! [...] > FAIL tests/forking-service.sh (exit status: 1) This one is a bug fixed in Shepherd commit 82a333f8e83b4abbd19dbc6c9bab1b7d62cced0c. > + herd -s t-socket-12440 start logger > Starting service system-log... > Service system-log has been started. > Service system-log started. > > ;;; (message-destination-> ("/tmp/guix-build-shepherd-1.0.3.drv-0/shepher= d-1.0.3/t-syslog-12440")) > Service system-log running with value #. > Starting service logger... > Service logger has been started. > + herd -s t-socket-12440 status logger > + grep stopped > ++ zcat 't-log-12440.*' > gzip: t-log-12440.*.gz: No such file or directory > Terminated [...] > FAIL tests/services/system-log.sh (exit status: 124) This one also hanged more than 3m it seems. To be continued=E2=80=A6 Ludo=E2=80=99. From unknown Sun Jun 15 13:02:11 2025 X-Loop: help-debbugs@gnu.org Subject: bug#77634: [shepherd] Test failures on GNU/Hurd Resent-From: yelninei@tutamail.com Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Mon, 14 Apr 2025 11:47:02 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: followup 77634 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: Ludovic =?UTF-8?Q?Court=C3=A8s?= Cc: 77634 <77634@debbugs.gnu.org> Received: via spool by 77634-submit@debbugs.gnu.org id=B77634.174463122028368 (code B ref 77634); Mon, 14 Apr 2025 11:47:02 +0000 Received: (at 77634) by debbugs.gnu.org; 14 Apr 2025 11:47:00 +0000 Received: from localhost ([127.0.0.1]:46094 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1u4IHD-0007NP-DV for submit@debbugs.gnu.org; Mon, 14 Apr 2025 07:47:00 -0400 Received: from mail.w13.tutanota.de ([185.205.69.213]:33106) by debbugs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.84_2) (envelope-from ) id 1u4IH9-0007Mz-Rp for 77634@debbugs.gnu.org; Mon, 14 Apr 2025 07:46:56 -0400 Received: from tutadb.w10.tutanota.de (w10.api.tuta.com [IPv6:fd:ac::d:10]) by mail.w13.tutanota.de (Postfix) with ESMTP id A193C7A0F86D for <77634@debbugs.gnu.org>; Mon, 14 Apr 2025 13:46:49 +0200 (CEST) DKIM-Signature: v=1; a=rsa-sha256; q=dns/txt; c=relaxed/relaxed; t=1744631209; s=s1; d=tutamail.com; h=From:From:To:To:Subject:Subject:Content-Description:Content-ID:Content-Type:Content-Type:Content-Transfer-Encoding:Content-Transfer-Encoding:Cc:Cc:Date:Date:In-Reply-To:In-Reply-To:MIME-Version:MIME-Version:Message-ID:Message-ID:Reply-To:References:References:Sender; bh=rje6DIu36XV0KpLUxLzEYFgB/UiSK9E7hBfwvC89CJA=; b=arwi5SqDm2jJNkP47Q6SjSpVaiIhMEodbHDqOxmjqyhv7F9ICG+LHqDeK4SoXHRK 4G42UZelhaEupT6lX6jLb+j63czLUfFCYYtoTMHoVTTsf/NXvJxUtcwGYscNWsLOEB3 40nZUF6TY+elE2hMqcIXwdlYsAvrxk0NJz7zUI27Qzpy+3K5RQaSfYJJrT68VBt3lBN OB1NOgrkXHbDBGHTh1Jmtn2mVhTOP1cPQ6hhTbpFmGuTxt+/CnyvcQNRjDjb2YMLR1N WuAu5nTFEpkgZY8UWsod0Y4Lr/x/Y5R7cAcapx/4WKYKFURXJW6smfkH4zqN9E0FYrA l0l/KPhkmQ== Date: Mon, 14 Apr 2025 13:46:49 +0200 (CEST) From: yelninei@tutamail.com Message-ID: In-Reply-To: <8734ebdb0s.fsf@gnu.org> References: <8734ebdb0s.fsf@gnu.org> MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable X-Spam-Score: -0.7 (/) X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -1.7 (-) Hello Ludo, Apr 13, 2025, 21:09 by ludo@gnu.org: >> + herd -s t-socket-8783 start log-directory-not-writable >> ++ cat t-log-8783 >> 2025-04-08 07:53:13 GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) >> 2025-04-08 07:53:13 Starting service root... >> 2025-04-08 07:53:13 Service root started. >> 2025-04-08 07:53:13 Service root running with value #< id: 8788= command: #f>. >> 2025-04-08 07:53:13 Service root has been started. >> 2025-04-08 07:53:13 Configuration successfully loaded from 't-conf-8783'= . >> 2025-04-08 07:53:13 Starting service log-directory-does-not-exist... >> 2025-04-08 07:53:13 Service log-directory-does-not-exist has been starte= d. >> 2025-04-08 07:53:13 Service log-directory-does-not-exist started. >> 2025-04-08 07:53:13 Service log-directory-does-not-exist running with va= lue #< id: 8792 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9= jd6r-bash-minimal-5.1.16/bin/bash" "-c" "while true; do echo logging; sleep= 0.2; done")>. >> 2025-04-08 07:53:13 Starting service log-directory-not-writable... >> Terminated >> ++ rm -f t-log-8783 t-socket-8783 t-conf-8783 t-pid-8783 >> ++ rm -rf /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log-dire= ctory-8783 >> ++ kill 8788 >> FAIL tests/logging-failure.sh (exit status: 124) >> > > Apparently the =E2=80=98shepherd=E2=80=99 process was terminated by the = =E2=80=98timeout=E2=80=99 > command, meaning that the test hanged for more than 3 minutes. > > I failed to reproduce it on Linux (without =E2=80=98signalfd=E2=80=99 and= without > =E2=80=98PR_SET_CHILD_SUBREAPER=E2=80=99), and I have yet to build depend= encies in my > childhurd to try it natively=E2=80=A6 > If there is something you want me to try let me know. I was able to reproduce this by extracting the shepherd conf from the test = and and attempting to run the test manually on the childhurd with the cross= compiled shepherd (so you should not need to have a native toolchain avail= able) Might be the attempt to write to /proc. Starting the=C2=A0log-directory-not-writable service causes the entire shep= herd to become completely unresponsive causing the timeout failure. A similar behavior occurs with (call-with-output-file "/proc/1/something" =C2=A0 (lambda (port) =C2=A0=C2=A0=C2=A0 (display "Hello" port))) which hangs my guile completely. When I change the log file to a more normal unwritable path the test passes= . >> 2025-04-08 07:56:27 PID #< id: 10610 command: ("/gnu/store/45ss= yl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n = >/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-1059= 5; sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.= 3/t-service-pid-10595")> (test) is dead! >> > > [...] > >> FAIL tests/forking-service.sh (exit status: 1) >> > > This one is a bug fixed in Shepherd commit > 82a333f8e83b4abbd19dbc6c9bab1b7d62cced0c. > Can confirm that this is fixed in 1.0.4rc1. >> + herd -s t-socket-12440 start logger >> Starting service system-log... >> Service system-log has been started. >> Service system-log started. >> >> ;;; (message-destination-> ("/tmp/guix-build-shepherd-1.0.3.drv-0/shephe= rd-1.0.3/t-syslog-12440")) >> Service system-log running with value #. >> Starting service logger... >> Service logger has been started. >> + herd -s t-socket-12440 status logger >> + grep stopped >> ++ zcat 't-log-12440.*' >> gzip: t-log-12440.*.gz: No such file or directory >> Terminated >> > > [...] > >> FAIL tests/services/system-log.sh (exit status: 124) >> > > This one also hanged more than 3m it seems. > The shepherd syslog seems to be extremely slow: I extracted the logger.scm and conf.scm from the test, removed the $ from t= he shell variables variables, started shepherd, echoed the test msg into=C2= =A0 the kmsg file and then tried to start the shepherd syslog. It took multiple minutes to see the=C2=A0"Service system-log running with v= alue" in the log and then another couple of minutes for "herd start syslogd= " to return. Afterwards trying to query the syslog status (or trying to int= eract with shepherd in any other way) takes forever to complete while syslo= gd is running. I did this with the 1.0.4rc1 shepherd to work around the blocking socket pr= oblem with guix-daemon (#77610, it still fails on the first connection now = but idk if this is a problem with shepherd or the guix-daemon) With the 1.0.3 shepherd syslogd seems to be a lot quicker to start initally= ( see the log from the first message) but still extremely slow to interact= with afterwards. Even after removing the test timeout the test will fail eventually after ~9= 00 seconds because $syslog_auth_file did not show up (yet?). > To be continued=E2=80=A6 > > Ludo=E2=80=99. > Thank you for your hard work on shepherd. From unknown Sun Jun 15 13:02:11 2025 X-Loop: help-debbugs@gnu.org Subject: bug#77634: [shepherd] Test failures on GNU/Hurd Resent-From: Ludovic =?UTF-8?Q?Court=C3=A8s?= Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Mon, 14 Apr 2025 13:29:02 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: followup 77634 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: yelninei@tutamail.com Cc: 77634 <77634@debbugs.gnu.org> Received: via spool by 77634-submit@debbugs.gnu.org id=B77634.174463728816165 (code B ref 77634); Mon, 14 Apr 2025 13:29:02 +0000 Received: (at 77634) by debbugs.gnu.org; 14 Apr 2025 13:28:08 +0000 Received: from localhost ([127.0.0.1]:46407 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1u4Jr5-0004Cc-Vw for submit@debbugs.gnu.org; Mon, 14 Apr 2025 09:28:08 -0400 Received: from eggs.gnu.org ([2001:470:142:3::10]:59036) by debbugs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.84_2) (envelope-from ) id 1u4Jr3-0004CE-VK for 77634@debbugs.gnu.org; Mon, 14 Apr 2025 09:28:07 -0400 Received: from fencepost.gnu.org ([2001:470:142:3::e]) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1u4Jqy-00058Q-Cs; Mon, 14 Apr 2025 09:28:00 -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:References:In-Reply-To:Subject:To: From; bh=oCORY2BZRCKzeRsdTSZSyRiqElypQSuGAAWMa2q832w=; b=HobyPucXmMDU910s+I3l QggnZMGUN9IzmllADkG55lOZk/a1wb1u7jMklNEqUbn/LEFrIED7XyfhIGVw8gnjXOy1w3xcBpiaf T3ywS9WyybXw+D3Wom/PsefYZeeyoSmugtVPFVaOf1z0KvPuDif+0QsNT+GeI/iE2su5p8xwmZCWZ PHAEoIwWG5FoSK+08WGuU0iTiG6GDRH4HPwWJIrRVcKgd1lZDpKR4SDh2ujDPj5LDuSIoiJ1X+dgX bSGN02eqeDJ4aZPXs6cfZp4QirEugYx7PtFDBm86XaH6zhpzloO+aFwhs672wvBFKEpL9rCwpR+Lo GJA5eke1qb0umA==; From: Ludovic =?UTF-8?Q?Court=C3=A8s?= In-Reply-To: (yelninei@tutamail.com's message of "Mon, 14 Apr 2025 13:46:49 +0200 (CEST)") References: <8734ebdb0s.fsf@gnu.org> User-Agent: mu4e 1.12.9; emacs 29.4 X-URL: https://people.bordeaux.inria.fr/lcourtes/ X-PGP-Fingerprint: 3CE4 6455 8A84 FDC6 9DB4 0CFB 090B 1199 3D9A EBB5 X-OS: x86_64-pc-linux-gnu X-Revolutionary-Date: Quintidi 25 Germinal an 233 de la =?UTF-8?Q?R=C3=A9volution,?= jour du Pigeon Date: Mon, 14 Apr 2025 15:27:58 +0200 Message-ID: <87tt6qg9ep.fsf@gnu.org> MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-Spam-Score: -2.3 (--) X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -3.3 (---) Hi yelninei, yelninei@tutamail.com writes: >>> FAIL tests/logging-failure.sh (exit status: 124) [...] > I was able to reproduce this by extracting the shepherd conf from the > test and and attempting to run the test manually on the childhurd with > the cross compiled shepherd (so you should not need to have a native > toolchain available) > > > Might be the attempt to write to /proc. > > Starting the=C2=A0log-directory-not-writable service causes the entire sh= epherd to become completely unresponsive causing the timeout failure. > > A similar behavior occurs with > > (call-with-output-file "/proc/1/something" > =C2=A0 (lambda (port) > =C2=A0=C2=A0=C2=A0 (display "Hello" port))) > > which hangs my guile completely. > > When I change the log file to a more normal unwritable path the test pass= es. Ah, interesting. So /something instead of /proc/1/something would work, right? (I picked /proc/1/something because =E2=80=98guix shell -C=E2=80=99= currently gives a writable root file system=E2=80=A6 Going to be fixed with .) You could also report the procfs issue to bug-hurd I guess. :-) >>> FAIL tests/services/system-log.sh (exit status: 124) >>> >> >> This one also hanged more than 3m it seems. >> > The shepherd syslog seems to be extremely slow: > > I extracted the logger.scm and conf.scm from the test, removed the $ > from the shell variables variables, started shepherd, echoed the test > msg into=C2=A0 the kmsg file and then tried to start the shepherd syslog. > > It took multiple minutes to see the=C2=A0"Service system-log running with > value" in the log and then another couple of minutes for "herd start > syslogd" to return. Afterwards trying to query the syslog status (or > trying to interact with shepherd in any other way) takes forever to > complete while syslogd is running. > > I did this with the 1.0.4rc1 shepherd to work around the blocking > socket problem with guix-daemon (#77610, it still fails on the first > connection now but idk if this is a problem with shepherd or the > guix-daemon) > > With the 1.0.3 shepherd syslogd seems to be a lot quicker to start > initally ( see the log from the first message) but still extremely > slow to interact with afterwards. Weird. Could you bisect between 1.0.3 and HEAD to try and find the source of slowness? I wonder if f1a82845eaf8851af9a811e5a1d185b68b1c363f might explain it, but that=E2=80=99s pre-1.0.3. Alternatively, you can try running =E2=80=98shepherd=E2=80=99 under =E2=80= =98rpctrace=E2=80=99 for the syslog slowness issue, so we get an idea of what it=E2=80=99s doing. Thanks a lot for helping out! Ludo=E2=80=99. From unknown Sun Jun 15 13:02:11 2025 X-Loop: help-debbugs@gnu.org Subject: bug#77634: [shepherd] Test failures on GNU/Hurd Resent-From: yelninei@tutamail.com Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Mon, 14 Apr 2025 14:55:01 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: followup 77634 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: Ludovic =?UTF-8?Q?Court=C3=A8s?= Cc: 77634 <77634@debbugs.gnu.org> Received: via spool by 77634-submit@debbugs.gnu.org id=B77634.17446424552335 (code B ref 77634); Mon, 14 Apr 2025 14:55:01 +0000 Received: (at 77634) by debbugs.gnu.org; 14 Apr 2025 14:54:15 +0000 Received: from localhost ([127.0.0.1]:48470 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1u4LCQ-0000ba-1i for submit@debbugs.gnu.org; Mon, 14 Apr 2025 10:54:14 -0400 Received: from mail.w13.tutanota.de ([185.205.69.213]:51308) by debbugs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.84_2) (envelope-from ) id 1u4LCN-0000bE-CG for 77634@debbugs.gnu.org; Mon, 14 Apr 2025 10:54:12 -0400 Received: from tutadb.w10.tutanota.de (w10.api.tuta.com [IPv6:fd:ac::d:10]) by mail.w13.tutanota.de (Postfix) with ESMTP id 5F7347A276D9 for <77634@debbugs.gnu.org>; Mon, 14 Apr 2025 16:53:35 +0200 (CEST) DKIM-Signature: v=1; a=rsa-sha256; q=dns/txt; c=relaxed/relaxed; t=1744642415; s=s1; d=tutamail.com; h=From:From:To:To:Subject:Subject:Content-Description:Content-ID:Content-Type:Content-Type:Content-Transfer-Encoding:Content-Transfer-Encoding:Cc:Cc:Date:Date:In-Reply-To:In-Reply-To:MIME-Version:MIME-Version:Message-ID:Message-ID:Reply-To:References:References:Sender; bh=io8gSkkS2ZUjG2PegvDsUqS8enHQfH3oXFffyBhs20c=; b=oa91hmK9LUbAwe7/YtZ35FQL+FwNq0lJqiYf+aHFbCWwl1k0WHKBhL6LhH96zlWy 3TLhCrzGHFwyVmVZlS8ksHVhoUG6cDlyVBG0qL/uKo9INY2+hRXAVWfrtbMoRBX0vqb N93U8E7Eu8kObkqIFBjmKR0MdI89BE/OV3MjPp93sM+eLxZW2+vOhKvZD4jYNS2CZ+3 QSgMVhRq95nmZtOnzrTEBAaLT1Li3m7bmKg1IareFVNfx3zW191XDI/SiMkbtfQJIFy mV+2MunFSoMgfThCxGq+XC51Kr8S81USaO0lteUzrAMI1+SLbyzwxITlT+j6oyEvmLL TQN643Sekw== Date: Mon, 14 Apr 2025 16:53:35 +0200 (CEST) From: yelninei@tutamail.com Message-ID: In-Reply-To: <87tt6qg9ep.fsf@gnu.org> References: <8734ebdb0s.fsf@gnu.org> <87tt6qg9ep.fsf@gnu.org> MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable X-Spam-Score: -0.7 (/) X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -1.7 (-) Hello Ludo, Apr 14, 2025, 13:28 by ludo@gnu.org: > Hi yelninei, > > yelninei@tutamail.com writes: > >>>> FAIL tests/logging-failure.sh (exit status: 124) >>>> > > [...] > >> I was able to reproduce this by extracting the shepherd conf from the >> test and and attempting to run the test manually on the childhurd with >> the cross compiled shepherd (so you should not need to have a native >> toolchain available) >> >> >> Might be the attempt to write to /proc. >> >> Starting the=C2=A0log-directory-not-writable service causes the entire s= hepherd to become completely unresponsive causing the timeout failure. >> >> A similar behavior occurs with >> >> (call-with-output-file "/proc/1/something" >> =C2=A0 (lambda (port) >> =C2=A0=C2=A0=C2=A0 (display "Hello" port))) >> >> which hangs my guile completely. >> >> When I change the log file to a more normal unwritable path the test pas= ses. >> > > Ah, interesting. So /something instead of /proc/1/something would work, > right? (I picked /proc/1/something because =E2=80=98guix shell -C=E2=80= =99 currently > gives a writable root file system=E2=80=A6 Going to be fixed with > .) > > You could also report the procfs issue to bug-hurd I guess. :-) > Yes. I think reproducing this on the latest mach/hurd first would be better but = I have been a bit busy trying to understand what went wrong with glibc2.41 >>>> FAIL tests/services/system-log.sh (exit status: 124) >>>> >>> >>> This one also hanged more than 3m it seems. >>> >> The shepherd syslog seems to be extremely slow: >> >> I extracted the logger.scm and conf.scm from the test, removed the $ >> from the shell variables variables, started shepherd, echoed the test >> msg into=C2=A0 the kmsg file and then tried to start the shepherd syslog= . >> >> It took multiple minutes to see the=C2=A0"Service system-log running wit= h >> value" in the log and then another couple of minutes for "herd start >> syslogd" to return. Afterwards trying to query the syslog status (or >> trying to interact with shepherd in any other way) takes forever to >> complete while syslogd is running. >> >> I did this with the 1.0.4rc1 shepherd to work around the blocking >> socket problem with guix-daemon (#77610, it still fails on the first >> connection now but idk if this is a problem with shepherd or the >> guix-daemon) >> >> With the 1.0.3 shepherd syslogd seems to be a lot quicker to start >> initally ( see the log from the first message) but still extremely >> slow to interact with afterwards. >> > > Weird. Could you bisect between 1.0.3 and HEAD to try and find the > source of slowness? > > I wonder if f1a82845eaf8851af9a811e5a1d185b68b1c363f might explain it, > but that=E2=80=99s pre-1.0.3. > > Alternatively, you can try running =E2=80=98shepherd=E2=80=99 under =E2= =80=98rpctrace=E2=80=99 for the > syslog slowness issue, so we get an idea of what it=E2=80=99s doing. > > Thanks a lot for helping out! > > Ludo=E2=80=99. > It is still slow on 1.0.3 to do anything once its started but with 1.0.4rc1= even starting is extremely painful. I tried to manually add features to the system-log-service and the thing th= at causes everything to slow down drastically is the addition of the #:kern= el-log-file (even if it is empty). Setting that to #f instead makes the test now fail considerable faster (1.8= s rather than 900). Running the logger manually only the=C2=A0syslog_remote_file seems to be cr= eated. This is consistent with the log output from the test. I also added a pk for the message-sender to investigate. Difference I can see is that on Linux most of them are #f but here are #(1 = \x00") instead FAIL: tests/services/system-log =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D + shepherd --version System lacks support for 'signalfd'; using fallback mechanism. shepherd (GNU Shepherd) 1.0.4rc1 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + herd --version herd (GNU Shepherd) 1.0.4rc1 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + socket=3Dt-socket-25332 + conf=3Dt-conf-25332 + log=3Dt-log-25332 + pid=3Dt-pid-25332 + logger=3D/tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/source/t-sysl= og-logger-25332.scm + kmsg=3D/tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/source/t-syslog= -kmsg-25332 + syslog_file=3D/tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/source/t= -syslog-25332 + syslog_auth_file=3D/tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/sou= rce/t-syslog-auth-25332 + syslog_debug_file=3D/tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/so= urce/t-syslog-debug-25332 + syslog_remote_file=3D/tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/s= ource/t-syslog-remote-25332 + syslog_socket=3D/tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/source= /t-syslog-socket-25332 + herd=3D'herd -s t-socket-25332' + trap 'zcat t-log-25332.* || true; cat t-log-25332 || true; =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 rm -f t-socket-25332 t-conf-25332 t-log-2533= 2* /tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/source/t-syslog-logge= r-25332.scm /tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/source/t-sys= log-kmsg-25332 /tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/source/t-= syslog-socket-25332; =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 rm -f /tmp/guix-build-shepherd-libevent-1.0.= 99-git.drv-0/source/t-syslog-25332* /tmp/guix-build-shepherd-libevent-1.0.9= 9-git.drv-0/source/t-syslog-auth-25332* /tmp/guix-build-shepherd-libevent-1= .0.99-git.drv-0/source/t-syslog-debug-25332* /tmp/guix-build-shepherd-libev= ent-1.0.99-git.drv-0/source/t-syslog-remote-25332*; =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 test -f t-pid-25332 && kill `cat t-pid-25332= ` || true; rm -f t-pid-25332' EXIT + cat + cat + chmod +x /tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/source/t-sysl= og-logger-25332.scm + cat + rm -f t-pid-25332 + test -f t-pid-25332 + shepherd -I -s t-socket-25332 -c t-conf-25332 -l t-log-25332 --pid=3Dt-pi= d-25332 + sleep 0.3 System lacks support for 'signalfd'; using fallback mechanism. GNU Shepherd 1.0.4rc1 (Guile 3.0.9, i586-unknown-gnu) Starting service root... Service root started. Service root running with value #< id: 25340 command: #f>. Service root has been started. Configuration successfully loaded from 't-conf-25332'. + test -f t-pid-25332 + herd -s t-socket-25332 eval root '(gc)' Evaluating user expression (gc). "#" ++ file_descriptor_count ++ wc -l +++ cat t-pid-25332 ++ ls -l '/proc/25340/fd/[0-9]*' ls: cannot access '/proc/25340/fd/[0-9]*': No such file or directory + initial_fd_count=3D0 + herd -s t-socket-25332 start logger Starting service system-log... Service system-log has been started. Service system-log started. Service system-log running with value #. Starting service logger... Service logger has been started. + herd -s t-socket-25332 status logger + grep stopped =C2=A0 It is stopped since 14:39:57 (0 seconds ago). + grep 'starting logger' t-log-25332 2025-04-14 14:39:57 t-syslog-logger-25332.scm[25348] starting logger + grep 'sudo:.* session opened' /tmp/guix-build-shepherd-libevent-1.0.99-gi= t.drv-0/source/t-syslog-auth-25332 grep: /tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/source/t-syslog-au= th-25332: No such file or directory + zcat 't-log-25332.*' gzip: t-log-25332.*.gz: No such file or directory + true + cat t-log-25332 2025-04-14 14:39:57 GNU Shepherd 1.0.4rc1 (Guile 3.0.9, i586-unknown-gnu) 2025-04-14 14:39:57 Starting service root... 2025-04-14 14:39:57 Service root started. 2025-04-14 14:39:57 Service root running with value #< id: 25340 c= ommand: #f>. 2025-04-14 14:39:57 Service root has been started. 2025-04-14 14:39:57 Configuration successfully loaded from 't-conf-25332'. 2025-04-14 14:39:57 Evaluating user expression (gc). 2025-04-14 14:39:57 Starting service system-log... 2025-04-14 14:39:57 Service system-log has been started. 2025-04-14 14:39:57 Service system-log started. 2025-04-14 14:39:57 Service system-log running with value #. 2025-04-14 14:39:57 Starting service logger... 2025-04-14 14:39:57 Service logger has been started. 2025-04-14 14:39:57 Service logger started. 2025-04-14 14:39:57 Service logger running with value #< id: 25348= command: ("/tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/source/t-sys= log-logger-25332.scm")>. 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (system-log-message-sender-> #(1 "\x00")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (message-destination-> ("/tmp/guix-build-shepherd-l= ibevent-1.0.99-git.drv-0/source/t-syslog-remote-25332")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (system-log-message-sender-> #(2 2130706433 52204)) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (message-destination-> ("/tmp/guix-build-shepherd-l= ibevent-1.0.99-git.drv-0/source/t-syslog-remote-25332")) 2025-04-14 14:39:57 t-syslog-logger-25332.scm[25348] starting logger 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (system-log-message-sender-> #(1 "\x00")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (message-destination-> ("/tmp/guix-build-shepherd-l= ibevent-1.0.99-git.drv-0/source/t-syslog-remote-25332")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (system-log-message-sender-> #(1 "\x00")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (message-destination-> ("/tmp/guix-build-shepherd-l= ibevent-1.0.99-git.drv-0/source/t-syslog-remote-25332")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (system-log-message-sender-> #(1 "\x00")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (message-destination-> ("/tmp/guix-build-shepherd-l= ibevent-1.0.99-git.drv-0/source/t-syslog-remote-25332")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (system-log-message-sender-> #(1 "\x00")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (message-destination-> ("/tmp/guix-build-shepherd-l= ibevent-1.0.99-git.drv-0/source/t-syslog-remote-25332")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (system-log-message-sender-> #(1 "\x00")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (message-destination-> ("/tmp/guix-build-shepherd-l= ibevent-1.0.99-git.drv-0/source/t-syslog-remote-25332")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (system-log-message-sender-> #(1 "\x00")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (message-destination-> ("/tmp/guix-build-shepherd-l= ibevent-1.0.99-git.drv-0/source/t-syslog-remote-25332")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (system-log-message-sender-> #(1 "\x00")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (message-destination-> ("/tmp/guix-build-shepherd-l= ibevent-1.0.99-git.drv-0/source/t-syslog-remote-25332")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (system-log-message-sender-> #(1 "\x00")) 2025-04-14 14:39:57=C2=A0 2025-04-14 14:39:57 ;;; (message-destination-> ("/tmp/guix-build-shepherd-l= ibevent-1.0.99-git.drv-0/source/t-syslog-remote-25332")) 2025-04-14 14:39:57 Service logger has been disabled. + rm -f t-socket-25332 t-conf-25332 t-log-25332 /tmp/guix-build-shepherd-li= bevent-1.0.99-git.drv-0/source/t-syslog-logger-25332.scm /tmp/guix-build-sh= epherd-libevent-1.0.99-git.drv-0/source/t-syslog-kmsg-25332 /tmp/guix-build= -shepherd-libevent-1.0.99-git.drv-0/source/t-syslog-socket-25332 + rm -f '/tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/source/t-syslog= -25332*' '/tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/source/t-syslo= g-auth-25332*' '/tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/source/t= -syslog-debug-25332*' /tmp/guix-build-shepherd-libevent-1.0.99-git.drv-0/so= urce/t-syslog-remote-25332 + test -f t-pid-25332 ++ cat t-pid-25332 + kill 25340 + rm -f t-pid-25332 FAIL tests/services/system-log.sh (exit status: 2) Stopping service root... Exiting shepherd... Service log-rotation is not running. Service logger is not running. Exiting. From unknown Sun Jun 15 13:02:11 2025 X-Loop: help-debbugs@gnu.org Subject: bug#77634: [shepherd] Test failures on GNU/Hurd Resent-From: Ludovic =?UTF-8?Q?Court=C3=A8s?= Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Tue, 15 Apr 2025 15:02:01 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: followup 77634 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: yelninei@tutamail.com Cc: 77634 <77634@debbugs.gnu.org> Received: via spool by 77634-submit@debbugs.gnu.org id=B77634.17447293086317 (code B ref 77634); Tue, 15 Apr 2025 15:02:01 +0000 Received: (at 77634) by debbugs.gnu.org; 15 Apr 2025 15:01:48 +0000 Received: from localhost ([127.0.0.1]:53408 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1u4hnH-0001dp-EE for submit@debbugs.gnu.org; Tue, 15 Apr 2025 11:01:47 -0400 Received: from eggs.gnu.org ([2001:470:142:3::10]:43510) by debbugs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.84_2) (envelope-from ) id 1u4hnE-0001d5-KT for 77634@debbugs.gnu.org; Tue, 15 Apr 2025 11:01:46 -0400 Received: from fencepost.gnu.org ([2001:470:142:3::e]) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1u4hn9-0002Ki-2M; Tue, 15 Apr 2025 11:01:39 -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:References:In-Reply-To:Subject:To: From; bh=Jfw/Vyd5Dfs/6PqgFV1qMfjF+stCdJ5cRldLjVYGV1A=; b=hvbNtHbreytF54RICohS lkZYynqGZqjT2xk1aEHCJ1ebcZxu5+o2vRo+5tY1jsZQjCEAHKpqZbmxMR2fGqyxVYPHbj5kFxI11 QwVtHp/Bum2lSXmSRutF6aZVHNetPdQh7u1HVx5Im1t8VTZwR1Wf/0gwsETKkk0IDri4JdCjpCLBS DLo74BqIQKdhRs9+Y7nmcWrNnsgGj/jlMXSUnJUCvjInRKrJnRV7jHuCKNLuiFJ3zf9mGMEPeUJ5s RFHveEkAUZS8Vv0RanZ1nuKog8Cl5UXvKjJeFt/+sGnAtuJ0NvpuDlYlSJxgOxPFcbbS8qMa1uNyL 1uIMsfZPxDuYIQ==; From: Ludovic =?UTF-8?Q?Court=C3=A8s?= In-Reply-To: (yelninei@tutamail.com's message of "Mon, 14 Apr 2025 16:53:35 +0200 (CEST)") References: <8734ebdb0s.fsf@gnu.org> <87tt6qg9ep.fsf@gnu.org> User-Agent: mu4e 1.12.9; emacs 29.4 X-URL: https://people.bordeaux.inria.fr/lcourtes/ X-PGP-Fingerprint: 3CE4 6455 8A84 FDC6 9DB4 0CFB 090B 1199 3D9A EBB5 X-OS: x86_64-pc-linux-gnu X-Revolutionary-Date: Sextidi 26 Germinal an 233 de la =?UTF-8?Q?R=C3=A9volution,?= jour du Lilas Date: Tue, 15 Apr 2025 16:49:02 +0200 Message-ID: <87ecxtfpk1.fsf@gnu.org> MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-Spam-Score: -2.3 (--) X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -3.3 (---) Hey, yelninei@tutamail.com writes: > It is still slow on 1.0.3 to do anything once its started but with 1.0.4r= c1 even starting is extremely painful. > I tried to manually add features to the system-log-service and the > thing that causes everything to slow down drastically is the addition > of the #:kernel-log-file (even if it is empty). > > Setting that to #f instead makes the test now fail considerable faster (1= .8s rather than 900). Interesting. I cross-compiled current =E2=80=98main=E2=80=99 to test it in my childhurd,= as an unprivileged user, like so: echo '(use-modules (shepherd service system-log)) (register-services (lis= t (system-log-service (list (endpoint (make-socket-address AF_UNIX "/home/l= udo/syslog.sock") #:style SOCK_DGRAM)) #:kernel-log-file "t.conf")))' > t.c= onf ./shep/bin/shepherd -c t.conf & I can reproduce the unresponsiveness as soon as I run =E2=80=98herd start system-log=E2=80=99. Unfortunately, rpctrace is unhelpful. Even =E2=80=98rpctrace -o log ./shep/bin/shepherd --version=E2=80=99 hangs: --8<---------------cut here---------------start------------->8--- ludo@childhurd ~$ rpctrace -s 10 -o log ./shep/bin/shepherd --version Terminated ludo@childhurd ~$ tail -50 log ; echo END task13(pid283)-> 3206 (pn{ 35}) =3D 0=20 78<--79(pid283)->io_map_request () =3D 0 80<--77(pid283) (null) task13(pid283)-> 2089 (48619520 4096 0 0 80<--77(pid283) 4096 1 5 7 1) = =3D 0x3 ((os/kern) no space available)=20 task13(pid283)-> 2023 (48619520 4096) =3D 0=20 task13(pid283)-> 2089 (48619520 4096 0 0 80<--77(pid283) 4096 1 5 7 1) = =3D 0 48619520 task13(pid283)-> 3206 (pn{ 35}) =3D 0=20 78<--79(pid283)->io_map_request () =3D 0 80<--81(pid283) (null) task13(pid283)-> 2089 (48623616 4096 0 0 80<--81(pid283) 8192 1 1 7 1) = =3D 0x3 ((os/kern) no space available)=20 task13(pid283)-> 2023 (48623616 4096) =3D 0=20 task13(pid283)-> 2089 (48623616 4096 0 0 80<--81(pid283) 8192 1 1 7 1) = =3D 0 48623616 task13(pid283)-> 3206 (pn{ 35}) =3D 0=20 78<--79(pid283)->io_map_request () =3D 0 80<--77(pid283) (null) task13(pid283)-> 2089 (48627712 4096 0 0 80<--77(pid283) 8192 1 3 7 1) = =3D 0x3 ((os/kern) no space available)=20 task13(pid283)-> 2023 (48627712 4096) =3D 0=20 task13(pid283)-> 2089 (48627712 4096 0 0 80<--77(pid283) 8192 1 3 7 1) = =3D 0 48627712 task13(pid283)-> 3206 (pn{ 35}) =3D 0=20 task13(pid283)-> 3206 (pn{ 34}) =3D 0=20 17<--33(pid283)->dir_lookup ("gnu/store/" 4194305 0) =3D 0 1 "" 78<--8= 1(pid283) 78<--81(pid283)->term_getctty () =3D 0xfffffed1 ((ipc/mig) bad request me= ssage ID)=20 78<--81(pid283)->io_read_request (-1 512) =3D 0 "\x7fELF\x01\x01\x01\0\0\= 0" 78<--81(pid283)->io_stat_request () =3D 0 {23 7 0 80111 0 1744699086 0 33= 133 1 0 0 375340 0 1744691226 270000000 1 0 1744691169 440000000 8192 744 0= 0 0 0 0 0 0 0 0 0 0} 78<--81(pid283)->io_map_request () =3D 0 80<--79(pid283) (null) task13(pid283)-> 2089 (0 379380 0 1 80<--79(pid283) 0 1 1 7 1) =3D 0 486= 31808 task13(pid283)-> 3206 (pn{ 35}) =3D 0=20 78<--81(pid283)->io_map_request () =3D 0 80<--77(pid283) (null) task13(pid283)-> 2089 (48676864 204800 0 0 80<--77(pid283) 45056 1 5 7 1= ) =3D 0x3 ((os/kern) no space available)=20 task13(pid283)-> 2023 (48676864 204800) =3D 0=20 task13(pid283)-> 2089 (48676864 204800 0 0 80<--77(pid283) 45056 1 5 7 1= ) =3D 0 48676864 task13(pid283)-> 3206 (pn{ 35}) =3D 0=20 78<--81(pid283)->io_map_request () =3D 0 80<--79(pid283) (null) task13(pid283)-> 2089 (48881664 122880 0 0 80<--79(pid283) 249856 1 1 7 = 1) =3D 0x3 ((os/kern) no space available)=20 task13(pid283)-> 2023 (48881664 122880) =3D 0=20 task13(pid283)-> 2089 (48881664 122880 0 0 80<--79(pid283) 249856 1 1 7 = 1) =3D 0 48881664 task13(pid283)-> 3206 (pn{ 35}) =3D 0=20 78<--81(pid283)->io_map_request () =3D 0 80<--77(pid283) (null) task13(pid283)-> 2089 (49004544 8192 0 0 80<--77(pid283) 368640 1 3 7 1)= =3D 0x3 ((os/kern) no space available)=20 task13(pid283)-> 2023 (49004544 8192) =3D 0=20 task13(pid283)-> 2089 (49004544 8192 0 0 80<--77(pid283) 368640 1 3 7 1)= =3D 0 49004544 task13(pid283)-> 3206 (pn{ 35}) =3D 0=20 task13(pid283)-> 3206 (pn{ 34}) =3D 0=20 task13(pid283)-> 2089 (48435200 65536 0 0 (null) 0 1 3 7 1) =3D 0x3 ((os/k= ern) no space available)=20 task13(pid283)-> 2089 (48435200 65536 0 1 (null) 0 1 3 7 1) =3D 0 49012736 task13(pid283)-> 2012 (1 22) =3D 0 {0 25 183304192 6975488 0 0 0 0 17446930= 29 80000 0 0 0 0 0 0 0 0 1744693029 0 80000000 0} task13(pid283)-> 2012 (3 12) =3D 0 {0 0 0 10000 0 0 0 0 0 0 10000000 0} task13(pid283)-> 2012 (1 22) =3D 0 {0 25 183304192 6975488 0 0 0 0 17446930= 29 80000 0 0 0 0 0 0 0 0 1744693029 0 80000000 0} task13(pid283)-> 2012 (3 12) =3D 0 {0 0 0 10000 0 0 0 0 0 0 10000000 0} 65<--64(pid283)->io_write_request ("\0" -1) =3D 0 1 18... =3D 0 "\0" task13(pid283)-> 2089 (49078272 471040 0 0 (null) 0 1 3 7 1) ...18 task13(pid283)-> 4204 (18820768 2 0 0 0)END --8<---------------cut here---------------end--------------->8--- > Running the logger manually only the=C2=A0syslog_remote_file seems to be = created. This is consistent with the log output from the test. > I also added a pk for the message-sender to investigate. > Difference I can see is that on Linux most of them are #f but here are #(= 1 \x00") instead Yeah that=E2=80=99s the data coming from =E2=80=98recvfrom!=E2=80=99 where = 1 =3D AF_UNIX and Linux returns nothing. Thanks, Ludo=E2=80=99. From unknown Sun Jun 15 13:02:11 2025 X-Loop: help-debbugs@gnu.org Subject: bug#77634: [shepherd] Test failures on GNU/Hurd Resent-From: yelninei@tutamail.com Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Sat, 19 Apr 2025 09:05:02 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: followup 77634 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: Ludovic =?UTF-8?Q?Court=C3=A8s?= Cc: 77634 <77634@debbugs.gnu.org> Received: via spool by 77634-submit@debbugs.gnu.org id=B77634.1745053444549 (code B ref 77634); Sat, 19 Apr 2025 09:05:02 +0000 Received: (at 77634) by debbugs.gnu.org; 19 Apr 2025 09:04:04 +0000 Received: from localhost ([127.0.0.1]:57740 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1u647H-00008l-NV for submit@debbugs.gnu.org; Sat, 19 Apr 2025 05:04:04 -0400 Received: from mail.w13.tutanota.de ([185.205.69.213]:51310) by debbugs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.84_2) (envelope-from ) id 1u647E-00006u-0d for 77634@debbugs.gnu.org; Sat, 19 Apr 2025 05:04:01 -0400 Received: from tutadb.w10.tutanota.de (w10.api.tuta.com [IPv6:fd:ac::d:10]) by mail.w13.tutanota.de (Postfix) with ESMTP id 3B21E7C70EC4 for <77634@debbugs.gnu.org>; Sat, 19 Apr 2025 11:03:23 +0200 (CEST) DKIM-Signature: v=1; a=rsa-sha256; q=dns/txt; c=relaxed/relaxed; t=1745053403; s=s1; d=tutamail.com; h=From:From:To:To:Subject:Subject:Content-Description:Content-ID:Content-Type:Content-Type:Content-Transfer-Encoding:Content-Transfer-Encoding:Cc:Cc:Date:Date:In-Reply-To:In-Reply-To:MIME-Version:MIME-Version:Message-ID:Message-ID:Reply-To:References:References:Sender; bh=BldjcARA7LW9rSEWrRFxTGKuBQgL0hVZnjSQUCtsp4Y=; b=ru204ZxuhA6Kqg6TMmbjQiAVwf6mlSjPwBb3WR7+NrpY3GXl3HduQOJTm6nAOLJQ 6kk5Z8RjQ9BsibZOx8FN1o4xGy2w2c72qPMNA96jjgJ2mdxiTweo+v8IaC5mbZdkr8r dX7xs6RZIluFU2XlmIS+pYaAhbnS9ekgCYvaG9+bShUkJq7lGrlYVLbNGU9jgokWW7R LsMH7rQDZH3oQjADttGu9IBy4f5zAOwf+3oQL1Gz1yddN0U5iJ7hJAM4aWtWCynyssj lPpR1ix0yabrzKsGKaW8CANSOqp6DmF0WhdbohJENyTNCdJ1qJIk/9mfJ6GFop9aMER 0ByyzJsFKg== Date: Sat, 19 Apr 2025 11:03:23 +0200 (CEST) From: yelninei@tutamail.com Message-ID: In-Reply-To: References: <8734ebdb0s.fsf@gnu.org> <87tt6qg9ep.fsf@gnu.org> MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable X-Spam-Score: -0.7 (/) X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -1.7 (-) Hi Ludo, Apr 14, 2025, 14:53 by yelninei@tutamail.com: > > >>> The shepherd syslog seems to be extremely slow: >>> >>> I extracted the logger.scm and conf.scm from the test, removed the $ >>> from the shell variables variables, started shepherd, echoed the test >>> msg into=C2=A0 the kmsg file and then tried to start the shepherd syslo= g. >>> >>> It took multiple minutes to see the=C2=A0"Service system-log running wi= th >>> value" in the log and then another couple of minutes for "herd start >>> syslogd" to return. Afterwards trying to query the syslog status (or >>> trying to interact with shepherd in any other way) takes forever to >>> complete while syslogd is running. >>> >>> I did this with the 1.0.4rc1 shepherd to work around the blocking >>> socket problem with guix-daemon (#77610, it still fails on the first >>> connection now but idk if this is a problem with shepherd or the >>> guix-daemon) >>> >>> With the 1.0.3 shepherd syslogd seems to be a lot quicker to start >>> initally ( see the log from the first message) but still extremely >>> slow to interact with afterwards. >>> >> >> Weird. Could you bisect between 1.0.3 and HEAD to try and find the >> source of slowness? >> >> I wonder if f1a82845eaf8851af9a811e5a1d185b68b1c363f might explain it, >> but that=E2=80=99s pre-1.0.3. >> >> Alternatively, you can try running =E2=80=98shepherd=E2=80=99 under =E2= =80=98rpctrace=E2=80=99 for the >> syslog slowness issue, so we get an idea of what it=E2=80=99s doing. >> >> Thanks a lot for helping out! >> >> Ludo=E2=80=99. >> > It is still slow on 1.0.3 to do anything once its started but with 1.0.4r= c1 even starting is extremely painful. > > I tried to manually add features to the system-log-service and the thing = that causes everything to slow down drastically is the addition of the #:ke= rnel-log-file (even if it is empty). > > Setting that to #f instead makes the test now fail considerable faster (1= .8s rather than 900). > > > I think the issue is that shepherd constantly reads the eof object from the= input port (i guess the port is always readable to fibers but with nothing= there). Trying to add a print to that case leads to similar unresponsivene= ss on linux as well. So my theory is that that fibers is struggling to suspend the syslog loop. When i added a (sleep 1) to "wait-for-input-or-message" the issue went away= . I am actually impressed that this seems to be less of a problem on linux. I then tried to fix the test by adjusting the first case of the tests messa= ge destination to=C2=A0 (and=3D> (system-log-message-sender message) =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 =09=C2=A0=C2=A0=C2=A0 =09=C2=A0=C2=A0=C2=A0 = (lambda (addr) (not (=3D AF_UNIX (sockaddr:fam addr))))) After adjusting some of the sleep times in the test it is now passing, but = ofc this is not really a fix. Some minor other things: -=C2=A0 Currently the NULL byte from the sender is added into the log messa= ge, might be worth filtering out - "herd status syslogd" does not show the endpoints and kernel-log-file the= syslog is reading from - the shepherd package from the shepherd channel in 'set-fibers-directory s= ets fibers ccache dir also to the fibers source dir - I am unable to cross compile shepehrd from git with guix because of help2= man - the guix shepherd-syslog service currently does not expose setting the en= dpoints and kernel-log-file (currently on my childhurd this would be /dev/k= log instead of /dev/kmsg) From unknown Sun Jun 15 13:02:11 2025 X-Loop: help-debbugs@gnu.org Subject: bug#77634: [shepherd] Test failures on GNU/Hurd Resent-From: Ludovic =?UTF-8?Q?Court=C3=A8s?= Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Sat, 19 Apr 2025 20:43:07 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: followup 77634 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: yelninei@tutamail.com Cc: 77634 <77634@debbugs.gnu.org> Received: via spool by 77634-submit@debbugs.gnu.org id=B77634.174509535120584 (code B ref 77634); Sat, 19 Apr 2025 20:43:07 +0000 Received: (at 77634) by debbugs.gnu.org; 19 Apr 2025 20:42:31 +0000 Received: from localhost ([127.0.0.1]:38509 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1u6F1C-0005Lv-Hk for submit@debbugs.gnu.org; Sat, 19 Apr 2025 16:42:31 -0400 Received: from eggs.gnu.org ([2001:470:142:3::10]:43974) by debbugs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.84_2) (envelope-from ) id 1u6F0w-0005Ku-AJ for 77634@debbugs.gnu.org; Sat, 19 Apr 2025 16:42:14 -0400 Received: from fencepost.gnu.org ([2001:470:142:3::e]) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1u6F0q-0003xJ-PQ; Sat, 19 Apr 2025 16:42: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:References:In-Reply-To:Subject:To: From; bh=U+KAzNWlieGGBuOk6AJDviD6ylEkLi98INw12KU8fhk=; b=fS9pJ3R1hk5x88OhLcrl wnBbirN7bD7JgggcPmdUATd35vESCEIaheI3ni36sx7AvP/i2kfSBwkEFt0BUW3oe/fMEpw3+Yue1 /O4LArcRiaE7WtPxASCcbeLV9g8gj4HkWKvARn5KgJ3NblzJT2nv73lR2Pnr2RzpIis7wV8Ztv+Uw n0yql69hkoV8I5S1SsP9kQcQ67pHezC/PNjnxWQOeKxc7BmE6o2xYX9Q/Pfocw0wGUhE09A5laqwA AK+n0BFm8FgyP3Lx0ihs4t4cmwgdy/yYvSC996DuVyFH742wAg78yvVwwR8OGQd98dCcKwx/PIF/+ kgp2r/u9vFlUIA==; From: Ludovic =?UTF-8?Q?Court=C3=A8s?= In-Reply-To: (yelninei@tutamail.com's message of "Sat, 19 Apr 2025 11:03:23 +0200 (CEST)") References: <8734ebdb0s.fsf@gnu.org> <87tt6qg9ep.fsf@gnu.org> User-Agent: mu4e 1.12.9; emacs 29.4 X-URL: https://people.bordeaux.inria.fr/lcourtes/ X-PGP-Fingerprint: 3CE4 6455 8A84 FDC6 9DB4 0CFB 090B 1199 3D9A EBB5 X-OS: x86_64-pc-linux-gnu X-Revolutionary-Date: =?UTF-8?Q?D=C3=A9cadi?= 30 Germinal an 233 de la =?UTF-8?Q?R=C3=A9volution,?= jour du Greffoir Date: Sat, 19 Apr 2025 22:41:28 +0200 Message-ID: <878qnvg9zb.fsf@gnu.org> MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-Spam-Score: -2.3 (--) X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -3.3 (---) Hi again, yelninei@tutamail.com writes: > Some minor other things: > -=C2=A0 Currently the NULL byte from the sender is added into the log > message, might be worth filtering out > - "herd status syslogd" does not show the endpoints and > kernel-log-file the syslog is reading from > - the shepherd package from the shepherd channel in > 'set-fibers-directory sets fibers ccache dir also to the fibers source > dir > - I am unable to cross compile shepehrd from git with guix because of hel= p2man > - the guix shepherd-syslog service currently does not expose setting > the endpoints and kernel-log-file (currently on my childhurd this > would be /dev/klog instead of /dev/kmsg) All good points! Most of these are now fixed on the Shepherd side: 48e53a4 guix: Record the right Fibers .go file directory. 98878a7 system-log: Serialize information about sockets and the kernel lo= g file. be57f65 system-log: Close input file on end-of-file. 21bd4ec system-log: Print =E2=80=9Clocalhost=E2=80=9D for messages receiv= ed over AF_UNIX sockets. 1ad90aa system-log: Read kernel messages from /dev/klog on the Hurd. Regarding cross-compilation, here=E2=80=99s a protip: guix build -L $PWD/.guix/modules shepherd@1.0.99-tarball \ --target=3Di586-pc-gnu It works because it first builds the tarball, which includes pre-generated man pages, and then uses it as the source of the Shepherd being cross-compiled. Thanks a lot for the detailed bug reports and suggestions; it=E2=80=99s much appreciated! Ludo=E2=80=99. From unknown Sun Jun 15 13:02:11 2025 X-Loop: help-debbugs@gnu.org Subject: bug#77634: [shepherd] Test failures on GNU/Hurd Resent-From: yelninei@tutamail.com Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Sun, 20 Apr 2025 11:36:01 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: followup 77634 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: Ludovic =?UTF-8?Q?Court=C3=A8s?= Cc: 77634 <77634@debbugs.gnu.org> Received: via spool by 77634-submit@debbugs.gnu.org id=B77634.17451489149439 (code B ref 77634); Sun, 20 Apr 2025 11:36:01 +0000 Received: (at 77634) by debbugs.gnu.org; 20 Apr 2025 11:35:14 +0000 Received: from localhost ([127.0.0.1]:46369 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1u6Sx4-0002Qn-RV for submit@debbugs.gnu.org; Sun, 20 Apr 2025 07:35:13 -0400 Received: from mail.w13.tutanota.de ([185.205.69.213]:33136) by debbugs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.84_2) (envelope-from ) id 1u6Swz-0002NP-Et for 77634@debbugs.gnu.org; Sun, 20 Apr 2025 07:35:08 -0400 Received: from tutadb.w10.tutanota.de (w10.api.tuta.com [IPv6:fd:ac::d:10]) by mail.w13.tutanota.de (Postfix) with ESMTP id 1333A7CCF3B7 for <77634@debbugs.gnu.org>; Sun, 20 Apr 2025 13:34:29 +0200 (CEST) DKIM-Signature: v=1; a=rsa-sha256; q=dns/txt; c=relaxed/relaxed; t=1745148868; s=s1; d=tutamail.com; h=From:From:To:To:Subject:Subject:Content-Description:Content-ID:Content-Type:Content-Type:Content-Transfer-Encoding:Content-Transfer-Encoding:Cc:Cc:Date:Date:In-Reply-To:In-Reply-To:MIME-Version:MIME-Version:Message-ID:Message-ID:Reply-To:References:References:Sender; bh=2HTeSKsEAUT2pFWpiV+0T70HGR2N+Jdi5iaiqvVH2wg=; b=OtHbHMy+opYgQ2g28RNS+/slEdbhx3cKvpG3SvnKhNOoXiKbIgyPL5ECSfMYzV4C hdiwSGODJd8CIlVw6DUtDbickLk3v7SjK6I9VrOvp8AftkTzxwSEqdDJJFfkXj6gsO0 rNgjyZSvZC0dtcnIeeCkauCtbptGdqp2eyRCB0Xzf1WwIYSMoGo5rIYBQv1ac3Aj+U1 fe5iaQkOx0D17cZbRZ/PuafwYZ4hodR37KShsQWqu/0n42dJ+LwuG82VXg9fnZRzJT7 0/qz69lTMuEd7RiG7L4hOJ+iiwAYVT3YiJXhDiCow3EZN+WCx5+lYEGEmUCHL7SfSVH J5xDh9SCOw== Date: Sun, 20 Apr 2025 13:34:28 +0200 (CEST) From: yelninei@tutamail.com Message-ID: In-Reply-To: <878qnvg9zb.fsf@gnu.org> References: <8734ebdb0s.fsf@gnu.org> <87tt6qg9ep.fsf@gnu.org> <878qnvg9zb.fsf@gnu.org> MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable X-Spam-Score: -0.7 (/) X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -1.7 (-) Hello, Apr 19, 2025, 20:42 by ludo@gnu.org: > Hi again, > > yelninei@tutamail.com writes: > >> Some minor other things: >> -=C2=A0 Currently the NULL byte from the sender is added into the log >> message, might be worth filtering out >> - "herd status syslogd" does not show the endpoints and >> kernel-log-file the syslog is reading from >> - the shepherd package from the shepherd channel in >> 'set-fibers-directory sets fibers ccache dir also to the fibers source >> dir >> - I am unable to cross compile shepehrd from git with guix because of he= lp2man >> - the guix shepherd-syslog service currently does not expose setting >> the endpoints and kernel-log-file (currently on my childhurd this >> would be /dev/klog instead of /dev/kmsg) >> > > All good points! Most of these are now fixed on the Shepherd side: > > 48e53a4 guix: Record the right Fibers .go file directory. > 98878a7 system-log: Serialize information about sockets and the kernel lo= g file. > be57f65 system-log: Close input file on end-of-file. > 21bd4ec system-log: Print =E2=80=9Clocalhost=E2=80=9D for messages receiv= ed over AF_UNIX sockets. > 1ad90aa system-log: Read kernel messages from /dev/klog on the Hurd. > Great, thanks. The last thing that still needs fixing is the message-destination procedure= in the system-log.sh test. Currently It sends all messages to the $syslog_= remote_file if the message has a sender, but arguably the (null) AF_UNIX so= ckets are not remote. After I changed that I was able to finally build shepherd with all non skip= ped tests passing. I then tried the shepherd syslogd on the childhurd but some things were not= quite right: - the /dev/klog messages are being logged but were missing the %kernel-pref= ix, i dont think this is a=C2=A0 problem with shepherd. - I saw no syslog messages in /var/log/messages, i.e. from sshd or a user s= hepherd logging to syslog. It just seems to stop logging to the main log fi= le entirely after finishing with /dev/klog. Any ideas? > Regarding cross-compilation, here=E2=80=99s a protip: > > guix build -L $PWD/.guix/modules shepherd@1.0.99-tarball \ > --target=3Di586-pc-gnu > > It works because it first builds the tarball, which includes > pre-generated man pages, and then uses it as the source of the Shepherd > being cross-compiled. > This even works in the childhurd operating system record and I can just use= that package instead of the manual "make dist" and=C2=A0 source override I= did before. > Thanks a lot for the detailed bug reports and suggestions; it=E2=80=99s m= uch > appreciated! > Thank you for making shepherd very approachable. I had no issues understand= ing how the syslog service is supposed to work and hunt down the bug. > Ludo=E2=80=99. > From unknown Sun Jun 15 13:02:11 2025 X-Loop: help-debbugs@gnu.org Subject: bug#77634: [shepherd] Test failures on GNU/Hurd Resent-From: Ludovic =?UTF-8?Q?Court=C3=A8s?= Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Wed, 23 Apr 2025 20:59:02 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: followup 77634 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: yelninei@tutamail.com Cc: 77634 <77634@debbugs.gnu.org> Received: via spool by 77634-submit@debbugs.gnu.org id=B77634.174544191719410 (code B ref 77634); Wed, 23 Apr 2025 20:59:02 +0000 Received: (at 77634) by debbugs.gnu.org; 23 Apr 2025 20:58:37 +0000 Received: from localhost ([127.0.0.1]:59089 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1u7hAx-00052c-02 for submit@debbugs.gnu.org; Wed, 23 Apr 2025 16:58:37 -0400 Received: from eggs.gnu.org ([2001:470:142:3::10]:51780) by debbugs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.84_2) (envelope-from ) id 1u7hAt-00050r-75 for 77634@debbugs.gnu.org; Wed, 23 Apr 2025 16:58:32 -0400 Received: from fencepost.gnu.org ([2001:470:142:3::e]) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1u7hAm-00059H-4g; Wed, 23 Apr 2025 16:58: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:Date:References:In-Reply-To:Subject:To: From; bh=I3Vd6KCtP65PCrvAuY527juTnNp8PrxYdeFm9duSf3g=; b=JwoQ+aFlLFBz0GIwbx1d dzAcBzjOb9l4nPKpR+rZESRcApMuKpQ1p1ZZDRDWttJimY8yLqd9FO5PcCrGiEUsk6rOnnmd8qSfg WnkcgKmzFrqP66O/th814K5JL8D67oDZR8IF5n4r2QROH0Ji3FsNYaafEqsvd6Lul/QoNG2t3mom7 kdLypBXYGQ+AqqeLFAQlTTaCHUBGcsyOpNx8DrB+KsQHMlHLeAfEeXbZEpp2m7bfw1mFqNfZZpJvu 8i43AjbKL+m9DZknQsgjpg6cuLhHH4BqNQiVEKO8zSkD+9Fcy+EJghMFI44E7NjZCMW65whcgOgGI r50rvDjfalDqsg==; From: Ludovic =?UTF-8?Q?Court=C3=A8s?= In-Reply-To: (yelninei@tutamail.com's message of "Sun, 20 Apr 2025 13:34:28 +0200 (CEST)") References: <8734ebdb0s.fsf@gnu.org> <87tt6qg9ep.fsf@gnu.org> <878qnvg9zb.fsf@gnu.org> User-Agent: mu4e 1.12.9; emacs 29.4 X-URL: https://people.bordeaux.inria.fr/lcourtes/ X-PGP-Fingerprint: 3CE4 6455 8A84 FDC6 9DB4 0CFB 090B 1199 3D9A EBB5 X-OS: x86_64-pc-linux-gnu X-Revolutionary-Date: Quartidi 4 =?UTF-8?Q?Flor=C3=A9al?= an 233 de la =?UTF-8?Q?R=C3=A9volution,?= jour de =?UTF-8?Q?l'Aub=C3=A9pine?= Date: Wed, 23 Apr 2025 18:19:56 +0200 Message-ID: <87v7qun93n.fsf@gnu.org> MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-Spam-Score: -1.2 (-) X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -2.2 (--) Hey, yelninei@tutamail.com writes: > The last thing that still needs fixing is the message-destination > procedure in the system-log.sh test. Currently It sends all messages > to the $syslog_remote_file if the message has a sender, but arguably > the (null) AF_UNIX sockets are not remote. Oh right. I think 2d70e2f3948910ab97b9d90d75aa0196596fa917 should fix it. > I then tried the shepherd syslogd on the childhurd but some things were n= ot quite right: > - the /dev/klog messages are being logged but were missing the > %kernel-prefix, i dont think this is a=C2=A0 problem with shepherd. > - I saw no syslog messages in /var/log/messages, i.e. from sshd or a > user shepherd logging to syslog. It just seems to stop logging to the > main log file entirely after finishing with /dev/klog. > Any ideas? Yeah, I think that=E2=80=99s because of the streamio bug: https://lists.gnu.org/archive/html/bug-hurd/2025-04/msg00023.html > Thank you for making shepherd very approachable. I had no issues > understanding how the syslog service is supposed to work and hunt down > the bug. Thanks for the kind words. I very much hope if feels approachable to as many people as possible. Ludo=E2=80=99. From unknown Sun Jun 15 13:02:11 2025 X-Loop: help-debbugs@gnu.org Subject: bug#77634: [shepherd] Test failures on GNU/Hurd Resent-From: yelninei@tutamail.com Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Thu, 24 Apr 2025 15:16:01 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: followup 77634 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: Ludovic =?UTF-8?Q?Court=C3=A8s?= Cc: 77634 <77634@debbugs.gnu.org> Received: via spool by 77634-submit@debbugs.gnu.org id=B77634.174550773018586 (code B ref 77634); Thu, 24 Apr 2025 15:16:01 +0000 Received: (at 77634) by debbugs.gnu.org; 24 Apr 2025 15:15:30 +0000 Received: from localhost ([127.0.0.1]:40186 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1u7yIT-0004pi-RL for submit@debbugs.gnu.org; Thu, 24 Apr 2025 11:15:30 -0400 Received: from mail.w13.tutanota.de ([185.205.69.213]:50930) by debbugs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.84_2) (envelope-from ) id 1u7yIP-0004pG-AJ for 77634@debbugs.gnu.org; Thu, 24 Apr 2025 11:15:27 -0400 Received: from tutadb.w10.tutanota.de (w10.api.tuta.com [IPv6:fd:ac::d:10]) by mail.w13.tutanota.de (Postfix) with ESMTP id 21A737EB8296 for <77634@debbugs.gnu.org>; Thu, 24 Apr 2025 17:14:48 +0200 (CEST) DKIM-Signature: v=1; a=rsa-sha256; q=dns/txt; c=relaxed/relaxed; t=1745507687; s=s1; d=tutamail.com; h=From:From:To:To:Subject:Subject:Content-Description:Content-ID:Content-Type:Content-Type:Content-Transfer-Encoding:Content-Transfer-Encoding:Cc:Cc:Date:Date:In-Reply-To:In-Reply-To:MIME-Version:MIME-Version:Message-ID:Message-ID:Reply-To:References:References:Sender; bh=tkX6w3qSHu1/Q1uiUYqcLBV4FF5p4f4ifDoeMxX3LMw=; b=xOug1WS3ciutF3gHOI0LkHbDAkNwVEnieoq4YgHI2iXflBbhAN/iIYlt953gIzjt ifsradHwTnKb4laM/3UXgdzEfhPLRmUOA+SCTOjWq1FEC67lckPM4FZQcPXWiwewgTE cr0nd+y+zaSbK2FH3C1WMQOn4JdZffaUdVHlqnddmRb3IU/NLk+JAjo22uTGL+9nkoa PJ1QPSEvEqgFAc8PczzZmjciMhBqh6V+sIlxmYYCveD+71I4b7Z9BmJvi9Ww0uWTfFw XAtcm/clpAGvGj3+gHqU/wNJNibIqAtAjvyxY18nVKDk9QTThRkrsUo59GczD9nTr1a pqPgWFbs6g== Date: Thu, 24 Apr 2025 17:14:47 +0200 (CEST) From: yelninei@tutamail.com Message-ID: In-Reply-To: <87v7qun93n.fsf@gnu.org> References: <8734ebdb0s.fsf@gnu.org> <87tt6qg9ep.fsf@gnu.org> <878qnvg9zb.fsf@gnu.org> <87v7qun93n.fsf@gnu.org> MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: 7bit X-Spam-Score: -0.7 (/) X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -1.7 (-) Hello Apr 23, 2025, 20:58 by ludo@gnu.org: > Hey, > > yelninei@tutamail.com writes: > >> The last thing that still needs fixing is the message-destination >> procedure in the system-log.sh test. Currently It sends all messages >> to the $syslog_remote_file if the message has a sender, but arguably >> the (null) AF_UNIX sockets are not remote. >> > > Oh right. I think 2d70e2f3948910ab97b9d90d75aa0196596fa917 should fix > it. Hurray: git rev-parse HEAD 2d70e2f3948910ab97b9d90d75aa0196596fa917 guix --version guix (GNU Guix) 9f4bdb270e178eac636df3f4bbffbe4aa3b90a67 [this is including the automake hack I mentioned earlier]guix build -f guix.scm -s i586-gnu --without-tests=guile-fibers --no-grafts/gnu/store/4fjq66pr19q9p4ki49fp1d2ddv5g1809-shepherd-1.0.99-git Can this be closed now as the streamio and the systemd activation issue are not shepherd related? Thank you. From unknown Sun Jun 15 13:02:11 2025 MIME-Version: 1.0 X-Mailer: MIME-tools 5.505 (Entity 5.505) X-Loop: help-debbugs@gnu.org From: help-debbugs@gnu.org (GNU bug Tracking System) To: yelninei@tutamail.com Subject: bug#77634: closed (Re: bug#77634: [shepherd] Test failures on GNU/Hurd) Message-ID: References: <877c39jo9b.fsf@gnu.org> X-Gnu-PR-Message: they-closed 77634 X-Gnu-PR-Package: guix Reply-To: 77634@debbugs.gnu.org Date: Thu, 24 Apr 2025 21:40:05 +0000 Content-Type: multipart/mixed; boundary="----------=_1745530805-27026-1" This is a multi-part message in MIME format... ------------=_1745530805-27026-1 Content-Disposition: inline Content-Transfer-Encoding: quoted-printable Content-Type: text/plain; charset="utf-8" Your bug report #77634: [shepherd] Test failures on GNU/Hurd which was filed against the guix package, has been closed. The explanation is attached below, along with your original report. If you require more details, please reply to 77634@debbugs.gnu.org. --=20 77634: https://debbugs.gnu.org/cgi/bugreport.cgi?bug=3D77634 GNU Bug Tracking System Contact help-debbugs@gnu.org with problems ------------=_1745530805-27026-1 Content-Type: message/rfc822 Content-Disposition: inline Content-Transfer-Encoding: 7bit Received: (at 77634-done) by debbugs.gnu.org; 24 Apr 2025 21:39:28 +0000 Received: from localhost ([127.0.0.1]:42164 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1u84I3-0006yQ-Uh for submit@debbugs.gnu.org; Thu, 24 Apr 2025 17:39:28 -0400 Received: from eggs.gnu.org ([2001:470:142:3::10]:34052) by debbugs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.84_2) (envelope-from ) id 1u84I0-0006xp-AY for 77634-done@debbugs.gnu.org; Thu, 24 Apr 2025 17:39:24 -0400 Received: from fencepost.gnu.org ([2001:470:142:3::e]) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1u84Hu-0004MS-8e; Thu, 24 Apr 2025 17:39:18 -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:References:In-Reply-To:Subject:To: From; bh=5G/PBaj2fli0TDervSrHeTwBAWBKnX1r/DE2XS+nSXY=; b=c0qLizyz+dPRcIm6Q6np 6Rp3CJaD6Rv15YqNOW/ssIs9sIx60JJMlGX8RQsHrg/CuKVM6fAmfAbC4JwHBVuWPJN8Fd1Qx7zSl uUPBsa6EYlKcx/oFhIpiTgcKeR0eVUG4GiOzdmZz0IpRdrzvbTevG9qWPCXN8IITLyscSKDeNOivL xBp8Ds93D7mC/nHOkQeIK60NnsuYZ+PzZDdm53p+Hch4OPAnPD/pb/uD1xEdA0ZUDA+0AsIpVPi/+ Yr56vvotcXerNgTEpoeje5T6/dS0+7k130XvvwfKsQ7r9Q2h8G76VczbmyoBGBhYhkWaNsA9wtFqp M7qHGTzWO0YIAg==; From: =?utf-8?Q?Ludovic_Court=C3=A8s?= To: yelninei@tutamail.com Subject: Re: bug#77634: [shepherd] Test failures on GNU/Hurd In-Reply-To: (yelninei@tutamail.com's message of "Thu, 24 Apr 2025 17:14:47 +0200 (CEST)") References: <8734ebdb0s.fsf@gnu.org> <87tt6qg9ep.fsf@gnu.org> <878qnvg9zb.fsf@gnu.org> <87v7qun93n.fsf@gnu.org> User-Agent: mu4e 1.12.9; emacs 29.4 X-URL: https://people.bordeaux.inria.fr/lcourtes/ X-PGP-Fingerprint: 3CE4 6455 8A84 FDC6 9DB4 0CFB 090B 1199 3D9A EBB5 X-OS: x86_64-pc-linux-gnu X-Revolutionary-Date: Quintidi 5 =?utf-8?Q?Flor=C3=A9al?= an 233 de la =?utf-8?Q?R=C3=A9volution=2C?= jour du Rossignol Date: Thu, 24 Apr 2025 22:30:40 +0200 Message-ID: <877c39jo9b.fsf@gnu.org> MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-Spam-Score: -2.3 (--) X-Debbugs-Envelope-To: 77634-done Cc: 77634 <77634-done@debbugs.gnu.org> X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -3.3 (---) Hi, yelninei@tutamail.com writes: > [this is including the automake hack I mentioned earlier]guix build -f Would be nice to fix that Automake issue, sometime=E2=80=A6 > Can this be closed now as the streamio and the systemd activation issue a= re not shepherd related? I think so. Closing! =F0=9F=8E=89 Ludo=E2=80=99. ------------=_1745530805-27026-1 Content-Type: message/rfc822 Content-Disposition: inline Content-Transfer-Encoding: 7bit Received: (at submit) by debbugs.gnu.org; 8 Apr 2025 08:43:39 +0000 Received: from localhost ([127.0.0.1]:58938 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1u24YQ-0005tw-Jl for submit@debbugs.gnu.org; Tue, 08 Apr 2025 04:43:39 -0400 Received: from lists.gnu.org ([2001:470:142::17]:43386) by debbugs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.84_2) (envelope-from ) id 1u24YL-0005tJ-UR for submit@debbugs.gnu.org; Tue, 08 Apr 2025 04:43:32 -0400 Received: from eggs.gnu.org ([2001:470:142:3::10]) by lists.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1u24YG-0000Sb-72 for bug-guix@gnu.org; Tue, 08 Apr 2025 04:43:24 -0400 Received: from mail.w13.tutanota.de ([185.205.69.213]) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1u24YC-0002VW-2D for bug-guix@gnu.org; Tue, 08 Apr 2025 04:43:23 -0400 Received: from tutadb.w10.tutanota.de (w10.api.tuta.com [IPv6:fd:ac::d:10]) by mail.w13.tutanota.de (Postfix) with ESMTP id DB613774EDCE for ; Tue, 8 Apr 2025 10:43:17 +0200 (CEST) DKIM-Signature: v=1; a=rsa-sha256; q=dns/txt; c=relaxed/relaxed; t=1744101797; s=s1; d=tutamail.com; h=From:From:To:To:Subject:Subject:Content-Description:Content-ID:Content-Type:Content-Type:Content-Transfer-Encoding:Content-Transfer-Encoding:Cc:Date:Date:In-Reply-To:MIME-Version:MIME-Version:Message-ID:Message-ID:Reply-To:References:Sender; bh=ugBKNj5xK/87EKAxDfEtn+Iee/ZDeIVKwrCtAlzhqq4=; b=AA50LsEgttMAiBaeaO1MiQOZpz/R8q6SU0AN+9mVhEp/i9ofDIP4QqrSceLmlNQ7 ADUpVSzBDQ334ilHj4EVcbYROImGpeHqD3AFNUHYzYbYIQoepLDItWfyESUzyKKsGZf +FRkV1o9+saLIQGuteirYWK8cOpGgdWtQJAwdd7oHkEr/EV1/CsIPzlCd5fmhT2ML3A JvKybTRNM53OkbzhIoeSyY0LdMIm3QK+xc2C1JBrVzQQ6rNGW+pc3tSK0EjUlAqD4gQ Dm6xz39YT1Ho3kBwnsga5I73itzXNpM1lcjIWx2u0TBXaPJ2vIcdIKf0nvVFGXogyZ5 YlFQC78afw== Date: Tue, 8 Apr 2025 10:43:17 +0200 (CEST) From: yelninei@tutamail.com To: bug-guix@gnu.org Message-ID: Subject: [shepherd] Test failures on GNU/Hurd MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Received-SPF: pass client-ip=185.205.69.213; envelope-from=yelninei@tutamail.com; helo=mail.w13.tutanota.de X-Spam_score_int: -27 X-Spam_score: -2.8 X-Spam_bar: -- X-Spam_report: (-2.8 / 5.0 requ) BAYES_00=-1.9, DKIM_SIGNED=0.1, DKIM_VALID=-0.1, DKIM_VALID_AU=-0.1, DKIM_VALID_EF=-0.1, RCVD_IN_DNSWL_LOW=-0.7, RCVD_IN_MSPIKE_H2=0.001, RCVD_IN_VALIDITY_CERTIFIED_BLOCKED=0.001, RCVD_IN_VALIDITY_RPBL_BLOCKED=0.001, SPF_HELO_NONE=0.001, SPF_PASS=-0.001 autolearn=ham autolearn_force=no X-Spam_action: no action X-Spam-Score: 0.9 (/) X-Debbugs-Envelope-To: submit X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -0.1 (/) guix --version guix (GNU Guix) 812f972f046e521eabc3ddd76e790d7a69d426b5 fibers test suite is hanging so I am skipping it for now. Also I had to cheat a bit to get past automakes "t/output-order.sh" test wh= ere the guile build driver is leaking "GC Warning: Repeated allocation of v= ery large block" onto stderr confusing the test (similar problem to https:/= /issues.guix.gnu.org/73181). I added env["GC_LARGE_ALLOC_WARN_INTERVAL"] = =3D "100" to=C2=A0DerivationGoal::startBuilder() and used the slightly patc= hed daemon to build automake without changing the derivation. guix build shepherd --without-tests=3Dguile-fibers -s i586-gnu These 3 tests are failing: FAIL: tests/logging-failure.sh FAIL: tests/forking-service.sh FAIL: tests/services/system-log.sh Some others are being skipped, looking at the shepherd code this seems to b= e expected. SKIP: tests/logging-ENOSPC.sh SKIP: tests/close-on-exec.sh SKIP: tests/daemonize.sh Here is the output of the failing ones: FAIL: tests/logging-failure =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D + shepherd --version System lacks support for 'signalfd'; using fallback mechanism. shepherd (GNU Shepherd) 1.0.3 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + herd --version herd (GNU Shepherd) 1.0.3 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + log_directory=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log= -directory-8783 + socket=3Dt-socket-8783 + conf=3Dt-conf-8783 + log=3Dt-log-8783 + pid=3Dt-pid-8783 + herd=3D'herd -s t-socket-8783' + trap 'cat t-log-8783 || true; rm -f t-log-8783 t-socket-8783 t-conf-8783 = t-pid-8783; rm -rf /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-lo= g-directory-8783; =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 kill $main_pid || true' EXIT + cat + rm -f t-pid-8783 + main_pid=3D8788 + test -f t-pid-8783 + shepherd -I -s t-socket-8783 -c t-conf-8783 -l t-log-8783 --pid=3Dt-pid-8= 783 + sleep 0.3 System lacks support for 'signalfd'; using fallback mechanism. GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) Starting service root... Service root started. Service root running with value #< id: 8788 command: #f>. Service root has been started. Configuration successfully loaded from 't-conf-8783'. + test -f t-pid-8783 + herd -s t-socket-8783 status Started: + root Stopped: - log-directory-does-not-exist - log-directory-not-writable + test -f /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log-directo= ry-8783 + herd -s t-socket-8783 start log-directory-does-not-exist Service log-directory-does-not-exist has been started. + herd -s t-socket-8783 status log-directory-does-not-exist + grep running =C2=A0 It is running since 07:53:13 (0 seconds ago). + herd -s t-socket-8783 status log-directory-does-not-exist + grep /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log-directory-= 8783/service.log =C2=A0 Log file: /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log-= directory-8783/service.log + grep logging /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log-di= rectory-8783/service.log 2025-04-08 07:53:13 logging + herd -s t-socket-8783 start log-directory-not-writable ++ cat t-log-8783 2025-04-08 07:53:13 GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) 2025-04-08 07:53:13 Starting service root... 2025-04-08 07:53:13 Service root started. 2025-04-08 07:53:13 Service root running with value #< id: 8788 co= mmand: #f>. 2025-04-08 07:53:13 Service root has been started. 2025-04-08 07:53:13 Configuration successfully loaded from 't-conf-8783'. 2025-04-08 07:53:13 Starting service log-directory-does-not-exist... 2025-04-08 07:53:13 Service log-directory-does-not-exist has been started. 2025-04-08 07:53:13 Service log-directory-does-not-exist started. 2025-04-08 07:53:13 Service log-directory-does-not-exist running with value= #< id: 8792 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6= r-bash-minimal-5.1.16/bin/bash" "-c" "while true; do echo logging; sleep 0.= 2; done")>. 2025-04-08 07:53:13 Starting service log-directory-not-writable... Terminated ++ rm -f t-log-8783 t-socket-8783 t-conf-8783 t-pid-8783 ++ rm -rf /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-log-directo= ry-8783 ++ kill 8788 FAIL tests/logging-failure.sh (exit status: 124) FAIL: tests/forking-service =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D + shepherd --version System lacks support for 'signalfd'; using fallback mechanism. shepherd (GNU Shepherd) 1.0.3 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + herd --version herd (GNU Shepherd) 1.0.3 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + socket=3Dt-socket-10595 + conf=3Dt-conf-10595 + log=3Dt-log-10595 + pid=3Dt-pid-10595 + service_pid=3Dt-service-pid-10595 + service_nofiles=3Dt-service-nofiles-10595 + service2_pid=3Dt-service2-pid-10595 + service2_started=3Dt-service2-starts-10595 + herd=3D'herd -s t-socket-10595' + trap cleanup EXIT + cat + cat t-conf-10595 ;; Leave a timeout long enough for slow machines. (default-pid-file-timeout 10) (define %command =C2=A0 '("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/b= in/bash" "-c" "ulimit -n >/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0= .3/t-service-nofiles-10595; sleep 600 & echo $! > /tmp/guix-build-shepherd-= 1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")) (register-services (list (service ;; A service that forks into a different process. '(test) #:start (make-forkexec-constructor =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2= =A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 %command =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2= =A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 #:pid-file "/tmp/guix-build-shepherd-1.0.= 3.drv-0/shepherd-1.0.3/t-service-pid-10595" =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2= =A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 #:resource-limits '((nofile 1567 1567))) #:stop=C2=A0 (make-kill-destructor) #:respawn? #f))) (define %command2 =C2=A0 '("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/b= in/bash" "-c" "echo started >> /tmp/guix-build-shepherd-1.0.3.drv-0/shepher= d-1.0.3/t-service2-starts-10595; sleep 600 & echo $! > /tmp/guix-build-shep= herd-1.0.3.drv-0/shepherd-1.0.3/t-service2-pid-10595")) (register-services (list (service ;; A service that forks into a different process. '(test2) #:start (make-forkexec-constructor %command2 =C2=A0=C2=A0 #:pid-file "/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.= 3/t-service2-pid-10595") #:stop=C2=A0 (make-kill-destructor) =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 #:respawn-delay 0 #:respawn? #t))) (define %command3 =C2=A0 '("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/b= in/bash" "-c" "sleep 600")) (register-services (list (service ;; A service that forks into a different process. '(test3) #:start (make-forkexec-constructor %command3) #:stop=C2=A0 (make-kill-destructor) #:respawn? #t))) (define %command4 =C2=A0 '("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/b= in/bash" "-c" "trap 'echo ignoring SIGTERM' SIGTERM; while true ; do : ; do= ne")) (register-services (list (service ;; A service that ignores SIGTERM. '(test4) #:requirement '(test3) #:start (make-forkexec-constructor %command4) #:stop=C2=A0 (make-kill-destructor SIGTERM #:grace-period 3)))) + rm -f t-pid-10595 + test -f t-pid-10595 + shepherd -I -s t-socket-10595 -c t-conf-10595 -l t-log-10595 --pid=3Dt-pi= d-10595 + sleep 0.3 System lacks support for 'signalfd'; using fallback mechanism. GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) Starting service root... Service root started. Service root running with value #< id: 10602 command: #f>. Service root has been started. Configuration successfully loaded from 't-conf-10595'. + test -f t-pid-10595 ++ cat t-pid-10595 + shepherd_pid=3D10602 + herd -s t-socket-10595 start test Starting service test... Service test has been started. + herd -s t-socket-10595 start test2 Starting service test2... Service test2 started. Service test2 running with value #< id: 10623 command: ("/gnu/stor= e/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "echo= started >> /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service2-= starts-10595; sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/sh= epherd-1.0.3/t-service2-pid-10595")>. Service test2 has been started. + herd -s t-socket-10595 status test + grep running =C2=A0 It is running since 07:56:16 (1 second ago). + test -f t-service-pid-10595 ++ cat t-service-pid-10595 + service_pid_value=3D10610 + kill 10610 + kill -0 10610 ./tests/forking-service.sh: line 133: kill: (10610) - No such process + within_seconds 10 'herd -s t-socket-10595 status test | grep stopped' + seconds=3D10 + '[' 10 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! Uncaught exception in essential task process-monitor: (wrong-type-arg "=3D"= "Wrong type argument in position ~A: ~S" (1 #< id: 10610 command:= ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash= " "-c" "ulimit -n >/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-se= rvice-nofiles-10595; sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.d= rv-0/shepherd-1.0.3/t-service-pid-10595")>) (#< id: 10610 command:= ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash= " "-c" "ulimit -n >/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-se= rvice-nofiles-10595; sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.d= rv-0/shepherd-1.0.3/t-service-pid-10595")>)) PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 10 - 1 + seconds=3D9 + '[' 9 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 9 - 1 + seconds=3D8 + '[' 8 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 8 - 1 + seconds=3D7 + '[' 7 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 7 - 1 + seconds=3D6 + '[' 6 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 6 - 1 + seconds=3D5 + '[' 5 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 5 - 1 + seconds=3D4 + '[' 4 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 4 - 1 + seconds=3D3 + '[' 3 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 3 - 1 + seconds=3D2 + '[' 2 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 2 - 1 + seconds=3D1 + '[' 1 -gt 0 ']' + /gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash = -c 'herd -s t-socket-10595 status test | grep stopped' + sleep 1 PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! PID #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk= 9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shephe= rd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! = > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")= > (test) is dead! ++ expr 1 - 1 + seconds=3D0 + cleanup + cat t-log-10595 2025-04-08 07:56:14 GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) 2025-04-08 07:56:14 Starting service root... 2025-04-08 07:56:14 Service root started. 2025-04-08 07:56:14 Service root running with value #< id: 10602 c= ommand: #f>. 2025-04-08 07:56:14 Service root has been started. 2025-04-08 07:56:14 Configuration successfully loaded from 't-conf-10595'. 2025-04-08 07:56:15 Starting service test... 2025-04-08 07:56:16 Service test has been started. 2025-04-08 07:56:16 Service test started. 2025-04-08 07:56:16 Service test running with value #< id: 10610 c= ommand: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/b= in/bash" "-c" "ulimit -n >/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0= .3/t-service-nofiles-10595; sleep 600 & echo $! > /tmp/guix-build-shepherd-= 1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")>. 2025-04-08 07:56:16 Starting service test2... 2025-04-08 07:56:17 Service test2 has been started. 2025-04-08 07:56:17 Service test2 started. 2025-04-08 07:56:17 Service test2 running with value #< id: 10623 = command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/= bin/bash" "-c" "echo started >> /tmp/guix-build-shepherd-1.0.3.drv-0/shephe= rd-1.0.3/t-service2-starts-10595; sleep 600 & echo $! > /tmp/guix-build-she= pherd-1.0.3.drv-0/shepherd-1.0.3/t-service2-pid-10595")>. 2025-04-08 07:56:17 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:17 Uncaught exception in essential task process-monitor: (= wrong-type-arg "=3D" "Wrong type argument in position ~A: ~S" (1 #< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-min= imal-5.1.16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shepherd-1.0.3.drv-0= /shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! > /tmp/guix-bu= ild-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")>) (#< id: 10610 command: ("/gnu/store/45ssyl3mps6yvh0k0zsgxs8wjqk9jd6r-bash-min= imal-5.1. 2025-04-08 07:56:17 16/bin/bash" "-c" "ulimit -n >/tmp/guix-build-shepherd-= 1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; sleep 600 & echo $! > /= tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-pid-10595")>)) 2025-04-08 07:56:18 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:18 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:19 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:19 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:20 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:20 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:21 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:21 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:22 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:22 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:23 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:23 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:24 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:24 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:25 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:25 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:26 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:26 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! 2025-04-08 07:56:27 PID #< id: 10610 command: ("/gnu/store/45ssyl3= mps6yvh0k0zsgxs8wjqk9jd6r-bash-minimal-5.1.16/bin/bash" "-c" "ulimit -n >/t= mp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-service-nofiles-10595; = sleep 600 & echo $! > /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -service-pid-10595")> (test) is dead! + rm -f t-socket-10595 t-conf-10595 t-log-10595 t-service2-starts-10595 t-s= ervice-nofiles-10595 + test -f t-pid-10595 ++ cat t-pid-10595 + kill 10602 + rm -f t-pid-10595 Stopping service root... Exiting shepherd... Ignoring error while stopping test: (system-error "getpgid" "~A" ("No such = process") (1073741827)) Stopping service test2... Awaiting PID 10622, which is already gone. Service test2 stopped. Service test2 is now stopped. Service test3 is not running. Service test4 is not running. Exiting. + test -f t-service-pid-10595 ++ cat t-service-pid-10595 + kill 10610 ./tests/forking-service.sh: line 40: kill: (10610) - No such process + true + rm -f t-service-pid-10595 + test -f t-service2-pid-10595 ++ cat t-service2-pid-10595 + kill 10623 ./tests/forking-service.sh: line 42: kill: (10623) - No such process + true + rm -f t-service2-pid-10595 FAIL tests/forking-service.sh (exit status: 1) FAIL: tests/services/system-log =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D + shepherd --version System lacks support for 'signalfd'; using fallback mechanism. shepherd (GNU Shepherd) 1.0.3 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + herd --version herd (GNU Shepherd) 1.0.3 Copyright (C) 2025 the Shepherd authors License GPLv3+: GNU GPL version 3 or later This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. + socket=3Dt-socket-12440 + conf=3Dt-conf-12440 + log=3Dt-log-12440 + pid=3Dt-pid-12440 + logger=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-log= ger-12440.scm + kmsg=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-kmsg-= 12440 + syslog_file=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslo= g-12440 + syslog_auth_file=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-= syslog-auth-12440 + syslog_debug_file=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t= -syslog-debug-12440 + syslog_remote_file=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/= t-syslog-remote-12440 + syslog_socket=3D/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-sys= log-socket-12440 + herd=3D'herd -s t-socket-12440' + trap 'zcat t-log-12440.* || true; cat t-log-12440 || true; =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 rm -f t-socket-12440 t-conf-12440 t-log-1244= 0* /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-logger-1244= 0.scm /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-kmsg-124= 40 /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-socket-1244= 0; =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 rm -f /tmp/guix-build-shepherd-1.0.3.drv-0/s= hepherd-1.0.3/t-syslog-12440* /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd= -1.0.3/t-syslog-auth-12440* /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1= .0.3/t-syslog-debug-12440* /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.= 0.3/t-syslog-remote-12440*; =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 test -f t-pid-12440 && kill `cat t-pid-12440= ` || true; rm -f t-pid-12440' EXIT + cat + cat + chmod +x /tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-log= ger-12440.scm + cat + rm -f t-pid-12440 + test -f t-pid-12440 + shepherd -I -s t-socket-12440 -c t-conf-12440 -l t-log-12440 --pid=3Dt-pi= d-12440 + sleep 0.3 System lacks support for 'signalfd'; using fallback mechanism. GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) Starting service root... Service root started. Service root running with value #< id: 12448 command: #f>. Service root has been started. Configuration successfully loaded from 't-conf-12440'. + test -f t-pid-12440 + herd -s t-socket-12440 eval root '(gc)' Evaluating user expression (gc). "#" ++ file_descriptor_count ++ wc -l +++ cat t-pid-12440 ++ ls -l '/proc/12448/fd/[0-9]*' ls: cannot access '/proc/12448/fd/[0-9]*': No such file or directory + initial_fd_count=3D0 + herd -s t-socket-12440 start logger Starting service system-log... Service system-log has been started. Service system-log started. ;;; (message-destination-> ("/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-= 1.0.3/t-syslog-12440")) Service system-log running with value #. Starting service logger... Service logger has been started. + herd -s t-socket-12440 status logger + grep stopped ++ zcat 't-log-12440.*' gzip: t-log-12440.*.gz: No such file or directory Terminated ++ true ++ cat t-log-12440 2025-04-08 07:57:32 GNU Shepherd 1.0.3 (Guile 3.0.9, i586-unknown-gnu) 2025-04-08 07:57:32 Starting service root... 2025-04-08 07:57:32 Service root started. 2025-04-08 07:57:32 Service root running with value #< id: 12448 c= ommand: #f>. 2025-04-08 07:57:32 Service root has been started. 2025-04-08 07:57:32 Configuration successfully loaded from 't-conf-12440'. 2025-04-08 07:57:32 Evaluating user expression (gc). 2025-04-08 07:57:32 Starting service system-log... 2025-04-08 07:57:59 Service system-log has been started. 2025-04-08 07:57:59 Service system-log started. 2025-04-08 07:58:32=C2=A0 2025-04-08 07:58:32 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-12440")) 2025-04-08 07:58:32 Service system-log running with value #. 2025-04-08 07:58:32 Starting service logger... 2025-04-08 07:58:32 Service logger has been started. 2025-04-08 07:58:32 Service logger started. 2025-04-08 07:58:32=C2=A0 2025-04-08 07:58:32 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:58:32=C2=A0 2025-04-08 07:58:32 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:58:32 Service logger running with value #< id: 12978= command: ("/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-lo= gger-12440.scm")>. 2025-04-08 07:58:32=C2=A0 2025-04-08 07:58:32 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:58:32=C2=A0 2025-04-08 07:58:43 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:58:43=C2=A0 2025-04-08 07:58:45 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:58:45=C2=A0 2025-04-08 07:58:59 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:58:59=C2=A0 2025-04-08 07:59:00 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:59:00=C2=A0 2025-04-08 07:59:05 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:59:05=C2=A0 2025-04-08 07:59:05 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:59:05=C2=A0 2025-04-08 07:59:06 ;;; (message-destination-> ("/tmp/guix-build-shepherd-1= .0.3.drv-0/shepherd-1.0.3/t-syslog-remote-12440")) 2025-04-08 07:59:06 t-syslog-logger-12440.scm[12978] starting logger 2025-04-08 07:59:07 Service logger has been disabled. ++ rm -f t-socket-12440 t-conf-12440 't-log-12440*' /tmp/guix-build-shepher= d-1.0.3.drv-0/shepherd-1.0.3/t-syslog-logger-12440.scm /tmp/guix-build-shep= herd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-kmsg-12440 /tmp/guix-build-shepher= d-1.0.3.drv-0/shepherd-1.0.3/t-syslog-socket-12440 ++ rm -f '/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-1244= 0*' '/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-auth-1244= 0*' '/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-debug-124= 40*' '/tmp/guix-build-shepherd-1.0.3.drv-0/shepherd-1.0.3/t-syslog-remote-1= 2440*' ++ test -f t-pid-12440 +++ cat t-pid-12440 ++ kill 12448 ++ rm -f t-pid-12440 FAIL tests/services/system-log.sh (exit status: 124) yelninei ------------=_1745530805-27026-1--