/nix/store/hxk8cpr7syf9nz1w56q56dbvhzyl9sl4-vm-test-run-cardano-node-dbtools-test.drv

Machine state will be reset. To keep it, pass --keep-vm-state
start all VLans
start vlan
running vlan (pid 7; ctl /build/vde1.ctl)
(finished: start all VLans, in 0.00 seconds)
Test will time out and terminate in 30 seconds
run the VM test script
additionally exposed symbols:
    machine,
    vlan1,
    start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine, t, debug
start all VMs
machine: starting vm
mke2fs 1.47.3 (8-Jul-2025)
machine: QEMU running (pid 9)
machine # Disk image do not exist, creating the virtualisation disk image...
(finished: start all VMs, in 0.96 seconds)
machine # Formatting '/build/vm-state-machine/tmp.nADWk9mvrk', fmt=raw size=1073741824
machine: must succeed: cardano-node --version
machine # Discarding device blocks:      0/262144�������������             �������������done
machine: waiting for the VM to finish booting
machine # Creating filesystem with 262144 4k blocks and 65536 inodes
machine # Filesystem UUID: 9f0a4276-7853-424a-95c0-dd6e1aacbf9c
machine # Superblock backups stored on blocks:
machine #       32768, 98304, 163840, 229376
machine # 
machine # Allocating group tables: 0/8���   ���done
machine # Writing inode tables: 0/8���   ���done
machine # Creating journal (8192 blocks): done
machine # Writing superblocks and filesystem accounting information: 0/8���   ���done
machine # 
machine # Virtualisation disk image created.
machine # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]
machine # [    0.000000] Linux version 6.12.49 (nixbld@localhost) (gcc (GCC) 14.3.0, GNU ld (GNU Binutils) 2.44) #1-NixOS SMP Thu Sep 25 09:13:51 UTC 2025
machine # [    0.000000] KASLR enabled
machine # [    0.000000] random: crng init done
machine # [    0.000000] Machine model: linux,dummy-virt
machine # [    0.000000] efi: UEFI not found.
machine # [    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT
machine # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]
machine # [    0.000000] NODE_DATA(0) allocated [mem 0x7fc05780-0x7fc08ebf]
machine # [    0.000000] Zone ranges:
machine # [    0.000000]   DMA      [mem 0x0000000040000000-0x000000007fffffff]
machine # [    0.000000]   DMA32    empty
machine # [    0.000000]   Normal   empty
machine # [    0.000000]   Device   empty
machine # [    0.000000] Movable zone start for each node
machine # [    0.000000] Early memory node ranges
machine # [    0.000000]   node   0: [mem 0x0000000040000000-0x000000007fffffff]
machine # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]
machine # [    0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 on node -1
machine # [    0.000000] psci: probing for conduit method from DT.
machine # [    0.000000] psci: PSCIv1.1 detected in firmware.
machine # [    0.000000] psci: Using standard PSCI v0.2 function IDs
machine # [    0.000000] psci: Trusted OS migration not required
machine # [    0.000000] psci: SMC Calling Convention v1.1
machine # [    0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)
machine # [    0.000000] percpu: Embedded 88 pages/cpu s180120 r65536 d114792 u360448
machine # [    0.000000] Detected PIPT I-cache on CPU0
machine # [    0.000000] CPU features: detected: GIC system register CPU interface
machine # [    0.000000] CPU features: detected: Spectre-v4
machine # [    0.000000] CPU features: detected: Spectre-BHB
machine # [    0.000000] CPU features: kernel page table isolation forced ON by KASLR
machine # [    0.000000] CPU features: detected: Kernel page table isolation (KPTI)
machine # [    0.000000] CPU features: detected: ARM erratum 1418040
machine # [    0.000000] CPU features: detected: SSBS not fully self-synchronizing
machine # [    0.000000] alternatives: applying boot alternatives
machine # [    0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/7qx5rsnv2160i39db4krsarjn3gqaxwb-nixos-system-machine-test/init regInfo=/nix/store/fwclvhw4b25pp9a3ndxr20glsni8bd9q-closure-info/registration console=ttyAMA0,115200n8 console=tty0
machine # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/store/fwclvhw4b25pp9a3ndxr20glsni8bd9q-closure-info/registration", will be passed to user space.
machine # [    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
machine # [    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
machine # [    0.000000] Fallback order for Node 0: 0
machine # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 262144
machine # [    0.000000] Policy zone: DMA
machine # [    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
machine # [    0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB
machine # [    0.000000] software IO TLB: area num 1.
machine # [    0.000000] software IO TLB: mapped [mem 0x000000007c8b7000-0x000000007c9b7000] (1MB)
machine # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
machine # [    0.000000] allocated 2097152 bytes of page_ext
machine # [    0.000000] ftrace: allocating 72218 entries in 283 pages
machine # [    0.000000] ftrace: allocated 283 pages with 5 groups
machine # [    0.000000] rcu: Hierarchical RCU implementation.
machine # [    0.000000] rcu:   RCU event tracing is enabled.
machine # [    0.000000] rcu:   RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
machine # [    0.000000]        Rude variant of Tasks RCU enabled.
machine # [    0.000000]        Tracing variant of Tasks RCU enabled.
machine # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
machine # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
machine # [    0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
machine # [    0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
machine # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
machine # [    0.000000] GICv3: 256 SPIs implemented
machine # [    0.000000] GICv3: 0 Extended SPIs implemented
machine # [    0.000000] Root IRQ handler: gic_handle_irq
machine # [    0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI
machine # [    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0
machine # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000
machine # [    0.000000] ITS [mem 0x08080000-0x0809ffff]
machine # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @448d0000 (indirect, esz 8, psz 64K, shr 1)
machine # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @448e0000 (flat, esz 8, psz 64K, shr 1)
machine # [    0.000000] GICv3: using LPI property table @0x00000000448f0000
machine # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044900000
machine # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
machine # [    0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040
machine # [    0.000000] arch_timer: cp15 timer(s) running at 25.00MHz (virt).
machine # [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns
machine # [    0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns
machine # [    0.000047] arm-pv: using stolen time PV
machine # [    0.000286] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
machine # [    0.000492] Console: colour dummy device 80x25
machine # [    0.000503] printk: legacy console [tty0] enabled
machine # [    0.000698] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)
machine # [    0.000702] pid_max: default: 32768 minimum: 301
machine # [    0.000767] LSM: initializing lsm=capability,landlock,yama,bpf
machine # [    0.000880] landlock: Up and running.
machine # [    0.000882] Yama: becoming mindful.
machine # [    0.001215] LSM support for eBPF active
machine # [    0.001579] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
machine # [    0.001595] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
machine # [    0.003019] cacheinfo: Unable to detect cache hierarchy for CPU 0
machine # [    0.007271] rcu: Hierarchical SRCU implementation.
machine # [    0.007276] rcu:   Max phase no-delay instances is 1000.
machine # [    0.008116] fsl-mc MSI: its@8080000 domain created
machine # [    0.008281] EFI services will not be available.
machine # [    0.008348] smp: Bringing up secondary CPUs ...
machine # [    0.008363] smp: Brought up 1 node, 1 CPU
machine # [    0.008366] SMP: Total of 1 processors activated.
machine # [    0.008367] CPU: All CPU(s) started at EL1
machine # [    0.008373] CPU features: detected: 32-bit EL0 Support
machine # [    0.008378] CPU features: detected: Data cache clean to the PoU not required for I/D coherence
machine # [    0.008381] CPU features: detected: Common not Private translations
machine # [    0.008383] CPU features: detected: CRC32 instructions
machine # [    0.008385] CPU features: detected: Enhanced Virtualization Traps
machine # [    0.008394] CPU features: detected: RCpc load-acquire (LDAPR)
machine # [    0.008396] CPU features: detected: LSE atomic instructions
machine # [    0.008398] CPU features: detected: Privileged Access Never
machine # [    0.008399] CPU features: detected: RAS Extension Support
machine # [    0.008402] CPU features: detected: Speculative Store Bypassing Safe (SSBS)
machine # [    0.008419] alternatives: applying system-wide alternatives
machine # [    0.010525] CPU features: detected: Hardware dirty bit management on CPU0
machine # [    0.010690] Memory: 912132K/1048576K available (23168K kernel code, 6540K rwdata, 25008K rodata, 4416K init, 1047K bss, 95248K reserved, 32768K cma-reserved)
machine # [    0.011160] devtmpfs: initialized
machine # [    0.012249] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
machine # [    0.012267] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
machine # [    0.012426] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL
machine # [    0.012429] 0 pages in range for non-PLT usage
machine # [    0.012430] 509184 pages in range for PLT usage
machine # [    0.012524] pinctrl core: initialized pinctrl subsystem
machine # [    0.013169] DMI not present or invalid.
machine # [    0.018294] NET: Registered PF_NETLINK/PF_ROUTE protocol family
machine # [    0.024337] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
machine # [    0.024491] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
machine # [    0.024656] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
machine # [    0.024676] audit: initializing netlink subsys (disabled)
machine # [    0.025460] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1
machine # [    0.025875] thermal_sys: Registered thermal governor 'step_wise'
machine # [    0.025877] thermal_sys: Registered thermal governor 'power_allocator'
machine # [    0.025906] cpuidle: using governor ladder
machine # [    0.025911] cpuidle: using governor menu
machine # [    0.026019] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
machine # [    0.026040] ASID allocator initialised with 32768 entries
machine # [    0.026556] Serial: AMBA PL011 UART driver
machine # [    0.029142] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1
machine # [    0.029209] printk: legacy console [ttyAMA0] enabled
machine # [    0.093186] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
machine # [    0.093724] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page
machine # [    0.094214] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages
machine # [    0.094707] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page
machine # [    0.095165] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
machine # [    0.095654] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page
machine # [    0.096110] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages
machine # [    0.096612] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page
machine # [    0.102297] fbcon: Taking over console
machine # [    0.102633] ACPI: Interpreter disabled.
machine # [    0.105845] iommu: Default domain type: Translated
machine # [    0.106235] iommu: DMA domain TLB invalidation policy: strict mode
machine # [    0.107131] SCSI subsystem initialized
machine # [    0.107647] usbcore: registered new interface driver usbfs
machine # [    0.108090] usbcore: registered new interface driver hub
machine # [    0.108512] usbcore: registered new device driver usb
machine # [    0.109086] pps_core: LinuxPPS API ver. 1 registered
machine # [    0.109506] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
machine # [    0.113520] PTP clock support registered
machine # [    0.113870] EDAC MC: Ver: 3.0.0
machine # [    0.114332] scmi_core: SCMI protocol bus registered
machine # [    0.114960] FPGA manager framework
machine # [    0.115303] Advanced Linux Sound Architecture Driver Initialized.
machine # [    0.117937] vgaarb: loaded
machine # [    0.119521] clocksource: Switched to clocksource arch_sys_counter
machine # [    0.120313] VFS: Disk quotas dquot_6.6.0
machine # [    0.120641] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
machine # [    0.121279] netfs: FS-Cache loaded
machine # [    0.122712] pnp: PnP ACPI: disabled
machine # [    0.203080] NET: Registered PF_INET protocol family
machine # [    0.205254] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
machine # [    0.233471] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
machine # [    0.234154] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
machine # [    0.234725] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
machine # [    0.235331] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
machine # [    0.235975] TCP: Hash tables configured (established 8192 bind 8192)
machine # [    0.236630] MPTCP token hash table entries: 1024 (order: 2, 24576 bytes, linear)
machine # [    0.237208] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
machine # [    0.237726] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
machine # [    0.238395] NET: Registered PF_UNIX/PF_LOCAL protocol family
machine # [    0.238853] NET: Registered PF_XDP protocol family
machine # [    0.239217] PCI: CLS 0 bytes, default 64
machine # [    0.239638] Trying to unpack rootfs image as initramfs...
machine # [    0.247634] kvm [1]: HYP mode not available
machine # [    0.282270] Initialise system trusted keyrings
machine # [    0.283815] workingset: timestamp_bits=42 max_order=18 bucket_order=0
machine # [    0.287993] squashfs: version 4.0 (2009/01/31) Phillip Lougher
machine # [    0.288501] 9p: Installing v9fs 9p2000 file system support
machine # [    0.605340] Key type asymmetric registered
machine # [    0.605805] Asymmetric key parser 'x509' registered
machine # [    0.606624] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)
machine # [    0.608043] io scheduler mq-deadline registered
machine # [    0.608418] io scheduler kyber registered
machine # [    0.612082] pl061_gpio 9030000.pl061: PL061 GPIO chip registered
machine # [    0.612850] ledtrig-cpu: registered to indicate activity on CPUs
machine # [    0.613685] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:
machine # [    0.614278] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
machine # [    0.614943] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
machine # [    0.615624] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000
machine # [    0.616318] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits
machine # [    0.616963] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]
machine # [    0.617707] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00
machine # [    0.618262] pci_bus 0000:00: root bus resource [bus 00-ff]
machine # [    0.618672] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
machine # [    0.619122] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]
machine # [    0.623839] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]
machine # [    0.624502] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint
machine # [    0.625484] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
machine # [    0.626129] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]
machine # [    0.626535] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]
machine # [    0.627018] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
machine # [    0.627575] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]
machine # [    0.632115] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
machine # [    0.632775] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]
machine # [    0.633183] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]
machine # [    0.633665] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
machine # [    0.634439] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
machine # [    0.635076] pci 0000:00:03.0: BAR 0 [io  0x0000-0x003f]
machine # [    0.635471] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]
machine # [    0.636011] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
machine # [    0.643879] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
machine # [    0.644536] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]
machine # [    0.644930] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]
machine # [    0.645410] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
machine # [    0.646183] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
machine # [    0.646826] pci 0000:00:05.0: BAR 0 [io  0x0000-0x001f]
machine # [    0.647239] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]
machine # [    0.647742] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
machine # [    0.652007] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
machine # [    0.652650] pci 0000:00:06.0: BAR 0 [io  0x0000-0x007f]
machine # [    0.653048] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]
machine # [    0.653537] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
machine # [    0.654464] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
machine # [    0.655107] pci 0000:00:07.0: BAR 0 [io  0x0000-0x001f]
machine # [    0.655506] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]
machine # [    0.656041] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
machine # [    0.656560] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]
machine # [    0.663954] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
machine # [    0.664614] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]
machine # [    0.665097] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
machine # [    0.665872] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint
machine # [    0.666525] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]
machine # [    0.667014] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
machine # [    0.672054] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint
machine # [    0.672687] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]
machine # [    0.673374] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
machine # [    0.674195] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]
machine # [    0.674611] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]
machine # [    0.675095] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
machine # [    0.679856] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
machine # [    0.680511] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]
machine # [    0.680918] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]
machine # [    0.681415] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
machine # [    0.682276] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned
machine # [    0.682811] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned
machine # [    0.683333] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned
machine # [    0.683996] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned
machine # [    0.684597] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned
machine # [    0.685214] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned
machine # [    0.685833] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned
machine # [    0.686453] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned
machine # [    0.687059] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned
machine # [    0.687680] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned
machine # [    0.688283] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned
machine # [    0.688889] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned
machine # [    0.689536] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned
machine # [    0.690241] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned
machine # [    0.690748] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned
machine # [    0.691272] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned
machine # [    0.691811] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned
machine # [    0.692350] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned
machine # [    0.692855] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned
machine # [    0.693369] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned
machine # [    0.693884] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned
machine # [    0.694405] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned
machine # [    0.694924] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned
machine # [    0.695445] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned
machine # [    0.695993] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned
machine # [    0.696496] pci 0000:00:06.0: BAR 0 [io  0x1000-0x107f]: assigned
machine # [    0.696955] pci 0000:00:03.0: BAR 0 [io  0x1080-0x10bf]: assigned
machine # [    0.697425] pci 0000:00:0b.0: BAR 0 [io  0x10c0-0x10ff]: assigned
machine # [    0.697887] pci 0000:00:01.0: BAR 0 [io  0x1100-0x111f]: assigned
machine # [    0.698310] pci 0000:00:02.0: BAR 0 [io  0x1120-0x113f]: assigned
machine # [    0.698768] pci 0000:00:04.0: BAR 0 [io  0x1140-0x115f]: assigned
machine # [    0.699235] pci 0000:00:05.0: BAR 0 [io  0x1160-0x117f]: assigned
machine # [    0.699713] pci 0000:00:07.0: BAR 0 [io  0x1180-0x119f]: assigned
machine # [    0.700178] pci 0000:00:0c.0: BAR 0 [io  0x11a0-0x11bf]: assigned
machine # [    0.700634] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]
machine # [    0.701042] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]
machine # [    0.701503] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]
machine # [    0.723983] pci 0000:00:0a.0: enabling device (0000 -> 0002)
machine # [    0.938065] Freeing initrd memory: 10036K
machine # [    0.939162] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)
machine # [    0.942000] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)
machine # [    0.944215] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)
machine # [    0.946441] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)
machine # [    0.948766] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)
machine # [    0.951878] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)
machine # [    0.953886] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)
machine # [    0.956342] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)
machine # [    0.958239] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)
machine # [    0.960354] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)
machine # [    0.964041] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)
machine # [    0.967603] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
machine # [    0.969034] msm_serial: driver initialized
machine # [    0.969448] SuperH (H)SCI(F) driver initialized
machine # [    0.969824] STM32 USART driver initialized
machine # [    0.983148] loop: module loaded
machine # [    0.983500] virtio_blk virtio5: 1/0/0 default/read/poll queues
machine # [    0.987198] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
machine # [    0.989635] megasas: 07.727.03.00-rc1
machine # [    0.990393] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]
machine # [    0.993295] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
machine # [    0.994043] Intel/Sharp Extended Query Table at 0x0031
machine # [    0.996037] Using buffer write method
machine # [    0.996348] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]
machine # [    0.998681] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
machine # [    0.999403] Intel/Sharp Extended Query Table at 0x0031
machine # [    1.001384] Using buffer write method
machine # [    1.001688] Concatenating MTD devices:
machine # [    1.001979] (0): "0.flash"
machine # [    1.002191] (1): "0.flash"
machine # [    1.002398] into device "0.flash"
machine # [    1.004849] tun: Universal TUN/TAP device driver, 1.6
machine # [    1.007842] thunder_xcv, ver 1.0
machine # [    1.008118] thunder_bgx, ver 1.0
machine # [    1.008377] nicpf, ver 1.0
machine # [    1.008847] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version
machine # [    1.009398] hns3: Copyright (c) 2017 Huawei Corporation.
machine # [    1.009823] hclge is initializing
machine # [    1.010093] e1000: Intel(R) PRO/1000 Network Driver
machine # [    1.010465] e1000: Copyright (c) 1999-2006 Intel Corporation.
machine # [    1.010905] e1000e: Intel(R) PRO/1000 Network Driver
machine # [    1.011280] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
machine # [    1.011780] igb: Intel(R) Gigabit Ethernet Network Driver
machine # [    1.012200] igb: Copyright (c) 2007-2014 Intel Corporation.
machine # [    1.012626] igbvf: Intel(R) Gigabit Virtual Function Network Driver
machine # [    1.013102] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
machine # [    1.013614] sky2: driver version 1.30
machine # [    1.015050] ehci-pci 0000:00:0a.0: EHCI Host Controller
machine # [    1.015493] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1
machine # [    1.016312] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000
machine # [    1.017045] usbcore: registered new interface driver usb-storage
machine # [    1.017595] usbcore: registered new interface driver usbserial_generic
machine # [    1.018117] usbserial: USB Serial support registered for generic
machine # [    1.019349] rtc-pl031 9010000.pl031: registered as rtc0
machine # [    1.019811] rtc-pl031 9010000.pl031: setting system clock to 2026-08-05T13:13:45 UTC (1785935625)
machine # [    1.020628] i2c_dev: i2c /dev entries driver
machine # [    1.022416] sdhci: Secure Digital Host Controller Interface driver
machine # [    1.022907] sdhci: Copyright(c) Pierre Ossman
machine # [    1.023378] Synopsys Designware Multimedia Card Interface Driver
machine # [    1.025169] sdhci-pltfm: SDHCI platform and OF driver helper
machine # [    1.026401] hid: raw HID events driver (C) Jiri Kosina
machine # [    1.026951] usbcore: registered new interface driver usbhid
machine # [    1.027376] usbhid: USB HID core driver
machine # [    1.027719] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00
machine # [    1.028691] hub 1-0:1.0: USB hub found
machine # [    1.029010] hub 1-0:1.0: 6 ports detected
machine # [    1.030255] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available
machine # [    1.036359] drop_monitor: Initializing network drop monitor service
machine # [    1.037135] NET: Registered PF_INET6 protocol family
machine # [    1.038383] Segment Routing with IPv6
machine # [    1.038711] In-situ OAM (IOAM) with IPv6
machine # [    1.039069] NET: Registered PF_PACKET protocol family
machine # [    1.039572] 9pnet: Installing 9P2000 support
machine # [    1.041586] Key type dns_resolver registered
machine # [    1.045643] registered taskstats version 1
machine # [    1.046186] Loading compiled-in X.509 certificates
machine # [    1.050756] Demotion targets for Node 0: null
machine # [    1.051256] Key type .fscrypt registered
machine # [    1.051875] Key type fscrypt-provisioning registered
machine # [    1.052746] input: gpio-keys as /devices/platform/gpio-keys/input/input0
machine # [    1.063373] clk: Disabling unused clocks
machine # [    1.063748] PM: genpd: Disabling unused power domains
machine # [    1.064140] ALSA device list:
machine # [    1.064370]   No soundcards found.
machine # [    1.067337] Freeing unused kernel memory: 4416K
machine # [    1.067907] Run /init as init process
machine # [    1.106611] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
machine # [    1.117779] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0
machine # [    1.118411] [drm] features: -virgl +edid -resource_blob -host_visible
machine # [    1.118413] [drm] features: -context_init
machine # [    1.119765] [drm] number of scanouts: 1
machine # [    1.120070] [drm] number of cap sets: 0
machine # [    1.120899] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0
machine # [    1.126809] Console: switching to colour frame buffer device 160x50
machine # [    1.133258] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device
machine # [    1.279562] usb 1-1: new high-speed USB device number 2 using ehci-pci
machine # [    1.366412] EXT4-fs (vda): mounted filesystem 9f0a4276-7853-424a-95c0-dd6e1aacbf9c r/w with ordered data mode. Quota mode: none.
machine # [    1.436096] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1
machine # [    1.535733] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0
machine # [    1.655570] usb 1-2: new high-speed USB device number 3 using ehci-pci
machine # [    1.707576] EXT4-fs (vda): re-mounted 9f0a4276-7853-424a-95c0-dd6e1aacbf9c.
machine # [    1.710451] booting system configuration /nix/store/7qx5rsnv2160i39db4krsarjn3gqaxwb-nixos-system-machine-test
machine # [    1.805299] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2
machine # [    1.807274] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0
machine # [    4.261410] systemd[1]: systemd 257.8 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN -IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE)
machine # [    4.271491] systemd[1]: Detected virtualization qemu.
machine # [    4.274538] systemd[1]: Detected architecture arm64.
machine # [    4.276802] systemd[1]: Detected first boot.
machine # [    4.286815] systemd[1]: Hostname set to <machine>.
machine # [    4.290872] systemd[1]: Initializing machine ID from random generator.
machine # [    5.008426] systemd[1]: bpf-restrict-fs: LSM BPF program attached
machine # [    5.578252] systemd[1]: Populated /etc with preset unit settings.
machine # [    6.046957] systemd[1]: Queued start job for default target Multi-User System.
machine # [    6.086798] systemd[1]: Created slice Slice /system/getty.
machine # [    6.088517] systemd[1]: Created slice Slice /system/modprobe.
machine # [    6.090057] systemd[1]: Created slice User and Session Slice.
machine # [    6.091241] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
machine # [    6.092521] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
machine # [    6.093918] systemd[1]: Expecting device /dev/hvc0...
machine # [    6.094937] systemd[1]: Expecting device /dev/ttyAMA0...
machine # [    6.096099] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...
machine # [    6.097224] systemd[1]: Reached target Local Encrypted Volumes.
machine # [    6.098363] systemd[1]: Reached target Containers.
machine # [    6.099380] systemd[1]: Reached target Path Units.
machine # [    6.100261] systemd[1]: Reached target Remote File Systems.
machine # [    6.101384] systemd[1]: Reached target Slice Units.
machine # [    6.102419] systemd[1]: Reached target Swaps.
machine # [    6.108359] systemd[1]: Listening on Process Core Dump Socket.
machine # [    6.112177] systemd[1]: Listening on Credential Encryption/Decryption.
machine # [    6.113979] systemd[1]: Listening on Journal Audit Socket.
machine # [    6.115122] systemd[1]: Listening on Journal Socket (/dev/log).
machine # [    6.116221] systemd[1]: Listening on Journal Sockets.
machine # [    6.117465] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
machine # [    6.118612] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
machine # [    6.120197] systemd[1]: Listening on udev Control Socket.
machine # [    6.121266] systemd[1]: Listening on udev Kernel Socket.
machine # [    6.130371] systemd[1]: Mounting Huge Pages File System...
machine # [    6.134577] systemd[1]: Mounting POSIX Message Queue File System...
machine # [    6.138717] systemd[1]: Mounting Kernel Debug File System...
machine # [    6.143254] systemd[1]: Mounting Kernel Trace File System...
machine # [    6.150582] systemd[1]: Starting Create List of Static Device Nodes...
machine # [    6.156477] systemd[1]: Starting Load Kernel Module 9pnet_virtio...
machine # [    6.167945] systemd[1]: Starting Load Kernel Module configfs...
machine # [    6.177998] systemd[1]: Starting Load Kernel Module drm...
machine # [    6.194386] systemd[1]: Starting Load Kernel Module efi_pstore...
machine # [    6.201545] systemd[1]: Starting Load Kernel Module fuse...
machine # [    6.203026] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
machine # [    6.208253] systemd[1]: Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
machine # [    6.218051] systemd[1]: Starting Journal Service...
machine # [    6.223137] systemd[1]: Starting Load Kernel Modules...
machine # [    6.235216] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
machine # [    6.255494] systemd[1]: Starting Remount Root and Kernel File Systems...
machine # [    6.257236] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
machine # [    6.269841] systemd[1]: Starting Coldplug All udev Devices...
machine # [    6.273466] systemd[1]: Mounted Huge Pages File System.
machine # [    6.277171] systemd[1]: Mounted POSIX Message Queue File System.
machine # [    6.278406] systemd[1]: Mounted Kernel Debug File System.
machine # [    6.280257] systemd[1]: Mounted Kernel Trace File System.
machine # [    6.282629] systemd[1]: Finished Create List of Static Device Nodes.
machine # [    6.290601] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
machine # [    6.351236] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.
machine # [    6.352693] systemd[1]: Finished Load Kernel Module 9pnet_virtio.
machine # [    6.356456] systemd[1]: modprobe@configfs.service: Deactivated successfully.
machine # [    6.357721] systemd[1]: Finished Load Kernel Module configfs.
machine # [    6.362793] systemd[1]: Mounting Kernel Configuration File System...
machine # [    6.365979] systemd[1]: modprobe@drm.service: Deactivated successfully.
machine # [    6.368331] systemd[1]: Finished Load Kernel Module drm.
machine # [    6.377837] systemd[1]: Finished Load Kernel Modules.
machine # [    6.392106] systemd[1]: Starting Firewall...
machine # [    6.404327] systemd-journald[368]: Collecting audit messages is enabled.
machine # [    6.407011] systemd[1]: Starting Apply Kernel Variables...
machine # [    6.410356] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
machine # [    6.412293] systemd[1]: Finished Load Kernel Module efi_pstore.
machine # [    6.416003] systemd[1]: Mounted Kernel Configuration File System.
machine # [    6.423796] EXT4-fs (vda): re-mounted 9f0a4276-7853-424a-95c0-dd6e1aacbf9c.
machine # [    6.438998] fuse: init (API version 7.41)
machine # [    6.451037] systemd[1]: Finished Remount Root and Kernel File Systems.
machine # [    6.452778] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
machine # [    6.460150] systemd[1]: Starting Load/Save OS Random Seed...
machine # [    6.461747] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
machine # [    6.468125] systemd[1]: modprobe@fuse.service: Deactivated successfully.
machine # [    6.469354] systemd[1]: Finished Load Kernel Module fuse.
machine # [    6.514650] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
machine # [    6.544254] systemd[1]: Started Journal Service.
machine # [    6.537870] systemd-modules-load[369]: Module 'atkbd' is built in
machine # [    6.542540] systemd-modules-load[369]: Module 'loop' is built in
machine # [    6.552891] systemd-oomd[370]: No swap; memory pressure usage will be degraded
machine # [    6.557227] systemd[1]: Starting Flush Journal to Persistent Storage...
machine # [    6.563071] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
machine # [    6.569103] systemd[1]: Starting Create Static Device Nodes in /dev...
machine # [    6.573237] systemd[1]: Finished Load/Save OS Random Seed.
machine # [    6.578783] systemd[1]: Reached target First Boot Complete.
machine # [    6.613721] systemd[1]: Finished Apply Kernel Variables.
machine # [    6.645477] systemd-journald[368]: Received client request to flush runtime journal.
machine # [    6.668437] systemd[1]: Finished Flush Journal to Persistent Storage.
machine # [    6.692235] systemd[1]: Finished Create Static Device Nodes in /dev.
machine # [    6.692884] systemd[1]: Reached target Preparation for Local File Systems.
machine # [    6.697727] systemd[1]: Starting Rule-based Manager for Device Events and Files...
machine # [    6.974175] systemd-udevd[412]: Using default interface naming scheme 'v257'.
machine # [    7.050478] systemd[1]: Finished Coldplug All udev Devices.
machine # [    7.110469] systemd[1]: Started Rule-based Manager for Device Events and Files.
machine # [    7.120299] systemd[1]: Starting Load Kernel Module fuse...
machine # [    7.174777] systemd[1]: modprobe@fuse.service: Deactivated successfully.
machine # [    7.180667] systemd[1]: Finished Load Kernel Module fuse.
machine # [    7.249077] systemd[1]: Found device /dev/hvc0.
machine # [    7.267138] systemd[1]: Found device /dev/ttyAMA0.
machine # [    7.272134] systemd[1]: Mounting /run/wrappers...
machine # [    7.277318] systemd[1]: Mounting FUSE Control File System...
machine # [    7.324499] systemd[1]: Mounted FUSE Control File System.
machine # [    7.331114] systemd[1]: Mounted /run/wrappers.
machine # [    7.334063] systemd[1]: Reached target Local File Systems.
machine # [    7.337580] systemd[1]: Listening on Boot Entries Service Socket.
machine # [    7.340206] systemd[1]: Starting Create SUID/SGID Wrappers...
machine # [    7.345507] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.
machine # [    7.351591] systemd[1]: Starting Save Transient machine-id to Disk...
machine # [    7.355489] systemd[1]: Starting Create System Files and Directories...
machine # [    7.438411] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
machine # [    7.444870] systemd[1]: Finished Save Transient machine-id to Disk.
machine # [    7.504103] systemd[1]: Finished Create System Files and Directories.
machine # [    7.509382] systemd[1]: Starting Rebuild Journal Catalog...
machine # [    7.513063] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
machine # [    7.667101] (udev-worker)[439]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
machine # [    7.670739] (udev-worker)[439]: Network interface NamePolicy= disabled on kernel command line.
machine # [    7.672679] (udev-worker)[450]: Network interface NamePolicy= disabled on kernel command line.
machine # [    7.730017] mousedev: PS/2 mouse device common for all mice
machine # [    7.811685] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
machine # [    7.834496] systemd[1]: Finished Rebuild Journal Catalog.
machine # [    7.839648] systemd[1]: Starting Update is Completed...
machine # [    7.904991] systemd[1]: Found device Virtio network device.
machine # [    7.936431] systemd[1]: Finished Update is Completed.
machine # [    8.594141] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3
machine # [    8.795417] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
machine # [    8.796262] systemd[1]: Finished Create SUID/SGID Wrappers.
machine # [    8.796992] systemd[1]: Reached target System Initialization.
machine # [    8.798577] systemd[1]: Started Discard unused filesystem blocks once a week.
machine # [    8.799680] systemd[1]: Started Daily Cleanup of Temporary Directories.
machine # [    8.800866] systemd[1]: Reached target Timer Units.
machine # [    8.802521] systemd[1]: Listening on D-Bus System Message Bus Socket.
machine # [    8.803127] systemd[1]: Listening on Nix Daemon Socket.
machine # [    8.803616] systemd[1]: Listening on Hostname Service Socket.
machine # [    8.804421] systemd[1]: Reached target Socket Units.
machine # [    8.807139] systemd[1]: Reached target Basic System.
machine # [    8.809382] systemd[1]: Started backdoor.service.
machine # [    8.813707] systemd[1]: Starting Import lastlog data into lastlog2 database...
machine # [    8.819050] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
machine # [    8.822878] systemd[1]: Started Reset console on configuration changes.
machine # [    8.829214] systemd[1]: Starting resolvconf update...
machine # [    8.831960] systemd[1]: Starting D-Bus System Message Bus...
machine # [    9.101865] systemd[1]: Finished Firewall.
machine # [    9.157169] systemd[1]: Starting Virtual Console Setup...
machine # [    9.167660] systemd[1]: Finished Import lastlog data into lastlog2 database.
machine # [    9.185394] systemd[1]: Started Name Service Cache Daemon (nsncd).
machine # [    9.190782] systemd[1]: Reached target Host and Network Name Lookups.
machine # [    9.194704] nsncd[608]: Aug 05 13:13:53.675 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
machine # [    9.199149] systemd[1]: Reached target User and Group Name Lookups.
machine # [    9.201839] systemd[1]: Starting User Login Management...
machine # connecting to host...
machine # [    9.232471] dbus-daemon[612]: dbus[612]: Unknown username "systemd-timesync" in message bus configuration file
machine: Guest shell says: b'Spawning backdoor root shell...\n'
machine: connected to guest root shell
machine: (connecting took 9.77 seconds)
machine: (finished: waiting for the VM to finish booting, in 9.77 seconds)
machine # [    9.414948] systemd[1]: Started D-Bus System Message Bus.
machine # [    9.416596] systemd[1]: Stopped target Host and Network Name Lookups.
machine # [    9.418880] systemd[1]: Stopping Host and Network Name Lookups...
machine # [    9.419612] systemd[1]: Stopped target User and Group Name Lookups.
machine # [    9.422375] systemd[1]: Stopping User and Group Name Lookups...
machine # [    9.423726] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
machine # [    9.425704] systemd[1]: nscd.service: Deactivated successfully.
machine # [    9.428463] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
machine # [    9.434818] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
machine # [    9.460384] systemd-logind[640]: New seat seat0.
machine # [    9.468085] systemd-logind[640]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)
machine # [    9.474306] systemd-logind[640]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)
machine # [    9.479105] systemd-logind[640]: Watching system buttons on /dev/input/event0 (gpio-keys)
machine # [    9.484453] systemd[1]: Started User Login Management.
machine # [    9.511890] systemd[1]: Started Name Service Cache Daemon (nsncd).
machine # [    9.513135] nsncd[684]: Aug 05 13:13:54.003 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
machine # [    9.514793] systemd[1]: Reached target Host and Network Name Lookups.
machine # [    9.516241] systemd[1]: Reached target User and Group Name Lookups.
machine # [    9.538648] systemd[1]: Finished resolvconf update.
machine # [    9.542719] systemd[1]: Reached target Preparation for Network.
machine # [    9.546143] systemd[1]: Starting DHCP Client...
machine # [    9.549314] systemd[1]: Starting Address configuration of eth1...
machine: (finished: must succeed: cardano-node --version, in 10.17 seconds)
cardano-node 11.1.0.164 - linux-aarch64 - ghc-9.6
git rev ee7ea74fc8e3990357844de678f26ed99732d2bf

