vm-test-run-systemd-homed
checks.aarch64-linux.systemd-homed
· build #65
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.09 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: QEMU running (pid 48)12sshClient # Formatting '/build/vm-state-sshClient/tmp.yM2oppDkZ2', fmt=raw size=107374182413sshClient # mke2fs 1.47.4 (6-Mar-2025)14sshClient # Discarding device blocks: 0/262144 done15sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes16sshClient # Filesystem UUID: a3bd74eb-1caa-4f31-98e8-ebce3a7cff7817sshClient # Superblock backups stored on blocks:18sshClient # 32768, 98304, 163840, 22937619sshClient # 20sshClient # Allocating group tables: 0/8 done21sshClient # Writing inode tables: 0/8 done22sshClient # Creating journal (8192 blocks): done23sshClient # Writing superblocks and filesystem accounting information: 0/8 done24sshClient # 25sshClient # Virtualisation disk image created.26sshClient # Starting virtiofs daemons...27sshClient # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28sshClient # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29sshClient # [2026-10-23T03:05:52Z INFO virtiofsd] Waiting for vhost-user socket connection...30sshClient # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31sshClient # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32sshClient # [2026-10-23T03:05:52Z INFO virtiofsd] Waiting for vhost-user socket connection...33sshClient # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34sshClient # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35sshClient # [2026-10-23T03:05:52Z INFO virtiofsd] Waiting for vhost-user socket connection...36sshClient # [2026-10-23T03:05:52Z INFO virtiofsd] Client connected, servicing requests37sshClient # [2026-10-23T03:05:52Z INFO virtiofsd] Client connected, servicing requests38sshClient # [2026-10-23T03:05:52Z INFO virtiofsd] Client connected, servicing requests39machine # Disk image does not exist, creating the virtualisation disk image...40machine # Formatting '/build/vm-state-machine/tmp.cq5iUQ6xrj', fmt=raw size=107374182441machine: QEMU running (pid 47)42machine # mke2fs 1.47.4 (6-Mar-2025)43machine # Discarding device blocks: 0/262144 done44machine # Creating filesystem with 262144 4k blocks and 65536 inodes45machine # Filesystem UUID: a6ea0050-3b8b-40a7-b5e4-d8af0a78326546machine # Superblock backups stored on blocks:47machine # 32768, 98304, 163840, 22937648(finished: start all VMs, in 2.39 seconds)49machine # 50machine # Allocating group tables: 0/8 done51subtest: create systemd-homed user on first boot prompt52machine # Writing inode tables: 0/8 done53machine: waiting for unit systemd-homed.service54machine # Creating journal (8192 blocks): done55machine: waiting for the VM to finish booting56machine # Writing superblocks and filesystem accounting information: 0/8 done57machine # 58machine # Virtualisation disk image created.59machine # Starting virtiofs daemons...60machine # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)61machine # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether62machine # [2026-10-23T03:05:52Z INFO virtiofsd] Waiting for vhost-user socket connection...63machine # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)64machine # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether65machine # [2026-10-23T03:05:52Z INFO virtiofsd] Waiting for vhost-user socket connection...66machine # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)67machine # [2026-10-23T03:05:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68machine # [2026-10-23T03:05:52Z INFO virtiofsd] Waiting for vhost-user socket connection...69machine # [2026-10-23T03:05:52Z INFO virtiofsd] Client connected, servicing requests70machine # [2026-10-23T03:05:52Z INFO virtiofsd] Client connected, servicing requests71machine # [2026-10-23T03:05:52Z INFO virtiofsd] Client connected, servicing requests72machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]73machine # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @179205830074machine # [ 0.000000] KASLR enabled75machine # [ 0.000000] random: crng init done76machine # [ 0.000000] Machine model: linux,dummy-virt77machine # [ 0.000000] efi: UEFI not found.78machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT79machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]80machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]81machine # [ 0.000000] Zone ranges:82machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]83machine # [ 0.000000] DMA32 empty84machine # [ 0.000000] Normal empty85machine # [ 0.000000] Device empty86machine # [ 0.000000] Movable zone start for each node87machine # [ 0.000000] Early memory node ranges88machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]89machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]90machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000091machine # [ 0.000000] psci: probing for conduit method from DT.92machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.93machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs94machine # [ 0.000000] psci: Trusted OS migration not required95machine # [ 0.000000] psci: SMC Calling Convention v1.196machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)97machine # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u31129698machine # [ 0.000000] Detected PIPT I-cache on CPU099machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)100machine # [ 0.000000] CPU features: detected: GICv3 CPU interface101machine # [ 0.000000] CPU features: detected: Spectre-v4102machine # [ 0.000000] CPU features: detected: Spectre-BHB103machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38104machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23105machine # [ 0.000000] alternatives: applying boot alternatives106sshClient # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]107sshClient # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1792058300108sshClient # [ 0.000000] KASLR enabled109sshClient # [ 0.000000] random: crng init done110machine # [ 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/g3z59v7k5qdjgb547p7wxg0gzwvkcvn8-nixos-system-machine-test/init regInfo=/nix/.ro-store/fcf77dspcc5w4hzhklj1a9zjb4lwqj26-closure-info/registration console=ttyAMA0,115200n8 console=tty0111sshClient # [ 0.000000] Machine model: linux,dummy-virt112sshClient # [ 0.000000] efi: UEFI not found.113sshClient # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT114machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/fcf77dspcc5w4hzhklj1a9zjb4lwqj26-closure-info/registration", will be passed to user space.115sshClient # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]116machine # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes117sshClient # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]118sshClient # [ 0.000000] Zone ranges:119machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)120sshClient # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]121sshClient # [ 0.000000] DMA32 empty122machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)123sshClient # [ 0.000000] Normal empty124sshClient # [ 0.000000] Device empty125machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB126sshClient # [ 0.000000] Movable zone start for each node127machine # [ 0.000000] software IO TLB: area num 1.128sshClient # [ 0.000000] Early memory node ranges129machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)130sshClient # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]131machine # [ 0.000000] Fallback order for Node 0: 0132sshClient # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]133machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144134sshClient # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000135machine # [ 0.000000] Policy zone: DMA136sshClient # [ 0.000000] psci: probing for conduit method from DT.137machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off138sshClient # [ 0.000000] psci: PSCIv1.3 detected in firmware.139machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1140sshClient # [ 0.000000] psci: Using standard PSCI v0.2 function IDs141machine # [ 0.000000] allocated 2097152 bytes of page_ext142sshClient # [ 0.000000] psci: Trusted OS migration not required143sshClient # [ 0.000000] psci: SMC Calling Convention v1.1144machine # [ 0.000000] ftrace: allocating 75301 entries in 296 pages145machine # [ 0.000000] ftrace: allocated 296 pages with 3 groups146sshClient # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)147machine # [ 0.000000] rcu: Hierarchical RCU implementation.148machine # [ 0.000000] rcu: RCU event tracing is enabled.149sshClient # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u311296150sshClient # [ 0.000000] Detected PIPT I-cache on CPU0151machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.152machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.153sshClient # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)154machine # [ 0.000000] Rude variant of Tasks RCU enabled.155sshClient # [ 0.000000] CPU features: detected: GICv3 CPU interface156machine # [ 0.000000] Tracing variant of Tasks RCU enabled.157sshClient # [ 0.000000] CPU features: detected: Spectre-v4158sshClient # [ 0.000000] CPU features: detected: Spectre-BHB159machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.160sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38161machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1162sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23163machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.164sshClient # [ 0.000000] alternatives: applying boot alternatives165machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.166machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.167machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0168machine # [ 0.000000] GICv3: 256 SPIs implemented169sshClient # [ 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/5fy3hcm4l7bcy4gn68s4mcazymyrkqa6-nixos-system-sshClient-test/init regInfo=/nix/.ro-store/422maq87rahdpjh07nks4g9570jp2l9q-closure-info/registration console=ttyAMA0,115200n8 console=tty0170machine # [ 0.000000] GICv3: 0 Extended SPIs implemented171machine # [ 0.000000] Root IRQ handler: gic_handle_irq172machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI173machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0174sshClient # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/422maq87rahdpjh07nks4g9570jp2l9q-closure-info/registration", will be passed to user space.175machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000176sshClient # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes177machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]178sshClient # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)179machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450f0000 (indirect, esz 8, psz 64K, shr 1)180sshClient # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)181sshClient # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB182machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @45100000 (flat, esz 8, psz 64K, shr 1)183sshClient # [ 0.000000] software IO TLB: area num 1.184machine # [ 0.000000] GICv3: using LPI property table @0x0000000045110000185sshClient # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)186machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000187sshClient # [ 0.000000] Fallback order for Node 0: 0188machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.189sshClient # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144190sshClient # [ 0.000000] Policy zone: DMA191machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns192sshClient # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off193machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).194sshClient # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1195sshClient # [ 0.000000] allocated 2097152 bytes of page_ext196machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns197sshClient # [ 0.000000] ftrace: allocating 75301 entries in 296 pages198sshClient # [ 0.000000] ftrace: allocated 296 pages with 3 groups199machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns200sshClient # [ 0.000000] rcu: Hierarchical RCU implementation.201machine # [ 0.000035] arm-pv: using stolen time PV202sshClient # [ 0.000000] rcu: RCU event tracing is enabled.203sshClient # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.204machine # [ 0.000462] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)205machine # [ 0.000605] Console: colour dummy device 80x25206sshClient # [ 0.000000] Trampoline variant of Tasks RCU enabled.207machine # [ 0.000614] printk: legacy console [tty0] enabled208sshClient # [ 0.000000] Rude variant of Tasks RCU enabled.209sshClient # [ 0.000000] Tracing variant of Tasks RCU enabled.210machine # [ 0.000816] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)211sshClient # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.212machine # [ 0.000823] pid_max: default: 32768 minimum: 301213sshClient # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1214machine # [ 0.000917] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima215machine # [ 0.001050] landlock: Up and running.216sshClient # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.217machine # [ 0.001053] Yama: becoming mindful.218machine # [ 0.001559] LSM support for eBPF active219sshClient # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.220machine # [ 0.002387] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)221sshClient # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.222machine # [ 0.002409] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)223sshClient # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0224machine # [ 0.004865] rcu: Hierarchical SRCU implementation.225sshClient # [ 0.000000] GICv3: 256 SPIs implemented226machine # [ 0.004872] rcu: Max phase no-delay instances is 1000.227sshClient # [ 0.000000] GICv3: 0 Extended SPIs implemented228machine # [ 0.006222] fsl-mc MSI: its@8080000 domain created229sshClient # [ 0.000000] Root IRQ handler: gic_handle_irq230machine # [ 0.006361] EFI services will not be available.231sshClient # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI232machine # [ 0.006462] smp: Bringing up secondary CPUs ...233sshClient # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0234machine # [ 0.006473] smp: Brought up 1 node, 1 CPU235sshClient # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000236machine # [ 0.006477] SMP: Total of 1 processors activated.237sshClient # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]238machine # [ 0.006483] CPU: All CPU(s) started at EL1239machine # [ 0.006493] CPU features: detected: Branch Target Identification240sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450e0000 (indirect, esz 8, psz 64K, shr 1)241machine # [ 0.006497] CPU features: detected: ARMv8.4 Translation Table Level242sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @450f0000 (flat, esz 8, psz 64K, shr 1)243machine # [ 0.006500] CPU features: detected: Instruction cache invalidation not required for I/D coherence244sshClient # [ 0.000000] GICv3: using LPI property table @0x0000000045110000245machine # [ 0.006504] CPU features: detected: Data cache clean to the PoU not required for I/D coherence246sshClient # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000247machine # [ 0.006511] CPU features: detected: Common not Private translations248sshClient # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.249machine # [ 0.006514] CPU features: detected: CRC32 instructions250sshClient # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns251machine # [ 0.006517] CPU features: detected: Data cache clean to Point of Deep Persistence252sshClient # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).253machine # [ 0.006521] CPU features: detected: Data cache clean to Point of Persistence254machine # [ 0.006524] CPU features: detected: Data independent timing control (DIT)255sshClient # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns256machine # [ 0.006527] CPU features: detected: E0PD257machine # [ 0.006529] CPU features: detected: Enhanced Counter Virtualization258sshClient # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns259sshClient # [ 0.000032] arm-pv: using stolen time PV260machine # [ 0.006532] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)261machine # [ 0.006536] CPU features: detected: Enhanced Virtualization Traps262sshClient # [ 0.000398] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)263machine # [ 0.006539] CPU features: detected: Fine Grained Traps264sshClient # [ 0.000589] Console: colour dummy device 80x25265sshClient # [ 0.000599] printk: legacy console [tty0] enabled266machine # [ 0.006542] CPU features: detected: Generic authentication (architected QARMA5 algorithm)267machine # [ 0.006547] CPU features: detected: RCpc load-acquire (LDAPR)268sshClient # [ 0.000787] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)269machine # [ 0.006550] CPU features: detected: LSE atomic instructions270sshClient # [ 0.000795] pid_max: default: 32768 minimum: 301271machine # [ 0.006553] CPU features: detected: Privileged Access Never272sshClient # [ 0.000902] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima273machine # [ 0.006555] CPU features: detected: PMUv3274sshClient # [ 0.001032] landlock: Up and running.275machine # [ 0.006558] CPU features: detected: RAS Extension Support276sshClient # [ 0.001035] Yama: becoming mindful.277sshClient # [ 0.001497] LSM support for eBPF active278machine # [ 0.006561] CPU features: detected: RASv1p1 Extension Support279machine # [ 0.006563] CPU features: detected: Random Number Generator280sshClient # [ 0.002616] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)281machine # [ 0.006571] CPU features: detected: Speculation barrier (SB)282sshClient # [ 0.002637] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)283machine # [ 0.006574] CPU features: detected: Stage-2 Force Write-Back284sshClient # [ 0.005212] rcu: Hierarchical SRCU implementation.285machine # [ 0.006576] CPU features: detected: TLB range maintenance instructions286sshClient # [ 0.005220] rcu: Max phase no-delay instances is 1000.287sshClient # [ 0.006491] fsl-mc MSI: its@8080000 domain created288machine # [ 0.006581] CPU features: detected: Speculative Store Bypassing Safe (SSBS)289sshClient # [ 0.006611] EFI services will not be available.290machine # [ 0.006621] alternatives: applying system-wide alternatives291sshClient # [ 0.006689] smp: Bringing up secondary CPUs ...292sshClient # [ 0.006700] smp: Brought up 1 node, 1 CPU293machine # [ 0.009747] CPU features: detected: BBM Level 2 without TLB conflict abort294sshClient # [ 0.006703] SMP: Total of 1 processors activated.295sshClient # [ 0.006706] CPU: All CPU(s) started at EL1296sshClient # [ 0.006718] CPU features: detected: Branch Target Identification297machine # [ 0.010126] Memory: 886720K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 120596K reserved, 32768K cma-reserved)298machine # [ 0.010557] devtmpfs: initialized299sshClient # [ 0.006722] CPU features: detected: ARMv8.4 Translation Table Level300machine # [ 0.012485] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)301sshClient # [ 0.006726] CPU features: detected: Instruction cache invalidation not required for I/D coherence302machine # [ 0.012510] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).303sshClient # [ 0.006729] CPU features: detected: Data cache clean to the PoU not required for I/D coherence304machine # [ 0.012705] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL305sshClient # [ 0.006737] CPU features: detected: Common not Private translations306machine # [ 0.012710] 0 pages in range for non-PLT usage307machine # [ 0.012712] 506368 pages in range for PLT usage308sshClient # [ 0.006740] CPU features: detected: CRC32 instructions309machine # [ 0.012836] pinctrl core: initialized pinctrl subsystem310sshClient # [ 0.006743] CPU features: detected: Data cache clean to Point of Deep Persistence311machine # [ 0.013726] DMI not present or invalid.312sshClient # [ 0.006746] CPU features: detected: Data cache clean to Point of Persistence313machine # [ 0.017048] NET: Registered PF_NETLINK/PF_ROUTE protocol family314sshClient # [ 0.006750] CPU features: detected: Data independent timing control (DIT)315machine # [ 0.021126] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations316sshClient # [ 0.006753] CPU features: detected: E0PD317machine # [ 0.021306] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations318sshClient # [ 0.006755] CPU features: detected: Enhanced Counter Virtualization319sshClient # [ 0.006758] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)320machine # [ 0.021474] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations321machine # [ 0.021501] audit: initializing netlink subsys (disabled)322sshClient # [ 0.006762] CPU features: detected: Enhanced Virtualization Traps323machine # [ 0.022578] thermal_sys: Registered thermal governor 'fair_share'324sshClient # [ 0.006765] CPU features: detected: Fine Grained Traps325machine # [ 0.022581] thermal_sys: Registered thermal governor 'bang_bang'326sshClient # [ 0.006769] CPU features: detected: Generic authentication (architected QARMA5 algorithm)327machine # [ 0.022585] thermal_sys: Registered thermal governor 'step_wise'328sshClient # [ 0.006774] CPU features: detected: RCpc load-acquire (LDAPR)329machine # [ 0.022588] thermal_sys: Registered thermal governor 'user_space'330sshClient # [ 0.006777] CPU features: detected: LSE atomic instructions331machine # [ 0.022590] thermal_sys: Registered thermal governor 'power_allocator'332sshClient # [ 0.006780] CPU features: detected: Privileged Access Never333sshClient # [ 0.006782] CPU features: detected: PMUv3334machine # [ 0.022631] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1335sshClient # [ 0.006785] CPU features: detected: RAS Extension Support336machine # [ 0.022640] cpuidle: using governor ladder337machine # [ 0.022647] cpuidle: using governor menu338sshClient # [ 0.006788] CPU features: detected: RASv1p1 Extension Support339machine # [ 0.022852] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.340sshClient # [ 0.006791] CPU features: detected: Random Number Generator341machine # [ 0.022873] ASID allocator initialised with 65536 entries342sshClient # [ 0.006796] CPU features: detected: Speculation barrier (SB)343sshClient # [ 0.006799] CPU features: detected: Stage-2 Force Write-Back344machine # [ 0.024143] Serial: AMBA PL011 UART driver345sshClient # [ 0.006802] CPU features: detected: TLB range maintenance instructions346machine # [ 0.029883] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1347machine # [ 0.030173] printk: console [ttyAMA0] enabled348sshClient # [ 0.006806] CPU features: detected: Speculative Store Bypassing Safe (SSBS)349sshClient # [ 0.006844] alternatives: applying system-wide alternatives350sshClient # [ 0.009871] CPU features: detected: BBM Level 2 without TLB conflict abort351sshClient # [ 0.010081] Memory: 886680K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 120600K reserved, 32768K cma-reserved)352sshClient # [ 0.010457] devtmpfs: initialized353sshClient # [ 0.012288] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)354sshClient # [ 0.012312] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).355sshClient # [ 0.012507] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL356sshClient # [ 0.012512] 0 pages in range for non-PLT usage357sshClient # [ 0.012513] 506368 pages in range for PLT usage358sshClient # [ 0.012616] pinctrl core: initialized pinctrl subsystem359sshClient # [ 0.013613] DMI not present or invalid.360sshClient # [ 0.016997] NET: Registered PF_NETLINK/PF_ROUTE protocol family361sshClient # [ 0.020145] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations362sshClient # [ 0.020295] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations363machine # [ 0.160256] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages364sshClient # [ 0.020453] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations365machine # [ 0.160295] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page366sshClient # [ 0.020477] audit: initializing netlink subsys (disabled)367machine # [ 0.160299] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages368sshClient # [ 0.021786] thermal_sys: Registered thermal governor 'fair_share'369machine # [ 0.160309] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page370sshClient # [ 0.021789] thermal_sys: Registered thermal governor 'bang_bang'371machine # [ 0.160313] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages372sshClient # [ 0.021793] thermal_sys: Registered thermal governor 'step_wise'373machine # [ 0.160316] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page374sshClient # [ 0.021796] thermal_sys: Registered thermal governor 'user_space'375machine # [ 0.160319] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages376sshClient # [ 0.021799] thermal_sys: Registered thermal governor 'power_allocator'377machine # [ 0.160321] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page378sshClient # [ 0.021830] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1379machine # [ 0.161590] fbcon: Taking over console380sshClient # [ 0.021839] cpuidle: using governor ladder381machine # [ 0.161601] ACPI: Interpreter disabled.382sshClient # [ 0.021846] cpuidle: using governor menu383machine # [ 0.171635] iommu: Default domain type: Translated384sshClient # [ 0.022073] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.385machine # [ 0.171641] iommu: DMA domain TLB invalidation policy: strict mode386sshClient # [ 0.022093] ASID allocator initialised with 65536 entries387machine # [ 0.172062] SCSI subsystem initialized388sshClient # [ 0.023403] Serial: AMBA PL011 UART driver389sshClient # [ 0.028978] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1390sshClient # [ 0.029125] printk: console [ttyAMA0] enabled391machine # [ 0.174188] usbcore: registered new interface driver usbfs392machine # [ 0.174218] usbcore: registered new interface driver hub393machine # [ 0.174233] usbcore: registered new device driver usb394machine # [ 0.174987] pps_core: LinuxPPS API ver. 1 registered395machine # [ 0.174992] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>396machine # [ 0.175001] PTP clock support registered397machine # [ 0.175050] EDAC MC: Ver: 3.0.0398machine # [ 0.181017] scmi_core: SCMI protocol bus registered399machine # [ 0.184685] FPGA manager framework400machine # [ 0.186422] vgaarb: loaded401machine # [ 0.186737] clocksource: Switched to clocksource arch_sys_counter402machine # [ 0.193709] VFS: Disk quotas dquot_6.6.0403machine # [ 0.193745] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)404sshClient # [ 0.159393] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages405sshClient # [ 0.159407] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page406sshClient # [ 0.159411] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages407sshClient # [ 0.159413] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page408machine # [ 0.196442] netfs: FS-Cache loaded409sshClient # [ 0.159416] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages410machine # [ 0.196571] pnp: PnP ACPI: disabled411sshClient # [ 0.159419] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page412sshClient # [ 0.159422] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages413sshClient # [ 0.159424] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page414sshClient # [ 0.160765] fbcon: Taking over console415sshClient # [ 0.160779] ACPI: Interpreter disabled.416sshClient # [ 0.178692] iommu: Default domain type: Translated417machine # [ 0.203274] NET: Registered PF_INET protocol family418sshClient # [ 0.178708] iommu: DMA domain TLB invalidation policy: strict mode419sshClient # [ 0.180026] SCSI subsystem initialized420machine # [ 0.203433] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)421sshClient # [ 0.183396] usbcore: registered new interface driver usbfs422sshClient # [ 0.183426] usbcore: registered new interface driver hub423sshClient # [ 0.183441] usbcore: registered new device driver usb424sshClient # [ 0.183784] pps_core: LinuxPPS API ver. 1 registered425sshClient # [ 0.183788] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>426sshClient # [ 0.183797] PTP clock support registered427sshClient # [ 0.183834] EDAC MC: Ver: 3.0.0428sshClient # [ 0.188723] scmi_core: SCMI protocol bus registered429sshClient # [ 0.189123] FPGA manager framework430sshClient # [ 0.189723] vgaarb: loaded431sshClient # [ 0.189984] clocksource: Switched to clocksource arch_sys_counter432sshClient # [ 0.191441] VFS: Disk quotas dquot_6.6.0433sshClient # [ 0.191472] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)434sshClient # [ 0.197470] netfs: FS-Cache loaded435sshClient # [ 0.197609] pnp: PnP ACPI: disabled436sshClient # [ 0.203673] NET: Registered PF_INET protocol family437sshClient # [ 0.203847] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)438machine # [ 0.235766] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)439machine # [ 0.235813] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)440machine # [ 0.235839] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)441machine # [ 0.235886] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)442machine # [ 0.235959] TCP: Hash tables configured (established 8192 bind 8192)443machine # [ 0.236049] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)444machine # [ 0.237296] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)445machine # [ 0.237322] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)446machine # [ 0.237405] NET: Registered PF_UNIX/PF_LOCAL protocol family447machine # [ 0.237451] NET: Registered PF_XDP protocol family448machine # [ 0.237468] PCI: CLS 0 bytes, default 64449machine # [ 0.237701] Trying to unpack rootfs image as initramfs...450machine # [ 0.255688] kvm [1]: HYP mode not available451sshClient # [ 0.237631] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)452sshClient # [ 0.237677] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)453sshClient # [ 0.237704] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)454sshClient # [ 0.237750] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)455sshClient # [ 0.237823] TCP: Hash tables configured (established 8192 bind 8192)456sshClient # [ 0.237923] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)457sshClient # [ 0.237983] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)458sshClient # [ 0.244737] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)459sshClient # [ 0.244834] NET: Registered PF_UNIX/PF_LOCAL protocol family460sshClient # [ 0.244887] NET: Registered PF_XDP protocol family461sshClient # [ 0.244905] PCI: CLS 0 bytes, default 64462sshClient # [ 0.245167] Trying to unpack rootfs image as initramfs...463sshClient # [ 0.254016] kvm [1]: HYP mode not available464machine # [ 0.383087] Initialise system trusted keyrings465machine # [ 0.383126] Key type blacklist registered466machine # [ 0.383256] workingset: timestamp_bits=42 max_order=18 bucket_order=0467machine # [ 0.383647] squashfs: version 4.0 (2009/01/31) Phillip Lougher468machine # [ 0.383709] 9p: Installing v9fs 9p2000 file system support469machine # [ 0.383811] integrity: Platform Keyring initialized470machine # [ 0.383820] integrity: Machine keyring initialized471sshClient # [ 0.378691] Initialise system trusted keyrings472sshClient # [ 0.378730] Key type blacklist registered473sshClient # [ 0.378958] workingset: timestamp_bits=42 max_order=18 bucket_order=0474sshClient # [ 0.379418] squashfs: version 4.0 (2009/01/31) Phillip Lougher475sshClient # [ 0.379472] 9p: Installing v9fs 9p2000 file system support476sshClient # [ 0.379572] integrity: Platform Keyring initialized477sshClient # [ 0.379581] integrity: Machine keyring initialized478machine # [ 0.415780] Key type asymmetric registered479machine # [ 0.415799] Asymmetric key parser 'x509' registered480machine # [ 0.415881] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)481machine # [ 0.416013] io scheduler mq-deadline registered482machine # [ 0.416018] io scheduler kyber registered483sshClient # [ 0.404365] Key type asymmetric registered484sshClient # [ 0.404382] Asymmetric key parser 'x509' registered485sshClient # [ 0.404499] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)486machine # [ 0.430943] pl061_gpio 9030000.pl061: PL061 GPIO chip registered487machine # [ 0.431660] ledtrig-cpu: registered to indicate activity on CPUs488machine # [ 0.432061] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:489machine # [ 0.432078] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000490machine # [ 0.432115] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000491machine # [ 0.432123] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000492machine # [ 0.432142] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits493machine # [ 0.432169] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]494machine # [ 0.432260] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00495sshClient # [ 0.414197] io scheduler mq-deadline registered496sshClient # [ 0.414212] io scheduler kyber registered497machine # [ 0.432268] pci_bus 0000:00: root bus resource [bus 00-ff]498machine # [ 0.432272] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]499machine # [ 0.432275] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]500machine # [ 0.432279] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]501sshClient # [ 0.419568] pl061_gpio 9030000.pl061: PL061 GPIO chip registered502machine # [ 0.432372] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint503sshClient # [ 0.420147] ledtrig-cpu: registered to indicate activity on CPUs504machine # [ 0.432827] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint505sshClient # [ 0.420511] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:506machine # [ 0.433017] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]507machine # [ 0.433029] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]508sshClient # [ 0.420526] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000509machine # [ 0.433056] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]510sshClient # [ 0.420539] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000511machine # [ 0.433068] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]512sshClient # [ 0.420546] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000513machine # [ 0.433533] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint514machine # [ 0.433718] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]515sshClient # [ 0.420564] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits516machine # [ 0.433729] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]517sshClient # [ 0.420584] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]518machine # [ 0.433756] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]519sshClient # [ 0.420656] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00520machine # [ 0.434231] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint521sshClient # [ 0.420663] pci_bus 0000:00: root bus resource [bus 00-ff]522machine # [ 0.434417] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]523sshClient # [ 0.420667] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]524machine # [ 0.434429] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]525sshClient # [ 0.420670] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]526machine # [ 0.434455] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]527sshClient # [ 0.420673] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]528sshClient # [ 0.420734] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint529sshClient # [ 0.421217] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint530sshClient # [ 0.421409] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]531sshClient # [ 0.421421] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]532machine # [ 0.459566] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint533sshClient # [ 0.421447] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]534machine # [ 0.459758] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]535sshClient # [ 0.421459] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]536machine # [ 0.459769] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]537sshClient # [ 0.421935] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint538machine # [ 0.459796] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]539machine # [ 0.459809] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]540machine # [ 0.460274] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint541machine # [ 0.460465] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]542machine # [ 0.460491] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]543machine # [ 0.460951] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint544machine # [ 0.461142] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]545sshClient # [ 0.446179] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]546sshClient # [ 0.446201] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]547sshClient # [ 0.446228] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]548sshClient # [ 0.446758] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint549sshClient # [ 0.446950] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]550machine # [ 0.461169] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]551sshClient # [ 0.446961] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]552sshClient # [ 0.446987] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]553machine # [ 0.461566] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint554machine # [ 0.461754] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]555sshClient # [ 0.447478] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint556sshClient # [ 0.447669] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]557machine # [ 0.462030] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint558sshClient # [ 0.447681] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]559machine # [ 0.462223] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]560sshClient # [ 0.447707] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]561machine # [ 0.462249] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]562sshClient # [ 0.447719] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]563machine # [ 0.462711] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint564sshClient # [ 0.448205] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint565sshClient # [ 0.448395] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]566machine # [ 0.485497] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]567sshClient # [ 0.448421] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]568machine # [ 0.485535] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]569sshClient # [ 0.448898] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint570sshClient # [ 0.449090] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]571machine # [ 0.486590] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint572sshClient # [ 0.449116] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]573sshClient # [ 0.449532] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint574machine # [ 0.489508] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]575sshClient # [ 0.449717] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]576machine # [ 0.489537] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]577sshClient # [ 0.449978] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint578machine # [ 0.490055] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint579machine # [ 0.490329] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]580machine # [ 0.490345] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]581machine # [ 0.490372] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]582machine # [ 0.495787] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint583machine # [ 0.495970] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]584machine # [ 0.495982] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]585machine # [ 0.496008] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]586machine # [ 0.496640] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned587machine # [ 0.496648] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned588sshClient # [ 0.474254] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]589sshClient # [ 0.474297] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]590machine # [ 0.496651] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned591sshClient # [ 0.474831] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint592machine # [ 0.496696] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned593sshClient # [ 0.475032] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]594machine # [ 0.496741] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned595sshClient # [ 0.475058] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]596machine # [ 0.496786] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned597sshClient # [ 0.475526] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint598machine # [ 0.496831] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned599sshClient # [ 0.475716] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]600sshClient # [ 0.475742] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]601machine # [ 0.496879] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned602sshClient # [ 0.476217] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint603machine # [ 0.496928] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned604sshClient # [ 0.476533] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]605machine # [ 0.496972] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned606sshClient # [ 0.476551] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]607machine # [ 0.497015] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned608sshClient # [ 0.476577] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]609machine # [ 0.497059] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned610sshClient # [ 0.477067] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint611sshClient # [ 0.477256] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]612machine # [ 0.497138] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned613sshClient # [ 0.477268] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]614machine # [ 0.497182] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned615sshClient # [ 0.477294] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]616machine # [ 0.497200] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned617sshClient # [ 0.477915] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned618machine # [ 0.497218] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned619sshClient # [ 0.477922] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned620machine # [ 0.497236] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned621machine # [ 0.497254] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned622sshClient # [ 0.477926] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned623machine # [ 0.497273] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned624sshClient # [ 0.477972] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned625machine # [ 0.497292] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned626machine # [ 0.497312] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned627machine # [ 0.497330] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned628machine # [ 0.497349] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned629sshClient # [ 0.495619] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned630machine # [ 0.497368] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned631sshClient # [ 0.495665] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned632machine # [ 0.497387] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned633sshClient # [ 0.495713] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned634machine # [ 0.497405] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned635sshClient # [ 0.495764] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned636machine # [ 0.497422] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned637machine # [ 0.497440] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned638sshClient # [ 0.495809] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned639machine # [ 0.497458] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned640sshClient # [ 0.495854] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned641machine # [ 0.497476] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned642sshClient # [ 0.495897] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned643machine # [ 0.497493] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned644sshClient # [ 0.495942] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned645machine # [ 0.497516] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]646machine # [ 0.497521] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]647sshClient # [ 0.496032] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned648machine # [ 0.497525] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]649sshClient # [ 0.496079] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned650machine # [ 0.498470] pci 0000:00:07.0: enabling device (0000 -> 0002)651sshClient # [ 0.496098] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned652sshClient # [ 0.496115] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned653sshClient # [ 0.496133] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned654sshClient # [ 0.496151] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned655sshClient # [ 0.496169] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned656sshClient # [ 0.496187] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned657sshClient # [ 0.496207] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned658sshClient # [ 0.496225] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned659sshClient # [ 0.496244] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned660sshClient # [ 0.496263] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned661sshClient # [ 0.496281] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned662sshClient # [ 0.496299] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned663machine # [ 0.538871] pci 0000:00:07.0: quirk_usb_early_handoff+0x0/0xa60 took 39457 usecs664sshClient # [ 0.496317] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned665sshClient # [ 0.496334] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned666sshClient # [ 0.496352] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned667sshClient # [ 0.496370] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned668sshClient # [ 0.496388] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned669sshClient # [ 0.496413] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]670sshClient # [ 0.496419] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]671sshClient # [ 0.496422] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]672sshClient # [ 0.497361] pci 0000:00:07.0: enabling device (0000 -> 0002)673sshClient # [ 0.530110] pci 0000:00:07.0: quirk_usb_early_handoff+0x0/0xa60 took 31985 usecs674sshClient # [ 0.558968] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)675machine # [ 0.569032] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)676sshClient # [ 0.582933] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)677machine # [ 0.632786] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)678sshClient # [ 0.607599] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)679sshClient # [ 0.621406] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)680sshClient # [ 0.623555] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)681sshClient # [ 0.670297] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)682sshClient # [ 0.672328] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)683sshClient # [ 0.675160] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)684sshClient # [ 0.707577] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)685machine # [ 0.643674] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)686machine # [ 0.646184] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)687machine # [ 0.784403] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)688machine # [ 0.787467] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)689machine # [ 0.789348] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)690machine # [ 0.807409] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)691machine # [ 0.809299] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)692machine # [ 0.819409] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)693sshClient # [ 0.710875] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)694sshClient # [ 0.713541] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)695machine # [ 0.832265] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)696sshClient # [ 0.855825] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled697sshClient # [ 0.857873] msm_serial: driver initialized698sshClient # [ 0.866244] SuperH (H)SCI(F) driver initialized699sshClient # [ 0.866315] STM32 USART driver initialized700machine # [ 0.916748] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled701machine # [ 0.918799] msm_serial: driver initialized702machine # [ 0.918989] SuperH (H)SCI(F) driver initialized703machine # [ 0.919053] STM32 USART driver initialized704sshClient # [ 0.909670] loop: module loaded705sshClient # [ 0.909883] virtio_blk virtio2: 1/0/0 default/read/poll queues706sshClient # [ 0.912154] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)707machine # [ 0.968899] loop: module loaded708machine # [ 0.969112] virtio_blk virtio2: 1/0/0 default/read/poll queues709machine # [ 0.970182] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)710sshClient # [ 0.942954] megasas: 07.734.00.00-rc1711sshClient # [ 0.945448] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]712sshClient # [ 0.981284] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000713sshClient # [ 0.981328] Intel/Sharp Extended Query Table at 0x0031714sshClient # [ 0.986377] Using buffer write method715machine # [ 1.000827] megasas: 07.734.00.00-rc1716sshClient # [ 0.986458] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]717machine # [ 1.001749] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]718machine # [ 1.005926] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000719machine # [ 1.005951] Intel/Sharp Extended Query Table at 0x0031720machine # [ 1.007885] Using buffer write method721machine # [ 1.007933] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]722machine # [ 1.023576] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000723machine # [ 1.023614] Intel/Sharp Extended Query Table at 0x0031724machine # [ 1.025202] Using buffer write method725machine # [ 1.025224] Concatenating MTD devices:726machine # [ 1.025226] (0): "0.flash"727machine # [ 1.025229] (1): "0.flash"728machine # [ 1.025231] into device "0.flash"729sshClient # [ 1.007000] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000730sshClient # [ 1.007034] Intel/Sharp Extended Query Table at 0x0031731sshClient # [ 1.009029] Using buffer write method732sshClient # [ 1.009058] Concatenating MTD devices:733sshClient # [ 1.009061] (0): "0.flash"734sshClient # [ 1.009064] (1): "0.flash"735sshClient # [ 1.009066] into device "0.flash"736machine # [ 1.266324] Freeing initrd memory: 26056K737machine # [ 1.272965] tun: Universal TUN/TAP device driver, 1.6738sshClient # [ 1.248525] Freeing initrd memory: 26060K739machine # [ 1.277235] thunder_xcv, ver 1.0740machine # [ 1.277266] thunder_bgx, ver 1.0741machine # [ 1.277292] nicpf, ver 1.0742machine # [ 1.277906] e1000: Intel(R) PRO/1000 Network Driver743machine # [ 1.277911] e1000: Copyright (c) 1999-2006 Intel Corporation.744machine # [ 1.277939] e1000e: Intel(R) PRO/1000 Network Driver745machine # [ 1.277945] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.746machine # [ 1.278070] igb: Intel(R) Gigabit Ethernet Network Driver747sshClient # [ 1.255227] tun: Universal TUN/TAP device driver, 1.6748machine # [ 1.278074] igb: Copyright (c) 2007-2014 Intel Corporation.749machine # [ 1.278096] igbvf: Intel(R) Gigabit Virtual Function Network Driver750machine # [ 1.278101] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.751machine # [ 1.278242] sky2: driver version 1.30752sshClient # [ 1.259587] thunder_xcv, ver 1.0753sshClient # [ 1.259620] thunder_bgx, ver 1.0754sshClient # [ 1.259639] nicpf, ver 1.0755sshClient # [ 1.260253] e1000: Intel(R) PRO/1000 Network Driver756sshClient # [ 1.260258] e1000: Copyright (c) 1999-2006 Intel Corporation.757sshClient # [ 1.260284] e1000e: Intel(R) PRO/1000 Network Driver758machine # [ 1.287542] usbcore: registered new interface driver usb-storage759sshClient # [ 1.260291] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.760machine # [ 1.287641] usbcore: registered new interface driver usbserial_generic761sshClient # [ 1.260315] igb: Intel(R) Gigabit Ethernet Network Driver762machine # [ 1.287653] usbserial: USB Serial support registered for generic763sshClient # [ 1.260319] igb: Copyright (c) 2007-2014 Intel Corporation.764machine # [ 1.288287] hv_vmbus: registering driver hyperv_keyboard765machine # [ 1.289640] ehci-pci 0000:00:07.0: EHCI Host Controller766sshClient # [ 1.260339] igbvf: Intel(R) Gigabit Virtual Function Network Driver767sshClient # [ 1.260343] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.768machine # [ 1.289685] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1769sshClient # [ 1.260486] sky2: driver version 1.30770machine # [ 1.289896] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000771sshClient # [ 1.262736] ehci-pci 0000:00:07.0: EHCI Host Controller772machine # [ 1.290306] rtc-pl031 9010000.pl031: registered as rtc0773sshClient # [ 1.262762] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1774machine # [ 1.290334] rtc-pl031 9010000.pl031: setting system clock to 2026-10-23T03:05:56 UTC (1792724756)775sshClient # [ 1.262978] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000776machine # [ 1.290700] i2c_dev: i2c /dev entries driver777sshClient # [ 1.273698] usbcore: registered new interface driver usb-storage778sshClient # [ 1.273911] usbcore: registered new interface driver usbserial_generic779sshClient # [ 1.273923] usbserial: USB Serial support registered for generic780machine # [ 1.299516] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00781sshClient # [ 1.276460] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00782machine # [ 1.299823] hub 1-0:1.0: USB hub found783sshClient # [ 1.276960] hub 1-0:1.0: USB hub found784machine # [ 1.299840] hub 1-0:1.0: 6 ports detected785sshClient # [ 1.276981] hub 1-0:1.0: 6 ports detected786machine # [ 1.304632] sdhci: Secure Digital Host Controller Interface driver787sshClient # [ 1.279861] hv_vmbus: registering driver hyperv_keyboard788machine # [ 1.304642] sdhci: Copyright(c) Pierre Ossman789sshClient # [ 1.280890] rtc-pl031 9010000.pl031: registered as rtc0790machine # [ 1.304933] Synopsys Designware Multimedia Card Interface Driver791machine # [ 1.305322] sdhci-pltfm: SDHCI platform and OF driver helper792sshClient # [ 1.280923] rtc-pl031 9010000.pl031: setting system clock to 2026-10-23T03:05:56 UTC (1792724756)793sshClient # [ 1.281274] i2c_dev: i2c /dev entries driver794machine # [ 1.309916] hid: raw HID events driver (C) Jiri Kosina795machine # [ 1.310256] usbcore: registered new interface driver usbhid796machine # [ 1.310260] usbhid: USB HID core driver797machine # [ 1.313373] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available798sshClient # [ 1.288118] sdhci: Secure Digital Host Controller Interface driver799sshClient # [ 1.288126] sdhci: Copyright(c) Pierre Ossman800sshClient # [ 1.288428] Synopsys Designware Multimedia Card Interface Driver801sshClient # [ 1.288861] sdhci-pltfm: SDHCI platform and OF driver helper802machine # [ 1.316211] drop_monitor: Initializing network drop monitor service803sshClient # [ 1.293652] hid: raw HID events driver (C) Jiri Kosina804machine # [ 1.316380] NET: Registered PF_INET6 protocol family805sshClient # [ 1.293897] usbcore: registered new interface driver usbhid806machine # [ 1.317030] Segment Routing with IPv6807sshClient # [ 1.293901] usbhid: USB HID core driver808machine # [ 1.317047] In-situ OAM (IOAM) with IPv6809machine # [ 1.317072] NET: Registered PF_PACKET protocol family810machine # [ 1.317128] 9pnet: Installing 9P2000 support811machine # [ 1.317166] Key type dns_resolver registered812sshClient # [ 1.297148] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available813sshClient # [ 1.300313] drop_monitor: Initializing network drop monitor service814sshClient # [ 1.300502] NET: Registered PF_INET6 protocol family815sshClient # [ 1.301199] Segment Routing with IPv6816sshClient # [ 1.301214] In-situ OAM (IOAM) with IPv6817sshClient # [ 1.301243] NET: Registered PF_PACKET protocol family818sshClient # [ 1.301307] 9pnet: Installing 9P2000 support819sshClient # [ 1.301354] Key type dns_resolver registered820machine # [ 1.327812] registered taskstats version 1821machine # [ 1.327979] Loading compiled-in X.509 certificates822machine # [ 1.337054] Demotion targets for Node 0: null823sshClient # [ 1.311846] registered taskstats version 1824machine # [ 1.337185] Key type .fscrypt registered825sshClient # [ 1.312006] Loading compiled-in X.509 certificates826machine # [ 1.337189] Key type fscrypt-provisioning registered827machine # [ 1.337289] ima: No TPM chip found, activating TPM-bypass!828machine # [ 1.337312] ima: Allocated hash algorithm: sha1829machine # [ 1.337334] ima: No architecture policies found830machine # [ 1.338119] input: gpio-keys as /devices/platform/gpio-keys/input/input0831sshClient # [ 1.321103] Demotion targets for Node 0: null832sshClient # [ 1.321231] Key type .fscrypt registered833sshClient # [ 1.321235] Key type fscrypt-provisioning registered834sshClient # [ 1.321352] ima: No TPM chip found, activating TPM-bypass!835sshClient # [ 1.321376] ima: Allocated hash algorithm: sha1836sshClient # [ 1.321398] ima: No architecture policies found837sshClient # [ 1.326111] input: gpio-keys as /devices/platform/gpio-keys/input/input0838machine # [ 1.361364] clk: Disabling unused clocks839machine # [ 1.361387] PM: genpd: Disabling unused power domains840machine # [ 1.367549] Freeing unused kernel memory: 7808K841machine # [ 1.367760] Run /init as init process842sshClient # [ 1.346249] clk: Disabling unused clocks843sshClient # [ 1.346271] PM: genpd: Disabling unused power domains844sshClient # [ 1.352502] Freeing unused kernel memory: 7808K845sshClient # [ 1.352701] Run /init as init process846machine # [ 1.385553] fuse: module verification failed: signature and/or required key missing - tainting kernel847machine # [ 1.389562] fuse: init (API version 7.45)848machine # [ 1.393277] virtiofs virtio6: discovered new tag: nix-store849machine # [ 1.394110] virtiofs virtio6: virtio_fs_setup_dax: No cache capability850sshClient # [ 1.371040] fuse: module verification failed: signature and/or required key missing - tainting kernel851sshClient # [ 1.375410] fuse: init (API version 7.45)852sshClient # [ 1.379210] virtiofs virtio6: discovered new tag: nix-store853sshClient # [ 1.380049] virtiofs virtio6: virtio_fs_setup_dax: No cache capability854machine # [ 1.408408] virtiofs virtio7: discovered new tag: shared855machine # [ 1.409167] virtiofs virtio7: virtio_fs_setup_dax: No cache capability856machine # [ 1.410434] virtiofs virtio8: discovered new tag: xchg857machine # [ 1.413324] virtiofs virtio8: virtio_fs_setup_dax: No cache capability858sshClient # [ 1.395658] virtiofs virtio7: discovered new tag: shared859sshClient # [ 1.396468] virtiofs virtio7: virtio_fs_setup_dax: No cache capability860machine # [ 1.425457] systemd[1]: Successfully made /usr/ read-only.861sshClient # [ 1.400465] virtiofs virtio8: discovered new tag: xchg862sshClient # [ 1.401319] virtiofs virtio8: virtio_fs_setup_dax: No cache capability863machine # [ 1.425954] systemd[1]: systemd 263-c676df8 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 +EXPERIMENTAL-ENVVAR)864machine # [ 1.425969] systemd[1]: Detected virtualization qemu.865machine # [ 1.426075] systemd[1]: Detected architecture arm64.866machine # [ 1.426081] systemd[1]: Running in initrd.867machine # [ 1.426410] systemd[1]: Initializing machine ID from random generator.868machine # [ 1.426508] systemd[1]: Hostname set to <machine>.869sshClient # [ 1.416829] systemd[1]: Successfully made /usr/ read-only.870sshClient # [ 1.417523] systemd[1]: systemd 263-c676df8 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 +EXPERIMENTAL-ENVVAR)871sshClient # [ 1.417539] systemd[1]: Detected virtualization qemu.872sshClient # [ 1.417563] systemd[1]: Detected architecture arm64.873sshClient # [ 1.417569] systemd[1]: Running in initrd.874sshClient # [ 1.417891] systemd[1]: Initializing machine ID from random generator.875sshClient # [ 1.425655] systemd[1]: Hostname set to <sshClient>.876machine # [ 1.528038] systemd[1]: bpf-restrict-fs: LSM BPF program attached877sshClient # [ 1.520037] systemd[1]: bpf-restrict-fs: LSM BPF program attached878sshClient # [ 1.526143] usb 1-1: new high-speed USB device number 2 using ehci-pci879machine # [ 1.554812] usb 1-1: new high-speed USB device number 2 using ehci-pci880machine # [ 1.588432] systemd[1]: Queued start job for default target Initrd Default Target.881machine # [ 1.594123] systemd[1]: Created slice Slice /system/modprobe.882machine # [ 1.594327] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.883machine # [ 1.594355] systemd[1]: Expecting device /dev/disk/by-label/nixos...884machine # [ 1.594377] systemd[1]: Reached target Path Units.885machine # [ 1.594391] systemd[1]: Reached target Slice Units.886machine # [ 1.594403] systemd[1]: Reached target Swaps.887machine # [ 1.594417] systemd[1]: Reached target Timer Units.888machine # [ 1.594554] systemd[1]: Listening on D-Bus System Message Bus Socket.889machine # [ 1.594722] systemd[1]: Listening on Journal Socket (/dev/log).890machine # [ 1.601474] systemd[1]: Listening on Journal Sockets.891machine # [ 1.601565] systemd[1]: Listening on udev Kernel Socket.892machine # [ 1.601768] systemd[1]: Listening on udev Varlink Socket.893machine # [ 1.601785] systemd[1]: Reached target Socket Units.894machine # [ 1.606187] systemd[1]: Starting Create List of Static Device Nodes...895sshClient # [ 1.581956] systemd[1]: Queued start job for default target Initrd Default Target.896machine # [ 1.606279] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs897machine # [ 1.611830] systemd[1]: Mounting Kernel Configuration File System...898sshClient # [ 1.593096] systemd[1]: Created slice Slice /system/modprobe.899sshClient # [ 1.593313] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.900sshClient # [ 1.593343] systemd[1]: Expecting device /dev/disk/by-label/nixos...901sshClient # [ 1.593367] systemd[1]: Reached target Path Units.902sshClient # [ 1.593381] systemd[1]: Reached target Slice Units.903sshClient # [ 1.593395] systemd[1]: Reached target Swaps.904sshClient # [ 1.593408] systemd[1]: Reached target Timer Units.905sshClient # [ 1.593549] systemd[1]: Listening on D-Bus System Message Bus Socket.906sshClient # [ 1.593709] systemd[1]: Listening on Journal Socket (/dev/log).907machine # [ 1.625654] systemd[1]: Starting Journal Service...908sshClient # [ 1.593830] systemd[1]: Listening on Journal Sockets.909sshClient # [ 1.593908] systemd[1]: Listening on udev Kernel Socket.910sshClient # [ 1.598645] systemd[1]: Listening on udev Varlink Socket.911sshClient # [ 1.598678] systemd[1]: Reached target Socket Units.912sshClient # [ 1.605264] systemd[1]: Starting Create List of Static Device Nodes...913sshClient # [ 1.605369] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs914machine # [ 1.633855] systemd[1]: Starting Load Kernel Modules...915machine # [ 1.633963] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os916sshClient # [ 1.611038] systemd[1]: Mounting Kernel Configuration File System...917machine # [ 1.643446] systemd[1]: Starting Coldplug All udev Devices...918sshClient # [ 1.630229] systemd[1]: Starting Journal Service...919sshClient # [ 1.646555] systemd[1]: Starting Load Kernel Modules...920sshClient # [ 1.646690] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os921machine # [ 1.679242] systemd-journald[71]: Collecting audit messages is disabled.922machine # [ 1.684203] systemd[1]: Finished Create List of Static Device Nodes.923machine # [ 1.685350] systemd[1]: Mounted Kernel Configuration File System.924sshClient # [ 1.666204] systemd[1]: Starting Coldplug All udev Devices...925machine # [ 1.695206] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...926sshClient # [ 1.671257] systemd[1]: Finished Create List of Static Device Nodes.927sshClient # [ 1.691104] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1928sshClient # [ 1.697731] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...929sshClient # [ 1.699675] systemd[1]: Mounted Kernel Configuration File System.930machine # [ 1.726873] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1931sshClient # [ 1.700108] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.932sshClient # [ 1.700349] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev933machine # [ 1.740783] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.934sshClient # [ 1.735891] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0935sshClient # [ 1.736138] [drm] features: -virgl +edid -resource_blob -host_visible936sshClient # [ 1.736141] [drm] features: -context_init937sshClient # [ 1.736940] [drm] number of scanouts: 1938sshClient # [ 1.736955] [drm] number of cap sets: 0939machine # [ 1.762807] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev940sshClient # [ 1.742196] systemd-journald[71]: Collecting audit messages is disabled.941machine # [ 1.775516] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.942machine # [ 1.777339] systemd[1]: Starting Create Static Device Nodes in /dev...943sshClient # [ 1.754351] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic944sshClient # [ 1.754371] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0945machine # [ 1.785844] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0946machine # [ 1.786177] [drm] features: -virgl +edid -resource_blob -host_visible947machine # [ 1.786179] [drm] features: -context_init948sshClient # [ 1.770761] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.949machine # [ 1.800859] [drm] number of scanouts: 1950machine # [ 1.800882] [drm] number of cap sets: 0951sshClient # [ 1.775450] systemd[1]: Starting Create Static Device Nodes in /dev...952machine # [ 1.801473] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic953machine # [ 1.801483] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0954sshClient # [ 1.780617] Console: switching to colour frame buffer device 160x50955sshClient # [ 1.787630] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0956machine # [ 1.811674] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0957sshClient # [ 1.810713] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device958sshClient # [ 1.823468] systemd[1]: Finished Load Kernel Modules.959machine # [ 1.850856] Console: switching to colour frame buffer device 160x50960machine # [ 1.851557] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device961machine # [ 1.853142] systemd[1]: Finished Create Static Device Nodes in /dev.962machine # [ 1.853423] systemd[1]: Reached target Preparation for Local File Systems.963machine # [ 1.853453] systemd[1]: Reached target Local File Systems.964machine # [ 1.860967] systemd[1]: Starting Rule-based Manager for Device Events and Files...965sshClient # [ 1.833963] systemd[1]: Starting Apply Kernel Variables...966machine # [ 1.875656] systemd[1]: Finished Load Kernel Modules.967sshClient # [ 1.851222] systemd[1]: Finished Create Static Device Nodes in /dev.968sshClient # [ 1.851474] systemd[1]: Reached target Preparation for Local File Systems.969machine # [ 1.862424] systemd-modules-load[72]: Inserted module 'dm_mod'970sshClient # [ 1.851507] systemd[1]: Reached target Local File Systems.971sshClient # [ 1.853585] systemd[1]: Starting Rule-based Manager for Device Events and Files...972machine # [ 1.870268] systemd-modules-load[72]: Module 'virtio_balloon' is built in973machine # [ 1.871637] systemd-modules-load[72]: Module 'virtio_console' is built in974machine # [ 1.880559] systemd-modules-load[72]: Inserted module 'virtio_gpu'975machine # [ 1.881678] systemd-modules-load[72]: Module 'virtio_rng' is built in976machine # [ 1.901837] systemd[1]: Starting Apply Kernel Variables...977machine # [ 1.902323] systemd[1]: Started Journal Service.978sshClient # [ 1.902461] systemd[1]: Finished Apply Kernel Variables.979sshClient # [ 1.906058] usb 1-2: new high-speed USB device number 3 using ehci-pci980machine # [ 1.930874] usb 1-2: new high-speed USB device number 3 using ehci-pci981machine # [ 1.919376] systemd[1]: Starting Create System Files and Directories...982sshClient # [ 1.924824] systemd[1]: Started Journal Service.983sshClient # [ 1.913087] systemd-modules-load[72]: Inserted module 'dm_mod'984sshClient # [ 1.919972] systemd-modules-load[72]: Module 'virtio_balloon' is built in985sshClient # [ 1.921291] systemd-modules-load[72]: Module 'virtio_console' is built in986sshClient # [ 1.922451] systemd-modules-load[72]: Inserted module 'virtio_gpu'987sshClient # [ 1.923557] systemd-modules-load[72]: Module 'virtio_rng' is built in988machine # [ 1.961188] systemd[1]: Finished Apply Kernel Variables.989sshClient # [ 1.940585] systemd[1]: Starting Create System Files and Directories...990machine # [ 1.965831] systemd-udevd[78]: Using default interface naming scheme 'v261'.991machine # [ 1.988788] systemd[1]: Finished Create System Files and Directories.992sshClient # [ 1.968517] systemd-udevd[80]: Using default interface naming scheme 'v261'.993machine # [ 2.013860] systemd[1]: Started Rule-based Manager for Device Events and Files.994sshClient # [ 1.990983] systemd[1]: Finished Create System Files and Directories.995sshClient # [ 2.006565] systemd[1]: Started Rule-based Manager for Device Events and Files.996sshClient # [ 2.062067] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2997sshClient # [ 2.062381] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0998machine # [ 2.091181] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2999machine # [ 2.091509] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input01000machine # [ 2.121359] systemd[1]: Starting Virtual Console Setup...1001sshClient # [ 2.120132] systemd[1]: Starting Virtual Console Setup...1002machine # [ 2.213074] systemd-vconsole-setup[98]: Configuration of first virtual console was skipped, ignoring remaining ones.1003machine # [ 2.217676] systemd[1]: Finished Virtual Console Setup.1004sshClient # [ 2.208358] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.1005sshClient # [ 2.213741] systemd[1]: Finished Virtual Console Setup.1006machine # [ 3.008448] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line.1007machine # [ 3.024873] (udev-worker)[97]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1008machine # [ 3.038935] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.1009sshClient # [ 3.043117] (udev-worker)[91]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1010sshClient # [ 3.055626] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.1011sshClient # [ 3.071106] systemd[1]: Finished Coldplug All udev Devices.1012sshClient # [ 3.076235] systemd[1]: Reached target System Initialization.1013sshClient # [ 3.080391] systemd[1]: Reached target Basic System.1014sshClient # [ 3.084589] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.1015machine # [ 3.114801] systemd[1]: Found device /dev/disk/by-label/nixos.1016machine # [ 3.121858] systemd[1]: Reached target Initrd Root Device.1017machine # [ 3.130617] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1018machine # [ 3.134293] systemd[1]: Finished Coldplug All udev Devices.1019machine # [ 3.150586] systemd[1]: Reached target System Initialization.1020machine # [ 3.152631] systemd[1]: Reached target Basic System.1021sshClient # [ 3.129784] systemd[1]: Found device /dev/disk/by-label/nixos.1022sshClient # [ 3.134226] systemd[1]: Reached target Initrd Root Device.1023sshClient # [ 3.139739] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1024machine # [ 3.224795] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks1025sshClient # [ 3.220247] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks1026sshClient # [ 3.238485] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1027machine # [ 3.292085] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1028sshClient # [ 3.278550] systemd[1]: Mounting /sysroot...1029machine # [ 3.336626] systemd[1]: Mounting /sysroot...1030sshClient # [ 3.497655] EXT4-fs (vda): mounted filesystem a3bd74eb-1caa-4f31-98e8-ebce3a7cff78 r/w with ordered data mode. Quota mode: none.1031sshClient # [ 3.513995] systemd[1]: Mounted /sysroot.1032sshClient # [ 3.524435] systemd[1]: Reached target Initrd Root File System.1033sshClient # [ 3.552156] systemd[1]: Starting Mountpoints Configured in the Real Root...1034sshClient # [ 3.603625] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.1035sshClient # [ 3.613977] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...1036sshClient # [ 3.620605] systemd[1]: Reloading...1037machine # [ 3.850640] EXT4-fs (vda): mounted filesystem a6ea0050-3b8b-40a7-b5e4-d8af0a783265 r/w with ordered data mode. Quota mode: none.1038machine # [ 3.837914] systemd[1]: Mounted /sysroot.1039machine # [ 3.839105] systemd[1]: Reached target Initrd Root File System.1040machine # [ 3.846652] systemd[1]: Mounting /sysroot/nix/.ro-store...1041machine # [ 3.859818] systemd[1]: Mounting /sysroot/nix/.rw-store...1042sshClient # [ 3.842624] systemd[1]: Reloading finished in 225 ms.1043machine # [ 3.874115] systemd[1]: Mounting /sysroot/run...1044sshClient # [ 3.880170] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...1045sshClient # [ 3.884202] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...1046machine # [ 3.909101] systemd[1]: Mounting /sysroot/tmp/shared...1047sshClient # [ 3.893827] systemd[1]: Mounting /sysroot/nix/.ro-store...1048machine # [ 3.918778] systemd[1]: Mounting /sysroot/tmp/xchg...1049sshClient # [ 3.913912] systemd[1]: Mounting /sysroot/nix/.rw-store...1050sshClient # [ 3.917377] systemd[1]: Mounting /sysroot/run...1051machine # [ 3.954215] systemd[1]: Starting Mountpoints Configured in the Real Root...1052sshClient # [ 3.931941] systemd[1]: Mounting /sysroot/tmp/shared...1053machine # [ 3.978217] systemd[1]: Mounted /sysroot/nix/.ro-store.1054machine # [ 4.003012] systemd[1]: Mounted /sysroot/nix/.rw-store.1055machine # [ 4.013212] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload.1056machine # [ 4.021191] systemd[1]: Mounted /sysroot/run.1057sshClient # [ 3.999482] systemd[1]: Mounting /sysroot/tmp/xchg...1058sshClient # [ 4.002606] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1059sshClient # [ 4.013188] systemd[1]: Finished Mountpoints Configured in the Real Root.1060machine # [ 4.038897] systemd[1]: Mounted /sysroot/tmp/shared.1061sshClient # [ 4.014303] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1062sshClient # [ 4.020873] systemd[1]: Mounted /sysroot/nix/.ro-store.1063machine # [ 4.049949] systemd[1]: Mounted /sysroot/tmp/xchg.1064machine # [ 4.054781] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)...1065machine # [ 4.061204] systemd[1]: Reloading...1066sshClient # [ 4.036959] systemd[1]: Mounted /sysroot/nix/.rw-store.1067sshClient # [ 4.041402] systemd[1]: Mounted /sysroot/run.1068sshClient # [ 4.042475] systemd[1]: Mounted /sysroot/tmp/shared.1069sshClient # [ 4.071528] systemd[1]: Starting rw-sysroot-nix-store.service...1070sshClient # [ 4.077634] systemd[1]: Mounted /sysroot/tmp/xchg.1071sshClient # [ 4.103989] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1072sshClient # [ 4.107264] systemd[1]: Finished rw-sysroot-nix-store.service.1073sshClient # [ 4.109358] systemd[1]: Mounting /sysroot/nix/store...1074sshClient # [ 4.165002] systemd[1]: Mounted /sysroot/nix/store.1075sshClient # [ 4.167123] systemd[1]: Reached target Initrd File Systems.1076sshClient # [ 4.169373] systemd[1]: Starting Find NixOS closure...1077sshClient # [ 4.179730] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1078sshClient # [ 4.217988] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1079sshClient # [ 4.239827] systemd[1]: Finished Find NixOS closure.1080sshClient # [ 4.241000] systemd[1]: Reached target Initrd Default Target.1081sshClient # [ 4.243376] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1082machine # [ 4.278648] systemd[1]: Reloading finished in 219 ms.1083sshClient # [ 4.286196] systemd[1]: Stopped target Initrd Default Target.1084sshClient # [ 4.290520] systemd[1]: Stopped target Basic System.1085sshClient # [ 4.292419] systemd[1]: Stopped target Initrd Root Device.1086machine # [ 4.319752] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace...1087sshClient # [ 4.296191] systemd[1]: Stopped target Path Units.1088sshClient # [ 4.297999] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1089sshClient # [ 4.302451] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1090machine # [ 4.327507] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace...1091sshClient # [ 4.308230] systemd[1]: Stopped target Slice Units.1092machine # [ 4.332726] systemd[1]: Starting rw-sysroot-nix-store.service...1093sshClient # [ 4.310972] systemd[1]: Stopped target Socket Units.1094sshClient # [ 4.312464] systemd[1]: Stopped target System Initialization.1095machine # [ 4.337108] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1096sshClient # [ 4.313972] systemd[1]: Stopped target Swaps.1097sshClient # [ 4.316405] systemd[1]: Stopped target Timer Units.1098machine # [ 4.340831] systemd[1]: Finished Mountpoints Configured in the Real Root.1099machine # [ 4.343318] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1100sshClient # [ 4.321490] systemd[1]: dbus.socket: Deactivated successfully.1101sshClient # [ 4.323581] systemd[1]: Closed D-Bus System Message Bus Socket.1102sshClient # [ 4.327795] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1103sshClient # [ 4.330957] systemd[1]: Stopped Find NixOS closure.1104sshClient # [ 4.335388] systemd[1]: Starting rw-sysroot-nix-store.service...1105sshClient # [ 4.341415] systemd[1]: systemd-sysctl.service: Deactivated successfully.1106sshClient # [ 4.342602] systemd[1]: Stopped Apply Kernel Variables.1107sshClient # [ 4.343412] systemd[1]: systemd-modules-load.service: Deactivated successfully.1108machine # [ 4.370500] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1109machine # [ 4.372779] systemd[1]: Finished rw-sysroot-nix-store.service.1110sshClient # [ 4.358147] systemd[1]: Stopped Load Kernel Modules.1111sshClient # [ 4.359068] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1112sshClient # [ 4.362282] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1113sshClient # [ 4.368196] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1114sshClient # [ 4.369385] systemd[1]: Stopped Create System Files and Directories.1115sshClient # [ 4.370312] systemd[1]: Stopped target Local File Systems.1116sshClient # [ 4.371145] systemd[1]: Stopped target Preparation for Local File Systems.1117sshClient # [ 4.374405] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1118sshClient # [ 4.376169] systemd[1]: Stopped Coldplug All udev Devices.1119sshClient # [ 4.380208] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1120sshClient # [ 4.381398] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1121sshClient # [ 4.383979] systemd[1]: Stopped Virtual Console Setup.1122sshClient # [ 4.384907] systemd[1]: initrd-cleanup.service: Deactivated successfully.1123sshClient # [ 4.385913] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1124sshClient # [ 4.386983] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1125sshClient # [ 4.391479] systemd[1]: Finished rw-sysroot-nix-store.service.1126sshClient # [ 4.394515] systemd[1]: systemd-udevd.service: Deactivated successfully.1127sshClient # [ 4.395863] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1128sshClient # [ 4.400605] systemd[1]: systemd-udevd.service: Consumed 1.009s CPU time over 2.555s wall clock time, 17.1M memory peak.1129sshClient # [ 4.402204] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully.1130sshClient # [ 4.404301] systemd[1]: Closed udev Varlink Socket.1131sshClient # [ 4.406602] systemd[1]: Starting Cleanup udev Database...1132sshClient # [ 4.407614] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1133sshClient # [ 4.408930] systemd[1]: Stopped Create Static Device Nodes in /dev.1134sshClient # [ 4.409839] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1135sshClient # [ 4.411105] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1136sshClient # [ 4.416281] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1137sshClient # [ 4.417368] systemd[1]: Stopped Create List of Static Device Nodes.1138sshClient # [ 4.440440] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1139sshClient # [ 4.443449] systemd[1]: Finished Cleanup udev Database.1140sshClient # [ 4.444817] systemd[1]: Reached target Switch Root.1141sshClient # [ 4.448547] systemd[1]: Starting NixOS Activation...1142sshClient # [ 4.546575] initrd-nixos-activation-start[167]: booting system configuration /nix/store/5fy3hcm4l7bcy4gn68s4mcazymyrkqa6-nixos-system-sshClient-test1143sshClient # [ 4.583202] initrd-nixos-activation-start[167]: running activation script...1144machine # [ 4.840639] systemd[1]: Mounting /sysroot/nix/store...1145sshClient # [ 4.815427] initrd-nixos-activation-start[190]: setting up /etc...1146machine # [ 4.900130] systemd[1]: Mounted /sysroot/nix/store.1147machine # [ 4.901020] systemd[1]: Reached target Initrd File Systems.1148machine # [ 4.905546] systemd[1]: Starting Find NixOS closure...1149machine # [ 4.918893] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1150machine # [ 4.969943] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1151machine # [ 4.972298] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1152sshClient # [ 4.951868] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1153sshClient # [ 4.955451] systemd[1]: Finished NixOS Activation.1154sshClient # [ 4.956837] systemd[1]: Starting Switch Root...1155machine # [ 4.983601] systemd[1]: Finished Find NixOS closure.1156machine # [ 4.987382] systemd[1]: Reached target Initrd Default Target.1157machine # [ 4.998226] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1158sshClient # [ 4.982807] systemd[1]: Switching root.1159machine # [ 5.030533] systemd[1]: Stopped target Initrd Default Target.1160machine # [ 5.036107] systemd[1]: Stopped target Basic System.1161machine # [ 5.037332] systemd[1]: Stopped target Initrd Root Device.1162machine # [ 5.039084] systemd[1]: Stopped target Path Units.1163machine # [ 5.043507] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1164machine # [ 5.045466] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1165machine # [ 5.053188] systemd[1]: Stopped target Slice Units.1166machine # [ 5.056169] systemd[1]: Stopped target Socket Units.1167machine # [ 5.057031] systemd[1]: Stopped target System Initialization.1168machine # [ 5.057879] systemd[1]: Stopped target Swaps.1169machine # [ 5.060154] systemd[1]: Stopped target Timer Units.1170machine # [ 5.064823] systemd[1]: dbus.socket: Deactivated successfully.1171machine # [ 5.066886] systemd[1]: Closed D-Bus System Message Bus Socket.1172machine # [ 5.069792] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1173machine # [ 5.074121] systemd[1]: Stopped Find NixOS closure.1174machine # [ 5.076115] systemd[1]: Starting rw-sysroot-nix-store.service...1175machine # [ 5.084192] systemd[1]: systemd-sysctl.service: Deactivated successfully.1176machine # [ 5.088434] systemd[1]: Stopped Apply Kernel Variables.1177machine # [ 5.092511] systemd[1]: systemd-modules-load.service: Deactivated successfully.1178machine # [ 5.102127] systemd[1]: Stopped Load Kernel Modules.1179machine # [ 5.104251] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1180machine # [ 5.108259] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1181machine # [ 5.109464] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1182machine # [ 5.112186] systemd[1]: Stopped Create System Files and Directories.1183machine # [ 5.113598] systemd[1]: Stopped target Local File Systems.1184machine # [ 5.115604] systemd[1]: Stopped target Preparation for Local File Systems.1185machine # [ 5.119255] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1186machine # [ 5.120805] systemd[1]: Stopped Coldplug All udev Devices.1187machine # [ 5.122986] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1188machine # [ 5.125841] systemd[1]: Stopped Virtual Console Setup.1189machine # [ 5.127954] systemd[1]: initrd-cleanup.service: Deactivated successfully.1190machine # [ 5.129734] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1191machine # [ 5.131678] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1192machine # [ 5.134401] systemd[1]: Finished rw-sysroot-nix-store.service.1193machine # [ 5.147181] systemd[1]: systemd-udevd.service: Deactivated successfully.1194machine # [ 5.151361] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1195machine # [ 5.152545] systemd[1]: systemd-udevd.service: Consumed 1.719s CPU time over 3.306s wall clock time, 24.4M memory peak.1196machine # [ 5.154978] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully.1197machine # [ 5.160436] systemd[1]: Closed udev Varlink Socket.1198machine # [ 5.161219] systemd[1]: Starting Cleanup udev Database...1199machine # [ 5.162003] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1200machine # [ 5.163083] systemd[1]: Stopped Create Static Device Nodes in /dev.1201machine # [ 5.165196] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1202machine # [ 5.166517] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1203machine # [ 5.167619] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1204machine # [ 5.168745] systemd[1]: Stopped Create List of Static Device Nodes.1205sshClient # [ 5.168954] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1206machine # [ 5.216183] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1207machine # [ 5.218109] systemd[1]: Finished Cleanup udev Database.1208machine # [ 5.219809] systemd[1]: Reached target Switch Root.1209machine # [ 5.223652] systemd[1]: Starting NixOS Activation...1210sshClient # [ 5.272912] NET: Registered PF_VSOCK protocol family1211machine # [ 5.326393] initrd-nixos-activation-start[179]: booting system configuration /nix/store/g3z59v7k5qdjgb547p7wxg0gzwvkcvn8-nixos-system-machine-test1212sshClient # [ 5.346544] systemd[1]: systemd 263-c676df8 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 +EXPERIMENTAL-ENVVAR)1213sshClient # [ 5.346621] systemd[1]: Detected virtualization qemu.1214sshClient # [ 5.346661] systemd[1]: Detected architecture arm64.1215sshClient # [ 5.346781] systemd[1]: Detected first boot.1216machine # [ 5.366474] initrd-nixos-activation-start[179]: running activation script...1217sshClient # [ 5.348753] systemd[1]: Initializing machine ID from random generator.1218sshClient # [ 5.695180] systemd[1]: bpf-restrict-fs: LSM BPF program attached1219sshClient # [ 5.876148] systemd[1]: Applying preset policy.1220machine # [ 5.886712] initrd-nixos-activation-start[202]: setting up /etc...1221machine # [ 6.012609] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1222machine # [ 6.015817] systemd[1]: Finished NixOS Activation.1223machine # [ 6.017249] systemd[1]: Starting Switch Root...1224machine # [ 6.042157] systemd[1]: Switching root.1225sshClient # [ 6.201938] systemd[1]: Populated /etc with preset unit settings.1226machine # [ 6.229184] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1227machine # [ 6.328421] NET: Registered PF_VSOCK protocol family1228machine # [ 6.412131] systemd[1]: systemd 263-c676df8 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 +EXPERIMENTAL-ENVVAR)1229machine # [ 6.412786] systemd[1]: Detected virtualization qemu.1230machine # [ 6.413490] systemd[1]: Detected architecture arm64.1231machine # [ 6.413595] systemd[1]: Detected first boot.1232machine # [ 6.425263] systemd[1]: Initializing machine ID from random generator.1233sshClient # [ 6.412654] systemd[1]: initrd-switch-root.service: Deactivated successfully.1234sshClient # [ 6.413639] systemd[1]: Stopped initrd-switch-root.service.1235sshClient # [ 6.419144] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1236sshClient # [ 6.421002] systemd[1]: Created slice Slice /system/getty.1237sshClient # [ 6.424955] systemd[1]: Created slice User and Session Slice.1238sshClient # [ 6.425578] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1239sshClient # [ 6.428515] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1240sshClient # [ 6.428978] systemd[1]: Expecting device /dev/hvc0...1241sshClient # [ 6.429579] systemd[1]: Expecting device /dev/ttyAMA0...1242sshClient # [ 6.432985] systemd[1]: Reached target Local Encrypted Volumes.1243sshClient # [ 6.433399] systemd[1]: Stopped target initrd-fs.target.1244sshClient # [ 6.435936] systemd[1]: Stopped target initrd-root-fs.target.1245sshClient # [ 6.436333] systemd[1]: Stopped target initrd-switch-root.target.1246sshClient # [ 6.437068] systemd[1]: Reached target Virtual Machines and Containers.1247sshClient # [ 6.437645] systemd[1]: Reached target Path Units.1248sshClient # [ 6.441652] systemd[1]: Reached target Remote File Systems.1249sshClient # [ 6.443474] systemd[1]: Reached target Slice Units.1250sshClient # [ 6.443889] systemd[1]: Reached target Swaps.1251sshClient # [ 6.445875] systemd[1]: Listening on Query the User Interactively for a Password.1252sshClient # [ 6.450080] systemd[1]: Listening on Process Core Dump Socket.1253sshClient # [ 6.451380] systemd[1]: Listening on Credential Encryption/Decryption.1254sshClient # [ 6.452656] systemd[1]: Listening on Factory Reset Management.1255sshClient # [ 6.453288] systemd[1]: Listening on Hostname Service Socket.1256sshClient # [ 6.460467] systemd[1]: Starting Journal Log Access Socket...1257sshClient # [ 6.463492] systemd[1]: Listening on Journal Audit Socket.1258sshClient # [ 6.467697] systemd[1]: Listening on Console Output Muting Service Socket.1259sshClient # [ 6.468378] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1260sshClient # [ 6.468976] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1261sshClient # [ 6.469521] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1262sshClient # [ 6.477813] systemd[1]: Listening on Disk Repartitioning Service Socket.1263sshClient # [ 6.479322] systemd[1]: Listening on udev Varlink Socket.1264sshClient # [ 6.480026] systemd[1]: Listening on User Database Manager Socket.1265sshClient # [ 6.484395] systemd[1]: Mounting Huge Pages File System...1266sshClient # [ 6.489459] systemd[1]: Mounting POSIX Message Queue File System...1267sshClient # [ 6.506705] systemd[1]: Mounting Kernel Debug File System...1268sshClient # [ 6.514646] systemd[1]: Mounting Kernel Trace File System...1269sshClient # [ 6.529222] systemd[1]: Starting Create List of Static Device Nodes...1270sshClient # [ 6.529770] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1271machine # [ 6.568276] systemd[1]: bpf-restrict-fs: LSM BPF program attached1272sshClient # [ 6.549870] systemd[1]: Mounting Kernel Configuration File System...1273sshClient # [ 6.558319] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1274sshClient # [ 6.558875] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1275sshClient # [ 6.565932] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1276sshClient # [ 6.604401] systemd[1]: Mounting FUSE Control File System...1277sshClient # [ 6.604986] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671278sshClient # [ 6.642661] systemd[1]: Starting Journal Service...1279sshClient # [ 6.662504] systemd[1]: Starting Load Kernel Modules...1280sshClient # [ 6.689294] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1281sshClient # [ 6.703299] systemd[1]: Starting Remount Root and Kernel File Systems...1282sshClient # [ 6.703728] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1283sshClient # [ 6.724215] systemd[1]: Starting Coldplug All udev Devices...1284sshClient # [ 6.739379] systemd[1]: Listening on Journal Log Access Socket.1285sshClient # [ 6.739907] systemd[1]: Mounted Huge Pages File System.1286sshClient # [ 6.740312] systemd[1]: Mounted POSIX Message Queue File System.1287sshClient # [ 6.740693] systemd[1]: Mounted Kernel Debug File System.1288sshClient # [ 6.741196] systemd[1]: Mounted Kernel Trace File System.1289sshClient # [ 6.761966] systemd-journald[262]: Collecting audit messages is enabled.1290sshClient # [ 6.766199] systemd[1]: Queued start job for default target Multi-User System.1291sshClient # [ 6.778603] systemd[1]: Finished Create List of Static Device Nodes.1292sshClient # [ 6.779298] systemd[1]: Mounted Kernel Configuration File System.1293sshClient # [ 6.779700] systemd[1]: Mounted FUSE Control File System.1294machine # [ 6.812100] systemd[1]: Applying preset policy.1295sshClient # [ 6.778459] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0.1296sshClient # [ 6.802966] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1297sshClient # [ 6.803645] systemd[1]: Started Journal Service.1298sshClient # [ 6.796602] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.1299sshClient # [ 6.797966] systemd[1]: systemd-journald.service: Deactivated successfully.1300sshClient # [ 6.799428] systemd-modules-load[263]: Module 'atkbd' is built in1301sshClient # [ 6.816553] systemd-modules-load[263]: Module 'loop' is built in1302sshClient # [ 6.817660] systemd[1]: Finished Load Kernel Modules.1303sshClient # [ 6.847185] systemd[1]: Starting Firewall...1304sshClient # [ 6.853436] systemd[1]: Starting Apply Kernel Variables...1305sshClient # [ 6.892038] EXT4-fs (vda): re-mounted a3bd74eb-1caa-4f31-98e8-ebce3a7cff78.1306sshClient # [ 6.971107] systemd[1]: Finished Remount Root and Kernel File Systems.1307sshClient # [ 6.990216] systemd-oomd[264]: No swap; memory pressure usage will be degraded1308sshClient # [ 7.092629] systemd[1]: Listening on Disk Image Download Service Socket.1309sshClient # [ 7.093910] systemd[1]: Starting Flush Journal to Persistent Storage...1310sshClient # [ 7.094992] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1311sshClient # [ 7.117115] systemd[1]: Starting Load/Save OS Random Seed...1312sshClient # [ 7.118066] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1313sshClient # [ 7.165220] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1314machine # [ 7.242701] systemd[1]: Populated /etc with preset unit settings.1315sshClient # [ 7.214278] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1316sshClient # [ 7.228680] systemd[1]: Starting Create Static Device Nodes in /dev...1317sshClient # [ 7.255112] systemd-journald[262]: Received client request to flush runtime journal.1318sshClient # [ 7.325060] systemd[1]: Finished Load/Save OS Random Seed.1319sshClient # [ 7.326119] systemd[1]: Finished Apply Kernel Variables.1320sshClient # [ 7.360104] systemd[1]: Finished Flush Journal to Persistent Storage.1321sshClient # [ 7.421076] systemd[1]: Finished Create Static Device Nodes in /dev.1322sshClient # [ 7.428154] systemd[1]: Reached target Preparation for Local File Systems.1323sshClient # [ 7.436875] systemd[1]: Mounting /run/wrappers...1324sshClient # [ 7.446563] systemd[1]: Starting Rule-based Manager for Device Events and Files...1325machine # [ 7.490925] systemd[1]: initrd-switch-root.service: Deactivated successfully.1326machine # [ 7.493267] systemd[1]: Stopped initrd-switch-root.service.1327machine # [ 7.499714] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1328machine # [ 7.502437] systemd[1]: Created slice Slice /system/getty.1329machine # [ 7.507301] systemd[1]: Created slice User and Session Slice.1330machine # [ 7.508066] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1331machine # [ 7.508878] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1332machine # [ 7.509462] systemd[1]: Expecting device /dev/hvc0...1333machine # [ 7.510163] systemd[1]: Expecting device /dev/ttyAMA0...1334machine # [ 7.515458] systemd[1]: Reached target Local Encrypted Volumes.1335machine # [ 7.516348] systemd[1]: Stopped target initrd-fs.target.1336machine # [ 7.517098] systemd[1]: Stopped target initrd-root-fs.target.1337machine # [ 7.518619] systemd[1]: Stopped target initrd-switch-root.target.1338machine # [ 7.522635] systemd[1]: Reached target Virtual Machines and Containers.1339machine # [ 7.524667] systemd[1]: Reached target Path Units.1340machine # [ 7.525110] systemd[1]: Reached target Remote File Systems.1341machine # [ 7.525749] systemd[1]: Reached target Slice Units.1342machine # [ 7.526387] systemd[1]: Reached target Swaps.1343machine # [ 7.531409] systemd[1]: Listening on Query the User Interactively for a Password.1344machine # [ 7.533484] systemd[1]: Listening on Process Core Dump Socket.1345machine # [ 7.535090] systemd[1]: Listening on Credential Encryption/Decryption.1346machine # [ 7.540441] systemd[1]: Listening on Factory Reset Management.1347machine # [ 7.541128] systemd[1]: Listening on Hostname Service Socket.1348machine # [ 7.547845] systemd[1]: Starting Journal Log Access Socket...1349machine # [ 7.550175] systemd[1]: Listening on Journal Audit Socket.1350machine # [ 7.555721] systemd[1]: Listening on Console Output Muting Service Socket.1351machine # [ 7.556732] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1352machine # [ 7.557228] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1353machine # [ 7.557699] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1354machine # [ 7.571376] systemd[1]: Listening on Disk Repartitioning Service Socket.1355machine # [ 7.572255] systemd[1]: Listening on udev Varlink Socket.1356machine # [ 7.573120] systemd[1]: Listening on User Database Manager Socket.1357machine # [ 7.581630] systemd[1]: Mounting Huge Pages File System...1358machine # [ 7.587539] systemd[1]: Mounting POSIX Message Queue File System...1359sshClient # [ 7.562063] systemd[1]: Mounted /run/wrappers.1360sshClient # [ 7.563181] systemd[1]: Reached target Local File Systems.1361machine # [ 7.603822] systemd[1]: Mounting Kernel Debug File System...1362machine # [ 7.621291] systemd[1]: Mounting Kernel Trace File System...1363sshClient # [ 7.580001] systemd[1]: Listening on Boot Loader Control Service Socket.1364sshClient # [ 7.584346] systemd[1]: Starting Create SUID/SGID Wrappers...1365sshClient # [ 7.585370] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1366sshClient # [ 7.599044] systemd[1]: Starting Create System Files and Directories...1367machine # [ 7.639820] systemd[1]: Starting Create List of Static Device Nodes...1368machine # [ 7.641367] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1369machine # [ 7.664170] systemd[1]: Mounting Kernel Configuration File System...1370machine # [ 7.671865] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1371machine # [ 7.672460] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1372machine # [ 7.681728] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1373machine # [ 7.735132] systemd[1]: Mounting FUSE Control File System...1374machine # [ 7.737730] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671375machine # [ 7.789382] systemd[1]: Starting Journal Service...1376sshClient # [ 7.764868] systemd-udevd[300]: Using default interface naming scheme 'v261'.1377machine # [ 7.819446] systemd[1]: Starting Load Kernel Modules...1378machine # [ 7.934057] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1379machine # [ 7.948387] systemd[1]: Starting Remount Root and Kernel File Systems...1380machine # [ 7.948947] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1381machine # [ 7.974584] systemd[1]: Starting Coldplug All udev Devices...1382sshClient # [ 7.942248] systemd[1]: Starting User Database Manager...1383machine # [ 7.986070] systemd[1]: Listening on Journal Log Access Socket.1384machine # [ 7.986661] systemd[1]: Mounted Huge Pages File System.1385sshClient # [ 7.949114] systemd[1]: Finished Create System Files and Directories.1386machine # [ 8.001423] systemd[1]: Mounted POSIX Message Queue File System.1387machine # [ 8.002188] systemd[1]: Mounted Kernel Debug File System.1388machine # [ 8.002687] systemd[1]: Mounted Kernel Trace File System.1389sshClient # [ 7.963422] systemd[1]: Starting Rebuild Journal Catalog...1390sshClient # [ 7.968619] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1391machine # [ 8.019206] systemd-journald[274]: Collecting audit messages is enabled.1392machine # [ 8.033234] systemd[1]: Finished Create List of Static Device Nodes.1393machine # [ 8.034095] systemd[1]: Mounted Kernel Configuration File System.1394machine # [ 8.034644] systemd[1]: Mounted FUSE Control File System.1395machine # [ 8.022101] systemd[1]: Queued start job for default target Multi-User System.1396machine # [ 8.043372] systemd[1]: Started Journal Service.1397machine # [ 8.029236] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0.1398machine # [ 8.031210] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.1399machine # [ 8.044700] systemd[1]: systemd-journald.service: Deactivated successfully.1400machine # [ 8.046040] systemd-modules-load[275]: Module 'atkbd' is built in1401machine # [ 8.047092] systemd-modules-load[275]: Module 'loop' is built in1402machine # [ 8.053794] systemd[1]: Finished Load Kernel Modules.1403machine # [ 8.081032] systemd[1]: Starting Firewall...1404machine # [ 8.091548] systemd[1]: Starting Apply Kernel Variables...1405machine # [ 8.101697] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1406machine # [ 8.110330] systemd-oomd[277]: No swap; memory pressure usage will be degraded1407machine # [ 8.123736] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1408machine # [ 8.151651] EXT4-fs (vda): re-mounted a6ea0050-3b8b-40a7-b5e4-d8af0a783265.1409sshClient # [ 8.134141] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1410machine # [ 8.172141] systemd[1]: Finished Remount Root and Kernel File Systems.1411machine # [ 8.173244] systemd[1]: Listening on Disk Image Download Service Socket.1412machine # [ 8.198766] systemd[1]: Starting Flush Journal to Persistent Storage...1413machine # [ 8.199915] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1414sshClient # [ 8.177946] systemd[1]: Finished Rebuild Journal Catalog.1415sshClient # [ 8.187562] systemd[1]: Started User Database Manager.1416machine # [ 8.212823] systemd[1]: Starting Load/Save OS Random Seed...1417machine # [ 8.213727] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1418sshClient # [ 8.191280] systemd[1]: Starting Update is Completed...1419sshClient # [ 8.295368] systemd[1]: Finished Update is Completed.1420machine # [ 8.340338] systemd-journald[274]: Received client request to flush runtime journal.1421machine # [ 8.384978] systemd[1]: Finished Apply Kernel Variables.1422machine # [ 8.385902] systemd[1]: Finished Load/Save OS Random Seed.1423sshClient # [ 8.375375] systemd[1]: Started Rule-based Manager for Device Events and Files.1424machine # [ 8.408141] systemd[1]: Finished Flush Journal to Persistent Storage.1425machine # [ 8.410083] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1426machine # [ 8.429038] systemd[1]: Starting Create Static Device Nodes in /dev...1427machine # [ 8.565348] systemd[1]: Finished Create Static Device Nodes in /dev.1428machine # [ 8.585823] systemd[1]: Reached target Preparation for Local File Systems.1429machine # [ 8.596494] systemd[1]: Mounting /run/wrappers...1430machine # [ 8.622794] systemd[1]: Starting Rule-based Manager for Device Events and Files...1431machine # [ 8.755876] systemd[1]: Mounted /run/wrappers.1432machine # [ 8.756868] systemd[1]: Reached target Local File Systems.1433machine # [ 8.761416] systemd[1]: Listening on Boot Loader Control Service Socket.1434machine # [ 8.772271] systemd[1]: Starting Create SUID/SGID Wrappers...1435machine # [ 8.773291] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1436machine # [ 8.781478] systemd[1]: Starting Create System Files and Directories...1437machine # [ 8.841719] systemd-udevd[315]: Using default interface naming scheme 'v261'.1438machine # [ 8.933314] systemd[1]: Starting User Database Manager...1439sshClient # [ 8.950896] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1440sshClient # [ 8.957019] systemd[1]: Finished Create SUID/SGID Wrappers.1441machine # [ 8.997716] systemd[1]: Finished Create System Files and Directories.1442machine # [ 9.022598] systemd[1]: Starting Rebuild Journal Catalog...1443machine # [ 9.033584] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1444machine # [ 9.226217] systemd[1]: Started User Database Manager.1445machine # [ 9.284105] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1446machine # [ 9.315599] systemd[1]: Finished Rebuild Journal Catalog.1447machine # [ 9.331554] systemd[1]: Starting Update is Completed...1448machine # [ 9.446759] systemd[1]: Finished Update is Completed.1449sshClient # [ 9.423721] systemd[1]: Finished Coldplug All udev Devices.1450sshClient # [ 9.425870] systemd[1]: Reached target System Initialization.1451sshClient # [ 9.426940] systemd[1]: Started Discard unused filesystem blocks once a week.1452sshClient # [ 9.431311] systemd[1]: Started Daily Cleanup of Temporary Directories.1453sshClient # [ 9.437188] systemd[1]: Reached target Timer Units.1454sshClient # [ 9.438005] systemd[1]: Listening on D-Bus System Message Bus Socket.1455sshClient # [ 9.438985] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1456sshClient # [ 9.446926] systemd[1]: Reached target Socket Units.1457sshClient # [ 9.447776] systemd[1]: Reached target Basic System.1458machine # [ 9.475436] systemd[1]: Started Rule-based Manager for Device Events and Files.1459sshClient # [ 9.452617] systemd[1]: Starting Import lastlog data into lastlog2 database...1460sshClient # [ 9.453698] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1461sshClient # [ 9.468175] systemd[1]: Starting Post-Boot Actions...1462sshClient # [ 9.500653] systemd[1]: Started Reset console on configuration changes.1463sshClient # [ 9.538031] systemd[1]: Starting resolvconf update...1464sshClient # [ 9.583352] systemd[1]: Starting Home Area Manager...1465sshClient # [ 9.633748] systemd[1]: Finished Post-Boot Actions.1466sshClient # [ 9.671627] nsncd[440]: Oct 23 03:06:04.907 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1467sshClient # [ 9.687328] systemd[1]: Started Name Service Cache Daemon (nsncd).1468machine # [ 9.724436] systemd[1]: Starting Virtual Console Setup...1469sshClient # [ 9.724110] systemd[1]: Reached target Host and Network Name Lookups.1470sshClient # [ 9.725236] systemd[1]: Reached target User and Group Name Lookups.1471sshClient # [ 9.736515] systemd[1]: Starting D-Bus System Message Bus...1472sshClient # [ 9.774226] systemd[1]: Starting User Login Management...1473sshClient # [ 9.802661] systemd[1]: Finished Import lastlog data into lastlog2 database.1474sshClient # [ 9.848236] systemd-homed[445]: Watching /home.1475sshClient # [ 10.036595] dbus-broker-launch[463]: Looking up NSS user entry for 'systemd-timesync'...1476machine # [ 10.072169] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1477sshClient # [ 10.047954] systemd-logind[470]: New seat seat0.1478sshClient # [ 10.055672] dbus-broker-launch[463]: NSS returned no entry for 'systemd-timesync'1479machine # [ 10.084590] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1480sshClient # [ 10.061225] dbus-broker-launch[463]: Invalid user-name in /nix/store/zay1cm11xv2kiq6m7ram48k5y8k4fn4r-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1481sshClient # [ 10.063904] systemd[1]: Started User Login Management.1482machine # [ 10.088383] systemd[1]: Finished Create SUID/SGID Wrappers.1483sshClient # [ 10.084059] systemd[1]: Starting linger-users.service...1484sshClient # [ 10.104171] systemd[1]: Started D-Bus System Message Bus.1485machine # [ 10.136234] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1486sshClient # [ 10.113752] systemd[1]: Stopped target Host and Network Name Lookups.1487sshClient # [ 10.124215] systemd[1]: Stopping Host and Network Name Lookups...1488sshClient # [ 10.125343] systemd[1]: Stopped target User and Group Name Lookups.1489sshClient # [ 10.126292] systemd[1]: Stopping User and Group Name Lookups...1490sshClient # [ 10.127197] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1491sshClient # [ 10.145040] systemd[1]: nscd.service: Deactivated successfully.1492sshClient # [ 10.154622] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1493sshClient # [ 10.181194] dbus-broker-launch[463]: Ready1494sshClient # [ 10.186587] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1495sshClient # [ 10.195474] systemd[1]: linger-users.service: Deactivated successfully.1496sshClient # [ 10.198703] systemd[1]: Finished linger-users.service.1497sshClient # [ 10.216451] systemd[1]: Started Home Area Manager.1498sshClient # [ 10.235197] systemd[1]: Finished Home Area Activation.1499sshClient # [ 10.255040] systemd[1]: Starting First Boot Home Area Wizard...1500sshClient # [ 10.318407] systemd[1]: Finished resolvconf update.1501sshClient # [ 10.341302] systemd[1]: Starting DHCP Client...1502sshClient # [ 10.348604] systemd[1]: Started Name Service Cache Daemon (nsncd).1503sshClient # [ 10.360725] nsncd[536]: Oct 23 03:06:05.577 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1504sshClient # [ 10.368135] systemd[1]: Reached target Host and Network Name Lookups.1505sshClient # [ 10.373527] systemd[1]: Reached target User and Group Name Lookups.1506sshClient # [ 10.407303] systemd[1]: Finished First Boot Home Area Wizard.1507sshClient # [ 10.414758] systemd[1]: Reached target First Boot Complete.1508machine # [ 10.441531] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1509sshClient # [ 10.433056] systemd[1]: Starting Save Transient machine-id to Disk...1510sshClient # [ 10.473269] systemd[1]: Finished Firewall.1511sshClient # [ 10.481330] systemd[1]: Reached target Preparation for Network.1512sshClient # [ 10.487535] systemd[1]: Starting Extra networking commands....1513machine # [ 10.516896] systemd[1]: Finished Coldplug All udev Devices.1514machine # [ 10.567498] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1515sshClient # [ 10.598397] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1516sshClient # [ 10.610471] systemd[1]: Finished Save Transient machine-id to Disk.1517machine # [ 10.638503] systemd-vconsole-setup[420]: Configuration of first virtual console was skipped, ignoring remaining ones.1518machine # [ 10.647348] systemd[1]: Finished Virtual Console Setup.1519machine # [ 10.650265] systemd[1]: Reached target System Initialization.1520machine # [ 10.651155] systemd[1]: Started Discard unused filesystem blocks once a week.1521machine # [ 10.658679] systemd[1]: Started Daily Cleanup of Temporary Directories.1522machine # [ 10.659878] systemd[1]: Reached target Timer Units.1523machine # [ 10.664965] systemd[1]: Listening on D-Bus System Message Bus Socket.1524machine # [ 10.665962] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).1525machine # [ 10.667295] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1526machine # [ 10.677844] systemd[1]: Reached target Socket Units.1527machine # [ 10.678647] systemd[1]: Reached target Basic System.1528machine # [ 10.679419] systemd[1]: Started backdoor.service.1529machine # [ 10.689222] systemd[1]: Starting Import lastlog data into lastlog2 database...1530machine # [ 10.693219] (udev-worker)[393]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1531machine # [ 10.704235] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1532sshClient # [ 10.680568] dhcpcd[581]: dhcpcd-10.3.2 starting1533machine # [ 10.705654] (udev-worker)[397]: Network interface NamePolicy= disabled on kernel command line.1534machine # [ 10.707001] (udev-worker)[393]: Network interface NamePolicy= disabled on kernel command line.1535sshClient # [ 10.691133] dhcpcd[591]: dev: loaded udev1536machine # [ 10.719489] systemd[1]: Starting Post-Boot Actions...1537sshClient # [ 10.696703] dhcpcd[591]: no valid interfaces found1538sshClient # [ 10.699638] dhcpcd[591]: no valid interfaces found1539machine # [ 10.760945] systemd[1]: Started Reset console on configuration changes.1540machine # [ 10.799832] systemd[1]: Starting resolvconf update...1541sshClient # [ 10.809299] systemd[1]: Finished Extra networking commands..1542sshClient # [ 10.811671] systemd[1]: Reached target Network.1543sshClient # [ 10.816504] systemd[1]: Starting Permit User Sessions...1544machine # [ 10.847995] systemd[1]: Starting SSH Host Keys Generation...1545machine # connecting to host...1546machine # [ 10.898279] systemd[1]: Starting Home Area Manager...1547sshClient # [ 10.880222] systemd[1]: Finished Permit User Sessions.1548sshClient # [ 10.887622] systemd[1]: Started Getty on tty1.1549sshClient # [ 10.890620] systemd[1]: Reached target Login Prompts.1550machine: Guest shell says: b'Spawning backdoor root shell...\n'1551machine # [ 10.928340] systemd[1]: Finished Firewall.1552machine # [ 10.932187] systemd[1]: Started Name Service Cache Daemon (nsncd).1553machine: connected to guest root shell1554machine: (connecting took 11.23 seconds)1555machine: (finished: waiting for the VM to finish booting, in 11.23 seconds)1556machine # [ 10.978265] systemd[1]: Finished Post-Boot Actions.1557sshClient # [ 10.973059] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31558machine # [ 10.997558] nsncd[492]: Oct 23 03:06:06.158 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1559machine # [ 11.036489] systemd[1]: Reached target Host and Network Name Lookups.1560machine # [ 11.067854] systemd[1]: Reached target User and Group Name Lookups.1561machine # [ 11.100350] systemd[1]: Starting D-Bus System Message Bus...1562machine # [ 11.133828] systemd-homed[508]: Watching /home.1563machine # [ 11.134661] systemd[1]: Starting User Login Management...1564machine # [ 11.270982] systemd[1]: Finished Import lastlog data into lastlog2 database.1565sshClient # [ 11.288686] systemd[1]: Starting Virtual Console Setup...1566sshClient # [ 11.303211] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1567sshClient # [ 11.307888] systemd[1]: Stopped Virtual Console Setup.1568sshClient # [ 11.315340] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1569sshClient # [ 11.323927] systemd[1]: Starting Virtual Console Setup...1570sshClient # [ 11.361804] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1571machine # [ 11.390554] systemd[1]: Stopped target Host and Network Name Lookups.1572machine # [ 11.391684] systemd[1]: Stopping Host and Network Name Lookups...1573machine # [ 11.402162] systemd[1]: Stopped target User and Group Name Lookups.1574machine # [ 11.403341] systemd[1]: Stopping User and Group Name Lookups...1575machine # [ 11.414791] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1576machine # [ 11.415817] systemd[1]: nscd.service: Deactivated successfully.1577machine # [ 11.421493] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1578machine # [ 11.463813] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1579machine # [ 11.479929] systemd-logind[542]: New seat seat0.1580machine # [ 11.488785] dbus-broker-launch[535]: Looking up NSS user entry for 'systemd-timesync'...1581machine # [ 11.489962] dbus-broker-launch[535]: NSS returned no entry for 'systemd-timesync'1582machine # [ 11.490997] dbus-broker-launch[535]: Invalid user-name in /nix/store/fq3kwf03knlx507q24vjcqcfyhn4x8xr-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1583sshClient # [ 11.475556] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1584machine # [ 11.517020] systemd[1]: Started User Login Management.1585machine # [ 11.517891] systemd[1]: Starting linger-users.service...1586sshClient # [ 11.515079] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1587sshClient # [ 11.521978] systemd[1]: Started backdoor.service.1588machine # [ 11.546598] systemd[1]: Started D-Bus System Message Bus.1589machine # [ 11.570501] systemd-logind[542]: Watching system buttons on /dev/input/event0 (gpio-keys)1590machine # [ 11.609683] systemd[1]: Started Name Service Cache Daemon (nsncd).1591machine # [ 11.611115] nsncd[590]: Oct 23 03:06:06.837 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1592sshClient # connecting to host...1593machine # [ 11.634601] systemd[1]: Reached target Host and Network Name Lookups.1594machine # [ 11.635716] systemd[1]: Reached target User and Group Name Lookups.1595machine # [ 11.646962] dbus-broker-launch[535]: Ready1596machine # [ 11.647720] systemd[1]: Condition check resulted in Virtio network device being skipped.1597machine # [ 11.664207] systemd[1]: Condition check resulted in Virtio network device being skipped.1598machine # [ 11.665525] systemd[1]: Started Home Area Manager.1599machine # [ 11.666224] systemd[1]: Finished Home Area Activation.1600machine # [ 11.666953] systemd[1]: Starting First Boot Home Area Wizard...1601machine # [ 11.680358] systemd[1]: linger-users.service: Deactivated successfully.1602machine # [ 11.681470] systemd[1]: Finished linger-users.service.1603sshClient # [ 11.672294] 8021q: 802.1Q VLAN Support v1.81604sshClient # [ 11.666402] (udev-worker)[636]: Network interface NamePolicy= disabled on kernel command line.1605sshClient # [ 11.680569] (udev-worker)[641]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1606machine # [ 11.708172] systemd[1]: Finished resolvconf update.1607machine # [ 11.709087] systemd[1]: Reached target Preparation for Network.1608sshClient # [ 11.695317] (udev-worker)[641]: Network interface NamePolicy= disabled on kernel command line.1609machine # [ 11.727536] systemd[1]: Starting DHCP Client...1610machine # [ 11.733953] systemd[1]: Starting Address configuration of eth1...1611machine # [ 11.745825] systemd[1]: Starting Extra networking commands....1612sshClient # [ 11.803690] cfg80211: Loading compiled-in X.509 certificates for regulatory database1613sshClient # [ 11.805889] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1614sshClient # [ 11.809169] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1615sshClient # [ 11.812548] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21616sshClient # [ 11.813008] cfg80211: failed to load regulatory.db1617machine # [ 11.871979] systemd[1]: Created slice Slice /system/systemd-mute-console.1618machine # [ 11.895648] systemd[1]: Started Console Output Muting Service (PID 614/UID 0).1619sshClient # [ 11.890099] systemd-logind[470]: Watching system buttons on /dev/input/event0 (gpio-keys)1620machine # [ 11.940584] mousedev: PS/2 mouse device common for all mice1621machine # [ 11.959563] sshd-keygen-start[546]: Generating public/private rsa key pair.1622machine # [ 11.969434] sshd-keygen-start[546]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key1623machine # [ 11.970738] sshd-keygen-start[546]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub1624sshClient # [ 11.963661] systemd[1]: Condition check resulted in Virtio network device being skipped.1625machine # [ 11.990752] sshd-keygen-start[546]: The key fingerprint is:1626machine # [ 11.991670] sshd-keygen-start[546]: SHA256:ma+y82d2mxKJs6C/jEGFgDNlmdxqKgrmqJYjvBQi7kw root@machine1627sshClient # [ 11.971525] systemd[1]: Condition check resulted in Virtio network device being skipped.1628machine # [ 12.000434] sshd-keygen-start[546]: The key's randomart image is:1629machine # [ 12.001396] sshd-keygen-start[546]: +---[RSA 4096]----+1630machine # [ 12.002134] sshd-keygen-start[546]: | +++ |1631machine # [ 12.002844] sshd-keygen-start[546]: |+.+... |1632sshClient # [ 11.979565] systemd[1]: Starting Address configuration of eth1...1633machine # [ 12.003676] sshd-keygen-start[546]: | o .. . |1634machine # [ 12.026245] sshd-keygen-start[546]: | o . o |1635machine # [ 12.027000] sshd-keygen-start[546]: |o+ . S. . |1636machine # [ 12.027876] sshd-keygen-start[546]: |B... . o.o |1637sshClient # [ 12.011201] dhcpcd[591]: eth0: waiting for carrier1638sshClient # [ 12.028134] 8021q: adding VLAN 0 to HW filter on device eth01639sshClient # [ 12.016936] dhcpcd[591]: eth0: carrier acquired1640machine # [ 12.049299] sshd-keygen-start[546]: |OE. .. . o.. |1641machine # [ 12.050123] sshd-keygen-start[546]: |O* .+o ..= .. |1642machine # [ 12.050869] sshd-keygen-start[546]: |=+o ..=*o+ oo. |1643machine # [ 12.051777] sshd-keygen-start[546]: +----[SHA256]-----+1644sshClient # [ 12.036518] dhcpcd[591]: DUID 00:01:00:01:32:6d:8b:9f:52:54:00:12:34:561645sshClient # [ 12.037623] dhcpcd[591]: eth0: IAID 00:12:34:561646sshClient # [ 12.038420] dhcpcd[591]: eth0: adding address fe80::5054:ff:fe12:34561647machine # [ 12.073247] network-addresses-eth1-start[619]: adding address 192.168.1.1/24... done1648sshClient # [ 12.074326] systemd-vconsole-setup[652]: Configuration of first virtual console was skipped, ignoring remaining ones.1649sshClient # [ 12.100156] 8021q: adding VLAN 0 to HW filter on device eth11650machine # [ 12.109745] network-addresses-eth1-start[619]: adding address 2001:db8:1::1/64... done1651sshClient # [ 12.087533] systemd[1]: Finished Virtual Console Setup.1652machine # [ 12.119607] sshd-keygen-start[645]: Generating public/private ed25519 key pair.1653machine # [ 12.128587] sshd-keygen-start[645]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key1654machine # [ 12.129943] sshd-keygen-start[645]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub1655machine # [ 12.131287] sshd-keygen-start[645]: The key fingerprint is:1656sshClient # [ 12.106651] network-addresses-eth1-start[672]: adding address 192.168.1.2/24... done1657sshClient # [ 12.121276] network-addresses-eth1-start[672]: adding address 2001:db8:1::2/64... done1658machine # [ 12.148237] sshd-keygen-start[645]: SHA256:ipvwH74p5jjbrWnd6T0nPVhpDsyZRfglSxA9nqR0cso root@machine1659machine # [ 12.149566] sshd-keygen-start[645]: The key's randomart image is:1660machine # [ 12.150423] sshd-keygen-start[645]: +--[ED25519 256]--+1661machine # [ 12.151176] sshd-keygen-start[645]: | o= |1662machine # [ 12.151938] sshd-keygen-start[645]: | + X . |1663machine # [ 12.165267] sshd-keygen-start[645]: | o % * |1664machine # [ 12.166095] sshd-keygen-start[645]: | E * |1665machine # [ 12.166841] sshd-keygen-start[645]: | So + . |1666machine # [ 12.167602] sshd-keygen-start[645]: | . . * + |1667sshClient # [ 12.144231] systemd[1]: Finished Address configuration of eth1.1668machine # [ 12.173280] sshd-keygen-start[645]: | . ..o. . B |1669machine # [ 12.174079] sshd-keygen-start[645]: | .=+*.oo.+ = |1670machine # [ 12.174805] sshd-keygen-start[645]: | o*O+*o. .+ . |1671machine # [ 12.175569] sshd-keygen-start[645]: +----[SHA256]-----+1672sshClient # [ 12.171421] mousedev: PS/2 mouse device common for all mice1673machine # [ 12.189013] systemd[1]: sshd-keygen.service: Deactivated successfully.1674machine # [ 12.190246] systemd[1]: Finished SSH Host Keys Generation.1675machine # [ 12.191084] systemd[1]: Finished Address configuration of eth1.1676sshClient # [ 12.187950] systemd-logind[470]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1677machine # [ 12.224746] dhcpcd[628]: dhcpcd-10.3.2 starting1678machine # [ 12.237207] dhcpcd[681]: dev: loaded udev1679machine # [ 12.320157] systemd[1]: Finished Extra networking commands..1680machine # [ 12.322760] systemd[1]: Reached target Network.1681machine # [ 12.345297] systemd[1]: Starting SSH Daemon...1682machine: (finished: waiting for unit systemd-homed.service, in 12.71 seconds)1683machine: waiting for Please enter user name to create to appear on tty 11684machine # [ 12.447613] systemd-logind[542]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1685machine # [ 12.516491] sshd[709]: Server listening on 0.0.0.0 port 22.1686machine # [ 12.517508] sshd[709]: Server listening on :: port 22.1687machine # [ 12.526886] systemd[1]: Started SSH Daemon.1688machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.14 seconds)1689machine: sending keys 'test-homed-user\n'1690machine # [ 12.597195] dhcpcd[681]: eth0: waiting for carrier1691machine # [ 12.598114] dhcpcd[681]: eth0: carrier acquired1692machine # [ 12.610091] dhcpcd[681]: DUID 00:01:00:01:32:6d:8b:9f:52:54:00:12:34:561693machine # [ 12.611374] dhcpcd[681]: eth0: IAID 00:12:34:561694machine # [ 12.613988] dhcpcd[681]: eth0: adding address fe80::5054:ff:fe12:34561695machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds)1696machine: waiting for Please enter new password for user test-homed-user: to appear on tty 11697sshClient # [ 13.236460] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1698sshClient # [ 13.236626] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1699sshClient # [ 13.236677] systemd[1]: Stopped Virtual Console Setup.1700sshClient # [ 13.236765] systemd[1]: Stopping Virtual Console Setup...1701sshClient # [ 13.247314] systemd[1]: Starting Virtual Console Setup...1702sshClient # [ 13.303461] systemd-logind[470]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1703sshClient # [ 13.387177] systemd-vconsole-setup[692]: Configuration of first virtual console was skipped, ignoring remaining ones.1704machine # [ 13.415051] dhcpcd[681]: eth0: soliciting a DHCP lease1705sshClient # [ 13.392176] systemd[1]: Finished Virtual Console Setup.1706machine # [ 13.420637] dhcpcd[681]: eth0: offered 10.0.2.15 from 10.0.2.21707machine # [ 13.428294] dhcpcd[681]: eth0: probing address 10.0.2.15/241708sshClient # [ 13.428155] dhcpcd[591]: eth0: soliciting a DHCP lease1709sshClient # [ 13.435518] dhcpcd[591]: eth0: offered 10.0.2.15 from 10.0.2.21710sshClient # [ 13.440321] dhcpcd[591]: eth0: probing address 10.0.2.15/241711machine # [ 13.641747] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1712machine # [ 13.650829] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1713machine # [ 13.652423] systemd[1]: Stopped Virtual Console Setup.1714machine # [ 13.653194] systemd[1]: Stopping Virtual Console Setup...1715machine # [ 13.657640] systemd[1]: Starting Virtual Console Setup...1716machine # [ 13.701745] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1717machine # [ 13.704436] systemd[1]: Stopped Virtual Console Setup.1718machine # [ 13.716926] systemd[1]: Starting Virtual Console Setup...1719machine # [ 13.755244] systemd-logind[542]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1720machine # [ 13.837074] systemd-vconsole-setup[740]: Configuration of first virtual console was skipped, ignoring remaining ones.1721machine # [ 13.846253] systemd[1]: Finished Virtual Console Setup.1722machine: (finished: waiting for Please enter new password for user test-homed-user: to appear on tty 1, in 1.14 seconds)1723machine: sending keys 'foobarfoo\n'1724machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1725machine: waiting for (repeat) to appear on tty 11726sshClient # [ 14.885108] dhcpcd[591]: eth0: soliciting an IPv6 router1727sshClient # [ 14.887779] dhcpcd[591]: eth0: Router Advertisement from fe80::21728sshClient # [ 14.890899] dhcpcd[591]: eth0: adding address fec0::5054:ff:fe12:3456/641729sshClient # [ 14.892584] dhcpcd[591]: eth0: adding route to fec0::/641730sshClient # [ 14.893937] dhcpcd[591]: eth0: adding default route via fe80::21731machine # [ 15.017780] dhcpcd[681]: eth0: soliciting an IPv6 router1732machine # [ 15.019398] dhcpcd[681]: eth0: Router Advertisement from fe80::21733machine # [ 15.021004] dhcpcd[681]: eth0: adding address fec0::5054:ff:fe12:3456/641734machine # [ 15.022476] dhcpcd[681]: eth0: adding route to fec0::/641735machine # [ 15.023734] dhcpcd[681]: eth0: adding default route via fe80::21736machine: (finished: waiting for (repeat) to appear on tty 1, in 1.11 seconds)1737machine: sending keys 'foobarfoo\n'1738machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)1739machine: waiting for unit systemd-homed-firstboot.service1740machine # [ 18.482184] dhcpcd[681]: eth0: leased 10.0.2.15 for 86400 seconds1741machine # [ 18.483919] dhcpcd[681]: eth0: adding route to 10.0.2.0/241742machine # [ 18.485540] dhcpcd[681]: eth0: adding default route via 10.0.2.21743machine # [ 18.599870] systemd[1]: Started DHCP Client.1744sshClient # [ 18.802347] dhcpcd[591]: eth0: leased 10.0.2.15 for 86400 seconds1745sshClient # [ 18.804799] dhcpcd[591]: eth0: adding route to 10.0.2.0/241746sshClient # [ 18.806190] dhcpcd[591]: eth0: adding default route via 10.0.2.21747sshClient # [ 18.921487] systemd[1]: Started DHCP Client.1748sshClient # [ 18.925279] systemd[1]: Reached target Multi-User System.1749sshClient # [ 18.926785] systemd[1]: Startup finished in 1.339s (kernel) + 3.893s (initrd) + 13.692s (userspace) = 18.926s.1750machine # [ 25.411312] systemd-homed[508]: Generating key pair for signing local user identity records.1751machine # [ 25.416319] systemd-homed[508]: Successfully created Ed25519 key pair.1752machine # [ 25.429081] systemd-homed[508]: test-homed-user: changing state absent → creating1753machine # [ 25.523467] systemd-homework[864]: Using automatic default storage of 'luks'.1754machine # [ 25.528512] systemd-homework[864]: Sizing home to 83% of available disk space, which is 744M.1755machine # [ 25.530086] systemd-homework[864]: Allocating image file completed.1756machine # [ 25.572143] systemd-homework[864]: Writing of partition table completed.1757machine # [ 25.576124] systemd-homework[864]: Setting up loopback device /dev/loop0 completed.1758machine # [ 26.859882] systemd-homework[864]: LUKS formatting completed.1759machine # [ 29.988123] systemd-homework[864]: Writing password to LUKS keyslot 0 completed.1760machine # [ 30.244220] systemd-homework[864]: LUKS activation by volume key succeeded.1761machine # [ 30.262379] systemd-homework[864]: Writing user record as LUKS token completed.1762machine # [ 30.263670] systemd-homework[864]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1763machine # [ 30.268135] systemd-homework[864]: Formatting /dev/mapper/home-test-homed-user as btrfs1764machine # [ 31.389787] systemd[1]: proc-896-mounts.mount: Deactivated successfully.1765machine # [ 31.390818] systemd-homework[864]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid 64ea3196-bd7f-4137-ba47-873ddcb82844)1766machine # [ 31.390915] systemd-homework[864]: Formatting file system completed.1767machine # [ 31.461798] systemd-homework[864]: Mounting file system completed.1768machine # [ 31.464705] systemd-homework[864]: Skeleton directory /etc/skel missing, ignoring.1769machine # [ 31.466673] systemd-homework[864]: Wrote embedded .identity file.1770machine # [ 31.467792] systemd-homework[864]: Replaced embedded blob dir with contents of system blob dir.1771machine # [ 31.470573] systemd-homework[864]: Recursive changing of ownership completed.1772machine # [ 31.472300] systemd-homework[864]: Changed top-level directory access mode to 0700.1773machine # [ 31.475884] systemd-homework[864]: Synchronized disk.1774machine # [ 31.478114] systemd-homework[864]: Discarded unused 652.8M.1775machine # [ 31.491547] systemd-homed[508]: block device /sys/devices/virtual/block/dm-0 has been removed.1776machine # [ 31.548826] systemd-homed[508]: block device /sys/devices/virtual/block/dm-0 has been removed.1777machine # [ 31.554696] (udev-worker)[887]: loop0: Failed to add device watch, ignoring: No such file or directory1778machine # [ 31.561485] (udev-worker)[887]: loop0: Failed to add device watch, ignoring: No such file or directory1779machine # [ 31.569447] (udev-worker)[887]: loop0: Failed to add device watch, ignoring: No such file or directory1780machine # [ 31.571423] systemd-homework[864]: Moved image file into place.1781machine # [ 31.581261] systemd-homework[864]: Creation completed.1782machine # [ 31.582166] systemd-homework[864]: Image size is 744M, file system size is 726M, file system payload size is 726M, file system free is 719.9M.1783machine # [ 31.583994] (udev-worker)[887]: loop0: Failed to add device watch, ignoring: No such file or directory1784machine # [ 31.586153] (udev-worker)[887]: loop0: Failed to add device watch, ignoring: No such file or directory1785machine # [ 31.587979] systemd-homed[508]: block device /sys/devices/virtual/block/loop0 has been removed.1786machine # [ 31.597188] systemd-homed[508]: test-homed-user: changing state creating → inactive1787machine # [ 31.618314] systemd[1]: Finished First Boot Home Area Wizard.1788machine # [ 31.625576] systemd[1]: systemd-mute-console@0-1-614_615-0.service: Deactivated successfully.1789machine # [ 31.634932] systemd[1]: Reached target First Boot Complete.1790machine # [ 31.640166] systemd[1]: Starting Save Transient machine-id to Disk...1791machine # [ 31.645703] systemd[1]: Starting Permit User Sessions...1792machine # [ 31.701740] systemd[1]: Finished Permit User Sessions.1793machine # [ 31.708567] systemd[1]: Started Getty on tty1.1794machine # [ 31.710925] systemd[1]: Reached target Login Prompts.1795machine # [ 31.717158] systemd[1]: Reached target Multi-User System.1796machine # [ 32.314668] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1797machine # [ 32.323563] systemd[1]: Finished Save Transient machine-id to Disk.1798machine # [ 32.329650] systemd[1]: Startup finished in 1.353s (kernel) + 4.934s (initrd) + 26.038s (userspace) = 32.327s.1799machine: (finished: waiting for unit systemd-homed-firstboot.service, in 17.46 seconds)1800(finished: subtest: create systemd-homed user on first boot prompt, in 32.95 seconds)1801machine: must succeed: homectl update test-homed-user --offline -G wheel1802machine # [ 32.722602] systemd-homed[508]: test-homed-user: changing state inactive → updating1803machine # [ 32.746839] systemd-homework[947]: Offline update requested. Not touching embedded records.1804machine # [ 32.751180] systemd-homed[508]: Home test-homed-user is signed exclusively by our key, accepting.1805machine # [ 32.758083] systemd-homed[508]: test-homed-user: changing state updating → inactive1806machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.09 seconds)1807subtest: login as homed user1808machine: waiting for login: to appear on tty 11809machine: (finished: waiting for login: to appear on tty 1, in 0.05 seconds)1810machine: sending keys 'test-homed-user\n'1811machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds)1812machine: waiting for Password: to appear on tty 11813machine # [ 33.153353] systemd-homed[508]: test-homed-user: changing state inactive → activating-for-acquire1814machine # [ 33.163238] systemd-homework[962]: None of the supplied plaintext passwords unlock the user record's hashed passwords.1815machine # [ 33.173067] systemd-homed[508]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.1816machine # [ 33.177273] systemd-homed[508]: test-homed-user: changing state activating-for-acquire → inactive1817machine # [ 33.180426] systemd-homed[508]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.1818machine # [ 33.182987] systemd-homed[508]: Home test-homed-user already deactivated, no automatic deactivation needed.1819machine: (finished: waiting for Password: to appear on tty 1, in 1.13 seconds)1820machine: sending keys 'foobarfoo\n'1821machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)1822machine: waiting for success: pgrep -u test-homed-user -t tty1 bash1823machine # [ 34.300270] systemd-homed[508]: test-homed-user: changing state inactive → activating-for-acquire1824machine # [ 34.341375] systemd-homework[974]: Provided password unlocks user record.1825machine # [ 34.346920] systemd-homework[974]: Successfully locked image file '/home/test-homed-user.home'.1826machine # [ 34.354129] systemd-homework[974]: Allocated additional 652.8M.1827machine # [ 34.377918] loop1: detected capacity change from 0 to 15196161828machine # [ 34.361260] systemd-homework[974]: Setting up loopback device /dev/loop1 completed.1829machine # [ 35.045108] systemd-homework[974]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1830machine # [ 35.085797] systemd-homework[974]: Provided password unlocks user record.1831machine # [ 35.093163] systemd-homework[974]: Probing file system completed (found btrfs).1832machine # [ 35.095661] systemd-homework[974]: No fsck for file system btrfs installed, ignoring.1833machine # [ 35.117337] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (974)1834machine # [ 35.122216] BTRFS info (device dm-0): first mount of filesystem 64ea3196-bd7f-4137-ba47-873ddcb828441835machine # [ 35.122276] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm1836machine # [ 35.109835] systemd-homework[974]: Mounting file system completed.1837machine # [ 35.128213] BTRFS info (device dm-0): enabling free space tree1838machine # [ 35.128240] BTRFS info (device dm-0): use zstd compression, level 11839machine # [ 35.115901] systemd-homework[974]: Discovered used loopback device /dev/loop1.1840machine # [ 35.119356] systemd-homework[974]: offset = 1048576, size = 778043392, image = 7801405441841machine # [ 35.127679] systemd-homework[974]: Ready to resize image size 744M → 897M, partition size 742M → 895M, file system size 726M → 879M.1842machine # [ 35.130398] systemd-homework[974]: Growing of image file from 744M to 897M completed.1843machine # [ 35.142498] systemd-homework[974]: Refreshing loop device size completed.1844machine # [ 35.158792] loop1: detected capacity change from 1519616 to 18329601845machine # [ 35.159271] systemd-homework[974]: Growing of partition completed.1846machine # [ 35.209875] async_tx: api initialized (async)1847machine # [ 35.253801] systemd-homework[974]: LUKS device growing completed.1848machine # [ 35.281374] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 761266176 to 9216983041849machine # [ 35.266481] systemd-homework[974]: File system resizing from 726M to 879M completed.1850machine # [ 35.267992] systemd-homework[974]: Synchronized disk.1851machine # [ 35.273538] systemd-homework[974]: Synchronized disk.1852machine # [ 35.274383] systemd-homework[974]: Resizing completed.1853machine # [ 35.275358] systemd-homework[974]: Image size is 897M, file system size is 879M, file system payload size is 879M, file system free is 872.9M.1854machine # [ 35.288325] systemd-homework[974]: Read embedded .identity file.1855machine # [ 35.316344] systemd-homework[974]: Provided password unlocks user record.1856machine # [ 35.318803] systemd-homework[974]: Reconciling header user identity completed (host version was newer).1857machine # [ 35.322501] systemd-homework[974]: Reconciling embedded user identity completed (host version was newer).1858machine # [ 35.352611] systemd-homework[974]: Wrote LUKS header user record.1859machine # [ 35.353778] systemd-homework[974]: Wrote embedded .identity file.1860machine # [ 35.354709] systemd-homework[974]: Replaced embedded blob dir with contents of system blob dir.1861machine # [ 35.361200] systemd-homework[974]: Recursive changing of ownership not necessary, skipped.1862machine # [ 35.372153] systemd-homework[974]: Synchronized disk.1863machine # [ 35.373096] systemd-homework[974]: Moving to final mount point /home/test-homed-user completed.1864machine # [ 35.409980] systemd-homework[974]: Activation completed.1865machine # [ 35.411070] systemd-homework[974]: Image size is 895M, file system size is 879M, file system payload size is 879M, file system free is 872.9M.1866machine # [ 35.436649] systemd-homed[508]: Home test-homed-user is signed exclusively by our key, accepting.1867machine # [ 35.446845] login[937]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired.1868machine # [ 35.451331] systemd-homed[508]: test-homed-user: changing state activating-for-acquire → active1869machine # [ 35.457060] systemd-homed[508]: test-homed-user: changing state active → resizing-while-active1870machine # [ 35.467964] systemd-homework[1016]: Successfully acquired home volume key from kernel keyring.1871machine # [ 35.473434] systemd-homework[1016]: LUKS volume key from keyring unlocks user record.1872machine # [ 35.564668] login[937]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0)1873machine # [ 35.580650] systemd-homework[1016]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.1874machine # [ 35.709838] systemd-homework[1016]: Successfully re-activated LUKS device.1875machine # [ 35.712956] systemd-homework[1016]: LUKS volume key from keyring unlocks user record.1876machine # [ 35.716271] systemd-homework[1016]: Discovered used loopback device /dev/loop1.1877machine # [ 35.718966] systemd-homework[1016]: Read embedded .identity file.1878machine # [ 35.721141] systemd-homework[1016]: LUKS volume key from keyring unlocks user record.1879machine # [ 35.722307] systemd-homework[1016]: Reconciling header user identity completed (host version was newer).1880machine # [ 35.723817] systemd-homework[1016]: Reconciling embedded user identity completed (host version was newer).1881machine # [ 35.728748] systemd-homework[1016]: offset = 1048576, size = 938475520, image = 9405726721882machine # [ 35.864540] systemd-homework[1016]: Ready to resize image size 897M → 765M, partition size 895M → 763M, file system size 879M → 747M.1883machine # [ 35.868988] systemd-homework[1016]: Wrote embedded .identity file.1884machine # [ 35.870803] systemd-homework[1016]: Replaced embedded blob dir with contents of system blob dir.[ 35.893552] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 921698304 to 7832862721885machine # 1886machine # [ 35.879351] systemd-homework[1016]: Backing file is fully allocated already.1887machine # [ 35.885066] systemd-homework[1016]: File system resizing from 879M to 747M completed.1888machine # [ 35.886389] systemd-homework[1016]: Synchronized disk.1889machine # [ 35.927473] systemd[1]: Created slice Slice /user/60388.1890machine # [ 35.944735] systemd[1]: Starting User Runtime Directory /run/user/60388...1891machine # [ 35.979809] systemd-logind[542]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'.1892machine # [ 36.037484] systemd[1]: Finished User Runtime Directory /run/user/60388.1893machine # [ 36.055806] systemd[1]: Starting User Manager for UID 60388...1894machine # [ 36.094205] systemd-homework[1016]: LUKS device shrinking completed.1895machine # [ 36.122866] loop1: detected capacity change from 1832960 to 15626241896machine # [ 36.109333] systemd-homework[1016]: Refreshing loop device size completed.1897machine # [ 36.111228] systemd-homework[1016]: Shrinking of image file completed.1898machine # [ 36.126496] (systemd)[1030]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.1899machine # [ 36.148326] systemd-homework[1016]: Shrinking of partition completed.1900machine # [ 36.197950] systemd-homework[1016]: Wrote LUKS header user record.1901machine # [ 36.199091] systemd-homework[1016]: Synchronized disk.1902machine # [ 36.222199] systemd-homework[1016]: Resizing completed.1903machine # [ 36.223173] systemd-homework[1016]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.1904machine # [ 36.232201] systemd-homed[508]: Home test-homed-user is signed exclusively by our key, accepting.1905machine # [ 36.246523] systemd-homed[508]: test-homed-user: changing state resizing-while-active → active1906machine # [ 36.251234] systemd-homed[508]: Rebalancing complete.1907machine # [ 36.258026] (systemd)[1030]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)1908machine # [ 36.264965] systemd-logind[542]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'.1909machine # [ 36.295806] systemd[1030]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1910machine # [ 36.473034] systemd[1030]: Queued start job for default target Main User Target.1911machine # [ 36.481152] systemd[1030]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1912machine # [ 36.485421] systemd[1030]: Created slice User Application Slice.1913machine # [ 36.486483] systemd[1030]: Started Daily Cleanup of User's Temporary Directories.1914machine # [ 36.487705] systemd[1030]: Reached target Paths.1915machine # [ 36.488794] systemd[1030]: Reached target Timers.1916machine # [ 36.491477] systemd[1030]: Starting D-Bus User Message Bus Socket...1917machine # [ 36.494027] systemd[1030]: Starting Create User Files and Directories...1918machine # [ 36.542473] systemd[1030]: Finished Create User Files and Directories.1919machine # [ 36.553444] systemd[1030]: Listening on D-Bus User Message Bus Socket.1920machine # [ 36.554637] systemd[1030]: Reached target Sockets.1921machine # [ 36.562365] systemd[1030]: Reached target Basic System.1922machine # [ 36.563436] systemd[1]: Started User Manager for UID 60388.1923machine # [ 36.565431] systemd[1030]: Starting Run user-specific NixOS activation...1924machine # [ 36.576522] systemd[1]: Started Session 1 of User test-homed-user.1925machine # [ 36.616138] systemd[1030]: Finished Run user-specific NixOS activation.1926machine # [ 36.617299] systemd[1030]: Reached target Main User Target.1927machine # [ 36.619658] systemd[1030]: Startup finished in 334ms.1928machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 3.30 seconds)1929machine: sending keys 'whoami > /tmp/2\n'1930machine: (finished: sending keys 'whoami > /tmp/2\n', in 0.17 seconds)1931machine: waiting for file '/tmp/2'1932machine: (finished: waiting for file '/tmp/2', in 1.06 seconds)1933machine: must succeed: cat /tmp/21934machine: (finished: must succeed: cat /tmp/2, in 0.03 seconds)1935(finished: subtest: login as homed user, in 6.00 seconds)1936subtest: login as regular user1937sshClient: waiting for login: to appear on tty 11938sshClient: waiting for the VM to finish booting1939sshClient: Guest shell says: b'Spawning backdoor root shell...\n'1940sshClient: connected to guest root shell1941sshClient: (connecting took 0.00 seconds)1942sshClient: (finished: waiting for the VM to finish booting, in 0.00 seconds)1943sshClient: (finished: waiting for login: to appear on tty 1, in 0.06 seconds)1944sshClient: sending keys 'test-normal-user\n'1945sshClient: (finished: sending keys 'test-normal-user\n', in 0.18 seconds)1946sshClient: waiting for Password: to appear on tty 11947sshClient: (finished: waiting for Password: to appear on tty 1, in 1.12 seconds)1948sshClient: sending keys 'foobarfoo\n'1949sshClient: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1950sshClient: waiting for success: pgrep -u test-normal-user bash1951sshClient # [ 40.320697] login[645]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0)1952sshClient # [ 40.360128] systemd-logind[470]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'.1953sshClient # [ 40.383262] systemd[1]: Created slice Slice /user/1000.1954sshClient # [ 40.397431] systemd[1]: Starting User Runtime Directory /run/user/1000...1955sshClient # [ 40.431147] systemd[1]: Finished User Runtime Directory /run/user/1000.1956sshClient # [ 40.439001] systemd[1]: Starting User Manager for UID 1000...1957sshClient # [ 40.474490] (systemd)[795]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0)1958sshClient # [ 40.483235] systemd-logind[470]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'.1959sshClient # [ 40.519549] systemd[795]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1960sshClient # [ 40.657888] systemd[795]: Queued start job for default target Main User Target.1961sshClient # [ 40.665155] systemd[795]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1962sshClient # [ 40.669621] systemd[795]: Created slice User Application Slice.1963sshClient # [ 40.670738] systemd[795]: Started Daily Cleanup of User's Temporary Directories.1964sshClient # [ 40.671837] systemd[795]: Reached target Paths.1965sshClient # [ 40.673079] systemd[795]: Reached target Timers.1966sshClient # [ 40.675591] systemd[795]: Starting D-Bus User Message Bus Socket...1967sshClient # [ 40.677776] systemd[795]: Starting Create User Files and Directories...1968sshClient # [ 40.731762] systemd[795]: Finished Create User Files and Directories.1969sshClient # [ 40.752120] systemd[795]: Listening on D-Bus User Message Bus Socket.1970sshClient # [ 40.753217] systemd[795]: Reached target Sockets.1971sshClient # [ 40.753986] systemd[795]: Reached target Basic System.1972sshClient # [ 40.760999] systemd[1]: Started User Manager for UID 1000.1973sshClient # [ 40.761991] systemd[795]: Starting Run user-specific NixOS activation...1974sshClient # [ 40.770489] systemd[1]: Started Session 1 of User test-normal-user.1975sshClient # [ 40.830008] systemd[795]: Finished Run user-specific NixOS activation.1976sshClient # [ 40.836406] systemd[795]: Reached target Main User Target.1977sshClient # [ 40.837465] systemd[795]: Startup finished in 323ms.1978sshClient: (finished: waiting for success: pgrep -u test-normal-user bash, in 1.11 seconds)1979sshClient: sending keys 'whoami > /tmp/1\n'1980sshClient: (finished: sending keys 'whoami > /tmp/1\n', in 0.17 seconds)1981sshClient: waiting for file '/tmp/1'1982sshClient: (finished: waiting for file '/tmp/1', in 1.08 seconds)1983sshClient: must succeed: cat /tmp/11984sshClient: (finished: must succeed: cat /tmp/1, in 0.03 seconds)1985(finished: subtest: login as regular user, in 3.86 seconds)1986subtest: add homed ssh authorized key1987sshClient: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n'1988sshClient: (finished: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n', in 0.50 seconds)1989sshClient: waiting for file '/tmp/id_ed25519.pub'1990sshClient: (finished: waiting for file '/tmp/id_ed25519.pub', in 1.07 seconds)1991sshClient: must succeed: cat /tmp/id_ed25519.pub1992sshClient: (finished: must succeed: cat /tmp/id_ed25519.pub, in 0.03 seconds)1993machine: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIK+UHmjak5nHrmY+RJ9r3DGFB9Nk1OnAyY7ISBk8IWjJ test-normal-user@sshClient'1994machine # [ 44.260003] systemd-homed[508]: test-homed-user: changing state active → updating-while-active1995machine # [ 44.266776] systemd-homework[1078]: Offline update requested. Not touching embedded records.1996machine # [ 44.276551] systemd-homed[508]: test-homed-user: changing state updating-while-active → active1997machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIK+UHmjak5nHrmY+RJ9r3DGFB9Nk1OnAyY7ISBk8IWjJ test-normal-user@sshClient', in 0.06 seconds)1998machine: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed255191999machine: (finished: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed25519, in 0.03 seconds)2000(finished: subtest: add homed ssh authorized key, in 1.68 seconds)2001subtest: change homed user password2002machine: sending keys 'passwd; echo $? > /tmp/3\n'2003machine: (finished: sending keys 'passwd; echo $? > /tmp/3\n', in 0.27 seconds)2004machine: waiting for New password: to appear on tty 12005machine: (finished: waiting for New password: to appear on tty 1, in 1.08 seconds)2006machine: sending keys 'barfoobar\n'2007machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds)2008machine: waiting for Retype new password: to appear on tty 12009machine: (finished: waiting for Retype new password: to appear on tty 1, in 0.11 seconds)2010machine: sending keys 'barfoobar\n'2011machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds)2012??? Warning (UserWarning): sleep(): The 'secs' argument is deprecated. Use 'duration' instead.2013 File "/nix/store/0yd36fpwpnbc3yrryy7qi9hd645vflhd-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 5732014machine: must succeed: sleep 4.02015machine # [ 46.130190] systemd-homed[508]: test-homed-user: changing state active → passwd-while-active2016machine # [ 46.707669] systemd-homework[1110]: No valid password for LUKS superblock.2017machine # [ 49.185416] systemd-homed[508]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2018machine # [ 49.189689] systemd-homed[508]: test-homed-user: changing state passwd-while-active → active2019machine: (finished: must succeed: sleep 4.0, in 4.04 seconds)2020machine: sending keys 'foobarfoo\n'2021machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)2022machine: waiting for file '/tmp/3'2023machine # [ 50.300829] systemd-homed[508]: test-homed-user: changing state active → passwd-while-active2024machine # [ 51.585464] systemd-homework[1115]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2025machine # [ 51.713426] systemd-homework[1115]: Successfully re-activated LUKS device.2026machine # [ 51.771722] systemd-homework[1115]: Provided password unlocks user record.2027machine # [ 51.776324] systemd-homework[1115]: Discovered used loopback device /dev/loop1.2028machine # [ 51.777499] systemd-homework[1115]: Read embedded .identity file.2029machine # [ 51.830202] systemd-homework[1115]: Provided password unlocks user record.2030machine # [ 51.836209] systemd-homework[1115]: Reconciling header user identity completed (host version was newer).2031machine # [ 51.837736] systemd-homework[1115]: Reconciling embedded user identity completed (host version was newer).2032machine # [ 55.393406] systemd-homework[1115]: Added LUKS key slot 1.2033machine # [ 55.418605] systemd-homework[1115]: Destroyed LUKS key slot 0.2034machine # [ 55.445715] systemd-homework[1115]: Wrote LUKS header user record.2035machine # [ 55.447739] systemd-homework[1115]: Wrote embedded .identity file.2036machine # [ 55.451164] systemd-homework[1115]: Replaced embedded blob dir with contents of system blob dir.2037machine # [ 55.484850] systemd-homework[1115]: Synchronized disk.2038machine # [ 55.491147] systemd-homework[1115]: Everything completed.2039machine # [ 55.517022] passwd[1091]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user.2040machine # [ 55.521146] systemd-homed[508]: test-homed-user: changing state passwd-while-active → active2041machine: (finished: waiting for file '/tmp/3', in 6.24 seconds)2042machine: must succeed: cat /tmp/32043machine: (finished: must succeed: cat /tmp/3, in 0.03 seconds)2044(finished: subtest: change homed user password, in 12.09 seconds)2045subtest: escalate to root from homed user2046machine: sending keys 'sudo id | tee /tmp/4\n'2047machine: (finished: sending keys 'sudo id | tee /tmp/4\n', in 0.22 seconds)2048machine: waiting for password for test-homed-user to appear on tty 12049machine # [ 56.884607] systemd-homed[508]: test-homed-user: changing state active → authenticating-for-acquire2050machine # [ 56.893216] systemd-homework[1157]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2051machine # [ 56.903236] systemd-homed[508]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2052machine # [ 56.906652] systemd-homed[508]: test-homed-user: changing state authenticating-for-acquire → active2053machine: (finished: waiting for password for test-homed-user to appear on tty 1, in 1.11 seconds)2054machine: sending keys 'barfoobar\n'2055machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2056machine: waiting for file '/tmp/4'2057machine: (finished: waiting for file '/tmp/4', in 0.02 seconds)2058machine: waiting for success: grep uid=0 /tmp/42059machine # [ 58.155933] systemd-homed[508]: test-homed-user: changing state active → authenticating-for-acquire2060machine # [ 58.214981] systemd-homework[1173]: Provided password unlocks user record.2061machine # [ 58.952136] systemd-homework[1173]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2062machine # [ 59.076684] systemd-homework[1173]: Successfully re-activated LUKS device.2063machine # [ 59.107474] systemd-homework[1173]: Provided password unlocks user record.2064machine # [ 59.109499] systemd-homework[1173]: Discovered used loopback device /dev/loop1.2065machine # [ 59.110867] systemd-homework[1173]: Read embedded .identity file.2066machine # [ 59.138570] systemd-homework[1173]: Provided password unlocks user record.2067machine # [ 59.140830] systemd-homework[1173]: Reconciling user identities completed (host and header version were identical).2068machine # [ 59.143217] systemd-homework[1173]: Reconciling embedded user identity completed (host and embedded version were identical).2069machine # [ 59.151603] systemd-homework[1173]: Everything completed.2070machine # [ 59.160898] systemd-homed[508]: Home test-homed-user is signed exclusively by our key, accepting.2071machine # [ 59.639993] sudo[1154]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired.2072machine # [ 59.646281] systemd-homed[508]: test-homed-user: changing state authenticating-for-acquire → active2073machine # [ 59.657176] sudo[1154]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id2074machine # [ 59.698191] sudo[1154]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388)2075machine # [ 59.721064] sudo[1154]: pam_unix(sudo:session): session closed for user root2076machine: (finished: waiting for success: grep uid=0 /tmp/4, in 2.11 seconds)2077(finished: subtest: escalate to root from homed user, in 3.57 seconds)2078subtest: log out and deactivate homed user's home area2079machine: sending keys 'exit\n'2080machine: (finished: sending keys 'exit\n', in 0.05 seconds)2081machine: waiting for success: homectl inspect test-homed-user | grep 'State: inactive'2082machine # [ 60.058360] login[937]: pam_unix(login:session): session closed for user test-homed-user2083machine # [ 60.067677] login[937]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used.2084machine # [ 60.076957] systemd[1]: getty@tty1.service: Deactivated successfully.2085machine # [ 60.087956] systemd[1]: session-1.scope: Deactivated successfully.2086machine # [ 60.093075] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.2087machine # [ 60.099165] systemd-logind[542]: Session 1 logged out. Waiting for processes to exit.2088machine # [ 60.102472] systemd[1]: Started Getty on tty1.2089machine # [ 60.106645] systemd-logind[542]: Removed session 1.2090machine # [ 70.234714] systemd[1]: Stopping User Manager for UID 60388...2091machine # [ 70.243708] systemd[1030]: Activating special unit Exit the Session...2092machine # [ 70.251709] systemd[1030]: Stopped target Main User Target.2093machine # [ 70.257053] systemd[1030]: Stopped Run user-specific NixOS activation.2094machine # [ 70.258858] systemd[1030]: Stopped target Basic System.2095machine # [ 70.260500] systemd[1030]: Stopped target Paths.2096machine # [ 70.261698] systemd[1030]: Stopped target Sockets.2097machine # [ 70.262940] systemd[1030]: Stopped target Timers.2098machine # [ 70.264270] systemd[1030]: Stopped Daily Cleanup of User's Temporary Directories.2099machine # [ 70.266037] systemd[1030]: Closed D-Bus User Message Bus Socket.2100machine # [ 70.267588] systemd[1030]: Stopped Create User Files and Directories.2101machine # [ 70.269475] systemd[1030]: Removed slice User Application Slice.2102machine # [ 70.270993] systemd[1030]: Reached target Shutdown.2103machine # [ 70.272486] systemd[1030]: Finished Exit the Session.2104machine # [ 70.273803] systemd[1030]: Reached target Exit the Session.2105machine # [ 70.292002] systemd-homed[508]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2106machine # [ 70.313375] systemd-logind[542]: Removed session 2.2107machine # [ 70.314815] systemd[1]: user@60388.service: Deactivated successfully.2108machine # [ 70.324964] systemd-homed[508]: test-homed-user: changing state active → deactivating2109machine # [ 70.326594] systemd[1]: Stopped User Manager for UID 60388.2110machine # [ 70.331620] systemd-homework[1250]: Successfully acquired home volume key from kernel keyring.2111machine # [ 70.333794] systemd-homework[1250]: Discarded unused 674.3M.2112machine # [ 70.338368] systemd-homework[1250]: Syncing completed.2113machine # [ 70.369838] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully.2114machine # [ 70.379783] systemd[1]: Stopping User Runtime Directory /run/user/60388...2115machine # [ 70.432183] systemd-homework[1250]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2116machine # [ 70.529213] systemd[1]: run-user-60388.mount: Deactivated successfully.2117machine # [ 70.538812] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully.2118machine # [ 70.545836] systemd[1]: Stopped User Runtime Directory /run/user/60388.2119machine # [ 70.583932] systemd-homework[1250]: Successfully re-activated LUKS device.2120machine # [ 70.587166] systemd-homework[1250]: Discovered used loopback device /dev/loop1.2121machine # [ 70.590503] systemd-homework[1250]: offset = 1048576, size = 800063488, image = 8021606402122machine # [ 70.602981] systemd-homework[1250]: Ready to resize image size 765M → 274M, partition size 763M → 272M, file system size 747M → 256M.2123machine # [ 70.606507] systemd-homework[1250]: Allocated additional 674.3M.2124machine # [ 70.612782] systemd-homework[1250]: File system resizing from 747M to 256M completed.2125machine # [ 70.631182] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 783286272 to 2684354562126machine # [ 70.619786] systemd-homework[1250]: Synchronized disk.2127machine # [ 70.790338] systemd-homework[1250]: LUKS device shrinking completed.2128machine # [ 70.818859] loop1: detected capacity change from 1562624 to 5570562129machine # [ 70.806056] systemd-homework[1250]: Refreshing loop device size completed.2130machine # [ 70.807290] systemd-homework[1250]: Shrinking of image file completed.2131machine # [ 70.823845] systemd-homework[1250]: Shrinking of partition completed.2132machine # [ 70.825529] systemd-homework[1250]: Synchronized disk.2133machine # [ 70.845670] BTRFS info (device dm-0): last unmount of filesystem 64ea3196-bd7f-4137-ba47-873ddcb828442134machine # [ 70.832072] systemd-homework[1250]: Resizing completed.2135machine # [ 70.833009] systemd-homework[1250]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M.2136machine # [ 70.834780] systemd-homework[1250]: Unmounting completed.2137machine # [ 70.835586] systemd-homework[1250]: Discovered used LUKS device /dev/mapper/home-test-homed-user.2138machine # [ 70.838135] systemd-homework[1250]: Device home-test-homed-user is not active.2139machine # [ 70.868520] systemd-homed[508]: block device /sys/devices/virtual/block/dm-0 has been removed.2140machine # [ 70.876447] systemd-homework[1250]: Everything completed.2141machine # [ 70.888884] systemd-homed[508]: test-homed-user: changing state deactivating → inactive2142machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 11.82 seconds)2143(finished: subtest: log out and deactivate homed user's home area, in 11.87 seconds)2144subtest: ssh as homed user2145sshClient: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n'2146sshClient: (finished: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n', in 0.79 seconds)2147sshClient: waiting for Please enter password for user to appear on tty 12148machine # [ 73.692631] systemd-homed[508]: test-homed-user: changing state inactive → activating-for-acquire2149machine # [ 73.702933] systemd-homework[1286]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2150machine # [ 73.717170] systemd-homed[508]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2151machine # [ 73.724396] systemd-homed[508]: test-homed-user: changing state activating-for-acquire → inactive2152machine # [ 73.727082] sshd-session[1282]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error2153machine # [ 73.730435] systemd-homed[508]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2154machine # [ 73.733203] sshd-session[1282]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation.2155machine # [ 73.736443] systemd-homed[508]: Home test-homed-user already deactivated, no automatic deactivation needed.2156machine # [ 73.738412] sshd-session[1282]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 37286 ssh2: ED25519 SHA256:DqVlABoZ1gFOBvaMVk6uzrJEbNnsZez/zEQ2ZX+euOU2157machine # [ 73.791391] sshd-session[1282]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0)2158machine # [ 73.808751] systemd-logind[542]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'.2159machine # [ 73.814428] systemd[1]: Starting User Runtime Directory /run/user/60388...2160machine # [ 73.852447] systemd[1]: Finished User Runtime Directory /run/user/60388.2161machine # [ 73.862164] systemd[1]: Started Session 3 of User test-homed-user.2162machine # [ 73.879860] sshd-session[1282]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2163machine # [ 73.884625] sshd-session[1282]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2164machine # [ 73.906386] systemd-homed[508]: test-homed-user: changing state inactive → activating2165machine # [ 73.913103] systemd-homework[1291]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2166machine # [ 73.941787] systemd-homed[508]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2167machine # [ 73.944625] systemd-homed[508]: test-homed-user: changing state activating → inactive2168sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.18 seconds)2169sshClient: sending keys 'barfoobar\n'2170sshClient: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2171machine: waiting for success: pgrep -u test-homed-user bash2172machine # [ 75.006390] systemd-homed[508]: test-homed-user: changing state inactive → activating2173machine # [ 75.061791] systemd-homework[1296]: Provided password unlocks user record.2174machine # [ 75.067608] systemd-homework[1296]: Successfully locked image file '/home/test-homed-user.home'.2175machine # [ 75.085250] systemd-homework[1296]: Backing file is fully allocated already.2176machine # [ 75.093968] systemd-homework[1296]: Setting up loopback device /dev/loop1 completed.2177machine # [ 75.111171] loop1: detected capacity change from 0 to 5570562178machine # [ 76.036134] systemd-homework[1296]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.2179machine # [ 76.076188] systemd-homework[1296]: Provided password unlocks user record.2180machine # [ 76.082224] systemd-homework[1296]: Probing file system completed (found btrfs).2181machine # [ 76.084143] systemd-homework[1296]: No fsck for file system btrfs installed, ignoring.2182machine # [ 76.105131] BTRFS: device label test-homed-user devid 1 transid 17 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (1296)2183machine # [ 76.109584] BTRFS info (device dm-0): first mount of filesystem 64ea3196-bd7f-4137-ba47-873ddcb828442184machine # [ 76.109664] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm2185machine # [ 76.097473] systemd-homework[1296]: Mounting file system completed.2186machine # [ 76.115867] BTRFS info (device dm-0): enabling free space tree2187machine # [ 76.115903] BTRFS info (device dm-0): use zstd compression, level 12188machine # [ 76.104239] systemd-homework[1296]: Discovered used loopback device /dev/loop1.2189machine # [ 76.105371] systemd-homework[1296]: offset = 1048576, size = 285212672, image = 2873098242190machine # [ 76.114320] systemd-homework[1296]: Ready to resize image size 274M → 765M, partition size 272M → 763M, file system size 256M → 747M.2191machine # [ 76.117279] systemd-homework[1296]: Growing of image file from 274M to 765M completed.2192machine # [ 76.146802] loop1: detected capacity change from 557056 to 15626242193machine # [ 76.133308] systemd-homework[1296]: Refreshing loop device size completed.2194machine # [ 76.145500] systemd-homework[1296]: Growing of partition completed.2195machine # [ 76.192806] systemd-homework[1296]: LUKS device growing completed.2196machine # [ 76.217913] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 7832862722197machine # [ 76.202359] systemd-homework[1296]: File system resizing from 256M to 747M completed.2198machine # [ 76.203691] systemd-homework[1296]: Synchronized disk.2199machine # [ 76.208278] systemd-homework[1296]: Synchronized disk.2200machine # [ 76.209131] systemd-homework[1296]: Resizing completed.2201machine # [ 76.209906] systemd-homework[1296]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.2202machine # [ 76.211760] systemd-homework[1296]: Read embedded .identity file.2203machine # [ 76.237017] systemd-homework[1296]: Provided password unlocks user record.2204machine # [ 76.239291] systemd-homework[1296]: Reconciling user identities completed (host and header version were identical).2205machine # [ 76.243367] systemd-homework[1296]: Reconciling embedded user identity completed (host and embedded version were identical).2206machine # [ 76.248331] systemd-homework[1296]: Recursive changing of ownership not necessary, skipped.2207machine # [ 76.249663] systemd-homework[1296]: Synchronized disk.2208machine # [ 76.250418] systemd-homework[1296]: Moving to final mount point /home/test-homed-user completed.2209machine # [ 76.272987] systemd-homework[1296]: Activation completed.2210machine # [ 76.274301] systemd-homework[1296]: Image size is 763M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.2211machine # [ 76.291708] systemd-homed[508]: Home test-homed-user is signed exclusively by our key, accepting.2212machine # [ 76.301507] systemd-homed[508]: test-homed-user: changing state activating → active2213machine # [ 76.320523] systemd[1]: Starting User Manager for UID 60388...2214machine # [ 76.362769] (systemd)[1339]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.2215machine # [ 76.403709] (systemd)[1339]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)2216machine # [ 76.409903] systemd-logind[542]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'.2217machine # [ 76.439308] systemd[1339]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2218machine # [ 76.553079] systemd[1339]: Queued start job for default target Main User Target.2219machine # [ 76.557091] systemd[1339]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2220machine # [ 76.561326] systemd[1339]: Created slice User Application Slice.2221machine # [ 76.564251] systemd[1339]: Started Daily Cleanup of User's Temporary Directories.2222machine # [ 76.565395] systemd[1339]: Reached target Paths.2223machine # [ 76.566098] systemd[1339]: Reached target Timers.2224machine # [ 76.566934] systemd[1339]: Starting D-Bus User Message Bus Socket...2225machine # [ 76.572128] systemd[1339]: Starting Create User Files and Directories...2226machine # [ 76.619090] systemd[1339]: Finished Create User Files and Directories.2227machine # [ 76.623808] systemd[1339]: Listening on D-Bus User Message Bus Socket.2228machine # [ 76.627188] systemd[1339]: Reached target Sockets.2229machine # [ 76.627994] systemd[1339]: Reached target Basic System.2230machine # [ 76.633134] systemd[1]: Started User Manager for UID 60388.2231machine # [ 76.634031] systemd[1339]: Starting Run user-specific NixOS activation...2232machine # [ 76.692514] systemd[1339]: Finished Run user-specific NixOS activation.2233machine # [ 76.693740] systemd[1339]: Reached target Main User Target.2234machine # [ 76.694660] systemd[1339]: Startup finished in 265ms.2235machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.20 seconds)2236sshClient: sending keys 'whoami > /tmp/5\n'2237sshClient: (finished: sending keys 'whoami > /tmp/5\n', in 0.17 seconds)2238machine: waiting for file '/tmp/5'2239machine: (finished: waiting for file '/tmp/5', in 1.06 seconds)2240machine: must succeed: cat /tmp/52241machine: (finished: must succeed: cat /tmp/5, in 0.03 seconds)2242sshClient: sending keys 'exit\n'2243sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2244sshClient: sending keys 'exit\n'2245machine # [ 78.466826] sshd-session[1289]: Received disconnect from 2001:db8:1::2 port 37286:11: disconnected by user2246sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2247(finished: subtest: ssh as homed user, in 6.63 seconds)2248(finished: run the VM test script, in 81.14 seconds)2249machine # [ 78.474432] sshd-session[1289]: Disconnected from user test-homed-user 2001:db8:1::2 port 372862250machine # [ 78.483759] sshd-session[1282]: pam_unix(sshd:session): session closed for user test-homed-user2251machine # [ 78.489680] sshd-session[1282]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used.2252machine # [ 78.507542] systemd[1]: session-3.scope: Deactivated successfully.2253machine # [ 78.512886] systemd-logind[542]: Session 3 logged out. Waiting for processes to exit.2254machine # [ 78.514832] systemd-logind[542]: Removed session 3.2255sshClient # [ 78.529644] login[645]: pam_unix(login:session): session closed for user test-normal-user2256sshClient # [ 78.540723] systemd[1]: getty@tty1.service: Deactivated successfully.2257sshClient # [ 78.556291] systemd[1]: session-1.scope: Deactivated successfully.2258sshClient # [ 78.559319] systemd[1]: session-1.scope: Consumed 155ms CPU time over 37.790s wall clock time, 11M memory peak, 12K written to disk, 25.9K incoming IP traffic, 24.3K outgoing IP traffic.2259sshClient # [ 78.569453] systemd-logind[470]: Session 1 logged out. Waiting for processes to exit.2260sshClient # [ 78.576848] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.2261sshClient # [ 78.581684] systemd[1]: Started Getty on tty1.2262sshClient # [ 78.588777] systemd-logind[470]: Removed session 1.2263test script finished in 81.37s2264cleanup2265kill QemuMachine (pid 47)2266machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14)2267machine # [2026-10-23T03:07:13Z INFO virtiofsd] Client disconnected, shutting down2268machine # [2026-10-23T03:07:13Z INFO virtiofsd] Client disconnected, shutting down2269machine # [2026-10-23T03:07:13Z INFO virtiofsd] Client disconnected, shutting down2270kill QemuMachine (pid 48)2271sshClient # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14)2272sshClient # [2026-10-23T03:07:14Z INFO virtiofsd] Client disconnected, shutting down2273sshClient # [2026-10-23T03:07:14Z INFO virtiofsd] Client disconnected, shutting down2274sshClient # [2026-10-23T03:07:14Z INFO virtiofsd] Client disconnected, shutting down2275(finished: cleanup, in 1.15 seconds)2276additionally exposed symbols:2277 machine, sshClient,2278 vlan1,2279 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