login: tap1Ú#!{ô«Ô9¥GÀ @ À °þÿÿuvm_fault(0xfffffd8068f8d9b0, 0x0, 0, 1) -> e kernel: page fault trap, code=0 Stopped at dt_ioctl_record_stop+0xf0: movq 0(%r14),%r12 TID PID UID PRFLAGS PFLAGS CPU COMMAND dt_ioctl_record_stop(ffff800001496000) at dt_ioctl_record_stop+0xf0 sys/dev/dt/dt_dev.c:580 dtclose(11e5f,81,2000,ffff8000ffff2530) at dtclose+0x105 dt_pcb_purge sys/dev/dt/dt_dev.c:-1 [inline] dtclose(11e5f,81,2000,ffff8000ffff2530) at dtclose+0x105 sys/dev/dt/dt_dev.c:232 spec_close(ffff80002a3ad950) at spec_close+0x45f sys/kern/spec_vnops.c:-1 VOP_CLOSE(fffffd806e14c438,81,fffffd80097fb478,ffff8000ffff2530) at VOP_CLOSE+0x133 sys/kern/vfs_vops.c:156 vn_closefile(fffffd8066a085e0,ffff8000ffff2530) at vn_closefile+0x12b vn_close sys/kern/vfs_vnops.c:292 [inline] vn_closefile(fffffd8066a085e0,ffff8000ffff2530) at vn_closefile+0x12b sys/kern/vfs_vnops.c:615 fdrop(fffffd8066a085e0,ffff8000ffff2530) at fdrop+0x126 sys/kern/kern_descrip.c:1267 closef(fffffd8066a085e0,ffff8000ffff2530) at closef+0x192 sys/kern/kern_descrip.c:1251 fdfree(ffff8000ffff2530) at fdfree+0x116 sys/kern/kern_descrip.c:1182 exit1(ffff8000ffff2530,0,0,1) at exit1+0x59c sys/kern/kern_exit.c:215 sys_exit(ffff8000ffff2530,ffff80002a3adcc0,ffff80002a3adc10) at sys_exit+0x1a sys/kern/kern_exit.c:-1 syscall(ffff80002a3adcc0) at syscall+0xb08 mi_syscall sys/sys/syscall_mi.h:176 [inline] syscall(ffff80002a3adcc0) at syscall+0xb08 sys/arch/amd64/amd64/trap.c:748 Xsyscall() at Xsyscall+0x128 end of kernel end trace frame: 0x74a18ca47d10, count: 3 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: uvm_fault(0xfffffd8068f8d9b0, 0x0, 0, 1) -> e ddb{0}> trace dt_ioctl_record_stop(ffff800001496000) at dt_ioctl_record_stop+0xf0 sys/dev/dt/dt_dev.c:580 dtclose(11e5f,81,2000,ffff8000ffff2530) at dtclose+0x105 dt_pcb_purge sys/dev/dt/dt_dev.c:-1 [inline] dtclose(11e5f,81,2000,ffff8000ffff2530) at dtclose+0x105 sys/dev/dt/dt_dev.c:232 spec_close(ffff80002a3ad950) at spec_close+0x45f sys/kern/spec_vnops.c:-1 VOP_CLOSE(fffffd806e14c438,81,fffffd80097fb478,ffff8000ffff2530) at VOP_CLOSE+0x133 sys/kern/vfs_vops.c:156 vn_closefile(fffffd8066a085e0,ffff8000ffff2530) at vn_closefile+0x12b vn_close sys/kern/vfs_vnops.c:292 [inline] vn_closefile(fffffd8066a085e0,ffff8000ffff2530) at vn_closefile+0x12b sys/kern/vfs_vnops.c:615 fdrop(fffffd8066a085e0,ffff8000ffff2530) at fdrop+0x126 sys/kern/kern_descrip.c:1267 closef(fffffd8066a085e0,ffff8000ffff2530) at closef+0x192 sys/kern/kern_descrip.c:1251 fdfree(ffff8000ffff2530) at fdfree+0x116 sys/kern/kern_descrip.c:1182 exit1(ffff8000ffff2530,0,0,1) at exit1+0x59c sys/kern/kern_exit.c:215 sys_exit(ffff8000ffff2530,ffff80002a3adcc0,ffff80002a3adc10) at sys_exit+0x1a sys/kern/kern_exit.c:-1 syscall(ffff80002a3adcc0) at syscall+0xb08 mi_syscall sys/sys/syscall_mi.h:176 [inline] syscall(ffff80002a3adcc0) at syscall+0xb08 sys/arch/amd64/amd64/trap.c:748 Xsyscall() at Xsyscall+0x128 end of kernel end trace frame: 0x74a18ca47d10, count: -12 ddb{0}> show registers rdi 0 rsi 0 rbp 0xffff80002a3ad880 rbx 0xffffffff8223c070 dtclose rdx 0 rcx 0xffff8000ffff2530 rax 0xffffffff837f0ff0 cpu_info_full_primary+0x1ff0 r8 0xffffffffffffffff r9 0 r10 0x57736e9bb542bb4e r11 0xcae0b50e99a50e4a r12 0 r13 0 r14 0 r15 0xffff80000149c200 rip 0xffffffff8223c450 dt_ioctl_record_stop+0xf0 cs 0x8 rflags 0x10246 __ALIGN_SIZE+0xf246 rsp 0xffff80002a3ad850 ss 0x10 dt_ioctl_record_stop+0xf0: movq 0(%r14),%r12 ddb{0}> show proc PROC (syz-executor) tid=477976 pid=1112 tcnt=0 stat=onproc flags process=1008 proc=2000 runpri=32, usrpri=50, slppri=32, nice=20 wchan=0x0, wmesg=, ps_single=0xffff8000ffff2530 scnt=-1 ecnt=1 forw=0xffffffffffffffff, list=0xffff8000ffff2f70,0xffffffff839670b0 process=0xffff80003c499d68 user=0xffff80002a3a8000, vmspace=0xfffffd8068f8d9b0 estcpu=36, cpticks=3, pctcpu=0.0, user=0, sys=1, intr=0 ddb{0}> ps PID TID PPID UID S FLAGS WAIT COMMAND 7772 66924 54560 0 3 0x80 nanoslp syz-executor 7772 362408 54560 0 3 0x4000080 kqsel syz-executor 7772 221890 54560 0 3 0x4000080 fsleep syz-executor 33137 383256 5356 0 2 0 syz-executor 33137 349717 5356 0 3 0x4000080 fsleep syz-executor 33137 279437 5356 0 3 0x4000080 lockf syz-executor 16945 369354 45096 0 3 0x3000 suspend syz-executor 16945 234139 45096 0 3 0x4081000 inode syz-executor 16945 119871 45096 0 2 0x4081000 syz-executor 51112 146598 69001 0 3 0x82 wait syz-executor 54560 111440 69001 0 3 0x82 nanoslp syz-executor 64120 59092 1 0 3 0x100083 ttyin getty 63898 184610 69001 0 3 0x82 nanoslp syz-executor 56693 57841 0 0 3 0x14200 bored sosplice 4530 234481 69001 0 3 0x82 nanoslp syz-executor 32975 457739 69001 0 3 0x82 nanoslp syz-executor 43509 506459 69001 0 3 0x82 nanoslp syz-executor 5356 309507 69001 0 3 0x82 nanoslp syz-executor 45096 37782 69001 0 3 0x82 nanoslp syz-executor 69001 231272 78573 0 3 0x82 kqread syz-executor 78573 126475 38492 0 3 0x10008a sigsusp ksh 38492 24295 12483 0 3 0x98 kqread sshd-session 12483 335949 51344 0 3 0x92 kqread sshd-session 51344 299317 1 0 3 0x88 kqread sshd 72366 65537 29396 74 3 0x1100092 bpf pflogd 29396 93508 1 0 3 0x80 sbwait pflogd 11564 140910 29443 73 3 0x1100090 kqread syslogd 29443 144237 1 0 3 0x100082 sbwait syslogd 94611 196070 1 0 3 0x100080 kqread resolvd 13569 423771 31827 77 3 0x100092 kqread dhcpleased 95040 340362 31827 77 3 0x100092 kqread dhcpleased 31827 306312 1 0 3 0x80 kqread dhcpleased 76053 264115 0 0 3 0x14200 bored smr 31142 59355 0 0 3 0x14200 pgzero zerothread 20939 430776 0 0 3 0x14200 aiodoned aiodoned 64143 275460 0 0 3 0x14200 syncer update 47658 234941 0 0 3 0x14200 cleaner cleaner 10582 86908 0 0 3 0x14200 reaper reaper 9321 263473 0 0 3 0x14200 pgdaemon pagedaemon 26838 517005 0 0 3 0x14200 bored viomb 73110 478302 0 0 3 0x40014200 acpi0 acpi0 50837 44049 0 0 7 0x40014200 idle1 43429 393893 0 0 3 0x14200 bored softnet7 61073 69784 0 0 3 0x14200 bored softnet6 45913 35966 0 0 3 0x14200 bored softnet5 79995 432753 0 0 3 0x14200 bored softnet4 46937 142228 0 0 3 0x14200 bored softnet3 36588 478551 0 0 3 0x14200 bored softnet2 18723 190221 0 0 3 0x14200 bored softnet1 398 97555 0 0 3 0x14200 bored softnet0 87200 492973 0 0 3 0x14200 bored systqmp 48887 153032 0 0 3 0x14200 bored systq 8946 196486 0 0 3 0x14200 tmoslp softclockmp 44616 89923 0 0 3 0x40014200 tmoslp softclock 80939 201592 0 0 3 0x40014200 idle0 1 281335 0 0 3 0x82 wait init 0 0 -1 0 3 0x10010200 scheduler swapper ddb{0}> show all locks Process 16945 (syz-executor) thread 0xffff80003bc359d0 (119871) exclusive rrwlock inode r = 0 (0xfffffd806e33cc98) #0 witness_lock+0x5bb stacktrace_save sys/sys/stacktrace.h:37 [inline] #0 witness_lock+0x5bb sys/kern/subr_witness.c:1160 #1 rw_do_enter_write+0x42b sys/kern/kern_rwlock.c:319 #2 rrw_enter+0xc6 sys/kern/kern_rwlock.c:621 #3 VOP_LOCK+0xa6 sys/kern/vfs_vops.c:527 #4 vn_lock+0xa4 sys/kern/vfs_vnops.c:570 #5 vn_write+0x190 sys/kern/vfs_vnops.c:405 #6 dofilewritev+0x23c sys/kern/sys_generic.c:380 #7 sys_write+0xa2 sys/kern/sys_generic.c:300 #8 syscall+0xbc6 mi_syscall sys/sys/syscall_mi.h:176 [inline] #8 syscall+0xbc6 sys/arch/amd64/amd64/trap.c:748 #9 Xsyscall+0x128 ddb{0}> show malloc Type InUse MemUse HighUse Limit Requests Type Lim devbuf 10269 11198K 11480K 166960K 13896 0 pcb 17 19K 22K 166960K 631 0 rtable 210 12K 12K 166960K 626 0 pf 39 18K 67487K 166960K 259 0 ifaddr 41 7K 8K 166960K 153 0 ifgroup 59 2K 2K 166960K 260 0 sysctl 4 1K 9K 166960K 19 0 counters 70 37K 38K 166960K 280 0 ioctlops 0 0K 4K 166960K 1973 0 iov 0 0K 20K 166960K 215 0 mount 1 1K 1K 166960K 1 0 log 0 0K 0K 166960K 4 0 vnodes 1471 93K 93K 166960K 3420 0 UFS quota 1 32K 32K 166960K 1 0 UFS mount 5 36K 36K 166960K 5 0 shm 2 1K 5K 166960K 21 0 VM map 2 1K 1K 166960K 2 0 sem 12 0K 0K 166960K 81 0 dirhash 12 2K 3K 166960K 57 0 ACPI 1692 195K 286K 166960K 12470 0 file desc 18 65K 236K 166960K 2082 0 sigio 0 0K 0K 166960K 81 0 proc 72 115K 180K 166960K 898 0 subproc 72 4K 4K 166960K 127 0 NFS srvsock 1 0K 0K 166960K 1 0 NFS daemon 1 16K 16K 166960K 1 0 ip_moptions 0 0K 0K 166960K 471 0 in_multi 84 6K 7K 166960K 242 0 ether_multi 1 0K 0K 166960K 13 0 mrt 2 0K 0K 166960K 13 0 ISOFS mount 1 32K 32K 166960K 1 0 MSDOSFS mount 1 16K 16K 166960K 1 0 ttys 289 1288K 1288K 166960K 289 0 exec 0 0K 1K 166960K 851 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 264 160K 170K 166960K 20008 0 UVM aobj 23 2K 2K 166960K 27 0 pinsyscall 43 86K 109K 166960K 3337 0 memdesc 1 4K 4K 166960K 1 0 crypto data 1 1K 1K 166960K 1 0 ip6_options 0 0K 0K 166960K 99 0 NDP 13 0K 2K 166960K 110 0 temp 83 8684K 8939K 166960K 109581 0 kqueue 13 20K 34K 166960K 381 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 208 0 205 3 2 1 3 0 8 0 rtentry 176 196 0 119 6 2 4 5 0 8 0 unpcb 144 1334 0 1317 13 7 6 6 0 8 5 syncache 336 8 0 8 3 3 0 1 0 8 0 tcpcb 736 735 0 731 20 11 9 13 0 8 8 arp 128 20 0 11 1 0 1 1 0 8 0 inpcb 328 2577 0 2567 32 22 10 18 0 8 8 nd6 144 38 0 21 1 0 1 1 0 8 0 pkpcb 40 9 0 9 3 2 1 1 0 8 1 kcovpl 48 14 0 6 1 0 1 1 0 8 0 mppekey 1024 4 0 4 3 2 1 1 0 8 1 ppxss 1192 82 0 82 2 1 1 1 0 8 1 pppxif 1504 3 0 3 2 1 1 1 0 8 1 pfstscr 40 6 0 5 2 1 1 1 0 8 0 pffrag 232 14 0 6 1 0 1 1 0 482 0 pffrnode 88 10 0 4 1 0 1 1 0 8 0 pffrent 40 25 0 16 1 0 1 1 0 8 0 pfosfp 40 1430 0 1006 5 0 5 5 0 8 0 pfosfpen 112 1430 0 714 21 0 21 21 0 8 0 pfrktable 1344 3 0 3 3 2 1 1 0 8 1 pfanchor 1288 1 0 1 1 1 0 1 0 8 0 pftag 88 6 0 3 1 0 1 1 0 8 0 pfqueue 320 1 0 1 1 1 0 1 0 8 0 pfstitem 24 153 0 57 1 0 1 1 0 8 0 pfstkey 128 158 0 62 4 0 4 4 0 8 0 pfstate 384 154 0 60 10 0 10 10 0 8 0 pfrule 1344 42 0 37 2 1 1 2 0 8 0 rttmr 136 2 0 2 2 2 0 1 0 8 0 art_heap8 4096 3 0 0 3 0 3 3 0 8 0 art_heap4 256 903 0 520 33 7 26 28 0 8 1 art_table 40 906 0 520 5 0 5 5 0 8 0 art_node 32 195 0 127 1 0 1 1 0 8 0 sysvmsgpl 40 17 0 8 1 0 1 1 0 8 0 semupl 112 5 0 5 3 3 0 1 0 8 0 semapl 112 76 0 66 1 0 1 1 0 8 0 shmpl 112 24 0 4 1 0 1 1 0 8 0 dirhash 1024 48 0 31 3 0 3 3 0 8 0 dino2pl 256 5524 0 4008 96 0 96 96 0 8 0 ffsino 296 5524 0 4008 118 0 118 118 0 8 0 nchpl 144 8341 0 6634 64 0 64 64 0 8 0 rtmask 32 19 0 19 4 3 1 1 0 8 1 uvmvnodes 80 5926 0 0 121 0 121 121 0 8 0 vnodes 216 5926 0 0 330 0 330 330 0 8 0 namei 1024 30344 0 30344 3 2 1 2 0 8 1 percpumem 16 155 0 105 1 0 1 1 0 8 0 kstatmem 264 168 0 138 3 0 3 3 0 8 0 scsiplug 72 16 0 16 4 3 1 1 0 8 1 scxspl 216 49675 0 49675 11 9 2 8 1 8 2 plimitpl 152 774 0 753 1 0 1 1 0 8 0 sigapl 424 2391 0 2336 9 1 8 9 0 8 0 knotepl 120 614 0 0 18 0 18 18 0 8 0 kqueuepl 224 849 0 839 11 8 3 5 0 8 2 pipepl 344 285 0 256 3 0 3 3 0 8 0 fdescpl 528 2345 0 2313 3 0 3 3 0 8 0 filepl 160 16627 0 16383 28 10 18 18 0 8 5 lockfpl 104 1691 0 1687 4 3 1 2 0 8 0 lockfspl 48 536 0 533 1 0 1 1 0 8 0 sessionpl 144 33 0 24 1 0 1 1 0 8 0 pgrppl 48 80 0 63 1 0 1 1 0 8 0 ucredpl 104 3170 0 3156 1 0 1 1 0 8 0 zombiepl 144 2342 0 2336 1 0 1 1 0 8 0 processpl 1248 2391 0 2336 6 1 5 6 0 8 0 procpl 656 5580 0 5519 8 1 7 8 0 8 0 sosppl 168 14 0 14 3 2 1 1 0 8 1 sockpl 752 4205 0 4175 52 40 12 23 0 8 8 mcl64k 65536 5 0 0 1 0 1 1 0 8 0 mcl16k 16384 3 0 0 1 0 1 1 0 8 0 mcl12k 12288 2 0 0 1 0 1 1 0 8 0 mcl8k 8192 4 0 0 1 0 1 1 0 8 0 mcl4k 4096 115 0 0 15 0 15 15 0 8 0 mcl2k2 2112 1 0 0 1 0 1 1 0 8 0 mcl2k 2048 31 0 0 4 0 4 4 0 8 0 mtagpl 96 16 0 0 1 0 1 1 0 8 0 mbufpl 256 1172 0 0 73 0 73 73 0 8 0 bufpl 280 19335 0 13193 439 0 439 439 0 8 0 anonpl 32 11563 0 0 94 0 94 94 0 246 0 amapchunkpl 152 76339 0 75679 46 16 30 36 0 158 3 amappl16 200 9075 0 8856 64 48 16 27 0 8 3 amappl15 192 24 0 24 2 2 0 1 0 8 0 amappl14 184 164 0 152 1 0 1 1 0 8 0 amappl13 176 28 0 28 1 1 0 1 0 8 0 amappl12 168 3098 0 3066 4 1 3 3 0 8 0 amappl11 160 53 0 39 1 0 1 1 0 8 0 amappl10 152 9 0 8 1 0 1 1 0 8 0 amappl9 144 255 0 254 2 1 1 1 0 8 0 amappl8 136 28 0 25 1 0 1 1 0 8 0 amappl7 128 128 0 114 1 0 1 1 0 8 0 amappl6 120 263 0 258 1 0 1 1 0 8 0 amappl5 112 152 0 142 1 0 1 1 0 8 0 amappl4 104 326 0 305 1 0 1 1 0 8 0 amappl3 96 14205 0 14081 4 0 4 4 0 8 0 amappl2 88 768 0 704 2 0 2 2 0 8 0 amappl1 80 17574 0 16968 17 2 15 15 0 8 0 amappl 88 18852 0 18661 5 0 5 5 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 8 0 8 2 2 0 1 0 8 0 dma128 128 258 0 258 4 4 0 1 0 8 0 dma64 64 6 0 6 1 1 0 1 0 8 0 dma32 32 8 0 8 2 2 0 1 0 8 0 dma16 16 19 0 18 1 0 1 1 0 8 0 aobjpl 72 26 0 4 1 0 1 1 0 8 0 uaddrrnd 24 2345 0 2313 1 0 1 1 0 8 0 uaddrbest 32 2 0 0 1 0 1 1 0 8 0 uaddr 24 2345 0 2313 1 0 1 1 0 8 0 vmmpekpl 168 18966 0 18919 3 0 3 3 0 8 0 vmmpepl 168 152890 0 150597 114 9 105 111 0 357 0 vmsppl 488 2344 0 2313 6 1 5 5 0 8 0 rwobjpl 80 47021 0 39910 151 3 148 148 0 8 0 pdppl 4096 4698 0 4626 108 36 72 86 0 8 0 pvpl 32 20287 0 0 165 2 163 163 0 265 0 pmappl 256 2344 0 2313 3 0 3 3 0 8 0 extentpl 40 45 0 27 1 0 1 1 0 8 0 phpool 112 374 0 64 9 0 9 9 0 8 0 ddb{0}> machine ddbcpu 0 Invalid cpu 0 ddb{0}> trace dt_ioctl_record_stop(ffff800001496000) at dt_ioctl_record_stop+0xf0 sys/dev/dt/dt_dev.c:580 dtclose(11e5f,81,2000,ffff8000ffff2530) at dtclose+0x105 dt_pcb_purge sys/dev/dt/dt_dev.c:-1 [inline] dtclose(11e5f,81,2000,ffff8000ffff2530) at dtclose+0x105 sys/dev/dt/dt_dev.c:232 spec_close(ffff80002a3ad950) at spec_close+0x45f sys/kern/spec_vnops.c:-1 VOP_CLOSE(fffffd806e14c438,81,fffffd80097fb478,ffff8000ffff2530) at VOP_CLOSE+0x133 sys/kern/vfs_vops.c:156 vn_closefile(fffffd8066a085e0,ffff8000ffff2530) at vn_closefile+0x12b vn_close sys/kern/vfs_vnops.c:292 [inline] vn_closefile(fffffd8066a085e0,ffff8000ffff2530) at vn_closefile+0x12b sys/kern/vfs_vnops.c:615 fdrop(fffffd8066a085e0,ffff8000ffff2530) at fdrop+0x126 sys/kern/kern_descrip.c:1267 closef(fffffd8066a085e0,ffff8000ffff2530) at closef+0x192 sys/kern/kern_descrip.c:1251 fdfree(ffff8000ffff2530) at fdfree+0x116 sys/kern/kern_descrip.c:1182 exit1(ffff8000ffff2530,0,0,1) at exit1+0x59c sys/kern/kern_exit.c:215 sys_exit(ffff8000ffff2530,ffff80002a3adcc0,ffff80002a3adc10) at sys_exit+0x1a sys/kern/kern_exit.c:-1 syscall(ffff80002a3adcc0) at syscall+0xb08 mi_syscall sys/sys/syscall_mi.h:176 [inline] syscall(ffff80002a3adcc0) at syscall+0xb08 sys/arch/amd64/amd64/trap.c:748 Xsyscall() at Xsyscall+0x128 end of kernel end trace frame: 0x74a18ca47d10, count: -12 ddb{0}> machine ddbcpu 1 Stopped at x86_ipi_db+0x27: addq $0x8,%rsp 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 acpicpu_idle() at acpicpu_idle+0x41e sys/dev/acpi/acpicpu_x86.c:1218 sched_idle(ffff8000299ddff0) at sched_idle+0x4d8 sys/kern/kern_sched.c:191 end trace frame: 0x0, count: 10 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 acpicpu_idle() at acpicpu_idle+0x41e sys/dev/acpi/acpicpu_x86.c:1218 sched_idle(ffff8000299ddff0) at sched_idle+0x4d8 sys/kern/kern_sched.c:191 end trace frame: 0x0, count: -5