machine: must succeed: cardano-cli --version
machine # [    9.687361] network-addresses-eth1-start[712]: adding address 192.168.1.1/24... done
machine # [    9.706441] network-addresses-eth1-start[712]: adding address 2001:db8:1::1/64... done
machine # [    9.726470] systemd[1]: Finished Address configuration of eth1.
machine # [    9.731279] systemd[1]: Starting Networking Setup...
machine # [    9.739727] dhcpcd[721]: dhcpcd-10.2.4 starting
machine # [    9.766153] dhcpcd[738]: dev: loaded udev
machine # [    9.822274] 8021q: 802.1Q VLAN Support v1.8
machine # [    9.823105] 8021q: adding VLAN 0 to HW filter on device eth1
machine # [   10.180556] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
machine # [   10.250095] cfg80211: Loading compiled-in X.509 certificates for regulatory database
machine: (finished: must succeed: cardano-cli --version, in 0.59 seconds)
cardano-cli 11.1.0.0 - linux-aarch64 - ghc-9.6
git rev ee7ea74fc8e3990357844de678f26ed99732d2bf

machine: must succeed: cardano-testnet version
machine # [   10.301746] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
machine # [   10.302875] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
machine # [   10.307774] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
machine # [   10.309047] cfg80211: failed to load regulatory.db
machine # [   10.311106] systemd[1]: Finished Networking Setup.
machine # [   10.313135] systemd[1]: Reached target Network.
machine # [   10.316145] systemd[1]: Starting Permit User Sessions...
machine # [   10.381369] systemd[1]: Finished Permit User Sessions.
machine # [   10.385498] systemd[1]: Started Getty on tty1.
machine # [   10.386037] systemd[1]: Reached target Login Prompts.
machine # [   10.414903] 8021q: adding VLAN 0 to HW filter on device eth0
machine # [   10.406525] dhcpcd[738]: eth0: waiting for carrier
machine # [   10.434173] systemd[1]: Finished Virtual Console Setup.
machine: (finished: must succeed: cardano-testnet version, in 0.22 seconds)
cardano-node 11.0.0 - linux-aarch64 - ghc-9.6
git rev 0000000000000000000000000000000000000000
built against cardano-api 11.3.0.0
built against cardano-cli 11.1.0.0

