Re: kern/59339: heartbeat watchdog fires since 10.99.14

["Thomas Klausner via gnats" <gnats-admin%NetBSD.org@localhost>]

The following reply was made to PR kern/59339; it has been noted by GNATS.

From: Thomas Klausner <wiz%NetBSD.org@localhost>
To: Taylor R Campbell <riastradh%NetBSD.org@localhost>
Cc: NetBSD bugtracking <gnats-bugs%NetBSD.org@localhost>
Subject: Re: kern/59339: heartbeat watchdog fires since 10.99.14
Date: Sat, 10 May 2025 11:29:01 +0200

 On Sat, May 10, 2025 at 08:41:06AM +0000, Taylor R Campbell wrote:
 > New patch!  More diagnostics, more potential problems avoided.
 
 Much worse, two panics in less than 5 minutes each.
 
 May 10 11:17:20 exadelic savecore: reboot after panic: [ 270.1245676] panic: kernel diagnostic assertion "ts->tv_sec >= 0" failed: file "/disk/storage-202410/archive/foreign/src/sys/kern/subr_time.c", line 97
 May 10 11:21:41 exadelic savecore: reboot after panic: [ 221.8767863] panic: kernel diagnostic assertion "ts->tv_sec >= 0" failed: file "/disk/storage-202410/archive/foreign/src/sys/kern/subr_time.c", line 97
 
 
 (gdb) target kvm netbsd.14.core
 0xffffffff80239b85 in cpu_reboot (howto=howto@entry=260, bootstr=bootstr@entry=0x0) at /usr/src/sys/arch/amd64/amd64/machdep.c:709
 warning: 709    /usr/src/sys/arch/amd64/amd64/machdep.c: No such file or directory
 (gdb) bt
 #0  0xffffffff80239b85 in cpu_reboot (howto=howto@entry=260, bootstr=bootstr@entry=0x0) at /usr/src/sys/arch/amd64/amd64/machdep.c:709
 #1  0xffffffff80de3b15 in kern_reboot (howto=howto@entry=260, bootstr=bootstr@entry=0x0) at /usr/src/sys/kern/kern_reboot.c:91
 #2  0xffffffff80e29be3 in vpanic (fmt=0xffffffff81427440 "kernel %sassertion \"%s\" failed: file \"%s\", line %d ", ap=ap@entry=0xffff98a47e05bde8) at /usr/src/sys/kern/subr_prf.c:288
 #3  0xffffffff80ff84cc in kern_assert (fmt=fmt@entry=0xffffffff81427440 "kernel %sassertion \"%s\" failed: file \"%s\", line %d ") at /usr/src/sys/lib/libkern/kern_assert.c:51
 #4  0xffffffff80e2ba6e in tstohz (ts=ts@entry=0xffff98a47e05be80) at /usr/src/sys/kern/subr_time.c:97
 #5  0xffffffff80e2bb3b in tshzto (tsp=tsp@entry=0xffff876be5c93cb8) at /usr/src/sys/kern/subr_time.c:73
 #6  0xffffffff80e00a98 in itimer_arm_real (it=<optimized out>) at /usr/src/sys/kern/kern_time.c:836
 #7  itimer_settime (it=it@entry=0xffff876be5c93c40) at /usr/src/sys/kern/kern_time.c:1040
 #8  0xffffffff80e01105 in dosetitimer (p=p@entry=0xffff876c28b45c00, which=which@entry=0, itvp=itvp@entry=0xffff98a47e05bf20) at /usr/src/sys/kern/kern_time.c:1836
 #9  0xffffffff80e02379 in dosetitimer (itvp=0xffff98a47e05bf20, which=0, p=0xffff876c28b45c00) at /usr/src/sys/kern/kern_time.c:1748
 #10 sys___setitimer50 (l=0xffff876c23737400, uap=0xffff98a47e05c000, retval=0xffff98a47e05bfb0) at /usr/src/sys/kern/kern_time.c:1733
 #11 0xffffffff805b7cc1 in sy_call (rval=0xffff98a47e05bfb0, uap=0xffff98a47e05c000, l=0xffff876c23737400, sy=0xffffffff8188a358 <sysent+10200>) at /usr/src/sys/sys/syscallvar.h:65
 #12 sy_invoke (code=425, rval=0xffff98a47e05bfb0, uap=0xffff98a47e05c000, l=0xffff876c23737400, sy=0xffffffff8188a358 <sysent+10200>) at /usr/src/sys/sys/syscallvar.h:94
 #13 syscall (frame=0xffff98a47e05c000) at /usr/src/sys/arch/x86/x86/syscall.c:137
 #14 0xffffffff8021025d in handle_syscall ()
 
 (gdb) fr 6
 #6  0xffffffff80e00a98 in itimer_arm_real (it=<optimized out>) at /usr/src/sys/kern/kern_time.c:836
 warning: 836    /usr/src/sys/kern/kern_time.c: No such file or directory
 (gdb) p *it
 value has been optimized out
 (gdb) fr 7
 #7  itimer_settime (it=it@entry=0xffff876be5c93c40) at /usr/src/sys/kern/kern_time.c:1040
 1040    in /usr/src/sys/kern/kern_time.c
 (gdb) p *it
 $1 = {{it_real = {it_ch = {_c_store = {0xffff98810ad5f098, 0xffff98810ad5f098, 0xffffffff80dff106 <itimer_callout>, 0xffff876be5c93c40, 0xffff98810ad5f000, 0x10000006902, 0x11deeba1, 0x0, 0x0, 0x0}}, it_rtchgq = {
         le_next = 0x0, le_prev = 0x0}}, it_virtual = {it_vlist = 0xffff98810ad5f098, it_list = {le_next = 0xffff98810ad5f098, le_prev = 0xffffffff80dff106 <itimer_callout>}, it_active = 64}},
   it_ops = 0xffffffff8138a470 <ptimer_itimer_ops>, it_time = {it_interval = {tv_sec = 0, tv_nsec = 10000000}, it_value = {tv_sec = 1746868565, tv_nsec = 444217852}}, it_clockid = 0, it_overruns = 0, it_dying = false}
 (gdb)
 
 (gdb) target kvm netbsd.15.core
 0xffffffff80239b85 in cpu_reboot (howto=howto@entry=260, bootstr=bootstr@entry=0x0) at /usr/src/sys/arch/amd64/amd64/machdep.c:709
 warning: 709    /usr/src/sys/arch/amd64/amd64/machdep.c: No such file or directory
 (gdb) bt
 #0  0xffffffff80239b85 in cpu_reboot (howto=howto@entry=260, bootstr=bootstr@entry=0x0) at /usr/src/sys/arch/amd64/amd64/machdep.c:709
 #1  0xffffffff80de3b15 in kern_reboot (howto=howto@entry=260, bootstr=bootstr@entry=0x0) at /usr/src/sys/kern/kern_reboot.c:91
 #2  0xffffffff80e29be3 in vpanic (fmt=0xffffffff81427440 "kernel %sassertion \"%s\" failed: file \"%s\", line %d ", ap=ap@entry=0xffffc0247bf84de8) at /usr/src/sys/kern/subr_prf.c:288
 #3  0xffffffff80ff84cc in kern_assert (fmt=fmt@entry=0xffffffff81427440 "kernel %sassertion \"%s\" failed: file \"%s\", line %d ") at /usr/src/sys/lib/libkern/kern_assert.c:51
 #4  0xffffffff80e2ba6e in tstohz (ts=ts@entry=0xffffc0247bf84e80) at /usr/src/sys/kern/subr_time.c:97
 #5  0xffffffff80e2bb3b in tshzto (tsp=tsp@entry=0xffff888c44bf9f38) at /usr/src/sys/kern/subr_time.c:73
 #6  0xffffffff80e00a98 in itimer_arm_real (it=<optimized out>) at /usr/src/sys/kern/kern_time.c:836
 #7  itimer_settime (it=it@entry=0xffff888c44bf9ec0) at /usr/src/sys/kern/kern_time.c:1040
 #8  0xffffffff80e01d4a in dotimer_settime (timerid=4, value=value@entry=0xffffc0247bf84f40, ovalue=ovalue@entry=0x0, flags=1, p=<optimized out>) at /usr/src/sys/kern/kern_time.c:1557
 #9  0xffffffff80e01f44 in sys___timer_settime50 (l=0xffff888a9d1c7c00, uap=0xffffc0247bf85000, retval=<optimized out>) at /usr/src/sys/kern/kern_time.c:1485
 #10 0xffffffff805b7cc1 in sy_call (rval=0xffffc0247bf84fb0, uap=0xffffc0247bf85000, l=0xffff888a9d1c7c00, sy=0xffffffff8188a550 <sysent+10704>) at /usr/src/sys/sys/syscallvar.h:65
 #11 sy_invoke (code=446, rval=0xffffc0247bf84fb0, uap=0xffffc0247bf85000, l=0xffff888a9d1c7c00, sy=0xffffffff8188a550 <sysent+10704>) at /usr/src/sys/sys/syscallvar.h:94
 #12 syscall (frame=0xffffc0247bf85000) at /usr/src/sys/arch/x86/x86/syscall.c:137
 #13 0xffffffff8021025d in handle_syscall ()
 (gdb) p *it
 $1 = {{it_real = {it_ch = {_c_store = {0xffff888acc47b4c0, 0xffffc0010ad87328, 0xffffffff80dff106 <itimer_callout>, 0xffff888c44bf9ec0, 0xffffc0010ad87000, 0x10000005628, 0x11deeba1, 0x0, 0x0, 0x0}}, it_rtchgq = {
         le_next = 0x0, le_prev = 0x0}}, it_virtual = {it_vlist = 0xffff888acc47b4c0, it_list = {le_next = 0xffffc0010ad87328, le_prev = 0xffffffff80dff106 <itimer_callout>}, it_active = 192}},
   it_ops = 0xffffffff8138a470 <ptimer_itimer_ops>, it_time = {it_interval = {tv_sec = 0, tv_nsec = 0}, it_value = {tv_sec = 1746868825, tv_nsec = 142185780}}, it_clockid = 0, it_overruns = 0, it_dying = false}
 (gdb)