witness: userret: returning with the following locks held: exclusive rwlock solock r = 0 (0xfffffd806d5ce818) #0 witness_lock+0x44d #1 unp_solock_peer+0x64 sys/kern/uipc_usrreq.c:168 #2 uipc_usrreq+0x7c6 sys/kern/uipc_usrreq.c:350 #3 sosend+0x61b sys/kern/uipc_socket.c:657 #4 sendit+0x65d sys/kern/uipc_syscalls.c:682 #5 sys_sendmsg+0x198 sys/kern/uipc_syscalls.c:589 #6 syscall+0x4c3 mi_syscall sys/sys/syscall_mi.h:101 [inline] #6 syscall+0x4c3 sys/arch/amd64/amd64/trap.c:585 #7 Xsyscall+0x128 panic: witness_warn Stopped at db_enter+0x18: addq $0x8,%rsp TID PID UID PRFLAGS PFLAGS CPU COMMAND * 93424 55503 0 0x2 0 0 syz-executor1463305089 13620 1412 73 0x1100010 0 1 syslogd db_enter() at db_enter+0x18 sys/arch/amd64/amd64/db_interface.c:437 panic(ffffffff82593e80) at panic+0x177 sys/kern/subr_prf.c:202 witness_warn(2,0,ffffffff82620d13) at witness_warn+0x65e witness_debugger sys/kern/subr_witness.c:2505 [inline] witness_warn(2,0,ffffffff82620d13) at witness_warn+0x65e sys/kern/subr_witness.c:1473 userret(ffff8000ffff6d30) at userret+0x265 sys/kern/kern_sig.c:2012 syscall(ffff800021282d90) at syscall+0x57e mi_syscall_return sys/sys/syscall_mi.h:128 [inline] syscall(ffff800021282d90) at syscall+0x57e sys/arch/amd64/amd64/trap.c:607 Xsyscall() at Xsyscall+0x128 end of kernel end trace frame: 0x7f7ffffe9830, count: 9 https://www.openbsd.org/ddb.html describes the minimum info required in bug reports. Insufficient info makes it difficult to find and fix bugs. ddb{0}> ddb{0}> set $lines = 0 ddb{0}> set $maxwidth = 0 ddb{0}> show panic *cpu0: witness_warn ddb{0}> trace db_enter() at db_enter+0x18 sys/arch/amd64/amd64/db_interface.c:437 panic(ffffffff82593e80) at panic+0x177 sys/kern/subr_prf.c:202 witness_warn(2,0,ffffffff82620d13) at witness_warn+0x65e witness_debugger sys/kern/subr_witness.c:2505 [inline] witness_warn(2,0,ffffffff82620d13) at witness_warn+0x65e sys/kern/subr_witness.c:1473 userret(ffff8000ffff6d30) at userret+0x265 sys/kern/kern_sig.c:2012 syscall(ffff800021282d90) at syscall+0x57e mi_syscall_return sys/sys/syscall_mi.h:128 [inline] syscall(ffff800021282d90) at syscall+0x57e sys/arch/amd64/amd64/trap.c:607 Xsyscall() at Xsyscall+0x128 end of kernel end trace frame: 0x7f7ffffe9830, count: -6 ddb{0}> show registers rdi 0 rsi 0x1 rbp 0xffff800021282ac0 rbx 0xffffffff82937bb7 cpu_info_full_primary+0x2bb7 rdx 0x3fd rcx 0 rax 0x14 r8 0x101010101010101 r9 0x8080808080808080 r10 0x1cc926f12b0e2d8d r11 0x51addd96160db9d2 r12 0xffffffff829379b8 cpu_info_full_primary+0x29b8 r13 0 r14 0 r15 0x1 rip 0xffffffff81d03c18 db_enter+0x18 cs 0x8 rflags 0x246 rsp 0xffff800021282ab0 ss 0x10 db_enter+0x18: addq $0x8,%rsp ddb{0}> show proc PROC (syz-executor1463305089) pid=93424 stat=onproc flags process=2 proc=0 pri=53, usrpri=54, nice=20 forw=0xffffffffffffffff, list=0xffff8000ffff67f0,0xffffffff82a6ea48 process=0xffff8000ffff2150 user=0xffff80002127d000, vmspace=0xfffffd806ee3fe68 estcpu=4, cpticks=3, pctcpu=0.0 user=0, sys=3, intr=0 ddb{0}> ps PID TID PPID UID S FLAGS WAIT COMMAND *55503 93424 34654 0 7 0x2 syz-executor1463305089 34654 281232 94224 0 3 0x10008a sigsusp ksh 94224 139358 64810 0 3 0x9a kqread sshd 82251 10149 1 0 3 0x100083 ttyin getty 64810 333187 1 0 3 0x88 kqread sshd 84912 44922 2834 74 3 0x1100092 bpf pflogd 2834 422394 1 0 3 0x80 netio pflogd 1412 13620 82690 73 7 0x1100010 syslogd 82690 17505 1 0 3 0x100082 netio syslogd 56115 351794 1 0 3 0x100080 kqread resolvd 516 252647 28478 77 3 0x100092 kqread dhcpleased 23477 208376 28478 77 3 0x100092 kqread dhcpleased 28478 261659 1 0 3 0x80 kqread dhcpleased 28777 62707 0 0 3 0x14200 bored smr 13947 284746 0 0 3 0x14200 pgzero zerothread 13628 492808 0 0 3 0x14200 aiodoned aiodoned 57660 50271 0 0 3 0x14200 syncer update 46015 67307 0 0 3 0x14200 cleaner cleaner 47462 433480 0 0 3 0x14200 reaper reaper 98972 34367 0 0 3 0x14200 pgdaemon pagedaemon 77528 379448 0 0 3 0x14200 bored viomb 34514 428748 0 0 3 0x40014200 acpi0 acpi0 54055 72623 0 0 3 0x40014200 idle1 33274 291997 0 0 3 0x14200 bored softnet 63887 381088 0 0 3 0x14200 bored softnet 55765 31041 0 0 3 0x14200 bored softnet 9980 136450 0 0 3 0x14200 bored softnet 90335 99094 0 0 3 0x14200 bored systqmp 22033 22859 0 0 3 0x14200 bored systq 47333 234952 0 0 3 0x40014200 bored softclock 29842 398698 0 0 3 0x40014200 idle0 1 242761 0 0 3 0x82 wait init 0 0 -1 0 3 0x10200 scheduler swapper ddb{0}> show all locks Process 55503 (syz-executor1463305089) thread 0xffff8000ffff6d30 (93424) exclusive rwlock solock r = 0 (0xfffffd806d5ce818) #0 witness_lock+0x44d #1 unp_solock_peer+0x64 sys/kern/uipc_usrreq.c:168 #2 uipc_usrreq+0x7c6 sys/kern/uipc_usrreq.c:350 #3 sosend+0x61b sys/kern/uipc_socket.c:657 #4 sendit+0x65d sys/kern/uipc_syscalls.c:682 #5 sys_sendmsg+0x198 sys/kern/uipc_syscalls.c:589 #6 syscall+0x4c3 mi_syscall sys/sys/syscall_mi.h:101 [inline] #6 syscall+0x4c3 sys/arch/amd64/amd64/trap.c:585 #7 Xsyscall+0x128 Process 1412 (syslogd) thread 0xffff8000ffff7a50 (13620) exclusive rrwlock inode r = 0 (0xfffffd806e7d62c0) #0 witness_lock+0x44d #1 rw_enter+0x3e1 sys/kern/kern_rwlock.c:310 #2 rrw_enter+0x8b sys/kern/kern_rwlock.c:465 #3 VOP_LOCK+0x87 sys/kern/vfs_vops.c:518 #4 vn_lock+0x84 sys/kern/vfs_vnops.c:567 #5 sys_fsync+0xf5 sys/kern/vfs_syscalls.c:2939 #6 syscall+0x435 mi_syscall sys/sys/syscall_mi.h:101 [inline] #6 syscall+0x435 sys/arch/amd64/amd64/trap.c:585 #7 Xsyscall+0x128 ddb{0}> show malloc Type InUse MemUse HighUse Limit Requests Type Lim devbuf 10152 6455K 6456K 78643K 11242 0 pcb 13 8K 8K 78643K 13 0 rtable 58 1K 2K 78643K 108 0 ifaddr 29 8K 8K 78643K 32 0 counters 40 33K 33K 78643K 40 0 ioctlops 0 0K 4K 78643K 1479 0 mount 1 1K 1K 78643K 1 0 log 0 0K 0K 78643K 5 0 vnodes 1167 73K 73K 78643K 1180 0 UFS quota 1 32K 32K 78643K 1 0 UFS mount 5 36K 36K 78643K 5 0 shm 2 1K 1K 78643K 2 0 VM map 2 1K 1K 78643K 2 0 sem 2 0K 0K 78643K 2 0 dirhash 12 2K 2K 78643K 12 0 ACPI 1697 195K 286K 78643K 12548 0 file desc 1 0K 0K 78643K 1 0 proc 67 91K 91K 78643K 282 0 NFS srvsock 1 0K 0K 78643K 1 0 NFS daemon 1 16K 16K 78643K 1 0 in_multi 11 0K 0K 78643K 11 0 ether_multi 1 0K 0K 78643K 1 0 ISOFS mount 1 32K 32K 78643K 1 0 MSDOSFS mount 1 16K 16K 78643K 1 0 ttys 25 122K 122K 78643K 25 0 exec 0 0K 2K 78643K 432 0 tdb 3 0K 0K 78643K 3 0 pagedep 1 8K 8K 78643K 1 0 inodedep 1 32K 32K 78643K 1 0 newblk 1 0K 0K 78643K 1 0 VM swap 7 26K 26K 78643K 7 0 UVM amap 59 3K 5K 78643K 1544 0 UVM aobj 3 2K 2K 78643K 3 0 memdesc 1 4K 4K 78643K 1 0 crypto data 1 1K 1K 78643K 1 0 NDP 4 0K 0K 78643K 4 0 temp 23 4705K 4769K 78643K 2514 0 kqueue 11 16K 18K 78643K 24 0 SYN cache 2 16K 16K 78643K 2 0 ddb{0}> show all pools Name Size Requests Fail Releases Pgreq Pgrel Npage Hiwat Minpg Maxpg Idle plcache 128 22 0 0 1 0 1 1 0 8 0 rtpcb 120 17 0 14 1 0 1 1 0 8 0 rtentry 112 23 0 1 1 0 1 1 0 8 0 unpcb 144 37 0 20 1 0 1 1 0 8 0 syncache 296 5 0 5 2 1 1 1 0 8 1 tcpcb 736 8 0 5 1 0 1 1 0 8 0 arp 120 2 0 0 1 0 1 1 0 8 0 inpcb 320 32 0 26 1 0 1 1 0 8 0 pfosfp 40 1428 0 1005 5 0 5 5 0 8 0 pfosfpen 112 1428 0 714 21 0 21 21 0 8 0 pfstitem 24 9 0 0 1 0 1 1 0 8 0 pfstkey 120 9 0 0 1 0 1 1 0 8 0 pfstate 336 9 0 0 1 0 1 1 0 8 0 pfrule 1360 21 0 16 2 1 1 2 0 8 0 art_heap8 4096 1 0 0 1 0 1 1 0 8 0 art_heap4 256 96 0 0 6 0 6 6 0 8 0 art_table 32 97 0 0 1 0 1 1 0 8 0 art_node 16 22 0 2 1 0 1 1 0 8 0 dirhash 1024 17 0 0 3 0 3 3 0 8 0 dino2pl 256 1425 0 39 87 0 87 87 0 8 0 ffsino 272 1425 0 39 93 0 93 93 0 8 0 nchpl 144 1614 0 49 58 0 58 58 0 8 0 uvmvnodes 80 1435 0 0 30 0 30 30 0 8 0 vnodes 224 1435 0 0 85 0 85 85 0 8 0 namei 1024 4472 0 4472 2 1 1 1 0 8 1 percpumem 16 32 0 0 1 0 1 1 0 8 0 kstatmem 264 8 0 0 1 0 1 1 0 8 0 scxspl 216 3956 0 3956 2 1 1 2 0 8 1 plimitpl 152 16 0 9 1 0 1 1 0 8 0 sigapl 424 323 0 291 5 1 4 5 0 8 0 knotepl 120 50 0 0 2 0 2 2 0 8 0 kqueuepl 224 20 0 13 1 0 1 1 0 8 0 pipepl 336 86 0 83 2 1 1 1 0 8 0 fdescpl 496 306 0 291 3 0 3 3 0 8 0 filepl 152 1184 0 1124 3 0 3 3 0 8 0 lockfpl 104 6 0 4 1 0 1 1 0 8 0 lockfspl 48 4 0 2 1 0 1 1 0 8 0 sessionpl 144 18 0 9 1 0 1 1 0 8 0 pgrppl 48 18 0 9 1 0 1 1 0 8 0 ucredpl 104 69 0 57 1 0 1 1 0 8 0 zombiepl 144 291 0 291 2 1 1 1 0 8 1 processpl 1064 323 0 291 3 0 3 3 0 8 0 procpl 672 323 0 291 4 1 3 4 0 8 0 sockpl 504 86 0 60 5 1 4 4 0 8 0 mcl8k 8192 6 0 0 1 0 1 1 0 8 0 mcl4k 4096 5 0 0 1 0 1 1 0 8 0 mcl2k 2048 77 0 0 10 0 10 10 0 8 0 mtagpl 96 2 0 0 1 0 1 1 0 8 0 mbufpl 256 122 0 0 8 0 8 8 0 8 0 bufpl 288 2011 0 92 138 0 138 138 0 8 0 anonpl 24 38655 0 36663 16 2 14 15 0 186 2 amapchunkpl 152 2837 0 2741 6 2 4 6 0 158 0 amappl16 200 99 0 93 1 0 1 1 0 8 0 amappl13 176 34 0 33 2 1 1 1 0 8 0 amappl12 168 3 0 3 2 1 1 1 0 8 1 amappl11 160 66 0 49 1 0 1 1 0 8 0 amappl10 152 3 0 1 1 0 1 1 0 8 0 amappl9 144 486 0 484 1 0 1 1 0 8 0 amappl8 136 364 0 363 2 1 1 1 0 8 0 amappl7 128 52 0 49 1 0 1 1 0 8 0 amappl6 120 105 0 95 1 0 1 1 0 8 0 amappl5 112 91 0 78 1 0 1 1 0 8 0 amappl4 104 628 0 607 1 0 1 1 0 8 0 amappl3 96 386 0 363 1 0 1 1 0 8 0 amappl2 88 454 0 416 1 0 1 1 0 8 0 amappl1 80 9436 0 9029 11 1 10 10 0 8 1 amappl 88 1262 0 1222 2 0 2 2 0 92 0 dma4096 4096 1 0 1 1 1 0 1 0 8 0 dma1024 1024 1 0 0 1 0 1 1 0 8 0 dma256 256 6 0 6 1 1 0 1 0 8 0 dma128 128 253 0 253 1 1 0 1 0 8 0 dma64 64 6 0 6 1 1 0 1 0 8 0 dma32 32 7 0 7 1 1 0 1 0 8 0 dma16 16 18 0 17 1 0 1 1 0 8 0 aobjpl 72 2 0 0 1 0 1 1 0 8 0 uaddrrnd 24 306 0 291 1 0 1 1 0 8 0 uaddrbest 32 2 0 0 1 0 1 1 0 8 0 uaddr 24 306 0 291 1 0 1 1 0 8 0 vmmpekpl 168 6313 0 6301 1 0 1 1 0 8 0 vmmpepl 168 27005 0 26146 43 1 42 42 0 357 3 vmsppl 368 305 0 291 2 0 2 2 0 8 0 rwobjpl 56 9671 0 7664 30 0 30 30 0 8 0 pdppl 4096 619 0 582 48 11 37 45 0 8 0 pvpl 32 127028 0 123065 37 1 36 36 0 265 3 pmappl 248 305 0 291 2 0 2 2 0 8 0 extentpl 40 58 0 38 1 0 1 1 0 8 0 phpool 112 414 0 17 12 0 12 12 0 8 0 ddb{0}> machine ddbcpu 0 Invalid cpu 0 ddb{0}> trace db_enter() at db_enter+0x18 sys/arch/amd64/amd64/db_interface.c:437 panic(ffffffff82593e80) at panic+0x177 sys/kern/subr_prf.c:202 witness_warn(2,0,ffffffff82620d13) at witness_warn+0x65e witness_debugger sys/kern/subr_witness.c:2505 [inline] witness_warn(2,0,ffffffff82620d13) at witness_warn+0x65e sys/kern/subr_witness.c:1473 userret(ffff8000ffff6d30) at userret+0x265 sys/kern/kern_sig.c:2012 syscall(ffff800021282d90) at syscall+0x57e mi_syscall_return sys/sys/syscall_mi.h:128 [inline] syscall(ffff800021282d90) at syscall+0x57e sys/arch/amd64/amd64/trap.c:607 Xsyscall() at Xsyscall+0x128 end of kernel end trace frame: 0x7f7ffffe9830, count: -6 ddb{0}> machine ddbcpu 1 Stopped at x86_ipi_db+0x1a: addq $0x8,%rsp x86_ipi_db(ffff800020dd8ff0) at x86_ipi_db+0x1a sys/arch/amd64/amd64/db_interface.c:393 x86_ipi_handler() at x86_ipi_handler+0xb7 sys/arch/amd64/amd64/ipi.c:106 Xresume_lapic_ipi() at Xresume_lapic_ipi+0x23 __mp_lock(ffffffff82a57c00) at __mp_lock+0x133 __mp_lock_spin sys/kern/kern_lock.c:116 [inline] __mp_lock(ffffffff82a57c00) at __mp_lock+0x133 sys/kern/kern_lock.c:147 __mp_acquire_count(ffffffff82a57c00,1) at __mp_acquire_count+0x48 sys/kern/kern_lock.c:227 mi_switch() at mi_switch+0x3bb sys/kern/sched_bsd.c:416 sleep_finish(ffff8000212b8170,1) at sleep_finish+0x180 sys/kern/kern_synch.c:420 tsleep(fffffd806e988370,11,ffffffff825b249b,0) at tsleep+0x12c sys/kern/kern_synch.c:158 vwaitforio(fffffd806e9882f0,0,ffffffff825b249b,ffffffffffffffff) at vwaitforio+0x98 sys/kern/vfs_subr.c:1897 ffs_fsync(ffff8000212b82c0) at ffs_fsync+0x38e sys/ufs/ffs/ffs_vnops.c:495 VOP_FSYNC(fffffd806e9882f0,fffffd807f7d7a90,1,ffff8000ffff7a50) at VOP_FSYNC+0xce sys/kern/vfs_vops.c:311 sys_fsync(ffff8000ffff7a50,ffff8000212b83b0,ffff8000212b8400) at sys_fsync+0x109 sys/kern/vfs_syscalls.c:2939 syscall(ffff8000212b8480) at syscall+0x435 mi_syscall sys/sys/syscall_mi.h:101 [inline] syscall(ffff8000212b8480) at syscall+0x435 sys/arch/amd64/amd64/trap.c:585 Xsyscall() at Xsyscall+0x128 end of kernel end trace frame: 0x7f7ffffe50d0, count: 1 ddb{1}> trace x86_ipi_db(ffff800020dd8ff0) at x86_ipi_db+0x1a sys/arch/amd64/amd64/db_interface.c:393 x86_ipi_handler() at x86_ipi_handler+0xb7 sys/arch/amd64/amd64/ipi.c:106 Xresume_lapic_ipi() at Xresume_lapic_ipi+0x23 __mp_lock(ffffffff82a57c00) at __mp_lock+0x133 __mp_lock_spin sys/kern/kern_lock.c:116 [inline] __mp_lock(ffffffff82a57c00) at __mp_lock+0x133 sys/kern/kern_lock.c:147 __mp_acquire_count(ffffffff82a57c00,1) at __mp_acquire_count+0x48 sys/kern/kern_lock.c:227 mi_switch() at mi_switch+0x3bb sys/kern/sched_bsd.c:416 sleep_finish(ffff8000212b8170,1) at sleep_finish+0x180 sys/kern/kern_synch.c:420 tsleep(fffffd806e988370,11,ffffffff825b249b,0) at tsleep+0x12c sys/kern/kern_synch.c:158 vwaitforio(fffffd806e9882f0,0,ffffffff825b249b,ffffffffffffffff) at vwaitforio+0x98 sys/kern/vfs_subr.c:1897 ffs_fsync(ffff8000212b82c0) at ffs_fsync+0x38e sys/ufs/ffs/ffs_vnops.c:495 VOP_FSYNC(fffffd806e9882f0,fffffd807f7d7a90,1,ffff8000ffff7a50) at VOP_FSYNC+0xce sys/kern/vfs_vops.c:311 sys_fsync(ffff8000ffff7a50,ffff8000212b83b0,ffff8000212b8400) at sys_fsync+0x109 sys/kern/vfs_syscalls.c:2939 syscall(ffff8000212b8480) at syscall+0x435 mi_syscall sys/sys/syscall_mi.h:101 [inline] syscall(ffff8000212b8480) at syscall+0x435 sys/arch/amd64/amd64/trap.c:585 Xsyscall() at Xsyscall+0x128 end of kernel end trace frame: 0x7f7ffffe50d0, count: -14 ddb{1}>