machine: must succeed: cardano-testnet create-env --epoch-length 1000 --output testnet
machine # [   10.757858] dhcpcd[738]: eth0: carrier acquired
machine # [   10.777622] dhcpcd[738]: DUID 00:01:00:01:32:05:f3:93:52:54:00:12:34:56
machine # [   10.778585] dhcpcd[738]: eth0: IAID 00:12:34:56
machine # [   10.779326] dhcpcd[738]: eth0: adding address fe80::5054:ff:fe12:3456
machine # [   11.412166] dhcpcd[738]: eth0: soliciting a DHCP lease
machine # [   11.431541] dhcpcd[738]: eth0: offered 10.0.2.15 from 10.0.2.2
machine # [   11.449098] dhcpcd[738]: eth0: probing address 10.0.2.15/24
machine # cardano-testnet: ! AnnotatedException !
machine # Underlying exception type: StringException
machine # 
machine # UnliftIO.Exception.throwString called with:
machine # 
machine # Process exited with non-zero exit-code: 1
machine # ━━━━ stderr ━━━━
machine # Command failed: genesis create-testnet-data
machine # Error: Error while decoding ShelleyGenesis at: /tmp/testnet/genesis-input.shelley.json Error: Error in $.extraConfig.initialFunds: InjectionData: No injection was provided. Expected either "file" or "data"
machine # CallStack (from HasCallStack):
machine #   fromExceptTCli, called at src/Cardano/CLI/EraBased/Genesis/CreateTestnetData/Run.hs:255:23 in cardano-cli-11.1.0.0-CGHmn1WiUZxC9Gxz9DydMK:Cardano.CLI.EraBased.Genesis.CreateTestnetData.Run
machine #   runGenesisCreateTestNetDataCmd, called at src/Cardano/CLI/EraBased/Genesis/Run.hs:124:36 in cardano-cli-11.1.0.0-CGHmn1WiUZxC9Gxz9DydMK:Cardano.CLI.EraBased.Genesis.Run
machine #   runGenesisCmds, called at src/Cardano/CLI/EraBased/Run.hs:49:5 in cardano-cli-11.1.0.0-CGHmn1WiUZxC9Gxz9DydMK:Cardano.CLI.EraBased.Run
machine #   runCmds, called at src/Cardano/CLI/EraBased/Run.hs:35:35 in cardano-cli-11.1.0.0-CGHmn1WiUZxC9Gxz9DydMK:Cardano.CLI.EraBased.Run
machine #   runAnyEraCommand, called at src/Cardano/CLI/Run.hs:56:5 in cardano-cli-11.1.0.0-CGHmn1WiUZxC9Gxz9DydMK:Cardano.CLI.Run
machine #   runClientCommand, called at app/cardano-cli.hs:58:14 in cardano-cli-11.1.0.0-BaWrvlrf29HI5WEXEH2QhM-cardano-cli:Main
machine # 
machine # 
machine # 
machine # Called from:
machine #   execCli_ (src/Testnet/Components/Configuration.hs:203:3 in cardano-testnet-11.0.0-4rhaQrTwRJiEeatmXbXX4j:Testnet.Components.Configuration)
machine #   createSPOGenesisAndFiles (src/Testnet/Start/Cardano.hs:127:8 in cardano-testnet-11.0.0-4rhaQrTwRJiEeatmXbXX4j:Testnet.Start.Cardano)
machine #   createTestnetEnv (src/Parsers/Run.hs:68:14 in cardano-testnet-11.0.0-4rhaQrTwRJiEeatmXbXX4j:Parsers.Run)
machine # 
machine # 
machine # CallStack (from HasCallStack):
machine #   execCli_, called at src/Testnet/Components/Configuration.hs:203:3 in cardano-testnet-11.0.0-4rhaQrTwRJiEeatmXbXX4j:Testnet.Components.Configuration
machine #   createSPOGenesisAndFiles, called at src/Testnet/Start/Cardano.hs:127:8 in cardano-testnet-11.0.0-4rhaQrTwRJiEeatmXbXX4j:Testnet.Start.Cardano
machine #   createTestnetEnv, called at src/Parsers/Run.hs:68:14 in cardano-testnet-11.0.0-4rhaQrTwRJiEeatmXbXX4j:Parsers.Run
machine: output: 
!!! Traceback (most recent call last):
!!!   File "<string>", line 12, in <module>
!!!     print(machine.succeed("cardano-testnet create-env --epoch-length 1000 --output testnet"))
!!! 
!!! RequestedAssertionFailed: command `cardano-testnet create-env --epoch-length 1000 --output testnet` failed (exit code 1)
cleanup
kill machine (pid 9)
qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/a65r9k46dxhyx2gn60bpx7j62anjdjr7-python3-3.13.7/bin/python3.13)
kill vlan (pid 7)
(finished: cleanup, in 0.01 seconds)