login: panic: acquiring blockable sleep lock with spinlock or critical section held (kernel_lock) &kernel_lock Stopped at db_enter+0x18: addq $0x8,%rsp TID PID UID PRFLAGS PFLAGS CPU COMMAND 260131 99016 0 0x2 0x4000000 0 syz-fuzzer *419967 97320 0 0x14000 0x200 1 systqmp db_enter() at db_enter+0x18 sys/arch/amd64/amd64/db_interface.c:398 panic(ffffffff8240e307) at panic+0x15c sys/kern/subr_prf.c:207 witness_checkorder(ffffffff828f8078,9,0) at witness_checkorder+0x10e0 sys/kern/subr_witness.c:821 __mp_lock(ffffffff828f7e70) at __mp_lock+0xa1 read_rflags machine/cpufunc.h:195 [inline] __mp_lock(ffffffff828f7e70) at __mp_lock+0xa1 intr_disable machine/cpufunc.h:216 [inline] __mp_lock(ffffffff828f7e70) at __mp_lock+0xa1 sys/kern/kern_lock.c:142 pageflttrap(ffff800020da3040,0) at pageflttrap+0x7f sys/arch/amd64/amd64/trap.c:180 kerntrap(ffff800020da3040) at kerntrap+0xec sys/arch/amd64/amd64/trap.c:302 alltraps_kern_meltdown() at alltraps_kern_meltdown+0x7b pool_cache_gc(ffffffff8290b140) at pool_cache_gc+0x138 pool_cache_list_put sys/kern/subr_pool.c:1970 [inline] pool_cache_gc(ffffffff8290b140) at pool_cache_gc+0x138 sys/kern/subr_pool.c:2025 pool_gc_pages(0) at pool_gc_pages+0x6c sys/kern/subr_pool.c:1559 taskq_thread(ffffffff826f6600) at taskq_thread+0xec sys/kern/kern_task.c:437 end trace frame: 0x0, count: 5 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{1}> ddb{1}> set $lines = 0 ddb{1}> set $maxwidth = 0 ddb{1}> show panic acquiring blockable sleep lock with spinlock or critical section held (kernel_lock) &kernel_lock ddb{1}> trace db_enter() at db_enter+0x18 sys/arch/amd64/amd64/db_interface.c:398 panic(ffffffff8240e307) at panic+0x15c sys/kern/subr_prf.c:207 witness_checkorder(ffffffff828f8078,9,0) at witness_checkorder+0x10e0 sys/kern/subr_witness.c:821 __mp_lock(ffffffff828f7e70) at __mp_lock+0xa1 read_rflags machine/cpufunc.h:195 [inline] __mp_lock(ffffffff828f7e70) at __mp_lock+0xa1 intr_disable machine/cpufunc.h:216 [inline] __mp_lock(ffffffff828f7e70) at __mp_lock+0xa1 sys/kern/kern_lock.c:142 pageflttrap(ffff800020da3040,0) at pageflttrap+0x7f sys/arch/amd64/amd64/trap.c:180 kerntrap(ffff800020da3040) at kerntrap+0xec sys/arch/amd64/amd64/trap.c:302 alltraps_kern_meltdown() at alltraps_kern_meltdown+0x7b pool_cache_gc(ffffffff8290b140) at pool_cache_gc+0x138 pool_cache_list_put sys/kern/subr_pool.c:1970 [inline] pool_cache_gc(ffffffff8290b140) at pool_cache_gc+0x138 sys/kern/subr_pool.c:2025 pool_gc_pages(0) at pool_gc_pages+0x6c sys/kern/subr_pool.c:1559 taskq_thread(ffffffff826f6600) at taskq_thread+0xec sys/kern/kern_task.c:437 end trace frame: 0x0, count: -10 ddb{1}> show registers rdi 0 rsi 0x1 rbp 0xffff800020da2db0 rbx 0xffff800020da2e60 rdx 0x8b rcx 0x2 rax 0x1 r8 0xffffffff81443d9f kprintf+0x16f r9 0x1 r10 0x2 r11 0xd251556aac89b2b6 r12 0x3000000008 r13 0xffff800020da2dc0 r14 0x100 r15 0x1 rip 0xffffffff81a17dc8 db_enter+0x18 cs 0x8 rflags 0x246 rsp 0xffff800020da2da0 ss 0 db_enter+0x18: addq $0x8,%rsp ddb{1}> show proc PROC (systqmp) pid=419967 stat=onproc flags process=14000 proc=200 pri=32, usrpri=50, nice=20 forw=0xffffffffffffffff, list=0xffff800020d889c0,0xffff800020d88280 process=0xffff800020d8ab88 user=0xffff800020d9e000, vmspace=0xffffffff8291d2d8 estcpu=0, cpticks=1, pctcpu=0.0 user=0, sys=1, intr=0 ddb{1}> ps PID TID PPID UID S FLAGS WAIT COMMAND 51345 508855 99016 0 3 0x82 piperd syz-executor.0 36784 253596 0 0 3 0x14280 nfsidl nfsio 8855 205587 0 0 3 0x14280 nfsidl nfsio 47648 315017 0 0 3 0x14280 nfsidl nfsio 7058 204693 0 0 3 0x14280 nfsidl nfsio 37236 361638 0 0 3 0x14280 nfsidl nfsio 37897 83691 0 0 3 0x14280 nfsidl nfsio 84889 291798 0 0 3 0x14280 nfsidl nfsio 46149 122357 0 0 3 0x14280 nfsidl nfsio 69702 362157 0 0 3 0x14280 nfsidl nfsio 14506 503421 0 0 3 0x14280 nfsidl nfsio 47446 384932 0 0 3 0x14280 nfsidl nfsio 15476 97645 0 0 3 0x14280 nfsidl nfsio 38539 134381 0 0 3 0x14280 nfsidl nfsio 27566 337552 0 0 3 0x14280 nfsidl nfsio 64651 150501 0 0 3 0x14280 nfsidl nfsio 98878 142838 0 0 3 0x14280 nfsidl nfsio 75778 213568 0 0 3 0x14280 nfsidl nfsio 96084 350321 0 0 3 0x14280 nfsidl nfsio 8550 350451 0 0 3 0x14280 nfsidl nfsio 83807 149920 0 0 3 0x14280 nfsidl nfsio 17171 436859 0 0 3 0x14200 bored sosplice 7034 411783 99016 0 3 0x82 piperd syz-executor.1 99016 310423 81061 0 3 0x82 thrsleep syz-fuzzer 99016 309980 81061 0 3 0x4000082 nanosleep syz-fuzzer 99016 1421 81061 0 3 0x4000082 thrsleep syz-fuzzer 99016 156075 81061 0 3 0x4000082 thrsleep syz-fuzzer 99016 512636 81061 0 3 0x4000082 thrsleep syz-fuzzer 99016 84803 81061 0 3 0x4000082 thrsleep syz-fuzzer 99016 411763 81061 0 2 0x4000002 syz-fuzzer 99016 515622 81061 0 3 0x4000082 thrsleep syz-fuzzer 99016 260131 81061 0 7 0x4000002 syz-fuzzer 99016 135807 81061 0 3 0x4000082 thrsleep syz-fuzzer 81061 468878 10908 0 3 0x10008a pause ksh 10908 5668 50361 0 3 0x92 select sshd 37246 209004 1 0 3 0x100083 ttyin getty 50361 31732 1 0 3 0x80 select sshd 13537 507285 96024 74 3 0x100092 bpf pflogd 96024 436245 1 0 3 0x80 netio pflogd 91721 162082 51255 73 3 0x100090 kqread syslogd 51255 183890 1 0 3 0x100082 netio syslogd 66873 442143 1 77 3 0x100090 poll dhclient 41789 210798 1 0 3 0x80 poll dhclient 61693 397364 0 0 3 0x14200 bored smr 10302 407744 0 0 3 0x14200 pgzero zerothread 91271 119004 0 0 3 0x14200 aiodoned aiodoned 95532 304142 0 0 3 0x14200 syncer update 43525 333329 0 0 3 0x14200 cleaner cleaner 6049 470355 0 0 3 0x14200 reaper reaper 15177 391835 0 0 3 0x14200 pgdaemon pagedaemon 93267 382381 0 0 3 0x14200 bored crynlk 3009 220752 0 0 3 0x14200 bored crypto 62278 101430 0 0 3 0x40014200 acpi0 acpi0 19939 218060 0 0 3 0x40014200 idle1 76147 477310 0 0 3 0x14200 bored softnet *97320 419967 0 0 7 0x14200 systqmp 37313 58714 0 0 3 0x14200 bored systq 46174 444846 0 0 3 0x40014200 bored softclock 92780 350943 0 0 3 0x40014200 idle0 1 311900 0 0 3 0x82 wait init 0 0 -1 0 3 0x10200 scheduler swapper ddb{1}> show all locks CPU 1: exclusive mutex mbufpl r = 0 (0xffffffff8290b150) #0 witness_lock+0x4c7 stacktrace_save sys/sys/stacktrace.h:36 [inline] #0 witness_lock+0x4c7 sys/kern/subr_witness.c:1164 #1 mtx_enter_try+0x102 #2 mtx_enter+0x4b sys/kern/kern_lock.c:266 #3 pool_cache_gc+0x12d pl_enter sys/kern/subr_pool.c:104 [inline] #3 pool_cache_gc+0x12d pool_cache_list_put sys/kern/subr_pool.c:1968 [inline] #3 pool_cache_gc+0x12d sys/kern/subr_pool.c:2025 #4 pool_gc_pages+0x6c sys/kern/subr_pool.c:1559 #5 taskq_thread+0xec sys/kern/kern_task.c:437 #6 proc_trampoline+0x1c Process 97320 (systqmp) thread 0xffff800020d884e0 (419967) shared rwlock pools r = 0 (0xffffffff826ec830) #0 witness_lock+0x4c7 stacktrace_save sys/sys/stacktrace.h:36 [inline] #0 witness_lock+0x4c7 sys/kern/subr_witness.c:1164 #1 pool_gc_pages+0x21 sys/kern/subr_pool.c:1555 #2 taskq_thread+0xec sys/kern/kern_task.c:437 #3 proc_trampoline+0x1c shared rwlock systqmp r = 0 (0xffffffff826f6670) #0 witness_lock+0x4c7 stacktrace_save sys/sys/stacktrace.h:36 [inline] #0 witness_lock+0x4c7 sys/kern/subr_witness.c:1164 #1 taskq_thread+0xdf sys/kern/kern_task.c:436 #2 proc_trampoline+0x1c exclusive mutex mbufpl r = 0 (0xffffffff8290b150) #0 witness_lock+0x4c7 stacktrace_save sys/sys/stacktrace.h:36 [inline] #0 witness_lock+0x4c7 sys/kern/subr_witness.c:1164 #1 mtx_enter_try+0x102 #2 mtx_enter+0x4b sys/kern/kern_lock.c:266 #3 pool_cache_gc+0x12d pl_enter sys/kern/subr_pool.c:104 [inline] #3 pool_cache_gc+0x12d pool_cache_list_put sys/kern/subr_pool.c:1968 [inline] #3 pool_cache_gc+0x12d sys/kern/subr_pool.c:2025 #4 pool_gc_pages+0x6c sys/kern/subr_pool.c:1559 #5 taskq_thread+0xec sys/kern/kern_task.c:437 #6 proc_trampoline+0x1c ddb{1}> show malloc Type InUse MemUse HighUse Limit Requests Type Lim devbuf 9489 6405K 6915K 78643K 10910 0 pcb 13 8K 8K 78643K 55 0 rtable 114 5K 9K 78643K 387 0 ifaddr 56 12K 13K 78643K 112 0 counters 43 33K 34K 78643K 57 0 ioctlops 0 0K 4K 78643K 1498 0 iov 0 0K 16K 78643K 35 0 mount 1 1K 1K 78643K 1 0 vnodes 1217 77K 77K 78643K 1360 0 UFS quota 1 32K 32K 78643K 1 0 UFS mount 5 36K 36K 78643K 5 0 shm 2 1K 5K 78643K 4 0 VM map 2 1K 1K 78643K 2 0 sem 12 0K 0K 78643K 28 0 dirhash 12 2K 2K 78643K 12 0 ACPI 1824 197K 290K 78643K 13058 0 file desc 4 9K 25K 78643K 225 0 proc 60 63K 83K 78643K 521 0 subproc 32 2K 2K 78643K 68 0 NFS srvsock 1 0K 0K 78643K 1 0 NFS daemon 1 16K 16K 78643K 1 0 ip_moptions 0 0K 0K 78643K 15 0 in_multi 49 3K 3K 78643K 82 0 ether_multi 1 0K 0K 78643K 2 0 ISOFS mount 1 32K 32K 78643K 1 0 MSDOSFS mount 1 16K 16K 78643K 1 0 ttys 31 148K 148K 78643K 31 0 exec 0 0K 1K 78643K 249 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 109 38K 38K 78643K 1586 0 UVM aobj 8 2K 2K 78643K 8 0 memdesc 1 4K 4K 78643K 1 0 crypto data 1 1K 1K 78643K 1 0 ip6_options 0 0K 0K 78643K 46 0 NDP 8 0K 0K 78643K 23 0 temp 86 3860K 3927K 78643K 4563 0 kqueue 3 4K 6K 78643K 4 0 SYN cache 2 16K 16K 78643K 2 0 ddb{1}> show all pools Name Size Requests Fail Releases Pgreq Pgrel Npage Hiwat Minpg Maxpg Idle arp 64 10 0 4 1 0 1 1 0 8 0 plcache 128 20 0 0 1 0 1 1 0 8 0 rtpcb 80 27 0 25 1 0 1 1 0 8 0 rtentry 112 69 0 25 2 0 2 2 0 8 0 unpcb 120 99 0 89 1 0 1 1 0 8 0 syncache 264 6 0 6 2 2 0 1 0 8 0 tcpqe 32 96 0 96 1 1 0 1 0 8 0 tcpcb 544 224 0 220 1 0 1 1 0 8 0 inpcb 296 532 0 525 2 1 1 2 0 8 0 nd6 48 14 0 8 1 0 1 1 0 8 0 ppxss 1128 1 0 1 1 1 0 1 0 8 0 pfosfp 40 846 0 423 5 0 5 5 0 8 0 pfosfpen 112 1428 0 714 21 0 21 21 0 8 0 pfrktable 1344 31 0 28 1 0 1 1 0 8 0 pftag 88 3 0 2 2 1 1 1 0 8 0 pfqueue 264 2 0 2 1 1 0 1 0 8 0 pfstitem 24 14 0 5 1 0 1 1 0 8 0 pfstkey 112 14 0 5 1 0 1 1 0 8 0 pfstate 328 14 0 5 2 0 2 2 0 8 0 pfrule 1360 31 0 22 2 1 1 2 0 8 0 art_heap8 4096 1 0 0 1 0 1 1 0 8 0 art_heap4 256 276 0 88 13 1 12 12 0 8 0 art_table 32 277 0 88 2 0 2 2 0 8 0 art_node 16 68 0 28 1 0 1 1 0 8 0 sysvmsgpl 40 6 0 2 1 0 1 1 0 8 0 semupl 112 2 0 2 1 1 0 1 0 8 0 semapl 112 24 0 14 1 0 1 1 0 8 0 shmpl 112 6 0 0 1 0 1 1 0 8 0 dirhash 1024 17 0 0 3 0 3 3 0 8 0 dino2pl 256 1650 0 248 88 0 88 88 0 8 0 ffsino 272 1650 0 248 94 0 94 94 0 8 0 nchpl 144 2102 0 508 60 0 60 60 0 8 0 uvmvnodes 72 1807 0 0 33 0 33 33 0 8 0 vnodes 208 1807 0 0 96 0 96 96 0 8 0 namei 1024 5783 0 5783 1 1 0 1 0 8 0 percpumem 16 39 0 7 1 0 1 1 0 8 0 vcpupl 1984 2 0 0 1 0 1 1 0 8 0 vmpool 560 2 0 0 1 0 1 1 0 8 0 pfiaddrpl 120 10 0 8 1 0 1 1 0 8 0 scxspl 192 6448 0 6448 8 8 0 7 0 8 0 plimitpl 152 25 0 17 1 0 1 1 0 8 0 sigapl 424 455 0 404 6 0 6 6 0 8 0 futexpl 56 3355 0 3355 1 1 0 1 0 8 0 knotepl 112 89 0 70 1 0 1 1 0 8 0 kqueuepl 144 9 0 7 1 0 1 1 0 8 0 pipelkpl 48 112 0 102 1 0 1 1 0 8 0 pipepl 120 224 0 205 1 0 1 1 0 8 0 fdescpl 496 419 0 404 3 0 3 3 0 8 0 filepl 152 2343 0 2243 5 1 4 5 0 8 0 lockfpl 104 44 0 43 1 0 1 1 0 8 0 lockfspl 48 20 0 19 1 0 1 1 0 8 0 sessionpl 112 20 0 9 1 0 1 1 0 8 0 pgrppl 48 22 0 11 1 0 1 1 0 8 0 ucredpl 96 104 0 95 1 0 1 1 0 8 0 zombiepl 144 404 0 404 1 1 0 1 0 8 0 processpl 984 455 0 404 7 0 7 7 0 8 0 procpl 624 764 0 704 6 1 5 6 0 8 0 srpgc 64 4 0 4 1 1 0 1 0 8 0 sockpl 400 658 0 639 5 3 2 4 0 8 0 mcl64k 65536 6 0 0 1 0 1 1 0 8 0 mcl12k 12288 4 0 0 1 0 1 1 0 8 0 mcl9k 9216 3 0 0 1 0 1 1 0 8 0 mcl8k 8192 3 0 0 1 0 1 1 0 8 0 mcl4k 4096 5 0 0 1 0 1 1 0 8 0 mcl2k2 2112 2 0 0 1 0 1 1 0 8 0 mcl2k 2048 230 0 0 28 0 28 28 0 8 0 mtagpl 96 8 0 0 1 0 1 1 0 8 0 mbufpl 256 276 0 0 17 0 17 17 0 8 0 bufpl 280 3749 0 134 259 0 259 259 0 8 0 anonpl 16 55587 0 42635 63 1 62 62 0 124 0 amapchunkpl 152 2191 0 2058 8 1 7 8 0 158 0 amappl16 192 1652 0 822 43 1 42 43 0 8 0 amappl15 184 1 0 0 1 0 1 1 0 8 0 amappl14 176 54 0 50 1 0 1 1 0 8 0 amappl13 168 44 0 41 1 0 1 1 0 8 0 amappl12 160 33 0 29 1 0 1 1 0 8 0 amappl11 152 60 0 44 1 0 1 1 0 8 0 amappl10 144 129 0 122 1 0 1 1 0 8 0 amappl9 136 416 0 414 1 0 1 1 0 8 0 amappl8 128 393 0 369 1 0 1 1 0 8 0 amappl7 120 132 0 119 1 0 1 1 0 8 0 amappl6 112 28 0 23 1 0 1 1 0 8 0 amappl5 104 287 0 270 1 0 1 1 0 8 0 amappl4 96 539 0 508 1 0 1 1 0 8 0 amappl3 88 133 0 128 1 0 1 1 0 8 0 amappl2 80 2257 0 2197 2 0 2 2 0 8 0 amappl1 72 19594 0 19166 23 13 10 18 0 8 0 amappl 80 1021 0 979 1 0 1 1 0 84 0 dma4096 4096 1 0 1 1 1 0 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 64 7 0 0 1 0 1 1 0 8 0 uaddrrnd 24 421 0 404 1 0 1 1 0 8 0 uaddrbest 32 2 0 0 1 0 1 1 0 8 0 uaddr 24 421 0 404 1 0 1 1 0 8 0 vmmpekpl 168 7069 0 7033 2 0 2 2 0 8 0 vmmpepl 168 58323 0 56448 127 17 110 119 0 357 22 vmsppl 368 420 0 404 2 0 2 2 0 8 0 pdppl 4096 849 0 810 7 1 6 6 0 8 0 pvpl 32 183168 0 167052 154 6 148 154 0 265 0 pmappl 232 420 0 404 3 2 1 2 0 8 0 extentpl 40 53 0 36 1 0 1 1 0 8 0 phpool 112 273 0 10 8 0 8 8 0 8 0 ddb{1}> machine ddbcpu 0 Stopped at x86_ipi_db+0x1a: addq $0x8,%rsp x86_ipi_db(ffffffff82741ff0) at x86_ipi_db+0x1a sys/arch/amd64/amd64/db_interface.c:352 x86_ipi_handler() at x86_ipi_handler+0xc6 sys/arch/amd64/amd64/ipi.c:106 Xresume_lapic_ipi() at Xresume_lapic_ipi+0x23 end of kernel end trace frame: 0xc0002d1558, count: 12 ddb{0}> trace x86_ipi_db(ffffffff82741ff0) at x86_ipi_db+0x1a sys/arch/amd64/amd64/db_interface.c:352 x86_ipi_handler() at x86_ipi_handler+0xc6 sys/arch/amd64/amd64/ipi.c:106 Xresume_lapic_ipi() at Xresume_lapic_ipi+0x23 end of kernel end trace frame: 0xc0002d1558, count: -3 ddb{0}> machine ddbcpu 1 Stopped at db_enter+0x18: addq $0x8,%rsp db_enter() at db_enter+0x18 sys/arch/amd64/amd64/db_interface.c:398 panic(ffffffff8240e307) at panic+0x15c sys/kern/subr_prf.c:207 witness_checkorder(ffffffff828f8078,9,0) at witness_checkorder+0x10e0 sys/kern/subr_witness.c:821 __mp_lock(ffffffff828f7e70) at __mp_lock+0xa1 read_rflags machine/cpufunc.h:195 [inline] __mp_lock(ffffffff828f7e70) at __mp_lock+0xa1 intr_disable machine/cpufunc.h:216 [inline] __mp_lock(ffffffff828f7e70) at __mp_lock+0xa1 sys/kern/kern_lock.c:142 pageflttrap(ffff800020da3040,0) at pageflttrap+0x7f sys/arch/amd64/amd64/trap.c:180 kerntrap(ffff800020da3040) at kerntrap+0xec sys/arch/amd64/amd64/trap.c:302 alltraps_kern_meltdown() at alltraps_kern_meltdown+0x7b pool_cache_gc(ffffffff8290b140) at pool_cache_gc+0x138 pool_cache_list_put sys/kern/subr_pool.c:1970 [inline] pool_cache_gc(ffffffff8290b140) at pool_cache_gc+0x138 sys/kern/subr_pool.c:2025 pool_gc_pages(0) at pool_gc_pages+0x6c sys/kern/subr_pool.c:1559 taskq_thread(ffffffff826f6600) at taskq_thread+0xec sys/kern/kern_task.c:437 end trace frame: 0x0, count: 5 ddb{1}> trace db_enter() at db_enter+0x18 sys/arch/amd64/amd64/db_interface.c:398 panic(ffffffff8240e307) at panic+0x15c sys/kern/subr_prf.c:207 witness_checkorder(ffffffff828f8078,9,0) at witness_checkorder+0x10e0 sys/kern/subr_witness.c:821 __mp_lock(ffffffff828f7e70) at __mp_lock+0xa1 read_rflags machine/cpufunc.h:195 [inline] __mp_lock(ffffffff828f7e70) at __mp_lock+0xa1 intr_disable machine/cpufunc.h:216 [inline] __mp_lock(ffffffff828f7e70) at __mp_lock+0xa1 sys/kern/kern_lock.c:142 pageflttrap(ffff800020da3040,0) at pageflttrap+0x7f sys/arch/amd64/amd64/trap.c:180 kerntrap(ffff800020da3040) at kerntrap+0xec sys/arch/amd64/amd64/trap.c:302 alltraps_kern_meltdown() at alltraps_kern_meltdown+0x7b pool_cache_gc(ffffffff8290b140) at pool_cache_gc+0x138 pool_cache_list_put sys/kern/subr_pool.c:1970 [inline] pool_cache_gc(ffffffff8290b140) at pool_cache_gc+0x138 sys/kern/subr_pool.c:2025 pool_gc_pages(0) at pool_gc_pages+0x6c sys/kern/subr_pool.c:1559 taskq_thread(ffffffff826f6600) at taskq_thread+0xec sys/kern/kern_task.c:437 end trace frame: 0x0, count: -10