bug#40981: Graphical installer tests sometimes hang.

[Mathieu Othacehe <m.othacehe@gmail.com>]

[-- Attachment #1: Type: text/plain, Size: 541 bytes --]


Hey!

> OK, but the trace above is “as expected”, isn’t it?

Oh sorry, I pasted the wrong part! Here's the part where getpgid returns
zero and the full log in attachment:

--8<---------------cut here---------------start------------->8---
[pid     1] stat("/etc/localtime", {st_mode=S_IFREG|0444, st_size=2298, ...}) = 0
[pid     1] write(9, "shepherd[1]: clearing HTTP/HTTPS"..., 59) = 59
[pid     1] getpgid(266)                = 0
--8<---------------cut here---------------end--------------->8---

Thanks,

Mathieu

[-- Attachment #2: strace.log --]
[-- Type: application/octet-stream, Size: 4974 bytes --]

/gnu/store/9xl2m0qgqcxk4m3qmf957pq6dfpnm5c1-strace-5.5/bin/strace: Process 1 attached with 2 threads
[pid   169] read(10,  <unfinished ...>
[pid     1] select(15, [3 14], [], [], {tv_sec=1, tv_usec=768164}) = 0 (Timeout)
[pid     1] wait4(-1, 0x7ffd2b3a79dc, WNOHANG, NULL) = 0
[pid     1] rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0
[pid     1] select(15, [3 14], [], [], {tv_sec=30, tv_usec=0}) = 1 (in [14], left {tv_sec=25, tv_usec=541389})
[pid     1] accept4(14, {sa_family=AF_UNIX}, [112->2], 0) = 16
[pid     1] read(16, "(shepherd-command (version 0) (a"..., 1024) = 134
[pid     1] getcwd("/", 100)            = 2
[pid     1] brk(0x5e9000)               = 0x5e9000
[pid     1] brk(0x5e1000)               = 0x5e1000
[pid     1] brk(0x5d9000)               = 0x5d9000
[pid     1] brk(0x5d1000)               = 0x5d1000
[pid     1] chdir("/root")              = 0
[pid     1] stat("/etc/localtime", {st_mode=S_IFREG|0444, st_size=2298, ...}) = 0
[pid     1] write(9, "shepherd[1]: changing HTTP/HTTPS"..., 86) = 86
[pid     1] getpgid(194)                = 194
[pid     1] kill(-194, SIGTERM)         = 0
[pid     1] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_KILLED, si_pid=194, si_uid=0, si_status=SIGTERM, si_utime=1, si_stime=2} ---
[pid     1] write(11, "\21", 1 <unfinished ...>
[pid   169] <... read resumed>"\21", 1) = 1
[pid     1] <... write resumed>)        = 1
[pid   169] rt_sigprocmask(SIG_BLOCK, NULL, ~[KILL STOP PWR RTMIN RT_1], 8) = 0
[pid   169] read(10,  <unfinished ...>
[pid     1] rt_sigreturn({mask=[]})     = 0
[pid     1] stat("/etc/localtime", {st_mode=S_IFREG|0444, st_size=2298, ...}) = 0
[pid     1] write(9, "shepherd[1]: Service guix-daemon"..., 51) = 51
[pid     1] wait4(-1, [{WIFSIGNALED(s) && WTERMSIG(s) == SIGTERM}], WNOHANG, NULL) = 194
[pid     1] wait4(-1, 0x7ffd2b3a779c, WNOHANG, NULL) = 0
[pid     1] getuid()                    = 0
[pid     1] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc8094a1e50) = 266
[pid     1] stat("/etc/localtime", {st_mode=S_IFREG|0444, st_size=2298, ...}) = 0
[pid     1] write(9, "shepherd[1]: Service guix-daemon"..., 51) = 51
[pid     1] chdir("/")                  = 0
[pid     1] write(16, "(reply (version 0) (result (#t))"..., 214) = 214
[pid     1] close(16)                   = 0
[pid     1] brk(0x5c1000)               = 0x5c1000
[pid     1] rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0
[pid     1] select(15, [3 14], [], [], {tv_sec=30, tv_usec=0}) = 1 (in [14], left {tv_sec=29, tv_usec=995886})
[pid     1] accept4(14, {sa_family=AF_UNIX}, [112->2], 0) = 16
[pid     1] read(16, "(shepherd-command (version 0) (a"..., 1024) = 111
[pid     1] getcwd("/", 100)            = 2
[pid     1] brk(0x5e9000)               = 0x5e9000
[pid     1] brk(0x5e1000)               = 0x5e1000
[pid     1] brk(0x5d9000)               = 0x5d9000
[pid     1] brk(0x5d1000)               = 0x5d1000
[pid     1] chdir("/root")              = 0
[pid     1] stat("/etc/localtime", {st_mode=S_IFREG|0444, st_size=2298, ...}) = 0
[pid     1] write(9, "shepherd[1]: clearing HTTP/HTTPS"..., 59) = 59
[pid     1] getpgid(266)                = 0
[pid     1] kill(0, SIGTERM)            = 0
[pid     1] --- SIGTERM {si_signo=SIGTERM, si_code=SI_USER, si_pid=1, si_uid=0} ---
[pid     1] write(11, "\17", 1 <unfinished ...>
[pid   169] <... read resumed>"\17", 1) = 1
[pid     1] <... write resumed>)        = 1
[pid   169] rt_sigprocmask(SIG_BLOCK, NULL, ~[KILL STOP PWR RTMIN RT_1], 8) = 0
[pid   169] read(10,  <unfinished ...>
[pid     1] rt_sigreturn({mask=[]})     = 0
[pid     1] stat("/etc/localtime", {st_mode=S_IFREG|0444, st_size=2298, ...}) = 0
[pid     1] write(9, "shepherd[1]: Service guix-daemon"..., 51) = 51
[pid     1] stat("/etc/localtime", {st_mode=S_IFREG|0444, st_size=2298, ...}) = 0
[pid     1] write(9, "shepherd[1]: Exiting shepherd..."..., 33) = 33
[pid     1] getpgid(222)                = 222
[pid     1] kill(-222, SIGTERM)         = 0
[pid     1] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_KILLED, si_pid=0, si_uid=0, si_status=SIGTERM, si_utime=3, si_stime=0} ---
[pid     1] write(11, "\21", 1 <unfinished ...>
[pid   169] <... read resumed>"\21", 1) = 1
[pid     1] <... write resumed>)        = 1
[pid   169] rt_sigprocmask(SIG_BLOCK, NULL, ~[KILL STOP PWR RTMIN RT_1], 8) = 0
[pid   169] read(10,  <unfinished ...>
[pid     1] rt_sigreturn({mask=[]})     = 0
[pid     1] stat("/etc/localtime", {st_mode=S_IFREG|0444, st_size=2298, ...}) = 0
[pid     1] write(9, "shepherd[1]: Service mcron has b"..., 45) = 45
[pid     1] stat("/etc/localtime", {st_mode=S_IFREG|0444, st_size=2298, ...}) = 0
[pid     1] write(9, "shepherd[1]: Service console-fon"..., 57) = 57
[pid     1] getpgid(203)                = 203
[pid     1] kill(-203, SIGTERM)         = 0
/gnu/store/9xl2m0qgqcxk4m3qmf957pq6dfpnm5c1-strace-5.5/bin/strace: Process 1 detached
/gnu/store/9xl2m0qgqcxk4m3qmf957pq6dfpnm5c1-strace-5.5/bin/strace: Process 169 detached