vm-test-run-systemd-homed
checks.aarch64-linux.systemd-homed
· build #43
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.01 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7start all VMs8machine: starting vm9sshClient: starting vm10sshClient # Disk image does not exist, creating the virtualisation disk image...11sshClient # Formatting '/build/vm-state-sshClient/tmp.HoFwkznlso', fmt=raw size=107374182412sshClient: QEMU running (pid 48)13sshClient # mke2fs 1.47.4 (6-Mar-2025)14sshClient # Discarding device blocks: 0/262144 done15sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes16sshClient # Filesystem UUID: f4329853-74ce-4408-808f-d2de807d141b17sshClient # Superblock backups stored on blocks:18sshClient # 32768, 98304, 163840, 22937619sshClient # 20sshClient # Allocating group tables: 0/8 done21sshClient # Writing inode tables: 0/8 done22sshClient # Creating journal (8192 blocks): done23sshClient # Writing superblocks and filesystem accounting information: 0/8 done24sshClient # 25sshClient # Virtualisation disk image created.26sshClient # Starting virtiofs daemons...27sshClient # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28sshClient # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29sshClient # [2026-09-24T02:50:39Z INFO virtiofsd] Waiting for vhost-user socket connection...30sshClient # [2026-09-24T02:50:39Z INFO virtiofsd] Client connected, servicing requests31sshClient # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32sshClient # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33sshClient # [2026-09-24T02:50:39Z INFO virtiofsd] Waiting for vhost-user socket connection...34sshClient # [2026-09-24T02:50:39Z INFO virtiofsd] Client connected, servicing requests35sshClient # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)36sshClient # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether37sshClient # [2026-09-24T02:50:39Z INFO virtiofsd] Waiting for vhost-user socket connection...38sshClient # [2026-09-24T02:50:39Z INFO virtiofsd] Client connected, servicing requests39machine # Disk image does not exist, creating the virtualisation disk image...40machine: QEMU running (pid 47)41machine # Formatting '/build/vm-state-machine/tmp.vLYDeirXLK', fmt=raw size=107374182442machine # mke2fs 1.47.4 (6-Mar-2025)43machine # Discarding device blocks: 0/262144 done44machine # Creating filesystem with 262144 4k blocks and 65536 inodes45machine # Filesystem UUID: 313095f8-9271-440c-b058-9bbbdd1fbe6e46machine # Superblock backups stored on blocks:47machine # 32768, 98304, 163840, 22937648(finished: start all VMs, in 5.15 seconds)49machine # 50subtest: create systemd-homed user on first boot prompt51machine # Allocating group tables: 0/8 done52machine: waiting for unit systemd-homed.service53machine # Writing inode tables: 0/8 done54machine: waiting for the VM to finish booting55machine # Creating journal (8192 blocks): done56machine # Writing superblocks and filesystem accounting information: 0/8 done57machine # 58machine # Virtualisation disk image created.59machine # Starting virtiofs daemons...60machine # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)61machine # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether62machine # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)63machine # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether64machine # [2026-09-24T02:50:39Z INFO virtiofsd] Waiting for vhost-user socket connection...65machine # [2026-09-24T02:50:39Z INFO virtiofsd] Waiting for vhost-user socket connection...66machine # [2026-09-24T02:50:39Z INFO virtiofsd] Client connected, servicing requests67machine # [2026-09-24T02:50:39Z INFO virtiofsd] Client connected, servicing requests68machine # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)69machine # [2026-09-24T02:50:39Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether70machine # [2026-09-24T02:50:39Z INFO virtiofsd] Waiting for vhost-user socket connection...71machine # [2026-09-24T02:50:39Z INFO virtiofsd] Client connected, servicing requests72sshClient # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]73sshClient # [ 0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 202674sshClient # [ 0.000000] KASLR enabled75sshClient # [ 0.000000] random: crng init done76sshClient # [ 0.000000] Machine model: linux,dummy-virt77sshClient # [ 0.000000] efi: UEFI not found.78sshClient # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT79sshClient # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]80sshClient # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f]81sshClient # [ 0.000000] Zone ranges:82sshClient # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]83sshClient # [ 0.000000] DMA32 empty84sshClient # [ 0.000000] Normal empty85sshClient # [ 0.000000] Device empty86sshClient # [ 0.000000] Movable zone start for each node87sshClient # [ 0.000000] Early memory node ranges88sshClient # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]89sshClient # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]90sshClient # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000091sshClient # [ 0.000000] psci: probing for conduit method from DT.92sshClient # [ 0.000000] psci: PSCIv1.3 detected in firmware.93sshClient # [ 0.000000] psci: Using standard PSCI v0.2 function IDs94sshClient # [ 0.000000] psci: Trusted OS migration not required95sshClient # [ 0.000000] psci: SMC Calling Convention v1.196sshClient # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)97sshClient # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u31129698sshClient # [ 0.000000] Detected PIPT I-cache on CPU099sshClient # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)100sshClient # [ 0.000000] CPU features: detected: GICv3 CPU interface101sshClient # [ 0.000000] CPU features: detected: Spectre-v4102sshClient # [ 0.000000] CPU features: detected: Spectre-BHB103sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38104sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23105sshClient # [ 0.000000] alternatives: applying boot alternatives106sshClient # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/5rkl1zpvxpi0gfiqglh9l3x1vzj96pry-nixos-system-sshClient-test/init regInfo=/nix/.ro-store/yi8ib0m3vkglp4zjfjb6q5qrny37sdqv-closure-info/registration console=ttyAMA0,115200n8 console=tty0107sshClient # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/yi8ib0m3vkglp4zjfjb6q5qrny37sdqv-closure-info/registration", will be passed to user space.108sshClient # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes109sshClient # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)110sshClient # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)111sshClient # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB112sshClient # [ 0.000000] software IO TLB: area num 1.113sshClient # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)114sshClient # [ 0.000000] Fallback order for Node 0: 0115sshClient # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144116sshClient # [ 0.000000] Policy zone: DMA117sshClient # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off118sshClient # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1119sshClient # [ 0.000000] allocated 2097152 bytes of page_ext120sshClient # [ 0.000000] ftrace: allocating 74963 entries in 294 pages121sshClient # [ 0.000000] ftrace: allocated 294 pages with 4 groups122sshClient # [ 0.000000] rcu: Hierarchical RCU implementation.123sshClient # [ 0.000000] rcu: RCU event tracing is enabled.124sshClient # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.125sshClient # [ 0.000000] Trampoline variant of Tasks RCU enabled.126sshClient # [ 0.000000] Rude variant of Tasks RCU enabled.127sshClient # [ 0.000000] Tracing variant of Tasks RCU enabled.128sshClient # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.129sshClient # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1130sshClient # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.131sshClient # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.132sshClient # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.133sshClient # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0134sshClient # [ 0.000000] GICv3: 256 SPIs implemented135sshClient # [ 0.000000] GICv3: 0 Extended SPIs implemented136sshClient # [ 0.000000] Root IRQ handler: gic_handle_irq137sshClient # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI138sshClient # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0139sshClient # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000140sshClient # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]141sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)142sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)143sshClient # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000144sshClient # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000145sshClient # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.146sshClient # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns147sshClient # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).148sshClient # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns149sshClient # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns150sshClient # [ 0.000035] arm-pv: using stolen time PV151sshClient # [ 0.000458] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)152sshClient # [ 0.000635] Console: colour dummy device 80x25153sshClient # [ 0.000643] printk: legacy console [tty0] enabled154sshClient # [ 0.000842] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)155sshClient # [ 0.000849] pid_max: default: 32768 minimum: 301156sshClient # [ 0.000927] LSM: initializing lsm=capability,landlock,yama,bpf,ima157sshClient # [ 0.001113] landlock: Up and running.158sshClient # [ 0.001116] Yama: becoming mindful.159sshClient # [ 0.001608] LSM support for eBPF active160sshClient # [ 0.001758] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)161sshClient # [ 0.001777] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)162sshClient # [ 0.003670] rcu: Hierarchical SRCU implementation.163sshClient # [ 0.003676] rcu: Max phase no-delay instances is 1000.164sshClient # [ 0.004954] fsl-mc MSI: its@8080000 domain created165sshClient # [ 0.005070] EFI services will not be available.166sshClient # [ 0.005157] smp: Bringing up secondary CPUs ...167sshClient # [ 0.005165] smp: Brought up 1 node, 1 CPU168sshClient # [ 0.005169] SMP: Total of 1 processors activated.169sshClient # [ 0.005172] CPU: All CPU(s) started at EL1170sshClient # [ 0.005186] CPU features: detected: Branch Target Identification171sshClient # [ 0.005190] CPU features: detected: ARMv8.4 Translation Table Level172sshClient # [ 0.005193] CPU features: detected: Instruction cache invalidation not required for I/D coherence173sshClient # [ 0.005196] CPU features: detected: Data cache clean to the PoU not required for I/D coherence174sshClient # [ 0.005200] CPU features: detected: Common not Private translations175sshClient # [ 0.005203] CPU features: detected: CRC32 instructions176sshClient # [ 0.005206] CPU features: detected: Data cache clean to Point of Deep Persistence177sshClient # [ 0.005210] CPU features: detected: Data cache clean to Point of Persistence178sshClient # [ 0.005213] CPU features: detected: Data independent timing control (DIT)179sshClient # [ 0.005216] CPU features: detected: E0PD180sshClient # [ 0.005218] CPU features: detected: Enhanced Counter Virtualization181sshClient # [ 0.005221] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)182sshClient # [ 0.005225] CPU features: detected: Enhanced Virtualization Traps183sshClient # [ 0.005228] CPU features: detected: Fine Grained Traps184sshClient # [ 0.005232] CPU features: detected: Generic authentication (architected QARMA5 algorithm)185sshClient # [ 0.005237] CPU features: detected: RCpc load-acquire (LDAPR)186sshClient # [ 0.005240] CPU features: detected: LSE atomic instructions187sshClient # [ 0.005243] CPU features: detected: Privileged Access Never188sshClient # [ 0.005246] CPU features: detected: PMUv3189sshClient # [ 0.005249] CPU features: detected: RAS Extension Support190sshClient # [ 0.005252] CPU features: detected: RASv1p1 Extension Support191sshClient # [ 0.005254] CPU features: detected: Random Number Generator192sshClient # [ 0.005257] CPU features: detected: Speculation barrier (SB)193sshClient # [ 0.005259] CPU features: detected: Stage-2 Force Write-Back194sshClient # [ 0.005262] CPU features: detected: TLB range maintenance instructions195sshClient # [ 0.005267] CPU features: detected: Speculative Store Bypassing Safe (SSBS)196sshClient # [ 0.005309] alternatives: applying system-wide alternatives197sshClient # [ 0.008359] CPU features: detected: BBM Level 2 without TLB conflict abort198sshClient # [ 0.008601] Memory: 893736K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 113504K reserved, 32768K cma-reserved)199sshClient # [ 0.009003] devtmpfs: initialized200sshClient # [ 0.010759] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)201sshClient # [ 0.010782] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).202sshClient # [ 0.010972] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL203sshClient # [ 0.010976] 0 pages in range for non-PLT usage204sshClient # [ 0.010977] 508224 pages in range for PLT usage205sshClient # [ 0.011080] pinctrl core: initialized pinctrl subsystem206sshClient # [ 0.011884] DMI not present or invalid.207sshClient # [ 0.016423] NET: Registered PF_NETLINK/PF_ROUTE protocol family208sshClient # [ 0.018931] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations209sshClient # [ 0.019093] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations210sshClient # [ 0.019265] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations211sshClient # [ 0.019290] audit: initializing netlink subsys (disabled)212sshClient # [ 0.019932] thermal_sys: Registered thermal governor 'fair_share'213sshClient # [ 0.019934] thermal_sys: Registered thermal governor 'bang_bang'214sshClient # [ 0.019938] thermal_sys: Registered thermal governor 'step_wise'215sshClient # [ 0.019941] thermal_sys: Registered thermal governor 'user_space'216sshClient # [ 0.019944] thermal_sys: Registered thermal governor 'power_allocator'217sshClient # [ 0.019973] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1218sshClient # [ 0.019983] cpuidle: using governor ladder219sshClient # [ 0.019988] cpuidle: using governor menu220sshClient # [ 0.020210] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.221sshClient # [ 0.020226] ASID allocator initialised with 65536 entries222sshClient # [ 0.021477] Serial: AMBA PL011 UART driver223sshClient # [ 0.027179] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1224sshClient # [ 0.027356] printk: console [ttyAMA0] enabled225sshClient # [ 0.155661] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages226sshClient # [ 0.155671] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page227sshClient # [ 0.155674] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages228sshClient # [ 0.155677] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page229sshClient # [ 0.155680] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages230sshClient # [ 0.155682] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page231sshClient # [ 0.155685] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages232sshClient # [ 0.155688] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page233sshClient # [ 0.156869] fbcon: Taking over console234sshClient # [ 0.156880] ACPI: Interpreter disabled.235sshClient # [ 0.157861] iommu: Default domain type: Translated236sshClient # [ 0.157865] iommu: DMA domain TLB invalidation policy: strict mode237sshClient # [ 0.158275] SCSI subsystem initialized238sshClient # [ 0.158444] usbcore: registered new interface driver usbfs239sshClient # [ 0.158474] usbcore: registered new interface driver hub240sshClient # [ 0.158496] usbcore: registered new device driver usb241sshClient # [ 0.158786] pps_core: LinuxPPS API ver. 1 registered242sshClient # [ 0.158789] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>243sshClient # [ 0.158805] PTP clock support registered244sshClient # [ 0.158855] EDAC MC: Ver: 3.0.0245sshClient # [ 0.173627] scmi_core: SCMI protocol bus registered246sshClient # [ 0.174050] FPGA manager framework247sshClient # [ 0.177803] vgaarb: loaded248sshClient # [ 0.178159] clocksource: Switched to clocksource arch_sys_counter249sshClient # [ 0.184510] VFS: Disk quotas dquot_6.6.0250sshClient # [ 0.184557] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)251sshClient # [ 0.187765] netfs: FS-Cache loaded252sshClient # [ 0.187904] pnp: PnP ACPI: disabled253sshClient # [ 0.192844] NET: Registered PF_INET protocol family254sshClient # [ 0.193004] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)255sshClient # [ 0.224363] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)256sshClient # [ 0.224411] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)257sshClient # [ 0.224436] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)258sshClient # [ 0.224488] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)259sshClient # [ 0.224560] TCP: Hash tables configured (established 8192 bind 8192)260sshClient # [ 0.224651] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)261sshClient # [ 0.224718] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)262sshClient # [ 0.224766] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)263sshClient # [ 0.224843] NET: Registered PF_UNIX/PF_LOCAL protocol family264sshClient # [ 0.224876] NET: Registered PF_XDP protocol family265sshClient # [ 0.224893] PCI: CLS 0 bytes, default 64266sshClient # [ 0.225163] Trying to unpack rootfs image as initramfs...267sshClient # [ 0.242340] kvm [1]: HYP mode not available268sshClient # [ 0.354717] Initialise system trusted keyrings269sshClient # [ 0.354861] workingset: timestamp_bits=42 max_order=18 bucket_order=0270sshClient # [ 0.355262] squashfs: version 4.0 (2009/01/31) Phillip Lougher271sshClient # [ 0.355329] 9p: Installing v9fs 9p2000 file system support272machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]273machine # [ 0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 2026274machine # [ 0.000000] KASLR enabled275machine # [ 0.000000] random: crng init done276machine # [ 0.000000] Machine model: linux,dummy-virt277machine # [ 0.000000] efi: UEFI not found.278machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT279machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]280machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f]281machine # [ 0.000000] Zone ranges:282machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]283machine # [ 0.000000] DMA32 empty284machine # [ 0.000000] Normal empty285machine # [ 0.000000] Device empty286machine # [ 0.000000] Movable zone start for each node287machine # [ 0.000000] Early memory node ranges288machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]289machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]290machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000291machine # [ 0.000000] psci: probing for conduit method from DT.292machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.293machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs294machine # [ 0.000000] psci: Trusted OS migration not required295sshClient # [ 0.383386] Key type asymmetric registered296machine # [ 0.000000] psci: SMC Calling Convention v1.1297sshClient # [ 0.383403] Asymmetric key parser 'x509' registered298machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)299sshClient # [ 0.383484] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)300sshClient # [ 0.383639] io scheduler mq-deadline registered301machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296302sshClient # [ 0.383643] io scheduler kyber registered303machine # [ 0.000000] Detected PIPT I-cache on CPU0304machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)305machine # [ 0.000000] CPU features: detected: GICv3 CPU interface306machine # [ 0.000000] CPU features: detected: Spectre-v4307machine # [ 0.000000] CPU features: detected: Spectre-BHB308machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38309machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23310machine # [ 0.000000] alternatives: applying boot alternatives311machine # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/wqgq817fldswva01wys37cyff9a9l9vl-nixos-system-machine-test/init regInfo=/nix/.ro-store/qq4drd6h59ip06px0jzah413jaaa7k40-closure-info/registration console=ttyAMA0,115200n8 console=tty0312sshClient # [ 0.396077] pl061_gpio 9030000.pl061: PL061 GPIO chip registered313sshClient # [ 0.396663] ledtrig-cpu: registered to indicate activity on CPUs314machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/qq4drd6h59ip06px0jzah413jaaa7k40-closure-info/registration", will be passed to user space.315sshClient # [ 0.397033] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:316machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes317sshClient # [ 0.397047] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000318machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)319machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)320sshClient # [ 0.397057] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000321machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB322sshClient # [ 0.397062] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000323machine # [ 0.000000] software IO TLB: area num 1.324sshClient # [ 0.397079] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits325machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)326machine # [ 0.000000] Fallback order for Node 0: 0327sshClient # [ 0.397097] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]328machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144329machine # [ 0.000000] Policy zone: DMA330sshClient # [ 0.397175] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00331machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off332sshClient # [ 0.397189] pci_bus 0000:00: root bus resource [bus 00-ff]333sshClient # [ 0.397193] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]334machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1335machine # [ 0.000000] allocated 2097152 bytes of page_ext336sshClient # [ 0.397196] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]337machine # [ 0.000000] ftrace: allocating 74963 entries in 294 pages338sshClient # [ 0.397199] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]339machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups340machine # [ 0.000000] rcu: Hierarchical RCU implementation.341sshClient # [ 0.397261] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint342machine # [ 0.000000] rcu: RCU event tracing is enabled.343sshClient # [ 0.397702] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint344machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.345sshClient # [ 0.397887] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]346machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.347machine # [ 0.000000] Rude variant of Tasks RCU enabled.348sshClient # [ 0.397899] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]349machine # [ 0.000000] Tracing variant of Tasks RCU enabled.350sshClient # [ 0.397925] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]351machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.352machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1353machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.354machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.355machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.356machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0357machine # [ 0.000000] GICv3: 256 SPIs implemented358machine # [ 0.000000] GICv3: 0 Extended SPIs implemented359machine # [ 0.000000] Root IRQ handler: gic_handle_irq360machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI361machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0362sshClient # [ 0.397936] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]363machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000364machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]365machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)366machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)367sshClient # [ 0.423323] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint368machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000369sshClient # [ 0.423533] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]370machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000371sshClient # [ 0.423548] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]372machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.373sshClient # [ 0.423576] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]374machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns375sshClient # [ 0.424070] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint376machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).377sshClient # [ 0.424256] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]378sshClient # [ 0.424268] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]379machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns380sshClient # [ 0.424293] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]381machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns382sshClient # [ 0.424754] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint383machine # [ 0.000041] arm-pv: using stolen time PV384sshClient # [ 0.424934] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]385machine # [ 0.000807] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)386sshClient # [ 0.424945] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]387machine # [ 0.001004] Console: colour dummy device 80x25388sshClient # [ 0.424973] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]389machine # [ 0.001012] printk: legacy console [tty0] enabled390sshClient # [ 0.424984] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]391machine # [ 0.001215] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)392sshClient # [ 0.425455] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint393machine # [ 0.001222] pid_max: default: 32768 minimum: 301394sshClient # [ 0.425639] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]395machine # [ 0.001304] LSM: initializing lsm=capability,landlock,yama,bpf,ima396machine # [ 0.001502] landlock: Up and running.397sshClient # [ 0.425665] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]398machine # [ 0.001505] Yama: becoming mindful.399machine # [ 0.002340] LSM support for eBPF active400sshClient # [ 0.426139] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint401machine # [ 0.002499] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)402machine # [ 0.002518] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)403machine # [ 0.004380] rcu: Hierarchical SRCU implementation.404sshClient # [ 0.440630] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]405machine # [ 0.004385] rcu: Max phase no-delay instances is 1000.406sshClient # [ 0.440656] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]407machine # [ 0.005621] fsl-mc MSI: its@8080000 domain created408machine # [ 0.005744] EFI services will not be available.409sshClient # [ 0.441094] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint410machine # [ 0.005857] smp: Bringing up secondary CPUs ...411sshClient # [ 0.441273] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]412machine # [ 0.005865] smp: Brought up 1 node, 1 CPU413machine # [ 0.005868] SMP: Total of 1 processors activated.414sshClient # [ 0.441520] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint415machine # [ 0.005871] CPU: All CPU(s) started at EL1416sshClient # [ 0.441706] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]417machine # [ 0.005885] CPU features: detected: Branch Target Identification418sshClient # [ 0.441732] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]419machine # [ 0.005890] CPU features: detected: ARMv8.4 Translation Table Level420machine # [ 0.005893] CPU features: detected: Instruction cache invalidation not required for I/D coherence421machine # [ 0.005897] CPU features: detected: Data cache clean to the PoU not required for I/D coherence422machine # [ 0.005900] CPU features: detected: Common not Private translations423sshClient # [ 0.448234] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint424machine # [ 0.005903] CPU features: detected: CRC32 instructions425sshClient # [ 0.448418] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]426machine # [ 0.005906] CPU features: detected: Data cache clean to Point of Deep Persistence427sshClient # [ 0.448444] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]428machine # [ 0.005910] CPU features: detected: Data cache clean to Point of Persistence429sshClient # [ 0.448920] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint430machine # [ 0.005913] CPU features: detected: Data independent timing control (DIT)431sshClient # [ 0.449108] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]432machine # [ 0.005916] CPU features: detected: E0PD433sshClient # [ 0.449133] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]434machine # [ 0.005918] CPU features: detected: Enhanced Counter Virtualization435sshClient # [ 0.449616] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint436machine # [ 0.005921] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)437sshClient # [ 0.449931] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]438machine # [ 0.005925] CPU features: detected: Enhanced Virtualization Traps439sshClient # [ 0.449943] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]440machine # [ 0.005928] CPU features: detected: Fine Grained Traps441sshClient # [ 0.449968] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]442machine # [ 0.005931] CPU features: detected: Generic authentication (architected QARMA5 algorithm)443machine # [ 0.005936] CPU features: detected: RCpc load-acquire (LDAPR)444machine # [ 0.005939] CPU features: detected: LSE atomic instructions445machine # [ 0.005941] CPU features: detected: Privileged Access Never446machine # [ 0.005944] CPU features: detected: PMUv3447machine # [ 0.005947] CPU features: detected: RAS Extension Support448machine # [ 0.005950] CPU features: detected: RASv1p1 Extension Support449machine # [ 0.005953] CPU features: detected: Random Number Generator450machine # [ 0.005955] CPU features: detected: Speculation barrier (SB)451machine # [ 0.005958] CPU features: detected: Stage-2 Force Write-Back452machine # [ 0.005961] CPU features: detected: TLB range maintenance instructions453machine # [ 0.005965] CPU features: detected: Speculative Store Bypassing Safe (SSBS)454machine # [ 0.006009] alternatives: applying system-wide alternatives455machine # [ 0.009037] CPU features: detected: BBM Level 2 without TLB conflict abort456machine # [ 0.009252] Memory: 893736K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 113500K reserved, 32768K cma-reserved)457machine # [ 0.009630] devtmpfs: initialized458sshClient # [ 0.466582] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint459machine # [ 0.011443] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)460sshClient # [ 0.466779] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]461sshClient # [ 0.466792] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]462machine # [ 0.011466] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).463machine # [ 0.011654] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL464sshClient # [ 0.466817] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]465machine # [ 0.011658] 0 pages in range for non-PLT usage466sshClient # [ 0.467476] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned467machine # [ 0.011659] 508224 pages in range for PLT usage468machine # [ 0.011782] pinctrl core: initialized pinctrl subsystem469sshClient # [ 0.467482] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned470machine # [ 0.012587] DMI not present or invalid.471sshClient # [ 0.467485] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned472machine # [ 0.016537] NET: Registered PF_NETLINK/PF_ROUTE protocol family473sshClient # [ 0.467536] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned474machine # [ 0.018881] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations475machine # [ 0.019042] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations476sshClient # [ 0.467586] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned477machine # [ 0.019205] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations478sshClient # [ 0.467636] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned479sshClient # [ 0.467683] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned480machine # [ 0.019230] audit: initializing netlink subsys (disabled)481sshClient # [ 0.467727] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned482machine # [ 0.019849] thermal_sys: Registered thermal governor 'fair_share'483machine # [ 0.019851] thermal_sys: Registered thermal governor 'bang_bang'484sshClient # [ 0.467771] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned485machine # [ 0.019855] thermal_sys: Registered thermal governor 'step_wise'486sshClient # [ 0.467814] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned487machine # [ 0.019858] thermal_sys: Registered thermal governor 'user_space'488sshClient # [ 0.467857] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned489machine # [ 0.019860] thermal_sys: Registered thermal governor 'power_allocator'490sshClient # [ 0.467900] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned491machine # [ 0.019890] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1492machine # [ 0.019898] cpuidle: using governor ladder493sshClient # [ 0.468049] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned494machine # [ 0.019904] cpuidle: using governor menu495sshClient # [ 0.468092] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned496machine # [ 0.020118] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.497machine # [ 0.020134] ASID allocator initialised with 65536 entries498sshClient # [ 0.468110] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned499machine # [ 0.021461] Serial: AMBA PL011 UART driver500sshClient # [ 0.468126] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned501machine # [ 0.027276] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1502sshClient # [ 0.468144] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned503machine # [ 0.027419] printk: console [ttyAMA0] enabled504sshClient # [ 0.468161] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned505sshClient # [ 0.468178] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned506sshClient # [ 0.468195] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned507sshClient # [ 0.468214] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned508sshClient # [ 0.468235] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned509sshClient # [ 0.468252] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned510sshClient # [ 0.468270] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned511sshClient # [ 0.468288] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned512sshClient # [ 0.468305] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned513sshClient # [ 0.468321] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned514sshClient # [ 0.468338] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned515sshClient # [ 0.468355] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned516sshClient # [ 0.468371] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned517machine # [ 0.152184] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages518sshClient # [ 0.468388] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned519machine # [ 0.152195] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page520sshClient # [ 0.468410] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]521machine # [ 0.152198] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages522sshClient # [ 0.468414] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]523machine # [ 0.152201] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page524machine # [ 0.152204] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages525machine # [ 0.152207] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page526sshClient # [ 0.468417] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]527sshClient # [ 0.469270] pci 0000:00:07.0: enabling device (0000 -> 0002)528machine # [ 0.152210] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages529machine # [ 0.152212] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page530machine # [ 0.153396] fbcon: Taking over console531machine # [ 0.153405] ACPI: Interpreter disabled.532machine # [ 0.163198] iommu: Default domain type: Translated533machine # [ 0.163204] iommu: DMA domain TLB invalidation policy: strict mode534machine # [ 0.163635] SCSI subsystem initialized535machine # [ 0.163859] usbcore: registered new interface driver usbfs536machine # [ 0.163886] usbcore: registered new interface driver hub537machine # [ 0.167047] usbcore: registered new device driver usb538machine # [ 0.167489] pps_core: LinuxPPS API ver. 1 registered539machine # [ 0.167493] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>540machine # [ 0.167501] PTP clock support registered541machine # [ 0.167542] EDAC MC: Ver: 3.0.0542machine # [ 0.171181] scmi_core: SCMI protocol bus registered543machine # [ 0.172669] FPGA manager framework544machine # [ 0.175953] vgaarb: loaded545machine # [ 0.177026] clocksource: Switched to clocksource arch_sys_counter546sshClient # [ 0.524311] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)547machine # [ 0.182992] VFS: Disk quotas dquot_6.6.0548machine # [ 0.183021] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)549machine # [ 0.186620] netfs: FS-Cache loaded550machine # [ 0.186750] pnp: PnP ACPI: disabled551machine # [ 0.190964] NET: Registered PF_INET protocol family552machine # [ 0.191127] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)553sshClient # [ 0.535172] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)554sshClient # [ 0.537910] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)555sshClient # [ 0.549244] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)556sshClient # [ 0.552227] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)557sshClient # [ 0.562413] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)558sshClient # [ 0.564559] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)559machine # [ 0.222281] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)560machine # [ 0.222326] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)561machine # [ 0.222353] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)562machine # [ 0.222401] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)563machine # [ 0.222473] TCP: Hash tables configured (established 8192 bind 8192)564machine # [ 0.222600] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)565machine # [ 0.222633] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)566machine # [ 0.222658] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)567machine # [ 0.222757] NET: Registered PF_UNIX/PF_LOCAL protocol family568machine # [ 0.222778] NET: Registered PF_XDP protocol family569machine # [ 0.222795] PCI: CLS 0 bytes, default 64570sshClient # [ 0.574409] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)571machine # [ 0.223072] Trying to unpack rootfs image as initramfs...572sshClient # [ 0.576982] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)573machine # [ 0.235195] kvm [1]: HYP mode not available574sshClient # [ 0.580565] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)575sshClient # [ 0.591775] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)576sshClient # [ 0.597762] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled577sshClient # [ 0.607399] msm_serial: driver initialized578sshClient # [ 0.607537] SuperH (H)SCI(F) driver initialized579sshClient # [ 0.607593] STM32 USART driver initialized580sshClient # [ 0.636963] loop: module loaded581sshClient # [ 0.637160] virtio_blk virtio2: 1/0/0 default/read/poll queues582sshClient # [ 0.637998] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)583sshClient # [ 0.650919] megasas: 07.734.00.00-rc1584sshClient # [ 0.651683] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]585sshClient # [ 0.653960] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000586sshClient # [ 0.653979] Intel/Sharp Extended Query Table at 0x0031587sshClient # [ 0.659042] Using buffer write method588sshClient # [ 0.659112] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]589sshClient # [ 0.660834] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000590sshClient # [ 0.660879] Intel/Sharp Extended Query Table at 0x0031591sshClient # [ 0.674843] Using buffer write method592sshClient # [ 0.674870] Concatenating MTD devices:593sshClient # [ 0.674872] (0): "0.flash"594sshClient # [ 0.674875] (1): "0.flash"595sshClient # [ 0.674877] into device "0.flash"596machine # [ 0.360970] Initialise system trusted keyrings597machine # [ 0.361745] workingset: timestamp_bits=42 max_order=18 bucket_order=0598machine # [ 0.362184] squashfs: version 4.0 (2009/01/31) Phillip Lougher599machine # [ 0.362242] 9p: Installing v9fs 9p2000 file system support600machine # [ 0.390333] Key type asymmetric registered601machine # [ 0.390353] Asymmetric key parser 'x509' registered602machine # [ 0.390433] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)603machine # [ 0.390559] io scheduler mq-deadline registered604machine # [ 0.390563] io scheduler kyber registered605machine # [ 0.402953] pl061_gpio 9030000.pl061: PL061 GPIO chip registered606machine # [ 0.403545] ledtrig-cpu: registered to indicate activity on CPUs607machine # [ 0.403904] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:608machine # [ 0.403918] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000609machine # [ 0.403928] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000610machine # [ 0.403934] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000611machine # [ 0.403957] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits612machine # [ 0.403976] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]613machine # [ 0.404047] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00614machine # [ 0.404054] pci_bus 0000:00: root bus resource [bus 00-ff]615machine # [ 0.404058] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]616machine # [ 0.404061] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]617machine # [ 0.404064] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]618machine # [ 0.404128] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint619machine # [ 0.404556] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint620machine # [ 0.404739] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]621machine # [ 0.404751] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]622machine # [ 0.404776] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]623machine # [ 0.404787] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]624machine # [ 0.429351] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint625machine # [ 0.429546] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]626machine # [ 0.429559] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]627machine # [ 0.429584] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]628machine # [ 0.430074] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint629machine # [ 0.430251] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]630machine # [ 0.430263] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]631machine # [ 0.430288] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]632machine # [ 0.430752] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint633machine # [ 0.430928] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]634machine # [ 0.430940] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]635machine # [ 0.430964] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]636machine # [ 0.430976] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]637machine # [ 0.431428] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint638machine # [ 0.431611] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]639machine # [ 0.431636] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]640machine # [ 0.432078] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint641machine # [ 0.432256] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]642machine # [ 0.432281] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]643machine # [ 0.432656] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint644machine # [ 0.432827] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]645machine # [ 0.450219] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint646machine # [ 0.450400] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]647machine # [ 0.450425] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]648machine # [ 0.450880] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint649machine # [ 0.451063] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]650machine # [ 0.451088] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]651machine # [ 0.451533] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint652machine # [ 0.451714] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]653machine # [ 0.451739] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]654machine # [ 0.452193] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint655machine # [ 0.452487] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]656machine # [ 0.452498] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]657machine # [ 0.452522] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]658machine # [ 0.452976] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint659machine # [ 0.469223] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]660machine # [ 0.469241] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]661machine # [ 0.469267] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]662machine # [ 0.469955] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned663machine # [ 0.469961] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned664machine # [ 0.469964] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned665machine # [ 0.470010] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned666machine # [ 0.470053] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned667machine # [ 0.470096] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned668machine # [ 0.470139] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned669machine # [ 0.470183] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned670machine # [ 0.470226] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned671machine # [ 0.470269] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned672machine # [ 0.470312] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned673machine # [ 0.470354] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned674machine # [ 0.470465] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned675machine # [ 0.470507] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned676machine # [ 0.470524] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned677machine # [ 0.470542] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned678machine # [ 0.470558] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned679machine # [ 0.470575] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned680machine # [ 0.470592] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned681machine # [ 0.470610] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned682machine # [ 0.470629] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned683machine # [ 0.470650] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned684machine # [ 0.470668] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned685machine # [ 0.470687] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned686machine # [ 0.470705] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned687machine # [ 0.470721] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned688machine # [ 0.470738] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned689machine # [ 0.470755] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned690machine # [ 0.470772] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned691machine # [ 0.470790] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned692machine # [ 0.470807] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned693machine # [ 0.470829] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]694machine # [ 0.470834] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]695machine # [ 0.470837] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]696machine # [ 0.471666] pci 0000:00:07.0: enabling device (0000 -> 0002)697machine # [ 0.532604] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)698machine # [ 0.535771] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)699machine # [ 0.546183] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)700machine # [ 0.548194] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)701machine # [ 0.551844] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)702machine # [ 0.562056] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)703machine # [ 0.563857] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)704machine # [ 0.567449] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)705machine # [ 0.577316] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)706machine # [ 0.579240] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)707machine # [ 0.581527] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)708machine # [ 0.594356] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled709machine # [ 0.596198] msm_serial: driver initialized710machine # [ 0.596364] SuperH (H)SCI(F) driver initialized711machine # [ 0.596420] STM32 USART driver initialized712sshClient # [ 0.945595] Freeing initrd memory: 26392K713sshClient # [ 0.951766] tun: Universal TUN/TAP device driver, 1.6714sshClient # [ 0.955731] thunder_xcv, ver 1.0715sshClient # [ 0.955760] thunder_bgx, ver 1.0716sshClient # [ 0.955785] nicpf, ver 1.0717sshClient # [ 0.956364] e1000: Intel(R) PRO/1000 Network Driver718sshClient # [ 0.956368] e1000: Copyright (c) 1999-2006 Intel Corporation.719sshClient # [ 0.956392] e1000e: Intel(R) PRO/1000 Network Driver720sshClient # [ 0.956399] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.721sshClient # [ 0.956421] igb: Intel(R) Gigabit Ethernet Network Driver722sshClient # [ 0.956425] igb: Copyright (c) 2007-2014 Intel Corporation.723sshClient # [ 0.956444] igbvf: Intel(R) Gigabit Virtual Function Network Driver724sshClient # [ 0.956447] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.725sshClient # [ 0.956583] sky2: driver version 1.30726sshClient # [ 0.958942] ehci-pci 0000:00:07.0: EHCI Host Controller727sshClient # [ 0.958961] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1728sshClient # [ 0.959230] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000729sshClient # [ 0.969240] usbcore: registered new interface driver usb-storage730sshClient # [ 0.969333] usbcore: registered new interface driver usbserial_generic731sshClient # [ 0.969344] usbserial: USB Serial support registered for generic732sshClient # [ 0.969975] hv_vmbus: registering driver hyperv_keyboard733sshClient # [ 0.973182] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00734sshClient # [ 0.973513] hub 1-0:1.0: USB hub found735sshClient # [ 0.973541] hub 1-0:1.0: 6 ports detected736sshClient # [ 0.976444] rtc-pl031 9010000.pl031: registered as rtc0737sshClient # [ 0.976465] rtc-pl031 9010000.pl031: setting system clock to 2026-09-24T02:50:44 UTC (1790218244)738sshClient # [ 0.976775] i2c_dev: i2c /dev entries driver739machine # [ 0.637302] loop: module loaded740machine # [ 0.637507] virtio_blk virtio2: 1/0/0 default/read/poll queues741sshClient # [ 0.982676] sdhci: Secure Digital Host Controller Interface driver742sshClient # [ 0.982683] sdhci: Copyright(c) Pierre Ossman743machine # [ 0.638604] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)744sshClient # [ 0.982966] Synopsys Designware Multimedia Card Interface Driver745sshClient # [ 0.983345] sdhci-pltfm: SDHCI platform and OF driver helper746sshClient # [ 0.984911] hid: raw HID events driver (C) Jiri Kosina747sshClient # [ 0.985148] usbcore: registered new interface driver usbhid748sshClient # [ 0.985156] usbhid: USB HID core driver749machine # [ 0.643943] megasas: 07.734.00.00-rc1750sshClient # [ 0.990982] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available751machine # [ 0.644707] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]752sshClient # [ 0.992589] drop_monitor: Initializing network drop monitor service753sshClient # [ 0.992806] NET: Registered PF_INET6 protocol family754sshClient # [ 0.993368] Segment Routing with IPv6755sshClient # [ 0.993379] In-situ OAM (IOAM) with IPv6756sshClient # [ 0.993408] NET: Registered PF_PACKET protocol family757sshClient # [ 0.993467] 9pnet: Installing 9P2000 support758sshClient # [ 0.993503] Key type dns_resolver registered759machine # [ 0.658277] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000760sshClient # [ 1.004719] registered taskstats version 1761machine # [ 0.658299] Intel/Sharp Extended Query Table at 0x0031762sshClient # [ 1.004864] Loading compiled-in X.509 certificates763machine # [ 0.659900] Using buffer write method764machine # [ 0.659940] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]765sshClient # [ 1.013687] Demotion targets for Node 0: null766sshClient # [ 1.013793] Key type .fscrypt registered767sshClient # [ 1.013803] Key type fscrypt-provisioning registered768sshClient # [ 1.013899] ima: No TPM chip found, activating TPM-bypass!769sshClient # [ 1.013916] ima: Allocated hash algorithm: sha1770sshClient # [ 1.013937] ima: No architecture policies found771machine # [ 0.670826] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000772machine # [ 0.670883] Intel/Sharp Extended Query Table at 0x0031773machine # [ 0.672575] Using buffer write method774machine # [ 0.672591] Concatenating MTD devices:775machine # [ 0.672593] (0): "0.flash"776machine # [ 0.672595] (1): "0.flash"777machine # [ 0.672597] into device "0.flash"778sshClient # [ 1.018268] input: gpio-keys as /devices/platform/gpio-keys/input/input0779sshClient # [ 1.036972] clk: Disabling unused clocks780sshClient # [ 1.036990] PM: genpd: Disabling unused power domains781sshClient # [ 1.041238] Freeing unused kernel memory: 4736K782sshClient # [ 1.041429] Run /init as init process783sshClient # [ 1.052514] systemd[1]: Successfully made /usr/ read-only.784sshClient # [ 1.218261] usb 1-1: new high-speed USB device number 2 using ehci-pci785machine # [ 0.960732] Freeing initrd memory: 26388K786machine # [ 0.967176] tun: Universal TUN/TAP device driver, 1.6787machine # [ 0.971086] thunder_xcv, ver 1.0788machine # [ 0.971117] thunder_bgx, ver 1.0789machine # [ 0.971140] nicpf, ver 1.0790machine # [ 0.971693] e1000: Intel(R) PRO/1000 Network Driver791machine # [ 0.971697] e1000: Copyright (c) 1999-2006 Intel Corporation.792machine # [ 0.971721] e1000e: Intel(R) PRO/1000 Network Driver793machine # [ 0.971727] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.794machine # [ 0.971753] igb: Intel(R) Gigabit Ethernet Network Driver795machine # [ 0.971756] igb: Copyright (c) 2007-2014 Intel Corporation.796machine # [ 0.971776] igbvf: Intel(R) Gigabit Virtual Function Network Driver797machine # [ 0.971780] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.798machine # [ 0.971913] sky2: driver version 1.30799machine # [ 0.980856] usbcore: registered new interface driver usb-storage800machine # [ 0.980941] usbcore: registered new interface driver usbserial_generic801machine # [ 0.980952] usbserial: USB Serial support registered for generic802machine # [ 0.981766] ehci-pci 0000:00:07.0: EHCI Host Controller803machine # [ 0.981785] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1804machine # [ 0.982076] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000805machine # [ 0.987321] hv_vmbus: registering driver hyperv_keyboard806machine # [ 0.988231] rtc-pl031 9010000.pl031: registered as rtc0807machine # [ 0.988256] rtc-pl031 9010000.pl031: setting system clock to 2026-09-24T02:50:45 UTC (1790218245)808machine # [ 0.988569] i2c_dev: i2c /dev entries driver809machine # [ 0.994911] sdhci: Secure Digital Host Controller Interface driver810machine # [ 0.994918] sdhci: Copyright(c) Pierre Ossman811machine # [ 0.995201] Synopsys Designware Multimedia Card Interface Driver812machine # [ 0.995585] sdhci-pltfm: SDHCI platform and OF driver helper813machine # [ 0.999817] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00814machine # [ 1.000133] hub 1-0:1.0: USB hub found815machine # [ 1.000185] hub 1-0:1.0: 6 ports detected816machine # [ 1.001004] hid: raw HID events driver (C) Jiri Kosina817machine # [ 1.003610] usbcore: registered new interface driver usbhid818machine # [ 1.003620] usbhid: USB HID core driver819machine # [ 1.004805] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available820machine # [ 1.008706] drop_monitor: Initializing network drop monitor service821machine # [ 1.008840] NET: Registered PF_INET6 protocol family822machine # [ 1.011583] Segment Routing with IPv6823machine # [ 1.011596] In-situ OAM (IOAM) with IPv6824machine # [ 1.011634] NET: Registered PF_PACKET protocol family825machine # [ 1.011703] 9pnet: Installing 9P2000 support826machine # [ 1.011743] Key type dns_resolver registered827machine # [ 1.020982] registered taskstats version 1828machine # [ 1.022188] Loading compiled-in X.509 certificates829machine # [ 1.029826] Demotion targets for Node 0: null830machine # [ 1.029938] Key type .fscrypt registered831machine # [ 1.029944] Key type fscrypt-provisioning registered832sshClient # [ 1.370692] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1833machine # [ 1.030038] ima: No TPM chip found, activating TPM-bypass!834machine # [ 1.030055] ima: Allocated hash algorithm: sha1835machine # [ 1.030076] ima: No architecture policies found836machine # [ 1.030783] input: gpio-keys as /devices/platform/gpio-keys/input/input0837machine # [ 1.053150] clk: Disabling unused clocks838sshClient # [ 1.386994] systemd[1]: systemd 263-1f286f9 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)839machine # [ 1.053168] PM: genpd: Disabling unused power domains840machine # [ 1.056198] Freeing unused kernel memory: 4736K841machine # [ 1.056407] Run /init as init process842sshClient # [ 1.387042] systemd[1]: Detected virtualization qemu.843sshClient # [ 1.387162] systemd[1]: Detected architecture arm64.844sshClient # [ 1.387178] systemd[1]: Running in initrd.845sshClient # [ 1.388148] systemd[1]: Initializing machine ID from random generator.846sshClient # [ 1.388526] systemd[1]: Hostname set to <sshClient>.847machine # [ 1.075456] systemd[1]: Successfully made /usr/ read-only.848sshClient # [ 1.462751] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0849sshClient # [ 1.571832] systemd[1]: bpf-restrict-fs: LSM BPF program attached850sshClient # [ 1.586234] usb 1-2: new high-speed USB device number 3 using ehci-pci851machine # [ 1.245130] usb 1-1: new high-speed USB device number 2 using ehci-pci852sshClient # [ 1.627260] systemd[1]: Queued start job for default target Initrd Default Target.853sshClient # [ 1.636177] systemd[1]: Created slice Slice /system/modprobe.854sshClient # [ 1.636350] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.855sshClient # [ 1.636375] systemd[1]: Expecting device /dev/disk/by-label/nixos...856sshClient # [ 1.636392] systemd[1]: Reached target Path Units.857sshClient # [ 1.636405] systemd[1]: Reached target Slice Units.858sshClient # [ 1.636418] systemd[1]: Reached target Swaps.859sshClient # [ 1.636431] systemd[1]: Reached target Timer Units.860sshClient # [ 1.636554] systemd[1]: Listening on D-Bus System Message Bus Socket.861sshClient # [ 1.636691] systemd[1]: Listening on Journal Socket (/dev/log).862sshClient # [ 1.636828] systemd[1]: Listening on Journal Sockets.863sshClient # [ 1.636897] systemd[1]: Listening on udev Kernel Socket.864sshClient # [ 1.636910] systemd[1]: Reached target Socket Units.865sshClient # [ 1.638267] systemd[1]: Starting Create List of Static Device Nodes...866sshClient # [ 1.638326] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs867sshClient # [ 1.658325] systemd[1]: Mounting Kernel Configuration File System...868sshClient # [ 1.663687] systemd[1]: Starting Journal Service...869sshClient # [ 1.681678] systemd[1]: Starting Load Kernel Modules...870sshClient # [ 1.681796] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os871sshClient # [ 1.702688] systemd[1]: Starting Coldplug All udev Devices...872sshClient # [ 1.707636] systemd[1]: Finished Create List of Static Device Nodes.873sshClient # [ 1.708383] systemd[1]: Mounted Kernel Configuration File System.874sshClient # [ 1.732821] systemd-journald[71]: Collecting audit messages is disabled.875sshClient # [ 1.735597] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...876machine # [ 1.397276] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1877sshClient # [ 1.747192] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.878sshClient # [ 1.753653] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2879sshClient # [ 1.753779] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0880sshClient # [ 1.753953] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev881machine # [ 1.409476] systemd[1]: systemd 263-1f286f9 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)882machine # [ 1.409519] systemd[1]: Detected virtualization qemu.883machine # [ 1.409642] systemd[1]: Detected architecture arm64.884sshClient # [ 1.773754] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0885machine # [ 1.409659] systemd[1]: Running in initrd.886sshClient # [ 1.773987] [drm] features: -virgl +edid -resource_blob -host_visible887sshClient # [ 1.773991] [drm] features: -context_init888machine # [ 1.410632] systemd[1]: Initializing machine ID from random generator.889machine # [ 1.410899] systemd[1]: Hostname set to <machine>.890sshClient # [ 1.790885] [drm] number of scanouts: 1891sshClient # [ 1.790904] [drm] number of cap sets: 0892sshClient # [ 1.791998] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic893sshClient # [ 1.792009] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0894sshClient # [ 1.797213] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.895sshClient # [ 1.800119] systemd[1]: Starting Create Static Device Nodes in /dev...896sshClient # [ 1.818502] Console: switching to colour frame buffer device 160x50897machine # [ 1.489290] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0898sshClient # [ 1.850855] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device899sshClient # [ 1.862890] systemd[1]: Finished Create Static Device Nodes in /dev.900sshClient # [ 1.863302] systemd[1]: Reached target Preparation for Local File Systems.901sshClient # [ 1.863333] systemd[1]: Reached target Local File Systems.902sshClient # [ 1.868970] systemd[1]: Starting Rule-based Manager for Device Events and Files...903sshClient # [ 1.886298] systemd[1]: Finished Load Kernel Modules.904sshClient # [ 1.889078] systemd[1]: Starting Apply Kernel Variables...905sshClient # [ 1.902781] systemd-modules-load[72]: Inserted module 'dm_mod'906sshClient # [ 1.905719] systemd-modules-load[72]: Module 'virtio_balloon' is built in907sshClient # [ 1.922399] systemd[1]: Started Journal Service.908machine # [ 1.581430] systemd[1]: bpf-restrict-fs: LSM BPF program attached909sshClient # [ 1.916419] systemd-modules-load[72]: Module 'virtio_console' is built in910sshClient # [ 1.925272] systemd-modules-load[72]: Inserted module 'virtio_gpu'911sshClient # [ 1.929526] systemd-modules-load[72]: Module 'virtio_rng' is built in912sshClient # [ 1.932283] systemd[1]: Starting Create System Files and Directories...913machine # [ 1.609114] usb 1-2: new high-speed USB device number 3 using ehci-pci914sshClient # [ 1.940417] systemd[1]: Finished Apply Kernel Variables.915machine # [ 1.636941] systemd[1]: Queued start job for default target Initrd Default Target.916machine # [ 1.646112] systemd[1]: Created slice Slice /system/modprobe.917machine # [ 1.646310] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.918machine # [ 1.646337] systemd[1]: Expecting device /dev/disk/by-label/nixos...919sshClient # [ 1.977590] systemd-udevd[78]: Using default interface naming scheme 'v261'.920machine # [ 1.646360] systemd[1]: Reached target Path Units.921machine # [ 1.646372] systemd[1]: Reached target Slice Units.922machine # [ 1.646386] systemd[1]: Reached target Swaps.923machine # [ 1.646401] systemd[1]: Reached target Timer Units.924machine # [ 1.646541] systemd[1]: Listening on D-Bus System Message Bus Socket.925machine # [ 1.646692] systemd[1]: Listening on Journal Socket (/dev/log).926machine # [ 1.646819] systemd[1]: Listening on Journal Sockets.927machine # [ 1.646892] systemd[1]: Listening on udev Kernel Socket.928machine # [ 1.646906] systemd[1]: Reached target Socket Units.929machine # [ 1.648306] systemd[1]: Starting Create List of Static Device Nodes...930sshClient # [ 1.984610] systemd[1]: Finished Create System Files and Directories.931machine # [ 1.648372] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs932machine # [ 1.666301] systemd[1]: Mounting Kernel Configuration File System...933machine # [ 1.677209] systemd[1]: Starting Journal Service...934sshClient # [ 2.007394] systemd[1]: Started Rule-based Manager for Device Events and Files.935machine # [ 1.689594] systemd[1]: Starting Load Kernel Modules...936machine # [ 1.689744] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os937machine # [ 1.717296] systemd[1]: Starting Coldplug All udev Devices...938machine # [ 1.723107] systemd-journald[71]: Collecting audit messages is disabled.939machine # [ 1.733333] systemd[1]: Finished Create List of Static Device Nodes.940sshClient # [ 2.089914] systemd[1]: Starting Virtual Console Setup...941machine # [ 1.771664] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...942machine # [ 1.772414] systemd[1]: Mounted Kernel Configuration File System.943machine # [ 1.775435] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2944machine # [ 1.775593] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0945machine # [ 1.826205] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.946machine # [ 1.837884] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.947machine # [ 1.839813] systemd[1]: Starting Create Static Device Nodes in /dev...948sshClient # [ 2.169539] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.949machine # [ 1.842994] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev950sshClient # [ 2.172550] systemd[1]: Finished Virtual Console Setup.951machine # [ 1.861928] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0952machine # [ 1.862164] [drm] features: -virgl +edid -resource_blob -host_visible953machine # [ 1.862168] [drm] features: -context_init954machine # [ 1.862906] [drm] number of scanouts: 1955machine # [ 1.862917] [drm] number of cap sets: 0956machine # [ 1.881426] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic957machine # [ 1.881448] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0958machine # [ 1.905868] systemd[1]: Finished Create Static Device Nodes in /dev.959machine # [ 1.906082] systemd[1]: Reached target Preparation for Local File Systems.960machine # [ 1.906106] systemd[1]: Reached target Local File Systems.961machine # [ 1.907973] systemd[1]: Starting Rule-based Manager for Device Events and Files...962machine # [ 1.913454] Console: switching to colour frame buffer device 160x50963machine # [ 1.903158] systemd-modules-load[73]: Inserted module 'dm_mod'964machine # [ 1.910273] systemd-modules-load[73]: Module 'virtio_balloon' is built in965machine # [ 1.911925] systemd-modules-load[73]: Module 'virtio_console' is built in[ 1.925401] systemd[1]: Started Journal Service.966machine # 967machine # [ 1.926138] systemd[1]: Starting Create System Files and Directories...968machine # [ 1.941722] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device969machine # [ 1.936829] systemd-modules-load[73]: Inserted module 'virtio_gpu'970machine # [ 1.938828] systemd-modules-load[73]: Module 'virtio_rng' is built in971machine # [ 1.949215] systemd[1]: Finished Load Kernel Modules.972machine # [ 1.980202] systemd[1]: Starting Apply Kernel Variables...973machine # [ 2.025020] systemd[1]: Finished Create System Files and Directories.974machine # [ 2.027835] systemd-udevd[78]: Using default interface naming scheme 'v261'.975machine # [ 2.039796] systemd[1]: Finished Apply Kernel Variables.976machine # [ 2.061796] systemd[1]: Started Rule-based Manager for Device Events and Files.977machine # [ 2.136956] systemd[1]: Starting Virtual Console Setup...978machine # [ 2.216565] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.979machine # [ 2.220243] systemd[1]: Finished Virtual Console Setup.980sshClient # [ 2.772454] systemd[1]: Finished Coldplug All udev Devices.981sshClient # [ 2.774270] systemd[1]: Reached target System Initialization.982sshClient # [ 2.780838] systemd[1]: Reached target Basic System.983sshClient # [ 2.949951] (udev-worker)[98]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.984sshClient # [ 2.953969] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.985sshClient # [ 3.015427] systemd[1]: Found device /dev/disk/by-label/nixos.986sshClient # [ 3.020080] systemd[1]: Reached target Initrd Root Device.987sshClient # [ 3.023653] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...988sshClient # [ 3.077627] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks989machine # [ 2.812339] systemd[1]: Finished Coldplug All udev Devices.990machine # [ 2.813485] systemd[1]: Reached target System Initialization.991machine # [ 2.814336] systemd[1]: Reached target Basic System.992sshClient # [ 3.204292] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.993sshClient # [ 3.216211] systemd[1]: Mounting /sysroot...994machine # [ 2.963955] (udev-worker)[90]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.995machine # [ 3.009025] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.996machine # [ 3.059581] systemd[1]: Found device /dev/disk/by-label/nixos.997machine # [ 3.062142] systemd[1]: Reached target Initrd Root Device.998machine # [ 3.068386] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...999sshClient # [ 3.469239] EXT4-fs (vda): mounted filesystem f4329853-74ce-4408-808f-d2de807d141b r/w with ordered data mode. Quota mode: none.1000sshClient # [ 3.456111] systemd[1]: Mounted /sysroot.1001sshClient # [ 3.459170] systemd[1]: Reached target Initrd Root File System.1002sshClient # [ 3.465035] systemd[1]: Starting Mountpoints Configured in the Real Root...1003machine # [ 3.125024] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks1004sshClient # [ 3.500410] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.1005sshClient # [ 3.506948] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...1006sshClient # [ 3.512876] systemd[1]: Reloading...1007machine # [ 3.313386] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1008machine # [ 3.320873] systemd[1]: Mounting /sysroot...1009sshClient # [ 3.719270] systemd[1]: Reloading finished in 209 ms.1010sshClient # [ 3.750236] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...1011sshClient # [ 3.754886] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...1012sshClient # [ 3.760374] systemd[1]: Mounting /sysroot/nix/.ro-store...1013sshClient # [ 3.780292] systemd[1]: Mounting /sysroot/nix/.rw-store...1014sshClient # [ 3.784917] systemd[1]: Mounting /sysroot/run...1015sshClient # [ 3.801107] systemd[1]: Mounting /sysroot/tmp/shared...1016machine # [ 3.486940] EXT4-fs (vda): mounted filesystem 313095f8-9271-440c-b058-9bbbdd1fbe6e r/w with ordered data mode. Quota mode: none.1017machine # [ 3.475867] systemd[1]: Mounted /sysroot.1018machine # [ 3.476868] systemd[1]: Reached target Initrd Root File System.1019machine # [ 3.488181] systemd[1]: Starting Mountpoints Configured in the Real Root...1020sshClient # [ 3.834839] systemd[1]: Mounting /sysroot/tmp/xchg...1021sshClient # [ 3.850531] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1022machine # [ 3.508391] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.1023machine # [ 3.514946] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...1024machine # [ 3.520241] systemd[1]: Reloading...1025sshClient # [ 3.876722] systemd[1]: Finished Mountpoints Configured in the Real Root.1026sshClient # [ 3.878994] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1027sshClient # [ 3.921091] fuse: init (API version 7.45)1028sshClient # [ 3.931824] virtiofs virtio6: discovered new tag: nix-store1029sshClient # [ 3.932613] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1030sshClient # [ 3.948008] virtiofs virtio7: discovered new tag: shared1031sshClient # [ 3.948843] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1032sshClient # [ 3.956600] virtiofs virtio8: discovered new tag: xchg1033sshClient # [ 3.957488] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1034sshClient # [ 3.945966] systemd[1]: Mounted /sysroot/nix/.rw-store.1035sshClient # [ 3.950562] systemd[1]: Mounted /sysroot/run.1036sshClient # [ 3.966289] systemd[1]: Mounted /sysroot/nix/.ro-store.1037sshClient # [ 3.970297] systemd[1]: Mounted /sysroot/tmp/shared.1038sshClient # [ 3.978227] systemd[1]: Mounted /sysroot/tmp/xchg.1039sshClient # [ 3.991262] systemd[1]: Starting rw-sysroot-nix-store.service...1040sshClient # [ 4.014499] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1041sshClient # [ 4.017718] systemd[1]: Finished rw-sysroot-nix-store.service.1042sshClient # [ 4.021014] systemd[1]: Mounting /sysroot/nix/store...1043machine # [ 3.725317] systemd[1]: Reloading finished in 207 ms.1044sshClient # [ 4.072799] systemd[1]: Mounted /sysroot/nix/store.1045sshClient # [ 4.074931] systemd[1]: Reached target Initrd File Systems.1046sshClient # [ 4.076659] systemd[1]: Starting Find NixOS closure...1047sshClient # [ 4.087528] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1048machine # [ 3.757954] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...1049machine # [ 3.762666] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...1050machine # [ 3.770553] systemd[1]: Mounting /sysroot/nix/.ro-store...1051sshClient # [ 4.129953] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1052machine # [ 3.787662] systemd[1]: Mounting /sysroot/nix/.rw-store...1053sshClient # [ 4.141392] systemd[1]: Finished Find NixOS closure.1054machine # [ 3.798677] systemd[1]: Mounting /sysroot/run...1055sshClient # [ 4.145374] systemd[1]: Reached target Initrd Default Target.1056sshClient # [ 4.149478] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1057machine # [ 3.819663] systemd[1]: Mounting /sysroot/tmp/shared...1058sshClient # [ 4.183618] systemd[1]: Stopped target Initrd Default Target.1059sshClient # [ 4.188287] systemd[1]: Stopped target Basic System.1060sshClient # [ 4.189878] systemd[1]: Stopped target Initrd Root Device.1061sshClient # [ 4.193435] systemd[1]: Stopped target Path Units.1062sshClient # [ 4.196335] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1063machine # [ 3.855576] systemd[1]: Mounting /sysroot/tmp/xchg...1064sshClient # [ 4.199601] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1065sshClient # [ 4.205721] systemd[1]: Stopped target Slice Units.1066sshClient # [ 4.206513] systemd[1]: Stopped target Socket Units.1067sshClient # [ 4.207236] systemd[1]: Stopped target System Initialization.1068sshClient # [ 4.213377] systemd[1]: Stopped target Swaps.1069sshClient # [ 4.214775] systemd[1]: Stopped target Timer Units.1070sshClient # [ 4.219176] systemd[1]: dbus.socket: Deactivated successfully.1071sshClient # [ 4.222018] systemd[1]: Closed D-Bus System Message Bus Socket.1072sshClient # [ 4.222915] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1073machine # [ 3.889527] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1074sshClient # [ 4.233088] systemd[1]: Stopped Find NixOS closure.1075sshClient # [ 4.233961] systemd[1]: Starting rw-sysroot-nix-store.service...1076sshClient # [ 4.234967] systemd[1]: systemd-sysctl.service: Deactivated successfully.1077sshClient # [ 4.235960] systemd[1]: Stopped Apply Kernel Variables.1078machine # [ 3.894063] systemd[1]: Finished Mountpoints Configured in the Real Root.1079machine # [ 3.896335] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1080sshClient # [ 4.247344] systemd[1]: systemd-modules-load.service: Deactivated successfully.1081sshClient # [ 4.252603] systemd[1]: Stopped Load Kernel Modules.1082machine # [ 3.911694] systemd[1]: Mounted /sysroot/nix/.rw-store.1083sshClient # [ 4.257477] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1084sshClient # [ 4.259198] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1085sshClient # [ 4.261801] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1086sshClient # [ 4.262893] systemd[1]: Stopped Create System Files and Directories.1087sshClient # [ 4.263818] systemd[1]: Stopped target Local File Systems.1088sshClient # [ 4.264791] systemd[1]: Stopped target Preparation for Local File Systems.1089sshClient # [ 4.265759] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1090sshClient # [ 4.266776] systemd[1]: Stopped Coldplug All udev Devices.1091machine # [ 3.939142] fuse: init (API version 7.45)1092sshClient # [ 4.267567] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1093sshClient # [ 4.274256] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1094machine # [ 3.949535] virtiofs virtio6: discovered new tag: nix-store1095sshClient # [ 4.280450] systemd[1]: Stopped Virtual Console Setup.1096machine # [ 3.950442] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1097sshClient # [ 4.281320] systemd[1]: initrd-cleanup.service: Deactivated successfully.1098sshClient # [ 4.282275] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1099sshClient # [ 4.283209] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1100sshClient # [ 4.285551] systemd[1]: Finished rw-sysroot-nix-store.service.1101sshClient # [ 4.286413] systemd[1]: systemd-udevd.service: Deactivated successfully.1102sshClient # [ 4.287390] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1103sshClient # [ 4.288691] systemd[1]: systemd-udevd.service: Consumed 1.187s CPU time over 2.421s wall clock time, 17.7M memory peak.1104sshClient # [ 4.290208] systemd[1]: Starting Cleanup udev Database...1105sshClient # [ 4.291050] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1106sshClient # [ 4.296268] systemd[1]: Stopped Create Static Device Nodes in /dev.1107machine # [ 3.968965] virtiofs virtio7: discovered new tag: shared1108sshClient # [ 4.297264] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1109sshClient # [ 4.299708] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1110machine # [ 3.974484] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1111sshClient # [ 4.304284] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1112sshClient # [ 4.305350] systemd[1]: Stopped Create List of Static Device Nodes.1113machine # [ 3.964046] systemd[1]: Mounted /sysroot/run.1114machine # [ 3.983992] virtiofs virtio8: discovered new tag: xchg1115machine # [ 3.984806] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1116sshClient # [ 4.333189] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1117machine # [ 3.992955] systemd[1]: Starting rw-sysroot-nix-store.service...1118sshClient # [ 4.336537] systemd[1]: Finished Cleanup udev Database.1119sshClient # [ 4.337775] systemd[1]: Reached target Switch Root.1120machine # [ 3.997076] systemd[1]: Mounted /sysroot/nix/.ro-store.1121sshClient # [ 4.340337] systemd[1]: Starting NixOS Activation...1122machine # [ 3.999151] systemd[1]: Mounted /sysroot/tmp/shared.1123machine # [ 4.001396] systemd[1]: Mounted /sysroot/tmp/xchg.1124machine # [ 4.030631] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1125machine # [ 4.033848] systemd[1]: Finished rw-sysroot-nix-store.service.1126machine # [ 4.036167] systemd[1]: Mounting /sysroot/nix/store...1127machine # [ 4.089233] systemd[1]: Mounted /sysroot/nix/store.1128machine # [ 4.090132] systemd[1]: Reached target Initrd File Systems.1129machine # [ 4.092430] systemd[1]: Starting Find NixOS closure...1130sshClient # [ 4.435196] initrd-nixos-activation-start[166]: booting system configuration /nix/store/5rkl1zpvxpi0gfiqglh9l3x1vzj96pry-nixos-system-sshClient-test1131machine # [ 4.098254] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1132sshClient # [ 4.472258] initrd-nixos-activation-start[166]: running activation script...1133machine # [ 4.143421] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1134machine # [ 4.161068] systemd[1]: Finished Find NixOS closure.1135machine # [ 4.164110] systemd[1]: Reached target Initrd Default Target.1136machine # [ 4.177158] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1137machine # [ 4.205383] systemd[1]: Stopped target Initrd Default Target.1138machine # [ 4.208104] systemd[1]: Stopped target Basic System.1139machine # [ 4.210387] systemd[1]: Stopped target Initrd Root Device.1140machine # [ 4.212918] systemd[1]: Stopped target Path Units.1141machine # [ 4.216762] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1142machine # [ 4.220114] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1143machine # [ 4.225655] systemd[1]: Stopped target Slice Units.1144machine # [ 4.227653] systemd[1]: Stopped target Socket Units.1145machine # [ 4.228567] systemd[1]: Stopped target System Initialization.1146machine # [ 4.232274] systemd[1]: Stopped target Swaps.1147machine # [ 4.234120] systemd[1]: Stopped target Timer Units.1148machine # [ 4.237044] systemd[1]: dbus.socket: Deactivated successfully.1149machine # [ 4.240899] systemd[1]: Closed D-Bus System Message Bus Socket.1150machine # [ 4.244192] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1151machine # [ 4.248155] systemd[1]: Stopped Find NixOS closure.1152machine # [ 4.250707] systemd[1]: Starting rw-sysroot-nix-store.service...1153machine # [ 4.255108] systemd[1]: systemd-sysctl.service: Deactivated successfully.1154machine # [ 4.257430] systemd[1]: Stopped Apply Kernel Variables.1155machine # [ 4.258327] systemd[1]: systemd-modules-load.service: Deactivated successfully.1156machine # [ 4.259463] systemd[1]: Stopped Load Kernel Modules.1157machine # [ 4.269292] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1158machine # [ 4.270542] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1159machine # [ 4.279473] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1160machine # [ 4.284477] systemd[1]: Stopped Create System Files and Directories.1161machine # [ 4.285992] systemd[1]: Stopped target Local File Systems.1162machine # [ 4.288222] systemd[1]: Stopped target Preparation for Local File Systems.1163machine # [ 4.290332] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1164machine # [ 4.294142] systemd[1]: Stopped Coldplug All udev Devices.1165machine # [ 4.295130] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1166machine # [ 4.298698] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1167machine # [ 4.299813] systemd[1]: Stopped Virtual Console Setup.1168machine # [ 4.300792] systemd[1]: initrd-cleanup.service: Deactivated successfully.1169machine # [ 4.301825] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1170machine # [ 4.302818] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1171machine # [ 4.303892] systemd[1]: Finished rw-sysroot-nix-store.service.1172machine # [ 4.310084] systemd[1]: systemd-udevd.service: Deactivated successfully.1173machine # [ 4.311150] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1174machine # [ 4.316570] systemd[1]: systemd-udevd.service: Consumed 1.174s CPU time over 2.417s wall clock time, 17.1M memory peak.1175machine # [ 4.318093] systemd[1]: Starting Cleanup udev Database...1176machine # [ 4.318898] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1177machine # [ 4.319995] systemd[1]: Stopped Create Static Device Nodes in /dev.1178machine # [ 4.322886] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1179machine # [ 4.324222] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1180machine # [ 4.325271] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1181machine # [ 4.326271] systemd[1]: Stopped Create List of Static Device Nodes.1182machine # [ 4.362833] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1183machine # [ 4.366822] systemd[1]: Finished Cleanup udev Database.1184machine # [ 4.367751] systemd[1]: Reached target Switch Root.1185machine # [ 4.369742] systemd[1]: Starting NixOS Activation...1186sshClient # [ 4.722782] initrd-nixos-activation-start[189]: setting up /etc...1187machine # [ 4.465075] initrd-nixos-activation-start[167]: booting system configuration /nix/store/wqgq817fldswva01wys37cyff9a9l9vl-nixos-system-machine-test1188machine # [ 4.503456] initrd-nixos-activation-start[167]: running activation script...1189sshClient # [ 4.857081] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1190sshClient # [ 4.860329] systemd[1]: Finished NixOS Activation.1191sshClient # [ 4.864430] systemd[1]: Starting Switch Root...1192sshClient # [ 4.889102] systemd[1]: Switching root.1193sshClient # [ 5.076202] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1194machine # [ 4.759736] initrd-nixos-activation-start[190]: setting up /etc...1195machine # [ 4.890879] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1196machine # [ 4.893914] systemd[1]: Finished NixOS Activation.1197machine # [ 4.895216] systemd[1]: Starting Switch Root...1198machine # [ 4.921095] systemd[1]: Switching root.1199machine # [ 5.106813] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1200sshClient # [ 5.601783] systemd[1]: systemd 263-1f286f9 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1201sshClient # [ 5.601899] systemd[1]: Detected virtualization qemu.1202sshClient # [ 5.601983] systemd[1]: Detected architecture arm64.1203sshClient # [ 5.618990] systemd[1]: Detected first boot.1204sshClient # [ 5.621889] systemd[1]: Initializing machine ID from random generator.1205sshClient # [ 5.845666] systemd[1]: bpf-restrict-fs: LSM BPF program attached1206machine # [ 5.629631] systemd[1]: systemd 263-1f286f9 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1207machine # [ 5.629800] systemd[1]: Detected virtualization qemu.1208machine # [ 5.629886] systemd[1]: Detected architecture arm64.1209machine # [ 5.630112] systemd[1]: Detected first boot.1210machine # [ 5.650002] systemd[1]: Initializing machine ID from random generator.1211sshClient # [ 6.056666] systemd[1]: Applying preset policy.1212machine # [ 5.773152] systemd[1]: bpf-restrict-fs: LSM BPF program attached1213machine # [ 5.947956] NET: Registered PF_VSOCK protocol family1214machine # [ 5.956226] Guest personality initialized and is inactive1215machine # [ 5.959527] VMCI host device registered (name=vmci, major=10, minor=261)1216machine # [ 5.959555] Initialized host personality1217sshClient # [ 6.353613] systemd[1]: Populated /etc with preset unit settings.1218machine # [ 6.019576] systemd[1]: Applying preset policy.1219sshClient # [ 6.602572] systemd[1]: initrd-switch-root.service: Deactivated successfully.1220sshClient # [ 6.603459] systemd[1]: Stopped initrd-switch-root.service.1221sshClient # [ 6.608137] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1222sshClient # [ 6.609829] systemd[1]: Created slice Slice /system/getty.1223sshClient # [ 6.614461] systemd[1]: Created slice User and Session Slice.1224sshClient # [ 6.615055] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1225sshClient # [ 6.616262] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1226sshClient # [ 6.616977] systemd[1]: Expecting device /dev/hvc0...1227sshClient # [ 6.617821] systemd[1]: Expecting device /dev/ttyAMA0...1228sshClient # [ 6.623790] systemd[1]: Reached target Local Encrypted Volumes.1229sshClient # [ 6.624260] systemd[1]: Stopped target initrd-fs.target.1230sshClient # [ 6.625245] systemd[1]: Stopped target initrd-root-fs.target.1231sshClient # [ 6.629192] systemd[1]: Stopped target initrd-switch-root.target.1232sshClient # [ 6.629592] systemd[1]: Reached target Virtual Machines and Containers.1233sshClient # [ 6.633006] systemd[1]: Reached target Path Units.1234sshClient # [ 6.633396] systemd[1]: Reached target Remote File Systems.1235sshClient # [ 6.636623] systemd[1]: Reached target Slice Units.1236sshClient # [ 6.637017] systemd[1]: Reached target Swaps.1237machine # [ 6.296007] systemd[1]: Populated /etc with preset unit settings.1238sshClient # [ 6.641359] systemd[1]: Listening on Query the User Interactively for a Password.1239sshClient # [ 6.645147] systemd[1]: Listening on Process Core Dump Socket.1240sshClient # [ 6.648140] systemd[1]: Listening on Credential Encryption/Decryption.1241sshClient # [ 6.649567] systemd[1]: Listening on Factory Reset Management.1242sshClient # [ 6.650134] systemd[1]: Listening on Hostname Service Socket.1243sshClient # [ 6.657180] systemd[1]: Starting Journal Log Access Socket...1244sshClient # [ 6.660080] systemd[1]: Listening on Journal Audit Socket.1245sshClient # [ 6.662123] systemd[1]: Listening on Console Output Muting Service Socket.1246sshClient # [ 6.664529] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1247sshClient # [ 6.665219] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1248sshClient # [ 6.666077] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1249sshClient # [ 6.675555] systemd[1]: Listening on Disk Repartitioning Service Socket.1250sshClient # [ 6.676268] systemd[1]: Listening on udev Varlink Socket.1251sshClient # [ 6.677223] systemd[1]: Listening on User Database Manager Socket.1252sshClient # [ 6.683637] systemd[1]: Mounting Huge Pages File System...1253sshClient # [ 6.687883] systemd[1]: Mounting POSIX Message Queue File System...1254sshClient # [ 6.702126] systemd[1]: Mounting Kernel Debug File System...1255sshClient # [ 6.716216] systemd[1]: Mounting Kernel Trace File System...1256sshClient # [ 6.729157] systemd[1]: Starting Create List of Static Device Nodes...1257sshClient # [ 6.734446] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1258sshClient # [ 6.753475] systemd[1]: Mounting Kernel Configuration File System...1259sshClient # [ 6.762730] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1260sshClient # [ 6.763404] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1261sshClient # [ 6.771376] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1262sshClient # [ 6.792835] systemd[1]: Mounting FUSE Control File System...1263sshClient # [ 6.807090] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671264sshClient # [ 6.842123] systemd[1]: Starting Journal Service...1265sshClient # [ 6.871069] systemd[1]: Starting Load Kernel Modules...1266machine # [ 6.541584] systemd[1]: initrd-switch-root.service: Deactivated successfully.1267machine # [ 6.542567] systemd[1]: Stopped initrd-switch-root.service.1268machine # [ 6.547508] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1269machine # [ 6.550881] systemd[1]: Created slice Slice /system/getty.1270machine # [ 6.552341] systemd[1]: Created slice User and Session Slice.1271machine # [ 6.552957] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1272sshClient # [ 6.901677] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1273machine # [ 6.557428] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1274machine # [ 6.557934] systemd[1]: Expecting device /dev/hvc0...1275machine # [ 6.558929] systemd[1]: Expecting device /dev/ttyAMA0...1276machine # [ 6.559893] systemd[1]: Reached target Local Encrypted Volumes.1277machine # [ 6.560879] systemd[1]: Stopped target initrd-fs.target.1278machine # [ 6.566227] systemd[1]: Stopped target initrd-root-fs.target.1279machine # [ 6.567235] systemd[1]: Stopped target initrd-switch-root.target.1280machine # [ 6.568309] systemd[1]: Reached target Virtual Machines and Containers.1281machine # [ 6.572655] systemd[1]: Reached target Path Units.1282machine # [ 6.573888] systemd[1]: Reached target Remote File Systems.1283machine # [ 6.574901] systemd[1]: Reached target Slice Units.1284machine # [ 6.575924] systemd[1]: Reached target Swaps.1285machine # [ 6.581414] systemd[1]: Listening on Query the User Interactively for a Password.1286machine # [ 6.584106] systemd[1]: Listening on Process Core Dump Socket.1287sshClient # [ 6.926989] systemd[1]: Starting Remount Root and Kernel File Systems...1288sshClient # [ 6.927538] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1289machine # [ 6.588272] systemd[1]: Listening on Credential Encryption/Decryption.1290machine # [ 6.591704] systemd[1]: Listening on Factory Reset Management.1291machine # [ 6.592403] systemd[1]: Listening on Hostname Service Socket.1292machine # [ 6.599602] systemd[1]: Starting Journal Log Access Socket...1293sshClient # [ 6.945431] systemd[1]: Starting Coldplug All udev Devices...1294machine # [ 6.602356] systemd[1]: Listening on Journal Audit Socket.1295machine # [ 6.604974] systemd[1]: Listening on Console Output Muting Service Socket.1296machine # [ 6.608057] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1297machine # [ 6.612052] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1298machine # [ 6.612434] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1299machine # [ 6.620758] systemd[1]: Listening on Disk Repartitioning Service Socket.1300machine # [ 6.622488] systemd[1]: Listening on udev Varlink Socket.1301machine # [ 6.623545] systemd[1]: Listening on User Database Manager Socket.1302sshClient # [ 6.971172] systemd[1]: Listening on Journal Log Access Socket.1303sshClient # [ 6.971890] systemd[1]: Mounted Huge Pages File System.1304sshClient # [ 6.972492] systemd[1]: Mounted POSIX Message Queue File System.1305sshClient # [ 6.973097] systemd[1]: Mounted Kernel Debug File System.1306machine # [ 6.629217] systemd[1]: Mounting Huge Pages File System...1307sshClient # [ 6.973621] systemd[1]: Mounted Kernel Trace File System.1308machine # [ 6.632399] systemd[1]: Mounting POSIX Message Queue File System...1309sshClient # [ 6.980575] systemd-journald[261]: Collecting audit messages is enabled.1310sshClient # [ 6.971564] systemd[1]: Queued start job for default target Multi-User System.1311sshClient # [ 6.978165] systemd[1]: systemd-journald.service: Deactivated successfully.1312machine # [ 6.649978] systemd[1]: Mounting Kernel Debug File System...1313sshClient # [ 7.000321] systemd[1]: Finished Create List of Static Device Nodes.1314sshClient # [ 7.001203] systemd[1]: Started Journal Service.1315machine # [ 6.659820] systemd[1]: Mounting Kernel Trace File System...1316sshClient # [ 6.994458] systemd-modules-load[262]: Module 'atkbd' is built in1317sshClient # [ 6.995807] systemd[1]: Mounted Kernel Configuration File System.1318sshClient # [ 7.008682] systemd-modules-load[262]: Module 'loop' is built in1319machine # [ 6.681201] systemd[1]: Starting Create List of Static Device Nodes...1320machine # [ 6.681896] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1321sshClient # [ 7.025453] systemd[1]: Finished Load Kernel Modules.1322machine # [ 6.701321] systemd[1]: Mounting Kernel Configuration File System...1323machine # [ 6.703006] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1324machine # [ 6.703543] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1325machine # [ 6.713005] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1326sshClient # [ 7.050726] systemd[1]: Starting Firewall...1327sshClient # [ 7.063742] systemd[1]: Starting Apply Kernel Variables...1328machine # [ 6.743021] systemd[1]: Mounting FUSE Control File System...1329machine # [ 6.743529] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671330sshClient # [ 7.095000] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1331machine # [ 6.776553] systemd[1]: Starting Journal Service...1332sshClient # [ 7.104150] systemd[1]: Mounted FUSE Control File System.1333sshClient # [ 7.126311] EXT4-fs (vda): re-mounted f4329853-74ce-4408-808f-d2de807d141b.1334sshClient # [ 7.133121] systemd[1]: Finished Remount Root and Kernel File Systems.1335machine # [ 6.816670] systemd[1]: Starting Load Kernel Modules...1336sshClient # [ 7.152575] systemd-oomd[264]: No swap; memory pressure usage will be degraded1337sshClient # [ 7.154084] systemd[1]: Listening on Disk Image Download Service Socket.1338machine # [ 6.844123] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1339sshClient # [ 7.172485] systemd[1]: Starting Flush Journal to Persistent Storage...1340sshClient # [ 7.173612] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1341sshClient # [ 7.182870] systemd[1]: Starting Load/Save OS Random Seed...1342sshClient # [ 7.183915] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1343sshClient # [ 7.198389] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1344machine # [ 6.877958] systemd[1]: Starting Remount Root and Kernel File Systems...1345machine # [ 6.878429] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1346machine # [ 6.904719] systemd-journald[263]: Collecting audit messages is enabled.1347machine # [ 6.915841] systemd[1]: Starting Coldplug All udev Devices...1348machine # [ 6.923847] systemd[1]: Listening on Journal Log Access Socket.1349machine # [ 6.924405] systemd[1]: Mounted Huge Pages File System.1350machine # [ 6.924983] systemd[1]: Mounted POSIX Message Queue File System.1351machine # [ 6.914836] systemd[1]: Queued start job for default target Multi-User System.1352machine # [ 6.916467] systemd[1]: systemd-journald.service: Deactivated successfully.1353machine # [ 6.943661] systemd[1]: Started Journal Service.1354machine # [ 6.937436] systemd-modules-load[264]: Module 'atkbd' is built in1355machine # [ 6.944243] systemd-modules-load[264]: Module 'loop' is built in1356machine # [ 6.947877] systemd[1]: Mounted Kernel Debug File System.1357machine # [ 6.951454] systemd[1]: Mounted Kernel Trace File System.1358sshClient # [ 7.315502] systemd-journald[261]: Received client request to flush runtime journal.1359machine # [ 6.963872] systemd[1]: Finished Create List of Static Device Nodes.1360machine # [ 6.973284] systemd[1]: Finished Load Kernel Modules.1361machine # [ 7.011911] systemd-oomd[265]: No swap; memory pressure usage will be degraded1362machine # [ 7.021164] systemd[1]: Starting Firewall...1363sshClient # [ 7.371248] systemd[1]: Finished Apply Kernel Variables.1364machine # [ 7.041218] EXT4-fs (vda): re-mounted 313095f8-9271-440c-b058-9bbbdd1fbe6e.1365sshClient # [ 7.377484] systemd[1]: Finished Load/Save OS Random Seed.1366sshClient # [ 7.380473] systemd[1]: Finished Flush Journal to Persistent Storage.1367machine # [ 7.040506] systemd[1]: Starting Apply Kernel Variables...1368machine # [ 7.062472] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1369machine # [ 7.068261] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1370machine # [ 7.071304] systemd[1]: Mounted Kernel Configuration File System.1371machine # [ 7.075335] systemd[1]: Finished Remount Root and Kernel File Systems.1372machine # [ 7.101069] systemd[1]: Mounted FUSE Control File System.1373machine # [ 7.116214] systemd[1]: Listening on Disk Image Download Service Socket.1374machine # [ 7.136095] systemd[1]: Starting Flush Journal to Persistent Storage...1375machine # [ 7.137329] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1376machine # [ 7.155602] systemd[1]: Starting Load/Save OS Random Seed...1377machine # [ 7.160392] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1378sshClient # [ 7.513157] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1379sshClient # [ 7.517790] systemd[1]: Starting Create Static Device Nodes in /dev...1380machine # [ 7.249162] systemd[1]: Finished Apply Kernel Variables.1381machine # [ 7.282396] systemd-journald[263]: Received client request to flush runtime journal.1382machine # [ 7.329513] systemd[1]: Finished Load/Save OS Random Seed.1383machine # [ 7.338020] systemd[1]: Finished Flush Journal to Persistent Storage.1384sshClient # [ 7.745856] systemd[1]: Finished Create Static Device Nodes in /dev.1385sshClient # [ 7.752593] systemd[1]: Reached target Preparation for Local File Systems.1386sshClient # [ 7.756230] systemd[1]: Mounting /run/wrappers...1387sshClient # [ 7.764276] systemd[1]: Starting Rule-based Manager for Device Events and Files...1388machine # [ 7.433125] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1389machine # [ 7.441941] systemd[1]: Starting Create Static Device Nodes in /dev...1390sshClient # [ 7.891691] systemd[1]: Mounted /run/wrappers.1391sshClient # [ 7.893234] systemd[1]: Reached target Local File Systems.1392sshClient # [ 7.901687] systemd[1]: Listening on Boot Loader Control Service Socket.1393sshClient # [ 7.924434] systemd[1]: Starting Create SUID/SGID Wrappers...1394sshClient # [ 7.925554] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1395sshClient # [ 7.937378] systemd[1]: Starting Create System Files and Directories...1396machine # [ 7.650350] systemd[1]: Finished Create Static Device Nodes in /dev.1397machine # [ 7.655630] systemd[1]: Reached target Preparation for Local File Systems.1398machine # [ 7.661952] systemd[1]: Mounting /run/wrappers...1399machine # [ 7.673873] systemd[1]: Starting Rule-based Manager for Device Events and Files...1400machine # [ 7.797873] systemd[1]: Mounted /run/wrappers.1401machine # [ 7.798802] systemd[1]: Reached target Local File Systems.1402machine # [ 7.808306] systemd[1]: Listening on Boot Loader Control Service Socket.1403machine # [ 7.814047] systemd[1]: Starting Create SUID/SGID Wrappers...1404machine # [ 7.815044] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1405machine # [ 7.823861] systemd[1]: Starting Create System Files and Directories...1406sshClient # [ 8.167528] systemd-udevd[304]: Using default interface naming scheme 'v261'.1407sshClient # [ 8.242375] systemd[1]: Starting User Database Manager...1408sshClient # [ 8.248148] systemd[1]: Finished Create System Files and Directories.1409sshClient # [ 8.264351] systemd[1]: Starting Rebuild Journal Catalog...1410sshClient # [ 8.292596] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1411machine # [ 8.071253] systemd-udevd[306]: Using default interface naming scheme 'v261'.1412machine # [ 8.133480] systemd[1]: Starting User Database Manager...1413machine # [ 8.164205] systemd[1]: Finished Create System Files and Directories.1414machine # [ 8.186894] systemd[1]: Starting Rebuild Journal Catalog...1415sshClient # [ 8.545368] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1416machine # [ 8.207123] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1417sshClient # [ 8.560802] systemd[1]: Started User Database Manager.1418sshClient # [ 8.570609] systemd[1]: Finished Rebuild Journal Catalog.1419sshClient # [ 8.590504] systemd[1]: Starting Update is Completed...1420sshClient # [ 8.745943] systemd[1]: Finished Update is Completed.1421machine # [ 8.444407] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1422machine # [ 8.449776] systemd[1]: Started User Database Manager.1423machine # [ 8.502847] systemd[1]: Finished Rebuild Journal Catalog.1424machine # [ 8.517116] systemd[1]: Starting Update is Completed...1425sshClient # [ 8.872227] systemd[1]: Started Rule-based Manager for Device Events and Files.1426machine # [ 8.677912] systemd[1]: Finished Update is Completed.1427sshClient # [ 9.149074] systemd[1]: Finished Coldplug All udev Devices.1428machine # [ 8.814006] systemd[1]: Started Rule-based Manager for Device Events and Files.1429sshClient # [ 9.721954] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1430sshClient # [ 9.731736] systemd[1]: Finished Create SUID/SGID Wrappers.1431sshClient # [ 9.737316] systemd[1]: Reached target System Initialization.1432sshClient # [ 9.741364] systemd[1]: Started Discard unused filesystem blocks once a week.1433sshClient # [ 9.742494] systemd[1]: Started Daily Cleanup of Temporary Directories.1434sshClient # [ 9.743423] systemd[1]: Reached target Timer Units.1435sshClient # [ 9.758352] systemd[1]: Listening on D-Bus System Message Bus Socket.1436sshClient # [ 9.765117] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1437sshClient # [ 9.766670] systemd[1]: Reached target Socket Units.1438sshClient # [ 9.777082] systemd[1]: Reached target Basic System.1439sshClient # [ 9.782669] systemd[1]: Starting Import lastlog data into lastlog2 database...1440sshClient # [ 9.806749] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1441sshClient # [ 9.841042] systemd[1]: Starting Post-Boot Actions...1442sshClient # [ 9.884710] systemd[1]: Started Reset console on configuration changes.1443sshClient # [ 9.956271] systemd[1]: Starting resolvconf update...1444sshClient # [ 10.100131] systemd[1]: Starting Home Area Manager...1445sshClient # [ 10.334648] nsncd[437]: Sep 24 02:50:53.871 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1446machine # [ 10.036260] systemd[1]: Finished Coldplug All udev Devices.1447sshClient # [ 10.412668] systemd[1]: Starting D-Bus System Message Bus...1448sshClient # [ 10.422107] systemd[1]: Started Name Service Cache Daemon (nsncd).1449sshClient # [ 10.439815] systemd[1]: Finished Post-Boot Actions.1450sshClient # [ 10.464910] systemd[1]: Reached target Host and Network Name Lookups.1451sshClient # [ 10.466598] systemd[1]: Reached target User and Group Name Lookups.1452sshClient # [ 10.683236] systemd[1]: Starting User Login Management...1453sshClient # [ 10.975356] systemd[1]: Finished Import lastlog data into lastlog2 database.1454sshClient # [ 11.009273] systemd-homed[443]: Watching /home.1455machine # [ 10.697211] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1456machine # [ 10.701869] systemd[1]: Finished Create SUID/SGID Wrappers.1457machine # [ 10.706896] systemd[1]: Reached target System Initialization.1458machine # [ 10.707912] systemd[1]: Started Discard unused filesystem blocks once a week.1459machine # [ 10.711929] systemd[1]: Started Daily Cleanup of Temporary Directories.1460machine # [ 10.719792] systemd[1]: Reached target Timer Units.1461machine # [ 10.722156] systemd[1]: Listening on D-Bus System Message Bus Socket.1462machine # [ 10.723120] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).1463machine # [ 10.731386] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1464machine # [ 10.736885] systemd[1]: Reached target Socket Units.1465machine # [ 10.737722] systemd[1]: Reached target Basic System.1466machine # [ 10.738511] systemd[1]: Starting Import lastlog data into lastlog2 database...1467machine # [ 10.748186] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1468machine # [ 10.754896] systemd[1]: Starting Post-Boot Actions...1469machine # [ 10.777539] systemd[1]: Started Reset console on configuration changes.1470sshClient # [ 11.184497] dbus-broker-launch[462]: Looking up NSS user entry for 'systemd-timesync'...1471sshClient # [ 11.193860] dbus-broker-launch[462]: NSS returned no entry for 'systemd-timesync'1472sshClient # [ 11.195062] dbus-broker-launch[462]: Invalid user-name in /nix/store/srzcs2blg5a1pa0b5x1chg2br8i4igk4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1473machine # [ 10.855342] systemd[1]: Starting resolvconf update...1474machine # [ 10.871704] systemd[1]: Starting SSH Host Keys Generation...1475sshClient # [ 11.249462] systemd-logind[469]: New seat seat0.1476sshClient # [ 11.251968] systemd[1]: Started D-Bus System Message Bus.1477sshClient # [ 11.263316] systemd[1]: Started User Login Management.1478sshClient # [ 11.273522] systemd[1]: Starting linger-users.service...1479machine # [ 10.949211] systemd[1]: Starting Home Area Manager...1480sshClient # [ 11.307548] dbus-broker-launch[462]: Ready1481sshClient # [ 11.322045] systemd[1]: Started Home Area Manager.1482sshClient # [ 11.332557] systemd[1]: Finished Home Area Activation.1483sshClient # [ 11.344053] systemd[1]: Starting First Boot Home Area Wizard...1484machine # [ 11.019857] nsncd[445]: Sep 24 02:50:55.547 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1485machine # [ 11.033278] systemd[1]: Finished Post-Boot Actions.1486machine # [ 11.034173] systemd[1]: Started Name Service Cache Daemon (nsncd).1487sshClient # [ 11.406777] systemd[1]: linger-users.service: Deactivated successfully.1488sshClient # [ 11.418572] systemd[1]: Finished linger-users.service.1489machine # [ 11.092979] systemd[1]: Reached target Host and Network Name Lookups.1490machine # [ 11.094203] systemd[1]: Reached target User and Group Name Lookups.1491machine # [ 11.132400] systemd[1]: Starting D-Bus System Message Bus...1492sshClient # [ 11.477393] systemd[1]: Stopped target Host and Network Name Lookups.1493sshClient # [ 11.478941] systemd[1]: Stopping Host and Network Name Lookups...1494sshClient # [ 11.479966] systemd[1]: Stopped target User and Group Name Lookups.1495sshClient # [ 11.493249] systemd[1]: Stopping User and Group Name Lookups...1496sshClient # [ 11.494229] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1497sshClient # [ 11.495173] systemd[1]: nscd.service: Deactivated successfully.1498sshClient # [ 11.506820] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1499machine # [ 11.177586] systemd[1]: Starting User Login Management...1500sshClient # [ 11.548381] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1501sshClient # [ 11.553656] systemd[1]: Finished First Boot Home Area Wizard.1502machine # [ 11.210787] systemd[1]: Finished Import lastlog data into lastlog2 database.1503sshClient # [ 11.559617] systemd[1]: Reached target First Boot Complete.1504machine # [ 11.233890] systemd-homed[450]: Watching /home.1505sshClient # [ 11.587727] systemd[1]: Starting Save Transient machine-id to Disk...1506sshClient # [ 11.732404] systemd[1]: Started Name Service Cache Daemon (nsncd).1507sshClient # [ 11.741707] systemd[1]: Reached target Host and Network Name Lookups.1508sshClient # [ 11.743320] nsncd[549]: Sep 24 02:50:55.278 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1509sshClient # [ 11.750174] systemd[1]: Reached target User and Group Name Lookups.1510sshClient # [ 11.796131] systemd[1]: Finished Firewall.1511sshClient # [ 11.816916] systemd[1]: Finished resolvconf update.1512sshClient # [ 11.821955] systemd[1]: Reached target Preparation for Network.1513machine # [ 11.485183] dbus-broker-launch[474]: Looking up NSS user entry for 'systemd-timesync'...1514sshClient # [ 11.832302] systemd[1]: Starting DHCP Client...1515machine # [ 11.490979] dbus-broker-launch[474]: NSS returned no entry for 'systemd-timesync'1516sshClient # [ 11.836323] systemd[1]: Starting Extra networking commands....1517machine # [ 11.496297] dbus-broker-launch[474]: Invalid user-name in /nix/store/f3z8a7vpbmkym312xhqpyvjgpliwhihh-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1518sshClient # [ 11.894371] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1519machine # [ 11.548496] systemd-logind[477]: New seat seat0.1520machine # [ 11.561572] systemd[1]: Started User Login Management.1521sshClient # [ 11.915784] systemd[1]: Finished Save Transient machine-id to Disk.1522machine # [ 11.573375] systemd[1]: Starting linger-users.service...1523machine # [ 11.589179] systemd[1]: Started D-Bus System Message Bus.1524machine # [ 11.684585] dbus-broker-launch[474]: Ready1525sshClient # [ 12.057509] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31526machine # [ 11.706172] systemd[1]: Started Home Area Manager.1527machine # [ 11.710333] systemd[1]: Finished Home Area Activation.1528machine # [ 11.727265] systemd[1]: Starting First Boot Home Area Wizard...1529machine # [ 11.763916] systemd[1]: linger-users.service: Deactivated successfully.1530machine # [ 11.773552] systemd[1]: Finished linger-users.service.1531machine # [ 11.824178] systemd[1]: Stopped target Host and Network Name Lookups.1532machine # [ 11.830622] systemd[1]: Stopping Host and Network Name Lookups...1533machine # [ 11.837935] systemd[1]: Stopped target User and Group Name Lookups.1534machine # [ 11.838979] systemd[1]: Stopping User and Group Name Lookups...1535sshClient # [ 12.182905] dhcpcd[594]: dhcpcd-10.3.2 starting1536machine # [ 11.839842] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1537machine # [ 11.851372] systemd[1]: nscd.service: Deactivated successfully.1538sshClient # [ 12.195544] dhcpcd[618]: dev: loaded udev1539sshClient # [ 12.201290] dhcpcd[618]: no valid interfaces found1540machine # [ 11.858154] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1541sshClient # [ 12.202279] dhcpcd[618]: no valid interfaces found1542machine # [ 11.893919] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1543machine # [ 11.961118] systemd[1]: Created slice Slice /system/systemd-mute-console.1544machine # [ 11.997532] systemd[1]: Started Console Output Muting Service (PID 548/UID 0).1545machine # [ 12.086253] systemd[1]: Started Name Service Cache Daemon (nsncd).1546machine # [ 12.091048] nsncd[558]: Sep 24 02:50:56.618 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1547sshClient # [ 12.448386] systemd[1]: Finished Extra networking commands..1548machine # [ 12.106636] systemd[1]: Reached target Host and Network Name Lookups.1549machine # [ 12.107668] systemd[1]: Reached target User and Group Name Lookups.1550sshClient # [ 12.453674] systemd[1]: Reached target Network.1551sshClient # [ 12.457671] systemd[1]: Starting Permit User Sessions...1552sshClient # [ 12.526766] systemd[1]: Finished Permit User Sessions.1553sshClient # [ 12.537650] systemd[1]: Started Getty on tty1.1554sshClient # [ 12.540184] systemd[1]: Reached target Login Prompts.1555machine # [ 12.241059] systemd[1]: Finished resolvconf update.1556machine # [ 12.265142] systemd[1]: Starting DHCP Client...1557sshClient # [ 12.647531] systemd[1]: Starting Virtual Console Setup...1558sshClient # [ 12.678202] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1559sshClient # [ 12.682567] systemd[1]: Stopped Virtual Console Setup.1560sshClient # [ 12.700609] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1561sshClient # [ 12.708479] systemd[1]: Starting Virtual Console Setup...1562machine # [ 12.448828] systemd[1]: Finished Firewall.1563machine # [ 12.452369] systemd[1]: Reached target Preparation for Network.1564machine # [ 12.475362] systemd[1]: Starting Extra networking commands....1565sshClient # [ 12.896446] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1566sshClient # [ 12.916380] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1567sshClient # [ 12.940347] systemd[1]: Started backdoor.service.1568sshClient # [ 13.025587] (udev-worker)[540]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1569sshClient # [ 13.043877] (udev-worker)[540]: Network interface NamePolicy= disabled on kernel command line.1570sshClient # connecting to host...1571machine # [ 12.719697] dhcpcd[603]: dhcpcd-10.3.2 starting1572machine # [ 12.774996] dhcpcd[611]: dev: loaded udev1573machine # [ 12.786147] dhcpcd[611]: no valid interfaces found1574machine # [ 12.788234] dhcpcd[611]: no valid interfaces found1575sshClient # [ 13.160318] 8021q: 802.1Q VLAN Support v1.81576sshClient # [ 13.375760] cfg80211: Loading compiled-in X.509 certificates for regulatory database1577sshClient # [ 13.403169] systemd[1]: Condition check resulted in Virtio network device being skipped.[ 13.418571] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1578sshClient # [ 13.419099] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1579sshClient # 1580sshClient # [ 13.427366] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21581sshClient # [ 13.427753] cfg80211: failed to load regulatory.db1582sshClient # [ 13.424817] systemd[1]: Starting Address configuration of eth1...1583sshClient # [ 13.458402] systemd-logind[469]: Watching system buttons on /dev/input/event0 (gpio-keys)1584sshClient # [ 13.553551] 8021q: adding VLAN 0 to HW filter on device eth01585sshClient # [ 13.540551] dhcpcd[618]: eth0: waiting for carrier1586sshClient # [ 13.541522] dhcpcd[618]: eth0: carrier acquired1587sshClient # [ 13.577604] 8021q: adding VLAN 0 to HW filter on device eth11588sshClient # [ 13.568153] dhcpcd[618]: DUID 00:01:00:01:32:47:4c:91:52:54:00:12:34:561589sshClient # [ 13.569374] dhcpcd[618]: eth0: IAID 00:12:34:561590sshClient # [ 13.570078] dhcpcd[618]: eth0: adding address fe80::5054:ff:fe12:34561591sshClient # [ 13.592463] network-addresses-eth1-start[671]: adding address 192.168.1.2/24... done1592sshClient # [ 13.620301] network-addresses-eth1-start[671]: adding address 2001:db8:1::2/64... done1593sshClient # [ 13.650380] systemd[1]: Finished Address configuration of eth1.1594sshClient # [ 13.659604] systemd-vconsole-setup[653]: Configuration of first virtual console was skipped, ignoring remaining ones.1595sshClient # [ 13.667370] systemd[1]: Finished Virtual Console Setup.1596sshClient # [ 13.719578] mousedev: PS/2 mouse device common for all mice1597machine # [ 13.420613] systemd[1]: Finished Extra networking commands..1598machine # [ 13.425013] systemd[1]: Reached target Network.1599sshClient # [ 13.819248] systemd-logind[469]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1600machine # [ 13.609128] systemd[1]: Starting Virtual Console Setup...1601machine # [ 13.640278] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1602machine # [ 13.648427] systemd[1]: Stopped Virtual Console Setup.1603machine # [ 13.656504] systemd[1]: Starting Virtual Console Setup...1604machine # [ 13.710134] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1605sshClient # [ 14.184140] dhcpcd[618]: eth0: soliciting a DHCP lease1606sshClient # [ 14.192688] dhcpcd[618]: eth0: offered 10.0.2.15 from 10.0.2.21607sshClient # [ 14.200298] dhcpcd[618]: eth0: probing address 10.0.2.15/241608machine # [ 13.990843] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1609machine # [ 14.059817] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1610machine # [ 14.072615] systemd[1]: Started backdoor.service.1611machine # [ 14.153458] (udev-worker)[523]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1612machine # [ 14.159820] (udev-worker)[523]: Network interface NamePolicy= disabled on kernel command line.1613machine # [ 14.274899] systemd[1]: Condition check resulted in Virtio network device being skipped.1614machine # [ 14.290169] systemd[1]: Starting Address configuration of eth1...1615machine # connecting to host...1616machine: Guest shell says: b'Spawning backdoor root shell...\n'1617machine: connected to guest root shell1618machine: (connecting took 14.91 seconds)1619machine: (finished: waiting for the VM to finish booting, in 14.91 seconds)1620sshClient # [ 14.887027] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1621sshClient # [ 14.889369] systemd[1]: Stopped Virtual Console Setup.1622sshClient # [ 14.890370] systemd[1]: Stopping Virtual Console Setup...1623sshClient # [ 14.897456] systemd[1]: Starting Virtual Console Setup...1624sshClient # [ 14.933627] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1625machine # [ 14.599368] network-addresses-eth1-start[672]: adding address 192.168.1.1/24... done1626sshClient # [ 14.946298] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1627sshClient # [ 14.952104] systemd[1]: Stopped Virtual Console Setup.1628sshClient # [ 14.958605] systemd[1]: Starting Virtual Console Setup...1629machine # [ 14.638621] network-addresses-eth1-start[672]: adding address 2001:db8:1::1/64... done1630sshClient # [ 14.986921] systemd-logind[469]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1631machine: (finished: waiting for unit systemd-homed.service, in 15.11 seconds)1632machine: waiting for Please enter user name to create to appear on tty 11633machine # [ 14.690427] systemd[1]: Finished Address configuration of eth1.1634sshClient # [ 15.075945] systemd-vconsole-setup[707]: Configuration of first virtual console was skipped, ignoring remaining ones.1635sshClient # [ 15.080434] systemd[1]: Finished Virtual Console Setup.1636machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.23 seconds)1637machine: sending keys 'test-homed-user\n'1638sshClient # [ 15.357762] dhcpcd[618]: eth0: soliciting an IPv6 router1639machine # [ 15.016458] systemd-logind[477]: Watching system buttons on /dev/input/event0 (gpio-keys)1640sshClient # [ 15.361819] dhcpcd[618]: eth0: Router Advertisement from fe80::21641sshClient # [ 15.364820] dhcpcd[618]: eth0: adding address fec0::5054:ff:fe12:3456/641642sshClient # [ 15.367665] dhcpcd[618]: eth0: adding route to fec0::/641643sshClient # [ 15.370185] dhcpcd[618]: eth0: adding default route via fe80::21644machine: (finished: sending keys 'test-homed-user\n', in 0.18 seconds)1645machine: waiting for Please enter new password for user test-homed-user: to appear on tty 11646machine # [ 15.187832] sshd-keygen-start[488]: Generating public/private rsa key pair.1647machine # [ 15.194326] sshd-keygen-start[488]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key1648machine # [ 15.195622] sshd-keygen-start[488]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub1649machine # [ 15.208513] sshd-keygen-start[488]: The key fingerprint is:1650machine # [ 15.210184] sshd-keygen-start[488]: SHA256:yFDJLvapxP4vaHEw/BhI3MZpq1zP7rEC+U1rSzf/AIM root@machine1651machine # [ 15.211383] sshd-keygen-start[488]: The key's randomart image is:1652machine # [ 15.225222] sshd-keygen-start[488]: +---[RSA 4096]----+1653machine # [ 15.226053] sshd-keygen-start[488]: | . o o.. |1654machine # [ 15.226778] sshd-keygen-start[488]: | o *.o |1655machine # [ 15.227498] sshd-keygen-start[488]: | . =.o |1656machine # [ 15.244256] sshd-keygen-start[488]: | . Xo.o |1657machine # [ 15.245063] sshd-keygen-start[488]: | . * @E.S |1658machine # [ 15.245796] sshd-keygen-start[488]: | = = O o |1659machine # [ 15.246522] sshd-keygen-start[488]: | = Oo.o . |1660machine # [ 15.247284] sshd-keygen-start[488]: | B.*+ o . |1661machine # [ 15.248005] sshd-keygen-start[488]: | . ==+. ... |1662machine # [ 15.258344] sshd-keygen-start[488]: +----[SHA256]-----+1663machine: (finished: waiting for Please enter new password for user test-homed-user: to appear on tty 1, in 0.19 seconds)1664machine: sending keys 'foobarfoo\n'1665machine # [ 15.259479] dhcpcd[611]: eth0: waiting for carrier1666machine # [ 15.267759] dhcpcd[611]: eth0: carrier acquired1667machine # [ 15.269803] dhcpcd[611]: DUID 00:01:00:01:32:47:4c:93:52:54:00:12:34:561668machine # [ 15.270747] dhcpcd[611]: eth0: IAID 00:12:34:561669machine # [ 15.271444] dhcpcd[611]: eth0: adding address fe80::5054:ff:fe12:34561670machine # [ 15.315200] sshd-keygen-start[732]: Generating public/private ed25519 key pair.1671machine # [ 15.318885] sshd-keygen-start[732]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key1672machine # [ 15.322583] sshd-keygen-start[732]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub1673machine # [ 15.323887] sshd-keygen-start[732]: The key fingerprint is:1674machine # [ 15.327071] sshd-keygen-start[732]: SHA256:KJ0ILcR+rBfl+JMyBjRlzj+y4ZNPRLCkfkHVT6RzeLc root@machine1675machine # [ 15.332654] sshd-keygen-start[732]: The key's randomart image is:1676machine # [ 15.333566] sshd-keygen-start[732]: +--[ED25519 256]--+1677machine # [ 15.334292] sshd-keygen-start[732]: | ...*... .. |1678machine # [ 15.335020] sshd-keygen-start[732]: | .+O o. .o. |1679machine # [ 15.335756] sshd-keygen-start[732]: | o+o*+. +oo . |1680machine # [ 15.343416] sshd-keygen-start[732]: | .oo=*.o +.. . |1681machine # [ 15.346376] sshd-keygen-start[732]: | .+*oB.S E |1682machine # [ 15.353476] sshd-keygen-start[732]: | .o=B+. |1683machine # [ 15.354294] sshd-keygen-start[732]: | o=o.. |1684machine # [ 15.355026] sshd-keygen-start[732]: | + |1685machine # [ 15.355764] sshd-keygen-start[732]: | . |1686machine # [ 15.366413] sshd-keygen-start[732]: +----[SHA256]-----+1687machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1688machine: waiting for (repeat) to appear on tty 11689machine # [ 15.372350] systemd[1]: sshd-keygen.service: Deactivated successfully.1690machine # [ 15.373327] systemd[1]: Finished SSH Host Keys Generation.1691machine # [ 15.377719] systemd[1]: sshd-keygen.service: Consumed 1.328s CPU time over 4.474s wall clock time, 6.5M memory peak.1692machine # [ 15.379287] systemd[1]: Starting SSH Daemon...1693machine # [ 15.390699] systemd-vconsole-setup[663]: Configuration of first virtual console was skipped, ignoring remaining ones.1694machine # [ 15.402041] systemd[1]: Finished Virtual Console Setup.1695machine # [ 15.469898] sshd[734]: Server listening on 0.0.0.0 port 22.1696machine # [ 15.470875] systemd[1]: Started SSH Daemon.1697machine # [ 15.471529] sshd[734]: Server listening on :: port 22.1698machine: (finished: waiting for (repeat) to appear on tty 1, in 0.13 seconds)1699machine: sending keys 'foobarfoo\n'1700machine # [ 15.582591] systemd-logind[477]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1701machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1702machine: waiting for unit systemd-homed-firstboot.service1703machine # [ 16.789964] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1704machine # [ 16.793098] systemd[1]: Stopped Virtual Console Setup.1705machine # [ 16.793981] systemd[1]: Stopping Virtual Console Setup...1706machine # [ 16.800482] systemd[1]: Starting Virtual Console Setup...1707machine # [ 16.826905] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1708machine # [ 16.831164] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1709machine # [ 16.835640] systemd[1]: Stopped Virtual Console Setup.1710machine # [ 16.847953] systemd[1]: Starting Virtual Console Setup...1711machine # [ 16.887312] systemd-logind[477]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1712machine # [ 16.988728] systemd-vconsole-setup[757]: Configuration of first virtual console was skipped, ignoring remaining ones.1713machine # [ 16.993047] systemd[1]: Finished Virtual Console Setup.1714machine # [ 17.129187] dhcpcd[611]: eth0: soliciting a DHCP lease1715machine # [ 17.133393] dhcpcd[611]: eth0: offered 10.0.2.15 from 10.0.2.21716machine # [ 17.140479] dhcpcd[611]: eth0: probing address 10.0.2.15/241717machine # [ 17.720617] dhcpcd[611]: eth0: soliciting an IPv6 router1718machine # [ 17.725465] dhcpcd[611]: eth0: Router Advertisement from fe80::21719machine # [ 17.728514] dhcpcd[611]: eth0: adding address fec0::5054:ff:fe12:3456/641720machine # [ 17.731482] dhcpcd[611]: eth0: adding route to fec0::/641721machine # [ 17.734112] dhcpcd[611]: eth0: adding default route via fe80::21722sshClient # [ 19.226566] dhcpcd[618]: eth0: leased 10.0.2.15 for 86400 seconds1723sshClient # [ 19.229978] dhcpcd[618]: eth0: adding route to 10.0.2.0/241724sshClient # [ 19.234566] dhcpcd[618]: eth0: adding default route via 10.0.2.21725sshClient # [ 19.383724] systemd[1]: Started DHCP Client.1726sshClient # [ 19.386230] systemd[1]: Reached target Multi-User System.1727sshClient # [ 19.387481] systemd[1]: Startup finished in 1.027s (kernel) + 4.129s (initrd) + 14.229s (userspace) = 19.387s.1728machine # [ 22.129239] dhcpcd[611]: eth0: leased 10.0.2.15 for 86400 seconds1729machine # [ 22.132953] dhcpcd[611]: eth0: adding route to 10.0.2.0/241730machine # [ 22.135211] dhcpcd[611]: eth0: adding default route via 10.0.2.21731machine # [ 22.261760] systemd[1]: Started DHCP Client.1732machine # [ 25.874659] systemd-homed[450]: Generating key pair for signing local user identity records.1733machine # [ 25.881842] systemd-homed[450]: Successfully created Ed25519 key pair.1734machine # [ 25.904823] systemd-homed[450]: test-homed-user: changing state absent → creating1735machine # [ 26.024348] systemd-homework[852]: Using automatic default storage of 'luks'.1736machine # [ 26.030288] systemd-homework[852]: Sizing home to 83% of available disk space, which is 744M.1737machine # [ 26.032909] systemd-homework[852]: Allocating image file completed.1738machine # [ 26.131784] systemd-homework[852]: Writing of partition table completed.1739machine # [ 26.135695] systemd-homework[852]: Setting up loopback device /dev/loop0 completed.1740machine # [ 27.413872] systemd-homework[852]: LUKS formatting completed.1741machine # [ 29.688462] systemd-homework[852]: Writing password to LUKS keyslot 0 completed.1742machine # [ 29.951354] systemd-homework[852]: LUKS activation by volume key succeeded.1743machine # [ 29.969728] systemd-homework[852]: Writing user record as LUKS token completed.1744machine # [ 29.970901] systemd-homework[852]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1745machine # [ 29.974167] systemd-homework[852]: Formatting /dev/mapper/home-test-homed-user as btrfs1746machine # [ 30.789939] systemd[1]: proc-887-mounts.mount: Deactivated successfully.1747machine # [ 30.790673] systemd-homework[852]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid ebd9827e-c690-49c0-8649-650ba720b43f)1748machine # [ 30.790830] systemd-homework[852]: Formatting file system completed.1749machine # [ 30.826546] systemd-homework[852]: Mounting file system completed.1750machine # [ 30.829245] systemd-homework[852]: Skeleton directory /etc/skel missing, ignoring.1751machine # [ 30.831434] systemd-homework[852]: Wrote embedded .identity file.1752machine # [ 30.833379] systemd-homework[852]: Replaced embedded blob dir with contents of system blob dir.1753machine # [ 30.834851] systemd-homework[852]: Recursive changing of ownership completed.1754machine # [ 30.835998] systemd-homework[852]: Changed top-level directory access mode to 0700.1755machine # [ 30.843568] systemd-homework[852]: Synchronized disk.1756machine # [ 30.845736] systemd-homework[852]: Discarded unused 652.8M.1757machine # [ 30.855870] systemd-homed[450]: block device /sys/devices/virtual/block/dm-0 has been removed.1758machine # [ 30.889509] systemd-homed[450]: block device /sys/devices/virtual/block/dm-0 has been removed.1759machine # [ 30.909593] systemd-homed[450]: block device /sys/devices/virtual/block/loop0 has been removed.1760machine # [ 30.922226] systemd-homework[852]: Moved image file into place.1761machine # [ 30.927717] systemd-homework[852]: Creation completed.1762machine # [ 30.928736] systemd-homework[852]: Image size is 744M, file system size is 726M, file system payload size is 726M, file system free is 719.9M.1763machine # [ 30.939063] systemd-homed[450]: test-homed-user: changing state creating → inactive1764machine # [ 30.948437] systemd[1]: Finished First Boot Home Area Wizard.1765machine # [ 30.951790] systemd[1]: systemd-mute-console@0-1-548_549-0.service: Deactivated successfully.1766machine # [ 30.957744] systemd[1]: Reached target First Boot Complete.1767machine # [ 30.963054] systemd[1]: Starting Save Transient machine-id to Disk...1768machine # [ 30.965911] systemd[1]: Starting Permit User Sessions...1769machine # [ 31.011324] systemd[1]: Finished Permit User Sessions.1770machine # [ 31.018711] systemd[1]: Started Getty on tty1.1771machine # [ 31.021932] systemd[1]: Reached target Login Prompts.1772machine # [ 31.028322] systemd[1]: Reached target Multi-User System.1773machine # [ 31.056359] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1774machine # [ 31.061172] systemd[1]: Finished Save Transient machine-id to Disk.1775machine # [ 31.076423] systemd[1]: Startup finished in 1.045s (kernel) + 4.143s (initrd) + 25.887s (userspace) = 31.076s.1776machine: (finished: waiting for unit systemd-homed-firstboot.service, in 16.16 seconds)1777(finished: subtest: create systemd-homed user on first boot prompt, in 32.22 seconds)1778machine: must succeed: homectl update test-homed-user --offline -G wheel1779machine # [ 31.825794] systemd-homed[450]: test-homed-user: changing state inactive → updating1780machine # [ 31.835404] systemd-homework[932]: Offline update requested. Not touching embedded records.1781machine # [ 31.840163] systemd-homed[450]: Home test-homed-user is signed exclusively by our key, accepting.1782machine # [ 31.851477] systemd-homed[450]: test-homed-user: changing state updating → inactive1783machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.09 seconds)1784subtest: login as homed user1785machine: waiting for login: to appear on tty 11786machine: (finished: waiting for login: to appear on tty 1, in 0.05 seconds)1787machine: sending keys 'test-homed-user\n'1788machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds)1789machine: waiting for Password: to appear on tty 11790machine # [ 32.271861] systemd-homed[450]: test-homed-user: changing state inactive → activating-for-acquire1791machine # [ 32.285697] systemd-homework[947]: None of the supplied plaintext passwords unlock the user record's hashed passwords.1792machine # [ 32.299739] systemd-homed[450]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.1793machine # [ 32.304772] systemd-homed[450]: test-homed-user: changing state activating-for-acquire → inactive1794machine # [ 32.308374] systemd-homed[450]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.1795machine # [ 32.311819] systemd-homed[450]: Home test-homed-user already deactivated, no automatic deactivation needed.1796machine: (finished: waiting for Password: to appear on tty 1, in 1.18 seconds)1797machine: sending keys 'foobarfoo\n'1798machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1799machine: waiting for success: pgrep -u test-homed-user -t tty1 bash1800machine # [ 33.452709] systemd-homed[450]: test-homed-user: changing state inactive → activating-for-acquire1801machine # [ 33.514503] systemd-homework[959]: Provided password unlocks user record.1802machine # [ 33.520605] systemd-homework[959]: Successfully locked image file '/home/test-homed-user.home'.1803machine # [ 33.529545] systemd-homework[959]: Allocated additional 652.8M.1804machine # [ 33.535211] systemd-homework[959]: Setting up loopback device /dev/loop1 completed.[ 33.550485] loop1: detected capacity change from 0 to 15196161805machine # 1806machine # [ 34.425998] systemd-homework[959]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1807machine # [ 34.463364] systemd-homework[959]: Provided password unlocks user record.1808machine # [ 34.469450] systemd-homework[959]: Probing file system completed (found btrfs).1809machine # [ 34.472094] systemd-homework[959]: No fsck for file system btrfs installed, ignoring.1810machine # [ 34.491491] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (959)1811machine # [ 34.496345] BTRFS info (device dm-0): first mount of filesystem ebd9827e-c690-49c0-8649-650ba720b43f1812machine # [ 34.496396] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm1813machine # [ 34.486481] systemd-homework[959]: Mounting file system completed.[ 34.503198] BTRFS info (device dm-0): enabling free space tree1814machine # [ 34.503223] BTRFS info (device dm-0): use zstd compression, level 11815machine # 1816machine # [ 34.491764] systemd-homework[959]: Discovered used loopback device /dev/loop1.1817machine # [ 34.494662] systemd-homework[959]: offset = 1048576, size = 778043392, image = 7801405441818machine # [ 34.503339] systemd-homework[959]: Ready to resize image size 744M → 897M, partition size 742M → 895M, file system size 726M → 879M.1819machine # [ 34.505755] systemd-homework[959]: Growing of image file from 744M to 897M completed.1820machine # [ 34.520313] systemd-homework[959]: Refreshing loop device size completed.1821machine # [ 34.533085] loop1: detected capacity change from 1519616 to 18329601822machine # [ 34.541789] systemd-homework[959]: Growing of partition completed.1823machine # [ 34.604680] async_tx: api initialized (async)1824machine # [ 34.645100] systemd-homework[959]: LUKS device growing completed.1825machine # [ 34.669368] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 761266176 to 9216983041826machine # [ 34.657721] systemd-homework[959]: File system resizing from 726M to 879M completed.1827machine # [ 34.660081] systemd-homework[959]: Synchronized disk.1828machine # [ 34.660845] systemd-homework[959]: Synchronized disk.1829machine # [ 34.661550] systemd-homework[959]: Resizing completed.1830machine # [ 34.662276] systemd-homework[959]: Image size is 897M, file system size is 879M, file system payload size is 879M, file system free is 872.9M.1831machine # [ 34.668181] systemd-homework[959]: Read embedded .identity file.1832machine # [ 34.688894] systemd-homework[959]: Provided password unlocks user record.1833machine # [ 34.691187] systemd-homework[959]: Reconciling header user identity completed (host version was newer).1834machine # [ 34.693361] systemd-homework[959]: Reconciling embedded user identity completed (host version was newer).1835machine # [ 34.713596] systemd-homework[959]: Wrote LUKS header user record.1836machine # [ 34.715084] systemd-homework[959]: Wrote embedded .identity file.1837machine # [ 34.716732] systemd-homework[959]: Replaced embedded blob dir with contents of system blob dir.1838machine # [ 34.718307] systemd-homework[959]: Recursive changing of ownership not necessary, skipped.1839machine # [ 34.723247] systemd-homework[959]: Synchronized disk.1840machine # [ 34.726132] systemd-homework[959]: Moving to final mount point /home/test-homed-user completed.1841machine # [ 34.757248] systemd-homework[959]: Activation completed.1842machine # [ 34.759912] systemd-homework[959]: Image size is 895M, file system size is 879M, file system payload size is 879M, file system free is 872.9M.1843machine # [ 34.779387] systemd-homed[450]: Home test-homed-user is signed exclusively by our key, accepting.1844machine # [ 34.785247] systemd-homed[450]: test-homed-user: changing state activating-for-acquire → active1845machine # [ 34.786864] login[923]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired.1846machine # [ 34.794875] systemd-homed[450]: test-homed-user: changing state active → resizing-while-active1847machine # [ 34.808116] systemd-homework[1004]: Successfully acquired home volume key from kernel keyring.1848machine # [ 34.812473] systemd-homework[1004]: LUKS volume key from keyring unlocks user record.1849machine # [ 34.876676] login[923]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0)1850machine # [ 34.932040] systemd[1]: Created slice Slice /user/60388.1851machine # [ 34.937831] systemd[1]: Starting User Runtime Directory /run/user/60388...1852machine # [ 34.950356] systemd-homework[1004]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.1853machine # [ 34.966146] systemd-logind[477]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'.1854machine # [ 35.107919] systemd-homework[1004]: Successfully re-activated LUKS device.1855machine # [ 35.116518] systemd-homework[1004]: LUKS volume key from keyring unlocks user record.1856machine # [ 35.121837] systemd-homework[1004]: Discovered used loopback device /dev/loop1.1857machine # [ 35.124865] systemd-homework[1004]: Read embedded .identity file.1858machine # [ 35.125818] systemd-homework[1004]: LUKS volume key from keyring unlocks user record.1859machine # [ 35.126848] systemd-homework[1004]: Reconciling header user identity completed (host version was newer).1860machine # [ 35.134973] systemd-homework[1004]: Reconciling embedded user identity completed (host version was newer).1861machine # [ 35.136405] systemd-homework[1004]: offset = 1048576, size = 938475520, image = 9405726721862machine # [ 35.137777] systemd[1]: Finished User Runtime Directory /run/user/60388.1863machine # [ 35.146077] systemd-homework[1004]: Ready to resize image size 897M → 765M, partition size 895M → 763M, file system size 879M → 747M.1864machine # [ 35.152150] systemd-homework[1004]: Wrote embedded .identity file.1865machine # [ 35.153174] systemd-homework[1004]: Replaced embedded blob dir with contents of system blob dir.1866machine # [ 35.154397] systemd-homework[1004]: Backing file is fully allocated already.1867machine # [ 35.155378] systemd[1]: Starting User Manager for UID 60388...1868machine # [ 35.202110] systemd-homework[1004]: File system resizing from 879M to 747M completed.1869machine # [ 35.218898] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 921698304 to 7832862721870machine # [ 35.210474] systemd-homework[1004]: Synchronized disk.1871machine # [ 35.220282] (systemd)[1008]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.1872machine # [ 35.298234] (systemd)[1008]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)1873machine # [ 35.307047] systemd-logind[477]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'.1874machine # [ 35.331981] systemd-homework[1004]: LUKS device shrinking completed.1875machine # [ 35.357160] loop1: detected capacity change from 1832960 to 15626241876machine # [ 35.347478] systemd-homework[1004]: Refreshing loop device size completed.1877machine # [ 35.350547] systemd-homework[1004]: Shrinking of image file completed.1878machine # [ 35.364132] systemd[1008]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1879machine # [ 35.376753] systemd-homework[1004]: Shrinking of partition completed.1880machine # [ 35.404733] systemd-homework[1004]: Wrote LUKS header user record.1881machine # [ 35.405743] systemd-homework[1004]: Synchronized disk.1882machine # [ 35.422644] systemd-homework[1004]: Resizing completed.1883machine # [ 35.423563] systemd-homework[1004]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.1884machine # [ 35.437702] systemd-homed[450]: Home test-homed-user is signed exclusively by our key, accepting.1885machine # [ 35.446223] systemd-homed[450]: test-homed-user: changing state resizing-while-active → active1886machine # [ 35.447848] systemd-homed[450]: Rebalancing complete.1887machine # [ 35.531105] systemd[1008]: Queued start job for default target Main User Target.1888machine # [ 35.537226] systemd[1008]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1889machine # [ 35.541413] systemd[1008]: Created slice User Application Slice.1890machine # [ 35.542352] systemd[1008]: Started Daily Cleanup of User's Temporary Directories.1891machine # [ 35.543395] systemd[1008]: Reached target Paths.1892machine # [ 35.544377] systemd[1008]: Reached target Timers.1893machine # [ 35.546595] systemd[1008]: Starting D-Bus User Message Bus Socket...1894machine # [ 35.548803] systemd[1008]: Starting Create User Files and Directories...1895machine # [ 35.592678] systemd[1008]: Finished Create User Files and Directories.1896machine # [ 35.615136] systemd[1008]: Listening on D-Bus User Message Bus Socket.1897machine # [ 35.617906] systemd[1008]: Reached target Sockets.1898machine # [ 35.618648] systemd[1008]: Reached target Basic System.1899machine # [ 35.619374] systemd[1]: Started User Manager for UID 60388.1900machine # [ 35.624298] systemd[1008]: Starting Run user-specific NixOS activation...1901machine # [ 35.629373] systemd[1]: Started Session 1 of User test-homed-user.1902machine # [ 35.687095] systemd[1008]: Finished Run user-specific NixOS activation.1903machine # [ 35.689528] systemd[1008]: Reached target Main User Target.1904machine # [ 35.690363] systemd[1008]: Startup finished in 350ms.1905machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 2.33 seconds)1906machine: sending keys 'whoami > /tmp/2\n'1907machine: (finished: sending keys 'whoami > /tmp/2\n', in 0.17 seconds)1908machine: waiting for file '/tmp/2'1909machine: (finished: waiting for file '/tmp/2', in 1.14 seconds)1910machine: must succeed: cat /tmp/21911machine: (finished: must succeed: cat /tmp/2, in 0.04 seconds)1912(finished: subtest: login as homed user, in 5.20 seconds)1913subtest: login as regular user1914sshClient: waiting for login: to appear on tty 11915sshClient: waiting for the VM to finish booting1916sshClient: Guest shell says: b'Spawning backdoor root shell...\n'1917sshClient: connected to guest root shell1918sshClient: (connecting took 0.00 seconds)1919sshClient: (finished: waiting for the VM to finish booting, in 0.00 seconds)1920sshClient: (finished: waiting for login: to appear on tty 1, in 0.10 seconds)1921sshClient: sending keys 'test-normal-user\n'1922sshClient: (finished: sending keys 'test-normal-user\n', in 0.18 seconds)1923sshClient: waiting for Password: to appear on tty 11924sshClient: (finished: waiting for Password: to appear on tty 1, in 1.18 seconds)1925sshClient: sending keys 'foobarfoo\n'1926sshClient: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1927sshClient: waiting for success: pgrep -u test-normal-user bash1928sshClient # [ 39.085902] login[647]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0)1929sshClient # [ 39.130908] systemd[1]: Created slice Slice /user/1000.1930sshClient # [ 39.134629] systemd[1]: Starting User Runtime Directory /run/user/1000...1931sshClient # [ 39.175754] systemd-logind[469]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'.1932sshClient # [ 39.210660] systemd[1]: Finished User Runtime Directory /run/user/1000.1933sshClient # [ 39.216163] systemd[1]: Starting User Manager for UID 1000...1934sshClient # [ 39.244366] (systemd)[809]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0)1935sshClient # [ 39.251504] systemd-logind[469]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'.1936sshClient # [ 39.279544] systemd[809]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1937sshClient # [ 39.408877] systemd[809]: Queued start job for default target Main User Target.1938sshClient # [ 39.416967] systemd[809]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1939sshClient # [ 39.420785] systemd[809]: Created slice User Application Slice.1940sshClient # [ 39.421681] systemd[809]: Started Daily Cleanup of User's Temporary Directories.1941sshClient # [ 39.422679] systemd[809]: Reached target Paths.1942sshClient # [ 39.423308] systemd[809]: Reached target Timers.1943sshClient # [ 39.425649] systemd[809]: Starting D-Bus User Message Bus Socket...1944sshClient # [ 39.427639] systemd[809]: Starting Create User Files and Directories...1945sshClient # [ 39.468484] systemd[809]: Finished Create User Files and Directories.1946sshClient # [ 39.487726] systemd[809]: Listening on D-Bus User Message Bus Socket.1947sshClient # [ 39.492286] systemd[809]: Reached target Sockets.1948sshClient # [ 39.493033] systemd[809]: Reached target Basic System.1949sshClient # [ 39.493731] systemd[1]: Started User Manager for UID 1000.1950sshClient # [ 39.494488] systemd[809]: Starting Run user-specific NixOS activation...1951sshClient # [ 39.496771] systemd[1]: Started Session 1 of User test-normal-user.1952sshClient # [ 39.557484] systemd[809]: Finished Run user-specific NixOS activation.1953sshClient # [ 39.562797] systemd[809]: Reached target Main User Target.1954sshClient # [ 39.563714] systemd[809]: Startup finished in 291ms.1955sshClient: (finished: waiting for success: pgrep -u test-normal-user bash, in 1.19 seconds)1956sshClient: sending keys 'whoami > /tmp/1\n'1957sshClient: (finished: sending keys 'whoami > /tmp/1\n', in 0.17 seconds)1958sshClient: waiting for file '/tmp/1'1959sshClient: (finished: waiting for file '/tmp/1', in 1.09 seconds)1960sshClient: must succeed: cat /tmp/11961sshClient: (finished: must succeed: cat /tmp/1, in 0.04 seconds)1962(finished: subtest: login as regular user, in 4.05 seconds)1963subtest: add homed ssh authorized key1964sshClient: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n'1965sshClient: (finished: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n', in 0.50 seconds)1966sshClient: waiting for file '/tmp/id_ed25519.pub'1967sshClient: (finished: waiting for file '/tmp/id_ed25519.pub', in 1.11 seconds)1968sshClient: must succeed: cat /tmp/id_ed25519.pub1969sshClient: (finished: must succeed: cat /tmp/id_ed25519.pub, in 0.04 seconds)1970machine: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOCbeHV4ZhGKL5mVswKwYvA96ev3gVHxck+2sSHFwPrq test-normal-user@sshClient'1971machine # [ 42.844911] systemd-homed[450]: test-homed-user: changing state active → updating-while-active1972machine # [ 42.861776] systemd-homework[1058]: Offline update requested. Not touching embedded records.1973machine # [ 42.874396] systemd-homed[450]: test-homed-user: changing state updating-while-active → active1974machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOCbeHV4ZhGKL5mVswKwYvA96ev3gVHxck+2sSHFwPrq test-normal-user@sshClient', in 0.12 seconds)1975machine: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed255191976machine: (finished: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed25519, in 0.05 seconds)1977(finished: subtest: add homed ssh authorized key, in 1.82 seconds)1978subtest: change homed user password1979machine: sending keys 'passwd; echo $? > /tmp/3\n'1980machine: (finished: sending keys 'passwd; echo $? > /tmp/3\n', in 0.27 seconds)1981machine: waiting for New password: to appear on tty 11982machine: (finished: waiting for New password: to appear on tty 1, in 1.18 seconds)1983machine: sending keys 'barfoobar\n'1984machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds)1985machine: waiting for Retype new password: to appear on tty 11986machine: (finished: waiting for Retype new password: to appear on tty 1, in 0.13 seconds)1987machine: sending keys 'barfoobar\n'1988machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds)1989??? Warning (UserWarning): sleep(): The 'secs' argument is deprecated. Use 'duration' instead.1990 File "/nix/store/8jd7437maa67c2lshz41ajlc2wfp49j5-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 5731991machine: must succeed: sleep 4.01992machine # [ 44.883481] systemd-homed[450]: test-homed-user: changing state active → passwd-while-active1993machine # [ 45.676578] systemd-homework[1090]: No valid password for LUKS superblock.1994machine # [ 47.914247] systemd-homed[450]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.1995machine # [ 47.922504] systemd-homed[450]: test-homed-user: changing state passwd-while-active → active1996machine: (finished: must succeed: sleep 4.0, in 4.04 seconds)1997machine: sending keys 'foobarfoo\n'1998machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1999machine: waiting for file '/tmp/3'2000machine # [ 49.012331] systemd-homed[450]: test-homed-user: changing state active → passwd-while-active2001machine # [ 50.326089] systemd-homework[1096]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2002machine # [ 50.453296] systemd-homework[1096]: Successfully re-activated LUKS device.2003machine # [ 50.508069] systemd-homework[1096]: Provided password unlocks user record.2004machine # [ 50.509942] systemd-homework[1096]: Discovered used loopback device /dev/loop1.2005machine # [ 50.511128] systemd-homework[1096]: Read embedded .identity file.2006machine # [ 50.563328] systemd-homework[1096]: Provided password unlocks user record.2007machine # [ 50.568221] systemd-homework[1096]: Reconciling header user identity completed (host version was newer).2008machine # [ 50.569499] systemd-homework[1096]: Reconciling embedded user identity completed (host version was newer).2009machine # [ 53.798406] systemd-homework[1096]: Added LUKS key slot 1.2010machine # [ 53.822399] systemd-homework[1096]: Destroyed LUKS key slot 0.2011machine # [ 53.832815] systemd-homework[1096]: Wrote LUKS header user record.2012machine # [ 53.834305] systemd-homework[1096]: Wrote embedded .identity file.2013machine # [ 53.835287] systemd-homework[1096]: Replaced embedded blob dir with contents of system blob dir.2014machine # [ 53.840059] systemd-homework[1096]: Synchronized disk.2015machine # [ 53.844707] systemd-homework[1096]: Everything completed.2016machine # [ 53.860725] systemd-homed[450]: test-homed-user: changing state passwd-while-active → active2017machine # [ 53.863698] passwd[1071]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user.2018machine: (finished: waiting for file '/tmp/3', in 5.21 seconds)2019machine: must succeed: cat /tmp/32020machine: (finished: must succeed: cat /tmp/3, in 0.03 seconds)2021(finished: subtest: change homed user password, in 11.17 seconds)2022subtest: escalate to root from homed user2023machine: sending keys 'sudo id | tee /tmp/4\n'2024machine: (finished: sending keys 'sudo id | tee /tmp/4\n', in 0.22 seconds)2025machine: waiting for password for test-homed-user to appear on tty 12026machine # [ 54.594629] systemd-homed[450]: test-homed-user: changing state active → authenticating-for-acquire2027machine # [ 54.605503] systemd-homework[1133]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2028machine # [ 54.615486] systemd-homed[450]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2029machine # [ 54.621583] systemd-homed[450]: test-homed-user: changing state authenticating-for-acquire → active2030machine: (finished: waiting for password for test-homed-user to appear on tty 1, in 1.16 seconds)2031machine: sending keys 'barfoobar\n'2032machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds)2033machine: waiting for file '/tmp/4'2034machine: (finished: waiting for file '/tmp/4', in 0.04 seconds)2035machine: waiting for success: grep uid=0 /tmp/42036machine # [ 55.672730] systemd-homed[450]: test-homed-user: changing state active → authenticating-for-acquire2037machine # [ 55.731888] systemd-homework[1149]: Provided password unlocks user record.2038machine # [ 56.394316] systemd-homework[1149]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2039machine # [ 56.521216] systemd-homework[1149]: Successfully re-activated LUKS device.2040machine # [ 56.549578] systemd-homework[1149]: Provided password unlocks user record.2041machine # [ 56.551358] systemd-homework[1149]: Discovered used loopback device /dev/loop1.2042machine # [ 56.552666] systemd-homework[1149]: Read embedded .identity file.2043machine # [ 56.577832] systemd-homework[1149]: Provided password unlocks user record.2044machine # [ 56.579794] systemd-homework[1149]: Reconciling user identities completed (host and header version were identical).2045machine # [ 56.581869] systemd-homework[1149]: Reconciling embedded user identity completed (host and embedded version were identical).2046machine # [ 56.592310] systemd-homework[1149]: Everything completed.2047machine # [ 56.603539] systemd-homed[450]: Home test-homed-user is signed exclusively by our key, accepting.2048machine # [ 56.609825] systemd-homed[450]: test-homed-user: changing state authenticating-for-acquire → active2049machine # [ 56.611452] sudo[1131]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired.2050machine # [ 56.616744] sudo[1131]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id2051machine # [ 56.629041] sudo[1131]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388)2052machine # [ 56.638328] sudo[1131]: pam_unix(sudo:session): session closed for user root2053machine: (finished: waiting for success: grep uid=0 /tmp/4, in 1.09 seconds)2054(finished: subtest: escalate to root from homed user, in 2.62 seconds)2055subtest: log out and deactivate homed user's home area2056machine: sending keys 'exit\n'2057machine: (finished: sending keys 'exit\n', in 0.05 seconds)2058machine: waiting for success: homectl inspect test-homed-user | grep 'State: inactive'2059machine # [ 56.818700] login[923]: pam_unix(login:session): session closed for user test-homed-user2060machine # [ 56.824611] login[923]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used.2061machine # [ 56.835500] systemd[1]: getty@tty1.service: Deactivated successfully.2062machine # [ 56.855709] systemd[1]: session-1.scope: Deactivated successfully.2063machine # [ 56.874040] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.2064machine # [ 56.889586] systemd-logind[477]: Session 1 logged out. Waiting for processes to exit.2065machine # [ 56.891136] systemd[1]: Started Getty on tty1.2066machine # [ 56.900524] systemd-logind[477]: Removed session 1.2067machine # [ 67.135926] systemd[1]: Stopping User Manager for UID 60388...2068machine # [ 67.149569] systemd[1008]: Activating special unit Exit the Session...2069machine # [ 67.161967] systemd[1008]: Stopped target Main User Target.2070machine # [ 67.168098] systemd[1008]: Stopped Run user-specific NixOS activation.2071machine # [ 67.170833] systemd[1008]: Stopped target Basic System.2072machine # [ 67.173240] systemd[1008]: Stopped target Paths.2073machine # [ 67.175251] systemd[1008]: Stopped target Sockets.2074machine # [ 67.177479] systemd[1008]: Stopped target Timers.2075machine # [ 67.179522] systemd[1008]: Stopped Daily Cleanup of User's Temporary Directories.2076machine # [ 67.184241] systemd[1008]: Closed D-Bus User Message Bus Socket.2077machine # [ 67.186384] systemd[1008]: Stopped Create User Files and Directories.2078machine # [ 67.188785] systemd[1008]: Removed slice User Application Slice.2079machine # [ 67.190728] systemd[1008]: Reached target Shutdown.2080machine # [ 67.192583] systemd[1008]: Finished Exit the Session.2081machine # [ 67.194174] systemd[1008]: Reached target Exit the Session.2082machine # [ 67.213802] systemd-homed[450]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2083machine # [ 67.225795] systemd-logind[477]: Removed session 2.2084machine # [ 67.227495] systemd[1]: user@60388.service: Deactivated successfully.2085machine # [ 67.231608] systemd[1]: Stopped User Manager for UID 60388.2086machine # [ 67.240575] systemd-homed[450]: test-homed-user: changing state active → deactivating2087machine # [ 67.249937] systemd-homework[1224]: Successfully acquired home volume key from kernel keyring.2088machine # [ 67.253728] systemd-homework[1224]: Discarded unused 674.3M.2089machine # [ 67.262777] systemd-homework[1224]: Syncing completed.2090machine # [ 67.292201] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully.2091machine # [ 67.314869] systemd[1]: Stopping User Runtime Directory /run/user/60388...2092machine # [ 67.378867] systemd-homework[1224]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2093machine # [ 67.425010] systemd[1]: run-user-60388.mount: Deactivated successfully.2094machine # [ 67.432212] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully.2095machine # [ 67.434183] systemd[1]: Stopped User Runtime Directory /run/user/60388.2096machine # [ 67.523669] systemd-homework[1224]: Successfully re-activated LUKS device.2097machine # [ 67.526436] systemd-homework[1224]: Discovered used loopback device /dev/loop1.2098machine # [ 67.527530] systemd-homework[1224]: offset = 1048576, size = 800063488, image = 8021606402099machine # [ 67.539543] systemd-homework[1224]: Ready to resize image size 765M → 274M, partition size 763M → 272M, file system size 747M → 256M.2100machine # [ 67.542907] systemd-homework[1224]: Allocated additional 674.3M.2101machine # [ 67.564972] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 783286272 to 2684354562102machine # [ 67.550020] systemd-homework[1224]: File system resizing from 747M to 256M completed.2103machine # [ 67.556284] systemd-homework[1224]: Synchronized disk.2104machine # [ 67.696239] systemd-homework[1224]: LUKS device shrinking completed.2105machine # [ 67.725098] loop1: detected capacity change from 1562624 to 5570562106machine # [ 67.713219] systemd-homework[1224]: Refreshing loop device size completed.2107machine # [ 67.716000] systemd-homework[1224]: Shrinking of image file completed.2108machine # [ 67.727131] systemd-homework[1224]: Shrinking of partition completed.2109machine # [ 67.731329] systemd-homework[1224]: Synchronized disk.2110machine # [ 67.745470] BTRFS info (device dm-0): last unmount of filesystem ebd9827e-c690-49c0-8649-650ba720b43f2111machine # [ 67.734753] systemd-homework[1224]: Resizing completed.2112machine # [ 67.735533] systemd-homework[1224]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M.2113machine # [ 67.737988] systemd-homework[1224]: Unmounting completed.2114machine # [ 67.738753] systemd-homework[1224]: Discovered used LUKS device /dev/mapper/home-test-homed-user.2115machine # [ 67.739876] systemd-homework[1224]: Device home-test-homed-user is not active.2116machine # [ 67.768762] systemd-homed[450]: block device /sys/devices/virtual/block/dm-0 has been removed.2117machine # [ 67.773566] systemd-homework[1224]: Everything completed.2118machine # [ 67.788179] systemd-homed[450]: test-homed-user: changing state deactivating → inactive2119machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 11.94 seconds)2120(finished: subtest: log out and deactivate homed user's home area, in 11.99 seconds)2121subtest: ssh as homed user2122sshClient: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n'2123sshClient: (finished: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n', in 0.80 seconds)2124sshClient: waiting for Please enter password for user to appear on tty 12125machine # [ 70.658960] systemd-homed[450]: test-homed-user: changing state inactive → activating-for-acquire2126machine # [ 70.673818] systemd-homework[1260]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2127machine # [ 70.686218] systemd-homed[450]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2128machine # [ 70.696522] systemd-homed[450]: test-homed-user: changing state activating-for-acquire → inactive2129machine # [ 70.703551] sshd-session[1256]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error2130machine # [ 70.708679] systemd-homed[450]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2131machine # [ 70.712568] sshd-session[1256]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation.2132machine # [ 70.717060] systemd-homed[450]: Home test-homed-user already deactivated, no automatic deactivation needed.2133machine # [ 70.719798] sshd-session[1256]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 43766 ssh2: ED25519 SHA256:1o09W9zGdOtL4re4rbcHeFe12gnpSKexZUe0BY2pCdw2134machine # [ 70.778708] sshd-session[1256]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0)2135machine # [ 70.796241] systemd-logind[477]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'.2136machine # [ 70.802493] systemd[1]: Starting User Runtime Directory /run/user/60388...2137machine # [ 70.835407] systemd[1]: Finished User Runtime Directory /run/user/60388.2138machine # [ 70.845104] systemd[1]: Started Session 3 of User test-homed-user.2139machine # [ 70.863191] sshd-session[1256]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2140machine # [ 70.866428] sshd-session[1256]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2141machine # [ 70.886082] systemd-homed[450]: test-homed-user: changing state inactive → activating2142machine # [ 70.892324] systemd-homework[1266]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2143machine # [ 70.899310] systemd-homed[450]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2144machine # [ 70.901832] systemd-homed[450]: test-homed-user: changing state activating → inactive2145sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.25 seconds)2146sshClient: sending keys 'barfoobar\n'2147sshClient: (finished: sending keys 'barfoobar\n', in 0.11 seconds)2148machine: waiting for success: pgrep -u test-homed-user bash2149machine # [ 71.968276] systemd-homed[450]: test-homed-user: changing state inactive → activating2150machine # [ 72.027585] systemd-homework[1271]: Provided password unlocks user record.2151machine # [ 72.033982] systemd-homework[1271]: Successfully locked image file '/home/test-homed-user.home'.2152machine # [ 72.040678] systemd-homework[1271]: Backing file is fully allocated already.2153machine # [ 72.045464] systemd-homework[1271]: Setting up loopback device /dev/loop1 completed.2154machine # [ 72.061194] loop1: detected capacity change from 0 to 5570562155machine # [ 72.943134] systemd-homework[1271]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.2156machine # [ 72.989417] systemd-homework[1271]: Provided password unlocks user record.2157machine # [ 72.998779] systemd-homework[1271]: Probing file system completed (found btrfs).2158machine # [ 73.000501] systemd-homework[1271]: No fsck for file system btrfs installed, ignoring.2159machine # [ 73.019753] BTRFS: device label test-homed-user devid 1 transid 17 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (1271)2160machine # [ 73.023732] BTRFS info (device dm-0): first mount of filesystem ebd9827e-c690-49c0-8649-650ba720b43f2161machine # [ 73.023789] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm2162machine # [ 73.015791] systemd-homework[1271]: Mounting file system completed.[ 73.032539] BTRFS info (device dm-0): enabling free space tree2163machine # [ 73.032562] BTRFS info (device dm-0): use zstd compression, level 12164machine # 2165machine # [ 73.023902] systemd-homework[1271]: Discovered used loopback device /dev/loop1.2166machine # [ 73.025384] systemd-homework[1271]: offset = 1048576, size = 285212672, image = 2873098242167machine # [ 73.040264] systemd-homework[1271]: Ready to resize image size 274M → 765M, partition size 272M → 763M, file system size 256M → 747M.2168machine # [ 73.042080] systemd-homework[1271]: Growing of image file from 274M to 765M completed.2169machine # [ 73.051961] systemd-homework[1271]: Refreshing loop device size completed.[ 73.065224] loop1: detected capacity change from 557056 to 15626242170machine # 2171machine # [ 73.066976] systemd-homework[1271]: Growing of partition completed.2172machine # [ 73.114354] systemd-homework[1271]: LUKS device growing completed.2173machine # [ 73.122374] systemd-homework[1271]: File system resizing from 256M to 747M completed.2174machine # [ 73.123590] systemd-homework[1271]: Synchronized disk.[ 73.138094] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 7832862722175machine # 2176machine # [ 73.128372] systemd-homework[1271]: Synchronized disk.2177machine # [ 73.129166] systemd-homework[1271]: Resizing completed.2178machine # [ 73.129901] systemd-homework[1271]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.2179machine # [ 73.131527] systemd-homework[1271]: Read embedded .identity file.2180machine # [ 73.156373] systemd-homework[1271]: Provided password unlocks user record.2181machine # [ 73.158553] systemd-homework[1271]: Reconciling user identities completed (host and header version were identical).2182machine # [ 73.159968] systemd-homework[1271]: Reconciling embedded user identity completed (host and embedded version were identical).2183machine # [ 73.166790] systemd-homework[1271]: Recursive changing of ownership not necessary, skipped.2184machine # [ 73.167970] systemd-homework[1271]: Synchronized disk.2185machine # [ 73.172252] systemd-homework[1271]: Moving to final mount point /home/test-homed-user completed.2186machine # [ 73.195741] systemd-homework[1271]: Activation completed.2187machine # [ 73.197419] systemd-homework[1271]: Image size is 763M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.2188machine # [ 73.216398] systemd-homed[450]: Home test-homed-user is signed exclusively by our key, accepting.2189machine # [ 73.221992] systemd-homed[450]: test-homed-user: changing state activating → active2190machine # [ 73.244612] systemd[1]: Starting User Manager for UID 60388...2191machine # [ 73.295636] (systemd)[1312]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.2192machine # [ 73.352771] (systemd)[1312]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)2193machine # [ 73.359277] systemd-logind[477]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'.2194machine # [ 73.387129] systemd[1312]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2195machine # [ 73.513497] systemd[1312]: Queued start job for default target Main User Target.2196machine # [ 73.521369] systemd[1312]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2197machine # [ 73.525934] systemd[1312]: Created slice User Application Slice.2198machine # [ 73.526920] systemd[1312]: Started Daily Cleanup of User's Temporary Directories.2199machine # [ 73.527941] systemd[1312]: Reached target Paths.2200machine # [ 73.530632] systemd[1312]: Reached target Timers.2201machine # [ 73.531414] systemd[1312]: Starting D-Bus User Message Bus Socket...2202machine # [ 73.533576] systemd[1312]: Starting Create User Files and Directories...2203machine # [ 73.581217] systemd[1312]: Finished Create User Files and Directories.2204machine # [ 73.585600] systemd[1312]: Listening on D-Bus User Message Bus Socket.2205machine # [ 73.588934] systemd[1312]: Reached target Sockets.2206machine # [ 73.589735] systemd[1312]: Reached target Basic System.2207machine # [ 73.590492] systemd[1]: Started User Manager for UID 60388.2208machine # [ 73.594666] systemd[1312]: Starting Run user-specific NixOS activation...2209machine # [ 73.658888] systemd[1312]: Finished Run user-specific NixOS activation.2210machine # [ 73.665115] systemd[1312]: Reached target Main User Target.2211machine # [ 73.666000] systemd[1312]: Startup finished in 283ms.2212machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.32 seconds)2213sshClient: sending keys 'whoami > /tmp/5\n'2214sshClient: (finished: sending keys 'whoami > /tmp/5\n', in 0.17 seconds)2215machine: waiting for file '/tmp/5'2216machine: (finished: waiting for file '/tmp/5', in 1.13 seconds)2217machine: must succeed: cat /tmp/52218machine: (finished: must succeed: cat /tmp/5, in 0.04 seconds)2219sshClient: sending keys 'exit\n'2220sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2221sshClient: sending keys 'exit\n'2222machine # [ 75.630190] sshd-session[1264]: Received disconnect from 2001:db8:1::2 port 43766:11: disconnected by user2223sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2224(finished: subtest: ssh as homed user, in 6.93 seconds)2225(finished: run the VM test script, in 81.24 seconds)2226machine # [ 75.645025] sshd-session[1264]: Disconnected from user test-homed-user 2001:db8:1::2 port 437662227machine # [ 75.670562] sshd-session[1256]: pam_unix(sshd:session): session closed for user test-homed-user2228machine # [ 75.679471] sshd-session[1256]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used.2229machine # [ 75.704554] systemd[1]: session-3.scope: Deactivated successfully.2230machine # [ 75.706647] systemd-logind[477]: Session 3 logged out. Waiting for processes to exit.2231machine # [ 75.715329] systemd-logind[477]: Removed session 3.2232sshClient # [ 76.071005] login[647]: pam_unix(login:session): session closed for user test-normal-user2233sshClient # [ 76.095350] systemd[1]: getty@tty1.service: Deactivated successfully.2234sshClient # [ 76.121878] systemd[1]: session-1.scope: Deactivated successfully.2235sshClient # [ 76.125680] systemd[1]: session-1.scope: Consumed 221ms CPU time over 36.627s wall clock time, 9.3M memory peak, 12K written to disk, 25K incoming IP traffic, 23.9K outgoing IP traffic.2236sshClient # [ 76.137252] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.2237sshClient # [ 76.153074] systemd-logind[469]: Session 1 logged out. Waiting for processes to exit.2238sshClient # [ 76.155617] systemd[1]: Started Getty on tty1.2239sshClient # [ 76.176109] systemd-logind[469]: Removed session 1.2240test script finished in 81.55s2241cleanup2242kill QemuMachine (pid 47)2243machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2244machine # [2026-09-24T02:52:00Z INFO virtiofsd] Client disconnected, shutting down2245machine # [2026-09-24T02:52:00Z INFO virtiofsd] Client disconnected, shutting down2246machine # [2026-09-24T02:52:00Z INFO virtiofsd] Client disconnected, shutting down2247kill QemuMachine (pid 48)2248sshClient # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2249sshClient # [2026-09-24T02:52:00Z INFO virtiofsd] Client disconnected, shutting down2250sshClient # [2026-09-24T02:52:00Z INFO virtiofsd] Client disconnected, shutting down2251sshClient # [2026-09-24T02:52:00Z INFO virtiofsd] Client disconnected, shutting down2252(finished: cleanup, in 0.41 seconds)2253additionally exposed symbols:2254 machine, sshClient,2255 vlan1,2256 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh