witness: lock order reversal: 1st 0xffffffff83915588 &sched_lock (&sched_lock) 2nd 0xffffffff8389de30 &cq->cq_mtx (&cq->cq_mtx) lock order [1] &sched_lock (&sched_lock) -> [2] &cq->cq_mtx (&cq->cq_mtx) #0 mtx_enter+0x95 sys/kern/kern_lock.c:406 #1 clockintr_cancel+0x2f sys/kern/kern_clockintr.c:323 #2 mi_switch+0x187 sys/kern/sched_bsd.c:376 #3 sleep_finish+0x2d8 sys/kern/kern_synch.c:369 #4 sys_futex+0xb90 futex_wait sys/kern/sys_futex.c:306 [inline] #4 sys_futex+0xb90 sys/kern/sys_futex.c:155 #5 syscall+0xbd4 mi_syscall sys/sys/syscall_mi.h:176 [inline] #5 syscall+0xbd4 sys/arch/amd64/amd64/trap.c:775 #6 Xsyscall+0x128 lock order [2] &cq->cq_mtx (&cq->cq_mtx) -> [1] &sched_lock (&sched_lock) #0 mtx_enter+0x95 sys/kern/kern_lock.c:406 #1 sleep_setup+0xed sys/kern/kern_synch.c:304 #2 msleep_nsec+0x10b sys/kern/kern_synch.c:217 #3 dt_ioctl_record_stop+0xbc sys/dev/dt/dt_dev.c:589 #4 dtclose+0x109 dt_pcb_purge sys/dev/dt/dt_dev.c:-1 [inline] #4 dtclose+0x109 sys/dev/dt/dt_dev.c:239 #5 spec_close+0x466 sys/kern/spec_vnops.c:-1 #6 VOP_CLOSE+0x132 sys/kern/vfs_vops.c:156 #7 vn_closefile+0x12b vn_close sys/kern/vfs_vnops.c:292 [inline] #7 vn_closefile+0x12b sys/kern/vfs_vnops.c:615 #8 fdrop+0x121 sys/kern/kern_descrip.c:1280 #9 closef+0x192 sys/kern/kern_descrip.c:1264 #10 fdfree+0x116 sys/kern/kern_descrip.c:1195 #11 exit1+0x576 sys/kern/kern_exit.c:215 #12 sys_exit+0x1a sys/kern/kern_exit.c:-1 #13 syscall+0xb17 mi_syscall sys/sys/syscall_mi.h:176 [inline] #13 syscall+0xb17 sys/arch/amd64/amd64/trap.c:775 #14 Xsyscall+0x128 Stopped at db_enter+0x25: addq $0x8,%rsp ddb{0}> ddb{0}> set $lines = 0 ddb{0}> set $maxwidth = 0 ddb{0}> show panic the kernel did not panic ddb{0}> trace db_enter() at db_enter+0x25 sys/arch/amd64/amd64/db_interface.c:438 witness_checkorder(ffffffff8389de30,9,0) at witness_checkorder+0x10d1 sys/kern/subr_witness.c:-1 mtx_enter(ffffffff8389de20) at mtx_enter+0x95 sys/kern/kern_lock.c:406 clockintr_cancel(ffffffff8389d2f8) at clockintr_cancel+0x2f sys/kern/kern_clockintr.c:323 mi_switch() at mi_switch+0x187 sys/kern/sched_bsd.c:376 sleep_finish(ffffffffffffffff,1) at sleep_finish+0x2d8 sys/kern/kern_synch.c:369 sys_futex(ffff8000fffeb248,ffff80002b3d2aa0,ffff80002b3d29f0) at sys_futex+0xb90 futex_wait sys/kern/sys_futex.c:306 [inline] sys_futex(ffff8000fffeb248,ffff80002b3d2aa0,ffff80002b3d29f0) at sys_futex+0xb90 sys/kern/sys_futex.c:155 syscall(ffff80002b3d2aa0) at syscall+0xbd4 mi_syscall sys/sys/syscall_mi.h:176 [inline] syscall(ffff80002b3d2aa0) at syscall+0xbd4 sys/arch/amd64/amd64/trap.c:775 Xsyscall() at Xsyscall+0x128 end of kernel end trace frame: 0x402135c0980, count: -9 ddb{0}> show registers rdi 0 rsi 0x80000 acpi_pdirpa+0x6be71 rbp 0xffff80002b3d2670 rbx 0 rdx 0xffff80000155de00 rcx 0xffff8000fffeb248 rax 0x7ffff acpi_pdirpa+0x6be70 r8 0xffff80002b3d2550 r9 0x8080808080808080 r10 0x9548b5e04d33b45d r11 0xf6721b1b0cb00146 r12 0xfffffd8003f4a300 r13 0xfffffd80046f7330 r14 0x3 r15 0xffffffff8341ed46 substchar+0xb9ee rip 0xffffffff81fbe8e5 db_enter+0x25 cs 0x8 rflags 0x246 rsp 0xffff80002b3d2660 ss 0x10 db_enter+0x25: addq $0x8,%rsp ddb{0}> show proc PROC (syz-executor) tid=280477 pid=80620 tcnt=2 stat=sleep flags process=4000010 proc=4000080 runpri=50, usrpri=50, slppri=32, nice=20 wchan=0xffff80002b3d2910, wmesg=fsleep, ps_single=0x0 scnt=0 ecnt=0 forw=0x0, list=0xffff8000fffeb4e0,0xffffffff83911d78 process=0xffff80003b7ec9c0 user=0xffff80002b3cd000, vmspace=0xfffffd800ebe7020 estcpu=36, cpticks=0, pctcpu=0.0, user=0, sys=0, intr=0 ddb{0}> ps PID TID PPID UID S FLAGS WAIT COMMAND 80620 513965 52620 0 7 0x4000010 syz-executor *80620 280477 52620 0 3 0x4000090 fsleep syz-executor 42708 94535 98040 0 3 0x80 fsleep syz-executor 42708 120491 98040 0 3 0x4000080 rest syz-executor 79670 216936 48160 0 3 0x80 fsleep syz-executor 79670 463363 48160 0 3 0x4000080 ttyout syz-executor 88387 452184 37366 0 3 0x80 fsleep syz-executor 88387 212077 37366 0 3 0x4000080 fsleep syz-executor 88387 1794 37366 0 3 0x4000080 fsleep syz-executor 88387 515210 37366 0 3 0x4000080 ttyout syz-executor 74130 513295 58335 0 3 0x80 fsleep syz-executor 74130 201959 58335 0 3 0x4000080 kqread syz-executor 11312 168530 40209 0 3 0x80 fsleep syz-executor 11312 242521 40209 0 3 0x4000080 sbwait syz-executor 40209 354012 28845 0 3 0x82 nanoslp syz-executor 48160 35965 28845 0 3 0x82 nanoslp syz-executor 52015 262936 28845 0 3 0x82 wait syz-executor 76475 383471 1 0 3 0x100083 ttyopn getty 37366 451889 28845 0 3 0x82 nanoslp syz-executor 83895 198471 74920 0 3 0x100082 sbwait arp 74920 214030 51948 0 3 0x10008a sigsusp sh 51948 106744 28845 0 3 0x82 wait syz-executor 98040 419252 28845 0 3 0x82 nanoslp syz-executor 58335 200349 28845 0 3 0x82 nanoslp syz-executor 52620 34734 28845 0 3 0x82 nanoslp syz-executor 28845 120281 90005 0 3 0x82 kqread syz-executor 90005 472280 78929 0 3 0x10008a sigsusp ksh 78929 497688 34658 0 3 0x98 kqread sshd-session 34658 405603 26983 0 3 0x92 kqread sshd-session 26983 363779 1 0 3 0x88 kqread sshd 46797 276912 1054 74 3 0x1100092 bpf pflogd 1054 460856 1 0 3 0x80 sbwait pflogd 26187 444308 58426 73 3 0x1100090 kqread syslogd 58426 2095 1 0 3 0x100082 sbwait syslogd 9863 414665 1 0 3 0x100080 kqread resolvd 90738 451734 62279 77 3 0x100092 kqread dhcpleased 41693 273356 62279 77 3 0x100092 kqread dhcpleased 62279 475902 1 0 3 0x80 kqread dhcpleased 87471 25224 0 0 3 0x14200 bored smr 11549 77390 0 0 3 0x14200 pgzero zerothread 75365 235743 0 0 3 0x14200 aiodoned aiodoned 5112 487742 0 0 3 0x14200 syncer update 34456 62702 0 0 3 0x14200 cleaner cleaner 51757 463914 0 0 3 0x14200 reaper reaper 47041 315118 0 0 3 0x14200 pgdaemon pagedaemon 25825 300547 0 0 3 0x14200 bored viomb 79793 85095 0 0 3 0x40014200 acpi0 acpi0 33560 146176 0 0 3 0x40014200 idle1 41304 138637 0 0 3 0x14200 bored softnet1 7338 521511 0 0 3 0x14200 bored softnet0 9104 213180 0 0 3 0x14200 bored systqmp 87637 223660 0 0 3 0x14200 bored systq 40308 165874 0 0 3 0x14200 tmoslp softclockmp 46340 255432 0 0 3 0x40014200 tmoslp softclock 58608 441565 0 0 3 0x40014200 idle0 1 83863 0 0 3 0x82 wait init 0 0 -1 0 3 0x10010200 scheduler swapper ddb{0}> show all locks CPU 0: exclusive mutex &sched_lock r = 0 (0xffffffff83915588) #0 witness_lock+0x5f1 stacktrace_save sys/sys/stacktrace.h:37 [inline] #0 witness_lock+0x5f1 sys/kern/subr_witness.c:1160 #1 mtx_enter+0x4b4 sys/kern/kern_lock.c:487 #2 sleep_finish+0x1d6 sys/kern/kern_synch.c:357 #3 sys_futex+0xb90 futex_wait sys/kern/sys_futex.c:306 [inline] #3 sys_futex+0xb90 sys/kern/sys_futex.c:155 #4 syscall+0xbd4 mi_syscall sys/sys/syscall_mi.h:176 [inline] #4 syscall+0xbd4 sys/arch/amd64/amd64/trap.c:775 #5 Xsyscall+0x128 Process 80620 (syz-executor) thread 0xffff8000fffeb4e0 (513965) exclusive rwlock futexlk r = 0 (0xffffffff83921c68) #0 witness_lock+0x5f1 stacktrace_save sys/sys/stacktrace.h:37 [inline] #0 witness_lock+0x5f1 sys/kern/subr_witness.c:1160 #1 rw_do_enter_write+0x419 sys/kern/kern_rwlock.c:320 #2 futex_wake+0xbc sys/kern/sys_futex.c:467 #3 sys_futex+0x1d9 sys/kern/sys_futex.c:158 #4 syscall+0xbd4 mi_syscall sys/sys/syscall_mi.h:176 [inline] #4 syscall+0xbd4 sys/arch/amd64/amd64/trap.c:775 #5 Xsyscall+0x128 ddb{0}> show malloc Type InUse MemUse HighUse Limit Requests Type Lim devbuf 11098 12106K 12346K 166960K 13781 0 pcb 18 16K 17K 166960K 428 0 rtable 227 11K 12K 166960K 670 0 pf 33 17K 19K 166960K 198 0 ifaddr 40 7K 8K 166960K 147 0 ifgroup 52 2K 2K 166960K 256 0 sysctl 4 1K 9K 166960K 14 0 counters 70 37K 38K 166960K 358 0 ioctlops 0 0K 8K 166960K 1822 0 iov 1 4K 20K 166960K 83 0 mount 1 1K 1K 166960K 1 0 log 0 0K 0K 166960K 4 0 vnodes 1390 87K 88K 166960K 2502 0 UFS quota 1 32K 32K 166960K 1 0 UFS mount 5 36K 36K 166960K 5 0 shm 2 1K 5K 166960K 31 0 VM map 2 1K 1K 166960K 2 0 sem 12 0K 0K 166960K 49 0 dirhash 12 2K 2K 166960K 33 0 ACPI 1692 195K 286K 166960K 12470 0 file desc 19 69K 244K 166960K 1486 0 sigio 0 0K 0K 166960K 9 0 proc 72 115K 164K 166960K 790 0 subproc 72 4K 4K 166960K 108 0 NFS srvsock 1 0K 0K 166960K 1 0 NFS daemon 1 16K 16K 166960K 1 0 ip_moptions 0 0K 0K 166960K 303 0 in_multi 78 5K 6K 166960K 200 0 ether_multi 1 0K 0K 166960K 15 0 mrt 1 0K 0K 166960K 16 0 ISOFS mount 1 32K 32K 166960K 1 0 MSDOSFS mount 1 16K 16K 166960K 1 0 ttys 247 1102K 1102K 166960K 247 0 exec 0 0K 1K 166960K 716 0 fusefs mount 1 32K 32K 166960K 1 0 pfkey data 0 0K 0K 166960K 2 0 tdb 3 0K 0K 166960K 3 0 VM swap 8 62K 64K 166960K 10 0 UVM amap 257 154K 176K 166960K 15066 0 UVM aobj 38 17K 17K 166960K 43 0 pinsyscall 45 90K 106K 166960K 2689 0 memdesc 1 4K 4K 166960K 1 0 crypto data 1 1K 1K 166960K 1 0 ip6_options 0 0K 1K 166960K 133 0 NDP 12 0K 1K 166960K 108 0 temp 82 8680K 46309K 166960K 63094 0 kqueue 14 22K 30K 166960K 243 0 SYN cache 2 16K 16K 166960K 2 0 ddb{0}> show all pools Name Size Requests Fail Releases Pgreq Pgrel Npage Hiwat Minpg Maxpg Idle plcache 128 26 0 0 1 0 1 1 0 8 0 rtpcb 120 178 0 174 2 1 1 2 0 8 0 rtentry 176 193 0 105 5 0 5 5 0 8 0 unpcb 144 973 0 954 9 7 2 6 0 8 1 syncache 336 13 0 13 4 3 1 1 0 8 1 tcpqe 32 19 0 19 2 2 0 1 0 8 0 tcpcb 736 469 0 462 12 5 7 7 0 8 6 arp 136 34 0 18 1 0 1 1 0 8 0 inpcb 328 1702 0 1690 13 6 7 7 0 8 6 nd6 152 38 0 18 1 0 1 1 0 8 0 pkpcb 40 8 0 8 3 3 0 1 0 8 0 kcovpl 48 12 0 4 1 0 1 1 0 8 0 mppekey 1024 1 0 1 1 1 0 1 0 8 0 ppxss 1192 123 0 123 1 0 1 1 0 8 1 pppxif 1504 5 0 5 4 4 0 1 0 8 0 pffrag 232 17 0 6 1 0 1 1 0 482 0 pffrnode 88 12 0 3 1 0 1 1 0 8 0 pffrent 40 30 0 19 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 rttmr 136 3 0 3 3 2 1 1 0 8 1 art_heap8 4096 4 0 1 4 1 3 4 0 8 0 art_heap4 256 821 0 444 31 2 29 29 0 8 2 art_table 40 825 0 445 5 0 5 5 0 8 0 art_node 32 193 0 116 1 0 1 1 0 8 0 sysvmsgpl 40 23 0 9 2 1 1 1 0 8 0 semupl 112 1 0 1 1 1 0 1 0 8 0 semapl 112 43 0 33 1 0 1 1 0 8 0 shmpl 112 33 0 5 1 0 1 1 0 8 0 dirhash 1024 31 0 14 3 0 3 3 0 8 0 dino2pl 256 4246 0 2746 95 0 95 95 0 8 0 ffsino 296 4246 0 2746 117 0 117 117 0 8 0 nchpl 144 6181 0 4467 65 1 64 65 0 8 0 rtmask 32 27 0 27 1 0 1 1 0 8 1 vnodes 216 4772 0 0 266 0 266 266 0 8 0 namei 1024 21426 0 21426 2 1 1 2 0 8 1 percpumem 16 194 0 144 1 0 1 1 0 8 0 vcpupl 3968 7 0 0 1 0 1 1 0 8 0 vmpool 848 7 0 0 1 0 1 1 0 8 0 kstatmem 264 174 0 146 3 0 3 3 0 8 0 scsiplug 72 6 0 6 3 2 1 1 0 8 1 scxspl 216 48256 0 48256 10 9 1 8 1 8 1 plimitpl 152 414 0 395 1 0 1 1 0 8 0 sigapl 424 1740 0 1691 7 0 7 7 0 8 0 knotepl 120 570 0 0 17 0 17 17 0 8 0 kqueuepl 224 531 0 520 7 5 2 5 0 8 1 pipepl 344 227 0 200 3 0 3 3 0 8 0 fdescpl 528 1720 0 1687 3 0 3 3 0 8 0 filepl 160 11417 0 11189 17 4 13 17 0 8 1 lockfpl 104 446 0 444 1 0 1 1 0 8 0 lockfspl 48 187 0 185 1 0 1 1 0 8 0 sessionpl 144 35 0 26 1 0 1 1 0 8 0 pgrppl 48 67 0 50 1 0 1 1 0 8 0 ucredpl 104 2081 0 2067 1 0 1 1 0 8 0 zombiepl 144 1692 0 1691 1 0 1 1 0 8 0 processpl 1232 1740 0 1691 5 0 5 5 0 8 0 procpl 664 3956 0 3899 6 0 6 6 0 8 0 sosppl 176 9 0 9 2 2 0 1 0 8 0 sockpl 752 2907 0 2872 24 13 11 17 0 8 7 mcl64k 65536 11 0 0 2 0 2 2 0 8 0 mcl16k 16384 1 0 0 1 0 1 1 0 8 0 mcl8k 8192 6 0 0 1 0 1 1 0 8 0 mcl4k 4096 122 0 0 16 1 15 16 0 8 0 mcl2k 2048 48 0 0 5 0 5 5 0 8 0 mtagpl 96 5 0 0 1 0 1 1 0 8 0 mbufpl 256 264 0 0 15 0 15 15 0 8 0 bufpl 280 21159 0 15023 439 0 439 439 0 8 0 anonpl 32 13511 0 0 109 0 109 109 0 246 0 amapchunkpl 152 50937 0 50373 38 8 30 33 0 158 2 amappl16 200 7991 0 7898 49 31 18 29 0 8 4 amappl15 192 82 0 82 1 1 0 1 0 8 0 amappl14 184 8 0 7 1 0 1 1 0 8 0 amappl13 176 518 0 516 1 0 1 1 0 8 0 amappl12 168 2124 0 2078 3 0 3 3 0 8 0 amappl11 160 7 0 6 1 0 1 1 0 8 0 amappl10 152 49 0 35 1 0 1 1 0 8 0 amappl9 144 273 0 273 1 1 0 1 0 8 0 amappl8 136 48 0 45 1 0 1 1 0 8 0 amappl7 128 95 0 93 1 0 1 1 0 8 0 amappl6 120 332 0 316 1 0 1 1 0 8 0 amappl5 112 89 0 78 1 0 1 1 0 8 0 amappl4 104 493 0 460 1 0 1 1 0 8 0 amappl3 96 8794 0 8700 4 1 3 3 0 8 0 amappl2 88 1876 0 1798 2 0 2 2 0 8 0 amappl1 80 16713 0 16088 15 0 15 15 0 8 0 amappl 88 14006 0 13831 5 0 5 5 0 92 0 uvmvnodes 80 151 0 0 4 0 4 4 0 8 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 255 0 255 2 2 0 1 0 8 0 dma64 64 8 0 8 2 2 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 42 0 5 1 0 1 1 0 8 0 uaddrrnd 24 1720 0 1687 1 0 1 1 0 8 0 uaddrbest 32 2 0 0 1 0 1 1 0 8 0 uaddr 24 1720 0 1687 1 0 1 1 0 8 0 vmmpekpl 168 15135 0 15089 3 0 3 3 0 8 0 vmmpepl 168 119132 0 117066 117 8 109 112 0 357 7 vmsppl 488 1719 0 1687 5 0 5 5 0 8 0 rwobjpl 80 35450 0 34220 37 2 35 35 0 8 1 pdppl 4096 3461 0 3381 116 34 82 87 0 8 2 pvpl 32 22835 0 0 184 0 184 184 0 265 0 pmappl 256 1726 0 1687 3 0 3 3 0 8 0 extentpl 40 45 0 27 1 0 1 1 0 8 0 phpool 112 314 0 50 8 0 8 8 0 8 0 ddb{0}> machine ddbcpu 0 Invalid cpu 0 ddb{0}> trace db_enter() at db_enter+0x25 sys/arch/amd64/amd64/db_interface.c:438 witness_checkorder(ffffffff8389de30,9,0) at witness_checkorder+0x10d1 sys/kern/subr_witness.c:-1 mtx_enter(ffffffff8389de20) at mtx_enter+0x95 sys/kern/kern_lock.c:406 clockintr_cancel(ffffffff8389d2f8) at clockintr_cancel+0x2f sys/kern/kern_clockintr.c:323 mi_switch() at mi_switch+0x187 sys/kern/sched_bsd.c:376 sleep_finish(ffffffffffffffff,1) at sleep_finish+0x2d8 sys/kern/kern_synch.c:369 sys_futex(ffff8000fffeb248,ffff80002b3d2aa0,ffff80002b3d29f0) at sys_futex+0xb90 futex_wait sys/kern/sys_futex.c:306 [inline] sys_futex(ffff8000fffeb248,ffff80002b3d2aa0,ffff80002b3d29f0) at sys_futex+0xb90 sys/kern/sys_futex.c:155 syscall(ffff80002b3d2aa0) at syscall+0xbd4 mi_syscall sys/sys/syscall_mi.h:176 [inline] syscall(ffff80002b3d2aa0) at syscall+0xbd4 sys/arch/amd64/amd64/trap.c:775 Xsyscall() at Xsyscall+0x128 end of kernel end trace frame: 0x402135c0980, count: -9 ddb{0}> machine ddbcpu 1 Stopped at x86_ipi_db+0x27: addq $0x8,%rsp ddb{1}> trace x86_ipi_db(ffff8000299ddff0) at x86_ipi_db+0x27 sys/arch/amd64/amd64/db_interface.c:394 x86_ipi_handler() at x86_ipi_handler+0xd9 sys/arch/amd64/amd64/ipi.c:106 Xresume_lapic_ipi() at Xresume_lapic_ipi+0x27 mtx_enter(ffffffff83915578) at mtx_enter+0x377 sys/kern/kern_lock.c:454 futex_wake(ffff8000fffeb4e0,40200277de0,7fffffff,80,ffff80002a38e730) at futex_wake+0x245 futex_list_wakeup sys/kern/sys_futex.c:345 [inline] futex_wake(ffff8000fffeb4e0,40200277de0,7fffffff,80,ffff80002a38e730) at futex_wake+0x245 sys/kern/sys_futex.c:482 sys_futex(ffff8000fffeb4e0,ffff80002a38e7e0,ffff80002a38e730) at sys_futex+0x1d9 sys/kern/sys_futex.c:158 syscall(ffff80002a38e7e0) at syscall+0xbd4 mi_syscall sys/sys/syscall_mi.h:176 [inline] syscall(ffff80002a38e7e0) at syscall+0xbd4 sys/arch/amd64/amd64/trap.c:775 Xsyscall() at Xsyscall+0x128 end of kernel end trace frame: 0x7c413c2e69f0, count: -8