DF-3173 / fix_run_dmesg.txt
Copyright (c) 2003-2026 The DragonFly Project.
Copyright (c) 1992-2003 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
The Regents of the University of California. All rights reserved.
DragonFly 6.5-DEVELOPMENT #0: Thu Jul 2 06:02:54 UTC 2026
root@dfbsd:/usr/obj/usr/src/sys/X86_64_GENERIC
acpi_hpet: frequency 100000000
Using cputimer HPET for TSC calibration
Timer latency (in TSC ticks): 32225 min=31934 max=32524
TSC clock: 3600007430 Hz, NOT invariant
CPU: QEMU Virtual CPU version 2.5+ (3600.01-MHz K8-class CPU)
Origin="GenuineIntel" Id=0x60fb1 Family=0xf Model=0x6b Stepping=1
Features=0x1783fbfd<FPU,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE,SSE2,HTT>
Features2=0x80202001<SSE3,CX16,x2APIC,VMM>
AMD Features=0x20100800<SYSCALL,NX,LM>
AMD Features2=0x1<LAHF>
MONITOR/MWAIT Features=0x2<INTBRK>
VMM/Hypervisor: Origin="KVMKVMKVM"
real memory = 4293381120 (4094 MB)
avail memory = 4088926208 (3899 MB)
LAPIC: enter X2APIC mode
madt_lapic_probe: lapic_count=6 x2apic_count=0
ACPI CPUS = 6
lapic: divisor index 0, frequency 500002815 Hz
CPU Topology: cores_per_chip: 6; threads_per_core: 1; chips_per_package: 1;
srat_probe: can't locate SRAT
SMI Frequency (worst case): 30303 Hz (33 us)
Initialize MI interrupts for 6 cpus
TSC is not invariant, no further tests will be performed
Spectre: support=( none ) req=ffff operating=( none )
MDS: support=( none ) req=ffff operating=( none )
machdep.meltdown_mitigation enabled to protect against (mostly Intel) meltdown bug
system call performance will be impacted
interrupt uses mplock: swi_taskq
wdog: In-kernel automatic watchdog reset enabled
md0: Malloc disk
evdev device loaded.
kbd1 at kbdmux0
ACPI: RSDP 0x00000000000F59A0 000014 (v00 BOCHS )
ACPI: RSDT 0x00000000BFFE1CAC 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)
ACPI: FACP 0x00000000BFFE1B38 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)
ACPI: DSDT 0x00000000BFFE0040 001AF8 (v01 BOCHS BXPC 00000001 BXPC 00000001)
ACPI: FACS 0x00000000BFFE0000 000040
ACPI: APIC 0x00000000BFFE1BAC 0000A0 (v01 BOCHS BXPC 00000001 BXPC 00000001)
ACPI: HPET 0x00000000BFFE1C4C 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)
ACPI: WAET 0x00000000BFFE1C84 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)
sc0: <System console> on motherboard
sc0: VGA <16 virtual consoles, flags=0x100>
acpi0: <BOCHS BXPC> on motherboard
ACPI: 1 ACPI AML tables successfully acquired and loaded
ACPI FADT: SCI testing interrupt mode ...
ACPI FADT: SCI select level/high
acpi0: Power Button (fixed)
acpi_hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff on acpi0
acpi_timer0 on acpi0
isab0: <ACPI Generic ISA bridge> on acpi0
isa0: <ISA bus> on isab0
cpu0: <ACPI CPU> on acpi0
cpu_cst0: <ACPI CPU C-State> on cpu0
cpu1: <ACPI CPU> on acpi0
cpu_cst1: <ACPI CPU C-State> on cpu1
cpu2: <ACPI CPU> on acpi0
cpu_cst2: <ACPI CPU C-State> on cpu2
cpu3: <ACPI CPU> on acpi0
cpu_cst3: <ACPI CPU C-State> on cpu3
cpu4: <ACPI CPU> on acpi0
cpu_cst4: <ACPI CPU C-State> on cpu4
cpu5: <ACPI CPU> on acpi0
cpu_cst5: <ACPI CPU C-State> on cpu5
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
isab1: <PCI-ISA bridge> at device 1.0 on pci0
isa1: <ISA bus> on isab1
atapci0: <Intel PIIX3 WDMA2 controller> port 0xc0a0-0xc0af,0x376,0x170-0x177,0x3f6,0x1f0-0x1f7 at device 1.1 on pci0
ata0: <ATA channel 0> on atapci0
interrupt uses mplock: ata0
ata1: <ATA channel 1> on atapci0
interrupt uses mplock: ata1
acd0: DVDROM <QEMU DVD-ROM/2.5+> at ata1-master WDMA2
pci0: <bridge> (vendor 0x8086, dev 0x7113) at device 1.3 irq 9
vgapci0: <VGA-compatible display> mem 0xfebd0000-0xfebd0fff,0xfd000000-0xfdffffff at device 2.0 on pci0
vgapci0: Boot video device
virtio_pci0: <VirtIO PCI Network adapter> port 0xc080-0xc09f mem 0xfe000000-0xfe003fff,0xfebd1000-0xfebd1fff irq 11 at device 3.0 on pci0
vtnet0: <VirtIO Networking Adapter> on virtio_pci0
virtio_pci0: host features: 0x79bf8064 <EventIdx,RingIndirect,AnyLayout,NotifyOnEmpty,SetMacAddress,GuestAnnounce,RxModeExtra,VLanFilter,RxMode,ControlVq,Status,MrgRxBuf,TxAllGSO,MacAddress,DynOffload>
virtio_pci0: negotiated features: 0x198f8020 <RingIndirect,AnyLayout,NotifyOnEmpty,SetMacAddress,VLanFilter,RxMode,ControlVq,Status,MrgRxBuf,MacAddress>
virtio_pci0: using 3 MSI-X vectors
vtnet0: MAC address: 52:54:00:12:34:56
virtio_pci1: <VirtIO PCI Block adapter> port 0xc000-0xc07f mem 0xfe004000-0xfe007fff,0xfebd2000-0xfebd2fff irq 11 at device 4.0 on pci0
vtblk0: <VirtIO Block Adapter> on virtio_pci1
virtio_pci1: host features: 0x79007e54 <EventIdx,RingIndirect,AnyLayout,NotifyOnEmpty,WriteZeroes,Discard,MultiQueue,ConfigWCE,Topology,FlushCommand,BlockSize,DiskGeometry,MaxNumSegs>
virtio_pci1: negotiated features: 0x10001a54 <RingIndirect,MultiQueue,ConfigWCE,FlushCommand,BlockSize,DiskGeometry,MaxNumSegs>
virtio_pci1: using 6 MSI-X vectors
vtblk0: Virtio: ncylinders at legacy maximum (16383), recalculating to 62415
vtblk0: Block size: 512
vtblk0: 30720MB (62914560 512 byte sectors: 16H 63S/T 16383C)
atkbdc0: <Keyboard controller (i8042)> port 0x64,0x60 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
psm0: <PS/2 Mouse> irq 12 on atkbdc0
interrupt uses mplock: psm0
psm0: model IntelliMouse Explorer, device ID 4
sio0: <16550A-compatible COM port> port 0x3f8-0x3ff irq 4 on acpi0
sio0: type 16550A, console
ACPI: Enabled 2 GPEs in block 00 to 0F
rdrand0: No RdRand support.
orm0: <ISA Option ROM> at iomem 0xe8000-0xeffff on isa1
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa1
sio2: can't drain, serial port might not exist, disabling
hpt27xx: no controller detected.
CAM: Configuring 2 busses
CAM: finished configuring all busses
cd0 at ata1 bus 0 target 0 lun 0
cd0: <QEMU QEMU DVD-ROM 2.5+> Removable CD-ROM SCSI-0 device
cd0: 16.000MB/s transfers
cd0: Attempt to query device size failed: NOT READY, Medium not present
Mounting root from hammer2:vbd0s1d
hammer2_mount: root devstr="vbd0s1d"
hammer2_mount: device="vbd0s1d" label="ROOT" rdonly=1
hammer2_ondisk: "/dev/vbd0s1d" zone=2 id=0 offset=0x0000000000000000 size=0x000000063f000000
hammer2_mount: hmp=0xfffff8008fae0000 pmp=0xfffff80090280000
DMA space used: 13580k, remaining available: 131072k
Mounting devfs
hammer2_mount: "vbd0s1d": no recovery needed
hammer2: enable read/write
HAMMER2: VOLDATA DUMP
HAMMER2: INITIATE SPANs
swap low/high-water marks set to 41940/62910
boot() called on cpu#2
Switching to cpu #0 for shutdown
Waiting (max 60 seconds) for system thread swapcached to stop...stopped
Waiting (max 60 seconds) for system thread vnlru to stop...stopped
Waiting (max 60 seconds) for system thread consttyd to stop...stopped
Waiting (max 60 seconds) for system thread bufdaemon to stop...stopped
Waiting (max 60 seconds) for system thread bufdaemon_hw to stop...stopped
syncing disks... 0 0 0
done
v-chain 0xfffff8008fb604c0 volume.0 0000000000000010 0000000000000000/0 mir=00000000000000cf
[00002000] (?) refs=1
f-chain 0xfffff8008fb60640 freemap.0 0000000000000010 0000000000000000/0 mir=00000000000000cf
[00002000] (?) refs=1
Uptime: 9m3s
Rebooting...
Copyright (c) 2003-2026 The DragonFly Project.
Copyright (c) 1992-2003 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
The Regents of the University of California. All rights reserved.
DragonFly 6.5-DEVELOPMENT #1: Mon Sep 7 20:24:03 UTC 2026
root@dfbsd:/usr/obj/usr/src/sys/X86_64_GENERIC
acpi_hpet: frequency 100000000
Using cputimer HPET for TSC calibration
Timer latency (in TSC ticks): 33284 min=31840 max=99286
TSC clock: 3600010589 Hz, NOT invariant
CPU: QEMU Virtual CPU version 2.5+ (3600.01-MHz K8-class CPU)
Origin="GenuineIntel" Id=0x60fb1 Family=0xf Model=0x6b Stepping=1
Features=0x1783fbfd<FPU,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE,SSE2,HTT>
Features2=0x80202001<SSE3,CX16,x2APIC,VMM>
AMD Features=0x20100800<SYSCALL,NX,LM>
AMD Features2=0x1<LAHF>
MONITOR/MWAIT Features=0x2<INTBRK>
VMM/Hypervisor: Origin="KVMKVMKVM"
real memory = 4293381120 (4094 MB)
avail memory = 4088795136 (3899 MB)
LAPIC: enter X2APIC mode
madt_lapic_probe: lapic_count=6 x2apic_count=0
ACPI CPUS = 6
lapic: divisor index 0, frequency 500002834 Hz
CPU Topology: cores_per_chip: 6; threads_per_core: 1; chips_per_package: 1;
srat_probe: can't locate SRAT
SMI Frequency (worst case): 45454 Hz (22 us)
Initialize MI interrupts for 6 cpus
TSC is not invariant, no further tests will be performed
Spectre: support=( none ) req=ffff operating=( none )
MDS: support=( none ) req=ffff operating=( none )
machdep.meltdown_mitigation enabled to protect against (mostly Intel) meltdown bug
system call performance will be impacted
interrupt uses mplock: swi_taskq
wdog: In-kernel automatic watchdog reset enabled
md0: Malloc disk
evdev device loaded.
kbd1 at kbdmux0
ACPI: RSDP 0x00000000000F59A0 000014 (v00 BOCHS )
ACPI: RSDT 0x00000000BFFE1CAC 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)
ACPI: FACP 0x00000000BFFE1B38 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)
ACPI: DSDT 0x00000000BFFE0040 001AF8 (v01 BOCHS BXPC 00000001 BXPC 00000001)
ACPI: FACS 0x00000000BFFE0000 000040
ACPI: APIC 0x00000000BFFE1BAC 0000A0 (v01 BOCHS BXPC 00000001 BXPC 00000001)
ACPI: HPET 0x00000000BFFE1C4C 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)
ACPI: WAET 0x00000000BFFE1C84 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)
sc0: <System console> on motherboard
sc0: VGA <16 virtual consoles, flags=0x100>
acpi0: <BOCHS BXPC> on motherboard
ACPI: 1 ACPI AML tables successfully acquired and loaded
ACPI FADT: SCI testing interrupt mode ...
ACPI FADT: SCI select level/high
acpi0: Power Button (fixed)
acpi_hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff on acpi0
acpi_timer0 on acpi0
isab0: <ACPI Generic ISA bridge> on acpi0
isa0: <ISA bus> on isab0
cpu0: <ACPI CPU> on acpi0
cpu_cst0: <ACPI CPU C-State> on cpu0
cpu1: <ACPI CPU> on acpi0
cpu_cst1: <ACPI CPU C-State> on cpu1
cpu2: <ACPI CPU> on acpi0
cpu_cst2: <ACPI CPU C-State> on cpu2
cpu3: <ACPI CPU> on acpi0
cpu_cst3: <ACPI CPU C-State> on cpu3
cpu4: <ACPI CPU> on acpi0
cpu_cst4: <ACPI CPU C-State> on cpu4
cpu5: <ACPI CPU> on acpi0
cpu_cst5: <ACPI CPU C-State> on cpu5
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
isab1: <PCI-ISA bridge> at device 1.0 on pci0
isa1: <ISA bus> on isab1
atapci0: <Intel PIIX3 WDMA2 controller> port 0xc0a0-0xc0af,0x376,0x170-0x177,0x3f6,0x1f0-0x1f7 at device 1.1 on pci0
ata0: <ATA channel 0> on atapci0
interrupt uses mplock: ata0
ata1: <ATA channel 1> on atapci0
interrupt uses mplock: ata1
acd0: DVDROM <QEMU DVD-ROM/2.5+> at ata1-master WDMA2
pci0: <bridge> (vendor 0x8086, dev 0x7113) at device 1.3 irq 9
vgapci0: <VGA-compatible display> mem 0xfebd0000-0xfebd0fff,0xfd000000-0xfdffffff at device 2.0 on pci0
vgapci0: Boot video device
virtio_pci0: <VirtIO PCI Network adapter> port 0xc080-0xc09f mem 0xfe000000-0xfe003fff,0xfebd1000-0xfebd1fff irq 11 at device 3.0 on pci0
vtnet0: <VirtIO Networking Adapter> on virtio_pci0
virtio_pci0: host features: 0x79bf8064 <EventIdx,RingIndirect,AnyLayout,NotifyOnEmpty,SetMacAddress,GuestAnnounce,RxModeExtra,VLanFilter,RxMode,ControlVq,Status,MrgRxBuf,TxAllGSO,MacAddress,DynOffload>
virtio_pci0: negotiated features: 0x198f8020 <RingIndirect,AnyLayout,NotifyOnEmpty,SetMacAddress,VLanFilter,RxMode,ControlVq,Status,MrgRxBuf,MacAddress>
virtio_pci0: using 3 MSI-X vectors
vtnet0: MAC address: 52:54:00:12:34:56
virtio_pci1: <VirtIO PCI Block adapter> port 0xc000-0xc07f mem 0xfe004000-0xfe007fff,0xfebd2000-0xfebd2fff irq 11 at device 4.0 on pci0
vtblk0: <VirtIO Block Adapter> on virtio_pci1
virtio_pci1: host features: 0x79007e54 <EventIdx,RingIndirect,AnyLayout,NotifyOnEmpty,WriteZeroes,Discard,MultiQueue,ConfigWCE,Topology,FlushCommand,BlockSize,DiskGeometry,MaxNumSegs>
virtio_pci1: negotiated features: 0x10001a54 <RingIndirect,MultiQueue,ConfigWCE,FlushCommand,BlockSize,DiskGeometry,MaxNumSegs>
virtio_pci1: using 6 MSI-X vectors
vtblk0: Virtio: ncylinders at legacy maximum (16383), recalculating to 62415
vtblk0: Block size: 512
vtblk0: 30720MB (62914560 512 byte sectors: 16H 63S/T 16383C)
atkbdc0: <Keyboard controller (i8042)> port 0x64,0x60 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
psm0: <PS/2 Mouse> irq 12 on atkbdc0
interrupt uses mplock: psm0
psm0: model IntelliMouse Explorer, device ID 4
sio0: <16550A-compatible COM port> port 0x3f8-0x3ff irq 4 on acpi0
sio0: type 16550A, console
ACPI: Enabled 2 GPEs in block 00 to 0F
rdrand0: No RdRand support.
orm0: <ISA Option ROM> at iomem 0xe8000-0xeffff on isa1
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa1
sio2: can't drain, serial port might not exist, disabling
hpt27xx: no controller detected.
CAM: Configuring 2 busses
CAM: finished configuring all busses
cd0 at ata1 bus 0 target 0 lun 0
cd0: <QEMU QEMU DVD-ROM 2.5+> Removable CD-ROM SCSI-0 device
cd0: 16.000MB/s transfers
cd0: Attempt to query device size failed: NOT READY, Medium not present
Mounting root from hammer2:vbd0s1d
hammer2_mount: root devstr="vbd0s1d"
hammer2_mount: device="vbd0s1d" label="ROOT" rdonly=1
hammer2_ondisk: "/dev/vbd0s1d" zone=0 id=0 offset=0x0000000000000000 size=0x000000063f000000
hammer2_mount: hmp=0xfffff8008fae0000 pmp=0xfffff80090280000
DMA space used: 13580k, remaining available: 131072k
Mounting devfs
hammer2_mount: "vbd0s1d": no recovery needed
hammer2: enable read/write
HAMMER2: VOLDATA DUMP
HAMMER2: INITIATE SPANs
swap low/high-water marks set to 41940/62910
DF3173: autofs mount /df3173_mp amp=0xfffff8008d5d7c00 root=0xfffff8008da90f00
DF3173: harness loaded; sysctl debug.df3173_mode=1|2 to start
DF3173: mode=1 starting (PANIC variant: foreign-queue STAILQ_REMOVE)
DF3173: R01 T0 creator=userstat id=0 ar=0xfffff80118745a00 home_tq=cpu3-queue key=k3173_r001
DF3173: R01 t0.0 req=alive id=0 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R01 t0.6 req=alive id=0 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: sleeper running on cpu3 until tick 8533 (now 8463)
DF3173: R01 w req=alive id=0 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R01 w req=alive id=0 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R01 w req=alive id=0 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R01 w req=alive id=0 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R01 w req=alive id=0 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: R01 w req=alive id=0 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: R01 w req=alive id=1 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: sleeper done at tick 8534
DF3173: R01 w req=alive id=1 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R01 w req=alive id=1 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R01 w req=alive id=1 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R01 w req=alive id=1 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R01 w req=alive id=1 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R01 w req=alive id=1 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R01 w req=alive id=1 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R01 w req=alive id=1 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R01 w req=alive id=1 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R01 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[0] obj=0xfffff80089944400 (request was 0xfffff801187dd000) different-block e0=0
DF3173: MARKER[1] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=0 done=0 (no post-get write this round)
DF3173: R01 freed-object resurfaced on cpu3 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R01 complete (request freed=yes)
DF3173: R02 T0 creator=userstat id=3 ar=0xfffff80118745a00 home_tq=cpu3-queue key=k3173_r002
DF3173: R02 t0.0 req=alive id=3 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 t0.6 req=alive id=3 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: sleeper running on cpu3 until tick 8881 (now 8811)
DF3173: R02 w req=alive id=3 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=alive id=3 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=alive id=3 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=alive id=3 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=alive id=3 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: R02 w req=alive id=3 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: R02 w req=alive id=3 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: sleeper done at tick 8881
DF3173: R02 w req=alive id=4 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=alive id=4 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=alive id=4 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=alive id=4 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=alive id=4 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=alive id=4 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=alive id=4 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=alive id=4 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=alive id=4 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=alive id=4 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R02 w req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: R02 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[2] obj=0xfffff8011880de00 (request was 0xfffff80118745a00) different-block e0=0
DF3173: MARKER[1] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[5] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=0 done=0 (no post-get write this round)
DF3173: R02 freed-object resurfaced on cpu3 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R02 complete (request freed=yes)
DF3173: R03 T0 creator=userstat id=8 ar=0xfffff80118785a00 home_tq=cpu1-queue key=k3173_r003
DF3173: R03 t0.0 req=alive id=8 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 t0.6 req=alive id=8 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 9319 (now 9249)
DF3173: R03 w req=alive id=8 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=8 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=8 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=8 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=8 ref=2 done=0 inprog=0 ta_pending=1 tq=cpu1 cflags=0
DF3173: R03 w req=alive id=8 ref=2 done=0 inprog=0 ta_pending=1 tq=cpu1 cflags=0
DF3173: R03 w req=alive id=8 ref=2 done=0 inprog=0 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 9320
DF3173: R03 w req=alive id=10 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=10 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=10 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=10 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=10 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=10 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=10 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=10 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=10 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=10 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=11 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R03 w req=alive id=11 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R03 w req=alive id=11 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R03 w req=alive id=11 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R03 w req=alive id=11 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R03 w req=alive id=11 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R03 w req=alive id=11 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R03 w req=alive id=11 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R03 w req=alive id=11 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R03 w req=alive id=11 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R03 w req=alive id=12 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=12 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=12 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=12 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 w req=alive id=12 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R03 post req=alive id=12 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: MARKER[3] obj=0xfffff80118747000 (request was 0xfffff80118788600) different-block e0=60
DF3173: MARKER[4] obj=0xfffff801187c0200 (request was 0xfffff80118788600) different-block e0=60
DF3173: MARKER[5] readback e=60 done=1 (no post-get write this round)
DF3173: MARKER[3] readback e=60 done=1 (no post-get write this round)
DF3173: MARKER[4] readback e=60 done=1 (no post-get write this round)
DF3173: R03 complete (request freed=NO (still queued?))
DF3173: R04 T0 creator=userstat id=13 ar=0xfffff8011878c800 home_tq=cpu1-queue key=k3173_r004
DF3173: R04 t0.0 req=alive id=13 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 t0.6 req=alive id=13 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 9832 (now 9762)
DF3173: R04 w req=alive id=13 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 w req=alive id=13 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 w req=alive id=13 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 w req=alive id=13 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 w req=alive id=13 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R04 w req=alive id=13 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R04 w req=alive id=15 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper done at tick 9832
DF3173: R04 w req=alive id=15 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 w req=alive id=15 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 w req=alive id=15 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 w req=alive id=15 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 w req=alive id=15 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 w req=alive id=15 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 w req=alive id=15 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 w req=alive id=15 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 w req=alive id=15 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R04 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[5] obj=0xfffff80118788600 (request was 0xfffff8011878b200) different-block e0=60
DF3173: MARKER[4] obj=0xfffff801187c2e00 (request was 0xfffff8011878b200) different-block e0=0
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[5] readback e=60 done=1 (no post-get write this round)
DF3173: MARKER[4] readback e=0 done=0 (no post-get write this round)
DF3173: R04 freed-object resurfaced on cpu2 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R04 complete (request freed=yes)
DF3173: R05 T0 creator=userstat id=18 ar=0xfffff80118785a00 home_tq=cpu5-queue key=k3173_r005
DF3173: R05 t0.0 req=alive id=18 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 t0.6 req=alive id=18 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: sleeper running on cpu5 until tick 10183 (now 10113)
DF3173: R05 w req=alive id=18 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=alive id=18 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=alive id=18 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=alive id=18 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=alive id=18 ref=2 done=0 inprog=0 ta_pending=1 tq=cpu5 cflags=0
DF3173: R05 w req=alive id=18 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu5 cflags=0
DF3173: R05 w req=alive id=18 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu5 cflags=0
DF3173: sleeper done at tick 10184
DF3173: R05 w req=alive id=19 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu4 cflags=1
DF3173: R05 w req=alive id=19 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu4 cflags=1
DF3173: R05 w req=alive id=19 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu4 cflags=1
DF3173: R05 w req=alive id=19 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu4 cflags=1
DF3173: R05 w req=alive id=19 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu4 cflags=1
DF3173: R05 w req=alive id=19 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu4 cflags=1
DF3173: R05 w req=alive id=19 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu4 cflags=1
DF3173: R05 w req=alive id=19 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu4 cflags=1
DF3173: R05 w req=alive id=19 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu4 cflags=1
DF3173: R05 w req=alive id=21 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=alive id=21 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=alive id=21 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=alive id=21 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=alive id=21 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=alive id=21 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=alive id=21 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=alive id=21 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=alive id=21 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=alive id=21 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu5 cflags=1
DF3173: R05 w req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: R05 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[3] obj=0xfffff80118749c00 (request was 0xfffff801187e0200) different-block e0=0
DF3173: MARKER[4] obj=0xfffff801187e0200 (request was 0xfffff801187e0200) SAME-BLOCK e0=60
DF3173: MARKER[5] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[2] readback e=60 done=1 (no post-get write this round)
DF3173: MARKER[3] readback e=0 done=0 (no post-get write this round)
DF3173: R05 freed-object resurfaced on cpu4 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R05 complete (request freed=yes)
DF3173: R06 T0 creator=userstat id=24 ar=0xfffff80118790a00 home_tq=cpu1-queue key=k3173_r006
DF3173: R06 t0.0 req=alive id=24 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 t0.6 req=alive id=24 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 10922 (now 10852)
DF3173: R06 w req=alive id=24 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 w req=alive id=24 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 w req=alive id=24 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 w req=alive id=24 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 w req=alive id=24 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R06 w req=alive id=24 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R06 w req=alive id=24 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 10922
DF3173: R06 w req=alive id=25 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 w req=alive id=25 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 w req=alive id=25 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 w req=alive id=25 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 w req=alive id=25 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 w req=alive id=25 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 w req=alive id=25 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 w req=alive id=25 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 w req=alive id=25 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 w req=alive id=25 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R06 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[5] obj=0xfffff801187e2e00 (request was 0xfffff80118792000) different-block e0=60
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=60 done=1 (no post-get write this round)
DF3173: MARKER[5] readback e=60 done=1 (no post-get write this round)
DF3173: R06 freed-object resurfaced on cpu3 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R06 complete (request freed=yes)
DF3173: R07 T0 creator=userstat id=28 ar=0xfffff80118796200 home_tq=cpu1-queue key=k3173_r007
DF3173: R07 t0.0 req=alive id=28 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R07 t0.6 req=alive id=28 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 11551 (now 11481)
DF3173: R07 w req=alive id=28 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R07 w req=alive id=28 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R07 w req=alive id=28 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R07 w req=alive id=28 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R07 w req=alive id=28 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R07 w req=alive id=28 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R07 w req=alive id=28 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 11551
DF3173: R07 w req=alive id=29 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R07 w req=alive id=29 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R07 w req=alive id=29 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R07 w req=alive id=29 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R07 w req=alive id=29 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R07 w req=alive id=29 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R07 w req=alive id=29 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R07 w req=alive id=29 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R07 w req=alive id=29 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R07 w req=alive id=29 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R07 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[3] obj=0xfffff80118790a00 (request was 0xfffff80118796200) different-block e0=0
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[3] readback e=0 done=1 (no post-get write this round)
DF3173: R07 freed-object resurfaced on cpu5 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R07 complete (request freed=yes)
DF3173: R08 T0 creator=userstat id=31 ar=0xfffff80118797800 home_tq=cpu1-queue key=k3173_r008
DF3173: R08 t0.0 req=alive id=31 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R08 t0.6 req=alive id=31 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 11909 (now 11839)
DF3173: R08 w req=alive id=31 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R08 w req=alive id=31 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R08 w req=alive id=31 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R08 w req=alive id=31 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R08 w req=alive id=31 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R08 w req=alive id=31 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R08 w req=alive id=31 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 11909
DF3173: R08 w req=alive id=32 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R08 w req=alive id=32 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R08 w req=alive id=32 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R08 w req=alive id=32 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R08 w req=alive id=32 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R08 w req=alive id=32 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R08 w req=alive id=32 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R08 w req=alive id=32 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R08 w req=alive id=32 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R08 w req=alive id=32 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R08 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[5] obj=0xfffff801187e4400 (request was 0xfffff80118797800) different-block e0=60
DF3173: MARKER[4] obj=0xfffff801187c5a00 (request was 0xfffff80118797800) different-block e0=0
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[5] readback e=60 done=1 (no post-get write this round)
DF3173: MARKER[4] readback e=0 done=0 (no post-get write this round)
DF3173: R08 freed-object resurfaced on cpu2 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R08 complete (request freed=yes)
DF3173: R09 T0 creator=userstat id=36 ar=0xfffff8011879d000 home_tq=cpu1-queue key=k3173_r009
DF3173: R09 t0.0 req=alive id=36 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R09 t0.6 req=alive id=36 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 12647 (now 12577)
DF3173: R09 w req=alive id=36 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R09 w req=alive id=36 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R09 w req=alive id=36 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R09 w req=alive id=36 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R09 w req=alive id=36 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R09 w req=alive id=36 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R09 w req=alive id=36 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 12647
DF3173: R09 w req=alive id=37 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R09 w req=alive id=37 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R09 w req=alive id=37 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R09 w req=alive id=37 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R09 w req=alive id=37 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R09 w req=alive id=37 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R09 w req=alive id=37 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R09 w req=alive id=37 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R09 w req=alive id=37 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R09 w req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: R09 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[5] obj=0xfffff801187e5a00 (request was 0xfffff8011879d000) different-block e0=0
DF3173: MARKER[1] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=60 done=1 <== WRITE-AFTER-FREE (e0 already 145 at get)
DF3173: MARKER[5] readback e=0 done=0 (no post-get write this round)
DF3173: R09 freed-object resurfaced on cpu4 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R09 complete (request freed=yes)
DF3173: R10 T0 creator=userstat id=39 ar=0xfffff8011874de00 home_tq=cpu3-queue key=k3173_r010
DF3173: R10 t0.0 req=alive id=39 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 t0.6 req=alive id=39 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: sleeper running on cpu3 until tick 13009 (now 12939)
DF3173: R10 w req=alive id=39 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=alive id=39 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=alive id=39 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=alive id=39 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=alive id=39 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: R10 w req=alive id=39 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: R10 w req=alive id=39 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: sleeper done at tick 13010
DF3173: R10 w req=alive id=40 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R10 w req=alive id=40 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R10 w req=alive id=40 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R10 w req=alive id=40 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R10 w req=alive id=40 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R10 w req=alive id=40 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R10 w req=alive id=40 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R10 w req=alive id=40 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R10 w req=alive id=40 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R10 w req=alive id=40 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R10 w req=alive id=41 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=alive id=41 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=alive id=41 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=alive id=41 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=alive id=41 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=alive id=41 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=alive id=41 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=alive id=41 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=alive id=41 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=alive id=41 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R10 w req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: R10 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[5] obj=0xfffff801187e7000 (request was 0xfffff8011874f400) different-block e0=0
DF3173: MARKER[4] obj=0xfffff801187c7000 (request was 0xfffff8011874f400) different-block e0=60
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[5] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=60 done=1 (no post-get write this round)
DF3173: R10 freed-object resurfaced on cpu1 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R10 complete (request freed=yes)
DF3173: R11 T0 creator=userstat id=44 ar=0xfffff80118752000 home_tq=cpu3-queue key=k3173_r011
DF3173: R11 t0.0 req=alive id=44 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R11 t0.6 req=alive id=44 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: sleeper running on cpu3 until tick 13748 (now 13678)
DF3173: R11 w req=alive id=44 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R11 w req=alive id=44 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R11 w req=alive id=44 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R11 w req=alive id=44 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R11 w req=alive id=44 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: R11 w req=alive id=44 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: R11 w req=alive id=44 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: sleeper done at tick 13748
DF3173: R11 w req=alive id=45 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R11 w req=alive id=45 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R11 w req=alive id=45 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R11 w req=alive id=45 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R11 w req=alive id=45 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R11 w req=alive id=45 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R11 w req=alive id=45 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R11 w req=alive id=45 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R11 w req=alive id=45 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu5 cflags=1
DF3173: R11 w req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: R11 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[3] obj=0xfffff801187e8600 (request was 0xfffff801187e8600) SAME-BLOCK e0=60
DF3173: MARKER[4] obj=0xfffff801187c8600 (request was 0xfffff801187e8600) different-block e0=0
DF3173: MARKER[1] readback e=60 done=1 (no post-get write this round)
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=0 done=0 (no post-get write this round)
DF3173: R11 freed-object resurfaced on cpu3 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R11 complete (request freed=yes)
DF3173: R12 T0 creator=userstat id=47 ar=0xfffff80118752000 home_tq=cpu3-queue key=k3173_r012
DF3173: R12 t0.0 req=alive id=47 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 t0.6 req=alive id=47 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: sleeper running on cpu3 until tick 14110 (now 14040)
DF3173: R12 w req=alive id=47 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=alive id=47 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=alive id=47 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=alive id=47 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=alive id=47 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: R12 w req=alive id=47 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: R12 w req=alive id=47 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: sleeper done at tick 14111
DF3173: R12 w req=alive id=48 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R12 w req=alive id=48 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R12 w req=alive id=48 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R12 w req=alive id=48 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R12 w req=alive id=48 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R12 w req=alive id=48 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R12 w req=alive id=48 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R12 w req=alive id=48 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R12 w req=alive id=48 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R12 w req=alive id=48 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R12 w req=alive id=50 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=alive id=50 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=alive id=50 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=alive id=50 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=alive id=50 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=alive id=50 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=alive id=50 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=alive id=50 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=alive id=50 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=alive id=50 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R12 w req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: R12 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[5] obj=0xfffff801187eb200 (request was 0xfffff80118753600) different-block e0=60
DF3173: MARKER[4] obj=0xfffff801187c9c00 (request was 0xfffff80118753600) different-block e0=0
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[5] readback e=60 done=1 (no post-get write this round)
DF3173: MARKER[4] readback e=0 done=0 (no post-get write this round)
DF3173: R12 freed-object resurfaced on cpu2 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R12 complete (request freed=yes)
DF3173: R13 T0 creator=userstat id=53 ar=0xfffff80118757800 home_tq=cpu3-queue key=k3173_r013
DF3173: R13 t0.0 req=alive id=53 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R13 t0.6 req=alive id=53 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: sleeper running on cpu3 until tick 14849 (now 14779)
DF3173: R13 w req=alive id=53 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R13 w req=alive id=53 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R13 w req=alive id=53 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R13 w req=alive id=53 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R13 w req=alive id=53 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: R13 w req=alive id=53 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: R13 w req=alive id=53 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu3 cflags=0
DF3173: sleeper done at tick 14850
DF3173: R13 w req=alive id=54 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R13 w req=alive id=54 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R13 w req=alive id=54 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R13 w req=alive id=54 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R13 w req=alive id=54 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R13 w req=alive id=54 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R13 w req=alive id=54 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R13 w req=alive id=54 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R13 w req=alive id=54 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R13 w req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: R13 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[4] obj=0xfffff80118757800 (request was 0xfffff80118757800) SAME-BLOCK e0=60
DF3173: MARKER[1] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=60 done=1 <== WRITE-AFTER-FREE (e0 already 145 at get)
DF3173: R13 freed-object resurfaced on cpu4 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R13 complete (request freed=yes)
DF3173: R14 T0 creator=userstat id=56 ar=0xfffff80118781800 home_tq=cpu1-queue key=k3173_r014
DF3173: R14 t0.0 req=alive id=56 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 t0.6 req=alive id=56 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 15211 (now 15141)
DF3173: R14 w req=alive id=56 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=56 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=56 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=56 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=56 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R14 w req=alive id=56 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R14 w req=alive id=56 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 15211
DF3173: R14 w req=alive id=57 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=57 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=57 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=57 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=57 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=57 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=57 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=57 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=57 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=57 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=58 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=58 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=58 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=58 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=58 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=58 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=58 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=58 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=58 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=58 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R14 w req=alive id=59 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=59 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=59 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=59 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 w req=alive id=59 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R14 post req=alive id=59 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: MARKER[2] obj=0xfffff80118816200 (request was 0xfffff80118758e00) different-block e0=0
DF3173: MARKER[4] obj=0xfffff801187cb200 (request was 0xfffff80118758e00) different-block e0=60
DF3173: MARKER[5] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[3] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[2] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=60 done=1 (no post-get write this round)
DF3173: R14 complete (request freed=NO (still queued?))
DF3173: R15 T0 creator=userstat id=61 ar=0xfffff80118758e00 home_tq=cpu1-queue key=k3173_r015
DF3173: R15 t0.0 req=alive id=61 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 t0.6 req=alive id=61 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 15848 (now 15778)
DF3173: R15 w req=alive id=61 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 w req=alive id=61 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 w req=alive id=61 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 w req=alive id=61 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 w req=alive id=61 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R15 w req=alive id=61 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R15 w req=alive id=61 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 15848
DF3173: R15 w req=alive id=62 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 w req=alive id=62 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 w req=alive id=62 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 w req=alive id=62 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 w req=alive id=62 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 w req=alive id=62 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 w req=alive id=62 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 w req=alive id=62 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 w req=alive id=62 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 w req=alive id=62 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R15 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[0] obj=0xfffff80089957800 (request was 0xfffff8011889e600) different-block e0=0
DF3173: MARKER[1] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: R15 freed-object resurfaced on cpu5 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R15 complete (request freed=yes)
DF3173: R16 T0 creator=userstat id=65 ar=0xfffff80118882e00 home_tq=cpu1-queue key=k3173_r016
DF3173: R16 t0.0 req=alive id=65 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 t0.6 req=alive id=65 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 16482 (now 16412)
DF3173: R16 w req=alive id=65 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 w req=alive id=65 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 w req=alive id=65 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 w req=alive id=65 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 w req=alive id=65 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R16 w req=alive id=65 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R16 w req=alive id=65 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 16482
DF3173: R16 w req=alive id=66 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 w req=alive id=66 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 w req=alive id=66 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 w req=alive id=66 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 w req=alive id=66 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 w req=alive id=66 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 w req=alive id=66 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 w req=alive id=66 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 w req=alive id=66 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 w req=alive id=66 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R16 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[5] obj=0xfffff80118758e00 (request was 0xfffff80118881800) different-block e0=0
DF3173: MARKER[4] obj=0xfffff801187cde00 (request was 0xfffff80118881800) different-block e0=0
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[5] readback e=0 done=1 (no post-get write this round)
DF3173: MARKER[4] readback e=0 done=0 (no post-get write this round)
DF3173: R16 freed-object resurfaced on cpu2 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R16 complete (request freed=yes)
DF3173: R17 T0 creator=userstat id=71 ar=0xfffff80118887000 home_tq=cpu1-queue key=k3173_r017
DF3173: R17 t0.0 req=alive id=71 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R17 t0.6 req=alive id=71 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 17413 (now 17343)
DF3173: R17 w req=alive id=71 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R17 w req=alive id=71 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R17 w req=alive id=71 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R17 w req=alive id=71 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R17 w req=alive id=71 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R17 w req=alive id=71 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R17 w req=alive id=71 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 17413
DF3173: R17 w req=alive id=72 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R17 w req=alive id=72 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R17 w req=alive id=72 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R17 w req=alive id=72 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R17 w req=alive id=72 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R17 w req=alive id=72 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R17 w req=alive id=72 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R17 w req=alive id=72 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R17 w req=alive id=72 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu4 cflags=1
DF3173: R17 w req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: R17 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[5] obj=0xfffff801187ef400 (request was 0xfffff80118887000) different-block e0=0
DF3173: MARKER[1] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=60 done=1 <== WRITE-AFTER-FREE (e0 already 145 at get)
DF3173: MARKER[5] readback e=0 done=0 (no post-get write this round)
DF3173: R17 freed-object resurfaced on cpu4 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R17 complete (request freed=yes)
DF3173: R18 T0 creator=userstat id=74 ar=0xfffff80118888600 home_tq=cpu1-queue key=k3173_r018
DF3173: R18 t0.0 req=alive id=74 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 t0.6 req=alive id=74 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 17773 (now 17703)
DF3173: R18 w req=alive id=74 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=74 ref=2 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=74 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=74 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=74 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R18 w req=alive id=74 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R18 w req=alive id=74 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 17773
DF3173: R18 w req=alive id=75 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=75 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=75 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=75 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=75 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=75 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=75 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=75 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=75 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=75 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=76 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=76 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=76 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=76 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=76 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=76 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=76 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=76 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=76 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=76 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R18 w req=alive id=77 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=77 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=77 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=77 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 w req=alive id=77 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: R18 post req=alive id=77 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu3 cflags=1
DF3173: MARKER[0] obj=0xfffff801188be600 (request was 0xfffff80118740200) different-block e0=0
DF3173: MARKER[4] obj=0xfffff801187cf400 (request was 0xfffff80118740200) different-block e0=60
DF3173: MARKER[5] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[3] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=60 done=1 (no post-get write this round)
DF3173: R18 complete (request freed=NO (still queued?))
DF3173: R19 T0 creator=userstat id=79 ar=0xfffff80118740200 home_tq=cpu1-queue key=k3173_r019
DF3173: R19 t0.0 req=alive id=79 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 t0.6 req=alive id=79 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 18410 (now 18340)
DF3173: R19 w req=alive id=79 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 w req=alive id=79 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 w req=alive id=79 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 w req=alive id=79 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 w req=alive id=79 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R19 w req=alive id=79 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R19 w req=alive id=79 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 18410
DF3173: R19 w req=alive id=80 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 w req=alive id=80 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 w req=alive id=80 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 w req=alive id=80 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 w req=alive id=80 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 w req=alive id=80 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 w req=alive id=80 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 w req=alive id=80 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 w req=alive id=80 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 w req=alive id=80 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R19 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[0] obj=0xfffff801188a0200 (request was 0xfffff80118889c00) different-block e0=0
DF3173: MARKER[1] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=0 done=0 (no post-get write this round)
DF3173: R19 freed-object resurfaced on cpu5 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R19 complete (request freed=yes)
DF3173: R20 T0 creator=userstat id=83 ar=0xfffff8011888de00 home_tq=cpu1-queue key=k3173_r020
DF3173: R20 t0.0 req=alive id=83 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 t0.6 req=alive id=83 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 19046 (now 18976)
DF3173: R20 w req=alive id=83 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=83 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=83 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=83 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=83 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R20 w req=alive id=83 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R20 w req=alive id=83 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 19046
DF3173: R20 w req=alive id=84 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=84 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=84 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=84 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=84 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=84 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=84 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=84 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=84 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=84 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=85 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=85 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=85 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=85 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=85 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=85 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=85 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=85 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=85 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=85 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R20 w req=alive id=86 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=86 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=86 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=86 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 w req=alive id=86 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: R20 post req=alive id=86 ref=1 done=0 inprog=1 ta_pending=0 tq=cpu2 cflags=1
DF3173: MARKER[5] obj=0xfffff80118740200 (request was 0xfffff8011881ba00) different-block e0=0
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=0 done=0 (no post-get write this round)
DF3173: R20 complete (request freed=NO (still queued?))
DF3173: R21 T0 creator=userstat id=88 ar=0xfffff8011881ba00 home_tq=cpu1-queue key=k3173_r021
DF3173: R21 t0.0 req=alive id=88 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 t0.6 req=alive id=88 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 19675 (now 19605)
DF3173: R21 w req=alive id=88 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 w req=alive id=88 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 w req=alive id=88 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 w req=alive id=88 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 w req=alive id=88 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R21 w req=alive id=88 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R21 w req=alive id=88 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 19675
DF3173: R21 w req=alive id=89 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 w req=alive id=89 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 w req=alive id=89 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 w req=alive id=89 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 w req=alive id=89 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 w req=alive id=89 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 w req=alive id=89 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 w req=alive id=89 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 w req=alive id=89 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 w req=alive id=89 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R21 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[5] obj=0xfffff801187f2000 (request was 0xfffff8011888c800) different-block e0=0
DF3173: MARKER[4] obj=0xfffff8011888c800 (request was 0xfffff8011888c800) SAME-BLOCK e0=60
DF3173: MARKER[1] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=60 done=1 <== WRITE-AFTER-FREE (e0 already 145 at get)
DF3173: R21 freed-object resurfaced on cpu4 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R21 complete (request freed=yes)
DF3173: R22 T0 creator=userstat id=92 ar=0xfffff80118892000 home_tq=cpu1-queue key=k3173_r022
DF3173: R22 t0.0 req=alive id=92 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 t0.6 req=alive id=92 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 20306 (now 20236)
DF3173: R22 w req=alive id=92 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 w req=alive id=92 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 w req=alive id=92 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 w req=alive id=92 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 w req=alive id=92 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R22 w req=alive id=92 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R22 w req=alive id=92 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 20306
DF3173: R22 w req=alive id=93 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 w req=alive id=93 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 w req=alive id=93 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 w req=alive id=93 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 w req=alive id=93 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 w req=alive id=93 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 w req=alive id=93 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 w req=alive id=93 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 w req=alive id=93 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 w req=alive id=93 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R22 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[0] obj=0xfffff801188a4400 (request was 0xfffff80118890a00) different-block e0=0
DF3173: MARKER[4] readback e=0 done=1 (no post-get write this round)
DF3173: MARKER[1] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: R22 freed-object resurfaced on cpu3 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R22 complete (request freed=yes)
DF3173: R23 T0 creator=userstat id=98 ar=0xfffff80118896200 home_tq=cpu1-queue key=k3173_r023
DF3173: R23 t0.0 req=alive id=98 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 t0.6 req=alive id=98 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 21235 (now 21165)
DF3173: R23 w req=alive id=98 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 w req=alive id=98 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 w req=alive id=98 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 w req=alive id=98 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 w req=alive id=98 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R23 w req=alive id=98 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R23 w req=alive id=98 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 21235
DF3173: R23 w req=alive id=99 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 w req=alive id=99 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 w req=alive id=99 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 w req=alive id=99 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 w req=alive id=99 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 w req=alive id=99 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 w req=alive id=99 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 w req=alive id=99 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 w req=alive id=99 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R23 w req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: R23 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[0] obj=0xfffff801188a5a00 (request was 0xfffff80118894c00) different-block e0=0
DF3173: MARKER[4] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[1] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: R23 freed-object resurfaced on cpu5 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R23 complete (request freed=yes)
DF3173: R24 T0 creator=userstat id=102 ar=0xfffff8011889a400 home_tq=cpu1-queue key=k3173_r024
DF3173: R24 t0.0 req=alive id=102 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 t0.6 req=alive id=102 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 21865 (now 21795)
DF3173: R24 w req=alive id=102 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 w req=alive id=102 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 w req=alive id=102 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 w req=alive id=102 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 w req=alive id=102 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R24 w req=alive id=102 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R24 w req=alive id=102 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 21865
DF3173: R24 w req=alive id=103 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 w req=alive id=103 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 w req=alive id=103 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 w req=alive id=103 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 w req=alive id=103 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 w req=alive id=103 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 w req=alive id=103 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 w req=alive id=103 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 w req=alive id=103 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 w req=alive id=103 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R24 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[0] obj=0xfffff801188a7000 (request was 0xfffff80118898e00) different-block e0=0
DF3173: MARKER[4] obj=0xfffff801187d4c00 (request was 0xfffff80118898e00) different-block e0=0
DF3173: MARKER[1] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=0 done=0 (no post-get write this round)
DF3173: R24 freed-object resurfaced on cpu2 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R24 complete (request freed=yes)
DF3173: R25 T0 creator=userstat id=108 ar=0xfffff80118936200 home_tq=cpu1-queue key=k3173_r025
DF3173: R25 t0.0 req=alive id=108 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 t0.6 req=alive id=108 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 22795 (now 22725)
DF3173: R25 w req=alive id=108 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 w req=alive id=108 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 w req=alive id=108 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 w req=alive id=108 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 w req=alive id=108 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R25 w req=alive id=108 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R25 w req=alive id=108 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 22795
DF3173: R25 w req=alive id=109 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 w req=alive id=109 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 w req=alive id=109 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 w req=alive id=109 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 w req=alive id=109 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 w req=alive id=109 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 w req=alive id=109 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 w req=alive id=109 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 w req=alive id=109 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 w req=alive id=109 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R25 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[5] obj=0xfffff801187f4c00 (request was 0xfffff80118937800) different-block e0=0
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[5] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[4] readback e=60 done=1 <== WRITE-AFTER-FREE (e0 already 145 at get)
DF3173: R25 freed-object resurfaced on cpu4 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R25 complete (request freed=yes)
DF3173: R26 T0 creator=userstat id=112 ar=0xfffff8011893ba00 home_tq=cpu1-queue key=k3173_r026
DF3173: R26 t0.0 req=alive id=112 ref=1 done=0 inprog=0 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 t0.6 req=alive id=112 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: sleeper running on cpu1 until tick 23425 (now 23355)
DF3173: R26 w req=alive id=112 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 w req=alive id=112 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 w req=alive id=112 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 w req=alive id=112 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 w req=alive id=112 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R26 w req=alive id=112 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: R26 w req=alive id=112 ref=2 done=0 inprog=1 ta_pending=1 tq=cpu1 cflags=0
DF3173: sleeper done at tick 23425
DF3173: R26 w req=alive id=113 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 w req=alive id=113 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 w req=alive id=113 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 w req=alive id=113 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 w req=alive id=113 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 w req=alive id=113 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 w req=alive id=113 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 w req=alive id=113 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 w req=alive id=113 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 w req=alive id=113 ref=2 done=0 inprog=1 ta_pending=0 tq=cpu1 cflags=1
DF3173: R26 post req=GONE id=-1 ref=-1 done=-1 inprog=-1 ta_pending=-1 tq=cpu-2 cflags=ffffffff
DF3173: MARKER[0] obj=0xfffff801188a9c00 (request was 0xfffff8011893a400) different-block e0=0
DF3173: MARKER[1] readback e=0 done=0 (no post-get write this round)
DF3173: MARKER[0] readback e=0 done=0 (no post-get write this round)
DF3173: R26 freed-object resurfaced on cpu3 magazine (home=cpu-1) <== CROSS-CPU FREE
DF3173: R26 complete (request freed=yes)
DF3173: R27 creator never created?!
DF3173: R28 creator never created?!
DF3173: R29 creator never created?!
DF3173: R30 creator never created?!