vm-test-run-systemd-homed
checks.aarch64-linux.systemd-homed
· build #49
· 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: QEMU running (pid 48)11machine # Disk image does not exist, creating the virtualisation disk image...12machine: QEMU running (pid 47)13machine # Formatting '/build/vm-state-machine/tmp.YaBw39dqnF', fmt=raw size=107374182414sshClient # Disk image does not exist, creating the virtualisation disk image...15machine # mke2fs 1.47.4 (6-Mar-2025)16sshClient # Formatting '/build/vm-state-sshClient/tmp.t0xnDDOHl8', fmt=raw size=107374182417machine # Discarding device blocks: 0/262144 done18sshClient # mke2fs 1.47.4 (6-Mar-2025)19machine # Creating filesystem with 262144 4k blocks and 65536 inodes20sshClient # Discarding device blocks: 0/262144 done21sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes22(finished: start all VMs, in 0.54 seconds)23sshClient # Filesystem UUID: cc0b0364-0557-489e-8107-ab2509587be724subtest: create systemd-homed user on first boot prompt25sshClient # Superblock backups stored on blocks:26machine: waiting for unit systemd-homed.service27sshClient # 32768, 98304, 163840, 22937628machine: waiting for the VM to finish booting29sshClient # 30machine # Filesystem UUID: bfbe21ec-e619-4204-98a0-d71cb902165d31sshClient # Allocating group tables: 0/8 done32sshClient # Writing inode tables: 0/8 done33machine # Superblock backups stored on blocks:34sshClient # Creating journal (8192 blocks): done35machine # 32768, 98304, 163840, 22937636sshClient # Writing superblocks and filesystem accounting information: 0/8 done37machine # 38sshClient # 39machine # Allocating group tables: 0/8 done40sshClient # Virtualisation disk image created.41machine # Writing inode tables: 0/8 done42sshClient # Starting virtiofs daemons...43machine # Creating journal (8192 blocks): done44sshClient # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)45machine # Writing superblocks and filesystem accounting information: 0/8 done46sshClient # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether47machine # 48sshClient # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)49machine # Virtualisation disk image created.50sshClient # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether51machine # Starting virtiofs daemons...52sshClient # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)53machine # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)54sshClient # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether55machine # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether56sshClient # [2026-09-30T03:39:13Z INFO virtiofsd] Waiting for vhost-user socket connection...57machine # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)58sshClient # [2026-09-30T03:39:13Z INFO virtiofsd] Waiting for vhost-user socket connection...59machine # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether60sshClient # [2026-09-30T03:39:13Z INFO virtiofsd] Waiting for vhost-user socket connection...61machine # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)62sshClient # [2026-09-30T03:39:13Z INFO virtiofsd] Client connected, servicing requests63machine # [2026-09-30T03:39:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether64sshClient # [2026-09-30T03:39:13Z INFO virtiofsd] Client connected, servicing requests65machine # [2026-09-30T03:39:13Z INFO virtiofsd] Waiting for vhost-user socket connection...66sshClient # [2026-09-30T03:39:13Z INFO virtiofsd] Client connected, servicing requests67machine # [2026-09-30T03:39:13Z INFO virtiofsd] Waiting for vhost-user socket connection...68machine # [2026-09-30T03:39:13Z INFO virtiofsd] Waiting for vhost-user socket connection...69machine # [2026-09-30T03:39:13Z INFO virtiofsd] Client connected, servicing requests70machine # [2026-09-30T03:39:13Z INFO virtiofsd] Client connected, servicing requests71machine # [2026-09-30T03:39:13Z INFO virtiofsd] Client connected, servicing requests72machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]73machine # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202674machine # [ 0.000000] KASLR enabled75machine # [ 0.000000] random: crng init done76machine # [ 0.000000] Machine model: linux,dummy-virt77machine # [ 0.000000] efi: UEFI not found.78machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT79machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]80machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f]81machine # [ 0.000000] Zone ranges:82machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]83machine # [ 0.000000] DMA32 empty84machine # [ 0.000000] Normal empty85machine # [ 0.000000] Device empty86machine # [ 0.000000] Movable zone start for each node87machine # [ 0.000000] Early memory node ranges88machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]89machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]90machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000091machine # [ 0.000000] psci: probing for conduit method from DT.92machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.93sshClient # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]94machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs95machine # [ 0.000000] psci: Trusted OS migration not required96machine # [ 0.000000] psci: SMC Calling Convention v1.197sshClient # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202698sshClient # [ 0.000000] KASLR enabled99machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)100sshClient # [ 0.000000] random: crng init done101sshClient # [ 0.000000] Machine model: linux,dummy-virt102machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296103sshClient # [ 0.000000] efi: UEFI not found.104machine # [ 0.000000] Detected PIPT I-cache on CPU0105sshClient # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT106machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)107sshClient # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]108machine # [ 0.000000] CPU features: detected: GICv3 CPU interface109machine # [ 0.000000] CPU features: detected: Spectre-v4110sshClient # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f]111sshClient # [ 0.000000] Zone ranges:112machine # [ 0.000000] CPU features: detected: Spectre-BHB113sshClient # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]114machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38115sshClient # [ 0.000000] DMA32 empty116sshClient # [ 0.000000] Normal empty117machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23118sshClient # [ 0.000000] Device empty119machine # [ 0.000000] alternatives: applying boot alternatives120sshClient # [ 0.000000] Movable zone start for each node121sshClient # [ 0.000000] Early memory node ranges122sshClient # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]123sshClient # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]124sshClient # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000125sshClient # [ 0.000000] psci: probing for conduit method from DT.126machine # [ 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/w3m7w2a54aa7pd9vcms7g47ph9swqc0k-nixos-system-machine-test/init regInfo=/nix/.ro-store/yxyh9jzy7qkmbd5lfmj3w55gi5dzsrn3-closure-info/registration console=ttyAMA0,115200n8 console=tty0127sshClient # [ 0.000000] psci: PSCIv1.3 detected in firmware.128sshClient # [ 0.000000] psci: Using standard PSCI v0.2 function IDs129sshClient # [ 0.000000] psci: Trusted OS migration not required130machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/yxyh9jzy7qkmbd5lfmj3w55gi5dzsrn3-closure-info/registration", will be passed to user space.131sshClient # [ 0.000000] psci: SMC Calling Convention v1.1132machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes133sshClient # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)134machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)135sshClient # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296136sshClient # [ 0.000000] Detected PIPT I-cache on CPU0137machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)138sshClient # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)139machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB140machine # [ 0.000000] software IO TLB: area num 1.141sshClient # [ 0.000000] CPU features: detected: GICv3 CPU interface142sshClient # [ 0.000000] CPU features: detected: Spectre-v4143machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)144sshClient # [ 0.000000] CPU features: detected: Spectre-BHB145machine # [ 0.000000] Fallback order for Node 0: 0146sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38147machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144148machine # [ 0.000000] Policy zone: DMA149sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23150sshClient # [ 0.000000] alternatives: applying boot alternatives151machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off152machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1153machine # [ 0.000000] allocated 2097152 bytes of page_ext154machine # [ 0.000000] ftrace: allocating 74966 entries in 294 pages155machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups156machine # [ 0.000000] rcu: Hierarchical RCU implementation.157machine # [ 0.000000] rcu: RCU event tracing is enabled.158sshClient # [ 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/zf77wrprw8y01r1lp74cyz60kjys14wb-nixos-system-sshClient-test/init regInfo=/nix/.ro-store/9cia8mbip88gv21vxd3c78h7cazwl1y3-closure-info/registration console=ttyAMA0,115200n8 console=tty0159machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.160machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.161sshClient # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/9cia8mbip88gv21vxd3c78h7cazwl1y3-closure-info/registration", will be passed to user space.162machine # [ 0.000000] Rude variant of Tasks RCU enabled.163machine # [ 0.000000] Tracing variant of Tasks RCU enabled.164sshClient # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes165machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.166sshClient # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)167machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1168sshClient # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)169machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.170sshClient # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB171sshClient # [ 0.000000] software IO TLB: area num 1.172machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.173sshClient # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)174sshClient # [ 0.000000] Fallback order for Node 0: 0175machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.176sshClient # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144177machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0178sshClient # [ 0.000000] Policy zone: DMA179machine # [ 0.000000] GICv3: 256 SPIs implemented180machine # [ 0.000000] GICv3: 0 Extended SPIs implemented181sshClient # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off182machine # [ 0.000000] Root IRQ handler: gic_handle_irq183sshClient # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1184machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI185sshClient # [ 0.000000] allocated 2097152 bytes of page_ext186machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0187sshClient # [ 0.000000] ftrace: allocating 74966 entries in 294 pages188machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000189sshClient # [ 0.000000] ftrace: allocated 294 pages with 4 groups190machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]191sshClient # [ 0.000000] rcu: Hierarchical RCU implementation.192sshClient # [ 0.000000] rcu: RCU event tracing is enabled.193machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)194sshClient # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.195sshClient # [ 0.000000] Trampoline variant of Tasks RCU enabled.196machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)197sshClient # [ 0.000000] Rude variant of Tasks RCU enabled.198machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000199sshClient # [ 0.000000] Tracing variant of Tasks RCU enabled.200machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000201sshClient # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.202machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.203sshClient # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1204machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns205sshClient # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.206machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).207sshClient # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.208machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns209sshClient # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.210sshClient # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0211machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns212sshClient # [ 0.000000] GICv3: 256 SPIs implemented213machine # [ 0.000037] arm-pv: using stolen time PV214sshClient # [ 0.000000] GICv3: 0 Extended SPIs implemented215sshClient # [ 0.000000] Root IRQ handler: gic_handle_irq216machine # [ 0.000693] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)217sshClient # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI218machine # [ 0.000889] Console: colour dummy device 80x25219sshClient # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0220machine # [ 0.000897] printk: legacy console [tty0] enabled221sshClient # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000222machine # [ 0.001125] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)223sshClient # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]224machine # [ 0.001131] pid_max: default: 32768 minimum: 301225sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)226machine # [ 0.001210] LSM: initializing lsm=capability,landlock,yama,bpf,ima227machine # [ 0.001391] landlock: Up and running.228machine # [ 0.001394] Yama: becoming mindful.229sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)230machine # [ 0.002189] LSM support for eBPF active231sshClient # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000232machine # [ 0.002360] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)233sshClient # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000234machine # [ 0.002381] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)235sshClient # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.236machine # [ 0.004341] rcu: Hierarchical SRCU implementation.237machine # [ 0.004347] rcu: Max phase no-delay instances is 1000.238sshClient # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns239machine # [ 0.005678] fsl-mc MSI: its@8080000 domain created240sshClient # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).241machine # [ 0.005798] EFI services will not be available.242machine # [ 0.005920] smp: Bringing up secondary CPUs ...243machine # [ 0.005929] smp: Brought up 1 node, 1 CPU244sshClient # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns245machine # [ 0.005932] SMP: Total of 1 processors activated.246machine # [ 0.005935] CPU: All CPU(s) started at EL1247sshClient # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns248sshClient # [ 0.000033] arm-pv: using stolen time PV249machine # [ 0.005949] CPU features: detected: Branch Target Identification250machine # [ 0.005954] CPU features: detected: ARMv8.4 Translation Table Level251sshClient # [ 0.000562] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)252sshClient # [ 0.000752] Console: colour dummy device 80x25253machine # [ 0.005957] CPU features: detected: Instruction cache invalidation not required for I/D coherence254sshClient # [ 0.000761] printk: legacy console [tty0] enabled255machine # [ 0.005961] CPU features: detected: Data cache clean to the PoU not required for I/D coherence256sshClient # [ 0.000976] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)257machine # [ 0.005965] CPU features: detected: Common not Private translations258sshClient # [ 0.000983] pid_max: default: 32768 minimum: 301259machine # [ 0.005968] CPU features: detected: CRC32 instructions260sshClient # [ 0.001067] LSM: initializing lsm=capability,landlock,yama,bpf,ima261sshClient # [ 0.001283] landlock: Up and running.262machine # [ 0.005971] CPU features: detected: Data cache clean to Point of Deep Persistence263sshClient # [ 0.001286] Yama: becoming mindful.264machine # [ 0.005974] CPU features: detected: Data cache clean to Point of Persistence265sshClient # [ 0.001866] LSM support for eBPF active266machine # [ 0.005977] CPU features: detected: Data independent timing control (DIT)267sshClient # [ 0.001997] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)268machine # [ 0.005980] CPU features: detected: E0PD269sshClient # [ 0.002018] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)270machine # [ 0.005982] CPU features: detected: Enhanced Counter Virtualization271sshClient # [ 0.003995] rcu: Hierarchical SRCU implementation.272machine # [ 0.005985] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)273sshClient # [ 0.004028] rcu: Max phase no-delay instances is 1000.274machine # [ 0.005989] CPU features: detected: Enhanced Virtualization Traps275sshClient # [ 0.005277] fsl-mc MSI: its@8080000 domain created276sshClient # [ 0.005389] EFI services will not be available.277machine # [ 0.005992] CPU features: detected: Fine Grained Traps278sshClient # [ 0.005486] smp: Bringing up secondary CPUs ...279sshClient # [ 0.005495] smp: Brought up 1 node, 1 CPU280machine # [ 0.005996] CPU features: detected: Generic authentication (architected QARMA5 algorithm)281sshClient # [ 0.005498] SMP: Total of 1 processors activated.282machine # [ 0.006001] CPU features: detected: RCpc load-acquire (LDAPR)283sshClient # [ 0.005501] CPU: All CPU(s) started at EL1284machine # [ 0.006004] CPU features: detected: LSE atomic instructions285sshClient # [ 0.005514] CPU features: detected: Branch Target Identification286machine # [ 0.006007] CPU features: detected: Privileged Access Never287machine # [ 0.006010] CPU features: detected: PMUv3288sshClient # [ 0.005519] CPU features: detected: ARMv8.4 Translation Table Level289machine # [ 0.006012] CPU features: detected: RAS Extension Support290sshClient # [ 0.005522] CPU features: detected: Instruction cache invalidation not required for I/D coherence291sshClient # [ 0.005525] CPU features: detected: Data cache clean to the PoU not required for I/D coherence292machine # [ 0.006015] CPU features: detected: RASv1p1 Extension Support293machine # [ 0.006018] CPU features: detected: Random Number Generator294sshClient # [ 0.005529] CPU features: detected: Common not Private translations295sshClient # [ 0.005532] CPU features: detected: CRC32 instructions296machine # [ 0.006020] CPU features: detected: Speculation barrier (SB)297machine # [ 0.006023] CPU features: detected: Stage-2 Force Write-Back298sshClient # [ 0.005535] CPU features: detected: Data cache clean to Point of Deep Persistence299machine # [ 0.006026] CPU features: detected: TLB range maintenance instructions300sshClient # [ 0.005539] CPU features: detected: Data cache clean to Point of Persistence301machine # [ 0.006031] CPU features: detected: Speculative Store Bypassing Safe (SSBS)302machine # [ 0.006071] alternatives: applying system-wide alternatives303sshClient # [ 0.005542] CPU features: detected: Data independent timing control (DIT)304machine # [ 0.009045] CPU features: detected: BBM Level 2 without TLB conflict abort305sshClient # [ 0.005545] CPU features: detected: E0PD306sshClient # [ 0.005547] CPU features: detected: Enhanced Counter Virtualization307sshClient # [ 0.005550] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)308machine # [ 0.009248] Memory: 893748K/1048576K available (24448K kernel code, 7094K rwdata, 26596K rodata, 4736K init, 1107K bss, 113552K reserved, 32768K cma-reserved)309machine # [ 0.009718] devtmpfs: initialized310sshClient # [ 0.005554] CPU features: detected: Enhanced Virtualization Traps311machine # [ 0.011649] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)312sshClient # [ 0.005557] CPU features: detected: Fine Grained Traps313sshClient # [ 0.005561] CPU features: detected: Generic authentication (architected QARMA5 algorithm)314machine # [ 0.011672] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).315sshClient # [ 0.005566] CPU features: detected: RCpc load-acquire (LDAPR)316machine # [ 0.011868] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL317machine # [ 0.011876] 0 pages in range for non-PLT usage318sshClient # [ 0.005569] CPU features: detected: LSE atomic instructions319machine # [ 0.011878] 508224 pages in range for PLT usage320sshClient # [ 0.005572] CPU features: detected: Privileged Access Never321sshClient # [ 0.005574] CPU features: detected: PMUv3322machine # [ 0.012011] pinctrl core: initialized pinctrl subsystem323machine # [ 0.012835] DMI not present or invalid.324sshClient # [ 0.005577] CPU features: detected: RAS Extension Support325machine # [ 0.016319] NET: Registered PF_NETLINK/PF_ROUTE protocol family326sshClient # [ 0.005580] CPU features: detected: RASv1p1 Extension Support327sshClient # [ 0.005583] CPU features: detected: Random Number Generator328machine # [ 0.018750] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations329sshClient # [ 0.005585] CPU features: detected: Speculation barrier (SB)330machine # [ 0.018913] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations331sshClient # [ 0.005588] CPU features: detected: Stage-2 Force Write-Back332machine # [ 0.019074] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations333sshClient # [ 0.005591] CPU features: detected: TLB range maintenance instructions334machine # [ 0.019100] audit: initializing netlink subsys (disabled)335sshClient # [ 0.005596] CPU features: detected: Speculative Store Bypassing Safe (SSBS)336machine # [ 0.019707] thermal_sys: Registered thermal governor 'fair_share'337sshClient # [ 0.005636] alternatives: applying system-wide alternatives338machine # [ 0.019709] thermal_sys: Registered thermal governor 'bang_bang'339sshClient # [ 0.008637] CPU features: detected: BBM Level 2 without TLB conflict abort340machine # [ 0.019712] thermal_sys: Registered thermal governor 'step_wise'341machine # [ 0.019715] thermal_sys: Registered thermal governor 'user_space'342sshClient # [ 0.008827] Memory: 893768K/1048576K available (24448K kernel code, 7094K rwdata, 26596K rodata, 4736K init, 1107K bss, 113536K reserved, 32768K cma-reserved)343machine # [ 0.019718] thermal_sys: Registered thermal governor 'power_allocator'344sshClient # [ 0.009180] devtmpfs: initialized345machine # [ 0.019746] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1346sshClient # [ 0.011038] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)347machine # [ 0.019754] cpuidle: using governor ladder348machine # [ 0.019760] cpuidle: using governor menu349sshClient # [ 0.011061] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).350machine # [ 0.019972] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.351sshClient # [ 0.011245] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL352machine # [ 0.019989] ASID allocator initialised with 65536 entries353sshClient # [ 0.011253] 0 pages in range for non-PLT usage354machine # [ 0.021242] Serial: AMBA PL011 UART driver355sshClient # [ 0.011254] 508224 pages in range for PLT usage356sshClient # [ 0.011360] pinctrl core: initialized pinctrl subsystem357machine # [ 0.027170] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1358sshClient # [ 0.012214] DMI not present or invalid.359machine # [ 0.027316] printk: console [ttyAMA0] enabled360sshClient # [ 0.015532] NET: Registered PF_NETLINK/PF_ROUTE protocol family361sshClient # [ 0.017870] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations362sshClient # [ 0.018022] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations363sshClient # [ 0.018183] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations364sshClient # [ 0.018205] audit: initializing netlink subsys (disabled)365sshClient # [ 0.018899] thermal_sys: Registered thermal governor 'fair_share'366sshClient # [ 0.018902] thermal_sys: Registered thermal governor 'bang_bang'367sshClient # [ 0.018906] thermal_sys: Registered thermal governor 'step_wise'368sshClient # [ 0.018909] thermal_sys: Registered thermal governor 'user_space'369sshClient # [ 0.018912] thermal_sys: Registered thermal governor 'power_allocator'370sshClient # [ 0.018941] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1371sshClient # [ 0.018949] cpuidle: using governor ladder372sshClient # [ 0.018955] cpuidle: using governor menu373sshClient # [ 0.019153] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.374machine # [ 0.158766] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages375sshClient # [ 0.019168] ASID allocator initialised with 65536 entries376machine # [ 0.158777] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page377sshClient # [ 0.020414] Serial: AMBA PL011 UART driver378machine # [ 0.158780] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages379sshClient # [ 0.026030] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1380machine # [ 0.158783] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page381sshClient # [ 0.026200] printk: console [ttyAMA0] enabled382machine # [ 0.158786] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages383machine # [ 0.158789] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page384machine # [ 0.158792] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages385machine # [ 0.158795] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page386machine # [ 0.160011] fbcon: Taking over console387machine # [ 0.160021] ACPI: Interpreter disabled.388machine # [ 0.170345] iommu: Default domain type: Translated389machine # [ 0.170352] iommu: DMA domain TLB invalidation policy: strict mode390machine # [ 0.170835] SCSI subsystem initialized391machine # [ 0.172981] usbcore: registered new interface driver usbfs392machine # [ 0.173010] usbcore: registered new interface driver hub393machine # [ 0.173027] usbcore: registered new device driver usb394sshClient # [ 0.158018] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages395machine # [ 0.173327] pps_core: LinuxPPS API ver. 1 registered396sshClient # [ 0.158031] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page397machine # [ 0.173331] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>398sshClient # [ 0.158034] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages399machine # [ 0.173339] PTP clock support registered400sshClient # [ 0.158037] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page401machine # [ 0.173377] EDAC MC: Ver: 3.0.0402machine # [ 0.174881] scmi_core: SCMI protocol bus registered403sshClient # [ 0.158040] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages404sshClient # [ 0.158043] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page405sshClient # [ 0.158046] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages406sshClient # [ 0.158049] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page407machine # [ 0.180807] FPGA manager framework408sshClient # [ 0.159271] fbcon: Taking over console409machine # [ 0.183085] vgaarb: loaded410sshClient # [ 0.159281] ACPI: Interpreter disabled.411sshClient # [ 0.169607] iommu: Default domain type: Translated412sshClient # [ 0.169614] iommu: DMA domain TLB invalidation policy: strict mode413sshClient # [ 0.170041] SCSI subsystem initialized414machine # [ 0.184219] clocksource: Switched to clocksource arch_sys_counter415sshClient # [ 0.172225] usbcore: registered new interface driver usbfs416sshClient # [ 0.172254] usbcore: registered new interface driver hub417sshClient # [ 0.172270] usbcore: registered new device driver usb418sshClient # [ 0.172567] pps_core: LinuxPPS API ver. 1 registered419machine # [ 0.190708] VFS: Disk quotas dquot_6.6.0420sshClient # [ 0.172570] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>421sshClient # [ 0.172578] PTP clock support registered422machine # [ 0.190741] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)423sshClient # [ 0.172618] EDAC MC: Ver: 3.0.0424machine # [ 0.191080] netfs: FS-Cache loaded425machine # [ 0.191193] pnp: PnP ACPI: disabled426sshClient # [ 0.179117] scmi_core: SCMI protocol bus registered427sshClient # [ 0.179507] FPGA manager framework428sshClient # [ 0.180076] vgaarb: loaded429sshClient # [ 0.180360] clocksource: Switched to clocksource arch_sys_counter430machine # [ 0.198904] NET: Registered PF_INET protocol family431machine # [ 0.199074] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)432sshClient # [ 0.189879] VFS: Disk quotas dquot_6.6.0433sshClient # [ 0.189917] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)434sshClient # [ 0.193847] netfs: FS-Cache loaded435sshClient # [ 0.193990] pnp: PnP ACPI: disabled436sshClient # [ 0.198098] NET: Registered PF_INET protocol family437sshClient # [ 0.198263] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)438machine # [ 0.229839] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)439machine # [ 0.229880] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)440machine # [ 0.229905] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)441machine # [ 0.229949] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)442machine # [ 0.230022] TCP: Hash tables configured (established 8192 bind 8192)443machine # [ 0.230104] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)444machine # [ 0.230136] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)445machine # [ 0.230184] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)446machine # [ 0.230263] NET: Registered PF_UNIX/PF_LOCAL protocol family447machine # [ 0.230329] NET: Registered PF_XDP protocol family448machine # [ 0.230346] PCI: CLS 0 bytes, default 64449machine # [ 0.230706] Trying to unpack rootfs image as initramfs...450machine # [ 0.242767] kvm [1]: HYP mode not available451sshClient # [ 0.229961] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)452sshClient # [ 0.230009] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)453sshClient # [ 0.230037] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)454sshClient # [ 0.230081] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)455sshClient # [ 0.230154] TCP: Hash tables configured (established 8192 bind 8192)456sshClient # [ 0.230240] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)457sshClient # [ 0.230297] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)458sshClient # [ 0.230343] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)459sshClient # [ 0.230418] NET: Registered PF_UNIX/PF_LOCAL protocol family460sshClient # [ 0.230440] NET: Registered PF_XDP protocol family461sshClient # [ 0.230456] PCI: CLS 0 bytes, default 64462sshClient # [ 0.230746] Trying to unpack rootfs image as initramfs...463sshClient # [ 0.248390] kvm [1]: HYP mode not available464machine # [ 0.369520] Initialise system trusted keyrings465machine # [ 0.369660] workingset: timestamp_bits=42 max_order=18 bucket_order=0466machine # [ 0.370049] squashfs: version 4.0 (2009/01/31) Phillip Lougher467machine # [ 0.370119] 9p: Installing v9fs 9p2000 file system support468sshClient # [ 0.364914] Initialise system trusted keyrings469sshClient # [ 0.365061] workingset: timestamp_bits=42 max_order=18 bucket_order=0470sshClient # [ 0.365470] squashfs: version 4.0 (2009/01/31) Phillip Lougher471sshClient # [ 0.365526] 9p: Installing v9fs 9p2000 file system support472machine # [ 0.398334] Key type asymmetric registered473machine # [ 0.398351] Asymmetric key parser 'x509' registered474machine # [ 0.398430] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)475machine # [ 0.398589] io scheduler mq-deadline registered476machine # [ 0.398593] io scheduler kyber registered477sshClient # [ 0.393759] Key type asymmetric registered478sshClient # [ 0.393774] Asymmetric key parser 'x509' registered479machine # [ 0.411721] pl061_gpio 9030000.pl061: PL061 GPIO chip registered480sshClient # [ 0.393849] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)481sshClient # [ 0.394006] io scheduler mq-deadline registered482sshClient # [ 0.394010] io scheduler kyber registered483sshClient # [ 0.406461] pl061_gpio 9030000.pl061: PL061 GPIO chip registered484sshClient # [ 0.407074] ledtrig-cpu: registered to indicate activity on CPUs485sshClient # [ 0.407448] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:486sshClient # [ 0.407462] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000487sshClient # [ 0.407473] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000488sshClient # [ 0.407478] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000489sshClient # [ 0.407511] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits490sshClient # [ 0.407531] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]491sshClient # [ 0.407604] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00492machine # [ 0.420638] ledtrig-cpu: registered to indicate activity on CPUs493sshClient # [ 0.407611] pci_bus 0000:00: root bus resource [bus 00-ff]494sshClient # [ 0.407615] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]495machine # [ 0.421121] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:496sshClient # [ 0.407618] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]497machine # [ 0.421140] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000498sshClient # [ 0.407621] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]499machine # [ 0.421152] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000500sshClient # [ 0.407719] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint501machine # [ 0.421158] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000502sshClient # [ 0.408174] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint503machine # [ 0.421190] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits504machine # [ 0.421216] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]505machine # [ 0.421315] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00506machine # [ 0.421324] pci_bus 0000:00: root bus resource [bus 00-ff]507machine # [ 0.421328] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]508machine # [ 0.421331] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]509machine # [ 0.421334] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]510machine # [ 0.421455] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint511machine # [ 0.422005] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint512machine # [ 0.422207] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]513machine # [ 0.422220] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]514sshClient # [ 0.428477] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]515machine # [ 0.422247] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]516sshClient # [ 0.428505] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]517machine # [ 0.422261] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]518sshClient # [ 0.428533] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]519sshClient # [ 0.428545] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]520machine # [ 0.422767] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint521machine # [ 0.422963] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]522sshClient # [ 0.429135] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint523machine # [ 0.422976] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]524sshClient # [ 0.429338] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]525sshClient # [ 0.429349] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]526machine # [ 0.423003] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]527machine # [ 0.423523] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint528sshClient # [ 0.429375] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]529machine # [ 0.423721] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]530sshClient # [ 0.429872] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint531machine # [ 0.423733] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]532sshClient # [ 0.430062] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]533machine # [ 0.423761] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]534sshClient # [ 0.430074] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]535sshClient # [ 0.430100] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]536machine # [ 0.424308] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint537machine # [ 0.424512] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]538sshClient # [ 0.430601] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint539machine # [ 0.424525] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]540sshClient # [ 0.430814] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]541machine # [ 0.424552] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]542sshClient # [ 0.430825] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]543machine # [ 0.424564] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]544sshClient # [ 0.430851] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]545machine # [ 0.425076] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint546sshClient # [ 0.430863] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]547machine # [ 0.425275] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]548sshClient # [ 0.431364] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint549machine # [ 0.425302] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]550sshClient # [ 0.431563] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]551sshClient # [ 0.431589] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]552machine # [ 0.425925] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint553machine # [ 0.426129] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]554sshClient # [ 0.432078] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint555machine # [ 0.426156] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]556sshClient # [ 0.432273] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]557sshClient # [ 0.432299] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]558machine # [ 0.426566] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint559machine # [ 0.426752] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]560machine # [ 0.427040] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint561machine # [ 0.427232] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]562machine # [ 0.427258] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]563machine # [ 0.427755] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint564machine # [ 0.427945] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]565machine # [ 0.427971] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]566machine # [ 0.428484] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint567machine # [ 0.428684] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]568machine # [ 0.428711] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]569machine # [ 0.429199] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint570machine # [ 0.429487] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]571machine # [ 0.429499] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]572machine # [ 0.429535] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]573sshClient # [ 0.452417] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint574machine # [ 0.430025] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint575sshClient # [ 0.452606] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]576machine # [ 0.430212] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]577sshClient # [ 0.452893] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint578machine # [ 0.430224] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]579sshClient # [ 0.453084] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]580machine # [ 0.430249] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]581sshClient # [ 0.453110] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]582machine # [ 0.430858] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned583sshClient # [ 0.453583] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint584machine # [ 0.430865] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned585sshClient # [ 0.453774] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]586machine # [ 0.430868] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned587sshClient # [ 0.453799] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]588machine # [ 0.430916] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned589sshClient # [ 0.454272] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint590machine # [ 0.430959] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned591sshClient # [ 0.454462] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]592sshClient # [ 0.454487] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]593machine # [ 0.431002] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned594sshClient # [ 0.455038] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint595machine # [ 0.431046] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned596sshClient # [ 0.455310] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]597machine # [ 0.431089] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned598sshClient # [ 0.455322] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]599sshClient # [ 0.455347] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]600machine # [ 0.431133] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned601sshClient # [ 0.455825] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint602machine # [ 0.431177] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned603sshClient # [ 0.456011] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]604machine # [ 0.431222] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned605sshClient # [ 0.456022] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]606machine # [ 0.431265] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned607sshClient # [ 0.456047] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]608machine # [ 0.431367] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned609machine # [ 0.431410] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned610machine # [ 0.431431] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned611machine # [ 0.431449] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned612sshClient # [ 0.481965] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned613machine # [ 0.431467] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned614sshClient # [ 0.481980] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned615machine # [ 0.431485] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned616sshClient # [ 0.481984] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned617machine # [ 0.431503] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned618sshClient # [ 0.482034] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned619machine # [ 0.431521] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned620sshClient # [ 0.482081] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned621machine # [ 0.431542] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned622sshClient # [ 0.482127] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned623machine # [ 0.431565] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned624sshClient # [ 0.482174] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned625machine # [ 0.431584] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned626machine # [ 0.431602] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned627sshClient # [ 0.482218] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned628machine # [ 0.431621] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned629sshClient # [ 0.482264] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned630machine # [ 0.431639] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned631sshClient # [ 0.482311] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned632machine # [ 0.431659] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned633sshClient # [ 0.482356] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned634machine # [ 0.431677] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned635sshClient # [ 0.482401] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned636machine # [ 0.431694] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned637machine # [ 0.431712] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned638sshClient # [ 0.482484] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned639machine # [ 0.431729] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned640sshClient # [ 0.482596] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned641machine # [ 0.431750] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]642sshClient # [ 0.482615] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned643machine # [ 0.431756] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]644sshClient # [ 0.482632] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned645machine # [ 0.431758] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]646sshClient # [ 0.482649] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned647sshClient # [ 0.482672] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned648sshClient # [ 0.482689] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned649sshClient # [ 0.482708] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned650sshClient # [ 0.482727] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned651sshClient # [ 0.482749] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned652sshClient # [ 0.482767] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned653sshClient # [ 0.482785] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned654sshClient # [ 0.482803] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned655sshClient # [ 0.482820] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned656sshClient # [ 0.482837] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned657sshClient # [ 0.482854] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned658sshClient # [ 0.482871] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned659sshClient # [ 0.482888] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned660sshClient # [ 0.482905] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned661sshClient # [ 0.482927] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]662machine # [ 0.524792] pci 0000:00:07.0: enabling device (0000 -> 0002)663sshClient # [ 0.482932] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]664sshClient # [ 0.482935] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]665sshClient # [ 0.483810] pci 0000:00:07.0: enabling device (0000 -> 0002)666machine # [ 0.555706] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)667sshClient # [ 0.543398] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)668machine # [ 0.559156] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)669sshClient # [ 0.546863] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)670machine # [ 0.569769] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)671machine # [ 0.571849] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)672sshClient # [ 0.557039] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)673sshClient # [ 0.559118] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)674machine # [ 0.576001] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)675sshClient # [ 0.563077] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)676machine # [ 0.586056] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)677machine # [ 0.587869] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)678sshClient # [ 0.573233] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)679sshClient # [ 0.575073] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)680machine # [ 0.591641] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)681sshClient # [ 0.578626] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)682machine # [ 0.594397] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)683sshClient # [ 0.588657] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)684sshClient # [ 0.590838] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)685machine # [ 0.604497] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)686machine # [ 0.606943] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)687sshClient # [ 0.594746] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)688sshClient # [ 0.605607] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled689sshClient # [ 0.607579] msm_serial: driver initialized690machine # [ 0.621109] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled691sshClient # [ 0.607711] SuperH (H)SCI(F) driver initialized692machine # [ 0.623086] msm_serial: driver initialized693sshClient # [ 0.607766] STM32 USART driver initialized694machine # [ 0.623238] SuperH (H)SCI(F) driver initialized695machine # [ 0.623301] STM32 USART driver initialized696sshClient # [ 0.646296] loop: module loaded697sshClient # [ 0.646514] virtio_blk virtio2: 1/0/0 default/read/poll queues698machine # [ 0.663708] loop: module loaded699sshClient # [ 0.647626] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)700machine # [ 0.663905] virtio_blk virtio2: 1/0/0 default/read/poll queues701machine # [ 0.666172] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)702sshClient # [ 0.653075] megasas: 07.734.00.00-rc1703sshClient # [ 0.653795] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]704machine # [ 0.670130] megasas: 07.734.00.00-rc1705machine # [ 0.670993] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]706sshClient # [ 0.669589] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000707sshClient # [ 0.669615] Intel/Sharp Extended Query Table at 0x0031708sshClient # [ 0.671269] Using buffer write method709sshClient # [ 0.671314] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]710machine # [ 0.693480] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000711machine # [ 0.693548] Intel/Sharp Extended Query Table at 0x0031712machine # [ 0.695341] Using buffer write method713machine # [ 0.695392] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]714sshClient # [ 0.685173] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000715sshClient # [ 0.685199] Intel/Sharp Extended Query Table at 0x0031716sshClient # [ 0.687059] Using buffer write method717machine # [ 0.700949] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000718sshClient # [ 0.687082] Concatenating MTD devices:719sshClient # [ 0.687085] (0): "0.flash"720machine # [ 0.700967] Intel/Sharp Extended Query Table at 0x0031721sshClient # [ 0.687088] (1): "0.flash"722machine # [ 0.703197] Using buffer write method723sshClient # [ 0.687090] into device "0.flash"724machine # [ 0.703222] Concatenating MTD devices:725machine # [ 0.703224] (0): "0.flash"726machine # [ 0.703227] (1): "0.flash"727machine # [ 0.703229] into device "0.flash"728sshClient # [ 0.988123] Freeing initrd memory: 26424K729sshClient # [ 0.994768] tun: Universal TUN/TAP device driver, 1.6730sshClient # [ 0.998813] thunder_xcv, ver 1.0731sshClient # [ 0.998843] thunder_bgx, ver 1.0732sshClient # [ 0.998863] nicpf, ver 1.0733sshClient # [ 0.999444] e1000: Intel(R) PRO/1000 Network Driver734sshClient # [ 0.999448] e1000: Copyright (c) 1999-2006 Intel Corporation.735sshClient # [ 0.999474] e1000e: Intel(R) PRO/1000 Network Driver736sshClient # [ 0.999480] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.737sshClient # [ 0.999504] igb: Intel(R) Gigabit Ethernet Network Driver738sshClient # [ 0.999508] igb: Copyright (c) 2007-2014 Intel Corporation.739sshClient # [ 0.999528] igbvf: Intel(R) Gigabit Virtual Function Network Driver740sshClient # [ 0.999531] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.741sshClient # [ 0.999673] sky2: driver version 1.30742sshClient # [ 1.009207] usbcore: registered new interface driver usb-storage743sshClient # [ 1.009297] usbcore: registered new interface driver usbserial_generic744sshClient # [ 1.009307] usbserial: USB Serial support registered for generic745sshClient # [ 1.009940] hv_vmbus: registering driver hyperv_keyboard746sshClient # [ 1.011379] ehci-pci 0000:00:07.0: EHCI Host Controller747sshClient # [ 1.011401] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1748sshClient # [ 1.011621] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000749sshClient # [ 1.011920] rtc-pl031 9010000.pl031: registered as rtc0750sshClient # [ 1.011945] rtc-pl031 9010000.pl031: setting system clock to 2026-09-30T03:39:15 UTC (1790739555)751sshClient # [ 1.012298] i2c_dev: i2c /dev entries driver752sshClient # [ 1.020389] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00753sshClient # [ 1.020765] hub 1-0:1.0: USB hub found754sshClient # [ 1.020780] hub 1-0:1.0: 6 ports detected755sshClient # [ 1.026370] sdhci: Secure Digital Host Controller Interface driver756sshClient # [ 1.026377] sdhci: Copyright(c) Pierre Ossman757sshClient # [ 1.026686] Synopsys Designware Multimedia Card Interface Driver758sshClient # [ 1.027095] sdhci-pltfm: SDHCI platform and OF driver helper759sshClient # [ 1.031694] hid: raw HID events driver (C) Jiri Kosina760sshClient # [ 1.031931] usbcore: registered new interface driver usbhid761sshClient # [ 1.031940] usbhid: USB HID core driver762sshClient # [ 1.035094] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available763sshClient # [ 1.037964] drop_monitor: Initializing network drop monitor service764sshClient # [ 1.038161] NET: Registered PF_INET6 protocol family765sshClient # [ 1.038797] Segment Routing with IPv6766sshClient # [ 1.038814] In-situ OAM (IOAM) with IPv6767sshClient # [ 1.038842] NET: Registered PF_PACKET protocol family768machine # [ 1.055959] Freeing initrd memory: 26440K769sshClient # [ 1.038902] 9pnet: Installing 9P2000 support770sshClient # [ 1.038941] Key type dns_resolver registered771machine # [ 1.063494] tun: Universal TUN/TAP device driver, 1.6772sshClient # [ 1.049662] registered taskstats version 1773sshClient # [ 1.049827] Loading compiled-in X.509 certificates774machine # [ 1.068018] thunder_xcv, ver 1.0775machine # [ 1.068056] thunder_bgx, ver 1.0776machine # [ 1.068082] nicpf, ver 1.0777machine # [ 1.070295] e1000: Intel(R) PRO/1000 Network Driver778machine # [ 1.070302] e1000: Copyright (c) 1999-2006 Intel Corporation.779machine # [ 1.070332] e1000e: Intel(R) PRO/1000 Network Driver780sshClient # [ 1.058886] Demotion targets for Node 0: null781machine # [ 1.070342] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.782sshClient # [ 1.059015] Key type .fscrypt registered783machine # [ 1.070376] igb: Intel(R) Gigabit Ethernet Network Driver784sshClient # [ 1.059023] Key type fscrypt-provisioning registered785sshClient # [ 1.059132] ima: No TPM chip found, activating TPM-bypass!786machine # [ 1.070380] igb: Copyright (c) 2007-2014 Intel Corporation.787sshClient # [ 1.059149] ima: Allocated hash algorithm: sha1788machine # [ 1.070407] igbvf: Intel(R) Gigabit Virtual Function Network Driver789sshClient # [ 1.059170] ima: No architecture policies found790machine # [ 1.070411] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.791sshClient # [ 1.059904] input: gpio-keys as /devices/platform/gpio-keys/input/input0792machine # [ 1.070585] sky2: driver version 1.30793machine # [ 1.072946] ehci-pci 0000:00:07.0: EHCI Host Controller794machine # [ 1.072993] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1795machine # [ 1.073218] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000796machine # [ 1.083097] usbcore: registered new interface driver usb-storage797machine # [ 1.083245] usbcore: registered new interface driver usbserial_generic798machine # [ 1.083260] usbserial: USB Serial support registered for generic799machine # [ 1.084055] hv_vmbus: registering driver hyperv_keyboard800machine # [ 1.087587] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00801machine # [ 1.087984] hub 1-0:1.0: USB hub found802machine # [ 1.088006] hub 1-0:1.0: 6 ports detected803machine # [ 1.091327] rtc-pl031 9010000.pl031: registered as rtc0804machine # [ 1.091355] rtc-pl031 9010000.pl031: setting system clock to 2026-09-30T03:39:15 UTC (1790739555)805machine # [ 1.091762] i2c_dev: i2c /dev entries driver806sshClient # [ 1.083009] clk: Disabling unused clocks807sshClient # [ 1.083028] PM: genpd: Disabling unused power domains808machine # [ 1.098448] sdhci: Secure Digital Host Controller Interface driver809machine # [ 1.098455] sdhci: Copyright(c) Pierre Ossman810machine # [ 1.098762] Synopsys Designware Multimedia Card Interface Driver811machine # [ 1.099178] sdhci-pltfm: SDHCI platform and OF driver helper812sshClient # [ 1.087329] Freeing unused kernel memory: 4736K813sshClient # [ 1.087548] Run /init as init process814machine # [ 1.103859] hid: raw HID events driver (C) Jiri Kosina815machine # [ 1.104120] usbcore: registered new interface driver usbhid816machine # [ 1.104129] usbhid: USB HID core driver817machine # [ 1.107343] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available818machine # [ 1.110762] drop_monitor: Initializing network drop monitor service819machine # [ 1.110976] NET: Registered PF_INET6 protocol family820machine # [ 1.111645] Segment Routing with IPv6821machine # [ 1.111659] In-situ OAM (IOAM) with IPv6822machine # [ 1.111690] NET: Registered PF_PACKET protocol family823sshClient # [ 1.099033] systemd[1]: Successfully made /usr/ read-only.824machine # [ 1.111767] 9pnet: Installing 9P2000 support825machine # [ 1.111815] Key type dns_resolver registered826sshClient # [ 1.099475] systemd[1]: systemd 263-889bc48 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)827sshClient # [ 1.099489] systemd[1]: Detected virtualization qemu.828sshClient # [ 1.099528] systemd[1]: Detected architecture arm64.829sshClient # [ 1.099533] systemd[1]: Running in initrd.830sshClient # [ 1.099815] systemd[1]: Initializing machine ID from random generator.831sshClient # [ 1.099913] systemd[1]: Hostname set to <sshClient>.832machine # [ 1.124063] registered taskstats version 1833machine # [ 1.124839] Loading compiled-in X.509 certificates834machine # [ 1.134098] Demotion targets for Node 0: null835machine # [ 1.134230] Key type .fscrypt registered836machine # [ 1.134238] Key type fscrypt-provisioning registered837machine # [ 1.134362] ima: No TPM chip found, activating TPM-bypass!838machine # [ 1.134381] ima: Allocated hash algorithm: sha1839machine # [ 1.134408] ima: No architecture policies found840machine # [ 1.135254] input: gpio-keys as /devices/platform/gpio-keys/input/input0841machine # [ 1.159029] clk: Disabling unused clocks842machine # [ 1.159047] PM: genpd: Disabling unused power domains843machine # [ 1.163716] Freeing unused kernel memory: 4736K844machine # [ 1.163937] Run /init as init process845machine # [ 1.183232] systemd[1]: Successfully made /usr/ read-only.846machine # [ 1.183772] systemd[1]: systemd 263-889bc48 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)847machine # [ 1.183787] systemd[1]: Detected virtualization qemu.848machine # [ 1.183829] systemd[1]: Detected architecture arm64.849machine # [ 1.183835] systemd[1]: Running in initrd.850machine # [ 1.184158] systemd[1]: Initializing machine ID from random generator.851machine # [ 1.192271] systemd[1]: Hostname set to <machine>.852sshClient # [ 1.207212] systemd[1]: bpf-restrict-fs: LSM BPF program attached853sshClient # [ 1.263761] systemd[1]: Queued start job for default target Initrd Default Target.854sshClient # [ 1.267662] systemd[1]: Created slice Slice /system/modprobe.855sshClient # [ 1.267839] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.856sshClient # [ 1.267865] systemd[1]: Expecting device /dev/disk/by-label/nixos...857sshClient # [ 1.267884] systemd[1]: Reached target Path Units.858sshClient # [ 1.267895] systemd[1]: Reached target Slice Units.859sshClient # [ 1.267908] systemd[1]: Reached target Swaps.860sshClient # [ 1.267920] systemd[1]: Reached target Timer Units.861sshClient # [ 1.268045] systemd[1]: Listening on D-Bus System Message Bus Socket.862sshClient # [ 1.268164] systemd[1]: Listening on Journal Socket (/dev/log).863sshClient # [ 1.268321] systemd[1]: Listening on Journal Sockets.864sshClient # [ 1.268455] usb 1-1: new high-speed USB device number 2 using ehci-pci865sshClient # [ 1.277308] systemd[1]: Listening on udev Kernel Socket.866sshClient # [ 1.277341] systemd[1]: Reached target Socket Units.867sshClient # [ 1.279074] systemd[1]: Starting Create List of Static Device Nodes...868sshClient # [ 1.279136] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs869machine # [ 1.299548] systemd[1]: bpf-restrict-fs: LSM BPF program attached870sshClient # [ 1.291561] systemd[1]: Mounting Kernel Configuration File System...871sshClient # [ 1.312595] systemd[1]: Starting Journal Service...872sshClient # [ 1.323655] systemd[1]: Starting Load Kernel Modules...873sshClient # [ 1.323779] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os874machine # [ 1.340459] usb 1-1: new high-speed USB device number 2 using ehci-pci875sshClient # [ 1.332614] systemd[1]: Starting Coldplug All udev Devices...876machine # [ 1.360526] systemd[1]: Queued start job for default target Initrd Default Target.877machine # [ 1.368064] systemd[1]: Created slice Slice /system/modprobe.878sshClient # [ 1.348576] systemd[1]: Finished Create List of Static Device Nodes.879sshClient # [ 1.349498] systemd[1]: Mounted Kernel Configuration File System.880machine # [ 1.369136] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.881machine # [ 1.369171] systemd[1]: Expecting device /dev/disk/by-label/nixos...882machine # [ 1.369196] systemd[1]: Reached target Path Units.883machine # [ 1.369210] systemd[1]: Reached target Slice Units.884sshClient # [ 1.358602] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...885machine # [ 1.369225] systemd[1]: Reached target Swaps.886machine # [ 1.369239] systemd[1]: Reached target Timer Units.887machine # [ 1.369433] systemd[1]: Listening on D-Bus System Message Bus Socket.888machine # [ 1.369617] systemd[1]: Listening on Journal Socket (/dev/log).889machine # [ 1.369775] systemd[1]: Listening on Journal Sockets.890machine # [ 1.369878] systemd[1]: Listening on udev Kernel Socket.891machine # [ 1.369894] systemd[1]: Reached target Socket Units.892machine # [ 1.371791] systemd[1]: Starting Create List of Static Device Nodes...893machine # [ 1.371869] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs894machine # [ 1.392562] systemd[1]: Mounting Kernel Configuration File System...895machine # [ 1.401689] systemd[1]: Starting Journal Service...896sshClient # [ 1.401165] systemd-journald[71]: Collecting audit messages is disabled.897sshClient # [ 1.411139] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.898machine # [ 1.433026] systemd[1]: Starting Load Kernel Modules...899machine # [ 1.433215] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os900sshClient # [ 1.423587] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.901sshClient # [ 1.427249] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev902sshClient # [ 1.430065] systemd[1]: Starting Create Static Device Nodes in /dev...903sshClient # [ 1.431326] 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/input1904machine # [ 1.460049] systemd-journald[71]: Collecting audit messages is disabled.905machine # [ 1.460587] systemd[1]: Starting Coldplug All udev Devices...906sshClient # [ 1.457713] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0907sshClient # [ 1.457972] [drm] features: -virgl +edid -resource_blob -host_visible908sshClient # [ 1.457975] [drm] features: -context_init909sshClient # [ 1.458780] [drm] number of scanouts: 1910sshClient # [ 1.458791] [drm] number of cap sets: 0911machine # [ 1.478515] systemd[1]: Finished Create List of Static Device Nodes.912sshClient # [ 1.473035] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic913sshClient # [ 1.473052] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0914machine # [ 1.496603] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...915machine # [ 1.497155] systemd[1]: Mounted Kernel Configuration File System.916sshClient # [ 1.497218] systemd[1]: Finished Create Static Device Nodes in /dev.917sshClient # [ 1.497451] systemd[1]: Reached target Preparation for Local File Systems.918sshClient # [ 1.497475] systemd[1]: Reached target Local File Systems.919sshClient # [ 1.503317] systemd[1]: Starting Rule-based Manager for Device Events and Files...920sshClient # [ 1.505828] Console: switching to colour frame buffer device 160x50921machine # [ 1.522098] 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/input1922sshClient # [ 1.524623] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0923sshClient # [ 1.525453] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device924sshClient # [ 1.540789] systemd[1]: Finished Load Kernel Modules.925sshClient # [ 1.542587] systemd[1]: Starting Apply Kernel Variables...926machine # [ 1.581735] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.927machine # [ 1.593223] systemd[1]: Starting Create Static Device Nodes in /dev...928machine # [ 1.595442] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.929sshClient # [ 1.596845] systemd[1]: Started Journal Service.930sshClient # [ 1.585098] systemd-modules-load[72]: Inserted module 'dm_mod'931sshClient # [ 1.586436] systemd-modules-load[72]: Module 'virtio_balloon' is built in932machine # [ 1.608578] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0933machine # [ 1.612449] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev934sshClient # [ 1.600463] systemd-modules-load[72]: Module 'virtio_console' is built in935sshClient # [ 1.601724] systemd-modules-load[72]: Inserted module 'virtio_gpu'936sshClient # [ 1.602870] systemd-modules-load[72]: Module 'virtio_rng' is built in937sshClient # [ 1.603891] systemd[1]: Starting Create System Files and Directories...938machine # [ 1.640273] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0939sshClient # [ 1.613274] systemd[1]: Finished Apply Kernel Variables.940machine # [ 1.640633] [drm] features: -virgl +edid -resource_blob -host_visible941machine # [ 1.640639] [drm] features: -context_init942machine # [ 1.641873] [drm] number of scanouts: 1943machine # [ 1.641890] [drm] number of cap sets: 0944machine # [ 1.644120] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic945machine # [ 1.644137] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0946sshClient # [ 1.622193] systemd-udevd[78]: Using default interface naming scheme 'v261'.947sshClient # [ 1.648492] usb 1-2: new high-speed USB device number 3 using ehci-pci948machine # [ 1.688966] Console: switching to colour frame buffer device 160x50949sshClient # [ 1.664263] systemd[1]: Finished Create System Files and Directories.950sshClient # [ 1.670462] systemd[1]: Started Rule-based Manager for Device Events and Files.951machine # [ 1.701725] systemd[1]: Started Journal Service.952machine # [ 1.693063] systemd-modules-load[73]: Inserted module 'dm_mod'953machine # [ 1.694747] systemd-modules-load[73]: Module 'virtio_balloon' is built in954machine # [ 1.715023] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device955machine # [ 1.706069] systemd-modules-load[73]: Module 'virtio_console' is built in956machine # [ 1.714124] systemd[1]: Finished Create Static Device Nodes in /dev.957machine # [ 1.728314] usb 1-2: new high-speed USB device number 3 using ehci-pci958machine # [ 1.721680] systemd[1]: Reached target Preparation for Local File Systems.959machine # [ 1.724666] systemd[1]: Reached target Local File Systems.960machine # [ 1.727392] systemd[1]: Starting Create System Files and Directories...961machine # [ 1.735215] systemd-modules-load[73]: Inserted module 'virtio_gpu'962machine # [ 1.736445] systemd-modules-load[73]: Module 'virtio_rng' is built in963machine # [ 1.740673] systemd[1]: Starting Rule-based Manager for Device Events and Files...964machine # [ 1.742830] systemd[1]: Finished Load Kernel Modules.965machine # [ 1.762476] systemd[1]: Starting Apply Kernel Variables...966sshClient # [ 1.764560] systemd[1]: Starting Virtual Console Setup...967machine # [ 1.805083] systemd[1]: Finished Create System Files and Directories.968sshClient # [ 1.818462] 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/input2969machine # [ 1.821951] systemd[1]: Finished Apply Kernel Variables.970sshClient # [ 1.818747] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0971machine # [ 1.832751] systemd-udevd[79]: Using default interface naming scheme 'v261'.972machine # [ 1.862573] systemd[1]: Started Rule-based Manager for Device Events and Files.973sshClient # [ 1.850216] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.974machine # [ 1.887019] 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/input2975sshClient # [ 1.860410] systemd[1]: Finished Virtual Console Setup.976machine # [ 1.887307] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0977machine # [ 1.968138] systemd[1]: Starting Virtual Console Setup...978machine # [ 2.056577] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.979machine # [ 2.064209] systemd[1]: Finished Virtual Console Setup.980sshClient # [ 2.525439] systemd[1]: Finished Coldplug All udev Devices.981sshClient # [ 2.526483] systemd[1]: Reached target System Initialization.982sshClient # [ 2.527351] systemd[1]: Reached target Basic System.983sshClient # [ 2.678087] (udev-worker)[94]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.984sshClient # [ 2.696228] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.985sshClient # [ 2.768838] systemd[1]: Found device /dev/disk/by-label/nixos.986sshClient # [ 2.772099] systemd[1]: Reached target Initrd Root Device.987sshClient # [ 2.776245] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...988machine # [ 2.791836] systemd[1]: Finished Coldplug All udev Devices.989machine # [ 2.792953] systemd[1]: Reached target System Initialization.990machine # [ 2.793990] systemd[1]: Reached target Basic System.991sshClient # [ 2.833800] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks992machine # [ 2.928069] (udev-worker)[96]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.993machine # [ 2.967210] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.994sshClient # [ 2.972335] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.995sshClient # [ 2.987265] systemd[1]: Mounting /sysroot...996machine # [ 3.041473] systemd[1]: Found device /dev/disk/by-label/nixos.997machine # [ 3.043739] systemd[1]: Reached target Initrd Root Device.998machine # [ 3.048222] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...999machine # [ 3.103306] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks1000machine # [ 3.175086] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1001sshClient # [ 3.175929] EXT4-fs (vda): mounted filesystem cc0b0364-0557-489e-8107-ab2509587be7 r/w with ordered data mode. Quota mode: none.1002sshClient # [ 3.165239] systemd[1]: Mounted /sysroot.1003sshClient # [ 3.167411] systemd[1]: Reached target Initrd Root File System.1004machine # [ 3.186833] systemd[1]: Mounting /sysroot...1005sshClient # [ 3.173669] systemd[1]: Starting Mountpoints Configured in the Real Root...1006sshClient # [ 3.207965] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.1007machine # [ 3.237895] EXT4-fs (vda): mounted filesystem bfbe21ec-e619-4204-98a0-d71cb902165d r/w with ordered data mode. Quota mode: none.1008machine # [ 3.227039] systemd[1]: Mounted /sysroot.1009sshClient # [ 3.213447] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...1010machine # [ 3.230015] systemd[1]: Reached target Initrd Root File System.1011sshClient # [ 3.218575] systemd[1]: Reloading...1012machine # [ 3.235739] systemd[1]: Starting Mountpoints Configured in the Real Root...1013machine # [ 3.269131] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.1014machine # [ 3.274890] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...1015machine # [ 3.280901] systemd[1]: Reloading...1016sshClient # [ 3.423593] systemd[1]: Reloading finished in 206 ms.1017sshClient # [ 3.459409] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...1018sshClient # [ 3.463925] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...1019sshClient # [ 3.471116] systemd[1]: Mounting /sysroot/nix/.ro-store...1020machine # [ 3.493709] systemd[1]: Reloading finished in 215 ms.1021sshClient # [ 3.491012] systemd[1]: Mounting /sysroot/nix/.rw-store...1022sshClient # [ 3.497585] systemd[1]: Mounting /sysroot/run...1023machine # [ 3.526400] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...1024sshClient # [ 3.514879] systemd[1]: Mounting /sysroot/tmp/shared...1025machine # [ 3.532183] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...1026machine # [ 3.539209] systemd[1]: Mounting /sysroot/nix/.ro-store...1027machine # [ 3.552186] systemd[1]: Mounting /sysroot/nix/.rw-store...1028sshClient # [ 3.546620] systemd[1]: Mounting /sysroot/tmp/xchg...1029sshClient # [ 3.549156] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1030machine # [ 3.567073] systemd[1]: Mounting /sysroot/run...1031machine # [ 3.584161] systemd[1]: Mounting /sysroot/tmp/shared...1032sshClient # [ 3.582162] systemd[1]: Finished Mountpoints Configured in the Real Root.1033sshClient # [ 3.583332] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1034sshClient # [ 3.612241] fuse: init (API version 7.45)1035machine # [ 3.614205] systemd[1]: Mounting /sysroot/tmp/xchg...1036sshClient # [ 3.623371] virtiofs virtio6: discovered new tag: nix-store1037sshClient # [ 3.624249] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1038sshClient # [ 3.614716] systemd[1]: Mounted /sysroot/nix/.rw-store.1039sshClient # [ 3.639366] virtiofs virtio7: discovered new tag: shared1040sshClient # [ 3.640179] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1041machine # [ 3.645622] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1042sshClient # [ 3.650744] virtiofs virtio8: discovered new tag: xchg1043sshClient # [ 3.651576] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1044machine # [ 3.656383] systemd[1]: Finished Mountpoints Configured in the Real Root.1045machine # [ 3.657865] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1046sshClient # [ 3.665317] systemd[1]: Starting rw-sysroot-nix-store.service...1047sshClient # [ 3.677985] systemd[1]: Mounted /sysroot/nix/.ro-store.1048sshClient # [ 3.681100] systemd[1]: Mounted /sysroot/run.1049machine # [ 3.699153] systemd[1]: Mounted /sysroot/nix/.rw-store.1050sshClient # [ 3.684260] systemd[1]: Mounted /sysroot/tmp/shared.1051sshClient # [ 3.702482] systemd[1]: Mounted /sysroot/tmp/xchg.1052machine # [ 3.721450] systemd[1]: Mounted /sysroot/run.1053machine # [ 3.742757] fuse: init (API version 7.45)1054sshClient # [ 3.724220] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1055machine # [ 3.753149] virtiofs virtio6: discovered new tag: nix-store1056machine # [ 3.754188] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1057sshClient # [ 3.726526] systemd[1]: Finished rw-sysroot-nix-store.service.1058sshClient # [ 3.728723] systemd[1]: Mounting /sysroot/nix/store...1059machine # [ 3.770182] virtiofs virtio7: discovered new tag: shared1060machine # [ 3.771094] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1061machine # [ 3.778542] virtiofs virtio8: discovered new tag: xchg1062machine # [ 3.779465] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1063machine # [ 3.778042] systemd[1]: Starting rw-sysroot-nix-store.service...1064machine # [ 3.800440] systemd[1]: Mounted /sysroot/tmp/shared.1065machine # [ 3.802239] systemd[1]: Mounted /sysroot/tmp/xchg.1066sshClient # [ 3.787904] systemd[1]: Mounted /sysroot/nix/store.1067sshClient # [ 3.790337] systemd[1]: Reached target Initrd File Systems.1068sshClient # [ 3.792172] systemd[1]: Starting Find NixOS closure...1069machine # [ 3.807624] systemd[1]: Mounted /sysroot/nix/.ro-store.1070machine # [ 3.813644] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1071sshClient # [ 3.800296] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1072machine # [ 3.817690] systemd[1]: Finished rw-sysroot-nix-store.service.1073sshClient # [ 3.846840] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1074sshClient # [ 3.862487] systemd[1]: Finished Find NixOS closure.1075sshClient # [ 3.866579] systemd[1]: Reached target Initrd Default Target.1076sshClient # [ 3.874612] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1077sshClient # [ 3.910121] systemd[1]: Stopped target Initrd Default Target.1078sshClient # [ 3.913957] systemd[1]: Stopped target Basic System.1079sshClient # [ 3.916793] systemd[1]: Stopped target Initrd Root Device.1080sshClient # [ 3.918508] systemd[1]: Stopped target Path Units.1081sshClient # [ 3.921098] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1082sshClient # [ 3.925415] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1083sshClient # [ 3.929605] systemd[1]: Stopped target Slice Units.1084sshClient # [ 3.932319] systemd[1]: Stopped target Socket Units.1085sshClient # [ 3.937815] systemd[1]: Stopped target System Initialization.1086sshClient # [ 3.939522] systemd[1]: Stopped target Swaps.1087sshClient # [ 3.944285] systemd[1]: Stopped target Timer Units.1088sshClient # [ 3.945107] systemd[1]: dbus.socket: Deactivated successfully.1089sshClient # [ 3.945996] systemd[1]: Closed D-Bus System Message Bus Socket.1090sshClient # [ 3.947027] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1091sshClient # [ 3.956919] systemd[1]: Stopped Find NixOS closure.1092sshClient # [ 3.958575] systemd[1]: Starting rw-sysroot-nix-store.service...1093sshClient # [ 3.962111] systemd[1]: systemd-sysctl.service: Deactivated successfully.1094sshClient # [ 3.965010] systemd[1]: Stopped Apply Kernel Variables.1095sshClient # [ 3.966988] systemd[1]: systemd-modules-load.service: Deactivated successfully.1096sshClient # [ 3.979658] systemd[1]: Stopped Load Kernel Modules.1097sshClient # [ 3.980735] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1098sshClient # [ 3.986231] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1099sshClient # [ 3.990969] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1100sshClient # [ 3.997189] systemd[1]: Stopped Create System Files and Directories.1101sshClient # [ 3.998202] systemd[1]: Stopped target Local File Systems.1102sshClient # [ 3.999108] systemd[1]: Stopped target Preparation for Local File Systems.1103sshClient # [ 4.002897] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1104sshClient # [ 4.003992] systemd[1]: Stopped Coldplug All udev Devices.1105sshClient # [ 4.004989] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1106sshClient # [ 4.006126] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1107sshClient # [ 4.007235] systemd[1]: Stopped Virtual Console Setup.1108sshClient # [ 4.019804] systemd[1]: initrd-cleanup.service: Deactivated successfully.1109sshClient # [ 4.021349] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1110sshClient # [ 4.022488] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1111sshClient # [ 4.023584] systemd[1]: Finished rw-sysroot-nix-store.service.1112sshClient # [ 4.024853] systemd[1]: systemd-udevd.service: Deactivated successfully.1113sshClient # [ 4.025898] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1114sshClient # [ 4.027153] systemd[1]: systemd-udevd.service: Consumed 1.243s CPU time over 2.524s wall clock time, 17.9M memory peak.1115sshClient # [ 4.034256] systemd[1]: Starting Cleanup udev Database...1116sshClient # [ 4.035493] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1117sshClient # [ 4.040588] systemd[1]: Stopped Create Static Device Nodes in /dev.1118sshClient # [ 4.041812] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1119sshClient # [ 4.043188] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1120sshClient # [ 4.048274] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1121sshClient # [ 4.049353] systemd[1]: Stopped Create List of Static Device Nodes.1122sshClient # [ 4.073823] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1123sshClient # [ 4.076506] systemd[1]: Finished Cleanup udev Database.1124sshClient # [ 4.078890] systemd[1]: Reached target Switch Root.1125sshClient # [ 4.080680] systemd[1]: Starting NixOS Activation...1126sshClient # [ 4.179899] initrd-nixos-activation-start[168]: booting system configuration /nix/store/zf77wrprw8y01r1lp74cyz60kjys14wb-nixos-system-sshClient-test1127sshClient # [ 4.222383] initrd-nixos-activation-start[168]: running activation script...1128sshClient # [ 4.487277] initrd-nixos-activation-start[191]: setting up /etc...1129machine # [ 4.535185] systemd[1]: Mounting /sysroot/nix/store...1130machine # [ 4.580227] systemd[1]: Mounted /sysroot/nix/store.1131machine # [ 4.582166] systemd[1]: Reached target Initrd File Systems.1132machine # [ 4.584188] systemd[1]: Starting Find NixOS closure...1133machine # [ 4.592918] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1134sshClient # [ 4.612382] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1135sshClient # [ 4.615452] systemd[1]: Finished NixOS Activation.1136sshClient # [ 4.616963] systemd[1]: Starting Switch Root...1137machine # [ 4.644132] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1138machine # [ 4.649027] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1139sshClient # [ 4.641330] systemd[1]: Switching root.1140machine # [ 4.670462] systemd[1]: Finished Find NixOS closure.1141machine # [ 4.672228] systemd[1]: Reached target Initrd Default Target.1142machine # [ 4.678355] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1143machine # [ 4.711104] systemd[1]: Stopped target Initrd Default Target.1144machine # [ 4.715644] systemd[1]: Stopped target Basic System.1145machine # [ 4.720235] systemd[1]: Stopped target Initrd Root Device.1146machine # [ 4.722937] systemd[1]: Stopped target Path Units.1147machine # [ 4.724642] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1148machine # [ 4.726748] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1149machine # [ 4.730569] systemd[1]: Stopped target Slice Units.1150machine # [ 4.733465] systemd[1]: Stopped target Socket Units.1151machine # [ 4.737480] systemd[1]: Stopped target System Initialization.1152machine # [ 4.740742] systemd[1]: Stopped target Swaps.1153machine # [ 4.743879] systemd[1]: Stopped target Timer Units.1154machine # [ 4.749365] systemd[1]: dbus.socket: Deactivated successfully.1155machine # [ 4.750350] systemd[1]: Closed D-Bus System Message Bus Socket.1156machine # [ 4.751282] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1157machine # [ 4.757123] systemd[1]: Stopped Find NixOS closure.1158machine # [ 4.760749] systemd[1]: Starting rw-sysroot-nix-store.service...1159machine # [ 4.764278] systemd[1]: systemd-sysctl.service: Deactivated successfully.1160machine # [ 4.768538] systemd[1]: Stopped Apply Kernel Variables.1161machine # [ 4.771065] systemd[1]: systemd-modules-load.service: Deactivated successfully.1162machine # [ 4.773866] systemd[1]: Stopped Load Kernel Modules.1163machine # [ 4.782104] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1164machine # [ 4.784917] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1165machine # [ 4.791196] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1166machine # [ 4.796602] systemd[1]: Stopped Create System Files and Directories.1167machine # [ 4.799743] systemd[1]: Stopped target Local File Systems.1168machine # [ 4.803237] systemd[1]: Stopped target Preparation for Local File Systems.1169machine # [ 4.805539] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1170machine # [ 4.808603] systemd[1]: Stopped Coldplug All udev Devices.1171machine # [ 4.812416] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1172machine # [ 4.813669] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1173machine # [ 4.815535] systemd[1]: Stopped Virtual Console Setup.1174machine # [ 4.816712] systemd[1]: initrd-cleanup.service: Deactivated successfully.1175machine # [ 4.817866] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1176machine # [ 4.818838] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1177machine # [ 4.819906] systemd[1]: Finished rw-sysroot-nix-store.service.1178machine # [ 4.820914] systemd[1]: systemd-udevd.service: Deactivated successfully.1179machine # [ 4.821884] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1180machine # [ 4.822913] systemd[1]: systemd-udevd.service: Consumed 1.752s CPU time over 3.073s wall clock time, 20.6M memory peak.1181machine # [ 4.828322] systemd[1]: Starting Cleanup udev Database...1182machine # [ 4.829308] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1183sshClient # [ 4.829944] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1184machine # [ 4.832084] systemd[1]: Stopped Create Static Device Nodes in /dev.1185machine # [ 4.836295] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1186machine # [ 4.837640] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1187machine # [ 4.840163] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1188machine # [ 4.844209] systemd[1]: Stopped Create List of Static Device Nodes.1189machine # [ 4.874875] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1190machine # [ 4.877224] systemd[1]: Finished Cleanup udev Database.1191machine # [ 4.880592] systemd[1]: Reached target Switch Root.1192machine # [ 4.882646] systemd[1]: Starting NixOS Activation...1193machine # [ 4.969651] initrd-nixos-activation-start[172]: booting system configuration /nix/store/w3m7w2a54aa7pd9vcms7g47ph9swqc0k-nixos-system-machine-test1194sshClient # [ 4.991488] systemd[1]: systemd 263-889bc48 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)1195sshClient # [ 4.999952] systemd[1]: Detected virtualization qemu.1196sshClient # [ 5.000009] systemd[1]: Detected architecture arm64.1197sshClient # [ 5.000120] systemd[1]: Detected first boot.1198machine # [ 5.004147] initrd-nixos-activation-start[172]: running activation script...1199sshClient # [ 5.004573] systemd[1]: Initializing machine ID from random generator.1200machine # [ 5.229659] initrd-nixos-activation-start[195]: setting up /etc...1201sshClient # [ 5.231501] systemd[1]: bpf-restrict-fs: LSM BPF program attached1202machine # [ 5.356186] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1203machine # [ 5.359398] systemd[1]: Finished NixOS Activation.1204machine # [ 5.360751] systemd[1]: Starting Switch Root...1205machine # [ 5.385632] systemd[1]: Switching root.1206sshClient # [ 5.422742] systemd[1]: Applying preset policy.1207machine # [ 5.571925] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1208sshClient # [ 5.671953] systemd[1]: Populated /etc with preset unit settings.1209machine # [ 5.746691] systemd[1]: systemd 263-889bc48 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)1210machine # [ 5.747625] systemd[1]: Detected virtualization qemu.1211machine # [ 5.757366] systemd[1]: Detected architecture arm64.1212machine # [ 5.757543] systemd[1]: Detected first boot.1213machine # [ 5.759410] systemd[1]: Initializing machine ID from random generator.1214sshClient # [ 5.891979] systemd[1]: initrd-switch-root.service: Deactivated successfully.1215sshClient # [ 5.894010] systemd[1]: Stopped initrd-switch-root.service.1216sshClient # [ 5.898140] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1217sshClient # [ 5.900110] systemd[1]: Created slice Slice /system/getty.1218sshClient # [ 5.904840] systemd[1]: Created slice User and Session Slice.1219sshClient # [ 5.905511] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1220sshClient # [ 5.906224] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1221sshClient # [ 5.906916] systemd[1]: Expecting device /dev/hvc0...1222sshClient # [ 5.907484] systemd[1]: Expecting device /dev/ttyAMA0...1223sshClient # [ 5.908098] systemd[1]: Reached target Local Encrypted Volumes.1224sshClient # [ 5.914396] systemd[1]: Stopped target initrd-fs.target.1225sshClient # [ 5.914891] systemd[1]: Stopped target initrd-root-fs.target.1226sshClient # [ 5.915638] systemd[1]: Stopped target initrd-switch-root.target.1227sshClient # [ 5.916285] systemd[1]: Reached target Virtual Machines and Containers.1228sshClient # [ 5.920623] systemd[1]: Reached target Path Units.1229sshClient # [ 5.921066] systemd[1]: Reached target Remote File Systems.1230sshClient # [ 5.921749] systemd[1]: Reached target Slice Units.1231sshClient # [ 5.922397] systemd[1]: Reached target Swaps.1232sshClient # [ 5.927526] systemd[1]: Listening on Query the User Interactively for a Password.1233sshClient # [ 5.931402] systemd[1]: Listening on Process Core Dump Socket.1234sshClient # [ 5.934094] systemd[1]: Listening on Credential Encryption/Decryption.1235sshClient # [ 5.935584] systemd[1]: Listening on Factory Reset Management.1236sshClient # [ 5.936157] systemd[1]: Listening on Hostname Service Socket.1237sshClient # [ 5.941946] systemd[1]: Starting Journal Log Access Socket...1238sshClient # [ 5.943750] systemd[1]: Listening on Journal Audit Socket.1239sshClient # [ 5.948154] systemd[1]: Listening on Console Output Muting Service Socket.1240sshClient # [ 5.950989] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1241sshClient # [ 5.953843] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1242sshClient # [ 5.955121] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1243sshClient # [ 5.961448] systemd[1]: Listening on Disk Repartitioning Service Socket.1244sshClient # [ 5.962096] systemd[1]: Listening on udev Varlink Socket.1245sshClient # [ 5.962965] systemd[1]: Listening on User Database Manager Socket.1246sshClient # [ 5.968787] systemd[1]: Mounting Huge Pages File System...1247sshClient # [ 5.972244] systemd[1]: Mounting POSIX Message Queue File System...1248sshClient # [ 5.986494] systemd[1]: Mounting Kernel Debug File System...1249sshClient # [ 5.996989] systemd[1]: Mounting Kernel Trace File System...1250sshClient # [ 6.013461] systemd[1]: Starting Create List of Static Device Nodes...1251sshClient # [ 6.014081] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1252sshClient # [ 6.032723] systemd[1]: Mounting Kernel Configuration File System...1253sshClient # [ 6.033601] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1254sshClient # [ 6.040039] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1255sshClient # [ 6.044861] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1256machine # [ 6.079980] systemd[1]: bpf-restrict-fs: LSM BPF program attached1257sshClient # [ 6.076633] systemd[1]: Mounting FUSE Control File System...1258sshClient # [ 6.077127] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671259sshClient # [ 6.116603] systemd[1]: Starting Journal Service...1260sshClient # [ 6.143831] systemd[1]: Starting Load Kernel Modules...1261sshClient # [ 6.175740] systemd-journald[263]: Collecting audit messages is enabled.1262sshClient # [ 6.183947] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1263sshClient # [ 6.167096] systemd[1]: Queued start job for default target Multi-User System.1264sshClient # [ 6.172664] systemd[1]: systemd-journald.service: Deactivated successfully.1265sshClient # [ 6.204030] systemd[1]: Starting Remount Root and Kernel File Systems...1266sshClient # [ 6.215251] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1267sshClient # [ 6.239834] systemd[1]: Starting Coldplug All udev Devices...1268sshClient # [ 6.263756] systemd[1]: Started Journal Service.1269machine # [ 6.276325] NET: Registered PF_VSOCK protocol family1270machine # [ 6.283694] Guest personality initialized and is inactive1271machine # [ 6.284055] VMCI host device registered (name=vmci, major=10, minor=261)1272machine # [ 6.287563] Initialized host personality1273sshClient # [ 6.260726] systemd-modules-load[264]: Module 'atkbd' is built in1274sshClient # [ 6.266011] systemd-modules-load[264]: Module 'loop' is built in1275sshClient # [ 6.275747] systemd[1]: Listening on Journal Log Access Socket.1276sshClient # [ 6.278711] systemd[1]: Mounted Huge Pages File System.1277sshClient # [ 6.279552] systemd[1]: Mounted POSIX Message Queue File System.1278sshClient # [ 6.288579] systemd[1]: Mounted Kernel Debug File System.1279sshClient # [ 6.289492] systemd[1]: Mounted Kernel Trace File System.1280sshClient # [ 6.290501] systemd[1]: Finished Create List of Static Device Nodes.1281sshClient # [ 6.291513] systemd[1]: Finished Load Kernel Modules.1282machine # [ 6.345140] systemd[1]: Applying preset policy.1283sshClient # [ 6.315605] systemd[1]: Mounted Kernel Configuration File System.[ 6.330760] EXT4-fs (vda): re-mounted cc0b0364-0557-489e-8107-ab2509587be7.1284sshClient # 1285sshClient # [ 6.337572] systemd[1]: Starting Firewall...1286sshClient # [ 6.348203] systemd[1]: Starting Apply Kernel Variables...1287sshClient # [ 6.362234] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1288sshClient # [ 6.363634] systemd[1]: Mounted FUSE Control File System.1289sshClient # [ 6.368251] systemd[1]: Finished Remount Root and Kernel File Systems.1290sshClient # [ 6.376534] systemd-oomd[266]: No swap; memory pressure usage will be degraded1291sshClient # [ 6.388203] systemd[1]: Listening on Disk Image Download Service Socket.1292sshClient # [ 6.418050] systemd[1]: Starting Flush Journal to Persistent Storage...1293sshClient # [ 6.420410] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1294sshClient # [ 6.433399] systemd[1]: Starting Load/Save OS Random Seed...1295sshClient # [ 6.444292] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1296sshClient # [ 6.448130] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1297sshClient # [ 6.550144] systemd-journald[263]: Received client request to flush runtime journal.1298machine # [ 6.577587] systemd[1]: Populated /etc with preset unit settings.1299sshClient # [ 6.605280] systemd[1]: Finished Apply Kernel Variables.1300sshClient # [ 6.606406] systemd[1]: Finished Load/Save OS Random Seed.1301sshClient # [ 6.614032] systemd[1]: Finished Flush Journal to Persistent Storage.1302sshClient # [ 6.652870] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1303sshClient # [ 6.665778] systemd[1]: Starting Create Static Device Nodes in /dev...1304machine # [ 6.806987] systemd[1]: initrd-switch-root.service: Deactivated successfully.1305machine # [ 6.807823] systemd[1]: Stopped initrd-switch-root.service.1306machine # [ 6.813398] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1307machine # [ 6.815361] systemd[1]: Created slice Slice /system/getty.1308machine # [ 6.819387] systemd[1]: Created slice User and Session Slice.1309machine # [ 6.819993] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1310machine # [ 6.823155] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1311machine # [ 6.823613] systemd[1]: Expecting device /dev/hvc0...1312machine # [ 6.826757] systemd[1]: Expecting device /dev/ttyAMA0...1313machine # [ 6.827245] systemd[1]: Reached target Local Encrypted Volumes.1314machine # [ 6.827911] systemd[1]: Stopped target initrd-fs.target.1315machine # [ 6.831623] systemd[1]: Stopped target initrd-root-fs.target.1316machine # [ 6.832180] systemd[1]: Stopped target initrd-switch-root.target.1317machine # [ 6.835371] systemd[1]: Reached target Virtual Machines and Containers.1318machine # [ 6.835958] systemd[1]: Reached target Path Units.1319machine # [ 6.839200] systemd[1]: Reached target Remote File Systems.1320machine # [ 6.839732] systemd[1]: Reached target Slice Units.1321machine # [ 6.842686] systemd[1]: Reached target Swaps.1322machine # [ 6.845875] systemd[1]: Listening on Query the User Interactively for a Password.1323machine # [ 6.850094] systemd[1]: Listening on Process Core Dump Socket.1324machine # [ 6.852111] systemd[1]: Listening on Credential Encryption/Decryption.1325machine # [ 6.855828] systemd[1]: Listening on Factory Reset Management.1326machine # [ 6.858370] systemd[1]: Listening on Hostname Service Socket.1327machine # [ 6.863728] systemd[1]: Starting Journal Log Access Socket...1328sshClient # [ 6.837048] systemd[1]: Finished Create Static Device Nodes in /dev.1329machine # [ 6.867663] systemd[1]: Listening on Journal Audit Socket.1330sshClient # [ 6.840349] systemd[1]: Reached target Preparation for Local File Systems.1331machine # [ 6.872912] systemd[1]: Listening on Console Output Muting Service Socket.1332machine # [ 6.875062] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1333sshClient # [ 6.846623] systemd[1]: Starting Rule-based Manager for Device Events and Files...1334machine # [ 6.879090] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1335machine # [ 6.879614] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1336machine # [ 6.889022] systemd[1]: Listening on Disk Repartitioning Service Socket.1337machine # [ 6.890123] systemd[1]: Listening on udev Varlink Socket.1338machine # [ 6.890971] systemd[1]: Listening on User Database Manager Socket.1339machine # [ 6.898491] systemd[1]: Mounting Huge Pages File System...1340machine # [ 6.906547] systemd[1]: Mounting POSIX Message Queue File System...1341sshClient # [ 6.890366] systemd[1]: Mounting /run/wrappers...1342machine # [ 6.924864] systemd[1]: Mounting Kernel Debug File System...1343machine # [ 6.948027] systemd[1]: Mounting Kernel Trace File System...1344machine # [ 6.971991] systemd[1]: Starting Create List of Static Device Nodes...1345machine # [ 6.978050] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1346sshClient # [ 6.961337] systemd[1]: Mounted /run/wrappers.1347sshClient # [ 6.962216] systemd[1]: Reached target Local File Systems.1348sshClient # [ 6.968141] systemd[1]: Listening on Boot Loader Control Service Socket.1349machine # [ 7.002689] systemd[1]: Mounting Kernel Configuration File System...1350sshClient # [ 6.975938] systemd[1]: Starting Create SUID/SGID Wrappers...1351sshClient # [ 6.980270] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1352machine # [ 7.008803] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1353sshClient # [ 6.988595] systemd[1]: Starting Create System Files and Directories...1354machine # [ 7.016741] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1355machine # [ 7.017625] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1356machine # [ 7.050529] systemd[1]: Mounting FUSE Control File System...1357machine # [ 7.051055] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671358machine # [ 7.111793] systemd[1]: Starting Journal Service...1359machine # [ 7.138723] systemd[1]: Starting Load Kernel Modules...1360machine # [ 7.168559] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1361machine # [ 7.187931] systemd[1]: Starting Remount Root and Kernel File Systems...1362machine # [ 7.191111] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1363sshClient # [ 7.186933] systemd[1]: Finished Create System Files and Directories.1364sshClient # [ 7.202773] systemd[1]: Starting Rebuild Journal Catalog...1365machine # [ 7.237306] systemd-journald[268]: Collecting audit messages is enabled.1366sshClient # [ 7.210472] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1367sshClient # [ 7.211908] systemd-udevd[304]: Using default interface naming scheme 'v261'.1368machine # [ 7.255859] systemd[1]: Starting Coldplug All udev Devices...1369machine # [ 7.243243] systemd[1]: Queued start job for default target Multi-User System.1370machine # [ 7.275154] systemd[1]: Started Journal Service.1371sshClient # [ 7.279688] systemd[1]: Starting User Database Manager...1372machine # [ 7.352396] EXT4-fs (vda): re-mounted bfbe21ec-e619-4204-98a0-d71cb902165d.1373sshClient # [ 7.366478] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1374sshClient # [ 7.409800] systemd[1]: Finished Rebuild Journal Catalog.1375sshClient # [ 7.428355] systemd[1]: Starting Update is Completed...1376sshClient # [ 7.492481] systemd[1]: Started User Database Manager.1377sshClient # [ 7.502599] systemd[1]: Finished Update is Completed.1378sshClient # [ 7.649435] systemd[1]: Started Rule-based Manager for Device Events and Files.1379machine # [ 7.245652] systemd[1]: systemd-journald.service: Deactivated successfully.1380machine # [ 7.885958] systemd-journald[268]: Received client request to flush runtime journal.1381machine # [ 8.091458] systemd-modules-load[269]: Module 'atkbd' is built in1382machine # [ 8.101402] systemd-modules-load[269]: Module 'loop' is built in1383machine # [ 8.102586] systemd[1]: Listening on Journal Log Access Socket.1384machine # [ 8.103667] systemd[1]: Mounted Huge Pages File System.1385machine # [ 8.123144] systemd[1]: Mounted POSIX Message Queue File System.1386machine # [ 8.128185] systemd[1]: Mounted Kernel Debug File System.1387machine # [ 8.129474] systemd[1]: Mounted Kernel Trace File System.1388machine # [ 8.130444] systemd[1]: Finished Create List of Static Device Nodes.1389machine # [ 8.131494] systemd[1]: Mounted Kernel Configuration File System.1390sshClient # [ 8.133066] systemd[1]: Finished Coldplug All udev Devices.1391machine # [ 8.154278] systemd[1]: Finished Load Kernel Modules.1392machine # [ 8.155283] systemd[1]: Starting Firewall...1393machine # [ 8.162381] systemd[1]: Starting Apply Kernel Variables...1394machine # [ 8.163553] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1395machine # [ 8.181103] systemd[1]: Finished Remount Root and Kernel File Systems.1396machine # [ 8.182460] systemd[1]: Listening on Disk Image Download Service Socket.1397machine # [ 8.183623] systemd[1]: Starting Flush Journal to Persistent Storage...1398machine # [ 8.196590] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1399machine # [ 8.198605] systemd[1]: Starting Load/Save OS Random Seed...1400machine # [ 8.199583] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1401machine # [ 8.215129] systemd-oomd[271]: No swap; memory pressure usage will be degraded1402sshClient # [ 8.203102] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1403sshClient # [ 8.207055] systemd[1]: Finished Create SUID/SGID Wrappers.1404sshClient # [ 8.210423] systemd[1]: Reached target System Initialization.1405sshClient # [ 8.211425] systemd[1]: Started Discard unused filesystem blocks once a week.1406machine # [ 8.228676] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1407machine # [ 8.229892] systemd[1]: Mounted FUSE Control File System.1408sshClient # [ 8.217425] systemd[1]: Started Daily Cleanup of Temporary Directories.1409sshClient # [ 8.218561] systemd[1]: Reached target Timer Units.1410sshClient # [ 8.219323] systemd[1]: Listening on D-Bus System Message Bus Socket.1411sshClient # [ 8.226369] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1412sshClient # [ 8.227648] systemd[1]: Reached target Socket Units.1413machine # [ 8.245841] systemd[1]: Finished Apply Kernel Variables.1414machine # [ 8.246879] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1415sshClient # [ 8.234998] systemd[1]: Reached target Basic System.1416sshClient # [ 8.235838] systemd[1]: Starting Import lastlog data into lastlog2 database...1417sshClient # [ 8.245639] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1418machine # [ 8.247949] systemd[1]: Starting Create Static Device Nodes in /dev...1419sshClient # [ 8.252143] systemd[1]: Starting Post-Boot Actions...1420machine # [ 8.267881] systemd[1]: Finished Create Static Device Nodes in /dev.1421machine # [ 8.276394] systemd[1]: Reached target Preparation for Local File Systems.1422machine # [ 8.277591] systemd[1]: Starting Rule-based Manager for Device Events and Files...1423machine # [ 8.278708] systemd[1]: Mounting /run/wrappers...1424machine # [ 8.279450] systemd[1]: Mounted /run/wrappers.1425sshClient # [ 8.270553] systemd[1]: Started Reset console on configuration changes.1426machine # [ 8.293075] systemd[1]: Reached target Local File Systems.1427machine # [ 8.294056] systemd[1]: Listening on Boot Loader Control Service Socket.1428machine # [ 8.295078] systemd[1]: Starting Create SUID/SGID Wrappers...1429machine # [ 8.312548] systemd-udevd[294]: Using default interface naming scheme 'v261'.1430machine # [ 8.313848] systemd[1]: Starting User Database Manager...1431machine # [ 8.314717] systemd[1]: Finished Load/Save OS Random Seed.1432machine # [ 8.315546] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1433sshClient # [ 8.318139] systemd[1]: Starting resolvconf update...1434machine # [ 8.337664] systemd[1]: Finished Flush Journal to Persistent Storage.1435machine # [ 8.338674] systemd[1]: Starting Create System Files and Directories...1436machine # [ 8.339629] systemd[1]: Started User Database Manager.1437sshClient # [ 8.347963] systemd[1]: Starting Home Area Manager...1438sshClient # [ 8.380165] nsncd[436]: Sep 30 03:39:22.880 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1439sshClient # [ 8.396292] systemd[1]: Started Name Service Cache Daemon (nsncd).1440sshClient # [ 8.405144] systemd[1]: Reached target Host and Network Name Lookups.1441sshClient # [ 8.412764] systemd[1]: Reached target User and Group Name Lookups.1442machine # [ 8.428479] systemd[1]: Finished Create System Files and Directories.1443machine # [ 8.444204] systemd[1]: Starting Rebuild Journal Catalog...1444machine # [ 8.453565] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1445sshClient # [ 8.446880] systemd[1]: Starting D-Bus System Message Bus...1446machine # [ 8.476380] systemd[1]: Started Rule-based Manager for Device Events and Files.1447sshClient # [ 8.502038] systemd[1]: Starting User Login Management...1448sshClient # [ 8.513347] systemd[1]: Finished Post-Boot Actions.1449sshClient # [ 8.580444] systemd-homed[445]: Watching /home.1450sshClient # [ 8.609665] systemd[1]: Finished Import lastlog data into lastlog2 database.1451machine # [ 8.637885] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1452machine # [ 8.668962] systemd[1]: Finished Rebuild Journal Catalog.1453machine # [ 8.684227] systemd[1]: Starting Update is Completed...1454sshClient # [ 8.751219] systemd-logind[463]: New seat seat0.1455sshClient # [ 8.759746] systemd[1]: Started User Login Management.1456sshClient # [ 8.762230] dbus-broker-launch[459]: Looking up NSS user entry for 'systemd-timesync'...1457sshClient # [ 8.772429] systemd[1]: Starting linger-users.service...1458sshClient # [ 8.777566] dbus-broker-launch[459]: NSS returned no entry for 'systemd-timesync'1459machine # [ 8.793057] systemd[1]: Finished Update is Completed.1460sshClient # [ 8.778809] dbus-broker-launch[459]: Invalid user-name in /nix/store/k6v9qys37ib5r1kk2svgxps95191fxbz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1461sshClient # [ 8.814734] systemd[1]: Started D-Bus System Message Bus.1462sshClient # [ 8.859371] systemd[1]: Stopped target Host and Network Name Lookups.1463sshClient # [ 8.865755] systemd[1]: Stopping Host and Network Name Lookups...1464sshClient # [ 8.866909] systemd[1]: Stopped target User and Group Name Lookups.1465sshClient # [ 8.867841] systemd[1]: Stopping User and Group Name Lookups...1466sshClient # [ 8.886145] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1467sshClient # [ 8.887187] systemd[1]: nscd.service: Deactivated successfully.1468sshClient # [ 8.899157] dbus-broker-launch[459]: Ready1469sshClient # [ 8.899934] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1470machine # [ 8.916763] systemd[1]: Starting Virtual Console Setup...1471sshClient # [ 8.907674] systemd[1]: linger-users.service: Deactivated successfully.1472sshClient # [ 8.912699] systemd[1]: Finished linger-users.service.1473sshClient # [ 8.942802] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1474sshClient # [ 8.948925] systemd[1]: Started Home Area Manager.1475sshClient # [ 8.968473] systemd[1]: Finished Home Area Activation.1476sshClient # [ 8.979468] systemd[1]: Starting First Boot Home Area Wizard...1477sshClient # [ 9.065435] systemd[1]: Finished resolvconf update.1478sshClient # [ 9.070585] nsncd[540]: Sep 30 03:39:23.574 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1479sshClient # [ 9.081299] systemd[1]: Starting DHCP Client...1480sshClient # [ 9.084814] systemd[1]: Started Name Service Cache Daemon (nsncd).1481sshClient # [ 9.095204] systemd[1]: Reached target Host and Network Name Lookups.1482sshClient # [ 9.098850] systemd[1]: Reached target User and Group Name Lookups.1483sshClient # [ 9.120831] systemd[1]: Finished First Boot Home Area Wizard.1484sshClient # [ 9.132233] systemd[1]: Reached target First Boot Complete.1485sshClient # [ 9.139553] systemd[1]: Starting Save Transient machine-id to Disk...1486machine # [ 9.229881] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1487machine # [ 9.236164] systemd[1]: Finished Create SUID/SGID Wrappers.1488sshClient # [ 9.277073] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31489sshClient # [ 9.300626] systemd[1]: Finished Firewall.1490sshClient # [ 9.307289] systemd[1]: Reached target Preparation for Network.1491sshClient # [ 9.311909] systemd[1]: Starting Extra networking commands....1492sshClient # [ 9.379697] dhcpcd[578]: dhcpcd-10.3.2 starting1493sshClient # [ 9.397157] dhcpcd[587]: dev: loaded udev1494sshClient # [ 9.401796] dhcpcd[587]: no valid interfaces found1495sshClient # [ 9.403352] dhcpcd[587]: no valid interfaces found1496sshClient # [ 9.408104] dhcpcd[587]: libudev: received NULL device1497sshClient # [ 9.409084] dhcpcd[587]: libudev: received NULL device1498machine # [ 9.515120] systemd[1]: Finished Coldplug All udev Devices.1499machine # [ 9.584723] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1500sshClient # [ 9.614660] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1501sshClient # [ 9.623379] systemd[1]: Finished Save Transient machine-id to Disk.1502sshClient # [ 9.714525] systemd[1]: Finished Extra networking commands..1503sshClient # [ 9.719195] systemd[1]: Reached target Network.1504sshClient # [ 9.723490] systemd[1]: Starting Permit User Sessions...1505machine # [ 9.792524] systemd-vconsole-setup[423]: Configuration of first virtual console was skipped, ignoring remaining ones.1506sshClient # [ 9.786871] systemd[1]: Finished Permit User Sessions.1507machine # [ 9.803329] systemd[1]: Finished Virtual Console Setup.1508machine # [ 9.805444] systemd[1]: Reached target System Initialization.1509machine # [ 9.806370] systemd[1]: Started Discard unused filesystem blocks once a week.1510machine # [ 9.807457] systemd[1]: Started Daily Cleanup of Temporary Directories.1511sshClient # [ 9.799562] systemd[1]: Started Getty on tty1.1512machine # [ 9.814896] systemd[1]: Reached target Timer Units.1513machine # [ 9.815808] systemd[1]: Listening on D-Bus System Message Bus Socket.1514sshClient # [ 9.802718] systemd[1]: Reached target Login Prompts.1515machine # [ 9.823143] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).1516machine # [ 9.825939] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1517machine # [ 9.827239] systemd[1]: Reached target Socket Units.1518machine # [ 9.834171] systemd[1]: Reached target Basic System.1519machine # [ 9.835641] systemd[1]: Starting Import lastlog data into lastlog2 database...1520sshClient # [ 9.825477] systemd[1]: Starting Virtual Console Setup...1521machine # [ 9.843182] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1522machine # [ 9.851063] systemd[1]: Starting Post-Boot Actions...1523sshClient # [ 9.856786] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1524machine # [ 9.874049] systemd[1]: Started Reset console on configuration changes.1525sshClient # [ 9.860209] systemd[1]: Stopped Virtual Console Setup.1526sshClient # [ 9.867534] systemd[1]: Starting Virtual Console Setup...1527machine # [ 9.899042] systemd[1]: Starting resolvconf update...1528sshClient # [ 9.888425] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1529machine # [ 9.992352] systemd[1]: Starting SSH Host Keys Generation...1530machine # [ 10.032163] systemd[1]: Starting Home Area Manager...1531machine # [ 10.066707] systemd[1]: Finished Firewall.1532machine # [ 10.068591] systemd[1]: Started Name Service Cache Daemon (nsncd).1533machine # [ 10.070082] nsncd[485]: Sep 30 03:39:24.489 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1534sshClient # [ 10.061690] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1535sshClient # [ 10.074658] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1536sshClient # [ 10.081837] systemd[1]: Started backdoor.service.1537machine # [ 10.103531] systemd[1]: Finished Post-Boot Actions.1538sshClient # connecting to host...1539machine # [ 10.191559] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1540machine # [ 10.201191] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1541sshClient # [ 10.193239] (udev-worker)[388]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1542machine # [ 10.210168] systemd[1]: Reached target Host and Network Name Lookups.1543sshClient # [ 10.202207] (udev-worker)[388]: Network interface NamePolicy= disabled on kernel command line.1544machine # [ 10.218389] systemd[1]: Reached target User and Group Name Lookups.1545machine # [ 10.228691] systemd[1]: Started backdoor.service.1546sshClient # [ 10.244935] 8021q: 802.1Q VLAN Support v1.81547machine # [ 10.249826] systemd[1]: Starting D-Bus System Message Bus...1548machine # [ 10.261645] systemd-homed[497]: Watching /home.1549machine # [ 10.281806] systemd[1]: Starting User Login Management...1550machine # [ 10.303029] systemd[1]: Finished Import lastlog data into lastlog2 database.1551sshClient # [ 10.320235] systemd[1]: Condition check resulted in Virtio network device being skipped.1552sshClient # [ 10.334742] systemd[1]: Starting Address configuration of eth1...1553sshClient # [ 10.389873] cfg80211: Loading compiled-in X.509 certificates for regulatory database1554sshClient # [ 10.423814] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1555sshClient # [ 10.424345] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1556sshClient # [ 10.429103] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21557sshClient # [ 10.429457] cfg80211: failed to load regulatory.db1558sshClient # [ 10.466930] 8021q: adding VLAN 0 to HW filter on device eth11559sshClient # [ 10.475005] network-addresses-eth1-start[656]: adding address 192.168.1.2/24... done1560sshClient # [ 10.491153] network-addresses-eth1-start[656]: adding address 2001:db8:1::2/64... done1561machine # connecting to host...1562sshClient # [ 10.529228] systemd[1]: Finished Address configuration of eth1.1563sshClient # [ 10.550558] 8021q: adding VLAN 0 to HW filter on device eth01564sshClient # [ 10.538141] dhcpcd[587]: eth0: waiting for carrier1565sshClient # [ 10.541438] dhcpcd[587]: eth0: carrier acquired1566sshClient # [ 10.554466] dhcpcd[587]: DUID 00:01:00:01:32:4f:40:ed:52:54:00:12:34:561567sshClient # [ 10.555542] dhcpcd[587]: eth0: IAID 00:12:34:561568machine # [ 10.570795] systemd[1]: Stopped target Host and Network Name Lookups.1569sshClient # [ 10.558583] dhcpcd[587]: eth0: adding address fe80::5054:ff:fe12:34561570machine # [ 10.580458] systemd[1]: Stopping Host and Network Name Lookups...1571sshClient # [ 10.565081] systemd-vconsole-setup[642]: Configuration of first virtual console was skipped, ignoring remaining ones.1572machine # [ 10.581788] systemd[1]: Stopped target User and Group Name Lookups.1573machine # [ 10.582767] systemd[1]: Stopping User and Group Name Lookups...1574machine # [ 10.583696] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1575machine: Guest shell says: b'Spawning backdoor root shell...\n'1576sshClient # [ 10.572744] systemd[1]: Finished Virtual Console Setup.1577machine # [ 10.605281] systemd[1]: nscd.service: Deactivated successfully.1578machine # [ 10.606225] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1579machine: connected to guest root shell1580machine: (connecting took 10.98 seconds)1581machine: (finished: waiting for the VM to finish booting, in 10.98 seconds)1582machine # [ 10.645556] (udev-worker)[386]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1583sshClient # [ 10.632815] dhcpcd[587]: eth0: soliciting a DHCP lease1584sshClient # [ 10.640860] dhcpcd[587]: eth0: offered 10.0.2.15 from 10.0.2.21585sshClient # [ 10.648350] dhcpcd[587]: eth0: probing address 10.0.2.15/241586machine # [ 10.672817] systemd-logind[525]: New seat seat0.1587machine # [ 10.682747] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1588machine # [ 10.694473] dbus-broker-launch[521]: Looking up NSS user entry for 'systemd-timesync'...1589machine # [ 10.696003] systemd[1]: Started User Login Management.1590machine # [ 10.706991] dbus-broker-launch[521]: NSS returned no entry for 'systemd-timesync'1591machine # [ 10.716731] dbus-broker-launch[521]: Invalid user-name in /nix/store/cz5bxfy0cscnwcfsqdp63q0saf0bayj2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1592machine # [ 10.719283] systemd[1]: Starting linger-users.service...1593machine # [ 10.738805] (udev-worker)[386]: Network interface NamePolicy= disabled on kernel command line.1594machine # [ 10.782937] systemd[1]: Started D-Bus System Message Bus.1595sshClient # [ 10.783477] systemd-logind[463]: Watching system buttons on /dev/input/event0 (gpio-keys)1596machine # [ 10.857605] dbus-broker-launch[521]: Ready1597sshClient # [ 10.901414] mousedev: PS/2 mouse device common for all mice1598machine # [ 10.909477] systemd[1]: Started Name Service Cache Daemon (nsncd).1599machine # [ 10.910971] nsncd[580]: Sep 30 03:39:25.330 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1600machine # [ 10.933541] systemd[1]: Started Home Area Manager.1601machine # [ 10.934457] systemd[1]: Reached target Host and Network Name Lookups.1602machine # [ 10.935468] systemd[1]: Reached target User and Group Name Lookups.1603machine # [ 10.950079] systemd[1]: Finished Home Area Activation.1604machine # [ 10.951100] systemd[1]: Starting First Boot Home Area Wizard...1605machine # [ 10.956710] systemd[1]: linger-users.service: Deactivated successfully.1606machine # [ 10.957920] systemd[1]: Finished linger-users.service.1607sshClient # [ 10.959772] systemd-logind[463]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1608machine # [ 11.020514] systemd[1]: Finished resolvconf update.1609machine # [ 11.021508] systemd[1]: Reached target Preparation for Network.1610machine # [ 11.044167] systemd[1]: Starting DHCP Client...1611machine # [ 11.052205] systemd[1]: Starting Extra networking commands....1612machine # [ 11.101519] systemd[1]: Condition check resulted in Virtio network device being skipped.1613machine # [ 11.140908] systemd[1]: Starting Address configuration of eth1...1614machine # [ 11.207099] systemd[1]: Created slice Slice /system/systemd-mute-console.1615machine # [ 11.224795] systemd[1]: Started Console Output Muting Service (PID 607/UID 0).1616machine # [ 11.450104] network-addresses-eth1-start[614]: adding address 192.168.1.1/24... done1617machine # [ 11.516123] network-addresses-eth1-start[614]: adding address 2001:db8:1::1/64... done1618machine # [ 11.596809] systemd[1]: Finished Address configuration of eth1.1619machine # [ 11.643567] dhcpcd[621]: dhcpcd-10.3.2 starting1620machine # [ 11.659575] dhcpcd[680]: dev: loaded udev1621machine # [ 11.772737] systemd-logind[525]: Watching system buttons on /dev/input/event0 (gpio-keys)1622machine # [ 11.779775] systemd[1]: Finished Extra networking commands..1623machine # [ 11.784744] systemd[1]: Reached target Network.1624sshClient # [ 12.018545] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1625sshClient # [ 12.023123] systemd[1]: Stopped Virtual Console Setup.1626sshClient # [ 12.025266] systemd[1]: Stopping Virtual Console Setup...1627sshClient # [ 12.028429] systemd[1]: Starting Virtual Console Setup...1628sshClient # [ 12.061575] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1629sshClient # [ 12.083560] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1630sshClient # [ 12.090506] systemd[1]: Stopped Virtual Console Setup.1631machine: (finished: waiting for unit systemd-homed.service, in 12.47 seconds)1632machine: waiting for Please enter user name to create to appear on tty 11633sshClient # [ 12.095954] systemd[1]: Starting Virtual Console Setup...1634sshClient # [ 12.139419] systemd-logind[463]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1635sshClient # [ 12.190799] dhcpcd[587]: eth0: soliciting an IPv6 router1636sshClient # [ 12.191815] dhcpcd[587]: eth0: Router Advertisement from fe80::21637sshClient # [ 12.193140] dhcpcd[587]: eth0: adding address fec0::5054:ff:fe12:3456/641638sshClient # [ 12.194153] dhcpcd[587]: eth0: adding route to fec0::/641639sshClient # [ 12.194978] dhcpcd[587]: eth0: adding default route via fe80::21640sshClient # [ 12.229187] systemd-vconsole-setup[696]: Configuration of first virtual console was skipped, ignoring remaining ones.1641sshClient # [ 12.233481] systemd[1]: Finished Virtual Console Setup.1642machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.18 seconds)1643machine: sending keys 'test-homed-user\n'1644machine # [ 12.333385] systemd-logind[525]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1645machine # [ 12.436230] dhcpcd[680]: eth0: waiting for carrier1646machine # [ 12.437128] dhcpcd[680]: libudev: received NULL device1647machine # [ 12.438092] dhcpcd[680]: libudev: received NULL device1648machine # [ 12.439059] dhcpcd[680]: eth0: carrier acquired1649machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds)1650machine: waiting for Please enter new password for user test-homed-user: to appear on tty 11651machine # [ 12.472102] dhcpcd[680]: DUID 00:01:00:01:32:4f:40:ee:52:54:00:12:34:561652machine # [ 12.473306] dhcpcd[680]: eth0: IAID 00:12:34:561653machine # [ 12.474105] dhcpcd[680]: eth0: adding address fe80::5054:ff:fe12:34561654machine # [ 13.049736] dhcpcd[680]: eth0: soliciting a DHCP lease1655machine # [ 13.059189] dhcpcd[680]: eth0: offered 10.0.2.15 from 10.0.2.21656machine # [ 13.064337] dhcpcd[680]: eth0: probing address 10.0.2.15/241657machine # [ 13.115917] sshd-keygen-start[538]: Generating public/private rsa key pair.1658machine # [ 13.117298] sshd-keygen-start[538]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key1659machine # [ 13.118581] sshd-keygen-start[538]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub1660machine # [ 13.119853] sshd-keygen-start[538]: The key fingerprint is:1661machine # [ 13.123741] sshd-keygen-start[538]: SHA256:RdjykSgJwwazvBmDADl/9qGfGmVtM6x7LFAWYrjxfWc root@machine1662machine # [ 13.126385] sshd-keygen-start[538]: The key's randomart image is:1663machine # [ 13.127300] sshd-keygen-start[538]: +---[RSA 4096]----+1664machine # [ 13.130718] sshd-keygen-start[538]: |+.o+o. . +.. |1665machine # [ 13.131564] sshd-keygen-start[538]: |+ooo=.+ +.+ |1666machine # [ 13.134888] sshd-keygen-start[538]: |.o=* o o o.. |1667machine # [ 13.135701] sshd-keygen-start[538]: | o=+ =o..E |1668machine # [ 13.138853] sshd-keygen-start[538]: | oo =ooSo |1669machine # [ 13.139666] sshd-keygen-start[538]: | oo.o o |1670machine # [ 13.142807] sshd-keygen-start[538]: | .o.o |1671machine # [ 13.143626] sshd-keygen-start[538]: | .+.o |1672machine # [ 13.146123] sshd-keygen-start[538]: | ...o |1673machine # [ 13.146924] sshd-keygen-start[538]: +----[SHA256]-----+1674machine # [ 13.206438] sshd-keygen-start[727]: Generating public/private ed25519 key pair.1675machine # [ 13.207596] sshd-keygen-start[727]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key1676machine # [ 13.211837] sshd-keygen-start[727]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub1677machine # [ 13.215581] sshd-keygen-start[727]: The key fingerprint is:1678machine # [ 13.219106] sshd-keygen-start[727]: SHA256:g9Po2p3zzbXbm2WWcJPAArMbfksrlExoYFLxIKPHa/s root@machine1679machine # [ 13.222546] sshd-keygen-start[727]: The key's randomart image is:1680machine # [ 13.223491] sshd-keygen-start[727]: +--[ED25519 256]--+1681machine # [ 13.226990] sshd-keygen-start[727]: | +.*. o |1682machine # [ 13.227826] sshd-keygen-start[727]: | o = + . + . |1683machine # [ 13.232813] sshd-keygen-start[727]: | . o + + . o |1684machine # [ 13.233808] sshd-keygen-start[727]: | . . .++ + . . .|1685machine # [ 13.234569] sshd-keygen-start[727]: | o + S* o . + |1686machine # [ 13.235324] sshd-keygen-start[727]: | . .. ...o o o o|1687machine # [ 13.243158] sshd-keygen-start[727]: | . . . o ..+|1688machine # [ 13.249807] sshd-keygen-start[727]: | .o ....o . =o|1689machine # [ 13.250707] sshd-keygen-start[727]: | .E. oo. o o+o|1690machine # [ 13.251471] sshd-keygen-start[727]: +----[SHA256]-----+1691machine # [ 13.255763] systemd[1]: sshd-keygen.service: Deactivated successfully.1692machine # [ 13.258394] systemd[1]: Finished SSH Host Keys Generation.1693machine # [ 13.259273] systemd[1]: Starting SSH Daemon...1694machine # [ 13.327938] sshd[729]: Server listening on 0.0.0.0 port 22.1695machine # [ 13.331050] sshd[729]: Server listening on :: port 22.1696machine # [ 13.331916] systemd[1]: Started SSH Daemon.1697machine # [ 13.664721] dhcpcd[680]: eth0: soliciting an IPv6 router1698machine # [ 13.668128] dhcpcd[680]: eth0: Router Advertisement from fe80::21699machine # [ 13.669156] dhcpcd[680]: eth0: adding address fec0::5054:ff:fe12:3456/641700machine # [ 13.670209] dhcpcd[680]: eth0: adding route to fec0::/641701machine # [ 13.671029] dhcpcd[680]: eth0: adding default route via fe80::21702machine: (finished: waiting for Please enter new password for user test-homed-user: to appear on tty 1, in 1.30 seconds)1703machine: sending keys 'foobarfoo\n'1704machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1705machine: waiting for (repeat) to appear on tty 11706machine: (finished: waiting for (repeat) to appear on tty 1, in 0.12 seconds)1707machine: sending keys 'foobarfoo\n'1708machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)1709machine: waiting for unit systemd-homed-firstboot.service1710machine # [ 14.397030] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1711machine # [ 14.404875] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1712machine # [ 14.409695] systemd[1]: Stopped Virtual Console Setup.1713machine # [ 14.412378] systemd[1]: Stopping Virtual Console Setup...1714machine # [ 14.416820] systemd[1]: Starting Virtual Console Setup...1715machine # [ 14.500907] systemd-logind[525]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1716machine # [ 14.578389] systemd-vconsole-setup[763]: Configuration of first virtual console was skipped, ignoring remaining ones.1717machine # [ 14.581908] systemd[1]: Finished Virtual Console Setup.1718sshClient # [ 15.604587] dhcpcd[587]: eth0: leased 10.0.2.15 for 86400 seconds1719sshClient # [ 15.606994] dhcpcd[587]: eth0: adding route to 10.0.2.0/241720sshClient # [ 15.609740] dhcpcd[587]: eth0: adding default route via 10.0.2.21721sshClient # [ 15.724982] systemd[1]: Started DHCP Client.1722sshClient # [ 15.728775] systemd[1]: Reached target Multi-User System.1723sshClient # [ 15.730743] systemd[1]: Startup finished in 1.076s (kernel) + 3.813s (initrd) + 10.840s (userspace) = 15.730s.1724machine # [ 17.559501] dhcpcd[680]: eth0: leased 10.0.2.15 for 86400 seconds1725machine # [ 17.561436] dhcpcd[680]: eth0: adding route to 10.0.2.0/241726machine # [ 17.562872] dhcpcd[680]: eth0: adding default route via 10.0.2.21727machine # [ 17.698124] systemd[1]: Started DHCP Client.1728machine # [ 24.376190] systemd-homed[497]: Generating key pair for signing local user identity records.1729machine # [ 24.376485] systemd-homed[497]: Successfully created Ed25519 key pair.1730machine # [ 24.390804] systemd-homed[497]: test-homed-user: changing state absent → creating1731machine # [ 24.494461] systemd-homework[862]: Using automatic default storage of 'luks'.1732machine # [ 24.502257] systemd-homework[862]: Sizing home to 83% of available disk space, which is 744M.1733machine # [ 24.504148] systemd-homework[862]: Allocating image file completed.1734machine # [ 24.523799] systemd-homework[862]: Writing of partition table completed.1735machine # [ 24.528629] systemd-homework[862]: Setting up loopback device /dev/loop0 completed.1736machine # [ 25.864174] systemd-homework[862]: LUKS formatting completed.1737machine # [ 28.120430] systemd-homework[862]: Writing password to LUKS keyslot 0 completed.1738machine # [ 28.340732] systemd-homework[862]: LUKS activation by volume key succeeded.1739machine # [ 28.360669] systemd-homework[862]: Writing user record as LUKS token completed.1740machine # [ 28.361895] systemd-homework[862]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1741machine # [ 28.363199] systemd-homework[862]: Formatting /dev/mapper/home-test-homed-user as btrfs1742machine # [ 29.181487] systemd[1]: proc-898-mounts.mount: Deactivated successfully.1743machine # [ 29.183452] systemd-homework[862]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid 30172656-be20-43bb-a0a2-e378254e9fe5)1744machine # [ 29.189798] systemd-homework[862]: Formatting file system completed.1745machine # [ 29.257247] systemd-homework[862]: Mounting file system completed.1746machine # [ 29.260788] systemd-homework[862]: Skeleton directory /etc/skel missing, ignoring.1747machine # [ 29.261997] systemd-homework[862]: Wrote embedded .identity file.1748machine # [ 29.262874] systemd-homework[862]: Replaced embedded blob dir with contents of system blob dir.1749machine # [ 29.265550] systemd-homework[862]: Recursive changing of ownership completed.1750machine # [ 29.267086] systemd-homework[862]: Changed top-level directory access mode to 0700.1751machine # [ 29.274615] systemd-homework[862]: Synchronized disk.1752machine # [ 29.275500] systemd-homework[862]: Discarded unused 652.8M.1753machine # [ 29.288299] systemd-homed[497]: block device /sys/devices/virtual/block/dm-0 has been removed.1754machine # [ 29.325465] systemd-homed[497]: block device /sys/devices/virtual/block/dm-0 has been removed.1755machine # [ 29.341735] systemd-homed[497]: block device /sys/devices/virtual/block/loop0 has been removed.1756machine # [ 29.358298] systemd-homework[862]: Moved image file into place.1757machine # [ 29.363999] systemd-homework[862]: Creation completed.1758machine # [ 29.365255] systemd-homework[862]: Image size is 744M, file system size is 726M, file system payload size is 726M, file system free is 719.9M.1759machine # [ 29.375617] systemd-homed[497]: test-homed-user: changing state creating → inactive1760machine # [ 29.384774] systemd[1]: Finished First Boot Home Area Wizard.1761machine # [ 29.388907] systemd[1]: systemd-mute-console@0-1-607_608-0.service: Deactivated successfully.1762machine # [ 29.395422] systemd[1]: Reached target First Boot Complete.1763machine # [ 29.400502] systemd[1]: Starting Save Transient machine-id to Disk...1764machine # [ 29.412677] systemd[1]: Starting Permit User Sessions...1765machine # [ 29.451850] systemd[1]: Finished Permit User Sessions.1766machine # [ 29.458517] systemd[1]: Started Getty on tty1.1767machine # [ 29.461874] systemd[1]: Reached target Login Prompts.1768machine # [ 29.462783] systemd[1]: Reached target Multi-User System.1769machine # [ 29.466544] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1770machine # [ 29.473757] systemd[1]: Finished Save Transient machine-id to Disk.1771machine # [ 29.477200] systemd[1]: Startup finished in 1.152s (kernel) + 4.490s (initrd) + 23.832s (userspace) = 29.475s.1772machine: (finished: waiting for unit systemd-homed-firstboot.service, in 16.18 seconds)1773(finished: subtest: create systemd-homed user on first boot prompt, in 30.63 seconds)1774machine: must succeed: homectl update test-homed-user --offline -G wheel1775machine # [ 30.312519] systemd-homed[497]: test-homed-user: changing state inactive → updating1776machine # [ 30.321529] systemd-homework[945]: Offline update requested. Not touching embedded records.1777machine # [ 30.325516] systemd-homed[497]: Home test-homed-user is signed exclusively by our key, accepting.1778machine # [ 30.330644] systemd-homed[497]: test-homed-user: changing state updating → inactive1779machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.06 seconds)1780subtest: login as homed user1781machine: waiting for login: to appear on tty 11782machine: (finished: waiting for login: to appear on tty 1, in 0.04 seconds)1783machine: sending keys 'test-homed-user\n'1784machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds)1785machine: waiting for Password: to appear on tty 11786machine # [ 30.719384] systemd-homed[497]: test-homed-user: changing state inactive → activating-for-acquire1787machine # [ 30.730583] systemd-homework[960]: None of the supplied plaintext passwords unlock the user record's hashed passwords.1788machine # [ 30.739755] systemd-homed[497]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.1789machine # [ 30.744518] systemd-homed[497]: test-homed-user: changing state activating-for-acquire → inactive1790machine # [ 30.747460] systemd-homed[497]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.1791machine # [ 30.750342] systemd-homed[497]: Home test-homed-user already deactivated, no automatic deactivation needed.1792machine: (finished: waiting for Password: to appear on tty 1, in 1.11 seconds)1793machine: sending keys 'foobarfoo\n'1794machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)1795machine: waiting for success: pgrep -u test-homed-user -t tty1 bash1796machine # [ 31.835614] systemd-homed[497]: test-homed-user: changing state inactive → activating-for-acquire1797machine # [ 31.883825] systemd-homework[972]: Provided password unlocks user record.1798machine # [ 31.890427] systemd-homework[972]: Successfully locked image file '/home/test-homed-user.home'.1799machine # [ 31.897440] systemd-homework[972]: Allocated additional 652.8M.1800machine # [ 31.903057] systemd-homework[972]: Setting up loopback device /dev/loop1 completed.[ 31.917728] loop1: detected capacity change from 0 to 15196161801machine # 1802machine # [ 32.824848] systemd-homework[972]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1803machine # [ 32.873023] systemd-homework[972]: Provided password unlocks user record.1804machine # [ 32.881043] systemd-homework[972]: Probing file system completed (found btrfs).1805machine # [ 32.884155] systemd-homework[972]: No fsck for file system btrfs installed, ignoring.1806machine # [ 32.903645] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (972)1807machine # [ 32.907817] BTRFS info (device dm-0): first mount of filesystem 30172656-be20-43bb-a0a2-e378254e9fe51808machine # [ 32.907869] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm1809machine # [ 32.906671] systemd-homework[972]: Mounting file system completed.[ 32.922418] BTRFS info (device dm-0): enabling free space tree1810machine # [ 32.922449] BTRFS info (device dm-0): use zstd compression, level 11811machine # 1812machine # [ 32.913767] systemd-homework[972]: Discovered used loopback device /dev/loop1.1813machine # [ 32.917898] systemd-homework[972]: offset = 1048576, size = 778043392, image = 7801405441814machine # [ 32.925647] systemd-homework[972]: Ready to resize image size 744M → 897M, partition size 742M → 895M, file system size 726M → 879M.1815machine # [ 32.927580] systemd-homework[972]: Growing of image file from 744M to 897M completed.1816machine # [ 32.952336] loop1: detected capacity change from 1519616 to 18329601817machine # [ 32.941180] systemd-homework[972]: Refreshing loop device size completed.1818machine # [ 32.952835] systemd-homework[972]: Growing of partition completed.1819machine # [ 32.996686] async_tx: api initialized (async)1820machine # [ 33.036653] systemd-homework[972]: LUKS device growing completed.1821machine # [ 33.060142] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 761266176 to 9216983041822machine # [ 33.048438] systemd-homework[972]: File system resizing from 726M to 879M completed.1823machine # [ 33.052258] systemd-homework[972]: Synchronized disk.1824machine # [ 33.053123] systemd-homework[972]: Synchronized disk.1825machine # [ 33.053892] systemd-homework[972]: Resizing completed.1826machine # [ 33.054660] systemd-homework[972]: Image size is 897M, file system size is 879M, file system payload size is 879M, file system free is 872.9M.1827machine # [ 33.060225] systemd-homework[972]: Read embedded .identity file.1828machine # [ 33.081589] systemd-homework[972]: Provided password unlocks user record.1829machine # [ 33.082729] systemd-homework[972]: Reconciling header user identity completed (host version was newer).1830machine # [ 33.085175] systemd-homework[972]: Reconciling embedded user identity completed (host version was newer).1831machine # [ 33.097825] systemd-homework[972]: Wrote LUKS header user record.1832machine # [ 33.099393] systemd-homework[972]: Wrote embedded .identity file.1833machine # [ 33.101183] systemd-homework[972]: Replaced embedded blob dir with contents of system blob dir.1834machine # [ 33.102455] systemd-homework[972]: Recursive changing of ownership not necessary, skipped.1835machine # [ 33.107707] systemd-homework[972]: Synchronized disk.1836machine # [ 33.111915] systemd-homework[972]: Moving to final mount point /home/test-homed-user completed.1837machine # [ 33.137412] systemd-homework[972]: Activation completed.1838machine # [ 33.140243] systemd-homework[972]: Image size is 895M, file system size is 879M, file system payload size is 879M, file system free is 872.9M.1839machine # [ 33.164598] systemd-homed[497]: Home test-homed-user is signed exclusively by our key, accepting.1840machine # [ 33.170841] systemd-homed[497]: test-homed-user: changing state activating-for-acquire → active1841machine # [ 33.174102] login[935]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired.1842machine # [ 33.179479] systemd-homed[497]: test-homed-user: changing state active → resizing-while-active1843machine # [ 33.198661] systemd-homework[1016]: Successfully acquired home volume key from kernel keyring.1844machine # [ 33.204156] systemd-homework[1016]: LUKS volume key from keyring unlocks user record.1845machine # [ 33.270030] login[935]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0)1846machine # [ 33.316307] systemd-homework[1016]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.1847machine # [ 33.364497] systemd[1]: Created slice Slice /user/60388.1848machine # [ 33.374686] systemd[1]: Starting User Runtime Directory /run/user/60388...1849machine # [ 33.400541] systemd-logind[525]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'.1850machine # [ 33.518196] systemd-homework[1016]: Successfully re-activated LUKS device.1851machine # [ 33.527136] systemd[1]: Finished User Runtime Directory /run/user/60388.1852machine # [ 33.531109] systemd-homework[1016]: LUKS volume key from keyring unlocks user record.1853machine # [ 33.533660] systemd-homework[1016]: Discovered used loopback device /dev/loop1.1854machine # [ 33.534879] systemd-homework[1016]: Read embedded .identity file.1855machine # [ 33.535869] systemd-homework[1016]: LUKS volume key from keyring unlocks user record.1856machine # [ 33.540339] systemd-homework[1016]: Reconciling header user identity completed (host version was newer).1857machine # [ 33.541894] systemd-homework[1016]: Reconciling embedded user identity completed (host version was newer).1858machine # [ 33.543493] systemd-homework[1016]: offset = 1048576, size = 938475520, image = 9405726721859machine # [ 33.564574] systemd[1]: Starting User Manager for UID 60388...1860machine # [ 33.579960] systemd-homework[1016]: Ready to resize image size 897M → 765M, partition size 895M → 763M, file system size 879M → 747M.1861machine # [ 33.583117] systemd-homework[1016]: Wrote embedded .identity file.1862machine # [ 33.584547] systemd-homework[1016]: Replaced embedded blob dir with contents of system blob dir.1863machine # [ 33.585983] systemd-homework[1016]: Backing file is fully allocated already.1864machine # [ 33.608165] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 921698304 to 7832862721865machine # [ 33.593734] systemd-homework[1016]: File system resizing from 879M to 747M completed.1866machine # [ 33.600494] systemd-homework[1016]: Synchronized disk.1867machine # [ 33.627913] (systemd)[1019]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.1868machine # [ 33.701812] (systemd)[1019]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)1869machine # [ 33.711169] systemd-logind[525]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'.1870machine # [ 33.722662] systemd-homework[1016]: LUKS device shrinking completed.1871machine # [ 33.748327] loop1: detected capacity change from 1832960 to 15626241872machine # [ 33.737968] systemd-homework[1016]: Refreshing loop device size completed.1873machine # [ 33.739190] systemd-homework[1016]: Shrinking of image file completed.1874machine # [ 33.762499] systemd-homework[1016]: Shrinking of partition completed.1875machine # [ 33.774424] systemd[1019]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1876machine # [ 33.812577] systemd-homework[1016]: Wrote LUKS header user record.1877machine # [ 33.813733] systemd-homework[1016]: Synchronized disk.1878machine # [ 33.814491] systemd-homework[1016]: Resizing completed.1879machine # [ 33.815258] systemd-homework[1016]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.1880machine # [ 33.822220] systemd-homed[497]: Home test-homed-user is signed exclusively by our key, accepting.1881machine # [ 33.823554] systemd-homed[497]: test-homed-user: changing state resizing-while-active → active1882machine # [ 33.828176] systemd-homed[497]: Rebalancing complete.1883machine # [ 33.923895] systemd[1019]: Queued start job for default target Main User Target.1884machine # [ 33.933363] systemd[1019]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1885machine # [ 33.941764] systemd[1019]: Created slice User Application Slice.1886machine # [ 33.942754] systemd[1019]: Started Daily Cleanup of User's Temporary Directories.1887machine # [ 33.943895] systemd[1019]: Reached target Paths.1888machine # [ 33.952704] systemd[1019]: Reached target Timers.1889machine # [ 33.953628] systemd[1019]: Starting D-Bus User Message Bus Socket...1890machine # [ 33.954596] systemd[1019]: Starting Create User Files and Directories...1891machine # [ 34.017457] systemd[1019]: Finished Create User Files and Directories.1892machine # [ 34.034143] systemd[1019]: Listening on D-Bus User Message Bus Socket.1893machine # [ 34.035237] systemd[1019]: Reached target Sockets.1894machine # [ 34.035962] systemd[1019]: Reached target Basic System.1895machine # [ 34.041940] systemd[1]: Started User Manager for UID 60388.1896machine # [ 34.042914] systemd[1019]: Starting Run user-specific NixOS activation...1897machine # [ 34.050014] systemd[1]: Started Session 1 of User test-homed-user.1898machine # [ 34.093829] systemd[1019]: Finished Run user-specific NixOS activation.1899machine # [ 34.096803] systemd[1019]: Reached target Main User Target.1900machine # [ 34.097739] systemd[1019]: Startup finished in 344ms.1901machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 3.33 seconds)1902machine: sending keys 'whoami > /tmp/2\n'1903machine: (finished: sending keys 'whoami > /tmp/2\n', in 0.17 seconds)1904machine: waiting for file '/tmp/2'1905machine: (finished: waiting for file '/tmp/2', in 1.06 seconds)1906machine: must succeed: cat /tmp/21907machine: (finished: must succeed: cat /tmp/2, in 0.03 seconds)1908(finished: subtest: login as homed user, in 6.00 seconds)1909subtest: login as regular user1910sshClient: waiting for login: to appear on tty 11911sshClient: waiting for the VM to finish booting1912sshClient: Guest shell says: b'Spawning backdoor root shell...\n'1913sshClient: connected to guest root shell1914sshClient: (connecting took 0.00 seconds)1915sshClient: (finished: waiting for the VM to finish booting, in 0.00 seconds)1916sshClient: (finished: waiting for login: to appear on tty 1, in 0.05 seconds)1917sshClient: sending keys 'test-normal-user\n'1918sshClient: (finished: sending keys 'test-normal-user\n', in 0.18 seconds)1919sshClient: waiting for Password: to appear on tty 11920sshClient: (finished: waiting for Password: to appear on tty 1, in 1.09 seconds)1921sshClient: sending keys 'foobarfoo\n'1922sshClient: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)1923sshClient: waiting for success: pgrep -u test-normal-user bash1924sshClient # [ 37.844652] login[640]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0)1925sshClient # [ 37.884517] systemd[1]: Created slice Slice /user/1000.1926sshClient # [ 37.889464] systemd[1]: Starting User Runtime Directory /run/user/1000...1927sshClient # [ 37.933296] systemd-logind[463]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'.1928sshClient # [ 37.955055] systemd[1]: Finished User Runtime Directory /run/user/1000.1929sshClient # [ 37.968723] systemd[1]: Starting User Manager for UID 1000...1930sshClient # [ 37.994521] (systemd)[800]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0)1931sshClient # [ 38.002159] systemd-logind[463]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'.1932sshClient # [ 38.022751] systemd[800]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1933sshClient # [ 38.139738] systemd[800]: Queued start job for default target Main User Target.1934sshClient # [ 38.148951] systemd[800]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1935sshClient # [ 38.152830] systemd[800]: Created slice User Application Slice.1936sshClient # [ 38.153740] systemd[800]: Started Daily Cleanup of User's Temporary Directories.1937sshClient # [ 38.154854] systemd[800]: Reached target Paths.1938sshClient # [ 38.155516] systemd[800]: Reached target Timers.1939sshClient # [ 38.159543] systemd[800]: Starting D-Bus User Message Bus Socket...1940sshClient # [ 38.164223] systemd[800]: Starting Create User Files and Directories...1941sshClient # [ 38.199932] systemd[800]: Finished Create User Files and Directories.1942sshClient # [ 38.211187] systemd[800]: Listening on D-Bus User Message Bus Socket.1943sshClient # [ 38.216384] systemd[800]: Reached target Sockets.1944sshClient # [ 38.217125] systemd[800]: Reached target Basic System.1945sshClient # [ 38.217861] systemd[1]: Started User Manager for UID 1000.1946sshClient # [ 38.219777] systemd[800]: Starting Run user-specific NixOS activation...1947sshClient # [ 38.227660] systemd[1]: Started Session 1 of User test-normal-user.1948sshClient # [ 38.270277] systemd[800]: Finished Run user-specific NixOS activation.1949sshClient # [ 38.271357] systemd[800]: Reached target Main User Target.1950sshClient # [ 38.276176] systemd[800]: Startup finished in 254ms.1951sshClient: (finished: waiting for success: pgrep -u test-normal-user bash, in 1.09 seconds)1952sshClient: sending keys 'whoami > /tmp/1\n'1953sshClient: (finished: sending keys 'whoami > /tmp/1\n', in 0.17 seconds)1954sshClient: waiting for file '/tmp/1'1955sshClient: (finished: waiting for file '/tmp/1', in 1.05 seconds)1956sshClient: must succeed: cat /tmp/11957sshClient: (finished: must succeed: cat /tmp/1, in 0.02 seconds)1958(finished: subtest: login as regular user, in 3.75 seconds)1959subtest: add homed ssh authorized key1960sshClient: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n'1961sshClient: (finished: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n', in 0.49 seconds)1962sshClient: waiting for file '/tmp/id_ed25519.pub'1963sshClient: (finished: waiting for file '/tmp/id_ed25519.pub', in 1.05 seconds)1964sshClient: must succeed: cat /tmp/id_ed25519.pub1965sshClient: (finished: must succeed: cat /tmp/id_ed25519.pub, in 0.02 seconds)1966machine: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKYJTeORB1j1D217JCqYUL2nzIlyVkTOAY9qoRgl+ICv test-normal-user@sshClient'1967machine # [ 41.686022] systemd-homed[497]: test-homed-user: changing state active → updating-while-active1968machine # [ 41.694081] systemd-homework[1072]: Offline update requested. Not touching embedded records.1969machine # [ 41.702687] systemd-homed[497]: test-homed-user: changing state updating-while-active → active1970machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKYJTeORB1j1D217JCqYUL2nzIlyVkTOAY9qoRgl+ICv test-normal-user@sshClient', in 0.06 seconds)1971machine: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed255191972machine: (finished: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed25519, in 0.03 seconds)1973(finished: subtest: add homed ssh authorized key, in 1.66 seconds)1974subtest: change homed user password1975machine: sending keys 'passwd; echo $? > /tmp/3\n'1976machine: (finished: sending keys 'passwd; echo $? > /tmp/3\n', in 0.26 seconds)1977machine: waiting for New password: to appear on tty 11978machine: (finished: waiting for New password: to appear on tty 1, in 1.12 seconds)1979machine: sending keys 'barfoobar\n'1980machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds)1981machine: waiting for Retype new password: to appear on tty 11982machine: (finished: waiting for Retype new password: to appear on tty 1, in 1.09 seconds)1983machine: sending keys 'barfoobar\n'1984machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds)1985??? Warning (UserWarning): sleep(): The 'secs' argument is deprecated. Use 'duration' instead.1986 File "/nix/store/iiab4a2pmymr5lc22a6yc0d0fyf3g00n-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 5731987machine: must succeed: sleep 4.01988machine # [ 44.548254] systemd-homed[497]: test-homed-user: changing state active → passwd-while-active1989machine # [ 45.248830] systemd-homework[1111]: No valid password for LUKS superblock.1990machine # [ 47.564715] systemd-homed[497]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.1991machine # [ 47.568624] systemd-homed[497]: test-homed-user: changing state passwd-while-active → active1992machine: (finished: must succeed: sleep 4.0, in 4.03 seconds)1993machine: sending keys 'foobarfoo\n'1994machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)1995machine: waiting for file '/tmp/3'1996machine # [ 48.679339] systemd-homed[497]: test-homed-user: changing state active → passwd-while-active1997machine # [ 49.947370] systemd-homework[1117]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.1998machine # [ 50.075114] systemd-homework[1117]: Successfully re-activated LUKS device.1999machine # [ 50.130300] systemd-homework[1117]: Provided password unlocks user record.2000machine # [ 50.136095] systemd-homework[1117]: Discovered used loopback device /dev/loop1.2001machine # [ 50.137362] systemd-homework[1117]: Read embedded .identity file.2002machine # [ 50.186203] systemd-homework[1117]: Provided password unlocks user record.2003machine # [ 50.187475] systemd-homework[1117]: Reconciling header user identity completed (host version was newer).2004machine # [ 50.192169] systemd-homework[1117]: Reconciling embedded user identity completed (host version was newer).2005machine # [ 53.376003] systemd-homework[1117]: Added LUKS key slot 1.2006machine # [ 53.394235] systemd-homework[1117]: Destroyed LUKS key slot 0.2007machine # [ 53.403249] systemd-homework[1117]: Wrote LUKS header user record.2008machine # [ 53.404915] systemd-homework[1117]: Wrote embedded .identity file.2009machine # [ 53.405849] systemd-homework[1117]: Replaced embedded blob dir with contents of system blob dir.2010machine # [ 53.410217] systemd-homework[1117]: Synchronized disk.2011machine # [ 53.416174] systemd-homework[1117]: Everything completed.2012machine # [ 53.439585] systemd-homed[497]: test-homed-user: changing state passwd-while-active → active2013machine # [ 53.443171] passwd[1086]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user.2014machine: (finished: waiting for file '/tmp/3', in 5.18 seconds)2015machine: must succeed: cat /tmp/32016machine: (finished: must succeed: cat /tmp/3, in 0.02 seconds)2017(finished: subtest: change homed user password, in 12.01 seconds)2018subtest: escalate to root from homed user2019machine: sending keys 'sudo id | tee /tmp/4\n'2020machine: (finished: sending keys 'sudo id | tee /tmp/4\n', in 0.22 seconds)2021machine: waiting for password for test-homed-user to appear on tty 12022machine # [ 54.214780] systemd-homed[497]: test-homed-user: changing state active → authenticating-for-acquire2023machine # [ 54.222422] systemd-homework[1155]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2024machine # [ 54.229103] systemd-homed[497]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2025machine # [ 54.232813] systemd-homed[497]: test-homed-user: changing state authenticating-for-acquire → active2026machine: (finished: waiting for password for test-homed-user to appear on tty 1, in 1.10 seconds)2027machine: sending keys 'barfoobar\n'2028machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2029machine: waiting for file '/tmp/4'2030machine: (finished: waiting for file '/tmp/4', in 0.03 seconds)2031machine: waiting for success: grep uid=0 /tmp/42032machine # [ 55.263256] systemd-homed[497]: test-homed-user: changing state active → authenticating-for-acquire2033machine # [ 55.312581] systemd-homework[1171]: Provided password unlocks user record.2034machine # [ 55.956079] systemd-homework[1171]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2035machine # [ 56.080133] systemd-homework[1171]: Successfully re-activated LUKS device.2036machine # [ 56.109057] systemd-homework[1171]: Provided password unlocks user record.2037machine # [ 56.110948] systemd-homework[1171]: Discovered used loopback device /dev/loop1.2038machine # [ 56.112215] systemd-homework[1171]: Read embedded .identity file.2039machine # [ 56.137769] systemd-homework[1171]: Provided password unlocks user record.2040machine # [ 56.139798] systemd-homework[1171]: Reconciling user identities completed (host and header version were identical).2041machine # [ 56.141893] systemd-homework[1171]: Reconciling embedded user identity completed (host and embedded version were identical).2042machine # [ 56.152160] systemd-homework[1171]: Everything completed.2043machine # [ 56.163880] systemd-homed[497]: Home test-homed-user is signed exclusively by our key, accepting.2044machine # [ 56.169082] systemd-homed[497]: test-homed-user: changing state authenticating-for-acquire → active2045machine # [ 56.170772] sudo[1153]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired.2046machine # [ 56.176601] sudo[1153]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id2047machine # [ 56.187855] sudo[1153]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388)2048machine # [ 56.198800] sudo[1153]: pam_unix(sudo:session): session closed for user root2049machine: (finished: waiting for success: grep uid=0 /tmp/4, in 1.06 seconds)2050(finished: subtest: escalate to root from homed user, in 2.52 seconds)2051subtest: log out and deactivate homed user's home area2052machine: sending keys 'exit\n'2053machine: (finished: sending keys 'exit\n', in 0.05 seconds)2054machine: waiting for success: homectl inspect test-homed-user | grep 'State: inactive'2055machine # [ 56.365119] login[935]: pam_unix(login:session): session closed for user test-homed-user2056machine # [ 56.367112] login[935]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used.2057machine # [ 56.385095] systemd[1]: getty@tty1.service: Deactivated successfully.2058machine # [ 56.391560] systemd[1]: session-1.scope: Deactivated successfully.2059machine # [ 56.396946] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.2060machine # [ 56.411536] systemd-logind[525]: Session 1 logged out. Waiting for processes to exit.2061machine # [ 56.422178] systemd[1]: Started Getty on tty1.2062machine # [ 56.423056] systemd-logind[525]: Removed session 1.2063machine # [ 66.592289] systemd[1]: Stopping User Manager for UID 60388...2064machine # [ 66.599534] systemd[1019]: Activating special unit Exit the Session...2065machine # [ 66.605752] systemd[1019]: Stopped target Main User Target.2066machine # [ 66.611343] systemd[1019]: Stopped Run user-specific NixOS activation.2067machine # [ 66.613875] systemd[1019]: Stopped target Basic System.2068machine # [ 66.615162] systemd[1019]: Stopped target Paths.2069machine # [ 66.616531] systemd[1019]: Stopped target Sockets.2070machine # [ 66.617823] systemd[1019]: Stopped target Timers.2071machine # [ 66.618990] systemd[1019]: Stopped Daily Cleanup of User's Temporary Directories.2072machine # [ 66.620859] systemd[1019]: Closed D-Bus User Message Bus Socket.2073machine # [ 66.622233] systemd[1019]: Stopped Create User Files and Directories.2074machine # [ 66.623685] systemd[1019]: Removed slice User Application Slice.2075machine # [ 66.627472] systemd[1019]: Reached target Shutdown.2076machine # [ 66.628946] systemd[1019]: Finished Exit the Session.2077machine # [ 66.630166] systemd[1019]: Reached target Exit the Session.2078machine # [ 66.633869] systemd-logind[525]: Removed session 2.2079machine # [ 66.643737] systemd-homed[497]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2080machine # [ 66.655878] systemd-homed[497]: test-homed-user: changing state active → deactivating2081machine # [ 66.658339] systemd[1]: user@60388.service: Deactivated successfully.2082machine # [ 66.659649] systemd[1]: Stopped User Manager for UID 60388.2083machine # [ 66.665007] systemd-homework[1246]: Successfully acquired home volume key from kernel keyring.2084machine # [ 66.668927] systemd-homework[1246]: Discarded unused 674.3M.2085machine # [ 66.675402] systemd-homework[1246]: Syncing completed.2086machine # [ 66.718361] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully.2087machine # [ 66.732126] systemd[1]: Stopping User Runtime Directory /run/user/60388...2088machine # [ 66.772165] systemd-homework[1246]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2089machine # [ 66.842665] systemd[1]: run-user-60388.mount: Deactivated successfully.2090machine # [ 66.851003] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully.2091machine # [ 66.855976] systemd[1]: Stopped User Runtime Directory /run/user/60388.2092machine # [ 66.953353] systemd-homework[1246]: Successfully re-activated LUKS device.2093machine # [ 66.955970] systemd-homework[1246]: Discovered used loopback device /dev/loop1.2094machine # [ 66.959094] systemd-homework[1246]: offset = 1048576, size = 800063488, image = 8021606402095machine # [ 66.966074] systemd-homework[1246]: Ready to resize image size 765M → 274M, partition size 763M → 272M, file system size 747M → 256M.2096machine # [ 66.969459] systemd-homework[1246]: Allocated additional 674.3M.2097machine # [ 66.975293] systemd-homework[1246]: File system resizing from 747M to 256M completed.[ 66.989296] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 783286272 to 2684354562098machine # 2099machine # [ 66.978868] systemd-homework[1246]: Synchronized disk.2100machine # [ 67.098892] systemd-homework[1246]: LUKS device shrinking completed.2101machine # [ 67.124353] loop1: detected capacity change from 1562624 to 5570562102machine # [ 67.113742] systemd-homework[1246]: Refreshing loop device size completed.2103machine # [ 67.114890] systemd-homework[1246]: Shrinking of image file completed.2104machine # [ 67.126820] systemd-homework[1246]: Shrinking of partition completed.2105machine # [ 67.130858] systemd-homework[1246]: Synchronized disk.2106machine # [ 67.131756] systemd-homework[1246]: Resizing completed.[ 67.144537] BTRFS info (device dm-0): last unmount of filesystem 30172656-be20-43bb-a0a2-e378254e9fe52107machine # 2108machine # [ 67.135970] systemd-homework[1246]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M.2109machine # [ 67.138008] systemd-homework[1246]: Unmounting completed.2110machine # [ 67.138858] systemd-homework[1246]: Discovered used LUKS device /dev/mapper/home-test-homed-user.2111machine # [ 67.140999] systemd-homework[1246]: Device home-test-homed-user is not active.2112machine # [ 67.169524] systemd-homed[497]: block device /sys/devices/virtual/block/dm-0 has been removed.2113machine # [ 67.172909] systemd-homework[1246]: Everything completed.2114machine # [ 67.187236] systemd-homed[497]: test-homed-user: changing state deactivating → inactive2115machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 11.66 seconds)2116(finished: subtest: log out and deactivate homed user's home area, in 11.71 seconds)2117subtest: ssh as homed user2118sshClient: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n'2119sshClient: (finished: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n', in 0.78 seconds)2120sshClient: waiting for Please enter password for user to appear on tty 12121machine # [ 69.802288] systemd-homed[497]: test-homed-user: changing state inactive → activating-for-acquire2122machine # [ 69.811003] systemd-homework[1280]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2123machine # [ 69.818564] systemd-homed[497]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2124machine # [ 69.824271] systemd-homed[497]: test-homed-user: changing state activating-for-acquire → inactive2125machine # [ 69.827015] sshd-session[1276]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error2126machine # [ 69.831421] systemd-homed[497]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2127machine # [ 69.834262] sshd-session[1276]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation.2128machine # [ 69.837629] systemd-homed[497]: Home test-homed-user already deactivated, no automatic deactivation needed.2129machine # [ 69.839766] sshd-session[1276]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 59596 ssh2: ED25519 SHA256:SHtDcnYC3ATvelDlZUOWwv0Yz+c/5at/16lm0TDR7vw2130machine # [ 69.892608] sshd-session[1276]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0)2131machine # [ 69.908056] systemd-logind[525]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'.2132machine # [ 69.911922] systemd[1]: Starting User Runtime Directory /run/user/60388...2133machine # [ 69.945069] systemd[1]: Finished User Runtime Directory /run/user/60388.2134machine # [ 69.957214] systemd[1]: Started Session 3 of User test-homed-user.2135machine # [ 69.977474] sshd-session[1276]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2136machine # [ 69.984621] sshd-session[1276]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2137machine # [ 70.004937] systemd-homed[497]: test-homed-user: changing state inactive → activating2138machine # [ 70.010147] systemd-homework[1287]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2139machine # [ 70.015199] systemd-homed[497]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2140machine # [ 70.017740] systemd-homed[497]: test-homed-user: changing state activating → inactive2141sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.14 seconds)2142sshClient: sending keys 'barfoobar\n'2143sshClient: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2144machine: waiting for success: pgrep -u test-homed-user bash2145machine # [ 71.097768] systemd-homed[497]: test-homed-user: changing state inactive → activating2146machine # [ 71.148386] systemd-homework[1292]: Provided password unlocks user record.2147machine # [ 71.154375] systemd-homework[1292]: Successfully locked image file '/home/test-homed-user.home'.2148machine # [ 71.159827] systemd-homework[1292]: Backing file is fully allocated already.2149machine # [ 71.179111] loop1: detected capacity change from 0 to 5570562150machine # [ 71.165447] systemd-homework[1292]: Setting up loopback device /dev/loop1 completed.2151machine # [ 72.052147] systemd-homework[1292]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.2152machine # [ 72.100198] systemd-homework[1292]: Provided password unlocks user record.2153machine # [ 72.108066] systemd-homework[1292]: Probing file system completed (found btrfs).2154machine # [ 72.110716] systemd-homework[1292]: No fsck for file system btrfs installed, ignoring.2155machine # [ 72.130434] BTRFS: device label test-homed-user devid 1 transid 17 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (1292)2156machine # [ 72.132162] BTRFS info (device dm-0): first mount of filesystem 30172656-be20-43bb-a0a2-e378254e9fe52157machine # [ 72.132208] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm2158machine # [ 72.125229] systemd-homework[1292]: Mounting file system completed.2159machine # [ 72.141163] BTRFS info (device dm-0): enabling free space tree2160machine # [ 72.141188] BTRFS info (device dm-0): use zstd compression, level 12161machine # [ 72.129833] systemd-homework[1292]: Discovered used loopback device /dev/loop1.2162machine # [ 72.130974] systemd-homework[1292]: offset = 1048576, size = 285212672, image = 2873098242163machine # [ 72.143703] systemd-homework[1292]: Ready to resize image size 274M → 765M, partition size 272M → 763M, file system size 256M → 747M.2164machine # [ 72.148501] systemd-homework[1292]: Growing of image file from 274M to 765M completed.2165machine # [ 72.155901] systemd-homework[1292]: Refreshing loop device size completed.[ 72.168271] loop1: detected capacity change from 557056 to 15626242166machine # 2167machine # [ 72.173514] systemd-homework[1292]: Growing of partition completed.2168machine # [ 72.225154] systemd-homework[1292]: LUKS device growing completed.2169machine # [ 72.247851] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 7832862722170machine # [ 72.237733] systemd-homework[1292]: File system resizing from 256M to 747M completed.2171machine # [ 72.239884] systemd-homework[1292]: Synchronized disk.2172machine # [ 72.240852] systemd-homework[1292]: Synchronized disk.2173machine # [ 72.241630] systemd-homework[1292]: Resizing completed.2174machine # [ 72.242407] systemd-homework[1292]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.2175machine # [ 72.248185] systemd-homework[1292]: Read embedded .identity file.2176machine # [ 72.267680] systemd-homework[1292]: Provided password unlocks user record.2177machine # [ 72.270807] systemd-homework[1292]: Reconciling user identities completed (host and header version were identical).2178machine # [ 72.275264] systemd-homework[1292]: Reconciling embedded user identity completed (host and embedded version were identical).2179machine # [ 72.284154] systemd-homework[1292]: Recursive changing of ownership not necessary, skipped.2180machine # [ 72.285524] systemd-homework[1292]: Synchronized disk.2181machine # [ 72.286306] systemd-homework[1292]: Moving to final mount point /home/test-homed-user completed.2182machine # [ 72.303186] systemd-homework[1292]: Activation completed.2183machine # [ 72.304553] systemd-homework[1292]: Image size is 763M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.2184machine # [ 72.321747] systemd-homed[497]: Home test-homed-user is signed exclusively by our key, accepting.2185machine # [ 72.326180] systemd-homed[497]: test-homed-user: changing state activating → active2186machine # [ 72.344371] systemd[1]: Starting User Manager for UID 60388...2187machine # [ 72.382538] (systemd)[1336]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.2188machine # [ 72.424914] (systemd)[1336]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)2189machine # [ 72.431886] systemd-logind[525]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'.2190machine # [ 72.455316] systemd[1336]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2191machine # [ 72.569613] systemd[1336]: Queued start job for default target Main User Target.2192machine # [ 72.573073] systemd[1336]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2193machine # [ 72.577192] systemd[1336]: Created slice User Application Slice.2194machine # [ 72.578221] systemd[1336]: Started Daily Cleanup of User's Temporary Directories.2195machine # [ 72.581610] systemd[1336]: Reached target Paths.2196machine # [ 72.582389] systemd[1336]: Reached target Timers.2197machine # [ 72.583128] systemd[1336]: Starting D-Bus User Message Bus Socket...2198machine # [ 72.588224] systemd[1336]: Starting Create User Files and Directories...2199machine # [ 72.624253] systemd[1336]: Finished Create User Files and Directories.2200machine # [ 72.626812] systemd[1336]: Listening on D-Bus User Message Bus Socket.2201machine # [ 72.631398] systemd[1336]: Reached target Sockets.2202machine # [ 72.633790] systemd[1336]: Reached target Basic System.2203machine # [ 72.634618] systemd[1]: Started User Manager for UID 60388.2204machine # [ 72.635479] systemd[1336]: Starting Run user-specific NixOS activation...2205machine # [ 72.685836] systemd[1336]: Finished Run user-specific NixOS activation.2206machine # [ 72.691362] systemd[1336]: Reached target Main User Target.2207machine # [ 72.692393] systemd[1336]: Startup finished in 240ms.2208machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.22 seconds)2209sshClient: sending keys 'whoami > /tmp/5\n'2210sshClient: (finished: sending keys 'whoami > /tmp/5\n', in 0.17 seconds)2211machine: waiting for file '/tmp/5'2212machine: (finished: waiting for file '/tmp/5', in 1.05 seconds)2213machine: must succeed: cat /tmp/52214machine: (finished: must succeed: cat /tmp/5, in 0.02 seconds)2215sshClient: sending keys 'exit\n'2216sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2217sshClient: sending keys 'exit\n'2218machine # [ 74.571271] sshd-session[1283]: Received disconnect from 2001:db8:1::2 port 59596:11: disconnected by user2219machine # [ 74.574703] sshd-session[1283]: Disconnected from user test-homed-user 2001:db8:1::2 port 595962220sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2221(finished: subtest: ssh as homed user, in 6.60 seconds)2222(finished: run the VM test script, in 75.48 seconds)2223machine # [ 74.578018] sshd-session[1276]: pam_unix(sshd:session): session closed for user test-homed-user2224machine # [ 74.582653] sshd-session[1276]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used.2225machine # [ 74.592728] systemd[1]: session-3.scope: Deactivated successfully.2226machine # [ 74.605320] systemd-logind[525]: Session 3 logged out. Waiting for processes to exit.2227machine # [ 74.607196] systemd-logind[525]: Removed session 3.2228sshClient # [ 74.647810] login[640]: pam_unix(login:session): session closed for user test-normal-user2229sshClient # [ 74.663309] systemd[1]: getty@tty1.service: Deactivated successfully.2230sshClient # [ 74.678189] systemd[1]: session-1.scope: Deactivated successfully.2231sshClient # [ 74.683194] systemd[1]: session-1.scope: Consumed 112ms CPU time over 36.455s wall clock time, 9.3M memory peak, 12K written to disk, 24.6K incoming IP traffic, 23.7K outgoing IP traffic.2232sshClient # [ 74.702557] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.2233sshClient # [ 74.713570] systemd-logind[463]: Session 1 logged out. Waiting for processes to exit.2234sshClient # [ 74.715124] systemd[1]: Started Getty on tty1.2235sshClient # [ 74.715998] systemd-logind[463]: Removed session 1.2236test script finished in 75.67s2237cleanup2238kill QemuMachine (pid 47)2239machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2240machine # [2026-09-30T03:40:28Z INFO virtiofsd] Client disconnected, shutting down2241machine # [2026-09-30T03:40:28Z INFO virtiofsd] Client disconnected, shutting down2242machine # [2026-09-30T03:40:28Z INFO virtiofsd] Client disconnected, shutting down2243kill QemuMachine (pid 48)2244sshClient # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2245sshClient # [2026-09-30T03:40:29Z INFO virtiofsd] Client disconnected, shutting down2246sshClient # [2026-09-30T03:40:29Z INFO virtiofsd] Client disconnected, shutting down2247sshClient # [2026-09-30T03:40:29Z INFO virtiofsd] Client disconnected, shutting down2248(finished: cleanup, in 0.30 seconds)2249additionally exposed symbols:2250 machine, sshClient,2251 vlan1,2252 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