vm-test-run-systemd-homed
checks.aarch64-linux.systemd-homed
· build #23
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7start all VMs8machine: starting vm9sshClient: starting vm10sshClient # Disk image does not exist, creating the virtualisation disk image...11sshClient # Formatting '/build/vm-state-sshClient/tmp.3qOukM48cN', fmt=raw size=107374182412sshClient # mke2fs 1.47.4 (6-Mar-2025)13sshClient # Discarding device blocks: 0/262144 done14sshClient: QEMU running (pid 48)15sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes16sshClient # Filesystem UUID: 257e409d-cd9e-4181-8262-03a8a67c387517sshClient # Superblock backups stored on blocks:18sshClient # 32768, 98304, 163840, 22937619machine # Disk image does not exist, creating the virtualisation disk image...20machine: QEMU running (pid 47)21machine # Formatting '/build/vm-state-machine/tmp.E81Q5pSS3M', fmt=raw size=107374182422sshClient # 23machine # mke2fs 1.47.4 (6-Mar-2025)24sshClient # Allocating group tables: 0/8 done25machine # Discarding device blocks: 0/262144 done26(finished: start all VMs, in 0.54 seconds)27machine # Creating filesystem with 262144 4k blocks and 65536 inodes28subtest: create systemd-homed user on first boot prompt29machine # Filesystem UUID: 5f304e20-4ac6-4c80-9ecb-de03ea1b907830machine: waiting for unit systemd-homed.service31machine # Superblock backups stored on blocks:32machine: waiting for the VM to finish booting33machine # 32768, 98304, 163840, 22937634sshClient # Writing inode tables: 0/8 done35machine # 36sshClient # Creating journal (8192 blocks): done37machine # Allocating group tables: 0/8 done38sshClient # Writing superblocks and filesystem accounting information: 0/8 done39machine # Writing inode tables: 0/8 done40machine # Creating journal (8192 blocks): done41sshClient # 42machine # Writing superblocks and filesystem accounting information: 0/8 done43sshClient # Virtualisation disk image created.44machine # 45machine # Virtualisation disk image created.46sshClient # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]47sshClient # [ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Wed Sep 2 12:31:51 UTC 202648sshClient # [ 0.000000] KASLR enabled49sshClient # [ 0.000000] random: crng init done50sshClient # [ 0.000000] Machine model: linux,dummy-virt51sshClient # [ 0.000000] efi: UEFI not found.52sshClient # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT53sshClient # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]54sshClient # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]55sshClient # [ 0.000000] Zone ranges:56sshClient # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]57sshClient # [ 0.000000] DMA32 empty58sshClient # [ 0.000000] Normal empty59sshClient # [ 0.000000] Device empty60sshClient # [ 0.000000] Movable zone start for each node61sshClient # [ 0.000000] Early memory node ranges62sshClient # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]63sshClient # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]64sshClient # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000065sshClient # [ 0.000000] psci: probing for conduit method from DT.66sshClient # [ 0.000000] psci: PSCIv1.3 detected in firmware.67sshClient # [ 0.000000] psci: Using standard PSCI v0.2 function IDs68sshClient # [ 0.000000] psci: Trusted OS migration not required69sshClient # [ 0.000000] psci: SMC Calling Convention v1.170sshClient # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)71sshClient # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u31129672sshClient # [ 0.000000] Detected PIPT I-cache on CPU073machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]74sshClient # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)75sshClient # [ 0.000000] CPU features: detected: GICv3 CPU interface76machine # [ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Wed Sep 2 12:31:51 UTC 202677sshClient # [ 0.000000] CPU features: detected: Spectre-v478machine # [ 0.000000] KASLR enabled79sshClient # [ 0.000000] CPU features: detected: Spectre-BHB80machine # [ 0.000000] random: crng init done81machine # [ 0.000000] Machine model: linux,dummy-virt82sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_3883machine # [ 0.000000] efi: UEFI not found.84sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_2385machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT86sshClient # [ 0.000000] alternatives: applying boot alternatives87machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]88machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]89machine # [ 0.000000] Zone ranges:90machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]91machine # [ 0.000000] DMA32 empty92machine # [ 0.000000] Normal empty93machine # [ 0.000000] Device empty94sshClient # [ 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/gbrdl7vk6alrzvkgilgjjib0apxf3048-nixos-system-sshClient-test/init regInfo=/nix/store/jddv797sb2qy10az0qgz8z1c6i78ml21-closure-info/registration console=ttyAMA0,115200n8 console=tty095machine # [ 0.000000] Movable zone start for each node96machine # [ 0.000000] Early memory node ranges97machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]98machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]99sshClient # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/jddv797sb2qy10az0qgz8z1c6i78ml21-closure-info/registration", will be passed to user space.100machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000101sshClient # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes102machine # [ 0.000000] psci: probing for conduit method from DT.103machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.104sshClient # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)105machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs106sshClient # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)107machine # [ 0.000000] psci: Trusted OS migration not required108machine # [ 0.000000] psci: SMC Calling Convention v1.1109sshClient # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB110sshClient # [ 0.000000] software IO TLB: area num 1.111machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)112sshClient # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)113machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u311296114sshClient # [ 0.000000] Fallback order for Node 0: 0115machine # [ 0.000000] Detected PIPT I-cache on CPU0116sshClient # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144117sshClient # [ 0.000000] Policy zone: DMA118machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)119sshClient # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off120machine # [ 0.000000] CPU features: detected: GICv3 CPU interface121machine # [ 0.000000] CPU features: detected: Spectre-v4122sshClient # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1123machine # [ 0.000000] CPU features: detected: Spectre-BHB124sshClient # [ 0.000000] allocated 2097152 bytes of page_ext125machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38126sshClient # [ 0.000000] ftrace: allocating 74886 entries in 294 pages127sshClient # [ 0.000000] ftrace: allocated 294 pages with 4 groups128machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23129sshClient # [ 0.000000] rcu: Hierarchical RCU implementation.130machine # [ 0.000000] alternatives: applying boot alternatives131sshClient # [ 0.000000] rcu: RCU event tracing is enabled.132sshClient # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.133sshClient # [ 0.000000] Trampoline variant of Tasks RCU enabled.134sshClient # [ 0.000000] Rude variant of Tasks RCU enabled.135sshClient # [ 0.000000] Tracing variant of Tasks RCU enabled.136machine # [ 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/a268jj7i274vaw5h84m9kw8hbwdp3ryl-nixos-system-machine-test/init regInfo=/nix/store/sp4iy2m5pqyj61dkks26d0vvnghphkp1-closure-info/registration console=ttyAMA0,115200n8 console=tty0137sshClient # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.138sshClient # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1139sshClient # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.140machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/sp4iy2m5pqyj61dkks26d0vvnghphkp1-closure-info/registration", will be passed to user space.141sshClient # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.142machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes143sshClient # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.144machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)145sshClient # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0146sshClient # [ 0.000000] GICv3: 256 SPIs implemented147machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)148sshClient # [ 0.000000] GICv3: 0 Extended SPIs implemented149machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB150sshClient # [ 0.000000] Root IRQ handler: gic_handle_irq151machine # [ 0.000000] software IO TLB: area num 1.152sshClient # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI153sshClient # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0154machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)155machine # [ 0.000000] Fallback order for Node 0: 0156sshClient # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000157sshClient # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]158machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144159machine # [ 0.000000] Policy zone: DMA160sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)161machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off162machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1163sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)164machine # [ 0.000000] allocated 2097152 bytes of page_ext165sshClient # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000166machine # [ 0.000000] ftrace: allocating 74886 entries in 294 pages167machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups168sshClient # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000169machine # [ 0.000000] rcu: Hierarchical RCU implementation.170sshClient # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.171machine # [ 0.000000] rcu: RCU event tracing is enabled.172machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.173sshClient # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns174machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.175sshClient # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).176machine # [ 0.000000] Rude variant of Tasks RCU enabled.177machine # [ 0.000000] Tracing variant of Tasks RCU enabled.178sshClient # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns179machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.180sshClient # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns181machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1182sshClient # [ 0.000034] arm-pv: using stolen time PV183machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.184sshClient # [ 0.000539] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)185sshClient # [ 0.000716] Console: colour dummy device 80x25186machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.187sshClient # [ 0.000725] printk: legacy console [tty0] enabled188machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.189sshClient # [ 0.000910] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)190machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0191sshClient # [ 0.000917] pid_max: default: 32768 minimum: 301192machine # [ 0.000000] GICv3: 256 SPIs implemented193machine # [ 0.000000] GICv3: 0 Extended SPIs implemented194sshClient # [ 0.000989] LSM: initializing lsm=capability,landlock,yama,bpf,ima195machine # [ 0.000000] Root IRQ handler: gic_handle_irq196sshClient # [ 0.001125] landlock: Up and running.197sshClient # [ 0.001128] Yama: becoming mindful.198machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI199sshClient # [ 0.001637] LSM support for eBPF active200machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0201sshClient # [ 0.001786] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)202machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000203machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]204sshClient # [ 0.001805] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)205sshClient # [ 0.002922] cacheinfo: Unable to detect cache hierarchy for CPU 0206machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)207sshClient # [ 0.003677] rcu: Hierarchical SRCU implementation.208sshClient # [ 0.003681] rcu: Max phase no-delay instances is 1000.209machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)210sshClient # [ 0.004941] fsl-mc MSI: its@8080000 domain created211machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000212sshClient # [ 0.005041] EFI services will not be available.213sshClient # [ 0.005122] smp: Bringing up secondary CPUs ...214machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000215sshClient # [ 0.005131] smp: Brought up 1 node, 1 CPU216machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.217sshClient # [ 0.005135] SMP: Total of 1 processors activated.218sshClient # [ 0.005138] CPU: All CPU(s) started at EL1219machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns220sshClient # [ 0.005151] CPU features: detected: Branch Target Identification221machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).222sshClient # [ 0.005156] CPU features: detected: ARMv8.4 Translation Table Level223sshClient # [ 0.005159] CPU features: detected: Instruction cache invalidation not required for I/D coherence224machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns225sshClient # [ 0.005163] CPU features: detected: Data cache clean to the PoU not required for I/D coherence226machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns227machine # [ 0.000030] arm-pv: using stolen time PV228sshClient # [ 0.005166] CPU features: detected: Common not Private translations229sshClient # [ 0.005169] CPU features: detected: CRC32 instructions230machine # [ 0.000463] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)231sshClient # [ 0.005172] CPU features: detected: Data cache clean to Point of Deep Persistence232machine # [ 0.000656] Console: colour dummy device 80x25233machine # [ 0.000664] printk: legacy console [tty0] enabled234sshClient # [ 0.005176] CPU features: detected: Data cache clean to Point of Persistence235sshClient # [ 0.005179] CPU features: detected: Data independent timing control (DIT)236machine # [ 0.000859] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)237sshClient # [ 0.005182] CPU features: detected: E0PD238machine # [ 0.000866] pid_max: default: 32768 minimum: 301239sshClient # [ 0.005185] CPU features: detected: Enhanced Counter Virtualization240machine # [ 0.000949] LSM: initializing lsm=capability,landlock,yama,bpf,ima241machine # [ 0.001086] landlock: Up and running.242sshClient # [ 0.005188] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)243machine # [ 0.001088] Yama: becoming mindful.244sshClient # [ 0.005191] CPU features: detected: Enhanced Virtualization Traps245machine # [ 0.001604] LSM support for eBPF active246sshClient # [ 0.005194] CPU features: detected: Fine Grained Traps247machine # [ 0.001743] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)248sshClient # [ 0.005198] CPU features: detected: Generic authentication (architected QARMA5 algorithm)249machine # [ 0.001763] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)250sshClient # [ 0.005202] CPU features: detected: RCpc load-acquire (LDAPR)251machine # [ 0.002859] cacheinfo: Unable to detect cache hierarchy for CPU 0252sshClient # [ 0.005206] CPU features: detected: LSE atomic instructions253machine # [ 0.003540] rcu: Hierarchical SRCU implementation.254sshClient # [ 0.005209] CPU features: detected: Privileged Access Never255machine # [ 0.003545] rcu: Max phase no-delay instances is 1000.256sshClient # [ 0.005211] CPU features: detected: PMUv3257machine # [ 0.004766] fsl-mc MSI: its@8080000 domain created258sshClient # [ 0.005214] CPU features: detected: RAS Extension Support259machine # [ 0.004860] EFI services will not be available.260sshClient # [ 0.005217] CPU features: detected: RASv1p1 Extension Support261machine # [ 0.004972] smp: Bringing up secondary CPUs ...262machine # [ 0.004980] smp: Brought up 1 node, 1 CPU263sshClient # [ 0.005220] CPU features: detected: Random Number Generator264machine # [ 0.004984] SMP: Total of 1 processors activated.265sshClient # [ 0.005222] CPU features: detected: Speculation barrier (SB)266machine # [ 0.004987] CPU: All CPU(s) started at EL1267sshClient # [ 0.005225] CPU features: detected: Stage-2 Force Write-Back268machine # [ 0.005001] CPU features: detected: Branch Target Identification269sshClient # [ 0.005228] CPU features: detected: TLB range maintenance instructions270machine # [ 0.005006] CPU features: detected: ARMv8.4 Translation Table Level271sshClient # [ 0.005233] CPU features: detected: Speculative Store Bypassing Safe (SSBS)272machine # [ 0.005009] CPU features: detected: Instruction cache invalidation not required for I/D coherence273sshClient # [ 0.005272] alternatives: applying system-wide alternatives274sshClient # [ 0.008271] CPU features: detected: BBM Level 2 without TLB conflict abort275machine # [ 0.005013] CPU features: detected: Data cache clean to the PoU not required for I/D coherence276machine # [ 0.005016] CPU features: detected: Common not Private translations277machine # [ 0.005019] CPU features: detected: CRC32 instructions278sshClient # [ 0.008485] Memory: 894320K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 112964K reserved, 32768K cma-reserved)279machine # [ 0.005022] CPU features: detected: Data cache clean to Point of Deep Persistence280sshClient # [ 0.008862] devtmpfs: initialized281machine # [ 0.005025] CPU features: detected: Data cache clean to Point of Persistence282sshClient # [ 0.010493] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)283machine # [ 0.005029] CPU features: detected: Data independent timing control (DIT)284sshClient # [ 0.010515] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).285machine # [ 0.005032] CPU features: detected: E0PD286sshClient # [ 0.010710] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL287machine # [ 0.005034] CPU features: detected: Enhanced Counter Virtualization288sshClient # [ 0.010714] 0 pages in range for non-PLT usage289sshClient # [ 0.010716] 508288 pages in range for PLT usage290machine # [ 0.005037] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)291sshClient # [ 0.010827] pinctrl core: initialized pinctrl subsystem292machine # [ 0.005040] CPU features: detected: Enhanced Virtualization Traps293sshClient # [ 0.011614] DMI not present or invalid.294machine # [ 0.005043] CPU features: detected: Fine Grained Traps295sshClient # [ 0.014815] NET: Registered PF_NETLINK/PF_ROUTE protocol family296machine # [ 0.005046] CPU features: detected: Generic authentication (architected QARMA5 algorithm)297sshClient # [ 0.017069] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations298machine # [ 0.005051] CPU features: detected: RCpc load-acquire (LDAPR)299sshClient # [ 0.017212] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations300machine # [ 0.005054] CPU features: detected: LSE atomic instructions301machine # [ 0.005056] CPU features: detected: Privileged Access Never302sshClient # [ 0.017375] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations303machine # [ 0.005059] CPU features: detected: PMUv3304sshClient # [ 0.017398] audit: initializing netlink subsys (disabled)305machine # [ 0.005062] CPU features: detected: RAS Extension Support306sshClient # [ 0.017994] thermal_sys: Registered thermal governor 'fair_share'307machine # [ 0.005064] CPU features: detected: RASv1p1 Extension Support308sshClient # [ 0.017996] thermal_sys: Registered thermal governor 'bang_bang'309machine # [ 0.005067] CPU features: detected: Random Number Generator310sshClient # [ 0.017999] thermal_sys: Registered thermal governor 'step_wise'311machine # [ 0.005070] CPU features: detected: Speculation barrier (SB)312sshClient # [ 0.018002] thermal_sys: Registered thermal governor 'user_space'313machine # [ 0.005072] CPU features: detected: Stage-2 Force Write-Back314sshClient # [ 0.018008] thermal_sys: Registered thermal governor 'power_allocator'315machine # [ 0.005075] CPU features: detected: TLB range maintenance instructions316sshClient # [ 0.018032] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1317machine # [ 0.005080] CPU features: detected: Speculative Store Bypassing Safe (SSBS)318sshClient # [ 0.018040] cpuidle: using governor ladder319machine # [ 0.005118] alternatives: applying system-wide alternatives320sshClient # [ 0.018046] cpuidle: using governor menu321machine # [ 0.008123] CPU features: detected: BBM Level 2 without TLB conflict abort322sshClient # [ 0.018240] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.323sshClient # [ 0.018256] ASID allocator initialised with 65536 entries324sshClient # [ 0.019603] Serial: AMBA PL011 UART driver325machine # [ 0.008342] Memory: 894272K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 112964K reserved, 32768K cma-reserved)326machine # [ 0.008709] devtmpfs: initialized327sshClient # [ 0.024977] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1328machine # [ 0.010391] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)329machine # [ 0.010414] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).330machine # [ 0.010596] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL331machine # [ 0.010601] 0 pages in range for non-PLT usage332machine # [ 0.010602] 508288 pages in range for PLT usage333machine # [ 0.010716] pinctrl core: initialized pinctrl subsystem334machine # [ 0.011512] DMI not present or invalid.335machine # [ 0.014675] NET: Registered PF_NETLINK/PF_ROUTE protocol family336machine # [ 0.016931] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations337machine # [ 0.017080] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations338machine # [ 0.017239] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations339sshClient # [ 0.025134] printk: console [ttyAMA0] enabled340sshClient # [ 0.148765] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages341sshClient # [ 0.148787] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page342sshClient # [ 0.148792] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages343sshClient # [ 0.148797] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page344sshClient # [ 0.148801] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages345sshClient # [ 0.148805] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page346sshClient # [ 0.148809] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages347sshClient # [ 0.148813] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page348machine # [ 0.017261] audit: initializing netlink subsys (disabled)349machine # [ 0.017782] thermal_sys: Registered thermal governor 'fair_share'350sshClient # [ 0.156342] fbcon: Taking over console351machine # [ 0.017784] thermal_sys: Registered thermal governor 'bang_bang'352sshClient # [ 0.156356] ACPI: Interpreter disabled.353machine # [ 0.017788] thermal_sys: Registered thermal governor 'step_wise'354sshClient # [ 0.158175] iommu: Default domain type: Translated355machine # [ 0.017790] thermal_sys: Registered thermal governor 'user_space'356sshClient # [ 0.158185] iommu: DMA domain TLB invalidation policy: strict mode357machine # [ 0.017796] thermal_sys: Registered thermal governor 'power_allocator'358sshClient # [ 0.159879] SCSI subsystem initialized359machine # [ 0.017820] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1360machine # [ 0.017828] cpuidle: using governor ladder361machine # [ 0.017834] cpuidle: using governor menu362machine # [ 0.018026] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.363machine # [ 0.018042] ASID allocator initialised with 65536 entries364machine # [ 0.019153] Serial: AMBA PL011 UART driver365machine # [ 0.024371] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1366machine # [ 0.024524] printk: console [ttyAMA0] enabled367machine # [ 0.148972] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages368machine # [ 0.148996] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page369machine # [ 0.149002] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages370sshClient # [ 0.167469] usbcore: registered new interface driver usbfs371sshClient # [ 0.167502] usbcore: registered new interface driver hub372machine # [ 0.149006] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page373sshClient # [ 0.167524] usbcore: registered new device driver usb374machine # [ 0.149010] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages375sshClient # [ 0.167806] pps_core: LinuxPPS API ver. 1 registered376machine # [ 0.149014] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page377sshClient # [ 0.167812] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>378machine # [ 0.149019] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages379sshClient # [ 0.167822] PTP clock support registered380sshClient # [ 0.167880] EDAC MC: Ver: 3.0.0381machine # [ 0.149023] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page382sshClient # [ 0.172571] scmi_core: SCMI protocol bus registered383sshClient # [ 0.173540] FPGA manager framework384sshClient # [ 0.174491] vgaarb: loaded385machine # [ 0.156574] fbcon: Taking over console386machine # [ 0.156591] ACPI: Interpreter disabled.387sshClient # [ 0.175128] clocksource: Switched to clocksource arch_sys_counter388sshClient # [ 0.175719] VFS: Disk quotas dquot_6.6.0389sshClient # [ 0.175751] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)390sshClient # [ 0.178136] netfs: FS-Cache loaded391sshClient # [ 0.178253] pnp: PnP ACPI: disabled392machine # [ 0.164420] iommu: Default domain type: Translated393machine # [ 0.164431] iommu: DMA domain TLB invalidation policy: strict mode394machine # [ 0.166188] SCSI subsystem initialized395machine # [ 0.166876] usbcore: registered new interface driver usbfs396machine # [ 0.166905] usbcore: registered new interface driver hub397machine # [ 0.166920] usbcore: registered new device driver usb398machine # [ 0.167221] pps_core: LinuxPPS API ver. 1 registered399sshClient # [ 0.184103] NET: Registered PF_INET protocol family400machine # [ 0.167227] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>401machine # [ 0.167237] PTP clock support registered402sshClient # [ 0.184288] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)403machine # [ 0.167285] EDAC MC: Ver: 3.0.0404machine # [ 0.171885] scmi_core: SCMI protocol bus registered405machine # [ 0.172909] FPGA manager framework406machine # [ 0.173917] vgaarb: loaded407machine # [ 0.174576] clocksource: Switched to clocksource arch_sys_counter408machine # [ 0.179194] VFS: Disk quotas dquot_6.6.0409machine # [ 0.179234] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)410machine # [ 0.180864] netfs: FS-Cache loaded411machine # [ 0.180980] pnp: PnP ACPI: disabled412machine # [ 0.184836] NET: Registered PF_INET protocol family413machine # [ 0.184993] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)414sshClient # [ 0.214853] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)415sshClient # [ 0.214906] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)416sshClient # [ 0.214935] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)417sshClient # [ 0.214983] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)418sshClient # [ 0.215058] TCP: Hash tables configured (established 8192 bind 8192)419sshClient # [ 0.219867] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)420sshClient # [ 0.219933] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)421sshClient # [ 0.219983] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)422sshClient # [ 0.220064] NET: Registered PF_UNIX/PF_LOCAL protocol family423sshClient # [ 0.220086] NET: Registered PF_XDP protocol family424sshClient # [ 0.220109] PCI: CLS 0 bytes, default 64425sshClient # [ 0.220387] Trying to unpack rootfs image as initramfs...426sshClient # [ 0.232262] kvm [1]: HYP mode not available427machine # [ 0.215283] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)428machine # [ 0.215337] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)429machine # [ 0.215363] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)430machine # [ 0.215414] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)431machine # [ 0.215490] TCP: Hash tables configured (established 8192 bind 8192)432machine # [ 0.215581] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)433machine # [ 0.215640] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)434machine # [ 0.215697] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)435machine # [ 0.215779] NET: Registered PF_UNIX/PF_LOCAL protocol family436machine # [ 0.215800] NET: Registered PF_XDP protocol family437machine # [ 0.215822] PCI: CLS 0 bytes, default 64438machine # [ 0.216070] Trying to unpack rootfs image as initramfs...439machine # [ 0.232228] kvm [1]: HYP mode not available440sshClient # [ 0.355764] Initialise system trusted keyrings441sshClient # [ 0.356680] workingset: timestamp_bits=42 max_order=18 bucket_order=0442sshClient # [ 0.357998] squashfs: version 4.0 (2009/01/31) Phillip Lougher443sshClient # [ 0.358768] 9p: Installing v9fs 9p2000 file system support444machine # [ 0.355232] Initialise system trusted keyrings445machine # [ 0.356037] workingset: timestamp_bits=42 max_order=18 bucket_order=0446machine # [ 0.357377] squashfs: version 4.0 (2009/01/31) Phillip Lougher447machine # [ 0.358155] 9p: Installing v9fs 9p2000 file system support448sshClient # [ 0.387519] Key type asymmetric registered449sshClient # [ 0.387551] Asymmetric key parser 'x509' registered450sshClient # [ 0.387627] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)451sshClient # [ 0.389842] io scheduler mq-deadline registered452sshClient # [ 0.389855] io scheduler kyber registered453sshClient # [ 0.399309] pl061_gpio 9030000.pl061: PL061 GPIO chip registered454sshClient # [ 0.400828] ledtrig-cpu: registered to indicate activity on CPUs455sshClient # [ 0.401216] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:456machine # [ 0.386909] Key type asymmetric registered457machine # [ 0.386941] Asymmetric key parser 'x509' registered458sshClient # [ 0.401235] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000459machine # [ 0.387028] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)460sshClient # [ 0.401251] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000461machine # [ 0.389165] io scheduler mq-deadline registered462machine # [ 0.389178] io scheduler kyber registered463sshClient # [ 0.401259] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000464sshClient # [ 0.401282] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits465sshClient # [ 0.401317] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]466sshClient # [ 0.401397] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00467sshClient # [ 0.401407] pci_bus 0000:00: root bus resource [bus 00-ff]468sshClient # [ 0.401413] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]469sshClient # [ 0.401418] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]470sshClient # [ 0.401423] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]471sshClient # [ 0.401517] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint472sshClient # [ 0.401950] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint473sshClient # [ 0.402132] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]474sshClient # [ 0.402148] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]475machine # [ 0.398780] pl061_gpio 9030000.pl061: PL061 GPIO chip registered476sshClient # [ 0.402178] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]477sshClient # [ 0.402194] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]478sshClient # [ 0.402656] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint479machine # [ 0.400350] ledtrig-cpu: registered to indicate activity on CPUs480sshClient # [ 0.402838] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]481machine # [ 0.400790] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:482sshClient # [ 0.402854] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]483sshClient # [ 0.402884] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]484machine # [ 0.400810] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000485machine # [ 0.400827] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000486sshClient # [ 0.422519] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint487machine # [ 0.400836] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000488sshClient # [ 0.422708] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]489sshClient # [ 0.422724] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]490machine # [ 0.400859] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits491sshClient # [ 0.422754] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]492machine # [ 0.400887] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]493machine # [ 0.400981] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00494machine # [ 0.400991] pci_bus 0000:00: root bus resource [bus 00-ff]495machine # [ 0.400997] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]496machine # [ 0.401002] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]497machine # [ 0.401007] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]498machine # [ 0.401099] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint499machine # [ 0.401556] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint500machine # [ 0.401743] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]501machine # [ 0.401759] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]502machine # [ 0.401789] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]503machine # [ 0.401806] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]504machine # [ 0.402269] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint505machine # [ 0.402457] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]506machine # [ 0.402473] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]507machine # [ 0.402502] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]508sshClient # [ 0.431277] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint509machine # [ 0.422274] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint510sshClient # [ 0.431489] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]511machine # [ 0.422466] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]512sshClient # [ 0.431507] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]513machine # [ 0.422482] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]514sshClient # [ 0.431537] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]515machine # [ 0.422512] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]516sshClient # [ 0.432069] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint517sshClient # [ 0.432279] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]518sshClient # [ 0.432295] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]519sshClient # [ 0.432325] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]520sshClient # [ 0.432782] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint521sshClient # [ 0.432967] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]522sshClient # [ 0.432982] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]523sshClient # [ 0.433012] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]524sshClient # [ 0.433464] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint525sshClient # [ 0.433646] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]526sshClient # [ 0.433662] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]527sshClient # [ 0.433692] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]528sshClient # [ 0.433708] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]529sshClient # [ 0.434225] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint530sshClient # [ 0.434412] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]531sshClient # [ 0.434441] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]532sshClient # [ 0.434897] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint533machine # [ 0.431119] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint534sshClient # [ 0.435091] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]535machine # [ 0.431334] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]536sshClient # [ 0.435120] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]537machine # [ 0.431351] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]538sshClient # [ 0.435533] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint539machine # [ 0.431381] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]540sshClient # [ 0.435716] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]541machine # [ 0.431864] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint542sshClient # [ 0.435960] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint543machine # [ 0.432046] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]544sshClient # [ 0.436304] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]545machine # [ 0.432062] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]546sshClient # [ 0.436321] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]547machine # [ 0.432092] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]548sshClient # [ 0.436351] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]549machine # [ 0.432548] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint550sshClient # [ 0.436826] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint551machine # [ 0.432730] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]552sshClient # [ 0.437012] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]553machine # [ 0.432746] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]554sshClient # [ 0.437028] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]555machine # [ 0.432776] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]556sshClient # [ 0.437060] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]557machine # [ 0.433222] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint558sshClient # [ 0.437655] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned559machine # [ 0.433405] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]560sshClient # [ 0.437667] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned561machine # [ 0.433421] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]562sshClient # [ 0.437673] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned563machine # [ 0.433450] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]564sshClient # [ 0.437722] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned565machine # [ 0.433466] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]566sshClient # [ 0.437770] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned567machine # [ 0.433944] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint568machine # [ 0.434133] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]569sshClient # [ 0.437817] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned570machine # [ 0.434163] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]571sshClient # [ 0.437862] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned572machine # [ 0.434634] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint573sshClient # [ 0.437911] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned574machine # [ 0.434818] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]575sshClient # [ 0.437960] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned576machine # [ 0.434848] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]577sshClient # [ 0.438006] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned578machine # [ 0.435247] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint579sshClient # [ 0.438055] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned580machine # [ 0.435428] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]581sshClient # [ 0.438107] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned582machine # [ 0.435684] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint583machine # [ 0.435997] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]584sshClient # [ 0.438191] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned585machine # [ 0.436015] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]586sshClient # [ 0.438244] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned587machine # [ 0.436044] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]588sshClient # [ 0.438269] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned589machine # [ 0.436520] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint590sshClient # [ 0.438293] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned591machine # [ 0.436702] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]592sshClient # [ 0.438318] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned593machine # [ 0.436718] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]594sshClient # [ 0.438343] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned595machine # [ 0.436747] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]596sshClient # [ 0.438369] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned597machine # [ 0.437347] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned598sshClient # [ 0.438391] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned599machine # [ 0.437359] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned600sshClient # [ 0.438413] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned601machine # [ 0.437365] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned602sshClient # [ 0.438435] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned603machine # [ 0.437409] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned604sshClient # [ 0.438457] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned605sshClient # [ 0.438480] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned606machine # [ 0.437457] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned607sshClient # [ 0.438502] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned608machine # [ 0.437503] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned609sshClient # [ 0.438523] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned610machine # [ 0.437549] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned611sshClient # [ 0.438544] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned612machine # [ 0.437594] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned613sshClient # [ 0.438566] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned614sshClient # [ 0.438587] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned615machine # [ 0.437641] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned616sshClient # [ 0.438609] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned617machine # [ 0.437687] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned618sshClient # [ 0.438630] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned619machine # [ 0.437732] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned620sshClient # [ 0.438652] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned621sshClient # [ 0.438674] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned622machine # [ 0.437780] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned623sshClient # [ 0.438696] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned624machine # [ 0.437861] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned625sshClient # [ 0.438721] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]626machine # [ 0.437908] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned627sshClient # [ 0.438731] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]628machine # [ 0.437930] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned629sshClient # [ 0.438736] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]630machine # [ 0.437951] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned631machine # [ 0.437972] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned632machine # [ 0.437994] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned633machine # [ 0.438018] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned634machine # [ 0.438040] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned635machine # [ 0.438062] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned636machine # [ 0.438084] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned637machine # [ 0.438106] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned638machine # [ 0.438128] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned639machine # [ 0.438150] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned640machine # [ 0.438172] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned641machine # [ 0.438194] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned642sshClient # [ 0.504200] pci 0000:00:0a.0: enabling device (0000 -> 0002)643machine # [ 0.438216] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned644machine # [ 0.438238] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned645machine # [ 0.438260] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned646machine # [ 0.438281] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned647machine # [ 0.438305] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned648machine # [ 0.438326] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned649machine # [ 0.438348] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned650machine # [ 0.438375] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]651machine # [ 0.438385] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]652machine # [ 0.438389] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]653machine # [ 0.503707] pci 0000:00:0a.0: enabling device (0000 -> 0002)654sshClient # [ 0.529501] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)655sshClient # [ 0.532519] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)656sshClient # [ 0.534517] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)657machine # [ 0.524480] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)658sshClient # [ 0.548866] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)659sshClient # [ 0.550840] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)660machine # [ 0.534833] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)661sshClient # [ 0.554518] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)662machine # [ 0.537634] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)663sshClient # [ 0.557516] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)664machine # [ 0.548632] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)665sshClient # [ 0.567804] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)666machine # [ 0.551586] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)667sshClient # [ 0.570146] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)668machine # [ 0.553560] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)669sshClient # [ 0.580865] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)670machine # [ 0.564051] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)671machine # [ 0.566344] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)672sshClient # [ 0.584063] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)673machine # [ 0.570316] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)674machine # [ 0.578185] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)675sshClient # [ 0.597381] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled676sshClient # [ 0.600051] msm_serial: driver initialized677sshClient # [ 0.600228] SuperH (H)SCI(F) driver initialized678sshClient # [ 0.600283] STM32 USART driver initialized679machine # [ 0.588257] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)680machine # [ 0.601939] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled681machine # [ 0.604587] msm_serial: driver initialized682machine # [ 0.604732] SuperH (H)SCI(F) driver initialized683machine # [ 0.604787] STM32 USART driver initialized684sshClient # [ 0.630051] loop: module loaded685sshClient # [ 0.630273] virtio_blk virtio5: 1/0/0 default/read/poll queues686sshClient # [ 0.631081] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)687sshClient # [ 0.643903] megasas: 07.734.00.00-rc1688sshClient # [ 0.644743] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]689sshClient # [ 0.646599] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000690sshClient # [ 0.646631] Intel/Sharp Extended Query Table at 0x0031691sshClient # [ 0.648319] Using buffer write method692sshClient # [ 0.648405] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]693sshClient # [ 0.650056] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000694sshClient # [ 0.650083] Intel/Sharp Extended Query Table at 0x0031695machine # [ 0.644345] loop: module loaded696sshClient # [ 0.663731] Using buffer write method697machine # [ 0.644573] virtio_blk virtio5: 1/0/0 default/read/poll queues698sshClient # [ 0.663766] Concatenating MTD devices:699sshClient # [ 0.663770] (0): "0.flash"700sshClient # [ 0.663774] (1): "0.flash"701machine # [ 0.645416] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)702sshClient # [ 0.663777] into device "0.flash"703machine # [ 0.659596] megasas: 07.734.00.00-rc1704machine # [ 0.660361] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]705machine # [ 0.662231] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000706machine # [ 0.662268] Intel/Sharp Extended Query Table at 0x0031707machine # [ 0.663940] Using buffer write method708machine # [ 0.664039] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]709machine # [ 0.665785] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000710machine # [ 0.665852] Intel/Sharp Extended Query Table at 0x0031711machine # [ 0.674999] Using buffer write method712machine # [ 0.675055] Concatenating MTD devices:713machine # [ 0.675059] (0): "0.flash"714machine # [ 0.675063] (1): "0.flash"715machine # [ 0.675067] into device "0.flash"716sshClient # [ 0.943160] Freeing initrd memory: 26104K717sshClient # [ 0.949321] tun: Universal TUN/TAP device driver, 1.6718sshClient # [ 0.953296] thunder_xcv, ver 1.0719sshClient # [ 0.953343] thunder_bgx, ver 1.0720sshClient # [ 0.953366] nicpf, ver 1.0721sshClient # [ 0.953919] e1000: Intel(R) PRO/1000 Network Driver722sshClient # [ 0.953926] e1000: Copyright (c) 1999-2006 Intel Corporation.723sshClient # [ 0.953954] e1000e: Intel(R) PRO/1000 Network Driver724sshClient # [ 0.953962] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.725sshClient # [ 0.953987] igb: Intel(R) Gigabit Ethernet Network Driver726sshClient # [ 0.953993] igb: Copyright (c) 2007-2014 Intel Corporation.727sshClient # [ 0.954018] igbvf: Intel(R) Gigabit Virtual Function Network Driver728sshClient # [ 0.954026] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.729sshClient # [ 0.954158] sky2: driver version 1.30730sshClient # [ 0.962760] usbcore: registered new interface driver usb-storage731sshClient # [ 0.962817] usbcore: registered new interface driver usbserial_generic732sshClient # [ 0.962830] usbserial: USB Serial support registered for generic733sshClient # [ 0.963864] ehci-pci 0000:00:0a.0: EHCI Host Controller734sshClient # [ 0.963891] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1735sshClient # [ 0.964276] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000736sshClient # [ 0.969177] hv_vmbus: registering driver hyperv_keyboard737sshClient # [ 0.970760] rtc-pl031 9010000.pl031: registered as rtc0738sshClient # [ 0.970789] rtc-pl031 9010000.pl031: setting system clock to 2026-09-06T02:31:04 UTC (1788661864)739sshClient # [ 0.971091] i2c_dev: i2c /dev entries driver740machine # [ 0.957334] Freeing initrd memory: 26104K741sshClient # [ 0.976168] sdhci: Secure Digital Host Controller Interface driver742sshClient # [ 0.976200] sdhci: Copyright(c) Pierre Ossman743sshClient # [ 0.976468] Synopsys Designware Multimedia Card Interface Driver744machine # [ 0.963443] tun: Universal TUN/TAP device driver, 1.6745sshClient # [ 0.976834] sdhci-pltfm: SDHCI platform and OF driver helper746sshClient # [ 0.978554] hid: raw HID events driver (C) Jiri Kosina747sshClient # [ 0.978789] usbcore: registered new interface driver usbhid748sshClient # [ 0.978796] usbhid: USB HID core driver749sshClient # [ 0.983632] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00750sshClient # [ 0.984009] hub 1-0:1.0: USB hub found751sshClient # [ 0.984030] hub 1-0:1.0: 6 ports detected752sshClient # [ 0.986869] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available753machine # [ 0.967381] thunder_xcv, ver 1.0754machine # [ 0.967427] thunder_bgx, ver 1.0755machine # [ 0.967453] nicpf, ver 1.0756sshClient # [ 0.989497] drop_monitor: Initializing network drop monitor service757machine # [ 0.967997] e1000: Intel(R) PRO/1000 Network Driver758sshClient # [ 0.989635] NET: Registered PF_INET6 protocol family759machine # [ 0.968004] e1000: Copyright (c) 1999-2006 Intel Corporation.760machine # [ 0.968029] e1000e: Intel(R) PRO/1000 Network Driver761sshClient # [ 0.991582] Segment Routing with IPv6762sshClient # [ 0.991600] In-situ OAM (IOAM) with IPv6763machine # [ 0.968038] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.764sshClient # [ 0.991626] NET: Registered PF_PACKET protocol family765machine # [ 0.968069] igb: Intel(R) Gigabit Ethernet Network Driver766machine # [ 0.968075] igb: Copyright (c) 2007-2014 Intel Corporation.767machine # [ 0.968097] igbvf: Intel(R) Gigabit Virtual Function Network Driver768machine # [ 0.968103] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.769sshClient # [ 0.993264] 9pnet: Installing 9P2000 support770machine # [ 0.968233] sky2: driver version 1.30771machine # [ 0.969775] usbcore: registered new interface driver usb-storage772machine # [ 0.969871] usbcore: registered new interface driver usbserial_generic773machine # [ 0.969884] usbserial: USB Serial support registered for generic774machine # [ 0.970463] hv_vmbus: registering driver hyperv_keyboard775machine # [ 0.971425] ehci-pci 0000:00:0a.0: EHCI Host Controller776sshClient # [ 0.996026] Key type dns_resolver registered777machine # [ 0.971456] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1778machine # [ 0.971767] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000779machine # [ 0.984048] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00780machine # [ 0.984438] hub 1-0:1.0: USB hub found781machine # [ 0.984459] hub 1-0:1.0: 6 ports detected782sshClient # [ 1.002302] registered taskstats version 1783sshClient # [ 1.002472] Loading compiled-in X.509 certificates784machine # [ 0.987496] rtc-pl031 9010000.pl031: registered as rtc0785machine # [ 0.987528] rtc-pl031 9010000.pl031: setting system clock to 2026-09-06T02:31:04 UTC (1788661864)786machine # [ 0.987844] i2c_dev: i2c /dev entries driver787sshClient # [ 1.011126] Demotion targets for Node 0: null788machine # [ 0.992730] sdhci: Secure Digital Host Controller Interface driver789sshClient # [ 1.011311] Key type .fscrypt registered790machine # [ 0.992747] sdhci: Copyright(c) Pierre Ossman791sshClient # [ 1.011338] Key type fscrypt-provisioning registered792machine # [ 0.993011] Synopsys Designware Multimedia Card Interface Driver793sshClient # [ 1.011460] ima: No TPM chip found, activating TPM-bypass!794machine # [ 0.993375] sdhci-pltfm: SDHCI platform and OF driver helper795sshClient # [ 1.011506] ima: Allocated hash algorithm: sha1796sshClient # [ 1.011576] ima: No architecture policies found797machine # [ 0.997809] hid: raw HID events driver (C) Jiri Kosina798sshClient # [ 1.015765] input: gpio-keys as /devices/platform/gpio-keys/input/input0799machine # [ 0.998084] usbcore: registered new interface driver usbhid800machine # [ 0.998091] usbhid: USB HID core driver801machine # [ 1.001040] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available802machine # [ 1.002523] drop_monitor: Initializing network drop monitor service803machine # [ 1.004537] NET: Registered PF_INET6 protocol family804machine # [ 1.005746] Segment Routing with IPv6805machine # [ 1.005767] In-situ OAM (IOAM) with IPv6806machine # [ 1.005796] NET: Registered PF_PACKET protocol family807machine # [ 1.007696] 9pnet: Installing 9P2000 support808machine # [ 1.009902] Key type dns_resolver registered809sshClient # [ 1.034642] clk: Disabling unused clocks810sshClient # [ 1.034679] PM: genpd: Disabling unused power domains811machine # [ 1.017039] registered taskstats version 1812machine # [ 1.017185] Loading compiled-in X.509 certificates813sshClient # [ 1.038934] Freeing unused kernel memory: 4736K814sshClient # [ 1.039769] Run /init as init process815machine # [ 1.025736] Demotion targets for Node 0: null816machine # [ 1.025860] Key type .fscrypt registered817machine # [ 1.025867] Key type fscrypt-provisioning registered818machine # [ 1.025975] ima: No TPM chip found, activating TPM-bypass!819machine # [ 1.025994] ima: Allocated hash algorithm: sha1820machine # [ 1.026025] ima: No architecture policies found821machine # [ 1.030173] input: gpio-keys as /devices/platform/gpio-keys/input/input0822sshClient # [ 1.053502] systemd[1]: Successfully made /usr/ read-only.823machine # [ 1.049049] clk: Disabling unused clocks824machine # [ 1.049084] PM: genpd: Disabling unused power domains825machine # [ 1.053316] Freeing unused kernel memory: 4736K826machine # [ 1.053534] Run /init as init process827machine # [ 1.069016] systemd[1]: Successfully made /usr/ read-only.828sshClient # [ 1.231259] usb 1-1: new high-speed USB device number 2 using ehci-pci829machine # [ 1.230699] usb 1-1: new high-speed USB device number 2 using ehci-pci830sshClient # [ 1.385753] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1831machine # [ 1.383629] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1832sshClient # [ 1.392158] systemd[1]: systemd 262-9457f81 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)833sshClient # [ 1.392252] systemd[1]: Detected virtualization qemu.834sshClient # [ 1.392336] systemd[1]: Detected architecture arm64.835sshClient # [ 1.392360] systemd[1]: Running in initrd.836sshClient # [ 1.393331] systemd[1]: Initializing machine ID from random generator.837sshClient # [ 1.393622] systemd[1]: Hostname set to <sshClient>.838machine # [ 1.403901] systemd[1]: systemd 262-9457f81 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)839machine # [ 1.412331] systemd[1]: Detected virtualization qemu.840machine # [ 1.413694] systemd[1]: Detected architecture arm64.841machine # [ 1.415136] systemd[1]: Running in initrd.842machine # [ 1.416931] systemd[1]: Initializing machine ID from random generator.843machine # [ 1.418954] systemd[1]: Hostname set to <machine>.844sshClient # [ 1.463463] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0845machine # [ 1.478836] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0846sshClient # [ 1.571104] systemd[1]: bpf-restrict-fs: LSM BPF program attached847machine # [ 1.555477] systemd[1]: bpf-restrict-fs: LSM BPF program attached848sshClient # [ 1.587222] usb 1-2: new high-speed USB device number 3 using ehci-pci849machine # [ 1.598629] usb 1-2: new high-speed USB device number 3 using ehci-pci850sshClient # [ 1.628274] systemd[1]: Queued start job for default target Initrd Default Target.851machine # [ 1.612833] systemd[1]: Queued start job for default target Initrd Default Target.852sshClient # [ 1.637392] systemd[1]: Created slice Slice /system/modprobe.853machine # [ 1.620849] systemd[1]: Created slice Slice /system/modprobe.854sshClient # [ 1.638420] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.855sshClient # [ 1.639658] systemd[1]: Expecting device /dev/disk/by-label/nixos...856machine # [ 1.622329] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.857sshClient # [ 1.640554] systemd[1]: Reached target Path Units.858machine # [ 1.623690] systemd[1]: Expecting device /dev/disk/by-label/nixos...859sshClient # [ 1.641231] systemd[1]: Reached target Slice Units.860machine # [ 1.624618] systemd[1]: Reached target Path Units.861sshClient # [ 1.641927] systemd[1]: Reached target Swaps.862machine # [ 1.625329] systemd[1]: Reached target Slice Units.863sshClient # [ 1.642556] systemd[1]: Reached target Timer Units.864machine # [ 1.626105] systemd[1]: Reached target Swaps.865sshClient # [ 1.643422] systemd[1]: Listening on D-Bus System Message Bus Socket.866machine # [ 1.626830] systemd[1]: Reached target Timer Units.867sshClient # [ 1.644486] systemd[1]: Listening on Journal Socket (/dev/log).868machine # [ 1.627742] systemd[1]: Listening on D-Bus System Message Bus Socket.869sshClient # [ 1.645426] systemd[1]: Listening on Journal Sockets.870machine # [ 1.628877] systemd[1]: Listening on Journal Socket (/dev/log).871sshClient # [ 1.646226] systemd[1]: Listening on udev Kernel Socket.872sshClient # [ 1.647050] systemd[1]: Reached target Socket Units.873machine # [ 1.629907] systemd[1]: Listening on Journal Sockets.874machine # [ 1.630778] systemd[1]: Listening on udev Kernel Socket.875machine # [ 1.631579] systemd[1]: Reached target Socket Units.876sshClient # [ 1.649347] systemd[1]: Starting Create List of Static Device Nodes...877machine # [ 1.634122] systemd[1]: Starting Create List of Static Device Nodes...878sshClient # [ 1.655798] systemd[1]: Starting Load Kernel Module 9pnet_virtio...879sshClient # [ 1.656774] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs880machine # [ 1.642865] systemd[1]: Starting Load Kernel Module 9pnet_virtio...881machine # [ 1.642994] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs882sshClient # [ 1.663559] systemd[1]: Mounting Kernel Configuration File System...883machine # [ 1.651013] systemd[1]: Mounting Kernel Configuration File System...884sshClient # [ 1.687424] systemd[1]: Starting Journal Service...885machine # [ 1.682968] systemd[1]: Starting Journal Service...886sshClient # [ 1.702886] systemd[1]: Starting Load Kernel Modules...887sshClient # [ 1.703993] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os888machine # [ 1.693637] systemd[1]: Starting Load Kernel Modules...889machine # [ 1.694726] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os890sshClient # [ 1.716644] systemd[1]: Starting Coldplug All udev Devices...891machine # [ 1.704406] systemd[1]: Starting Coldplug All udev Devices...892sshClient # [ 1.739350] systemd[1]: Finished Create List of Static Device Nodes.893sshClient # [ 1.740568] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.894sshClient # [ 1.740877] systemd[1]: Finished Load Kernel Module 9pnet_virtio.895sshClient # [ 1.741112] systemd[1]: Mounted Kernel Configuration File System.896machine # [ 1.726837] systemd[1]: Finished Create List of Static Device Nodes.897machine # [ 1.728228] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.898sshClient # [ 1.748687] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...899machine # [ 1.728587] systemd[1]: Finished Load Kernel Module 9pnet_virtio.900machine # [ 1.728871] systemd[1]: Mounted Kernel Configuration File System.901sshClient # [ 1.751793] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2902sshClient # [ 1.751943] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0903sshClient # [ 1.764909] systemd-journald[73]: Collecting audit messages is disabled.904machine # [ 1.749994] systemd-journald[73]: Collecting audit messages is disabled.905machine # [ 1.752586] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2906machine # [ 1.752754] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0907sshClient # [ 1.775579] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.908machine # [ 1.764011] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.909machine # [ 1.764529] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...910sshClient # [ 1.787253] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev911sshClient # [ 1.799739] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0912sshClient # [ 1.799995] [drm] features: -virgl +edid -resource_blob -host_visible913sshClient # [ 1.800005] [drm] features: -context_init914sshClient # [ 1.800774] [drm] number of scanouts: 1915sshClient # [ 1.800793] [drm] number of cap sets: 0916machine # [ 1.787132] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev917machine # [ 1.792357] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0918machine # [ 1.792610] [drm] features: -virgl +edid -resource_blob -host_visible919machine # [ 1.792621] [drm] features: -context_init920machine # [ 1.793369] [drm] number of scanouts: 1921machine # [ 1.793388] [drm] number of cap sets: 0922sshClient # [ 1.823543] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic923sshClient # [ 1.823572] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0924machine # [ 1.812098] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic925machine # [ 1.812130] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0926machine # [ 1.819490] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.927sshClient # [ 1.843928] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.928machine # [ 1.827080] systemd[1]: Starting Create Static Device Nodes in /dev...929sshClient # [ 1.846564] Console: switching to colour frame buffer device 160x50930sshClient # [ 1.855330] systemd[1]: Starting Create Static Device Nodes in /dev...931machine # [ 1.851095] Console: switching to colour frame buffer device 160x50932sshClient # [ 1.875900] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device933sshClient # [ 1.893292] systemd[1]: Finished Load Kernel Modules.934sshClient # [ 1.896325] systemd[1]: Starting Apply Kernel Variables...935machine # [ 1.883276] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device936machine # [ 1.890751] systemd[1]: Finished Create Static Device Nodes in /dev.937machine # [ 1.891269] systemd[1]: Reached target Preparation for Local File Systems.938machine # [ 1.891311] systemd[1]: Reached target Local File Systems.939machine # [ 1.898069] systemd[1]: Starting Rule-based Manager for Device Events and Files...940machine # [ 1.919354] systemd[1]: Finished Load Kernel Modules.941sshClient # [ 1.935675] systemd[1]: Finished Create Static Device Nodes in /dev.942sshClient # [ 1.936276] systemd[1]: Reached target Preparation for Local File Systems.943sshClient # [ 1.936321] systemd[1]: Reached target Local File Systems.944sshClient # [ 1.943684] systemd[1]: Starting Rule-based Manager for Device Events and Files...945machine # [ 1.927282] systemd[1]: Starting Apply Kernel Variables...946sshClient # [ 1.940488] systemd-modules-load[74]: Inserted module 'dm_mod'947sshClient # [ 1.944392] systemd-modules-load[74]: Module 'virtio_balloon' is built in948sshClient # [ 1.945784] systemd-modules-load[74]: Module 'virtio_console' is built in949sshClient # [ 1.946900] systemd-modules-load[74]: Inserted module 'virtio_gpu'950sshClient # [ 1.947936] systemd-modules-load[74]: Module 'virtio_rng' is built in951sshClient # [ 1.966907] systemd[1]: Started Journal Service.952machine # [ 1.942174] systemd-modules-load[74]: Inserted module 'dm_mod'953machine # [ 1.958226] systemd[1]: Started Journal Service.954sshClient # [ 1.963955] systemd[1]: Finished Apply Kernel Variables.955machine # [ 1.949580] systemd-modules-load[74]: Module 'virtio_balloon' is built in956machine # [ 1.961371] systemd-modules-load[74]: Module 'virtio_console' is built in957machine # [ 1.968518] systemd-modules-load[74]: Inserted module 'virtio_gpu'958sshClient # [ 1.988730] systemd[1]: Starting Create System Files and Directories...959machine # [ 1.976480] systemd-modules-load[74]: Module 'virtio_rng' is built in960machine # [ 1.978498] systemd[1]: Starting Create System Files and Directories...961machine # [ 1.984561] systemd[1]: Finished Apply Kernel Variables.962sshClient # [ 2.033989] systemd-udevd[81]: Using default interface naming scheme 'v261'.963machine # [ 2.023167] systemd[1]: Finished Create System Files and Directories.964sshClient # [ 2.044994] systemd[1]: Finished Create System Files and Directories.965machine # [ 2.029786] systemd-udevd[80]: Using default interface naming scheme 'v261'.966sshClient # [ 2.070085] systemd[1]: Started Rule-based Manager for Device Events and Files.967machine # [ 2.060395] systemd[1]: Started Rule-based Manager for Device Events and Files.968machine # [ 2.133625] systemd[1]: Starting Virtual Console Setup...969sshClient # [ 2.152842] systemd[1]: Starting Virtual Console Setup...970sshClient # [ 2.228585] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.971machine # [ 2.212943] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.972sshClient # [ 2.232389] systemd[1]: Finished Virtual Console Setup.973machine # [ 2.216410] systemd[1]: Finished Virtual Console Setup.974machine # [ 2.829577] systemd[1]: Finished Coldplug All udev Devices.975sshClient # [ 2.846281] systemd[1]: Finished Coldplug All udev Devices.976machine # [ 2.830544] systemd[1]: Reached target System Initialization.977sshClient # [ 2.847234] systemd[1]: Reached target System Initialization.978machine # [ 2.831386] systemd[1]: Reached target Basic System.979sshClient # [ 2.849459] systemd[1]: Reached target Basic System.980machine # [ 2.968101] (udev-worker)[101]: Network interface NamePolicy= disabled on kernel command line.981sshClient # [ 3.004469] (udev-worker)[99]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.982machine # [ 3.003248] (udev-worker)[96]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.983sshClient # [ 3.021546] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.984machine # [ 3.008221] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.985sshClient # [ 3.039775] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.986sshClient # [ 3.095649] systemd[1]: Found device /dev/disk/by-label/nixos.987machine # [ 3.081035] systemd[1]: Found device /dev/disk/by-label/nixos.988sshClient # [ 3.099971] systemd[1]: Reached target Initrd Root Device.989machine # [ 3.084871] systemd[1]: Reached target Initrd Root Device.990sshClient # [ 3.102235] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...991machine # [ 3.089265] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...992sshClient # [ 3.160539] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks993machine # [ 3.150969] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks994machine # [ 3.800752] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.995sshClient # [ 3.817456] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.996machine # [ 3.803529] systemd[1]: Mounting /sysroot...997sshClient # [ 3.828429] systemd[1]: Mounting /sysroot...998machine # [ 3.874222] EXT4-fs (vda): mounted filesystem 5f304e20-4ac6-4c80-9ecb-de03ea1b9078 r/w with ordered data mode. Quota mode: none.999sshClient # [ 3.892965] EXT4-fs (vda): mounted filesystem 257e409d-cd9e-4181-8262-03a8a67c3875 r/w with ordered data mode. Quota mode: none.1000machine # [ 3.868906] systemd[1]: Mounted /sysroot.1001sshClient # [ 3.886272] systemd[1]: Mounted /sysroot.1002machine # [ 3.871036] systemd[1]: Reached target Initrd Root File System.1003sshClient # [ 3.887993] systemd[1]: Reached target Initrd Root File System.1004machine # [ 3.877341] systemd[1]: Starting Mountpoints Configured in the Real Root...1005sshClient # [ 3.894867] systemd[1]: Mounting /sysroot/nix/.ro-store...1006sshClient # [ 3.906848] systemd[1]: Mounting /sysroot/nix/.rw-store...1007sshClient # [ 3.931171] systemd[1]: Mounting /sysroot/run...1008machine # [ 3.914470] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.1009sshClient # [ 3.940206] systemd[1]: Mounting /sysroot/tmp/shared...1010machine # [ 3.923122] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...1011machine # [ 3.926590] systemd[1]: Reloading...1012sshClient # [ 3.967429] systemd[1]: Mounting /sysroot/tmp/xchg...1013sshClient # [ 3.995618] systemd[1]: Starting Mountpoints Configured in the Real Root...1014sshClient # [ 4.037166] systemd[1]: Mounted /sysroot/nix/.ro-store.1015sshClient # [ 4.071828] systemd[1]: Mounted /sysroot/nix/.rw-store.1016sshClient # [ 4.080433] systemd-sysroot-fstab-check[125]: /sysroot should be mounted in the initrd, will request daemon-reload.1017sshClient # [ 4.085459] systemd[1]: Mounted /sysroot/run.1018sshClient # [ 4.091407] systemd[1]: Mounted /sysroot/tmp/xchg.1019sshClient # [ 4.094651] systemd[1]: Mounted /sysroot/tmp/shared.1020sshClient # [ 4.108602] systemd[1]: Starting rw-sysroot-nix-store.service...1021sshClient # [ 4.114338] systemd[1]: Reload requested from client PID 125 ('systemd-sysroot') (unit initrd-parse-etc.service)...1022sshClient # [ 4.120223] systemd[1]: Reloading...1023machine # [ 4.146114] systemd[1]: Reloading finished in 225 ms.1024machine # [ 4.178958] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...1025machine # [ 4.188116] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...1026machine # [ 4.192323] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1027machine # [ 4.206728] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1028machine # [ 4.212975] systemd[1]: Finished Mountpoints Configured in the Real Root.1029machine # [ 4.216105] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1030machine # [ 4.227195] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1031machine # [ 4.230860] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1032machine # [ 4.313462] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/x4i8f5p0japrw9j9zrdx7z9lf5387qn3-systemd-262-9457f81/lib/udev/mtd_probe": No such file or directory1033machine # [ 4.319376] (udev-worker)[93]: mtd0ro: /nix/store/x4i8f5p0japrw9j9zrdx7z9lf5387qn3-systemd-262-9457f81/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1034sshClient # [ 4.353209] systemd[1]: Reloading finished in 234 ms.1035machine # [ 4.346834] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1036machine # [ 4.348098] systemd[1]: Stopped Virtual Console Setup.1037machine # [ 4.352286] systemd[1]: Stopping Virtual Console Setup...1038machine # [ 4.356339] systemd[1]: Starting Virtual Console Setup...1039machine # [ 4.377096] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1040sshClient # [ 4.394385] systemd-sysroot-fstab-check[125]: Requesting initrd-fs.target/start/replace...1041machine # [ 4.378722] systemd[1]: Stopped Virtual Console Setup.1042sshClient # [ 4.397546] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1043machine # [ 4.381250] systemd[1]: Starting Virtual Console Setup...1044sshClient # [ 4.404490] systemd[1]: Finished rw-sysroot-nix-store.service.1045sshClient # [ 4.407536] systemd-sysroot-fstab-check[125]: Requesting swap.target/start/replace...1046sshClient # [ 4.421267] systemd[1]: Starting rw-sysroot-nix-store.service...1047machine # [ 4.406565] systemd-vconsole-setup[143]: Configuration of first virtual console was skipped, ignoring remaining ones.1048machine # [ 4.410656] systemd[1]: Finished Virtual Console Setup.1049sshClient # [ 4.428180] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1050sshClient # [ 4.434670] systemd[1]: Finished Mountpoints Configured in the Real Root.1051sshClient # [ 4.436201] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1052sshClient # [ 4.463067] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1053sshClient # [ 4.464570] systemd[1]: Finished rw-sysroot-nix-store.service.1054sshClient # [ 4.542825] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/x4i8f5p0japrw9j9zrdx7z9lf5387qn3-systemd-262-9457f81/lib/udev/mtd_probe": No such file or directory1055sshClient # [ 4.550752] (udev-worker)[97]: mtd0ro: /nix/store/x4i8f5p0japrw9j9zrdx7z9lf5387qn3-systemd-262-9457f81/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1056sshClient # [ 4.576580] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1057sshClient # [ 4.579137] systemd[1]: Stopped Virtual Console Setup.1058sshClient # [ 4.583326] systemd[1]: Stopping Virtual Console Setup...1059sshClient # [ 4.585608] systemd[1]: Starting Virtual Console Setup...1060sshClient # [ 4.595015] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1061sshClient # [ 4.596791] systemd[1]: Stopped Virtual Console Setup.1062sshClient # [ 4.605346] systemd[1]: Starting Virtual Console Setup...1063sshClient # [ 4.634238] systemd-vconsole-setup[156]: Configuration of first virtual console was skipped, ignoring remaining ones.1064sshClient # [ 4.637835] systemd[1]: Finished Virtual Console Setup.1065machine # [ 4.804172] systemd[1]: Mounting /sysroot/nix/.ro-store...1066machine # [ 4.809869] systemd[1]: Mounting /sysroot/nix/.rw-store...1067sshClient # [ 4.826210] systemd[1]: Mounting /sysroot/nix/store...1068machine # [ 4.820337] systemd[1]: Mounting /sysroot/run...1069machine # [ 4.836453] systemd[1]: Mounting /sysroot/tmp/shared...1070machine # [ 4.856368] systemd[1]: Mounting /sysroot/tmp/xchg...1071sshClient # [ 4.887973] systemd[1]: Mounted /sysroot/nix/store.1072machine # [ 4.874243] systemd[1]: Mounted /sysroot/nix/.ro-store.1073sshClient # [ 4.891836] systemd[1]: Reached target Initrd File Systems.1074machine # [ 4.878257] systemd[1]: Mounted /sysroot/nix/.rw-store.1075sshClient # [ 4.897395] systemd[1]: Starting Find NixOS closure...1076machine # [ 4.883035] systemd[1]: Mounted /sysroot/run.1077sshClient # [ 4.906271] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1078machine # [ 4.895383] systemd[1]: Starting rw-sysroot-nix-store.service...1079machine # [ 4.900936] systemd[1]: Mounted /sysroot/tmp/shared.1080machine # [ 4.902924] systemd[1]: Mounted /sysroot/tmp/xchg.1081machine # [ 4.921107] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1082machine # [ 4.922749] systemd[1]: Finished rw-sysroot-nix-store.service.1083sshClient # [ 4.958385] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1084sshClient # [ 4.963578] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1085sshClient # [ 4.978889] systemd[1]: Finished Find NixOS closure.1086sshClient # [ 4.982344] systemd[1]: Reached target Initrd Default Target.1087sshClient # [ 4.984402] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1088sshClient # [ 5.018921] systemd[1]: Stopped target Initrd Default Target.1089sshClient # [ 5.024745] systemd[1]: Stopped target Basic System.1090sshClient # [ 5.025738] systemd[1]: Stopped target Initrd Root Device.1091sshClient # [ 5.026679] systemd[1]: Stopped target Path Units.1092sshClient # [ 5.027481] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1093sshClient # [ 5.030293] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1094sshClient # [ 5.036302] systemd[1]: Stopped target Slice Units.1095sshClient # [ 5.037374] systemd[1]: Stopped target Socket Units.1096sshClient # [ 5.038175] systemd[1]: Stopped target System Initialization.1097sshClient # [ 5.039076] systemd[1]: Stopped target Swaps.1098sshClient # [ 5.040746] systemd[1]: Stopped target Timer Units.1099sshClient # [ 5.042700] systemd[1]: dbus.socket: Deactivated successfully.1100sshClient # [ 5.044498] systemd[1]: Closed D-Bus System Message Bus Socket.1101sshClient # [ 5.048216] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1102sshClient # [ 5.049475] systemd[1]: Stopped Find NixOS closure.1103sshClient # [ 5.050968] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1104sshClient # [ 5.055767] systemd[1]: Starting rw-sysroot-nix-store.service...1105sshClient # [ 5.058589] systemd[1]: systemd-sysctl.service: Deactivated successfully.1106sshClient # [ 5.064272] systemd[1]: Stopped Apply Kernel Variables.1107sshClient # [ 5.073385] systemd[1]: systemd-modules-load.service: Deactivated successfully.1108sshClient # [ 5.080410] systemd[1]: Stopped Load Kernel Modules.1109sshClient # [ 5.086748] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1110sshClient # [ 5.091664] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1111sshClient # [ 5.098739] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1112sshClient # [ 5.099873] systemd[1]: Stopped Create System Files and Directories.1113sshClient # [ 5.104324] systemd[1]: Stopped target Local File Systems.1114sshClient # [ 5.106638] systemd[1]: Stopped target Preparation for Local File Systems.1115sshClient # [ 5.108174] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1116sshClient # [ 5.111018] systemd[1]: Stopped Coldplug All udev Devices.1117sshClient # [ 5.112628] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1118sshClient # [ 5.116589] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1119sshClient # [ 5.117859] systemd[1]: Stopped Virtual Console Setup.1120sshClient # [ 5.120516] systemd[1]: initrd-cleanup.service: Deactivated successfully.1121sshClient # [ 5.124341] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1122sshClient # [ 5.125425] systemd[1]: systemd-udevd.service: Deactivated successfully.1123sshClient # [ 5.126383] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1124sshClient # [ 5.128531] systemd[1]: systemd-udevd.service: Consumed 1.581s CPU time over 3.175s wall clock time, 22.1M memory peak.1125sshClient # [ 5.132302] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1126sshClient # [ 5.134218] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1127sshClient # [ 5.135295] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1128sshClient # [ 5.136528] systemd[1]: Finished rw-sysroot-nix-store.service.1129sshClient # [ 5.137418] systemd[1]: Starting Cleanup udev Database...1130sshClient # [ 5.138251] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1131sshClient # [ 5.139313] systemd[1]: Stopped Create Static Device Nodes in /dev.1132sshClient # [ 5.144378] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1133sshClient # [ 5.146624] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1134sshClient # [ 5.149263] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1135sshClient # [ 5.152287] systemd[1]: Stopped Create List of Static Device Nodes.1136sshClient # [ 5.184984] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1137sshClient # [ 5.186820] systemd[1]: Finished Cleanup udev Database.1138sshClient # [ 5.189317] systemd[1]: Reached target Switch Root.1139sshClient # [ 5.191875] systemd[1]: Starting NixOS Activation...1140sshClient # [ 5.430095] initrd-nixos-activation-start[181]: booting system configuration /nix/store/gbrdl7vk6alrzvkgilgjjib0apxf3048-nixos-system-sshClient-test1141sshClient # [ 5.514950] initrd-nixos-activation-start[181]: running activation script...1142machine # [ 5.804747] systemd[1]: Mounting /sysroot/nix/store...1143machine # [ 5.878872] systemd[1]: Mounted /sysroot/nix/store.1144machine # [ 5.884504] systemd[1]: Reached target Initrd File Systems.1145machine # [ 5.891205] systemd[1]: Starting Find NixOS closure...1146machine # [ 5.907039] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1147machine # [ 5.951598] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1148machine # [ 5.953955] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1149machine # [ 5.970891] systemd[1]: Finished Find NixOS closure.1150machine # [ 5.972392] systemd[1]: Reached target Initrd Default Target.1151sshClient # [ 5.990847] initrd-nixos-activation-start[204]: setting up /etc...1152machine # [ 5.974817] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1153machine # [ 6.006189] systemd[1]: Stopped target Initrd Default Target.1154machine # [ 6.008605] systemd[1]: Stopped target Basic System.1155machine # [ 6.010548] systemd[1]: Stopped target Initrd Root Device.1156machine # [ 6.012943] systemd[1]: Stopped target Path Units.1157machine # [ 6.015771] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1158machine # [ 6.017864] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1159machine # [ 6.024236] systemd[1]: Stopped target Slice Units.1160machine # [ 6.025160] systemd[1]: Stopped target Socket Units.1161machine # [ 6.025910] systemd[1]: Stopped target System Initialization.1162machine # [ 6.026752] systemd[1]: Stopped target Swaps.1163machine # [ 6.027417] systemd[1]: Stopped target Timer Units.1164machine # [ 6.029394] systemd[1]: dbus.socket: Deactivated successfully.1165machine # [ 6.030620] systemd[1]: Closed D-Bus System Message Bus Socket.1166machine # [ 6.032640] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1167machine # [ 6.040202] systemd[1]: Stopped Find NixOS closure.1168machine # [ 6.044161] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1169machine # [ 6.045196] systemd[1]: Starting rw-sysroot-nix-store.service...1170machine # [ 6.046036] systemd[1]: systemd-sysctl.service: Deactivated successfully.1171machine # [ 6.047449] systemd[1]: Stopped Apply Kernel Variables.1172machine # [ 6.051062] systemd[1]: systemd-modules-load.service: Deactivated successfully.1173machine # [ 6.055042] systemd[1]: Stopped Load Kernel Modules.1174machine # [ 6.055808] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1175machine # [ 6.058058] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1176machine # [ 6.071459] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1177machine # [ 6.076120] systemd[1]: Stopped Create System Files and Directories.1178machine # [ 6.080385] systemd[1]: Stopped target Local File Systems.1179machine # [ 6.084001] systemd[1]: Stopped target Preparation for Local File Systems.1180machine # [ 6.086498] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1181machine # [ 6.088320] systemd[1]: Stopped Coldplug All udev Devices.1182machine # [ 6.091186] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1183machine # [ 6.092659] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1184machine # [ 6.096334] systemd[1]: Stopped Virtual Console Setup.1185machine # [ 6.097190] systemd[1]: systemd-udevd.service: Deactivated successfully.1186machine # [ 6.098459] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1187machine # [ 6.100985] systemd[1]: systemd-udevd.service: Consumed 1.521s CPU time over 4.170s wall clock time, 22M memory peak.1188machine # [ 6.104274] systemd[1]: initrd-cleanup.service: Deactivated successfully.1189machine # [ 6.108110] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1190machine # [ 6.109154] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1191machine # [ 6.110143] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1192machine # [ 6.110978] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1193machine # [ 6.111955] systemd[1]: Finished rw-sysroot-nix-store.service.1194machine # [ 6.113048] systemd[1]: Starting Cleanup udev Database...1195machine # [ 6.113830] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1196machine # [ 6.114862] systemd[1]: Stopped Create Static Device Nodes in /dev.1197machine # [ 6.115739] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1198machine # [ 6.117126] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1199machine # [ 6.118105] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1200machine # [ 6.119061] systemd[1]: Stopped Create List of Static Device Nodes.1201machine # [ 6.160574] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1202machine # [ 6.162261] systemd[1]: Finished Cleanup udev Database.1203machine # [ 6.164586] systemd[1]: Reached target Switch Root.1204machine # [ 6.167208] systemd[1]: Starting NixOS Activation...1205sshClient # [ 6.269463] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1206sshClient # [ 6.272207] systemd[1]: Finished NixOS Activation.1207sshClient # [ 6.273689] systemd[1]: Starting Switch Root...1208sshClient # [ 6.297088] systemd[1]: Switching root.1209machine # [ 6.344551] initrd-nixos-activation-start[181]: booting system configuration /nix/store/a268jj7i274vaw5h84m9kw8hbwdp3ryl-nixos-system-machine-test1210machine # [ 6.411201] initrd-nixos-activation-start[181]: running activation script...1211sshClient # [ 6.494784] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1212machine # [ 6.842963] initrd-nixos-activation-start[204]: setting up /etc...1213sshClient # [ 7.168118] systemd[1]: systemd 262-9457f81 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)1214sshClient # [ 7.180682] systemd[1]: Detected virtualization qemu.1215machine # [ 7.151117] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1216machine # [ 7.154392] systemd[1]: Finished NixOS Activation.1217sshClient # [ 7.183714] systemd[1]: Detected architecture arm64.1218machine # [ 7.155763] systemd[1]: Starting Switch Root...1219sshClient # [ 7.187526] systemd[1]: Detected first boot.1220sshClient # [ 7.196350] systemd[1]: Initializing machine ID from random generator.1221machine # [ 7.182540] systemd[1]: Switching root.1222machine # [ 7.378792] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1223sshClient # [ 7.522867] systemd[1]: bpf-restrict-fs: LSM BPF program attached1224sshClient # [ 7.762645] systemd[1]: Applying preset policy.1225machine # [ 7.976071] systemd[1]: systemd 262-9457f81 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)1226machine # [ 7.988827] systemd[1]: Detected virtualization qemu.1227machine # [ 7.992330] systemd[1]: Detected architecture arm64.1228machine # [ 7.996605] systemd[1]: Detected first boot.1229machine # [ 8.004563] systemd[1]: Initializing machine ID from random generator.1230machine # [ 8.185281] systemd[1]: bpf-restrict-fs: LSM BPF program attached1231sshClient # [ 8.330367] systemd[1]: Populated /etc with preset unit settings.1232machine # [ 8.341191] NET: Registered PF_VSOCK protocol family1233machine # [ 8.349430] Guest personality initialized and is inactive1234machine # [ 8.351421] VMCI host device registered (name=vmci, major=10, minor=261)1235machine # [ 8.351450] Initialized host personality1236sshClient # [ 8.909681] systemd[1]: initrd-switch-root.service: Deactivated successfully.1237sshClient # [ 8.911221] systemd[1]: Stopped initrd-switch-root.service.1238sshClient # [ 8.914747] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1239sshClient # [ 8.918897] systemd[1]: Created slice Slice /system/getty.1240sshClient # [ 8.921587] systemd[1]: Created slice User and Session Slice.1241sshClient # [ 8.922974] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1242sshClient # [ 8.925090] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1243sshClient # [ 8.927007] systemd[1]: Expecting device /dev/hvc0...1244sshClient # [ 8.928628] systemd[1]: Expecting device /dev/ttyAMA0...1245sshClient # [ 8.930277] systemd[1]: Reached target Local Encrypted Volumes.1246sshClient # [ 8.933040] systemd[1]: Stopped target initrd-fs.target.1247sshClient # [ 8.934209] systemd[1]: Stopped target initrd-root-fs.target.1248sshClient # [ 8.936142] systemd[1]: Stopped target initrd-switch-root.target.1249sshClient # [ 8.937900] systemd[1]: Reached target Virtual Machines and Containers.1250sshClient # [ 8.939826] systemd[1]: Reached target Path Units.1251sshClient # [ 8.941442] systemd[1]: Reached target Remote File Systems.1252sshClient # [ 8.943443] systemd[1]: Reached target Slice Units.1253sshClient # [ 8.945587] systemd[1]: Reached target Swaps.1254sshClient # [ 8.949487] systemd[1]: Listening on Query the User Interactively for a Password.1255sshClient # [ 8.954424] systemd[1]: Listening on Process Core Dump Socket.1256sshClient # [ 8.959033] systemd[1]: Listening on Credential Encryption/Decryption.1257sshClient # [ 8.963572] systemd[1]: Listening on Factory Reset Management.1258sshClient # [ 8.964985] systemd[1]: Listening on Hostname Service Socket.1259sshClient # [ 8.972295] systemd[1]: Starting Journal Log Access Socket...1260sshClient # [ 8.974231] systemd[1]: Listening on Journal Audit Socket.1261sshClient # [ 8.978990] systemd[1]: Listening on Console Output Muting Service Socket.1262sshClient # [ 8.980917] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1263sshClient # [ 8.982984] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1264sshClient # [ 8.986119] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1265sshClient # [ 8.998956] systemd[1]: Listening on Disk Repartitioning Service Socket.1266sshClient # [ 9.000713] systemd[1]: Listening on udev Varlink Socket.1267machine # [ 8.983127] systemd[1]: Applying preset policy.1268sshClient # [ 9.002421] systemd[1]: Listening on User Database Manager Socket.1269sshClient # [ 9.007998] systemd[1]: Mounting Huge Pages File System...1270sshClient # [ 9.013404] systemd[1]: Mounting POSIX Message Queue File System...1271sshClient # [ 9.023857] systemd[1]: Mounting Kernel Debug File System...1272sshClient # [ 9.042331] systemd[1]: Mounting Kernel Trace File System...1273sshClient # [ 9.052965] systemd[1]: Starting Create List of Static Device Nodes...1274sshClient # [ 9.072072] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1275sshClient # [ 9.074145] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1276sshClient # [ 9.090849] systemd[1]: Mounting Kernel Configuration File System...1277sshClient # [ 9.093195] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1278sshClient # [ 9.099659] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1279sshClient # [ 9.188951] systemd[1]: Starting Load Kernel Module fuse...1280sshClient # [ 9.193080] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671281sshClient # [ 9.224927] systemd[1]: Starting Journal Service...1282sshClient # [ 9.253683] systemd[1]: Starting Load Kernel Modules...1283sshClient # [ 9.289790] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1284sshClient # [ 9.326659] fuse: init (API version 7.45)1285sshClient # [ 9.328155] systemd[1]: Starting Remount Root and Kernel File Systems...1286sshClient # [ 9.328598] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1287sshClient # [ 9.344025] systemd[1]: Starting Coldplug All udev Devices...1288sshClient # [ 9.375761] systemd-journald[276]: Collecting audit messages is enabled.1289sshClient # [ 9.377069] systemd[1]: Queued start job for default target Multi-User System.1290sshClient # [ 9.384281] systemd[1]: systemd-journald.service: Deactivated successfully.1291sshClient # [ 9.401422] systemd[1]: Started Journal Service.1292sshClient # [ 9.395009] systemd-modules-load[277]: Module 'atkbd' is built in1293sshClient # [ 9.402419] systemd-modules-load[277]: Module 'loop' is built in1294sshClient # [ 9.404784] systemd[1]: Listening on Journal Log Access Socket.1295sshClient # [ 9.409932] systemd[1]: Mounted Huge Pages File System.1296sshClient # [ 9.410776] systemd[1]: Mounted POSIX Message Queue File System.1297sshClient # [ 9.411642] systemd[1]: Mounted Kernel Debug File System.1298sshClient # [ 9.416608] systemd[1]: Mounted Kernel Trace File System.1299sshClient # [ 9.424498] systemd[1]: Finished Create List of Static Device Nodes.1300sshClient # [ 9.426945] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1301sshClient # [ 9.430011] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1302sshClient # [ 9.436853] systemd[1]: Mounted Kernel Configuration File System.1303sshClient # [ 9.441270] systemd[1]: modprobe@fuse.service: Deactivated successfully.1304sshClient # [ 9.442287] systemd[1]: Finished Load Kernel Module fuse.1305sshClient # [ 9.443082] systemd[1]: Finished Load Kernel Modules.1306sshClient # [ 9.471930] systemd-oomd[279]: No swap; memory pressure usage will be degraded1307sshClient # [ 9.482637] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1308sshClient # [ 9.492412] systemd[1]: Mounting FUSE Control File System...1309sshClient # [ 9.497075] systemd[1]: Starting Firewall...1310sshClient # [ 9.529053] EXT4-fs (vda): re-mounted 257e409d-cd9e-4181-8262-03a8a67c3875.1311sshClient # [ 9.524914] systemd[1]: Starting Apply Kernel Variables...1312sshClient # [ 9.553925] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1313sshClient # [ 9.564395] systemd[1]: Finished Remount Root and Kernel File Systems.1314machine # [ 9.587425] systemd[1]: Populated /etc with preset unit settings.1315sshClient # [ 9.597202] systemd[1]: Listening on Disk Image Download Service Socket.1316sshClient # [ 9.612795] systemd[1]: Starting Flush Journal to Persistent Storage...1317sshClient # [ 9.617749] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1318sshClient # [ 9.655289] systemd[1]: Starting Load/Save OS Random Seed...1319sshClient # [ 9.658422] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1320sshClient # [ 9.696960] systemd[1]: Finished Apply Kernel Variables.1321sshClient # [ 9.746940] systemd-journald[276]: Received client request to flush runtime journal.1322machine # [ 10.147477] systemd[1]: initrd-switch-root.service: Deactivated successfully.1323machine # [ 10.149206] systemd[1]: Stopped initrd-switch-root.service.1324machine # [ 10.152757] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1325machine # [ 10.156768] systemd[1]: Created slice Slice /system/getty.1326machine # [ 10.159108] systemd[1]: Created slice User and Session Slice.1327machine # [ 10.160390] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1328machine # [ 10.163428] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1329machine # [ 10.165719] systemd[1]: Expecting device /dev/hvc0...1330machine # [ 10.167751] systemd[1]: Expecting device /dev/ttyAMA0...1331machine # [ 10.169716] systemd[1]: Reached target Local Encrypted Volumes.1332machine # [ 10.171932] systemd[1]: Stopped target initrd-fs.target.1333machine # [ 10.172994] systemd[1]: Stopped target initrd-root-fs.target.1334machine # [ 10.175608] systemd[1]: Stopped target initrd-switch-root.target.1335machine # [ 10.176768] systemd[1]: Reached target Virtual Machines and Containers.1336machine # [ 10.178495] systemd[1]: Reached target Path Units.1337machine # [ 10.180914] systemd[1]: Reached target Remote File Systems.1338machine # [ 10.181993] systemd[1]: Reached target Slice Units.1339machine # [ 10.183658] systemd[1]: Reached target Swaps.1340machine # [ 10.188451] systemd[1]: Listening on Query the User Interactively for a Password.1341machine # [ 10.193740] systemd[1]: Listening on Process Core Dump Socket.1342machine # [ 10.198166] systemd[1]: Listening on Credential Encryption/Decryption.1343machine # [ 10.202385] systemd[1]: Listening on Factory Reset Management.1344machine # [ 10.203743] systemd[1]: Listening on Hostname Service Socket.1345machine # [ 10.209923] systemd[1]: Starting Journal Log Access Socket...1346machine # [ 10.212263] systemd[1]: Listening on Journal Audit Socket.1347machine # [ 10.216546] systemd[1]: Listening on Console Output Muting Service Socket.1348machine # [ 10.218118] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1349machine # [ 10.220020] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1350machine # [ 10.223392] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1351machine # [ 10.235255] systemd[1]: Listening on Disk Repartitioning Service Socket.1352machine # [ 10.236822] systemd[1]: Listening on udev Varlink Socket.1353machine # [ 10.238505] systemd[1]: Listening on User Database Manager Socket.1354machine # [ 10.244646] systemd[1]: Mounting Huge Pages File System...1355machine # [ 10.249194] systemd[1]: Mounting POSIX Message Queue File System...1356machine # [ 10.259217] systemd[1]: Mounting Kernel Debug File System...1357machine # [ 10.267969] systemd[1]: Mounting Kernel Trace File System...1358machine # [ 10.284540] systemd[1]: Starting Create List of Static Device Nodes...1359machine # [ 10.297471] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1360machine # [ 10.299079] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1361machine # [ 10.320524] systemd[1]: Mounting Kernel Configuration File System...1362machine # [ 10.322722] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1363machine # [ 10.325327] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1364machine # [ 10.338988] systemd[1]: Starting Load Kernel Module fuse...1365machine # [ 10.341741] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671366machine # [ 10.394882] systemd[1]: Starting Journal Service...1367machine # [ 10.416348] systemd[1]: Starting Load Kernel Modules...1368machine # [ 10.461553] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1369machine # [ 10.494723] systemd[1]: Starting Remount Root and Kernel File Systems...1370machine # [ 10.514032] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1371machine # [ 10.556691] systemd[1]: Starting Coldplug All udev Devices...1372machine # [ 10.574022] fuse: init (API version 7.45)1373machine # [ 10.581568] systemd-journald[277]: Collecting audit messages is enabled.1374machine # [ 10.581503] systemd[1]: Queued start job for default target Multi-User System.1375machine # [ 10.601370] systemd[1]: Started Journal Service.1376machine # [ 10.589480] systemd[1]: systemd-journald.service: Deactivated successfully.1377machine # [ 10.591595] systemd-modules-load[278]: Module 'atkbd' is built in1378machine # [ 10.595725] systemd-modules-load[278]: Module 'loop' is built in1379machine # [ 10.598871] systemd[1]: Listening on Journal Log Access Socket.1380machine # [ 10.615154] EXT4-fs (vda): re-mounted 5f304e20-4ac6-4c80-9ecb-de03ea1b9078.1381machine # [ 10.609830] systemd[1]: Mounted Huge Pages File System.1382machine # [ 10.619433] systemd[1]: Mounted POSIX Message Queue File System.1383machine # [ 10.622291] systemd[1]: Mounted Kernel Debug File System.1384machine # [ 10.631927] systemd[1]: Mounted Kernel Trace File System.1385machine # [ 10.637042] systemd[1]: Finished Create List of Static Device Nodes.1386machine # [ 10.643807] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1387machine # [ 10.647544] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1388machine # [ 10.657990] systemd[1]: Mounted Kernel Configuration File System.1389machine # [ 10.658929] systemd[1]: modprobe@fuse.service: Deactivated successfully.1390machine # [ 10.659875] systemd[1]: Finished Load Kernel Module fuse.1391machine # [ 10.663841] systemd[1]: Finished Load Kernel Modules.1392machine # [ 10.667397] systemd[1]: Finished Remount Root and Kernel File Systems.1393machine # [ 10.678496] systemd[1]: Listening on Disk Image Download Service Socket.1394machine # [ 10.681799] systemd[1]: Mounting FUSE Control File System...1395machine # [ 10.708119] systemd[1]: Starting Firewall...1396machine # [ 10.714918] systemd[1]: Starting Flush Journal to Persistent Storage...1397machine # [ 10.716779] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1398machine # [ 10.734460] systemd-oomd[279]: No swap; memory pressure usage will be degraded1399machine # [ 10.749700] systemd[1]: Starting Load/Save OS Random Seed...1400machine # [ 10.770379] systemd[1]: Starting Apply Kernel Variables...1401machine # [ 10.801825] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1402machine # [ 10.805189] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1403machine # [ 10.811205] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1404machine # [ 10.833157] systemd-journald[277]: Received client request to flush runtime journal.1405sshClient # [ 12.103860] mousedev: PS/2 mouse device common for all mice1406sshClient # [ 13.121127] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31407machine # [ 13.267907] mousedev: PS/2 mouse device common for all mice1408sshClient # [ 13.465128] systemd[1]: Mounted FUSE Control File System.1409sshClient # [ 13.466905] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1410sshClient # [ 13.471789] systemd[1]: Starting Create Static Device Nodes in /dev...1411sshClient # [ 13.475091] systemd[1]: Finished Create Static Device Nodes in /dev.1412sshClient # [ 13.479041] systemd[1]: Reached target Preparation for Local File Systems.1413sshClient # [ 13.483191] systemd[1]: Mounting /run/wrappers...1414sshClient # [ 13.485956] systemd[1]: Starting Rule-based Manager for Device Events and Files...1415sshClient # [ 13.490272] systemd[1]: Mounted /run/wrappers.1416sshClient # [ 13.497188] systemd[1]: Reached target Local File Systems.1417sshClient # [ 13.499436] systemd[1]: Listening on Boot Loader Control Service Socket.1418sshClient # [ 13.501599] systemd[1]: Starting register-nix-paths.service...1419sshClient # [ 13.502461] systemd[1]: Starting Create SUID/SGID Wrappers...1420sshClient # [ 13.503591] systemd-udevd[319]: Using default interface naming scheme 'v261'.1421sshClient # [ 13.509568] systemd[1]: Starting User Database Manager...1422sshClient # [ 13.511278] systemd[1]: Started User Database Manager.1423sshClient # [ 13.513081] systemd[1]: Started Rule-based Manager for Device Events and Files.1424sshClient # [ 13.515277] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1425sshClient # [ 13.518241] systemd[1]: Finished Create SUID/SGID Wrappers.1426sshClient # [ 13.521638] systemd[1]: Finished Coldplug All udev Devices.1427sshClient # [ 13.526751] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1428sshClient # [ 13.530042] systemd[1]: Finished Firewall.1429sshClient # [ 13.532222] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1430sshClient # [ 13.535211] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1431sshClient # [ 13.540206] (udev-worker)[392]: Network interface NamePolicy= disabled on kernel command line.1432sshClient # [ 13.545094] (udev-worker)[403]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1433sshClient # [ 13.551033] (udev-worker)[403]: Network interface NamePolicy= disabled on kernel command line.1434sshClient # [ 13.556766] systemd[1]: Condition check resulted in Virtio network device being skipped.1435sshClient # [ 13.559939] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1436sshClient # [ 13.565235] systemd[1]: Starting Create SUID/SGID Wrappers...1437sshClient # [ 13.567073] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671438sshClient # [ 13.573088] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1439sshClient # [ 13.577511] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1440sshClient # [ 13.583613] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1441sshClient # [ 13.586973] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1442sshClient # [ 13.590959] systemd[1]: Finished Create SUID/SGID Wrappers.1443sshClient # [ 13.595122] systemd[1]: Finished Flush Journal to Persistent Storage.1444sshClient # [ 13.597156] systemd[1]: Starting Create System Files and Directories...1445sshClient # [ 13.652121] systemd[1]: Finished Create System Files and Directories.1446sshClient # [ 13.660346] systemd[1]: Starting Rebuild Journal Catalog...1447sshClient # [ 13.666947] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1448sshClient # [ 13.730036] systemd[1]: Starting Virtual Console Setup...1449sshClient # [ 13.761462] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1450sshClient # [ 13.767002] systemd[1]: Stopped Virtual Console Setup.1451sshClient # [ 13.782700] systemd[1]: Starting Virtual Console Setup...1452sshClient # [ 13.784707] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1453sshClient # [ 13.811167] systemd[1]: Finished Rebuild Journal Catalog.1454sshClient # [ 13.816848] systemd[1]: Starting Update is Completed...1455sshClient # [ 13.864231] systemd[1]: Finished Update is Completed.1456machine # [ 14.217175] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31457sshClient # [ 14.250701] systemd-vconsole-setup[569]: Configuration of first virtual console was skipped, ignoring remaining ones.1458sshClient # [ 14.254318] systemd[1]: Finished Virtual Console Setup.1459sshClient # [ 14.441986] systemd[1]: Finished Load/Save OS Random Seed.1460sshClient # [ 14.446458] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1461machine # [ 14.446642] systemd[1]: Mounted FUSE Control File System.1462machine # [ 14.449471] systemd[1]: Finished Apply Kernel Variables.1463machine # [ 14.453975] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1464machine # [ 14.456429] systemd[1]: Starting Create Static Device Nodes in /dev...1465machine # [ 14.460331] systemd[1]: Finished Create Static Device Nodes in /dev.1466machine # [ 14.461499] systemd[1]: Reached target Preparation for Local File Systems.1467machine # [ 14.464333] systemd[1]: Starting Rule-based Manager for Device Events and Files...1468machine # [ 14.468317] systemd[1]: Mounting /run/wrappers...1469machine # [ 14.469272] systemd[1]: Mounted /run/wrappers.1470machine # [ 14.470048] systemd[1]: Reached target Local File Systems.1471machine # [ 14.472264] systemd[1]: Listening on Boot Loader Control Service Socket.1472machine # [ 14.476229] systemd[1]: Starting register-nix-paths.service...1473machine # [ 14.477203] systemd[1]: Starting Create SUID/SGID Wrappers...1474machine # [ 14.478352] systemd-udevd[318]: Using default interface naming scheme 'v261'.1475machine # [ 14.483903] systemd[1]: Starting User Database Manager...1476machine # [ 14.484880] systemd[1]: Started User Database Manager.1477machine # [ 14.485641] systemd[1]: Started Rule-based Manager for Device Events and Files.1478machine # [ 14.486645] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1479machine # [ 14.487613] systemd[1]: Finished Create SUID/SGID Wrappers.1480machine # [ 14.494674] systemd[1]: Finished Coldplug All udev Devices.1481machine # [ 14.496150] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1482machine # [ 14.504272] systemd[1]: Finished Firewall.1483machine # [ 14.505106] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1484machine # [ 14.508161] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1485machine # [ 14.509501] (udev-worker)[393]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1486machine # [ 14.515261] (udev-worker)[393]: Network interface NamePolicy= disabled on kernel command line.1487machine # [ 14.518429] (udev-worker)[404]: Network interface NamePolicy= disabled on kernel command line.1488machine # [ 14.524745] systemd[1]: Condition check resulted in Virtio network device being skipped.1489machine # [ 14.527974] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1490machine # [ 14.532188] systemd[1]: Starting Create SUID/SGID Wrappers...1491machine # [ 14.536138] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671492machine # [ 14.538357] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1493machine # [ 14.544192] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1494machine # [ 14.545869] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1495machine # [ 14.548486] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1496machine # [ 14.553678] systemd[1]: Finished Create SUID/SGID Wrappers.1497machine # [ 14.556099] systemd[1]: Finished Load/Save OS Random Seed.1498machine # [ 14.560432] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1499machine # [ 14.561728] systemd[1]: Finished Flush Journal to Persistent Storage.1500machine # [ 14.564099] systemd[1]: Starting Create System Files and Directories...1501machine # [ 14.656807] systemd[1]: Finished Create System Files and Directories.1502machine # [ 14.664525] systemd[1]: Starting Rebuild Journal Catalog...1503machine # [ 14.670238] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1504machine # [ 14.747633] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1505machine # [ 14.788118] systemd[1]: Finished Rebuild Journal Catalog.1506machine # [ 14.792502] systemd[1]: Starting Update is Completed...1507machine # [ 14.848937] systemd[1]: Finished Update is Completed.1508machine # [ 14.894337] systemd[1]: Starting Virtual Console Setup...1509machine # [ 15.324461] systemd-vconsole-setup[583]: Configuration of first virtual console was skipped, ignoring remaining ones.1510machine # [ 15.328655] systemd[1]: Finished Virtual Console Setup.1511machine # [ 15.828894] systemd[1]: Finished register-nix-paths.service.1512sshClient # [ 15.846003] systemd[1]: Finished register-nix-paths.service.1513machine # [ 15.831048] systemd[1]: Reached target System Initialization.1514machine # [ 15.833123] systemd[1]: Started Discard unused filesystem blocks once a week.1515machine # [ 15.834560] systemd[1]: Started Daily Cleanup of Temporary Directories.1516machine # [ 15.837118] systemd[1]: Reached target Timer Units.1517machine # [ 15.839160] systemd[1]: Listening on D-Bus System Message Bus Socket.1518machine # [ 15.842160] systemd[1]: Listening on Nix Daemon Socket.1519machine # [ 15.843060] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).1520machine # [ 15.844876] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1521machine # [ 15.846697] systemd[1]: Reached target Socket Units.1522machine # [ 15.850651] systemd[1]: Reached target Basic System.1523machine # [ 15.851689] systemd[1]: Started backdoor.service.1524machine # [ 15.856173] systemd[1]: Starting Import lastlog data into lastlog2 database...1525machine # [ 15.860306] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1526machine # [ 15.879201] systemd[1]: Starting Post-Boot Actions...1527machine # [ 15.885842] systemd[1]: Started Reset console on configuration changes.1528machine # [ 15.913529] systemd[1]: Starting resolvconf update...1529machine # [ 15.949837] systemd[1]: Starting SSH Host Keys Generation...1530machine # [ 15.987861] systemd[1]: Starting Home Area Manager...1531machine # [ 16.001139] nsncd[589]: Sep 06 02:31:19.527 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1532machine # [ 16.008159] systemd[1]: Started Name Service Cache Daemon (nsncd).1533machine # connecting to host...1534machine # [ 16.031715] systemd[1]: Finished Post-Boot Actions.1535machine: Guest shell says: b'Spawning backdoor root shell...\n'1536machine # [ 16.093872] systemd[1]: Reached target Host and Network Name Lookups.1537machine # [ 16.099682] systemd[1]: Reached target User and Group Name Lookups.1538machine # [ 16.116815] systemd[1]: Starting D-Bus System Message Bus...1539machine: connected to guest root shell1540machine: (connecting took 16.47 seconds)1541machine: (finished: waiting for the VM to finish booting, in 16.47 seconds)1542machine # [ 16.156649] systemd-homed[597]: Watching /home.1543machine # [ 16.163094] systemd[1]: Starting User Login Management...1544machine # [ 16.168806] systemd[1]: Finished Import lastlog data into lastlog2 database.1545machine # [ 16.388394] systemd[1]: Stopped target Host and Network Name Lookups.1546machine # [ 16.395477] systemd[1]: Stopping Host and Network Name Lookups...1547machine # [ 16.400698] systemd[1]: Stopped target User and Group Name Lookups.1548machine # [ 16.409571] systemd[1]: Stopping User and Group Name Lookups...1549machine # [ 16.410466] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1550machine # [ 16.411262] systemd[1]: nscd.service: Deactivated successfully.1551machine # [ 16.421359] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1552machine # [ 16.422737] dbus-broker-launch[618]: Looking up NSS user entry for 'systemd-timesync'...1553machine # [ 16.433785] systemd-logind[626]: New seat seat0.1554machine # [ 16.438660] systemd-logind[626]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1555machine # [ 16.448903] dbus-broker-launch[618]: NSS returned no entry for 'systemd-timesync'1556machine # [ 16.449959] dbus-broker-launch[618]: Invalid user-name in /nix/store/6mddl56lv9ch4kg4fszsw0v15aaknkx1-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1557sshClient # [ 15.848518] systemd[1]: Reached target System Initialization.1558sshClient # [ 16.473207] systemd[1]: Started Discard unused filesystem blocks once a week.1559sshClient # connecting to host...1560sshClient # [ 16.477853] systemd[1]: Started Daily Cleanup of Temporary Directories.1561machine # [ 16.466427] systemd-logind[626]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1562sshClient # [ 16.483792] systemd[1]: Reached target Timer Units.1563machine # [ 16.467746] systemd-logind[626]: Watching system buttons on /dev/input/event0 (gpio-keys)1564sshClient # [ 16.489012] systemd[1]: Listening on D-Bus System Message Bus Socket.1565sshClient # [ 16.495252] nsncd[579]: Sep 06 02:31:19.550 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1566machine # [ 16.479957] systemd[1]: Started User Login Management.1567sshClient # [ 16.503259] systemd[1]: Listening on Nix Daemon Socket.1568sshClient # [ 16.508202] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1569machine # [ 16.487440] systemd[1]: Starting linger-users.service...1570machine # [ 16.495825] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1571sshClient # [ 16.515755] systemd[1]: Reached target Socket Units.1572sshClient # [ 16.518774] systemd[1]: Reached target Basic System.1573sshClient # [ 16.519584] systemd[1]: Started backdoor.service.1574machine # [ 16.503731] systemd[1]: Started D-Bus System Message Bus.1575sshClient # [ 16.528240] systemd[1]: Starting Import lastlog data into lastlog2 database...1576sshClient # [ 16.529705] dbus-broker-launch[590]: Looking up NSS user entry for 'systemd-timesync'...1577sshClient # [ 16.530738] dbus-broker-launch[590]: NSS returned no entry for 'systemd-timesync'1578sshClient # [ 16.531681] dbus-broker-launch[590]: Invalid user-name in /nix/store/67n59mmcv6xpk1sl91ilqlzywhyd6ajv-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1579sshClient # [ 16.555576] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1580sshClient # [ 16.563888] systemd[1]: Starting Post-Boot Actions...1581sshClient # [ 16.569765] systemd[1]: Started Reset console on configuration changes.1582sshClient # [ 16.580744] systemd[1]: Starting resolvconf update...1583machine # [ 16.570140] dbus-broker-launch[618]: Ready1584sshClient # [ 16.586151] nsncd[652]: Sep 06 02:31:19.944 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1585sshClient # [ 16.591343] systemd[1]: Starting Home Area Manager...1586machine # [ 16.580818] systemd[1]: Started Home Area Manager.1587sshClient # [ 16.601126] systemd[1]: Finished Post-Boot Actions.1588machine # [ 16.584577] systemd[1]: Finished Home Area Activation.1589sshClient # [ 16.601910] systemd[1]: Starting D-Bus System Message Bus...1590sshClient # [ 16.602658] systemd[1]: Started Name Service Cache Daemon (nsncd).1591sshClient # [ 16.603428] systemd[1]: Reached target Host and Network Name Lookups.1592sshClient # [ 16.609647] systemd[1]: Reached target User and Group Name Lookups.1593machine # [ 16.595377] systemd[1]: Starting First Boot Home Area Wizard...1594sshClient # [ 16.614293] systemd[1]: Starting User Login Management...1595sshClient # [ 16.617753] systemd[1]: Finished Import lastlog data into lastlog2 database.1596machine # [ 16.601857] systemd[1]: linger-users.service: Deactivated successfully.1597machine # [ 16.604869] systemd[1]: Finished linger-users.service.1598sshClient # [ 16.622209] systemd-homed[584]: Watching /home.1599sshClient # [ 16.627796] systemd-logind[601]: New seat seat0.1600sshClient # [ 16.628808] systemd-logind[601]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1601sshClient # [ 16.633556] systemd-logind[601]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1602machine # [ 16.621171] systemd[1]: Started Name Service Cache Daemon (nsncd).1603machine # [ 16.622582] nsncd[676]: Sep 06 02:31:20.143 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1604sshClient # [ 16.638292] systemd-logind[601]: Watching system buttons on /dev/input/event0 (gpio-keys)1605sshClient # [ 16.641774] systemd[1]: Started User Login Management.1606sshClient # [ 16.642906] systemd[1]: Starting linger-users.service...1607sshClient # [ 16.644705] systemd[1]: Started D-Bus System Message Bus.1608sshClient # [ 16.648468] systemd[1]: linger-users.service: Deactivated successfully.1609sshClient # [ 16.650037] systemd[1]: Finished linger-users.service.1610machine # [ 16.627829] systemd[1]: Reached target Host and Network Name Lookups.1611machine # [ 16.636621] systemd[1]: Reached target User and Group Name Lookups.1612sshClient # [ 16.654880] systemd[1]: Stopped target Host and Network Name Lookups.1613sshClient # [ 16.659949] systemd[1]: Stopping Host and Network Name Lookups...1614sshClient # [ 16.662222] systemd[1]: Stopped target User and Group Name Lookups.1615sshClient # [ 16.663894] systemd[1]: Stopping User and Group Name Lookups...1616sshClient # [ 16.666149] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1617machine # [ 16.652489] systemd[1]: Finished resolvconf update.1618sshClient # [ 16.668277] systemd[1]: nscd.service: Deactivated successfully.1619sshClient # [ 16.671722] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1620machine # [ 16.658939] systemd[1]: Reached target Preparation for Network.1621sshClient # [ 16.676284] dbus-broker-launch[590]: Ready1622sshClient # [ 16.680115] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1623machine # [ 16.668494] systemd[1]: Starting DHCP Client...1624sshClient # [ 16.686299] systemd[1]: Started Home Area Manager.1625machine # [ 16.687167] systemd[1]: Starting Address configuration of eth1...1626machine # [ 16.717070] systemd[1]: Starting Extra networking commands....1627sshClient # [ 16.739879] network-addresses-eth1-start[675]: adding address 192.168.1.2/24... done1628sshClient # [ 16.747077] network-addresses-eth1-start[675]: adding address 2001:db8:1::2/64... done1629sshClient # [ 16.749809] systemd[1]: Finished Home Area Activation.1630sshClient # [ 16.750613] systemd[1]: Starting First Boot Home Area Wizard...1631sshClient # [ 16.751385] systemd[1]: Started Name Service Cache Daemon (nsncd).1632sshClient # [ 16.757803] systemd[1]: Reached target Host and Network Name Lookups.1633sshClient # [ 16.764402] systemd[1]: Reached target User and Group Name Lookups.1634sshClient # [ 16.769605] systemd[1]: Finished resolvconf update.1635machine # [ 16.754568] systemd[1]: Created slice Slice /system/systemd-mute-console.1636sshClient # [ 16.770398] systemd[1]: Reached target Preparation for Network.1637sshClient # [ 16.771167] systemd[1]: Starting DHCP Client...1638sshClient # [ 16.771771] systemd[1]: Starting Address configuration of eth1...1639sshClient # [ 16.781690] systemd[1]: Starting Extra networking commands....1640sshClient # [ 16.782639] systemd[1]: Finished First Boot Home Area Wizard.1641machine # [ 16.767143] systemd[1]: Started Console Output Muting Service (PID 689/UID 0).1642sshClient # [ 16.783384] systemd[1]: Reached target First Boot Complete.1643sshClient # [ 16.790137] systemd[1]: Starting Save Transient machine-id to Disk...1644sshClient # [ 16.791178] systemd[1]: Finished Address configuration of eth1.1645sshClient # [ 16.867784] dhcpcd[714]: dhcpcd-10.3.2 starting1646sshClient # [ 16.881164] systemd[1]: Finished Extra networking commands..1647sshClient # [ 16.884516] systemd[1]: Reached target Network.1648sshClient # [ 16.888494] systemd[1]: Starting Permit User Sessions...1649sshClient # [ 16.891995] dhcpcd[758]: dev: loaded udev1650sshClient # [ 16.920724] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1651machine # [ 16.915916] network-addresses-eth1-start[705]: adding address 192.168.1.1/24... done1652sshClient # [ 16.934427] systemd[1]: Finished Save Transient machine-id to Disk.1653sshClient # [ 16.957507] 8021q: 802.1Q VLAN Support v1.81654sshClient # [ 16.957918] 8021q: adding VLAN 0 to HW filter on device eth11655sshClient # [ 16.958067] systemd[1]: Finished Permit User Sessions.1656sshClient # [ 16.965678] systemd[1]: Started Getty on tty1.1657sshClient # [ 16.967426] systemd[1]: Reached target Login Prompts.1658machine # [ 16.952996] network-addresses-eth1-start[705]: adding address 2001:db8:1::1/64... done1659sshClient # [ 16.994672] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1660machine # [ 16.993559] systemd[1]: Finished Address configuration of eth1.1661sshClient # [ 17.040985] cfg80211: Loading compiled-in X.509 certificates for regulatory database1662sshClient # [ 17.077035] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1663sshClient # [ 17.077583] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1664sshClient # [ 17.083547] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21665sshClient # [ 17.083936] cfg80211: failed to load regulatory.db1666machine # [ 17.079794] dhcpcd[718]: dhcpcd-10.3.2 starting1667machine # [ 17.103439] dhcpcd[760]: dev: loaded udev1668machine # [ 17.106333] sshd-keygen-start[638]: Generating public/private rsa key pair.1669machine # [ 17.107335] sshd-keygen-start[638]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key1670machine # [ 17.108998] sshd-keygen-start[638]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub1671machine # [ 17.110454] sshd-keygen-start[638]: The key fingerprint is:1672machine # [ 17.111499] sshd-keygen-start[638]: SHA256:ufP9n+1hA1Qq4ZXiJLk4O1chLBrwUGaxmBwVzrM35PA root@machine1673machine # [ 17.115808] sshd-keygen-start[638]: The key's randomart image is:1674machine # [ 17.117631] sshd-keygen-start[638]: +---[RSA 4096]----+1675machine # [ 17.119601] sshd-keygen-start[638]: | ++Bo . .. ... |1676machine # [ 17.123201] sshd-keygen-start[638]: | . @... =.+o.o |1677machine # [ 17.125570] sshd-keygen-start[638]: | + Bo.o *ooo |1678sshClient # [ 17.157500] 8021q: adding VLAN 0 to HW filter on device eth01679machine # [ 17.126772] sshd-keygen-start[638]: | .Bo o oo |1680sshClient # [ 17.144533] dhcpcd[758]: eth0: waiting for carrier1681sshClient # [ 17.146055] dhcpcd[758]: eth0: carrier acquired1682machine # [ 17.130735] sshd-keygen-start[638]: | . ES . . |1683machine # [ 17.132367] sshd-keygen-start[638]: | .o.o . |1684machine # [ 17.133396] sshd-keygen-start[638]: | = + |1685machine # [ 17.134515] sshd-keygen-start[638]: | o . . =|1686machine # [ 17.135802] sshd-keygen-start[638]: | . ...++|1687machine # [ 17.137747] sshd-keygen-start[638]: +----[SHA256]-----+1688sshClient # [ 17.158971] dhcpcd[758]: DUID 00:01:00:01:32:2f:8c:f8:52:54:00:12:34:561689sshClient # [ 17.160237] dhcpcd[758]: eth0: IAID 00:12:34:561690sshClient # [ 17.160974] dhcpcd[758]: eth0: adding address fe80::5054:ff:fe12:34561691machine # [ 17.176401] systemd[1]: Finished Extra networking commands..1692machine # [ 17.179502] systemd[1]: Reached target Network.1693machine # [ 17.203054] sshd-keygen-start[778]: Generating public/private ed25519 key pair.1694machine # [ 17.204523] sshd-keygen-start[778]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key1695machine # [ 17.205974] sshd-keygen-start[778]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub1696machine # [ 17.207878] sshd-keygen-start[778]: The key fingerprint is:1697machine # [ 17.209063] sshd-keygen-start[778]: SHA256:H3+HxjCjjpbeVjtpfdUROpBBwDrrkN8fgYe66iPMYVQ root@machine1698machine # [ 17.213319] sshd-keygen-start[778]: The key's randomart image is:1699machine # [ 17.216424] sshd-keygen-start[778]: +--[ED25519 256]--+1700machine # [ 17.219555] sshd-keygen-start[778]: | ..ooo |1701machine # [ 17.223419] sshd-keygen-start[778]: | E . o . |1702machine # [ 17.224418] sshd-keygen-start[778]: | . . . . .|1703machine # [ 17.225442] sshd-keygen-start[778]: | . o o o . |1704machine # [ 17.226700] sshd-keygen-start[778]: | . . So.o+ . o|1705sshClient # [ 17.243877] dhcpcd[758]: eth0: soliciting a DHCP lease1706machine # [ 17.231734] sshd-keygen-start[778]: | o o ....+o= .o|1707sshClient # [ 17.249163] dhcpcd[758]: eth0: offered 10.0.2.15 from 10.0.2.21708machine # [ 17.235072] sshd-keygen-start[778]: | + . +...oo.+= o|1709sshClient # [ 17.252288] dhcpcd[758]: eth0: probing address 10.0.2.15/241710machine # [ 17.237665] sshd-keygen-start[778]: | + . o+=. *o...|1711machine # [ 17.240871] sshd-keygen-start[778]: | ooo+o.+o . . |1712machine # [ 17.243736] sshd-keygen-start[778]: +----[SHA256]-----+1713machine # [ 17.249311] systemd[1]: sshd-keygen.service: Deactivated successfully.1714machine # [ 17.250282] systemd[1]: Finished SSH Host Keys Generation.1715machine # [ 17.251000] systemd[1]: Starting SSH Daemon...1716machine # [ 17.262493] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1717machine # [ 17.350486] sshd[782]: Server listening on 0.0.0.0 port 22.1718machine # [ 17.352952] sshd[782]: Server listening on :: port 22.1719machine # [ 17.356487] systemd[1]: Started SSH Daemon.1720machine # [ 17.442019] dhcpcd[760]: eth0: waiting for carrier1721machine: (finished: waiting for unit systemd-homed.service, in 17.81 seconds)1722machine: waiting for Please enter user name to create to appear on tty 11723machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.08 seconds)1724machine: sending keys 'test-homed-user\n'1725machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds)1726machine: waiting for Please enter new password for user test-homed-user: to appear on tty 11727machine # [ 17.889235] dhcpcd[760]: eth0: carrier acquired1728machine # [ 17.910616] dhcpcd[760]: DUID 00:01:00:01:32:2f:8c:f9:52:54:00:12:34:561729machine # [ 17.913248] dhcpcd[760]: eth0: IAID 00:12:34:561730machine # [ 17.914701] dhcpcd[760]: eth0: adding address fe80::5054:ff:fe12:34561731machine: (finished: waiting for Please enter new password for user test-homed-user: to appear on tty 1, in 1.20 seconds)1732machine: sending keys 'foobarfoo\n'1733machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1734machine: waiting for (repeat) to appear on tty 11735machine: (finished: waiting for (repeat) to appear on tty 1, in 0.11 seconds)1736machine: sending keys 'foobarfoo\n'1737machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1738machine: waiting for unit systemd-homed-firstboot.service1739machine # [ 19.266678] dhcpcd[760]: eth0: soliciting a DHCP lease1740machine # [ 19.273069] dhcpcd[760]: eth0: offered 10.0.2.15 from 10.0.2.21741sshClient # [ 19.294731] dhcpcd[758]: eth0: soliciting an IPv6 router1742machine # [ 19.280882] dhcpcd[760]: eth0: probing address 10.0.2.15/241743sshClient # [ 19.298615] dhcpcd[758]: eth0: Router Advertisement from fe80::21744sshClient # [ 19.301767] dhcpcd[758]: eth0: adding address fec0::5054:ff:fe12:3456/641745sshClient # [ 19.304789] dhcpcd[758]: eth0: adding route to fec0::/641746sshClient # [ 19.307129] dhcpcd[758]: eth0: adding default route via fe80::21747machine # [ 19.422333] dhcpcd[760]: eth0: soliciting an IPv6 router1748machine # [ 19.428364] dhcpcd[760]: eth0: Router Advertisement from fe80::21749machine # [ 19.429343] dhcpcd[760]: eth0: adding address fec0::5054:ff:fe12:3456/641750machine # [ 19.430231] dhcpcd[760]: eth0: adding route to fec0::/641751machine # [ 19.430995] dhcpcd[760]: eth0: adding default route via fe80::21752sshClient # [ 22.292613] dhcpcd[758]: eth0: leased 10.0.2.15 for 86400 seconds1753sshClient # [ 22.293095] dhcpcd[758]: eth0: adding route to 10.0.2.0/241754sshClient # [ 22.293240] dhcpcd[758]: eth0: adding default route via 10.0.2.21755sshClient # [ 22.460750] systemd[1]: Started DHCP Client.1756sshClient # [ 22.462830] systemd[1]: Reached target Multi-User System.1757sshClient # [ 22.464482] systemd[1]: Startup finished in 1.028s (kernel) + 5.602s (initrd) + 15.832s (userspace) = 22.464s.1758machine # [ 24.395714] dhcpcd[760]: eth0: leased 10.0.2.15 for 86400 seconds1759machine # [ 24.400622] dhcpcd[760]: eth0: adding route to 10.0.2.0/241760machine # [ 24.403056] dhcpcd[760]: eth0: adding default route via 10.0.2.21761machine # [ 24.604305] systemd[1]: Started DHCP Client.1762machine # [ 29.462901] systemd-homed[597]: Generating key pair for signing local user identity records.1763machine # [ 29.470330] systemd-homed[597]: Successfully created Ed25519 key pair.1764machine # [ 29.504691] systemd-homed[597]: test-homed-user: changing state absent → creating1765machine # [ 29.636997] systemd-homework[929]: Using automatic default storage of 'luks'.1766machine # [ 29.652518] systemd-homework[929]: Sizing home to 83% of available disk space, which is 737M.1767machine # [ 29.653965] systemd-homework[929]: Allocating image file completed.1768machine # [ 29.687179] systemd-homework[929]: Writing of partition table completed.1769machine # [ 29.690538] systemd-homework[929]: Setting up loopback device /dev/loop0 completed.1770machine # [ 31.044718] systemd-homework[929]: LUKS formatting completed.1771machine # [ 33.283359] systemd-homework[929]: Writing password to LUKS keyslot 0 completed.1772machine # [ 33.544137] systemd-homework[929]: LUKS activation by volume key succeeded.1773machine # [ 33.562544] systemd-homework[929]: Writing user record as LUKS token completed.1774machine # [ 33.563651] systemd-homework[929]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1775machine # [ 33.566764] systemd-homework[929]: Formatting /dev/mapper/home-test-homed-user as btrfs1776machine # [ 34.427977] systemd-homework[929]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid 01dca48c-3008-45c5-a8c7-367fd644b7e9)1777machine # [ 34.428277] systemd-homework[929]: Formatting file system completed.1778machine # [ 34.432369] systemd[1]: proc-962-mounts.mount: Deactivated successfully.1779machine # [ 34.496000] systemd-homework[929]: Mounting file system completed.1780machine # [ 34.498879] systemd-homework[929]: Skeleton directory /etc/skel missing, ignoring.1781machine # [ 34.502585] systemd-homework[929]: Wrote embedded .identity file.1782machine # [ 34.503725] systemd-homework[929]: Replaced embedded blob dir with contents of system blob dir.1783machine # [ 34.505041] systemd-homework[929]: Recursive changing of ownership completed.1784machine # [ 34.505988] systemd-homework[929]: Changed top-level directory access mode to 0700.1785machine # [ 34.515673] systemd-homework[929]: Synchronized disk.1786machine # [ 34.516634] systemd-homework[929]: Discarded unused 646.4M.1787machine # [ 34.530484] systemd-homed[597]: block device /sys/devices/virtual/block/dm-0 has been removed.1788machine # [ 34.583043] systemd-homed[597]: block device /sys/devices/virtual/block/dm-0 has been removed.1789machine # [ 34.609092] systemd-homed[597]: block device /sys/devices/virtual/block/loop0 has been removed.1790machine # [ 34.622850] systemd-homework[929]: Moved image file into place.1791machine # [ 34.628084] systemd-homework[929]: Creation completed.1792machine # [ 34.629087] systemd-homework[929]: Image size is 737M, file system size is 719M, file system payload size is 719M, file system free is 712.9M.1793machine # [ 34.638990] systemd-homed[597]: test-homed-user: changing state creating → inactive1794machine # [ 34.648331] systemd[1]: Finished First Boot Home Area Wizard.1795machine # [ 34.651876] systemd[1]: systemd-mute-console@0-1-689_690-0.service: Deactivated successfully.1796machine # [ 34.659093] systemd[1]: Reached target First Boot Complete.1797machine # [ 34.664877] systemd[1]: Starting Save Transient machine-id to Disk...1798machine # [ 34.669561] systemd[1]: Starting Permit User Sessions...1799machine # [ 34.724640] systemd[1]: Finished Permit User Sessions.1800machine # [ 34.733056] systemd[1]: Started Getty on tty1.1801machine # [ 34.735757] systemd[1]: Reached target Login Prompts.1802machine # [ 34.737314] systemd[1]: Reached target Multi-User System.1803machine # [ 34.741442] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1804machine # [ 34.748649] systemd[1]: Finished Save Transient machine-id to Disk.1805machine # [ 34.750917] systemd[1]: Startup finished in 1.043s (kernel) + 6.444s (initrd) + 27.261s (userspace) = 34.749s.1806machine: (finished: waiting for unit systemd-homed-firstboot.service, in 16.33 seconds)1807(finished: subtest: create systemd-homed user on first boot prompt, in 35.93 seconds)1808machine: must succeed: homectl update test-homed-user --offline -G wheel1809machine # [ 35.635497] systemd-homed[597]: test-homed-user: changing state inactive → updating1810machine # [ 35.646012] systemd-homework[1007]: Offline update requested. Not touching embedded records.1811machine # [ 35.650317] systemd-homed[597]: Home test-homed-user is signed exclusively by our key, accepting.1812machine # [ 35.658945] systemd-homed[597]: test-homed-user: changing state updating → inactive1813machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.08 seconds)1814subtest: login as homed user1815machine: waiting for login: to appear on tty 11816machine: (finished: waiting for login: to appear on tty 1, in 0.06 seconds)1817machine: sending keys 'test-homed-user\n'1818machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds)1819machine: waiting for Password: to appear on tty 11820machine # [ 36.129622] systemd-homed[597]: test-homed-user: changing state inactive → activating-for-acquire1821machine # [ 36.138285] systemd-homework[1022]: None of the supplied plaintext passwords unlock the user record's hashed passwords.1822machine # [ 36.145326] systemd-homed[597]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.1823machine # [ 36.147830] systemd-homed[597]: test-homed-user: changing state activating-for-acquire → inactive1824machine # [ 36.150411] systemd-homed[597]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.1825machine # [ 36.152056] systemd-homed[597]: Home test-homed-user already deactivated, no automatic deactivation needed.1826machine: (finished: waiting for Password: to appear on tty 1, in 1.19 seconds)1827machine: sending keys 'foobarfoo\n'1828machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1829machine: waiting for success: pgrep -u test-homed-user -t tty1 bash1830machine # [ 37.273889] systemd-homed[597]: test-homed-user: changing state inactive → activating-for-acquire1831machine # [ 37.334868] systemd-homework[1034]: Provided password unlocks user record.1832machine # [ 37.343887] systemd-homework[1034]: Successfully locked image file '/home/test-homed-user.home'.1833machine # [ 37.351990] systemd-homework[1034]: Allocated additional 646.4M.1834machine # [ 37.371484] loop1: detected capacity change from 0 to 15052801835machine # [ 37.357940] systemd-homework[1034]: Setting up loopback device /dev/loop1 completed.1836machine # [ 38.204444] systemd-homework[1034]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1837machine # [ 38.236903] systemd-homework[1034]: Provided password unlocks user record.1838machine # [ 38.242389] systemd-homework[1034]: Probing file system completed (found btrfs).1839machine # [ 38.247326] systemd-homework[1034]: No fsck for file system btrfs installed, ignoring.1840machine # [ 38.264535] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (1034)1841machine # [ 38.269152] BTRFS info (device dm-0): first mount of filesystem 01dca48c-3008-45c5-a8c7-367fd644b7e91842machine # [ 38.269210] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm1843machine # [ 38.275592] BTRFS info (device dm-0): enabling free space tree1844machine # [ 38.275621] BTRFS info (device dm-0): use zstd compression, level 11845machine # [ 38.263886] systemd-homework[1034]: Mounting file system completed.1846machine # [ 38.266277] systemd-homework[1034]: Discovered used loopback device /dev/loop1.1847machine # [ 38.267327] systemd-homework[1034]: offset = 1048576, size = 770703360, image = 7728005121848machine # [ 38.277373] systemd-homework[1034]: Ready to resize image size 737M → 888M, partition size 735M → 886M, file system size 719M → 870M.1849machine # [ 38.279452] systemd-homework[1034]: Growing of image file from 737M to 888M completed.1850machine # [ 38.302627] loop1: detected capacity change from 1505280 to 18145281851machine # [ 38.292405] systemd-homework[1034]: Refreshing loop device size completed.1852machine # [ 38.309409] systemd-homework[1034]: Growing of partition completed.1853machine # [ 38.361183] async_tx: api initialized (async)1854machine # [ 38.420385] systemd-homework[1034]: LUKS device growing completed.1855machine # [ 38.440559] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 753926144 to 9122611201856machine # [ 38.428661] systemd-homework[1034]: File system resizing from 719M to 870M completed.1857machine # [ 38.429886] systemd-homework[1034]: Synchronized disk.1858machine # [ 38.430624] systemd-homework[1034]: Synchronized disk.1859machine # [ 38.431330] systemd-homework[1034]: Resizing completed.1860machine # [ 38.436306] systemd-homework[1034]: Image size is 888M, file system size is 870M, file system payload size is 870M, file system free is 863.9M.1861machine # [ 38.438010] systemd-homework[1034]: Read embedded .identity file.1862machine # [ 38.461528] systemd-homework[1034]: Provided password unlocks user record.1863machine # [ 38.462634] systemd-homework[1034]: Reconciling header user identity completed (host version was newer).1864machine # [ 38.463866] systemd-homework[1034]: Reconciling embedded user identity completed (host version was newer).1865machine # [ 38.480609] systemd-homework[1034]: Wrote LUKS header user record.1866machine # [ 38.482259] systemd-homework[1034]: Wrote embedded .identity file.1867machine # [ 38.483130] systemd-homework[1034]: Replaced embedded blob dir with contents of system blob dir.1868machine # [ 38.484689] systemd-homework[1034]: Recursive changing of ownership not necessary, skipped.1869machine # [ 38.489924] systemd-homework[1034]: Synchronized disk.1870machine # [ 38.491638] systemd-homework[1034]: Moving to final mount point /home/test-homed-user completed.1871machine # [ 38.517553] systemd-homework[1034]: Activation completed.1872machine # [ 38.520183] systemd-homework[1034]: Image size is 886M, file system size is 870M, file system payload size is 870M, file system free is 863.9M.1873machine # [ 38.544259] systemd-homed[597]: Home test-homed-user is signed exclusively by our key, accepting.1874machine # [ 38.550490] systemd-homed[597]: test-homed-user: changing state activating-for-acquire → active1875machine # [ 38.552429] login[998]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired.1876machine # [ 38.558286] systemd-homed[597]: test-homed-user: changing state active → resizing-while-active1877machine # [ 38.570095] systemd-homework[1079]: Successfully acquired home volume key from kernel keyring.1878machine # [ 38.571341] systemd-homework[1079]: LUKS volume key from keyring unlocks user record.1879machine # [ 38.663605] login[998]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0)1880machine # [ 38.673900] systemd-homework[1079]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.1881machine # [ 38.711048] systemd[1]: Created slice Slice /user/60388.1882machine # [ 38.718174] systemd[1]: Starting User Runtime Directory /run/user/60388...1883machine # [ 38.743553] systemd-logind[626]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'.1884machine # [ 38.812256] systemd[1]: Finished User Runtime Directory /run/user/60388.1885machine # [ 38.833315] systemd[1]: Starting User Manager for UID 60388...1886machine # [ 38.885655] (systemd)[1082]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.1887machine # [ 38.956519] (systemd)[1082]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)1888machine # [ 38.964986] systemd-logind[626]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'.1889machine # [ 38.974318] systemd-homework[1079]: Successfully re-activated LUKS device.1890machine # [ 38.978234] systemd-homework[1079]: LUKS volume key from keyring unlocks user record.1891machine # [ 38.983291] systemd-homework[1079]: Discovered used loopback device /dev/loop1.1892machine # [ 38.988352] systemd-homework[1079]: Read embedded .identity file.1893machine # [ 38.990233] systemd-homework[1079]: LUKS volume key from keyring unlocks user record.1894machine # [ 38.994353] systemd-homework[1079]: Reconciling header user identity completed (host version was newer).1895machine # [ 38.996304] systemd-homework[1079]: Reconciling embedded user identity completed (host version was newer).1896machine # [ 38.997949] systemd-homework[1079]: offset = 1048576, size = 929038336, image = 9311354881897machine # [ 39.007867] systemd-homework[1079]: Ready to resize image size 888M → 758M, partition size 886M → 756M, file system size 870M → 740M.1898machine # [ 39.010758] systemd-homework[1079]: Wrote embedded .identity file.1899machine # [ 39.012689] systemd-homework[1079]: Replaced embedded blob dir with contents of system blob dir.1900machine # [ 39.013871] systemd-homework[1079]: Backing file is fully allocated already.1901machine # [ 39.017917] systemd-homework[1079]: File system resizing from 870M to 740M completed.1902machine # [ 39.032364] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 912261120 to 7759462401903machine # [ 39.021599] systemd-homework[1079]: Synchronized disk.1904machine # [ 39.037443] systemd[1082]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1905machine # [ 39.118046] systemd-homework[1079]: LUKS device shrinking completed.1906machine # [ 39.142650] loop1: detected capacity change from 1814528 to 15482881907machine # [ 39.132971] systemd-homework[1079]: Refreshing loop device size completed.1908machine # [ 39.135247] systemd-homework[1079]: Shrinking of image file completed.1909machine # [ 39.155441] systemd-homework[1079]: Shrinking of partition completed.1910machine # [ 39.169207] systemd-homework[1079]: Wrote LUKS header user record.1911machine # [ 39.176630] systemd-homework[1079]: Synchronized disk.1912machine # [ 39.180682] systemd-homework[1079]: Resizing completed.1913machine # [ 39.183034] systemd-homework[1079]: Image size is 758M, file system size is 740M, file system payload size is 740M, file system free is 733.9M.1914machine # [ 39.189247] systemd-homed[597]: Home test-homed-user is signed exclusively by our key, accepting.1915machine # [ 39.198426] systemd-homed[597]: test-homed-user: changing state resizing-while-active → active1916machine # [ 39.200655] systemd-homed[597]: Rebalancing complete.1917machine # [ 39.337226] systemd[1082]: Queued start job for default target Main User Target.1918machine # [ 39.345015] systemd[1082]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1919machine # [ 39.358239] systemd[1082]: Created slice User Application Slice.1920machine # [ 39.359285] systemd[1082]: Started Daily Cleanup of User's Temporary Directories.1921machine # [ 39.361611] systemd[1082]: Reached target Paths.1922machine # [ 39.362322] systemd[1082]: Reached target Timers.1923machine # [ 39.365002] systemd[1082]: Starting D-Bus User Message Bus Socket...1924machine # [ 39.368605] systemd[1082]: Starting Create User Files and Directories...1925machine # [ 39.424512] systemd[1082]: Finished Create User Files and Directories.1926machine # [ 39.483858] systemd[1082]: Listening on D-Bus User Message Bus Socket.1927machine # [ 39.487438] systemd[1082]: Reached target Sockets.1928machine # [ 39.490368] systemd[1082]: Reached target Basic System.1929machine # [ 39.491975] systemd[1]: Started User Manager for UID 60388.1930machine # [ 39.495519] systemd[1082]: Starting Run user-specific NixOS activation...1931machine # [ 39.500639] systemd[1]: Started Session 1 of User test-homed-user.1932machine # [ 39.548105] systemd[1082]: Finished Run user-specific NixOS activation.1933machine # [ 39.551209] systemd[1082]: Reached target Main User Target.1934machine # [ 39.552287] systemd[1082]: Startup finished in 547ms.1935machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 3.35 seconds)1936machine: sending keys 'whoami > /tmp/2\n'1937machine: (finished: sending keys 'whoami > /tmp/2\n', in 0.17 seconds)1938machine: waiting for file '/tmp/2'1939machine: (finished: waiting for file '/tmp/2', in 1.12 seconds)1940machine: must succeed: cat /tmp/21941machine: (finished: must succeed: cat /tmp/2, in 0.05 seconds)1942(finished: subtest: login as homed user, in 6.22 seconds)1943subtest: login as regular user1944sshClient: waiting for login: to appear on tty 11945sshClient: waiting for the VM to finish booting1946sshClient: Guest shell says: b'Spawning backdoor root shell...\n'1947sshClient: connected to guest root shell1948sshClient: (connecting took 0.00 seconds)1949sshClient: (finished: waiting for the VM to finish booting, in 0.00 seconds)1950sshClient: (finished: waiting for login: to appear on tty 1, in 0.12 seconds)1951sshClient: sending keys 'test-normal-user\n'1952sshClient: (finished: sending keys 'test-normal-user\n', in 0.18 seconds)1953sshClient: waiting for Password: to appear on tty 11954sshClient: (finished: waiting for Password: to appear on tty 1, in 1.20 seconds)1955sshClient: sending keys 'foobarfoo\n'1956sshClient: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1957sshClient: waiting for success: pgrep -u test-normal-user bash1958sshClient # [ 43.649145] login[766]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0)1959sshClient # [ 43.839775] systemd-logind[601]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'.1960sshClient # [ 43.858269] systemd[1]: Created slice Slice /user/1000.1961sshClient # [ 43.864256] systemd[1]: Starting User Runtime Directory /run/user/1000...1962sshClient # [ 43.933136] systemd[1]: Finished User Runtime Directory /run/user/1000.1963sshClient # [ 43.942083] systemd[1]: Starting User Manager for UID 1000...1964sshClient # [ 43.999158] (systemd)[877]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0)1965sshClient # [ 44.006103] systemd-logind[601]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'.1966sshClient # [ 44.045781] systemd[877]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1967sshClient # [ 44.272663] systemd[877]: Queued start job for default target Main User Target.1968sshClient # [ 44.276997] systemd[877]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1969sshClient # [ 44.280784] systemd[877]: Created slice User Application Slice.1970sshClient # [ 44.281694] systemd[877]: Started Daily Cleanup of User's Temporary Directories.1971sshClient # [ 44.282683] systemd[877]: Reached target Paths.1972sshClient # [ 44.283314] systemd[877]: Reached target Timers.1973sshClient # [ 44.286190] systemd[877]: Starting D-Bus User Message Bus Socket...1974sshClient # [ 44.288538] systemd[877]: Starting Create User Files and Directories...1975sshClient # [ 44.338082] systemd[877]: Finished Create User Files and Directories.1976sshClient # [ 44.383010] systemd[877]: Listening on D-Bus User Message Bus Socket.1977sshClient # [ 44.387291] systemd[877]: Reached target Sockets.1978sshClient # [ 44.388248] systemd[877]: Reached target Basic System.1979sshClient # [ 44.389192] systemd[1]: Started User Manager for UID 1000.1980sshClient # [ 44.391375] systemd[877]: Starting Run user-specific NixOS activation...1981sshClient # [ 44.394915] systemd[1]: Started Session 1 of User test-normal-user.1982sshClient # [ 44.458868] systemd[877]: Finished Run user-specific NixOS activation.1983sshClient # [ 44.461502] systemd[877]: Reached target Main User Target.1984sshClient # [ 44.462601] systemd[877]: Startup finished in 430ms.1985sshClient: (finished: waiting for success: pgrep -u test-normal-user bash, in 1.19 seconds)1986sshClient: sending keys 'whoami > /tmp/1\n'1987sshClient: (finished: sending keys 'whoami > /tmp/1\n', in 0.17 seconds)1988sshClient: waiting for file '/tmp/1'1989sshClient: (finished: waiting for file '/tmp/1', in 1.11 seconds)1990sshClient: must succeed: cat /tmp/11991sshClient: (finished: must succeed: cat /tmp/1, in 0.04 seconds)1992(finished: subtest: login as regular user, in 4.12 seconds)1993subtest: add homed ssh authorized key1994sshClient: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n'1995sshClient: (finished: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n', in 0.50 seconds)1996sshClient: waiting for file '/tmp/id_ed25519.pub'1997sshClient: (finished: waiting for file '/tmp/id_ed25519.pub', in 1.11 seconds)1998sshClient: must succeed: cat /tmp/id_ed25519.pub1999sshClient: (finished: must succeed: cat /tmp/id_ed25519.pub, in 0.04 seconds)2000machine: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKztnbzkh6Q2PXrB9jwagKsLiiPGN4pimmm+JBa0bgHL test-normal-user@sshClient'2001machine # [ 47.724536] systemd-homed[597]: test-homed-user: changing state active → updating-while-active2002machine # [ 47.737333] systemd-homework[1136]: Offline update requested. Not touching embedded records.2003machine # [ 47.748257] systemd-homed[597]: test-homed-user: changing state updating-while-active → active2004machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKztnbzkh6Q2PXrB9jwagKsLiiPGN4pimmm+JBa0bgHL test-normal-user@sshClient', in 0.10 seconds)2005machine: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed255192006machine: (finished: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed25519, in 0.05 seconds)2007(finished: subtest: add homed ssh authorized key, in 1.80 seconds)2008subtest: change homed user password2009machine: sending keys 'passwd; echo $? > /tmp/3\n'2010machine: (finished: sending keys 'passwd; echo $? > /tmp/3\n', in 0.26 seconds)2011machine: waiting for New password: to appear on tty 12012machine: (finished: waiting for New password: to appear on tty 1, in 1.20 seconds)2013machine: sending keys 'barfoobar\n'2014machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds)2015machine: waiting for Retype new password: to appear on tty 12016machine: (finished: waiting for Retype new password: to appear on tty 1, in 0.10 seconds)2017machine: sending keys 'barfoobar\n'2018machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2019??? Warning (UserWarning): sleep(): The 'secs' argument is deprecated. Use 'duration' instead.2020 File "/nix/store/ifid8v1phbdldw2n5l1p1v3an4s9p3q8-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 5732021machine: must succeed: sleep 4.02022machine # [ 49.716351] systemd-homed[597]: test-homed-user: changing state active → passwd-while-active2023machine # [ 50.454004] systemd-homework[1167]: No valid password for LUKS superblock.2024machine # [ 52.739801] systemd-homed[597]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2025machine # [ 52.743686] systemd-homed[597]: test-homed-user: changing state passwd-while-active → active2026machine: (finished: must succeed: sleep 4.0, in 4.05 seconds)2027machine: sending keys 'foobarfoo\n'2028machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)2029machine: waiting for file '/tmp/3'2030machine # [ 53.914240] systemd-homed[597]: test-homed-user: changing state active → passwd-while-active2031machine # [ 55.226449] systemd-homework[1175]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2032machine # [ 55.352883] systemd-homework[1175]: Successfully re-activated LUKS device.2033machine # [ 55.406951] systemd-homework[1175]: Provided password unlocks user record.2034machine # [ 55.412291] systemd-homework[1175]: Discovered used loopback device /dev/loop1.2035machine # [ 55.413396] systemd-homework[1175]: Read embedded .identity file.2036machine # [ 55.461040] systemd-homework[1175]: Provided password unlocks user record.2037machine # [ 55.464074] systemd-homework[1175]: Reconciling header user identity completed (host version was newer).2038machine # [ 55.465430] systemd-homework[1175]: Reconciling embedded user identity completed (host version was newer).2039machine # [ 58.678580] systemd-homework[1175]: Added LUKS key slot 1.2040machine # [ 58.699562] systemd-homework[1175]: Destroyed LUKS key slot 0.2041machine # [ 58.708276] systemd-homework[1175]: Wrote LUKS header user record.2042machine # [ 58.709676] systemd-homework[1175]: Wrote embedded .identity file.2043machine # [ 58.710645] systemd-homework[1175]: Replaced embedded blob dir with contents of system blob dir.2044machine # [ 58.715379] systemd-homework[1175]: Synchronized disk.2045machine # [ 58.724058] systemd-homework[1175]: Everything completed.2046machine # [ 58.734690] systemd-homed[597]: test-homed-user: changing state passwd-while-active → active2047machine # [ 58.736877] passwd[1149]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user.2048machine: (finished: waiting for file '/tmp/3', in 5.29 seconds)2049machine: must succeed: cat /tmp/32050machine: (finished: must succeed: cat /tmp/3, in 0.04 seconds)2051(finished: subtest: change homed user password, in 11.25 seconds)2052subtest: escalate to root from homed user2053machine: sending keys 'sudo id | tee /tmp/4\n'2054machine: (finished: sending keys 'sudo id | tee /tmp/4\n', in 0.22 seconds)2055machine: waiting for password for test-homed-user to appear on tty 12056machine # [ 59.562897] systemd-homed[597]: test-homed-user: changing state active → authenticating-for-acquire2057machine # [ 59.574926] systemd-homework[1212]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2058machine # [ 59.586351] systemd-homed[597]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2059machine # [ 59.591889] systemd-homed[597]: test-homed-user: changing state authenticating-for-acquire → active2060machine: (finished: waiting for password for test-homed-user to appear on tty 1, in 1.19 seconds)2061machine: sending keys 'barfoobar\n'2062machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds)2063machine: waiting for file '/tmp/4'2064machine: (finished: waiting for file '/tmp/4', in 0.05 seconds)2065machine: waiting for success: grep uid=0 /tmp/42066machine # [ 60.659889] systemd-homed[597]: test-homed-user: changing state active → authenticating-for-acquire2067machine # [ 60.725421] systemd-homework[1228]: Provided password unlocks user record.2068machine # [ 61.378596] systemd-homework[1228]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2069machine # [ 61.505704] systemd-homework[1228]: Successfully re-activated LUKS device.2070machine # [ 61.536905] systemd-homework[1228]: Provided password unlocks user record.2071machine # [ 61.540082] systemd-homework[1228]: Discovered used loopback device /dev/loop1.2072machine # [ 61.541179] systemd-homework[1228]: Read embedded .identity file.2073machine # [ 61.566885] systemd-homework[1228]: Provided password unlocks user record.2074machine # [ 61.568977] systemd-homework[1228]: Reconciling user identities completed (host and header version were identical).2075machine # [ 61.570884] systemd-homework[1228]: Reconciling embedded user identity completed (host and embedded version were identical).2076machine # [ 61.580180] systemd-homework[1228]: Everything completed.2077machine # [ 61.592650] systemd-homed[597]: Home test-homed-user is signed exclusively by our key, accepting.2078machine # [ 61.598830] systemd-homed[597]: test-homed-user: changing state authenticating-for-acquire → active2079machine # [ 61.603482] sudo[1210]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired.2080machine # [ 61.606365] sudo[1210]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id2081machine # [ 61.622926] sudo[1210]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388)2082machine # [ 61.640529] sudo[1210]: pam_unix(sudo:session): session closed for user root2083machine: (finished: waiting for success: grep uid=0 /tmp/4, in 1.12 seconds)2084(finished: subtest: escalate to root from homed user, in 2.70 seconds)2085subtest: log out and deactivate homed user's home area2086machine: sending keys 'exit\n'2087machine: (finished: sending keys 'exit\n', in 0.05 seconds)2088machine: waiting for success: homectl inspect test-homed-user | grep 'State: inactive'2089machine # [ 61.845400] login[998]: pam_unix(login:session): session closed for user test-homed-user2090machine # [ 61.847885] login[998]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used.2091machine # [ 61.865288] systemd[1]: getty@tty1.service: Deactivated successfully.2092machine # [ 61.884754] systemd[1]: session-1.scope: Deactivated successfully.2093machine # [ 61.897613] systemd-logind[626]: Session 1 logged out. Waiting for processes to exit.2094machine # [ 61.899518] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.2095machine # [ 61.905079] systemd[1]: Started Getty on tty1.2096machine # [ 61.913324] systemd-logind[626]: Removed session 1.2097machine # [ 72.063190] systemd[1]: Stopping User Manager for UID 60388...2098machine # [ 72.081495] systemd[1082]: Activating special unit Exit the Session...2099machine # [ 72.090926] systemd[1082]: Stopped target Main User Target.2100machine # [ 72.101834] systemd[1082]: Stopped Run user-specific NixOS activation.2101machine # [ 72.105009] systemd[1082]: Stopped target Basic System.2102machine # [ 72.107142] systemd[1082]: Stopped target Paths.2103machine # [ 72.109287] systemd[1082]: Stopped target Sockets.2104machine # [ 72.111226] systemd[1082]: Stopped target Timers.2105machine # [ 72.113369] systemd[1082]: Stopped Daily Cleanup of User's Temporary Directories.2106machine # [ 72.115970] systemd[1082]: Closed D-Bus User Message Bus Socket.2107machine # [ 72.121071] systemd[1082]: Stopped Create User Files and Directories.2108machine # [ 72.123366] systemd[1082]: Removed slice User Application Slice.2109machine # [ 72.125841] systemd[1082]: Reached target Shutdown.2110machine # [ 72.127643] systemd[1082]: Finished Exit the Session.2111machine # [ 72.140479] systemd[1082]: Reached target Exit the Session.2112machine # [ 72.143010] systemd-homed[597]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2113machine # [ 72.155422] systemd-logind[626]: Removed session 2.2114machine # [ 72.168575] systemd-homed[597]: test-homed-user: changing state active → deactivating2115machine # [ 72.170946] systemd[1]: user@60388.service: Deactivated successfully.2116machine # [ 72.176618] systemd[1]: Stopped User Manager for UID 60388.2117machine # [ 72.178960] systemd-homework[1301]: Successfully acquired home volume key from kernel keyring.2118machine # [ 72.183294] systemd-homework[1301]: Discarded unused 668M.2119machine # [ 72.194875] systemd-homework[1301]: Syncing completed.2120machine # [ 72.213914] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully.2121machine # [ 72.230719] systemd[1]: Stopping User Runtime Directory /run/user/60388...2122machine # [ 72.293773] systemd[1]: run-user-60388.mount: Deactivated successfully.2123machine # [ 72.301713] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully.2124machine # [ 72.304779] systemd[1]: Stopped User Runtime Directory /run/user/60388.2125machine # [ 72.320997] systemd-homework[1301]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2126machine # [ 72.449014] systemd-homework[1301]: Successfully re-activated LUKS device.2127machine # [ 72.451614] systemd-homework[1301]: Discovered used loopback device /dev/loop1.2128machine # [ 72.455096] systemd-homework[1301]: offset = 1048576, size = 792723456, image = 7948206082129machine # [ 72.476558] systemd-homework[1301]: Ready to resize image size 758M → 274M, partition size 756M → 272M, file system size 740M → 256M.2130machine # [ 72.478971] systemd-homework[1301]: Allocated additional 667.9M.2131machine # [ 72.497550] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 775946240 to 2684354562132machine # [ 72.485746] systemd-homework[1301]: File system resizing from 740M to 256M completed.2133machine # [ 72.492177] systemd-homework[1301]: Synchronized disk.2134machine # [ 72.656816] systemd-homework[1301]: LUKS device shrinking completed.2135machine # [ 72.682695] loop1: detected capacity change from 1548288 to 5570562136machine # [ 72.674607] systemd-homework[1301]: Refreshing loop device size completed.2137machine # [ 72.676349] systemd-homework[1301]: Shrinking of image file completed.2138machine # [ 72.741964] systemd-homework[1301]: Shrinking of partition completed.2139machine # [ 72.743068] systemd-homework[1301]: Synchronized disk.2140machine # [ 72.743815] systemd-homework[1301]: Resizing completed.2141machine # [ 72.759463] BTRFS info (device dm-0): last unmount of filesystem 01dca48c-3008-45c5-a8c7-367fd644b7e92142machine # [ 72.751356] systemd-homework[1301]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M.2143machine # [ 72.758722] systemd-homework[1301]: Unmounting completed.2144machine # [ 72.759509] systemd-homework[1301]: Discovered used LUKS device /dev/mapper/home-test-homed-user.2145machine # [ 72.773869] systemd-homed[597]: block device /sys/devices/virtual/block/dm-0 has been removed.2146machine # [ 72.789390] systemd-homework[1301]: LUKS device detaching completed.2147machine # [ 72.795250] systemd-homed[597]: block device /sys/devices/virtual/block/dm-0 has been removed.2148machine # [ 72.800873] systemd-homework[1301]: Everything completed.2149machine # [ 72.811056] systemd-homed[597]: test-homed-user: changing state deactivating → inactive2150machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 12.11 seconds)2151(finished: subtest: log out and deactivate homed user's home area, in 12.16 seconds)2152subtest: ssh as homed user2153sshClient: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n'2154sshClient: (finished: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n', in 0.80 seconds)2155sshClient: waiting for Please enter password for user to appear on tty 12156machine # [ 75.907346] systemd-homed[597]: test-homed-user: changing state inactive → activating-for-acquire2157machine # [ 75.924697] systemd-homework[1338]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2158machine # [ 76.009363] systemd-homed[597]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2159machine # [ 76.020497] systemd-homed[597]: test-homed-user: changing state activating-for-acquire → inactive2160machine # [ 76.027251] sshd-session[1334]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error2161machine # [ 76.033758] systemd-homed[597]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2162machine # [ 76.041653] sshd-session[1334]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation.2163machine # [ 76.061483] systemd-homed[597]: Home test-homed-user already deactivated, no automatic deactivation needed.2164machine # [ 76.076763] sshd-session[1334]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 45888 ssh2: ED25519 SHA256:FX2naD2JncvuLa4MhF0g2iPRxobV8/rjGCLubQXqiuo2165machine # [ 76.149279] sshd-session[1334]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0)2166machine # [ 76.179193] systemd-logind[626]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'.2167machine # [ 76.187049] systemd[1]: Starting User Runtime Directory /run/user/60388...2168machine # [ 76.232511] systemd[1]: Finished User Runtime Directory /run/user/60388.2169machine # [ 76.245294] systemd[1]: Started Session 3 of User test-homed-user.2170machine # [ 76.265871] sshd-session[1334]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2171machine # [ 76.269596] sshd-session[1334]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2172machine # [ 76.289165] systemd-homed[597]: test-homed-user: changing state inactive → activating2173machine # [ 76.296431] systemd-homework[1347]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2174machine # [ 76.303109] systemd-homed[597]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2175machine # [ 76.305574] systemd-homed[597]: test-homed-user: changing state activating → inactive2176sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.28 seconds)2177sshClient: sending keys 'barfoobar\n'2178sshClient: (finished: sending keys 'barfoobar\n', in 0.11 seconds)2179machine: waiting for success: pgrep -u test-homed-user bash2180machine # [ 77.188693] systemd-homed[597]: test-homed-user: changing state inactive → activating2181machine # [ 77.254231] systemd-homework[1352]: Provided password unlocks user record.2182machine # [ 77.263893] systemd-homework[1352]: Successfully locked image file '/home/test-homed-user.home'.2183machine # [ 77.270593] systemd-homework[1352]: Backing file is fully allocated already.2184machine # [ 77.290599] loop1: detected capacity change from 0 to 5570562185machine # [ 77.278707] systemd-homework[1352]: Setting up loopback device /dev/loop1 completed.2186machine # [ 78.212167] systemd-homework[1352]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.2187machine # [ 78.265302] systemd-homework[1352]: Provided password unlocks user record.2188machine # [ 78.273106] systemd-homework[1352]: Probing file system completed (found btrfs).2189machine # [ 78.275070] systemd-homework[1352]: No fsck for file system btrfs installed, ignoring.2190machine # [ 78.294140] BTRFS: device label test-homed-user devid 1 transid 17 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (1352)2191machine # [ 78.298256] BTRFS info (device dm-0): first mount of filesystem 01dca48c-3008-45c5-a8c7-367fd644b7e92192machine # [ 78.298316] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm2193machine # [ 78.307366] BTRFS info (device dm-0): enabling free space tree2194machine # [ 78.307404] BTRFS info (device dm-0): use zstd compression, level 12195machine # [ 78.295094] systemd-homework[1352]: Mounting file system completed.2196machine # [ 78.303197] systemd-homework[1352]: Discovered used loopback device /dev/loop1.2197machine # [ 78.304416] systemd-homework[1352]: offset = 1048576, size = 285212672, image = 2873098242198machine # [ 78.317556] systemd-homework[1352]: Ready to resize image size 274M → 758M, partition size 272M → 756M, file system size 256M → 740M.2199machine # [ 78.319351] systemd-homework[1352]: Growing of image file from 274M to 758M completed.2200machine # [ 78.342703] loop1: detected capacity change from 557056 to 15482882201machine # [ 78.334135] systemd-homework[1352]: Refreshing loop device size completed.2202machine # [ 78.346547] systemd-homework[1352]: Growing of partition completed.2203machine # [ 78.398294] systemd-homework[1352]: LUKS device growing completed.2204machine # [ 78.417545] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 7759462402205machine # [ 78.404906] systemd-homework[1352]: File system resizing from 256M to 740M completed.2206machine # [ 78.412236] systemd-homework[1352]: Synchronized disk.2207machine # [ 78.413055] systemd-homework[1352]: Synchronized disk.2208machine # [ 78.413783] systemd-homework[1352]: Resizing completed.2209machine # [ 78.414494] systemd-homework[1352]: Image size is 758M, file system size is 740M, file system payload size is 740M, file system free is 733.9M.2210machine # [ 78.420223] systemd-homework[1352]: Read embedded .identity file.2211machine # [ 78.441845] systemd-homework[1352]: Provided password unlocks user record.2212machine # [ 78.444061] systemd-homework[1352]: Reconciling user identities completed (host and header version were identical).2213machine # [ 78.445486] systemd-homework[1352]: Reconciling embedded user identity completed (host and embedded version were identical).2214machine # [ 78.448064] systemd-homework[1352]: Recursive changing of ownership not necessary, skipped.2215machine # [ 78.454955] systemd-homework[1352]: Synchronized disk.2216machine # [ 78.455840] systemd-homework[1352]: Moving to final mount point /home/test-homed-user completed.2217machine # [ 78.482187] systemd-homework[1352]: Activation completed.2218machine # [ 78.483438] systemd-homework[1352]: Image size is 756M, file system size is 740M, file system payload size is 740M, file system free is 733.9M.2219machine # [ 78.497165] systemd-homed[597]: Home test-homed-user is signed exclusively by our key, accepting.2220machine # [ 78.502992] systemd-homed[597]: test-homed-user: changing state activating → active2221machine # [ 78.528675] systemd[1]: Starting User Manager for UID 60388...2222machine # [ 78.585425] (systemd)[1394]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.2223machine # [ 78.640788] (systemd)[1394]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)2224machine # [ 78.648497] systemd-logind[626]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'.2225machine # [ 78.694107] systemd[1394]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2226machine # [ 78.891994] systemd[1394]: Queued start job for default target Main User Target.2227machine # [ 78.897080] systemd[1394]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2228machine # [ 78.901508] systemd[1394]: Created slice User Application Slice.2229machine # [ 78.902478] systemd[1394]: Started Daily Cleanup of User's Temporary Directories.2230machine # [ 78.903487] systemd[1394]: Reached target Paths.2231machine # [ 78.904491] systemd[1394]: Reached target Timers.2232machine # [ 78.907343] systemd[1394]: Starting D-Bus User Message Bus Socket...2233machine # [ 78.910111] systemd[1394]: Starting Create User Files and Directories...2234machine # [ 78.958412] systemd[1394]: Finished Create User Files and Directories.2235machine # [ 78.962475] systemd[1394]: Listening on D-Bus User Message Bus Socket.2236machine # [ 78.967163] systemd[1394]: Reached target Sockets.2237machine # [ 78.968853] systemd[1394]: Reached target Basic System.2238machine # [ 78.969852] systemd[1]: Started User Manager for UID 60388.2239machine # [ 78.971973] systemd[1394]: Starting Run user-specific NixOS activation...2240machine # [ 79.032541] systemd[1394]: Finished Run user-specific NixOS activation.2241machine # [ 79.034846] systemd[1394]: Reached target Main User Target.2242machine # [ 79.038932] systemd[1394]: Startup finished in 359ms.2243machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.33 seconds)2244sshClient: sending keys 'whoami > /tmp/5\n'2245sshClient: (finished: sending keys 'whoami > /tmp/5\n', in 0.17 seconds)2246machine: waiting for file '/tmp/5'2247machine: (finished: waiting for file '/tmp/5', in 1.11 seconds)2248machine: must succeed: cat /tmp/52249machine: (finished: must succeed: cat /tmp/5, in 0.04 seconds)2250sshClient: sending keys 'exit\n'2251sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2252sshClient: sending keys 'exit\n'2253sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2254(finished: subtest: ssh as homed user, in 6.95 seconds)2255(finished: run the VM test script, in 81.75 seconds)2256machine # [ 80.858418] sshd-session[1345]: Received disconnect from 2001:db8:1::2 port 45888:11: disconnected by user2257machine # [ 80.869887] sshd-session[1345]: Disconnected from user test-homed-user 2001:db8:1::2 port 458882258machine # [ 80.893659] sshd-session[1334]: pam_unix(sshd:session): session closed for user test-homed-user2259machine # [ 80.911823] sshd-session[1334]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used.2260machine # [ 80.923890] systemd[1]: session-3.scope: Deactivated successfully.2261machine # [ 80.926972] systemd-logind[626]: Session 3 logged out. Waiting for processes to exit.2262machine # [ 80.929521] systemd-logind[626]: Removed session 3.2263test script finished in 81.93s2264cleanup2265kill QemuMachine (pid 47)2266machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)2267kill QemuMachine (pid 48)2268sshClient # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)2269(finished: cleanup, in 0.41 seconds)2270additionally exposed symbols:2271 machine, sshClient,2272 vlan1,2273 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