vm-test-run-systemd-homed
checks.aarch64-linux.systemd-homed
· build #60
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7start all VMs8machine: starting vm9sshClient: starting vm10machine # Disk image does not exist, creating the virtualisation disk image...11machine: QEMU running (pid 47)12machine # Formatting '/build/vm-state-machine/tmp.D8e3FpLDGd', fmt=raw size=107374182413machine # mke2fs 1.47.4 (6-Mar-2025)14machine # Discarding device blocks: 0/262144 done15machine # Creating filesystem with 262144 4k blocks and 65536 inodes16sshClient: QEMU running (pid 48)17sshClient # Disk image does not exist, creating the virtualisation disk image...18machine # Filesystem UUID: 9b2166a4-964f-466b-9519-db572dfb457019machine # Superblock backups stored on blocks:20(finished: start all VMs, in 0.93 seconds)21sshClient # Formatting '/build/vm-state-sshClient/tmp.CK0uOHWKoc', fmt=raw size=107374182422subtest: create systemd-homed user on first boot prompt23sshClient # mke2fs 1.47.4 (6-Mar-2025)24machine # 32768, 98304, 163840, 22937625machine: waiting for unit systemd-homed.service26sshClient # Discarding device blocks: 0/262144 done27machine: waiting for the VM to finish booting28sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes29machine # 30sshClient # Filesystem UUID: e6a711f9-5e8e-4d29-bd17-5e01004f03d031machine # Allocating group tables: 0/8 done32sshClient # Superblock backups stored on blocks:33machine # Writing inode tables: 0/8 done34sshClient # 32768, 98304, 163840, 22937635machine # Creating journal (8192 blocks): done36sshClient # 37machine # Writing superblocks and filesystem accounting information: 0/8 done38sshClient # Allocating group tables: 0/8 done39machine # 40sshClient # Writing inode tables: 0/8 done41machine # Virtualisation disk image created.42sshClient # Creating journal (8192 blocks): done43machine # Starting virtiofs daemons...44sshClient # Writing superblocks and filesystem accounting information: 0/8 done45machine # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)46machine # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether47machine # [2026-10-18T02:43:35Z INFO virtiofsd] Waiting for vhost-user socket connection...48sshClient # 49sshClient # Virtualisation disk image created.50machine # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)51sshClient # Starting virtiofs daemons...52machine # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether53sshClient # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)54machine # [2026-10-18T02:43:35Z INFO virtiofsd] Waiting for vhost-user socket connection...55sshClient # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether56machine # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)57sshClient # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)58machine # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether59sshClient # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether60machine # [2026-10-18T02:43:35Z INFO virtiofsd] Waiting for vhost-user socket connection...61sshClient # [2026-10-18T02:43:35Z INFO virtiofsd] Waiting for vhost-user socket connection...62machine # [2026-10-18T02:43:35Z INFO virtiofsd] Client connected, servicing requests63sshClient # [2026-10-18T02:43:35Z INFO virtiofsd] Waiting for vhost-user socket connection...64machine # [2026-10-18T02:43:35Z INFO virtiofsd] Client connected, servicing requests65sshClient # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)66machine # [2026-10-18T02:43:35Z INFO virtiofsd] Client connected, servicing requests67sshClient # [2026-10-18T02:43:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68sshClient # [2026-10-18T02:43:35Z INFO virtiofsd] Waiting for vhost-user socket connection...69sshClient # [2026-10-18T02:43:35Z INFO virtiofsd] Client connected, servicing requests70sshClient # [2026-10-18T02:43:35Z INFO virtiofsd] Client connected, servicing requests71sshClient # [2026-10-18T02:43:35Z INFO virtiofsd] Client connected, servicing requests72machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]73machine # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @179172517274machine # [ 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]89sshClient # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]90machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]91machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000092machine # [ 0.000000] psci: probing for conduit method from DT.93sshClient # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @179172517294sshClient # [ 0.000000] KASLR enabled95machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.96sshClient # [ 0.000000] random: crng init done97machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs98sshClient # [ 0.000000] Machine model: linux,dummy-virt99machine # [ 0.000000] psci: Trusted OS migration not required100sshClient # [ 0.000000] efi: UEFI not found.101machine # [ 0.000000] psci: SMC Calling Convention v1.1102sshClient # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT103machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)104sshClient # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]105machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296106sshClient # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]107machine # [ 0.000000] Detected PIPT I-cache on CPU0108sshClient # [ 0.000000] Zone ranges:109sshClient # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]110machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)111sshClient # [ 0.000000] DMA32 empty112sshClient # [ 0.000000] Normal empty113machine # [ 0.000000] CPU features: detected: GICv3 CPU interface114sshClient # [ 0.000000] Device empty115machine # [ 0.000000] CPU features: detected: Spectre-v4116sshClient # [ 0.000000] Movable zone start for each node117machine # [ 0.000000] CPU features: detected: Spectre-BHB118sshClient # [ 0.000000] Early memory node ranges119machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38120sshClient # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]121machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23122sshClient # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]123machine # [ 0.000000] alternatives: applying boot alternatives124sshClient # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000125sshClient # [ 0.000000] psci: probing for conduit method from DT.126sshClient # [ 0.000000] psci: PSCIv1.3 detected in firmware.127sshClient # [ 0.000000] psci: Using standard PSCI v0.2 function IDs128sshClient # [ 0.000000] psci: Trusted OS migration not required129sshClient # [ 0.000000] psci: SMC Calling Convention v1.1130machine # [ 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/j3j1zhi3nlr9yh7n27j8jwmbnawgjk2n-nixos-system-machine-test/init regInfo=/nix/.ro-store/yd9vflyfl4arnvrrsjk8i23iy84671p1-closure-info/registration console=ttyAMA0,115200n8 console=tty0131sshClient # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)132sshClient # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296133sshClient # [ 0.000000] Detected PIPT I-cache on CPU0134machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/yd9vflyfl4arnvrrsjk8i23iy84671p1-closure-info/registration", will be passed to user space.135machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes136sshClient # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)137sshClient # [ 0.000000] CPU features: detected: GICv3 CPU interface138machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)139sshClient # [ 0.000000] CPU features: detected: Spectre-v4140sshClient # [ 0.000000] CPU features: detected: Spectre-BHB141machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)142sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38143machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB144machine # [ 0.000000] software IO TLB: area num 1.145sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23146sshClient # [ 0.000000] alternatives: applying boot alternatives147machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)148machine # [ 0.000000] Fallback order for Node 0: 0149machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144150machine # [ 0.000000] Policy zone: DMA151machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off152machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1153sshClient # [ 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/gmsqir826lmca28d8im6nynpl3zwlpxb-nixos-system-sshClient-test/init regInfo=/nix/.ro-store/i7f1x0ymxffqm3lcm81vimqw2bqb84g2-closure-info/registration console=ttyAMA0,115200n8 console=tty0154machine # [ 0.000000] allocated 2097152 bytes of page_ext155machine # [ 0.000000] ftrace: allocating 75049 entries in 294 pages156machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups157sshClient # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/i7f1x0ymxffqm3lcm81vimqw2bqb84g2-closure-info/registration", will be passed to user space.158machine # [ 0.000000] rcu: Hierarchical RCU implementation.159sshClient # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes160machine # [ 0.000000] rcu: RCU event tracing is enabled.161machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.162sshClient # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)163machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.164sshClient # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)165machine # [ 0.000000] Rude variant of Tasks RCU enabled.166machine # [ 0.000000] Tracing variant of Tasks RCU enabled.167sshClient # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB168sshClient # [ 0.000000] software IO TLB: area num 1.169machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.170sshClient # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)171machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1172sshClient # [ 0.000000] Fallback order for Node 0: 0173machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.174sshClient # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144175sshClient # [ 0.000000] Policy zone: DMA176machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.177sshClient # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off178sshClient # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1179machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.180sshClient # [ 0.000000] allocated 2097152 bytes of page_ext181machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0182sshClient # [ 0.000000] ftrace: allocating 75049 entries in 294 pages183machine # [ 0.000000] GICv3: 256 SPIs implemented184sshClient # [ 0.000000] ftrace: allocated 294 pages with 4 groups185machine # [ 0.000000] GICv3: 0 Extended SPIs implemented186sshClient # [ 0.000000] rcu: Hierarchical RCU implementation.187machine # [ 0.000000] Root IRQ handler: gic_handle_irq188sshClient # [ 0.000000] rcu: RCU event tracing is enabled.189machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI190machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0191sshClient # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.192sshClient # [ 0.000000] Trampoline variant of Tasks RCU enabled.193machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000194sshClient # [ 0.000000] Rude variant of Tasks RCU enabled.195machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]196sshClient # [ 0.000000] Tracing variant of Tasks RCU enabled.197machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)198sshClient # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.199sshClient # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1200machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)201machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000202sshClient # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.203machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000204sshClient # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.205machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.206sshClient # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.207machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns208sshClient # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0209sshClient # [ 0.000000] GICv3: 256 SPIs implemented210machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).211sshClient # [ 0.000000] GICv3: 0 Extended SPIs implemented212sshClient # [ 0.000000] Root IRQ handler: gic_handle_irq213machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns214sshClient # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI215sshClient # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0216machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns217machine # [ 0.000034] arm-pv: using stolen time PV218sshClient # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000219sshClient # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]220machine # [ 0.000496] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)221machine # [ 0.000696] Console: colour dummy device 80x25222sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)223machine # [ 0.000704] printk: legacy console [tty0] enabled224sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)225machine # [ 0.000912] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)226sshClient # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000227machine # [ 0.000920] pid_max: default: 32768 minimum: 301228sshClient # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000229machine # [ 0.001002] LSM: initializing lsm=capability,landlock,yama,bpf,ima230machine # [ 0.001195] landlock: Up and running.231sshClient # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.232machine # [ 0.001199] Yama: becoming mindful.233machine # [ 0.001722] LSM support for eBPF active234sshClient # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns235machine # [ 0.001884] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)236sshClient # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).237machine # [ 0.001906] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)238machine # [ 0.003933] rcu: Hierarchical SRCU implementation.239sshClient # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns240machine # [ 0.003939] rcu: Max phase no-delay instances is 1000.241sshClient # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns242machine # [ 0.005308] fsl-mc MSI: its@8080000 domain created243sshClient # [ 0.000031] arm-pv: using stolen time PV244machine # [ 0.005434] EFI services will not be available.245machine # [ 0.005537] smp: Bringing up secondary CPUs ...246sshClient # [ 0.000408] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)247machine # [ 0.005547] smp: Brought up 1 node, 1 CPU248sshClient # [ 0.000607] Console: colour dummy device 80x25249machine # [ 0.005551] SMP: Total of 1 processors activated.250sshClient # [ 0.000616] printk: legacy console [tty0] enabled251machine # [ 0.005553] CPU: All CPU(s) started at EL1252machine # [ 0.005563] CPU features: detected: Branch Target Identification253sshClient # [ 0.000806] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)254machine # [ 0.005567] CPU features: detected: ARMv8.4 Translation Table Level255sshClient # [ 0.000813] pid_max: default: 32768 minimum: 301256sshClient # [ 0.000889] LSM: initializing lsm=capability,landlock,yama,bpf,ima257machine # [ 0.005570] CPU features: detected: Instruction cache invalidation not required for I/D coherence258sshClient # [ 0.001064] landlock: Up and running.259sshClient # [ 0.001068] Yama: becoming mindful.260machine # [ 0.005574] CPU features: detected: Data cache clean to the PoU not required for I/D coherence261sshClient # [ 0.001519] LSM support for eBPF active262machine # [ 0.005581] CPU features: detected: Common not Private translations263sshClient # [ 0.001676] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)264machine # [ 0.005585] CPU features: detected: CRC32 instructions265sshClient # [ 0.001697] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)266machine # [ 0.005588] CPU features: detected: Data cache clean to Point of Deep Persistence267sshClient # [ 0.003805] rcu: Hierarchical SRCU implementation.268machine # [ 0.005591] CPU features: detected: Data cache clean to Point of Persistence269sshClient # [ 0.003812] rcu: Max phase no-delay instances is 1000.270sshClient # [ 0.005359] fsl-mc MSI: its@8080000 domain created271machine # [ 0.005594] CPU features: detected: Data independent timing control (DIT)272sshClient # [ 0.005472] EFI services will not be available.273machine # [ 0.005597] CPU features: detected: E0PD274sshClient # [ 0.005550] smp: Bringing up secondary CPUs ...275machine # [ 0.005600] CPU features: detected: Enhanced Counter Virtualization276sshClient # [ 0.005560] smp: Brought up 1 node, 1 CPU277sshClient # [ 0.005564] SMP: Total of 1 processors activated.278machine # [ 0.005603] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)279sshClient # [ 0.005566] CPU: All CPU(s) started at EL1280machine # [ 0.005607] CPU features: detected: Enhanced Virtualization Traps281sshClient # [ 0.005576] CPU features: detected: Branch Target Identification282machine # [ 0.005610] CPU features: detected: Fine Grained Traps283sshClient # [ 0.005580] CPU features: detected: ARMv8.4 Translation Table Level284machine # [ 0.005614] CPU features: detected: Generic authentication (architected QARMA5 algorithm)285sshClient # [ 0.005584] CPU features: detected: Instruction cache invalidation not required for I/D coherence286machine # [ 0.005619] CPU features: detected: RCpc load-acquire (LDAPR)287machine # [ 0.005622] CPU features: detected: LSE atomic instructions288sshClient # [ 0.005587] CPU features: detected: Data cache clean to the PoU not required for I/D coherence289machine # [ 0.005625] CPU features: detected: Privileged Access Never290sshClient # [ 0.005594] CPU features: detected: Common not Private translations291machine # [ 0.005628] CPU features: detected: PMUv3292sshClient # [ 0.005597] CPU features: detected: CRC32 instructions293machine # [ 0.005631] CPU features: detected: RAS Extension Support294sshClient # [ 0.005600] CPU features: detected: Data cache clean to Point of Deep Persistence295machine # [ 0.005634] CPU features: detected: RASv1p1 Extension Support296machine # [ 0.005636] CPU features: detected: Random Number Generator297sshClient # [ 0.005603] CPU features: detected: Data cache clean to Point of Persistence298machine # [ 0.005641] CPU features: detected: Speculation barrier (SB)299sshClient # [ 0.005606] CPU features: detected: Data independent timing control (DIT)300machine # [ 0.005644] CPU features: detected: Stage-2 Force Write-Back301sshClient # [ 0.005609] CPU features: detected: E0PD302machine # [ 0.005647] CPU features: detected: TLB range maintenance instructions303sshClient # [ 0.005611] CPU features: detected: Enhanced Counter Virtualization304machine # [ 0.005652] CPU features: detected: Speculative Store Bypassing Safe (SSBS)305sshClient # [ 0.005614] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)306machine # [ 0.005691] alternatives: applying system-wide alternatives307sshClient # [ 0.005618] CPU features: detected: Enhanced Virtualization Traps308machine # [ 0.008859] CPU features: detected: BBM Level 2 without TLB conflict abort309sshClient # [ 0.005621] CPU features: detected: Fine Grained Traps310sshClient # [ 0.005624] CPU features: detected: Generic authentication (architected QARMA5 algorithm)311sshClient # [ 0.005629] CPU features: detected: RCpc load-acquire (LDAPR)312sshClient # [ 0.005631] CPU features: detected: LSE atomic instructions313machine # [ 0.009060] Memory: 892900K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 114392K reserved, 32768K cma-reserved)314sshClient # [ 0.005634] CPU features: detected: Privileged Access Never315machine # [ 0.009421] devtmpfs: initialized316sshClient # [ 0.005637] CPU features: detected: PMUv3317machine # [ 0.011286] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)318sshClient # [ 0.005640] CPU features: detected: RAS Extension Support319sshClient # [ 0.005642] CPU features: detected: RASv1p1 Extension Support320machine # [ 0.011311] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).321sshClient # [ 0.005645] CPU features: detected: Random Number Generator322machine # [ 0.011503] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL323sshClient # [ 0.005650] CPU features: detected: Speculation barrier (SB)324machine # [ 0.011508] 0 pages in range for non-PLT usage325sshClient # [ 0.005653] CPU features: detected: Stage-2 Force Write-Back326machine # [ 0.011509] 508192 pages in range for PLT usage327machine # [ 0.011629] pinctrl core: initialized pinctrl subsystem328sshClient # [ 0.005656] CPU features: detected: TLB range maintenance instructions329machine # [ 0.012526] DMI not present or invalid.330sshClient # [ 0.005660] CPU features: detected: Speculative Store Bypassing Safe (SSBS)331machine # [ 0.016123] NET: Registered PF_NETLINK/PF_ROUTE protocol family332sshClient # [ 0.005697] alternatives: applying system-wide alternatives333machine # [ 0.018488] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations334sshClient # [ 0.008867] CPU features: detected: BBM Level 2 without TLB conflict abort335machine # [ 0.018631] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations336machine # [ 0.018791] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations337sshClient # [ 0.009069] Memory: 892852K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 114392K reserved, 32768K cma-reserved)338sshClient # [ 0.009440] devtmpfs: initialized339machine # [ 0.018818] audit: initializing netlink subsys (disabled)340machine # [ 0.019494] thermal_sys: Registered thermal governor 'fair_share'341sshClient # [ 0.011381] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)342machine # [ 0.019497] thermal_sys: Registered thermal governor 'bang_bang'343sshClient # [ 0.011408] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).344machine # [ 0.019501] thermal_sys: Registered thermal governor 'step_wise'345sshClient # [ 0.011601] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL346machine # [ 0.019504] thermal_sys: Registered thermal governor 'user_space'347sshClient # [ 0.011606] 0 pages in range for non-PLT usage348machine # [ 0.019507] thermal_sys: Registered thermal governor 'power_allocator'349sshClient # [ 0.011607] 508192 pages in range for PLT usage350sshClient # [ 0.011729] pinctrl core: initialized pinctrl subsystem351machine # [ 0.019535] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1352sshClient # [ 0.012557] DMI not present or invalid.353machine # [ 0.019544] cpuidle: using governor ladder354sshClient # [ 0.015920] NET: Registered PF_NETLINK/PF_ROUTE protocol family355machine # [ 0.019550] cpuidle: using governor menu356sshClient # [ 0.018332] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations357machine # [ 0.019757] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.358machine # [ 0.019776] ASID allocator initialised with 65536 entries359sshClient # [ 0.018496] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations360machine # [ 0.021162] Serial: AMBA PL011 UART driver361sshClient # [ 0.018659] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations362machine # [ 0.027303] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1363sshClient # [ 0.018685] audit: initializing netlink subsys (disabled)364machine # [ 0.027493] printk: console [ttyAMA0] enabled365sshClient # [ 0.019415] thermal_sys: Registered thermal governor 'fair_share'366sshClient # [ 0.019417] thermal_sys: Registered thermal governor 'bang_bang'367sshClient # [ 0.019421] thermal_sys: Registered thermal governor 'step_wise'368sshClient # [ 0.019424] thermal_sys: Registered thermal governor 'user_space'369sshClient # [ 0.019427] thermal_sys: Registered thermal governor 'power_allocator'370sshClient # [ 0.019458] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1371sshClient # [ 0.019467] cpuidle: using governor ladder372sshClient # [ 0.019474] cpuidle: using governor menu373sshClient # [ 0.019690] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.374sshClient # [ 0.019709] ASID allocator initialised with 65536 entries375sshClient # [ 0.021083] Serial: AMBA PL011 UART driver376sshClient # [ 0.026738] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1377sshClient # [ 0.026945] printk: console [ttyAMA0] enabled378machine # [ 0.163282] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages379machine # [ 0.163296] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page380machine # [ 0.163300] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages381machine # [ 0.163303] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page382machine # [ 0.163306] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages383machine # [ 0.163308] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page384machine # [ 0.163311] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages385machine # [ 0.163314] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page386machine # [ 0.164654] fbcon: Taking over console387machine # [ 0.164666] ACPI: Interpreter disabled.388sshClient # [ 0.159705] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages389machine # [ 0.175418] iommu: Default domain type: Translated390sshClient # [ 0.159717] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page391machine # [ 0.175426] iommu: DMA domain TLB invalidation policy: strict mode392machine # [ 0.175882] SCSI subsystem initialized393sshClient # [ 0.159721] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages394sshClient # [ 0.159723] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page395sshClient # [ 0.159726] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages396sshClient # [ 0.159729] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page397machine # [ 0.178085] usbcore: registered new interface driver usbfs398machine # [ 0.178120] usbcore: registered new interface driver hub399sshClient # [ 0.159732] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages400machine # [ 0.178135] usbcore: registered new device driver usb401sshClient # [ 0.159735] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page402machine # [ 0.178473] pps_core: LinuxPPS API ver. 1 registered403sshClient # [ 0.161018] fbcon: Taking over console404sshClient # [ 0.161028] ACPI: Interpreter disabled.405machine # [ 0.178477] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>406machine # [ 0.178486] PTP clock support registered407sshClient # [ 0.171144] iommu: Default domain type: Translated408machine # [ 0.178525] EDAC MC: Ver: 3.0.0409sshClient # [ 0.171151] iommu: DMA domain TLB invalidation policy: strict mode410sshClient # [ 0.171612] SCSI subsystem initialized411sshClient # [ 0.173788] usbcore: registered new interface driver usbfs412machine # [ 0.184924] scmi_core: SCMI protocol bus registered413sshClient # [ 0.173820] usbcore: registered new interface driver hub414machine # [ 0.185332] FPGA manager framework415machine # [ 0.188444] vgaarb: loaded416sshClient # [ 0.173835] usbcore: registered new device driver usb417sshClient # [ 0.174157] pps_core: LinuxPPS API ver. 1 registered418sshClient # [ 0.174161] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>419sshClient # [ 0.174169] PTP clock support registered420sshClient # [ 0.174208] EDAC MC: Ver: 3.0.0421machine # [ 0.190276] clocksource: Switched to clocksource arch_sys_counter422sshClient # [ 0.175665] scmi_core: SCMI protocol bus registered423sshClient # [ 0.181504] FPGA manager framework424sshClient # [ 0.183857] vgaarb: loaded425machine # [ 0.195988] VFS: Disk quotas dquot_6.6.0426machine # [ 0.196024] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)427sshClient # [ 0.185004] clocksource: Switched to clocksource arch_sys_counter428machine # [ 0.200115] netfs: FS-Cache loaded429machine # [ 0.200264] pnp: PnP ACPI: disabled430machine # [ 0.204868] NET: Registered PF_INET protocol family431machine # [ 0.205021] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)432sshClient # [ 0.193867] VFS: Disk quotas dquot_6.6.0433sshClient # [ 0.193899] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)434sshClient # [ 0.196548] netfs: FS-Cache loaded435sshClient # [ 0.196673] pnp: PnP ACPI: disabled436sshClient # [ 0.202719] NET: Registered PF_INET protocol family437sshClient # [ 0.203109] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)438machine # [ 0.237924] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)439machine # [ 0.237973] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)440machine # [ 0.237999] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)441machine # [ 0.238052] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)442machine # [ 0.238125] TCP: Hash tables configured (established 8192 bind 8192)443machine # [ 0.238223] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)444machine # [ 0.238259] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)445sshClient # [ 0.234513] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)446sshClient # [ 0.234559] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)447sshClient # [ 0.234585] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)448sshClient # [ 0.234630] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)449sshClient # [ 0.234703] TCP: Hash tables configured (established 8192 bind 8192)450sshClient # [ 0.234795] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)451machine # [ 0.245280] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)452sshClient # [ 0.234828] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)453machine # [ 0.245391] NET: Registered PF_UNIX/PF_LOCAL protocol family454machine # [ 0.245410] NET: Registered PF_XDP protocol family455sshClient # [ 0.234939] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)456machine # [ 0.245429] PCI: CLS 0 bytes, default 64457sshClient # [ 0.235023] NET: Registered PF_UNIX/PF_LOCAL protocol family458machine # [ 0.245696] Trying to unpack rootfs image as initramfs...459sshClient # [ 0.235041] NET: Registered PF_XDP protocol family460machine # [ 0.257929] kvm [1]: HYP mode not available461sshClient # [ 0.235057] PCI: CLS 0 bytes, default 64462sshClient # [ 0.235325] Trying to unpack rootfs image as initramfs...463sshClient # [ 0.253037] kvm [1]: HYP mode not available464sshClient # [ 0.370595] Initialise system trusted keyrings465sshClient # [ 0.370746] workingset: timestamp_bits=42 max_order=18 bucket_order=0466sshClient # [ 0.371143] squashfs: version 4.0 (2009/01/31) Phillip Lougher467sshClient # [ 0.375503] 9p: Installing v9fs 9p2000 file system support468machine # [ 0.387233] Initialise system trusted keyrings469machine # [ 0.387396] workingset: timestamp_bits=42 max_order=18 bucket_order=0470machine # [ 0.387798] squashfs: version 4.0 (2009/01/31) Phillip Lougher471machine # [ 0.387853] 9p: Installing v9fs 9p2000 file system support472sshClient # [ 0.396462] Key type asymmetric registered473sshClient # [ 0.396478] Asymmetric key parser 'x509' registered474machine # [ 0.416098] Key type asymmetric registered475sshClient # [ 0.396562] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)476machine # [ 0.416116] Asymmetric key parser 'x509' registered477machine # [ 0.416199] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)478machine # [ 0.416345] io scheduler mq-deadline registered479machine # [ 0.416351] io scheduler kyber registered480sshClient # [ 0.407309] io scheduler mq-deadline registered481sshClient # [ 0.407320] io scheduler kyber registered482machine # [ 0.429164] pl061_gpio 9030000.pl061: PL061 GPIO chip registered483sshClient # [ 0.417183] pl061_gpio 9030000.pl061: PL061 GPIO chip registered484sshClient # [ 0.417895] ledtrig-cpu: registered to indicate activity on CPUs485machine # [ 0.431256] ledtrig-cpu: registered to indicate activity on CPUs486sshClient # [ 0.418344] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:487machine # [ 0.431737] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:488sshClient # [ 0.418361] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000489machine # [ 0.431753] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000490sshClient # [ 0.418386] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000491machine # [ 0.431763] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000492sshClient # [ 0.418393] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000493machine # [ 0.431769] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000494sshClient # [ 0.418412] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits495machine # [ 0.431792] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits496sshClient # [ 0.418440] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]497machine # [ 0.431818] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]498sshClient # [ 0.418530] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00499machine # [ 0.431922] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00500sshClient # [ 0.418538] pci_bus 0000:00: root bus resource [bus 00-ff]501machine # [ 0.431930] pci_bus 0000:00: root bus resource [bus 00-ff]502sshClient # [ 0.418542] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]503machine # [ 0.431933] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]504sshClient # [ 0.418545] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]505machine # [ 0.431937] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]506sshClient # [ 0.418548] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]507machine # [ 0.431940] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]508sshClient # [ 0.418641] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint509machine # [ 0.432000] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint510sshClient # [ 0.419235] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint511machine # [ 0.432494] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint512sshClient # [ 0.419435] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]513machine # [ 0.432695] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]514sshClient # [ 0.419449] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]515machine # [ 0.432707] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]516sshClient # [ 0.419476] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]517machine # [ 0.432735] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]518sshClient # [ 0.419488] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]519machine # [ 0.432748] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]520sshClient # [ 0.419994] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint521machine # [ 0.433259] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint522sshClient # [ 0.420190] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]523machine # [ 0.433452] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]524sshClient # [ 0.420202] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]525machine # [ 0.433467] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]526sshClient # [ 0.420231] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]527machine # [ 0.433494] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]528sshClient # [ 0.420725] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint529machine # [ 0.434001] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint530sshClient # [ 0.420930] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]531machine # [ 0.434201] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]532sshClient # [ 0.420942] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]533machine # [ 0.434212] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]534sshClient # [ 0.420968] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]535machine # [ 0.434240] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]536sshClient # [ 0.453600] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint537sshClient # [ 0.453829] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]538machine # [ 0.466910] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint539sshClient # [ 0.453842] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]540machine # [ 0.467149] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]541sshClient # [ 0.453869] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]542machine # [ 0.467162] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]543sshClient # [ 0.453886] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]544machine # [ 0.467190] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]545sshClient # [ 0.454405] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint546machine # [ 0.467202] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]547sshClient # [ 0.454602] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]548machine # [ 0.467719] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint549sshClient # [ 0.454632] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]550machine # [ 0.467916] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]551sshClient # [ 0.455164] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint552machine # [ 0.467943] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]553sshClient # [ 0.455363] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]554machine # [ 0.468440] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint555sshClient # [ 0.455390] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]556machine # [ 0.468636] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]557sshClient # [ 0.455854] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint558machine # [ 0.468664] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]559sshClient # [ 0.456047] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]560machine # [ 0.469141] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint561sshClient # [ 0.456319] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint562machine # [ 0.469345] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]563sshClient # [ 0.456515] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]564machine # [ 0.469618] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint565sshClient # [ 0.456542] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]566machine # [ 0.469819] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]567machine # [ 0.469847] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]568sshClient # [ 0.471849] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint569sshClient # [ 0.472062] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]570machine # [ 0.485383] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint571sshClient # [ 0.472094] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]572machine # [ 0.485591] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]573sshClient # [ 0.472639] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint574machine # [ 0.485619] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]575sshClient # [ 0.472860] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]576machine # [ 0.486144] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint577sshClient # [ 0.472893] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]578sshClient # [ 0.485539] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint579sshClient # [ 0.485847] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]580machine # [ 0.498411] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]581sshClient # [ 0.485861] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]582machine # [ 0.498449] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]583sshClient # [ 0.485888] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]584machine # [ 0.499041] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint585sshClient # [ 0.486405] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint586machine # [ 0.499351] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]587sshClient # [ 0.486601] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]588machine # [ 0.499365] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]589sshClient # [ 0.486613] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]590machine # [ 0.499392] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]591sshClient # [ 0.486641] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]592machine # [ 0.499898] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint593sshClient # [ 0.487308] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned594machine # [ 0.500093] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]595sshClient # [ 0.487315] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned596machine # [ 0.500105] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]597sshClient # [ 0.487318] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned598machine # [ 0.500132] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]599machine # [ 0.500817] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned600sshClient # [ 0.487366] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned601machine # [ 0.500824] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned602sshClient # [ 0.487412] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned603machine # [ 0.500827] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned604sshClient # [ 0.487460] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned605machine # [ 0.500876] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned606sshClient # [ 0.487506] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned607machine # [ 0.500924] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned608sshClient # [ 0.487553] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned609machine # [ 0.500971] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned610sshClient # [ 0.487598] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned611machine # [ 0.501021] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned612sshClient # [ 0.487645] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned613sshClient # [ 0.487693] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned614sshClient # [ 0.487738] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned615sshClient # [ 0.487834] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned616machine # [ 0.501068] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned617sshClient # [ 0.487879] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned618sshClient # [ 0.487898] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned619machine # [ 0.501116] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned620sshClient # [ 0.487915] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned621machine # [ 0.501163] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned622sshClient # [ 0.487933] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned623machine # [ 0.501213] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned624sshClient # [ 0.487952] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned625machine # [ 0.501259] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned626sshClient # [ 0.487971] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned627machine # [ 0.501361] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned628sshClient # [ 0.487990] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned629machine # [ 0.501405] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned630sshClient # [ 0.488010] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned631machine # [ 0.501423] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned632sshClient # [ 0.488029] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned633machine # [ 0.501442] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned634sshClient # [ 0.488047] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned635sshClient # [ 0.488066] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned636machine # [ 0.501460] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned637sshClient # [ 0.488086] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned638machine # [ 0.501478] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned639sshClient # [ 0.488104] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned640machine # [ 0.501496] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned641sshClient # [ 0.488121] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned642machine # [ 0.501515] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned643sshClient # [ 0.488139] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned644machine # [ 0.501535] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned645sshClient # [ 0.488158] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned646machine # [ 0.501553] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned647sshClient # [ 0.488176] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned648machine # [ 0.501572] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned649sshClient # [ 0.488194] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned650machine # [ 0.501591] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned651sshClient # [ 0.488218] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]652sshClient # [ 0.488224] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]653machine # [ 0.501609] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned654sshClient # [ 0.488227] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]655machine # [ 0.501627] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned656machine # [ 0.501645] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned657sshClient # [ 0.528484] pci 0000:00:07.0: enabling device (0000 -> 0002)658machine # [ 0.501663] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned659machine # [ 0.501681] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned660machine # [ 0.501699] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned661machine # [ 0.501717] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned662machine # [ 0.501741] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]663machine # [ 0.501746] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]664machine # [ 0.501749] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]665machine # [ 0.519571] pci 0000:00:07.0: enabling device (0000 -> 0002)666machine # [ 0.569006] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)667sshClient # [ 0.557400] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)668sshClient # [ 0.559662] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)669machine # [ 0.579084] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)670machine # [ 0.581900] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)671sshClient # [ 0.569703] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)672sshClient # [ 0.572147] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)673machine # [ 0.591872] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)674sshClient # [ 0.582529] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)675sshClient # [ 0.584708] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)676sshClient # [ 0.588470] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)677machine # [ 0.603042] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)678machine # [ 0.605324] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)679machine # [ 0.609157] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)680sshClient # [ 0.598605] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)681sshClient # [ 0.600606] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)682sshClient # [ 0.604382] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)683machine # [ 0.619528] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)684machine # [ 0.621668] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)685machine # [ 0.625547] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)686sshClient # [ 0.614725] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)687machine # [ 0.635984] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)688sshClient # [ 0.626473] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled689sshClient # [ 0.628458] msm_serial: driver initialized690sshClient # [ 0.628593] SuperH (H)SCI(F) driver initialized691sshClient # [ 0.628651] STM32 USART driver initialized692machine # [ 0.647914] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled693machine # [ 0.650067] msm_serial: driver initialized694machine # [ 0.650234] SuperH (H)SCI(F) driver initialized695machine # [ 0.652372] STM32 USART driver initialized696sshClient # [ 0.670811] loop: module loaded697sshClient # [ 0.671115] virtio_blk virtio2: 1/0/0 default/read/poll queues698sshClient # [ 0.672013] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)699machine # [ 0.689590] loop: module loaded700machine # [ 0.689804] virtio_blk virtio2: 1/0/0 default/read/poll queues701sshClient # [ 0.677718] megasas: 07.734.00.00-rc1702sshClient # [ 0.678496] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]703machine # [ 0.698883] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)704sshClient # [ 0.694319] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000705sshClient # [ 0.694345] Intel/Sharp Extended Query Table at 0x0031706sshClient # [ 0.696243] Using buffer write method707machine # [ 0.704340] megasas: 07.734.00.00-rc1708sshClient # [ 0.696288] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]709machine # [ 0.705151] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]710sshClient # [ 0.701811] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000711sshClient # [ 0.701831] Intel/Sharp Extended Query Table at 0x0031712sshClient # [ 0.703508] Using buffer write method713sshClient # [ 0.703526] Concatenating MTD devices:714sshClient # [ 0.703529] (0): "0.flash"715sshClient # [ 0.703531] (1): "0.flash"716sshClient # [ 0.703533] into device "0.flash"717machine # [ 0.719622] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000718machine # [ 0.719653] Intel/Sharp Extended Query Table at 0x0031719machine # [ 0.721561] Using buffer write method720machine # [ 0.721620] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]721machine # [ 0.735810] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000722machine # [ 0.735842] Intel/Sharp Extended Query Table at 0x0031723machine # [ 0.737608] Using buffer write method724machine # [ 0.737634] Concatenating MTD devices:725machine # [ 0.737637] (0): "0.flash"726machine # [ 0.737639] (1): "0.flash"727machine # [ 0.737641] into device "0.flash"728machine # [ 1.044544] Freeing initrd memory: 27148K729sshClient # [ 1.033787] Freeing initrd memory: 27148K730sshClient # [ 1.039870] tun: Universal TUN/TAP device driver, 1.6731machine # [ 1.051461] tun: Universal TUN/TAP device driver, 1.6732sshClient # [ 1.044627] thunder_xcv, ver 1.0733sshClient # [ 1.044656] thunder_bgx, ver 1.0734sshClient # [ 1.044675] nicpf, ver 1.0735machine # [ 1.055790] thunder_xcv, ver 1.0736machine # [ 1.055822] thunder_bgx, ver 1.0737machine # [ 1.055842] nicpf, ver 1.0738machine # [ 1.056450] e1000: Intel(R) PRO/1000 Network Driver739machine # [ 1.056455] e1000: Copyright (c) 1999-2006 Intel Corporation.740sshClient # [ 1.046658] e1000: Intel(R) PRO/1000 Network Driver741machine # [ 1.056483] e1000e: Intel(R) PRO/1000 Network Driver742sshClient # [ 1.046663] e1000: Copyright (c) 1999-2006 Intel Corporation.743machine # [ 1.056491] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.744sshClient # [ 1.046691] e1000e: Intel(R) PRO/1000 Network Driver745machine # [ 1.056516] igb: Intel(R) Gigabit Ethernet Network Driver746sshClient # [ 1.046701] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.747machine # [ 1.056520] igb: Copyright (c) 2007-2014 Intel Corporation.748sshClient # [ 1.046725] igb: Intel(R) Gigabit Ethernet Network Driver749machine # [ 1.056541] igbvf: Intel(R) Gigabit Virtual Function Network Driver750sshClient # [ 1.046728] igb: Copyright (c) 2007-2014 Intel Corporation.751machine # [ 1.056545] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.752machine # [ 1.056691] sky2: driver version 1.30753sshClient # [ 1.046748] igbvf: Intel(R) Gigabit Virtual Function Network Driver754machine # [ 1.059198] ehci-pci 0000:00:07.0: EHCI Host Controller755sshClient # [ 1.046752] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.756sshClient # [ 1.047009] sky2: driver version 1.30757machine # [ 1.059231] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1758machine # [ 1.059474] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000759sshClient # [ 1.048738] usbcore: registered new interface driver usb-storage760sshClient # [ 1.048861] usbcore: registered new interface driver usbserial_generic761sshClient # [ 1.048874] usbserial: USB Serial support registered for generic762machine # [ 1.070080] usbcore: registered new interface driver usb-storage763sshClient # [ 1.049698] ehci-pci 0000:00:07.0: EHCI Host Controller764machine # [ 1.070245] usbcore: registered new interface driver usbserial_generic765sshClient # [ 1.049722] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1766machine # [ 1.070258] usbserial: USB Serial support registered for generic767sshClient # [ 1.049905] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000768machine # [ 1.070322] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00769machine # [ 1.070659] hub 1-0:1.0: USB hub found770machine # [ 1.070683] hub 1-0:1.0: 6 ports detected771sshClient # [ 1.061936] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00772sshClient # [ 1.062378] hub 1-0:1.0: USB hub found773machine # [ 1.076165] hv_vmbus: registering driver hyperv_keyboard774sshClient # [ 1.062402] hub 1-0:1.0: 6 ports detected775machine # [ 1.077177] rtc-pl031 9010000.pl031: registered as rtc0776sshClient # [ 1.063559] hv_vmbus: registering driver hyperv_keyboard777sshClient # [ 1.064525] rtc-pl031 9010000.pl031: registered as rtc0778machine # [ 1.077201] rtc-pl031 9010000.pl031: setting system clock to 2026-10-18T02:43:36 UTC (1792291416)779machine # [ 1.077529] i2c_dev: i2c /dev entries driver780sshClient # [ 1.064555] rtc-pl031 9010000.pl031: setting system clock to 2026-10-18T02:43:36 UTC (1792291416)781sshClient # [ 1.064915] i2c_dev: i2c /dev entries driver782machine # [ 1.084362] sdhci: Secure Digital Host Controller Interface driver783machine # [ 1.084370] sdhci: Copyright(c) Pierre Ossman784machine # [ 1.084671] Synopsys Designware Multimedia Card Interface Driver785machine # [ 1.085082] sdhci-pltfm: SDHCI platform and OF driver helper786sshClient # [ 1.073762] sdhci: Secure Digital Host Controller Interface driver787sshClient # [ 1.073772] sdhci: Copyright(c) Pierre Ossman788machine # [ 1.089844] hid: raw HID events driver (C) Jiri Kosina789sshClient # [ 1.074082] Synopsys Designware Multimedia Card Interface Driver790machine # [ 1.090119] usbcore: registered new interface driver usbhid791sshClient # [ 1.074484] sdhci-pltfm: SDHCI platform and OF driver helper792machine # [ 1.090123] usbhid: USB HID core driver793sshClient # [ 1.076236] hid: raw HID events driver (C) Jiri Kosina794sshClient # [ 1.076521] usbcore: registered new interface driver usbhid795sshClient # [ 1.076526] usbhid: USB HID core driver796machine # [ 1.093513] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available797sshClient # [ 1.083074] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available798machine # [ 1.096652] drop_monitor: Initializing network drop monitor service799sshClient # [ 1.084797] drop_monitor: Initializing network drop monitor service800machine # [ 1.096851] NET: Registered PF_INET6 protocol family801sshClient # [ 1.084959] NET: Registered PF_INET6 protocol family802machine # [ 1.097431] Segment Routing with IPv6803machine # [ 1.097444] In-situ OAM (IOAM) with IPv6804machine # [ 1.097472] NET: Registered PF_PACKET protocol family805sshClient # [ 1.088810] Segment Routing with IPv6806machine # [ 1.097528] 9pnet: Installing 9P2000 support807sshClient # [ 1.088852] In-situ OAM (IOAM) with IPv6808machine # [ 1.097572] Key type dns_resolver registered809sshClient # [ 1.088906] NET: Registered PF_PACKET protocol family810sshClient # [ 1.088982] 9pnet: Installing 9P2000 support811sshClient # [ 1.091412] Key type dns_resolver registered812machine # [ 1.108399] registered taskstats version 1813machine # [ 1.108585] Loading compiled-in X.509 certificates814sshClient # [ 1.098837] registered taskstats version 1815sshClient # [ 1.099037] Loading compiled-in X.509 certificates816machine # [ 1.117851] Demotion targets for Node 0: null817machine # [ 1.117988] Key type .fscrypt registered818machine # [ 1.117992] Key type fscrypt-provisioning registered819machine # [ 1.118102] ima: No TPM chip found, activating TPM-bypass!820machine # [ 1.118121] ima: Allocated hash algorithm: sha1821sshClient # [ 1.108296] Demotion targets for Node 0: null822machine # [ 1.118146] ima: No architecture policies found823sshClient # [ 1.108436] Key type .fscrypt registered824sshClient # [ 1.108441] Key type fscrypt-provisioning registered825sshClient # [ 1.108550] ima: No TPM chip found, activating TPM-bypass!826sshClient # [ 1.108569] ima: Allocated hash algorithm: sha1827sshClient # [ 1.108594] ima: No architecture policies found828machine # [ 1.122748] input: gpio-keys as /devices/platform/gpio-keys/input/input0829sshClient # [ 1.113395] input: gpio-keys as /devices/platform/gpio-keys/input/input0830machine # [ 1.142513] clk: Disabling unused clocks831machine # [ 1.142531] PM: genpd: Disabling unused power domains832machine # [ 1.145540] Freeing unused kernel memory: 4736K833machine # [ 1.145768] Run /init as init process834sshClient # [ 1.133313] clk: Disabling unused clocks835sshClient # [ 1.133336] PM: genpd: Disabling unused power domains836sshClient # [ 1.136549] Freeing unused kernel memory: 4736K837sshClient # [ 1.136770] Run /init as init process838machine # [ 1.168063] fuse: init (API version 7.45)839sshClient # [ 1.158952] fuse: init (API version 7.45)840machine # [ 1.171760] virtiofs virtio6: discovered new tag: nix-store841machine # [ 1.172580] virtiofs virtio6: virtio_fs_setup_dax: No cache capability842sshClient # [ 1.162788] virtiofs virtio6: discovered new tag: nix-store843sshClient # [ 1.163688] virtiofs virtio6: virtio_fs_setup_dax: No cache capability844machine # [ 1.188127] virtiofs virtio7: discovered new tag: shared845machine # [ 1.188873] virtiofs virtio7: virtio_fs_setup_dax: No cache capability846machine # [ 1.190232] virtiofs virtio8: discovered new tag: xchg847sshClient # [ 1.178754] virtiofs virtio7: discovered new tag: shared848sshClient # [ 1.179827] virtiofs virtio7: virtio_fs_setup_dax: No cache capability849machine # [ 1.193335] virtiofs virtio8: virtio_fs_setup_dax: No cache capability850sshClient # [ 1.182925] virtiofs virtio8: discovered new tag: xchg851sshClient # [ 1.183719] virtiofs virtio8: virtio_fs_setup_dax: No cache capability852machine # [ 1.205168] systemd[1]: Successfully made /usr/ read-only.853sshClient # [ 1.196131] systemd[1]: Successfully made /usr/ read-only.854machine # [ 1.205681] systemd[1]: systemd 263-409be20 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)855machine # [ 1.205696] systemd[1]: Detected virtualization qemu.856machine # [ 1.205718] systemd[1]: Detected architecture arm64.857machine # [ 1.205724] systemd[1]: Running in initrd.858machine # [ 1.206016] systemd[1]: Initializing machine ID from random generator.859sshClient # [ 1.196684] systemd[1]: systemd 263-409be20 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)860machine # [ 1.206124] systemd[1]: Hostname set to <machine>.861sshClient # [ 1.196701] systemd[1]: Detected virtualization qemu.862sshClient # [ 1.196724] systemd[1]: Detected architecture arm64.863sshClient # [ 1.196730] systemd[1]: Running in initrd.864sshClient # [ 1.204431] systemd[1]: Initializing machine ID from random generator.865sshClient # [ 1.204581] systemd[1]: Hostname set to <sshClient>.866machine # [ 1.305755] systemd[1]: bpf-restrict-fs: LSM BPF program attached867sshClient # [ 1.298370] systemd[1]: bpf-restrict-fs: LSM BPF program attached868sshClient # [ 1.309111] usb 1-1: new high-speed USB device number 2 using ehci-pci869machine # [ 1.322501] usb 1-1: new high-speed USB device number 2 using ehci-pci870machine # [ 1.363131] systemd[1]: Queued start job for default target Initrd Default Target.871machine # [ 1.369604] systemd[1]: Created slice Slice /system/modprobe.872machine # [ 1.369798] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.873machine # [ 1.369826] systemd[1]: Expecting device /dev/disk/by-label/nixos...874machine # [ 1.369848] systemd[1]: Reached target Path Units.875machine # [ 1.369860] systemd[1]: Reached target Slice Units.876machine # [ 1.369874] systemd[1]: Reached target Swaps.877sshClient # [ 1.359038] systemd[1]: Queued start job for default target Initrd Default Target.878machine # [ 1.369887] systemd[1]: Reached target Timer Units.879machine # [ 1.370019] systemd[1]: Listening on D-Bus System Message Bus Socket.880machine # [ 1.370185] systemd[1]: Listening on Journal Socket (/dev/log).881sshClient # [ 1.364312] systemd[1]: Created slice Slice /system/modprobe.882machine # [ 1.377225] systemd[1]: Listening on Journal Sockets.883machine # [ 1.377337] systemd[1]: Listening on udev Kernel Socket.884machine # [ 1.377352] systemd[1]: Reached target Socket Units.885sshClient # [ 1.364500] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.886sshClient # [ 1.364529] systemd[1]: Expecting device /dev/disk/by-label/nixos...887sshClient # [ 1.364552] systemd[1]: Reached target Path Units.888sshClient # [ 1.364566] systemd[1]: Reached target Slice Units.889sshClient # [ 1.364580] systemd[1]: Reached target Swaps.890sshClient # [ 1.364594] systemd[1]: Reached target Timer Units.891sshClient # [ 1.364730] systemd[1]: Listening on D-Bus System Message Bus Socket.892machine # [ 1.380929] systemd[1]: Starting Create List of Static Device Nodes...893sshClient # [ 1.364878] systemd[1]: Listening on Journal Socket (/dev/log).894machine # [ 1.380988] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs895sshClient # [ 1.371845] systemd[1]: Listening on Journal Sockets.896sshClient # [ 1.371953] systemd[1]: Listening on udev Kernel Socket.897sshClient # [ 1.371967] systemd[1]: Reached target Socket Units.898sshClient # [ 1.375891] systemd[1]: Starting Create List of Static Device Nodes...899sshClient # [ 1.375953] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs900machine # [ 1.390541] systemd[1]: Mounting Kernel Configuration File System...901sshClient # [ 1.385221] systemd[1]: Mounting Kernel Configuration File System...902sshClient # [ 1.394044] systemd[1]: Starting Journal Service...903sshClient # [ 1.397938] systemd[1]: Starting Load Kernel Modules...904sshClient # [ 1.398063] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os905machine # [ 1.414525] systemd[1]: Starting Journal Service...906machine # [ 1.426496] systemd[1]: Starting Load Kernel Modules...907machine # [ 1.426611] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os908machine # [ 1.430780] systemd[1]: Starting Coldplug All udev Devices...909sshClient # [ 1.429708] systemd[1]: Starting Coldplug All udev Devices...910machine # [ 1.448403] systemd[1]: Finished Create List of Static Device Nodes.911machine # [ 1.449239] systemd[1]: Mounted Kernel Configuration File System.912sshClient # [ 1.449054] systemd-journald[71]: Collecting audit messages is disabled.913sshClient # [ 1.454587] systemd[1]: Finished Create List of Static Device Nodes.914machine # [ 1.467416] systemd-journald[72]: Collecting audit messages is disabled.915machine # [ 1.470931] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...916sshClient # [ 1.469545] 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/input1917machine # [ 1.488301] 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/input1918sshClient # [ 1.495561] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.919sshClient # [ 1.496362] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...920sshClient # [ 1.497088] systemd[1]: Mounted Kernel Configuration File System.921machine # [ 1.532337] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.922machine # [ 1.533291] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.923sshClient # [ 1.517416] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev924sshClient # [ 1.526524] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0925sshClient # [ 1.526773] [drm] features: -virgl +edid -resource_blob -host_visible926sshClient # [ 1.526776] [drm] features: -context_init927sshClient # [ 1.527758] [drm] number of scanouts: 1928sshClient # [ 1.527771] [drm] number of cap sets: 0929machine # [ 1.546412] systemd[1]: Starting Create Static Device Nodes in /dev...930machine # [ 1.551489] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev931sshClient # [ 1.549254] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0932machine # [ 1.564097] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0933machine # [ 1.564342] [drm] features: -virgl +edid -resource_blob -host_visible934machine # [ 1.564345] [drm] features: -context_init935machine # [ 1.565164] [drm] number of scanouts: 1936machine # [ 1.565176] [drm] number of cap sets: 0937sshClient # [ 1.555530] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.938sshClient # [ 1.558490] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic939sshClient # [ 1.558502] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0940sshClient # [ 1.563433] systemd[1]: Starting Create Static Device Nodes in /dev...941machine # [ 1.578513] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0942machine # [ 1.590715] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic943machine # [ 1.590734] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0944sshClient # [ 1.585806] Console: switching to colour frame buffer device 160x50945machine # [ 1.618630] Console: switching to colour frame buffer device 160x50946sshClient # [ 1.609735] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device947machine # [ 1.627125] systemd[1]: Finished Create Static Device Nodes in /dev.948machine # [ 1.627583] systemd[1]: Reached target Preparation for Local File Systems.949machine # [ 1.627604] systemd[1]: Reached target Local File Systems.950sshClient # [ 1.621674] systemd[1]: Finished Load Kernel Modules.951sshClient # [ 1.626177] systemd[1]: Starting Apply Kernel Variables...952machine # [ 1.642963] systemd[1]: Starting Rule-based Manager for Device Events and Files...953machine # [ 1.647116] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device954sshClient # [ 1.645316] systemd[1]: Finished Create Static Device Nodes in /dev.955sshClient # [ 1.645586] systemd[1]: Reached target Preparation for Local File Systems.956sshClient # [ 1.645620] systemd[1]: Reached target Local File Systems.957machine # [ 1.645509] systemd-modules-load[73]: Inserted module 'dm_mod'958machine # [ 1.662683] systemd[1]: Started Journal Service.959machine # [ 1.648872] systemd-modules-load[73]: Module 'virtio_balloon' is built in960sshClient # [ 1.643233] systemd-modules-load[72]: Inserted module 'dm_mod'961machine # [ 1.658886] systemd-modules-load[73]: Module 'virtio_console' is built in962machine # [ 1.664461] systemd-modules-load[73]: Inserted module 'virtio_gpu'963sshClient # [ 1.651445] systemd-modules-load[72]: Module 'virtio_balloon' is built in[ 1.665534] systemd[1]: Starting Rule-based Manager for Device Events and Files...964sshClient # [ 1.665896] systemd[1]: Started Journal Service.965sshClient # [ 1.669536] usb 1-2: new high-speed USB device number 3 using ehci-pci966machine # [ 1.670052] systemd-modules-load[73]: Module 'virtio_rng' is built in967machine # [ 1.672328] systemd[1]: Finished Load Kernel Modules.968sshClient # 969sshClient # [ 1.663940] systemd-modules-load[72]: Module 'virtio_console' is built in970sshClient # [ 1.668493] systemd-modules-load[72]: Inserted module 'virtio_gpu'971sshClient # [ 1.669797] systemd-modules-load[72]: Module 'virtio_rng' is built in972machine # [ 1.698349] usb 1-2: new high-speed USB device number 3 using ehci-pci973machine # [ 1.685341] systemd[1]: Starting Apply Kernel Variables...974machine # [ 1.689333] systemd[1]: Starting Create System Files and Directories...975sshClient # [ 1.681237] systemd[1]: Starting Create System Files and Directories...976sshClient # [ 1.685275] systemd[1]: Finished Apply Kernel Variables.977machine # [ 1.740969] systemd[1]: Finished Create System Files and Directories.978sshClient # [ 1.730146] systemd[1]: Finished Create System Files and Directories.979machine # [ 1.744967] systemd-udevd[79]: Using default interface naming scheme 'v261'.980machine # [ 1.752318] systemd[1]: Finished Apply Kernel Variables.981sshClient # [ 1.757540] systemd-udevd[80]: Using default interface naming scheme 'v261'.982machine # [ 1.778351] systemd[1]: Started Rule-based Manager for Device Events and Files.983sshClient # [ 1.786755] systemd[1]: Started Rule-based Manager for Device Events and Files.984sshClient # [ 1.829873] 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/input2985sshClient # [ 1.830155] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0986machine # [ 1.862031] 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/input2987machine # [ 1.865256] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0988machine # [ 1.888142] systemd[1]: Starting Virtual Console Setup...989sshClient # [ 1.902204] systemd[1]: Starting Virtual Console Setup...990machine # [ 1.964981] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.991machine # [ 1.972420] systemd[1]: Finished Virtual Console Setup.992sshClient # [ 1.988911] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.993sshClient # [ 1.992638] systemd[1]: Finished Virtual Console Setup.994machine # [ 2.672396] (udev-worker)[91]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.995machine # [ 2.700931] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.996sshClient # [ 2.703974] (udev-worker)[99]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.997sshClient # [ 2.706484] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.998machine # [ 2.720912] systemd[1]: Finished Coldplug All udev Devices.999machine # [ 2.722683] systemd[1]: Reached target System Initialization.1000machine # [ 2.725233] systemd[1]: Reached target Basic System.1001sshClient # [ 2.718586] systemd[1]: Finished Coldplug All udev Devices.1002sshClient # [ 2.720486] systemd[1]: Reached target System Initialization.1003sshClient # [ 2.722815] systemd[1]: Reached target Basic System.1004machine # [ 2.747374] systemd[1]: Found device /dev/disk/by-label/nixos.1005machine # [ 2.748564] systemd[1]: Reached target Initrd Root Device.1006sshClient # [ 2.738893] systemd[1]: Found device /dev/disk/by-label/nixos.1007machine # [ 2.749914] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1008sshClient # [ 2.742430] systemd[1]: Reached target Initrd Root Device.1009sshClient # [ 2.743876] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1010machine # [ 2.804616] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks1011sshClient # [ 2.796995] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks1012machine # [ 2.812218] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1013sshClient # [ 2.804198] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1014machine # [ 2.817248] systemd[1]: Mounting /sysroot...1015sshClient # [ 2.806925] systemd[1]: Mounting /sysroot...1016machine # [ 2.871171] EXT4-fs (vda): mounted filesystem 9b2166a4-964f-466b-9519-db572dfb4570 r/w with ordered data mode. Quota mode: none.1017machine # [ 2.858714] systemd[1]: Mounted /sysroot.1018machine # [ 2.861295] systemd[1]: Reached target Initrd Root File System.1019machine # [ 2.868094] systemd[1]: Starting Mountpoints Configured in the Real Root...1020sshClient # [ 2.874739] EXT4-fs (vda): mounted filesystem e6a711f9-5e8e-4d29-bd17-5e01004f03d0 r/w with ordered data mode. Quota mode: none.1021sshClient # [ 2.865098] systemd[1]: Mounted /sysroot.1022sshClient # [ 2.869793] systemd[1]: Reached target Initrd Root File System.1023sshClient # [ 2.876134] systemd[1]: Starting Mountpoints Configured in the Real Root...1024machine # [ 2.901457] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.1025machine # [ 2.907959] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...1026machine # [ 2.912658] systemd[1]: Reloading...1027sshClient # [ 2.912749] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.1028sshClient # [ 2.916571] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...1029sshClient # [ 2.924134] systemd[1]: Reloading...1030machine # [ 3.120639] systemd[1]: Reloading finished in 210 ms.1031sshClient # [ 3.129486] systemd[1]: Reloading finished in 210 ms.1032machine # [ 3.153827] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...1033machine # [ 3.158679] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...1034machine # [ 3.168411] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1035machine # [ 3.171201] systemd[1]: Finished Mountpoints Configured in the Real Root.1036machine # [ 3.173718] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1037sshClient # [ 3.163854] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...1038sshClient # [ 3.168761] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...1039sshClient # [ 3.173451] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1040sshClient # [ 3.177665] systemd[1]: Finished Mountpoints Configured in the Real Root.1041sshClient # [ 3.180171] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1042machine # [ 3.419655] systemd[1]: Mounting /sysroot/nix/.ro-store...1043machine # [ 3.431020] systemd[1]: Mounting /sysroot/nix/.rw-store...1044sshClient # [ 3.430129] systemd[1]: Mounting /sysroot/nix/.ro-store...1045machine # [ 3.451233] systemd[1]: Mounting /sysroot/run...1046sshClient # [ 3.442448] systemd[1]: Mounting /sysroot/nix/.rw-store...1047machine # [ 3.457517] systemd[1]: Mounting /sysroot/tmp/shared...1048sshClient # [ 3.454209] systemd[1]: Mounting /sysroot/run...1049sshClient # [ 3.467210] systemd[1]: Mounting /sysroot/tmp/shared...1050machine # [ 3.490576] systemd[1]: Mounting /sysroot/tmp/xchg...1051sshClient # [ 3.488633] systemd[1]: Mounting /sysroot/tmp/xchg...1052machine # [ 3.527025] systemd[1]: Mounted /sysroot/nix/.rw-store.1053machine # [ 3.542283] systemd[1]: Mounted /sysroot/nix/.ro-store.1054machine # [ 3.559697] systemd[1]: Mounted /sysroot/run.1055sshClient # [ 3.549654] systemd[1]: Mounted /sysroot/nix/.ro-store.1056sshClient # [ 3.563438] systemd[1]: Mounted /sysroot/nix/.rw-store.1057machine # [ 3.576286] systemd[1]: Starting rw-sysroot-nix-store.service...1058sshClient # [ 3.573602] systemd[1]: Mounted /sysroot/run.1059machine # [ 3.593362] systemd[1]: Mounted /sysroot/tmp/shared.1060machine # [ 3.597688] systemd[1]: Mounted /sysroot/tmp/xchg.1061sshClient # [ 3.591458] systemd[1]: Mounted /sysroot/tmp/xchg.1062sshClient # [ 3.595981] systemd[1]: Mounted /sysroot/tmp/shared.1063sshClient # [ 3.610327] systemd[1]: Starting rw-sysroot-nix-store.service...1064machine # [ 3.625169] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1065machine # [ 3.626810] systemd[1]: Finished rw-sysroot-nix-store.service.1066sshClient # [ 3.637442] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1067sshClient # [ 3.639291] systemd[1]: Finished rw-sysroot-nix-store.service.1068machine # [ 4.057202] (udev-worker)[94]: mtd0ro: Failed to find and pin callout binary "/nix/store/126w02q3bp3kh7554k53zak86km11gnz-systemd-263-409be20/lib/udev/mtd_probe": No such file or directory1069machine # [ 4.063563] (udev-worker)[94]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1070sshClient # [ 4.062741] (udev-worker)[98]: mtd0ro: Failed to find and pin callout binary "/nix/store/126w02q3bp3kh7554k53zak86km11gnz-systemd-263-409be20/lib/udev/mtd_probe": No such file or directory1071sshClient # [ 4.070418] (udev-worker)[98]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1072machine # [ 4.091514] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1073machine # [ 4.093424] systemd[1]: Stopped Virtual Console Setup.1074machine # [ 4.096412] systemd[1]: Stopping Virtual Console Setup...1075machine # [ 4.097466] systemd[1]: Starting Virtual Console Setup...1076sshClient # [ 4.102163] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1077machine # [ 4.115581] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1078machine # [ 4.117494] systemd[1]: Stopped Virtual Console Setup.1079sshClient # [ 4.108202] systemd[1]: Stopped Virtual Console Setup.1080sshClient # [ 4.109990] systemd[1]: Stopping Virtual Console Setup...1081machine # [ 4.121380] systemd[1]: Starting Virtual Console Setup...1082sshClient # [ 4.111179] systemd[1]: Starting Virtual Console Setup...1083machine # [ 4.153032] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones.1084machine # [ 4.157060] systemd[1]: Finished Virtual Console Setup.1085sshClient # [ 4.149257] systemd-vconsole-setup[151]: Configuration of first virtual console was skipped, ignoring remaining ones.1086sshClient # [ 4.154016] systemd[1]: Finished Virtual Console Setup.1087machine # [ 4.418145] systemd[1]: Mounting /sysroot/nix/store...1088sshClient # [ 4.430201] systemd[1]: Mounting /sysroot/nix/store...1089machine # [ 4.464892] systemd[1]: Mounted /sysroot/nix/store.1090machine # [ 4.467374] systemd[1]: Reached target Initrd File Systems.1091machine # [ 4.472201] systemd[1]: Starting Find NixOS closure...1092machine # [ 4.478055] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1093sshClient # [ 4.481288] systemd[1]: Mounted /sysroot/nix/store.1094sshClient # [ 4.483994] systemd[1]: Reached target Initrd File Systems.1095sshClient # [ 4.488795] systemd[1]: Starting Find NixOS closure...1096sshClient # [ 4.500540] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1097machine # [ 4.526747] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1098machine # [ 4.530997] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1099machine # [ 4.545272] systemd[1]: Finished Find NixOS closure.1100machine # [ 4.548303] systemd[1]: Reached target Initrd Default Target.1101machine # [ 4.551396] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1102sshClient # [ 4.543094] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1103sshClient # [ 4.547125] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1104sshClient # [ 4.561239] systemd[1]: Finished Find NixOS closure.1105sshClient # [ 4.564145] systemd[1]: Reached target Initrd Default Target.1106sshClient # [ 4.565716] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1107machine # [ 4.581100] systemd[1]: Stopped target Initrd Default Target.1108machine # [ 4.584258] systemd[1]: Stopped target Basic System.1109machine # [ 4.585447] systemd[1]: Stopped target Initrd Root Device.1110machine # [ 4.588383] systemd[1]: Stopped target Path Units.1111machine # [ 4.590657] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1112machine # [ 4.592425] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1113machine # [ 4.599857] systemd[1]: Stopped target Slice Units.1114sshClient # [ 4.591612] systemd[1]: Stopped target Initrd Default Target.1115sshClient # [ 4.593468] systemd[1]: Stopped target Basic System.1116machine # [ 4.605408] systemd[1]: Stopped target Socket Units.1117machine # [ 4.606249] systemd[1]: Stopped target System Initialization.1118machine # [ 4.607153] systemd[1]: Stopped target Swaps.1119sshClient # [ 4.596466] systemd[1]: Stopped target Initrd Root Device.1120machine # [ 4.607876] systemd[1]: Stopped target Timer Units.1121sshClient # [ 4.597502] systemd[1]: Stopped target Path Units.1122sshClient # [ 4.599151] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1123machine # [ 4.612973] systemd[1]: dbus.socket: Deactivated successfully.1124sshClient # [ 4.602492] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1125sshClient # [ 4.604919] systemd[1]: Stopped target Slice Units.1126machine # [ 4.617797] systemd[1]: Closed D-Bus System Message Bus Socket.1127sshClient # [ 4.608195] systemd[1]: Stopped target Socket Units.1128sshClient # [ 4.609187] systemd[1]: Stopped target System Initialization.1129machine # [ 4.619060] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1130machine # [ 4.621801] systemd[1]: Stopped Find NixOS closure.1131sshClient # [ 4.611138] systemd[1]: Stopped target Swaps.1132machine # [ 4.622626] systemd[1]: Starting rw-sysroot-nix-store.service...1133machine # [ 4.623530] systemd[1]: systemd-sysctl.service: Deactivated successfully.1134sshClient # [ 4.613196] systemd[1]: Stopped target Timer Units.1135sshClient # [ 4.621258] systemd[1]: dbus.socket: Deactivated successfully.1136machine # [ 4.633412] systemd[1]: Stopped Apply Kernel Variables.1137machine # [ 4.634387] systemd[1]: systemd-modules-load.service: Deactivated successfully.1138machine # [ 4.635647] systemd[1]: Stopped Load Kernel Modules.1139sshClient # [ 4.623844] systemd[1]: Closed D-Bus System Message Bus Socket.1140sshClient # [ 4.629986] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1141sshClient # [ 4.631157] systemd[1]: Stopped Find NixOS closure.1142machine # [ 4.642226] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1143sshClient # [ 4.631923] systemd[1]: Starting rw-sysroot-nix-store.service...1144machine # [ 4.646239] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1145machine # [ 4.648188] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1146sshClient # [ 4.637994] systemd[1]: systemd-sysctl.service: Deactivated successfully.1147sshClient # [ 4.640268] systemd[1]: Stopped Apply Kernel Variables.1148machine # [ 4.652263] systemd[1]: Stopped Create System Files and Directories.1149sshClient # [ 4.641455] systemd[1]: systemd-modules-load.service: Deactivated successfully.1150machine # [ 4.653366] systemd[1]: Stopped target Local File Systems.1151sshClient # [ 4.642618] systemd[1]: Stopped Load Kernel Modules.1152sshClient # [ 4.643406] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1153machine # [ 4.655434] systemd[1]: Stopped target Preparation for Local File Systems.1154machine # [ 4.658591] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1155machine # [ 4.659665] systemd[1]: Stopped Coldplug All udev Devices.1156machine # [ 4.661808] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1157sshClient # [ 4.651051] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1158machine # [ 4.663815] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1159machine # [ 4.669589] systemd[1]: Stopped Virtual Console Setup.1160sshClient # [ 4.659093] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1161sshClient # [ 4.660833] systemd[1]: Stopped Create System Files and Directories.1162machine # [ 4.672218] systemd[1]: systemd-udevd.service: Deactivated successfully.1163sshClient # [ 4.661797] systemd[1]: Stopped target Local File Systems.1164sshClient # [ 4.662738] systemd[1]: Stopped target Preparation for Local File Systems.1165machine # [ 4.674442] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1166sshClient # [ 4.663725] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1167sshClient # [ 4.669220] systemd[1]: Stopped Coldplug All udev Devices.1168machine # [ 4.680291] systemd[1]: systemd-udevd.service: Consumed 1.367s CPU time over 2.996s wall clock time, 24.7M memory peak.1169machine # [ 4.682025] systemd[1]: Starting Cleanup udev Database...1170machine # [ 4.682867] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1171sshClient # [ 4.672204] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1172sshClient # [ 4.674197] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1173sshClient # [ 4.676298] systemd[1]: Stopped Virtual Console Setup.1174machine # [ 4.688153] systemd[1]: Stopped Create Static Device Nodes in /dev.1175machine # [ 4.689271] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1176sshClient # [ 4.679011] systemd[1]: initrd-cleanup.service: Deactivated successfully.1177machine # [ 4.690475] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1178sshClient # [ 4.680466] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1179machine # [ 4.691557] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1180sshClient # [ 4.681485] systemd[1]: systemd-udevd.service: Deactivated successfully.1181sshClient # [ 4.682534] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1182sshClient # [ 4.683620] systemd[1]: systemd-udevd.service: Consumed 1.394s CPU time over 3.000s wall clock time, 43.1M memory peak.1183sshClient # [ 4.685396] systemd[1]: Starting Cleanup udev Database...1184machine # [ 4.696497] systemd[1]: Stopped Create List of Static Device Nodes.1185sshClient # [ 4.686354] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1186machine # [ 4.697565] systemd[1]: initrd-cleanup.service: Deactivated successfully.1187sshClient # [ 4.687491] systemd[1]: Stopped Create Static Device Nodes in /dev.1188machine # [ 4.698570] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1189sshClient # [ 4.692302] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1190machine # [ 4.704107] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1191machine # [ 4.705358] systemd[1]: Finished rw-sysroot-nix-store.service.1192sshClient # [ 4.695077] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1193sshClient # [ 4.700562] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1194sshClient # [ 4.702028] systemd[1]: Stopped Create List of Static Device Nodes.1195sshClient # [ 4.703021] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1196sshClient # [ 4.704224] systemd[1]: Finished rw-sysroot-nix-store.service.1197machine # [ 4.728965] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1198machine # [ 4.730846] systemd[1]: Finished Cleanup udev Database.1199machine # [ 4.734961] systemd[1]: Reached target Switch Root.1200machine # [ 4.740356] systemd[1]: Starting NixOS Activation...1201sshClient # [ 4.741403] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1202sshClient # [ 4.743310] systemd[1]: Finished Cleanup udev Database.1203sshClient # [ 4.746230] systemd[1]: Reached target Switch Root.1204sshClient # [ 4.747485] systemd[1]: Starting NixOS Activation...1205machine # [ 4.829989] initrd-nixos-activation-start[175]: booting system configuration /nix/store/j3j1zhi3nlr9yh7n27j8jwmbnawgjk2n-nixos-system-machine-test1206sshClient # [ 4.841680] initrd-nixos-activation-start[174]: booting system configuration /nix/store/gmsqir826lmca28d8im6nynpl3zwlpxb-nixos-system-sshClient-test1207machine # [ 4.871081] initrd-nixos-activation-start[175]: running activation script...1208sshClient # [ 4.883558] initrd-nixos-activation-start[174]: running activation script...1209machine # [ 5.115485] initrd-nixos-activation-start[198]: setting up /etc...1210sshClient # [ 5.147854] initrd-nixos-activation-start[197]: setting up /etc...1211machine # [ 5.250556] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1212machine # [ 5.253869] systemd[1]: Finished NixOS Activation.1213machine # [ 5.255190] systemd[1]: Starting Switch Root...1214machine # [ 5.282021] systemd[1]: Switching root.1215sshClient # [ 5.293092] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1216sshClient # [ 5.296239] systemd[1]: Finished NixOS Activation.1217sshClient # [ 5.298301] systemd[1]: Starting Switch Root...1218sshClient # [ 5.326152] systemd[1]: Switching root.1219machine # [ 5.464113] systemd-journald[72]: Received SIGTERM from PID 1 (systemd).1220sshClient # [ 5.511747] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1221machine # [ 5.564334] NET: Registered PF_VSOCK protocol family1222sshClient # [ 5.613225] NET: Registered PF_VSOCK protocol family1223machine # [ 5.645992] systemd[1]: systemd 263-409be20 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)1224machine # [ 5.653869] systemd[1]: Detected virtualization qemu.1225machine # [ 5.653932] systemd[1]: Detected architecture arm64.1226machine # [ 5.654035] systemd[1]: Detected first boot.1227machine # [ 5.659163] systemd[1]: Initializing machine ID from random generator.1228sshClient # [ 5.698913] systemd[1]: systemd 263-409be20 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)1229sshClient # [ 5.699868] systemd[1]: Detected virtualization qemu.1230sshClient # [ 5.699929] systemd[1]: Detected architecture arm64.1231sshClient # [ 5.700020] systemd[1]: Detected first boot.1232sshClient # [ 5.711350] systemd[1]: Initializing machine ID from random generator.1233machine # [ 5.779228] systemd[1]: bpf-restrict-fs: LSM BPF program attached1234sshClient # [ 5.848681] systemd[1]: bpf-restrict-fs: LSM BPF program attached1235machine # [ 5.931279] systemd[1]: Applying preset policy.1236sshClient # [ 6.011500] systemd[1]: Applying preset policy.1237machine # [ 6.160353] systemd[1]: Populated /etc with preset unit settings.1238sshClient # [ 6.240468] systemd[1]: Populated /etc with preset unit settings.1239machine # [ 6.390151] systemd[1]: initrd-switch-root.service: Deactivated successfully.1240machine # [ 6.390640] systemd[1]: Stopped initrd-switch-root.service.1241machine # [ 6.392717] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1242machine # [ 6.398726] systemd[1]: Created slice Slice /system/getty.1243machine # [ 6.400235] systemd[1]: Created slice User and Session Slice.1244machine # [ 6.400856] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1245machine # [ 6.401620] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1246machine # [ 6.406589] systemd[1]: Expecting device /dev/hvc0...1247machine # [ 6.407080] systemd[1]: Expecting device /dev/ttyAMA0...1248machine # [ 6.407905] systemd[1]: Reached target Local Encrypted Volumes.1249machine # [ 6.408612] systemd[1]: Stopped target initrd-fs.target.1250machine # [ 6.409288] systemd[1]: Stopped target initrd-root-fs.target.1251machine # [ 6.409976] systemd[1]: Stopped target initrd-switch-root.target.1252machine # [ 6.415862] systemd[1]: Reached target Virtual Machines and Containers.1253machine # [ 6.416372] systemd[1]: Reached target Path Units.1254machine # [ 6.417176] systemd[1]: Reached target Remote File Systems.1255machine # [ 6.417908] systemd[1]: Reached target Slice Units.1256machine # [ 6.422186] systemd[1]: Reached target Swaps.1257machine # [ 6.424681] systemd[1]: Listening on Query the User Interactively for a Password.1258machine # [ 6.427842] systemd[1]: Listening on Process Core Dump Socket.1259machine # [ 6.429282] systemd[1]: Listening on Credential Encryption/Decryption.1260machine # [ 6.432604] systemd[1]: Listening on Factory Reset Management.1261machine # [ 6.433198] systemd[1]: Listening on Hostname Service Socket.1262machine # [ 6.439368] systemd[1]: Starting Journal Log Access Socket...1263machine # [ 6.440427] systemd[1]: Listening on Journal Audit Socket.1264machine # [ 6.444609] systemd[1]: Listening on Console Output Muting Service Socket.1265machine # [ 6.445555] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1266machine # [ 6.446072] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1267machine # [ 6.450109] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1268machine # [ 6.458556] systemd[1]: Listening on Disk Repartitioning Service Socket.1269machine # [ 6.459417] systemd[1]: Listening on udev Varlink Socket.1270machine # [ 6.460276] systemd[1]: Listening on User Database Manager Socket.1271machine # [ 6.465808] systemd[1]: Mounting Huge Pages File System...1272sshClient # [ 6.460774] systemd[1]: initrd-switch-root.service: Deactivated successfully.1273sshClient # [ 6.464055] systemd[1]: Stopped initrd-switch-root.service.1274machine # [ 6.475748] systemd[1]: Mounting POSIX Message Queue File System...1275sshClient # [ 6.468009] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1276sshClient # [ 6.472246] systemd[1]: Created slice Slice /system/getty.1277sshClient # [ 6.475250] systemd[1]: Created slice User and Session Slice.1278machine # [ 6.486725] systemd[1]: Mounting Kernel Debug File System...1279sshClient # [ 6.475888] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1280sshClient # [ 6.476876] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1281sshClient # [ 6.481786] systemd[1]: Expecting device /dev/hvc0...1282sshClient # [ 6.482553] systemd[1]: Expecting device /dev/ttyAMA0...1283sshClient # [ 6.483326] systemd[1]: Reached target Local Encrypted Volumes.1284sshClient # [ 6.483930] systemd[1]: Stopped target initrd-fs.target.1285sshClient # [ 6.484748] systemd[1]: Stopped target initrd-root-fs.target.1286sshClient # [ 6.490719] systemd[1]: Stopped target initrd-switch-root.target.1287machine # [ 6.504285] systemd[1]: Mounting Kernel Trace File System...1288sshClient # [ 6.491329] systemd[1]: Reached target Virtual Machines and Containers.1289sshClient # [ 6.492146] systemd[1]: Reached target Path Units.1290sshClient # [ 6.496292] systemd[1]: Reached target Remote File Systems.1291sshClient # [ 6.496931] systemd[1]: Reached target Slice Units.1292sshClient # [ 6.499894] systemd[1]: Reached target Swaps.1293sshClient # [ 6.502681] systemd[1]: Listening on Query the User Interactively for a Password.1294sshClient # [ 6.506268] systemd[1]: Listening on Process Core Dump Socket.1295sshClient # [ 6.507856] systemd[1]: Listening on Credential Encryption/Decryption.1296machine # [ 6.518385] systemd[1]: Starting Create List of Static Device Nodes...1297machine # [ 6.520772] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1298sshClient # [ 6.511281] systemd[1]: Listening on Factory Reset Management.1299sshClient # [ 6.511949] systemd[1]: Listening on Hostname Service Socket.1300sshClient # [ 6.517710] systemd[1]: Starting Journal Log Access Socket...1301sshClient # [ 6.519597] systemd[1]: Listening on Journal Audit Socket.1302sshClient # [ 6.524006] systemd[1]: Listening on Console Output Muting Service Socket.1303sshClient # [ 6.527005] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1304sshClient # [ 6.528147] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1305sshClient # [ 6.532325] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1306machine # [ 6.546538] systemd[1]: Mounting Kernel Configuration File System...1307sshClient # [ 6.538393] systemd[1]: Listening on Disk Repartitioning Service Socket.1308sshClient # [ 6.539292] systemd[1]: Listening on udev Varlink Socket.1309sshClient # [ 6.540121] systemd[1]: Listening on User Database Manager Socket.1310machine # [ 6.555089] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1311machine # [ 6.555681] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1312sshClient # [ 6.546468] systemd[1]: Mounting Huge Pages File System...1313machine # [ 6.557332] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1314sshClient # [ 6.551619] systemd[1]: Mounting POSIX Message Queue File System...1315sshClient # [ 6.569245] systemd[1]: Mounting Kernel Debug File System...1316machine # [ 6.588615] systemd[1]: Mounting FUSE Control File System...1317machine # [ 6.589075] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671318sshClient # [ 6.585777] systemd[1]: Mounting Kernel Trace File System...1319sshClient # [ 6.603839] systemd[1]: Starting Create List of Static Device Nodes...1320sshClient # [ 6.609334] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1321machine # [ 6.624547] systemd[1]: Starting Journal Service...1322machine # [ 6.640513] systemd[1]: Starting Load Kernel Modules...1323sshClient # [ 6.639713] systemd[1]: Mounting Kernel Configuration File System...1324sshClient # [ 6.640439] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1325sshClient # [ 6.648087] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1326machine # [ 6.663296] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1327sshClient # [ 6.653335] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1328machine # [ 6.686500] systemd[1]: Starting Remount Root and Kernel File Systems...1329machine # [ 6.686936] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1330sshClient # [ 6.680275] systemd[1]: Mounting FUSE Control File System...1331sshClient # [ 6.680777] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671332machine # [ 6.709947] systemd[1]: Starting Coldplug All udev Devices...1333machine # [ 6.719337] systemd-journald[269]: Collecting audit messages is enabled.1334sshClient # [ 6.720050] systemd[1]: Starting Journal Service...1335machine # [ 6.717103] systemd[1]: Queued start job for default target Multi-User System.1336machine # [ 6.735847] systemd[1]: Started Journal Service.1337sshClient # [ 6.737409] systemd[1]: Starting Load Kernel Modules...1338sshClient # [ 6.769465] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1339sshClient # [ 6.781244] systemd[1]: Starting Remount Root and Kernel File Systems...1340sshClient # [ 6.781684] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1341sshClient # [ 6.809491] systemd-journald[268]: Collecting audit messages is enabled.1342sshClient # [ 6.817163] systemd[1]: Starting Coldplug All udev Devices...1343sshClient # [ 6.820126] systemd[1]: Listening on Journal Log Access Socket.1344sshClient # [ 6.820596] systemd[1]: Mounted Huge Pages File System.1345sshClient # [ 6.809157] systemd[1]: Queued start job for default target Multi-User System.1346sshClient # [ 6.812133] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0.1347machine # [ 6.838345] EXT4-fs (vda): re-mounted 9b2166a4-964f-466b-9519-db572dfb4570.1348sshClient # [ 6.814210] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.1349sshClient # [ 6.815485] systemd[1]: systemd-journald.service: Deactivated successfully.1350sshClient # [ 6.825977] systemd-modules-load[269]: Module 'atkbd' is built in1351sshClient # [ 6.827188] systemd-modules-load[269]: Module 'loop' is built in1352sshClient # [ 6.846264] systemd[1]: Mounted POSIX Message Queue File System.1353sshClient # [ 6.848425] systemd[1]: Started Journal Service.1354sshClient # [ 6.846804] systemd[1]: Mounted Kernel Debug File System.1355sshClient # [ 6.859872] systemd[1]: Mounted Kernel Trace File System.1356sshClient # [ 6.863774] systemd[1]: Finished Create List of Static Device Nodes.1357sshClient # [ 6.868694] systemd[1]: Finished Load Kernel Modules.1358sshClient # [ 6.929747] EXT4-fs (vda): re-mounted e6a711f9-5e8e-4d29-bd17-5e01004f03d0.1359sshClient # [ 6.924313] systemd[1]: Mounted Kernel Configuration File System.1360sshClient # [ 6.925425] systemd[1]: Mounted FUSE Control File System.1361sshClient # [ 6.947886] systemd[1]: Starting Firewall...1362sshClient # [ 6.950161] systemd[1]: Starting Apply Kernel Variables...1363sshClient # [ 6.962708] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1364sshClient # [ 6.976207] systemd[1]: Finished Remount Root and Kernel File Systems.1365sshClient # [ 6.977472] systemd[1]: Listening on Disk Image Download Service Socket.1366sshClient # [ 7.024556] systemd-oomd[271]: No swap; memory pressure usage will be degraded1367sshClient # [ 7.036488] systemd[1]: Starting Flush Journal to Persistent Storage...1368sshClient # [ 7.037714] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1369sshClient # [ 7.054239] systemd[1]: Starting Load/Save OS Random Seed...1370sshClient # [ 7.055351] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1371sshClient # [ 7.073093] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1372sshClient # [ 7.149771] systemd[1]: Finished Apply Kernel Variables.1373sshClient # [ 7.188015] systemd-journald[268]: Received client request to flush runtime journal.1374sshClient # [ 7.248571] systemd[1]: Finished Load/Save OS Random Seed.1375sshClient # [ 7.257071] systemd[1]: Finished Flush Journal to Persistent Storage.1376sshClient # [ 7.273101] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1377sshClient # [ 7.281695] systemd[1]: Starting Create Static Device Nodes in /dev...1378machine # [ 6.721968] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0.1379machine # [ 7.364510] systemd-journald[269]: Received client request to flush runtime journal.1380machine # [ 7.413443] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.1381machine # [ 7.414926] systemd[1]: systemd-journald.service: Deactivated successfully.1382sshClient # [ 7.405156] systemd[1]: Finished Create Static Device Nodes in /dev.1383sshClient # [ 7.410330] systemd[1]: Reached target Preparation for Local File Systems.1384sshClient # [ 7.418697] systemd[1]: Starting Rule-based Manager for Device Events and Files...1385machine # [ 7.430443] systemd-modules-load[270]: Module 'atkbd' is built in1386machine # [ 7.431657] systemd[1]: Listening on Journal Log Access Socket.1387machine # [ 7.440174] systemd[1]: Mounted Huge Pages File System.1388machine # [ 7.441306] systemd[1]: Mounted POSIX Message Queue File System.1389machine # [ 7.442346] systemd[1]: Mounted Kernel Debug File System.1390machine # [ 7.443303] systemd[1]: Mounted Kernel Trace File System.1391machine # [ 7.457172] systemd-modules-load[270]: Module 'loop' is built in1392machine # [ 7.458295] systemd[1]: Finished Create List of Static Device Nodes.1393machine # [ 7.459360] systemd[1]: Finished Load Kernel Modules.1394sshClient # [ 7.457654] systemd[1]: Mounting /run/wrappers...1395machine # [ 7.476364] systemd[1]: Starting Firewall...1396machine # [ 7.482942] systemd[1]: Starting Apply Kernel Variables...1397machine # [ 7.490388] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1398machine # [ 7.491753] systemd[1]: Mounted Kernel Configuration File System.1399machine # [ 7.500855] systemd-oomd[271]: No swap; memory pressure usage will be degraded1400machine # [ 7.502482] systemd[1]: Finished Remount Root and Kernel File Systems.1401machine # [ 7.503570] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1402machine # [ 7.517762] systemd[1]: Mounted FUSE Control File System.1403machine # [ 7.518639] systemd[1]: Listening on Disk Image Download Service Socket.1404machine # [ 7.519618] systemd[1]: Starting Flush Journal to Persistent Storage...1405machine # [ 7.532365] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1406machine # [ 7.534406] systemd[1]: Starting Load/Save OS Random Seed...1407machine # [ 7.535263] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1408machine # [ 7.545401] systemd[1]: Finished Apply Kernel Variables.1409machine # [ 7.546279] systemd[1]: Finished Load/Save OS Random Seed.1410machine # [ 7.547102] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1411sshClient # [ 7.538151] systemd[1]: Mounted /run/wrappers.1412sshClient # [ 7.539039] systemd[1]: Reached target Local File Systems.1413machine # [ 7.552538] systemd[1]: Starting Create Static Device Nodes in /dev...1414machine # [ 7.553607] systemd[1]: Finished Create Static Device Nodes in /dev.1415sshClient # [ 7.548223] systemd[1]: Listening on Boot Loader Control Service Socket.1416machine # [ 7.559809] systemd[1]: Reached target Preparation for Local File Systems.1417sshClient # [ 7.552278] systemd[1]: Starting Create SUID/SGID Wrappers...1418sshClient # [ 7.553319] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1419machine # [ 7.570639] systemd[1]: Starting Rule-based Manager for Device Events and Files...1420machine # [ 7.571900] systemd[1]: Mounting /run/wrappers...1421sshClient # [ 7.568314] systemd[1]: Starting Create System Files and Directories...1422machine # [ 7.580370] systemd[1]: Finished Flush Journal to Persistent Storage.1423machine # [ 7.581846] systemd-udevd[295]: Using default interface naming scheme 'v261'.1424machine # [ 7.582913] systemd[1]: Starting User Database Manager...1425machine # [ 7.583760] systemd[1]: Mounted /run/wrappers.1426sshClient # [ 7.587539] systemd-udevd[309]: Using default interface naming scheme 'v261'.1427machine # [ 7.599051] systemd[1]: Reached target Local File Systems.1428machine # [ 7.599976] systemd[1]: Listening on Boot Loader Control Service Socket.1429machine # [ 7.610924] systemd[1]: Starting Create SUID/SGID Wrappers...1430machine # [ 7.611876] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1431machine # [ 7.624319] systemd[1]: Starting Create System Files and Directories...1432sshClient # [ 7.630432] systemd[1]: Starting User Database Manager...1433machine # [ 7.727129] systemd[1]: Started User Database Manager.1434sshClient # [ 7.763279] systemd[1]: Finished Create System Files and Directories.1435sshClient # [ 7.771945] systemd[1]: Starting Rebuild Journal Catalog...1436sshClient # [ 7.786326] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1437machine # [ 7.810633] systemd[1]: Finished Create System Files and Directories.1438machine # [ 7.826317] systemd[1]: Starting Rebuild Journal Catalog...1439sshClient # [ 7.825022] systemd[1]: Started User Database Manager.1440machine # [ 7.841145] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1441sshClient # [ 7.952109] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1442machine # [ 7.988874] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1443sshClient # [ 7.980718] systemd[1]: Finished Rebuild Journal Catalog.1444sshClient # [ 7.990732] systemd[1]: Starting Update is Completed...1445machine # [ 8.032372] systemd[1]: Started Rule-based Manager for Device Events and Files.1446machine # [ 8.039302] systemd[1]: Finished Rebuild Journal Catalog.1447machine # [ 8.066807] systemd[1]: Starting Update is Completed...1448sshClient # [ 8.066556] systemd[1]: Started Rule-based Manager for Device Events and Files.1449sshClient # [ 8.096593] systemd[1]: Finished Update is Completed.1450machine # [ 8.159003] systemd[1]: Finished Update is Completed.1451machine # [ 8.287028] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1452machine # [ 8.354911] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1453sshClient # [ 8.444579] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1454sshClient # [ 8.514283] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1455sshClient # [ 8.648161] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1456sshClient # [ 8.652274] systemd[1]: Finished Create SUID/SGID Wrappers.1457machine # [ 8.712413] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1458machine # [ 8.772710] systemd[1]: Finished Coldplug All udev Devices.1459machine # [ 8.812272] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1460sshClient # [ 8.814721] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1461machine # [ 8.827837] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1462machine # [ 8.831202] systemd[1]: Finished Create SUID/SGID Wrappers.1463machine # [ 8.835274] systemd[1]: Reached target System Initialization.1464machine # [ 8.838518] systemd[1]: Started Discard unused filesystem blocks once a week.1465machine # [ 8.839612] systemd[1]: Started Daily Cleanup of Temporary Directories.1466machine # [ 8.847457] systemd[1]: Reached target Timer Units.1467machine # [ 8.849479] systemd[1]: Listening on D-Bus System Message Bus Socket.1468machine # [ 8.850468] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).1469sshClient # [ 8.840285] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1470machine # [ 8.851776] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1471sshClient # [ 8.846193] systemd[1]: Finished Coldplug All udev Devices.1472sshClient # [ 8.847196] systemd[1]: Reached target System Initialization.1473machine # [ 8.861105] systemd[1]: Reached target Socket Units.1474machine # [ 8.861951] systemd[1]: Reached target Basic System.1475machine # [ 8.862727] systemd[1]: Started backdoor.service.1476sshClient # [ 8.852538] systemd[1]: Started Discard unused filesystem blocks once a week.1477sshClient # [ 8.853681] systemd[1]: Started Daily Cleanup of Temporary Directories.1478sshClient # [ 8.854725] systemd[1]: Reached target Timer Units.1479sshClient # [ 8.855487] systemd[1]: Listening on D-Bus System Message Bus Socket.1480machine # [ 8.871514] systemd[1]: Starting Import lastlog data into lastlog2 database...1481machine # [ 8.875277] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1482sshClient # [ 8.865664] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1483sshClient # [ 8.867000] systemd[1]: Reached target Socket Units.1484sshClient # [ 8.867842] systemd[1]: Reached target Basic System.1485machine # [ 8.883414] systemd[1]: Starting Post-Boot Actions...1486sshClient # [ 8.878116] systemd[1]: Started backdoor.service.1487sshClient # [ 8.878913] systemd[1]: Starting Import lastlog data into lastlog2 database...1488sshClient # [ 8.895354] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1489machine # [ 8.919419] systemd[1]: Started Reset console on configuration changes.1490sshClient # [ 8.938162] systemd[1]: Starting Post-Boot Actions...1491machine # [ 8.958427] systemd[1]: Starting resolvconf update...1492sshClient # [ 8.969581] systemd[1]: Started Reset console on configuration changes.1493machine # [ 8.989245] (udev-worker)[375]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1494machine # [ 8.991635] (udev-worker)[375]: Network interface NamePolicy= disabled on kernel command line.1495machine # [ 9.020430] systemd[1]: Starting SSH Host Keys Generation...1496sshClient # [ 9.024192] systemd[1]: Starting resolvconf update...1497machine # [ 9.044346] systemd[1]: Starting Home Area Manager...1498sshClient # [ 9.047317] systemd[1]: Starting Home Area Manager...1499sshClient # connecting to host...1500sshClient # [ 9.104888] nsncd[447]: Oct 18 02:43:44.553 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1501machine # connecting to host...1502sshClient # [ 9.122730] systemd[1]: Started Name Service Cache Daemon (nsncd).1503sshClient # [ 9.162300] systemd[1]: Finished Post-Boot Actions.1504machine # [ 9.171901] nsncd[452]: Oct 18 02:43:44.612 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1505machine: Guest shell says: b'Spawning backdoor root shell...\n'1506sshClient # [ 9.180547] (udev-worker)[377]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1507machine # [ 9.193120] systemd[1]: Started Name Service Cache Daemon (nsncd).1508machine # [ 9.194066] systemd[1]: Finished Post-Boot Actions.1509sshClient # [ 9.200141] (udev-worker)[377]: Network interface NamePolicy= disabled on kernel command line.1510sshClient # [ 9.209820] systemd[1]: Reached target Host and Network Name Lookups.1511machine: connected to guest root shell1512sshClient # [ 9.220171] systemd[1]: Reached target User and Group Name Lookups.1513machine: (connecting took 9.55 seconds)1514machine: (finished: waiting for the VM to finish booting, in 9.55 seconds)1515sshClient # [ 9.236611] systemd[1]: Starting D-Bus System Message Bus...1516machine # [ 9.249368] systemd[1]: Reached target Host and Network Name Lookups.1517machine # [ 9.250412] systemd[1]: Reached target User and Group Name Lookups.1518machine # [ 9.281551] systemd[1]: Starting D-Bus System Message Bus...1519sshClient # [ 9.276161] systemd[1]: Starting User Login Management...1520sshClient # [ 9.312755] systemd[1]: Finished Import lastlog data into lastlog2 database.1521machine # [ 9.324234] systemd[1]: Starting User Login Management...1522sshClient # [ 9.373721] systemd-homed[458]: Watching /home.1523machine # [ 9.407911] systemd-homed[461]: Watching /home.1524machine # [ 9.425207] systemd[1]: Finished Import lastlog data into lastlog2 database.1525sshClient # [ 9.498782] dbus-broker-launch[485]: Looking up NSS user entry for 'systemd-timesync'...1526sshClient # [ 9.511604] dbus-broker-launch[485]: NSS returned no entry for 'systemd-timesync'1527sshClient # [ 9.515435] dbus-broker-launch[485]: Invalid user-name in /nix/store/93gyp144815js4024wabbd432qckxf4s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1528sshClient # [ 9.531755] systemd[1]: Stopped target Host and Network Name Lookups.1529sshClient # [ 9.538824] systemd[1]: Stopping Host and Network Name Lookups...1530sshClient # [ 9.539843] systemd[1]: Stopped target User and Group Name Lookups.1531sshClient # [ 9.551064] systemd[1]: Stopping User and Group Name Lookups...1532sshClient # [ 9.556279] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1533sshClient # [ 9.557282] systemd[1]: nscd.service: Deactivated successfully.1534sshClient # [ 9.558276] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1535sshClient # [ 9.571163] systemd-logind[491]: New seat seat0.1536sshClient # [ 9.582138] systemd[1]: Started D-Bus System Message Bus.1537sshClient # [ 9.583093] systemd[1]: Started User Login Management.1538machine # [ 9.604880] dbus-broker-launch[495]: Looking up NSS user entry for 'systemd-timesync'...1539sshClient # [ 9.600499] systemd[1]: Starting linger-users.service...1540sshClient # [ 9.614457] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1541machine # [ 9.611641] dbus-broker-launch[495]: NSS returned no entry for 'systemd-timesync'1542machine # [ 9.627806] dbus-broker-launch[495]: Invalid user-name in /nix/store/mnbsyjaj69qm8xxffv4wsrlv74mp8g36-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1543machine # [ 9.639372] systemd[1]: Stopped target Host and Network Name Lookups.1544machine # [ 9.648773] systemd[1]: Stopping Host and Network Name Lookups...1545machine # [ 9.649770] systemd[1]: Stopped target User and Group Name Lookups.1546machine # [ 9.650666] systemd[1]: Stopping User and Group Name Lookups...1547machine # [ 9.651523] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1548sshClient # [ 9.641415] dbus-broker-launch[485]: Ready1549machine # [ 9.669696] systemd[1]: nscd.service: Deactivated successfully.1550machine # [ 9.670645] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1551sshClient # [ 9.660434] systemd[1]: Started Home Area Manager.1552sshClient # [ 9.666393] systemd[1]: Finished Home Area Activation.1553machine # [ 9.697283] systemd[1]: Started D-Bus System Message Bus.1554sshClient # [ 9.714577] systemd[1]: Starting First Boot Home Area Wizard...1555machine # [ 9.728526] systemd-logind[508]: New seat seat0.1556machine # [ 9.741468] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1557machine # [ 9.742581] systemd[1]: Started User Login Management.1558machine # [ 9.760624] systemd[1]: Starting linger-users.service...1559sshClient # [ 9.750411] systemd[1]: linger-users.service: Deactivated successfully.1560machine # [ 9.762605] dbus-broker-launch[495]: Ready1561sshClient # [ 9.761826] systemd[1]: Finished linger-users.service.1562machine # [ 9.776525] systemd[1]: Started Home Area Manager.1563sshClient # [ 9.798837] systemd[1]: Finished resolvconf update.1564machine # [ 9.820934] systemd[1]: Finished Home Area Activation.1565sshClient # [ 9.810461] nsncd[553]: Oct 18 02:43:45.256 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1566sshClient # [ 9.819431] systemd[1]: Starting DHCP Client...1567sshClient # [ 9.827015] systemd[1]: Started Name Service Cache Daemon (nsncd).1568machine # [ 9.841010] systemd[1]: Starting First Boot Home Area Wizard...1569sshClient # [ 9.827994] systemd[1]: Reached target Host and Network Name Lookups.1570sshClient # [ 9.834325] systemd[1]: Reached target User and Group Name Lookups.1571sshClient # [ 9.854460] systemd[1]: Finished Firewall.1572sshClient # [ 9.858690] systemd[1]: Finished First Boot Home Area Wizard.1573sshClient # [ 9.869708] systemd[1]: Reached target First Boot Complete.1574sshClient # [ 9.874856] systemd[1]: Reached target Preparation for Network.1575sshClient # [ 9.879194] systemd[1]: Starting Extra networking commands....1576sshClient # [ 9.884789] systemd[1]: Starting Save Transient machine-id to Disk...1577sshClient # [ 9.900253] systemd[1]: Condition check resulted in Virtio network device being skipped.1578sshClient # [ 9.901588] systemd[1]: Condition check resulted in Virtio network device being skipped.1579machine # [ 9.931806] systemd[1]: Finished resolvconf update.1580sshClient # [ 9.935298] systemd[1]: Starting Address configuration of eth1...1581machine # [ 9.965018] systemd[1]: Starting DHCP Client...1582machine # [ 9.970558] nsncd[568]: Oct 18 02:43:45.410 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1583machine # [ 9.988307] systemd[1]: Started Name Service Cache Daemon (nsncd).1584machine # [ 9.989328] systemd[1]: linger-users.service: Deactivated successfully.1585machine # [ 9.990285] systemd[1]: Finished linger-users.service.1586machine # [ 10.009572] systemd[1]: Reached target Host and Network Name Lookups.1587machine # [ 10.012334] systemd[1]: Reached target User and Group Name Lookups.1588machine # [ 10.060204] systemd[1]: Created slice Slice /system/systemd-mute-console.1589machine # [ 10.069959] systemd[1]: Started Console Output Muting Service (PID 580/UID 0).1590machine # [ 10.073802] systemd[1]: Condition check resulted in Virtio network device being skipped.1591machine # [ 10.075032] systemd[1]: Condition check resulted in Virtio network device being skipped.1592sshClient # [ 10.074042] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1593sshClient # [ 10.093752] systemd[1]: Finished Save Transient machine-id to Disk.1594sshClient # [ 10.108280] systemd-logind[491]: Watching system buttons on /dev/input/event0 (gpio-keys)1595machine # [ 10.128552] systemd[1]: Finished Firewall.1596machine # [ 10.136376] systemd[1]: Reached target Preparation for Network.1597machine # [ 10.137683] systemd-logind[508]: Watching system buttons on /dev/input/event0 (gpio-keys)1598machine # [ 10.160162] systemd[1]: Starting Address configuration of eth1...1599machine # [ 10.164582] systemd[1]: Starting Extra networking commands....1600sshClient # [ 10.173988] network-addresses-eth1-start[595]: adding address 192.168.1.2/24... done1601sshClient # [ 10.207336] network-addresses-eth1-start[595]: adding address 2001:db8:1::2/64... done1602sshClient # [ 10.222886] dhcpcd[598]: dhcpcd-10.3.2 starting1603sshClient # [ 10.243554] dhcpcd[645]: dev: loaded udev1604sshClient # [ 10.247073] systemd[1]: Finished Address configuration of eth1.1605sshClient # [ 10.306353] 8021q: 802.1Q VLAN Support v1.81606sshClient # [ 10.306725] 8021q: adding VLAN 0 to HW filter on device eth11607sshClient # [ 10.312528] systemd[1]: Finished Extra networking commands..1608sshClient # [ 10.316247] systemd[1]: Reached target Network.1609sshClient # [ 10.321274] systemd[1]: Starting Permit User Sessions...1610machine # [ 10.438372] network-addresses-eth1-start[611]: adding address 192.168.1.1/24... done1611sshClient # [ 10.448542] cfg80211: Loading compiled-in X.509 certificates for regulatory database1612sshClient # [ 10.446608] systemd[1]: Finished Permit User Sessions.1613sshClient # [ 10.458456] systemd[1]: Started Getty on tty1.1614sshClient # [ 10.459333] systemd[1]: Reached target Login Prompts.1615sshClient # [ 10.490337] mousedev: PS/2 mouse device common for all mice1616machine # [ 10.500408] network-addresses-eth1-start[611]: adding address 2001:db8:1::1/64... done1617machine # [ 10.502079] dhcpcd[614]: dhcpcd-10.3.2 starting1618sshClient # [ 10.520809] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1619sshClient # [ 10.522437] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1620machine # [ 10.522772] dhcpcd[640]: dev: loaded udev1621sshClient # [ 10.527718] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21622sshClient # [ 10.528076] cfg80211: failed to load regulatory.db1623machine # [ 10.554327] systemd[1]: Finished Address configuration of eth1.1624sshClient # [ 10.578427] systemd-logind[491]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1625sshClient # [ 10.602031] dhcpcd[645]: eth0: waiting for carrier1626sshClient # [ 10.618088] 8021q: adding VLAN 0 to HW filter on device eth01627sshClient # [ 10.607327] dhcpcd[645]: libudev: received NULL device1628sshClient # [ 10.612065] dhcpcd[645]: libudev: received NULL device1629sshClient # [ 10.612990] dhcpcd[645]: eth0: carrier acquired1630sshClient # [ 10.622368] dhcpcd[645]: DUID 00:01:00:01:32:66:ee:e2:52:54:00:12:34:561631sshClient # [ 10.623493] dhcpcd[645]: eth0: IAID 00:12:34:561632sshClient # [ 10.627302] dhcpcd[645]: eth0: adding address fe80::5054:ff:fe12:34561633machine: (finished: waiting for unit systemd-homed.service, in 11.07 seconds)1634machine: waiting for Please enter user name to create to appear on tty 11635machine # [ 10.909081] systemd[1]: Finished Extra networking commands..1636machine # [ 10.913647] systemd[1]: Reached target Network.1637machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.23 seconds)1638machine: sending keys 'test-homed-user\n'1639machine # [ 11.052487] systemd-logind[508]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1640machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds)1641machine: waiting for Please enter new password for user test-homed-user: to appear on tty 11642machine # [ 11.257255] dhcpcd[640]: eth0: waiting for carrier1643machine # [ 11.258176] dhcpcd[640]: eth0: carrier acquired1644machine # [ 11.277751] dhcpcd[640]: DUID 00:01:00:01:32:66:ee:e2:52:54:00:12:34:561645machine # [ 11.278988] dhcpcd[640]: eth0: IAID 00:12:34:561646machine # [ 11.279718] dhcpcd[640]: eth0: adding address fe80::5054:ff:fe12:34561647machine: (finished: waiting for Please enter new password for user test-homed-user: to appear on tty 1, in 0.20 seconds)1648machine: sending keys 'foobarfoo\n'1649sshClient # [ 11.368361] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31650machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)1651machine: waiting for (repeat) to appear on tty 11652sshClient # [ 11.714675] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1653sshClient # [ 11.718838] systemd[1]: Starting Virtual Console Setup...1654machine: (finished: waiting for (repeat) to appear on tty 1, in 0.28 seconds)1655machine: sending keys 'foobarfoo\n'1656machine # [ 11.745728] dhcpcd[640]: eth0: soliciting a DHCP lease1657machine # [ 11.753580] dhcpcd[640]: eth0: offered 10.0.2.15 from 10.0.2.21658machine # [ 11.760524] dhcpcd[640]: eth0: probing address 10.0.2.15/241659sshClient # [ 11.750681] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1660sshClient # [ 11.751994] systemd[1]: Stopped Virtual Console Setup.1661sshClient # [ 11.759477] systemd-logind[491]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1662sshClient # [ 11.764304] systemd[1]: Starting Virtual Console Setup...1663machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1664machine: waiting for unit systemd-homed-firstboot.service1665sshClient # [ 11.909015] systemd-vconsole-setup[692]: Configuration of first virtual console was skipped, ignoring remaining ones.1666sshClient # [ 11.914204] systemd[1]: Finished Virtual Console Setup.1667sshClient # [ 12.118983] dhcpcd[645]: eth0: soliciting a DHCP lease1668sshClient # [ 12.124893] dhcpcd[645]: eth0: offered 10.0.2.15 from 10.0.2.21669sshClient # [ 12.128267] dhcpcd[645]: eth0: probing address 10.0.2.15/241670sshClient # [ 12.283101] dhcpcd[645]: eth0: soliciting an IPv6 router1671sshClient # [ 12.285485] dhcpcd[645]: eth0: Router Advertisement from fe80::21672sshClient # [ 12.287050] dhcpcd[645]: eth0: adding address fec0::5054:ff:fe12:3456/641673sshClient # [ 12.288758] dhcpcd[645]: eth0: adding route to fec0::/641674sshClient # [ 12.290121] dhcpcd[645]: eth0: adding default route via fe80::21675machine # [ 12.499306] sshd-keygen-start[533]: Generating public/private rsa key pair.1676machine # [ 12.500799] sshd-keygen-start[533]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key1677machine # [ 12.500854] sshd-keygen-start[533]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub1678machine # [ 12.500887] sshd-keygen-start[533]: The key fingerprint is:1679machine # [ 12.500967] sshd-keygen-start[533]: SHA256:CbldxBD/d3ac4PIF6mH7/d1r2vxCM9+hIr3t/zOtKIk root@machine1680machine # [ 12.501002] sshd-keygen-start[533]: The key's randomart image is:1681machine # [ 12.501036] sshd-keygen-start[533]: +---[RSA 4096]----+1682machine # [ 12.502248] sshd-keygen-start[533]: | o+. |1683machine # [ 12.516700] sshd-keygen-start[533]: | . o. |1684machine # [ 12.517561] sshd-keygen-start[533]: | o o o |1685machine # [ 12.518329] sshd-keygen-start[533]: | + o .o o..|1686machine # [ 12.519083] sshd-keygen-start[533]: | . S =...o=|1687machine # [ 12.519834] sshd-keygen-start[533]: | o =.=+.|1688machine # [ 12.524811] sshd-keygen-start[533]: | ..+ o.++|1689machine # [ 12.525689] sshd-keygen-start[533]: | E.oooo=+*|1690machine # [ 12.526453] sshd-keygen-start[533]: | .o===O&|1691machine # [ 12.527205] sshd-keygen-start[533]: +----[SHA256]-----+1692machine # [ 12.591177] sshd-keygen-start[732]: Generating public/private ed25519 key pair.1693machine # [ 12.593970] sshd-keygen-start[732]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key1694machine # [ 12.595345] sshd-keygen-start[732]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub1695machine # [ 12.602054] sshd-keygen-start[732]: The key fingerprint is:1696machine # [ 12.602975] sshd-keygen-start[732]: SHA256:mE4ZaVisMdb1CPIg5FSIP7UElt9kRhqXddTFaN6WSQM root@machine1697machine # [ 12.606711] sshd-keygen-start[732]: The key's randomart image is:1698machine # [ 12.607631] sshd-keygen-start[732]: +--[ED25519 256]--+1699machine # [ 12.613150] sshd-keygen-start[732]: | o*==+=+o.o.E=. |1700machine # [ 12.614015] sshd-keygen-start[732]: |.+o.*XBo + + + |1701machine # [ 12.614798] sshd-keygen-start[732]: | ..=oX= . .o o + |1702machine # [ 12.615552] sshd-keygen-start[732]: | o +..= . = |1703machine # [ 12.624068] sshd-keygen-start[732]: | . = S . |1704machine # [ 12.625013] sshd-keygen-start[732]: | o |1705machine # [ 12.625753] sshd-keygen-start[732]: | . |1706machine # [ 12.626482] sshd-keygen-start[732]: | |1707machine # [ 12.627221] sshd-keygen-start[732]: | |1708machine # [ 12.627952] sshd-keygen-start[732]: +----[SHA256]-----+1709machine # [ 12.639355] systemd[1]: sshd-keygen.service: Deactivated successfully.1710machine # [ 12.641765] systemd[1]: Finished SSH Host Keys Generation.1711machine # [ 12.642629] systemd[1]: Starting SSH Daemon...1712machine # [ 12.699608] sshd[734]: Server listening on 0.0.0.0 port 22.1713machine # [ 12.703021] systemd[1]: Started SSH Daemon.1714machine # [ 12.703774] sshd[734]: Server listening on :: port 22.1715machine # [ 13.176744] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1716machine # [ 13.189801] systemd[1]: Starting Virtual Console Setup...1717machine # [ 13.274988] systemd-logind[508]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1718machine # [ 13.360210] systemd-vconsole-setup[744]: Configuration of first virtual console was skipped, ignoring remaining ones.1719machine # [ 13.364072] systemd[1]: Finished Virtual Console Setup.1720machine # [ 13.939191] dhcpcd[640]: eth0: soliciting an IPv6 router1721machine # [ 13.941550] dhcpcd[640]: eth0: Router Advertisement from fe80::21722machine # [ 13.942975] dhcpcd[640]: eth0: adding address fec0::5054:ff:fe12:3456/641723machine # [ 13.944506] dhcpcd[640]: eth0: adding route to fec0::/641724machine # [ 13.945897] dhcpcd[640]: eth0: adding default route via fe80::21725machine # [ 16.687898] dhcpcd[640]: eth0: leased 10.0.2.15 for 86400 seconds1726machine # [ 16.689809] dhcpcd[640]: eth0: adding route to 10.0.2.0/241727machine # [ 16.691091] dhcpcd[640]: eth0: adding default route via 10.0.2.21728machine # [ 16.804462] systemd[1]: Started DHCP Client.1729sshClient # [ 17.093678] dhcpcd[645]: eth0: leased 10.0.2.15 for 86400 seconds1730sshClient # [ 17.095760] dhcpcd[645]: eth0: adding route to 10.0.2.0/241731sshClient # [ 17.098754] dhcpcd[645]: eth0: adding default route via 10.0.2.21732sshClient # [ 17.258673] systemd[1]: Started DHCP Client.1733sshClient # [ 17.260864] systemd[1]: Reached target Multi-User System.1734sshClient # [ 17.262306] systemd[1]: Startup finished in 1.126s (kernel) + 4.449s (initrd) + 11.686s (userspace) = 17.262s.1735machine # [ 22.207859] systemd-homed[461]: Generating key pair for signing local user identity records.1736machine # [ 22.212706] systemd-homed[461]: Successfully created Ed25519 key pair.1737machine # [ 22.223861] systemd-homed[461]: test-homed-user: changing state absent → creating1738machine # [ 22.319052] systemd-homework[839]: Using automatic default storage of 'luks'.1739machine # [ 22.324723] systemd-homework[839]: Sizing home to 83% of available disk space, which is 744M.1740machine # [ 22.326329] systemd-homework[839]: Allocating image file completed.1741machine # [ 22.340941] systemd-homework[839]: Writing of partition table completed.1742machine # [ 22.342907] systemd-homework[839]: Setting up loopback device /dev/loop0 completed.1743machine # [ 23.660674] systemd-homework[839]: LUKS formatting completed.1744machine # [ 25.784506] systemd-homework[839]: Writing password to LUKS keyslot 0 completed.1745machine # [ 25.997228] systemd-homework[839]: LUKS activation by volume key succeeded.1746machine # [ 26.013095] systemd-homework[839]: Writing user record as LUKS token completed.1747machine # [ 26.014390] systemd-homework[839]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1748machine # [ 26.015812] systemd-homework[839]: Formatting /dev/mapper/home-test-homed-user as btrfs1749machine # [ 26.828887] systemd[1]: proc-868-mounts.mount: Deactivated successfully.1750machine # [ 26.829630] systemd-homework[839]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid 5ffd2fb6-a44f-4bb1-9164-bb6223fb6385)1751machine # [ 26.829735] systemd-homework[839]: Formatting file system completed.1752machine # [ 26.889180] systemd-homework[839]: Mounting file system completed.1753machine # [ 26.892538] systemd-homework[839]: Skeleton directory /etc/skel missing, ignoring.1754machine # [ 26.894574] systemd-homework[839]: Wrote embedded .identity file.1755machine # [ 26.895905] systemd-homework[839]: Replaced embedded blob dir with contents of system blob dir.1756machine # [ 26.897754] systemd-homework[839]: Recursive changing of ownership completed.1757machine # [ 26.899010] systemd-homework[839]: Changed top-level directory access mode to 0700.1758machine # [ 26.902591] systemd-homework[839]: Synchronized disk.1759machine # [ 26.903434] systemd-homework[839]: Discarded unused 652.8M.1760machine # [ 26.914582] systemd-homed[461]: block device /sys/devices/virtual/block/dm-0 has been removed.1761machine # [ 26.954360] systemd-homed[461]: block device /sys/devices/virtual/block/dm-0 has been removed.1762machine # [ 26.973286] systemd-homed[461]: block device /sys/devices/virtual/block/loop0 has been removed.1763machine # [ 26.989717] systemd-homework[839]: Moved image file into place.1764machine # [ 26.992120] systemd-homework[839]: Creation completed.1765machine # [ 26.993121] systemd-homework[839]: Image size is 744M, file system size is 726M, file system payload size is 726M, file system free is 719.9M.1766machine # [ 27.006042] systemd-homed[461]: test-homed-user: changing state creating → inactive1767machine # [ 27.012644] systemd[1]: Finished First Boot Home Area Wizard.1768machine # [ 27.016536] systemd[1]: systemd-mute-console@0-1-580_581-0.service: Deactivated successfully.1769machine # [ 27.022606] systemd[1]: Reached target First Boot Complete.1770machine # [ 27.028248] systemd[1]: Starting Save Transient machine-id to Disk...1771machine # [ 27.033565] systemd[1]: Starting Permit User Sessions...1772machine # [ 27.078007] systemd[1]: Finished Permit User Sessions.1773machine # [ 27.082823] systemd[1]: Started Getty on tty1.1774machine # [ 27.085082] systemd[1]: Reached target Login Prompts.1775machine # [ 27.085982] systemd[1]: Reached target Multi-User System.1776machine # [ 27.193909] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1777machine # [ 27.199275] systemd[1]: Finished Save Transient machine-id to Disk.1778machine # [ 27.212980] systemd[1]: Startup finished in 1.133s (kernel) + 4.391s (initrd) + 21.687s (userspace) = 27.212s.1779machine: (finished: waiting for unit systemd-homed-firstboot.service, in 16.02 seconds)1780(finished: subtest: create systemd-homed user on first boot prompt, in 28.19 seconds)1781machine: must succeed: homectl update test-homed-user --offline -G wheel1782machine # [ 27.903937] systemd-homed[461]: test-homed-user: changing state inactive → updating1783machine # [ 27.912623] systemd-homework[914]: Offline update requested. Not touching embedded records.1784machine # [ 27.916945] systemd-homed[461]: Home test-homed-user is signed exclusively by our key, accepting.1785machine # [ 27.922138] systemd-homed[461]: test-homed-user: changing state updating → inactive1786machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.06 seconds)1787subtest: login as homed user1788machine: waiting for login: to appear on tty 11789machine: (finished: waiting for login: to appear on tty 1, in 0.04 seconds)1790machine: sending keys 'test-homed-user\n'1791machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds)1792machine: waiting for Password: to appear on tty 11793machine # [ 28.301678] systemd-homed[461]: test-homed-user: changing state inactive → activating-for-acquire1794machine # [ 28.310089] systemd-homework[929]: None of the supplied plaintext passwords unlock the user record's hashed passwords.1795machine # [ 28.318326] systemd-homed[461]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.1796machine # [ 28.322307] systemd-homed[461]: test-homed-user: changing state activating-for-acquire → inactive1797machine # [ 28.325453] systemd-homed[461]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.1798machine # [ 28.327911] systemd-homed[461]: Home test-homed-user already deactivated, no automatic deactivation needed.1799machine: (finished: waiting for Password: to appear on tty 1, in 1.10 seconds)1800machine: sending keys 'foobarfoo\n'1801machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)1802machine: waiting for success: pgrep -u test-homed-user -t tty1 bash1803machine # [ 29.422683] systemd-homed[461]: test-homed-user: changing state inactive → activating-for-acquire1804machine # [ 29.475482] systemd-homework[941]: Provided password unlocks user record.1805machine # [ 29.481568] systemd-homework[941]: Successfully locked image file '/home/test-homed-user.home'.1806machine # [ 29.489491] systemd-homework[941]: Allocated additional 652.8M.1807machine # [ 29.493352] systemd-homework[941]: Setting up loopback device /dev/loop1 completed.1808machine # [ 29.510616] loop1: detected capacity change from 0 to 15196161809machine # [ 30.340943] systemd-homework[941]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1810machine # [ 30.379295] systemd-homework[941]: Provided password unlocks user record.1811machine # [ 30.385405] systemd-homework[941]: Probing file system completed (found btrfs).1812machine # [ 30.387977] systemd-homework[941]: No fsck for file system btrfs installed, ignoring.1813machine # [ 30.408660] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (941)1814machine # [ 30.413483] BTRFS info (device dm-0): first mount of filesystem 5ffd2fb6-a44f-4bb1-9164-bb6223fb63851815machine # [ 30.413532] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm1816machine # [ 30.402718] systemd-homework[941]: Mounting file system completed.[ 30.420664] BTRFS info (device dm-0): enabling free space tree1817machine # [ 30.420689] BTRFS info (device dm-0): use zstd compression, level 11818machine # 1819machine # [ 30.408383] systemd-homework[941]: Discovered used loopback device /dev/loop1.1820machine # [ 30.409662] systemd-homework[941]: offset = 1048576, size = 778043392, image = 7801405441821machine # [ 30.427180] systemd-homework[941]: Ready to resize image size 744M → 897M, partition size 742M → 895M, file system size 726M → 879M.1822machine # [ 30.429376] systemd-homework[941]: Growing of image file from 744M to 897M completed.1823machine # [ 30.454331] loop1: detected capacity change from 1519616 to 18329601824machine # [ 30.441461] systemd-homework[941]: Refreshing loop device size completed.1825machine # [ 30.462300] systemd-homework[941]: Growing of partition completed.1826machine # [ 30.512564] async_tx: api initialized (async)1827machine # [ 30.549291] systemd-homework[941]: LUKS device growing completed.1828machine # [ 30.574227] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 761266176 to 9216983041829machine # [ 30.560390] systemd-homework[941]: File system resizing from 726M to 879M completed.1830machine # [ 30.564231] systemd-homework[941]: Synchronized disk.1831machine # [ 30.565081] systemd-homework[941]: Synchronized disk.1832machine # [ 30.565860] systemd-homework[941]: Resizing completed.1833machine # [ 30.566643] systemd-homework[941]: Image size is 897M, file system size is 879M, file system payload size is 879M, file system free is 872.9M.1834machine # [ 30.572270] systemd-homework[941]: Read embedded .identity file.1835machine # [ 30.594327] systemd-homework[941]: Provided password unlocks user record.1836machine # [ 30.597347] systemd-homework[941]: Reconciling header user identity completed (host version was newer).1837machine # [ 30.600918] systemd-homework[941]: Reconciling embedded user identity completed (host version was newer).1838machine # [ 30.611323] systemd-homework[941]: Wrote LUKS header user record.1839machine # [ 30.613321] systemd-homework[941]: Wrote embedded .identity file.1840machine # [ 30.614251] systemd-homework[941]: Replaced embedded blob dir with contents of system blob dir.1841machine # [ 30.615496] systemd-homework[941]: Recursive changing of ownership not necessary, skipped.1842machine # [ 30.622587] systemd-homework[941]: Synchronized disk.1843machine # [ 30.623462] systemd-homework[941]: Moving to final mount point /home/test-homed-user completed.1844machine # [ 30.645321] systemd-homework[941]: Activation completed.1845machine # [ 30.648257] systemd-homework[941]: Image size is 895M, file system size is 879M, file system payload size is 879M, file system free is 872.9M.1846machine # [ 30.660900] systemd-homed[461]: Home test-homed-user is signed exclusively by our key, accepting.1847machine # [ 30.671701] login[905]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired.1848machine # [ 30.678263] systemd-homed[461]: test-homed-user: changing state activating-for-acquire → active1849machine # [ 30.689871] systemd-homed[461]: test-homed-user: changing state active → resizing-while-active1850machine # [ 30.703760] systemd-homework[985]: Successfully acquired home volume key from kernel keyring.1851machine # [ 30.707221] systemd-homework[985]: LUKS volume key from keyring unlocks user record.1852machine # [ 30.776216] login[905]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0)1853machine # [ 30.809284] systemd-homework[985]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.1854machine # [ 30.850045] systemd[1]: Created slice Slice /user/60388.1855machine # [ 30.853263] systemd[1]: Starting User Runtime Directory /run/user/60388...1856machine # [ 30.879031] systemd-logind[508]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'.1857machine # [ 30.998688] systemd[1]: Finished User Runtime Directory /run/user/60388.1858machine # [ 31.009235] systemd-homework[985]: Successfully re-activated LUKS device.1859machine # [ 31.020325] systemd[1]: Starting User Manager for UID 60388...1860machine # [ 31.021350] systemd-homework[985]: LUKS volume key from keyring unlocks user record.1861machine # [ 31.022466] systemd-homework[985]: Discovered used loopback device /dev/loop1.1862machine # [ 31.023553] systemd-homework[985]: Read embedded .identity file.1863machine # [ 31.034098] systemd-homework[985]: LUKS volume key from keyring unlocks user record.1864machine # [ 31.039530] systemd-homework[985]: Reconciling header user identity completed (host version was newer).1865machine # [ 31.044635] systemd-homework[985]: Reconciling embedded user identity completed (host version was newer).1866machine # [ 31.046018] systemd-homework[985]: offset = 1048576, size = 938475520, image = 9405726721867machine # [ 31.047898] systemd-homework[985]: Ready to resize image size 897M → 765M, partition size 895M → 763M, file system size 879M → 747M.1868machine # [ 31.076693] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 921698304 to 7832862721869machine # [ 31.063825] systemd-homework[985]: Wrote embedded .identity file.1870machine # [ 31.071316] systemd-homework[985]: Replaced embedded blob dir with contents of system blob dir.1871machine # [ 31.079511] systemd-homework[985]: Backing file is fully allocated already.1872machine # [ 31.085204] systemd-homework[985]: File system resizing from 879M to 747M completed.1873machine # [ 31.086516] systemd-homework[985]: Synchronized disk.1874machine # [ 31.100347] (systemd)[988]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.1875machine # [ 31.191306] (systemd)[988]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)1876machine # [ 31.200656] systemd-logind[508]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'.1877machine # [ 31.218176] systemd-homework[985]: LUKS device shrinking completed.1878machine # [ 31.246400] loop1: detected capacity change from 1832960 to 15626241879machine # [ 31.235346] systemd-homework[985]: Refreshing loop device size completed.1880machine # [ 31.237428] systemd-homework[985]: Shrinking of image file completed.1881machine # [ 31.257177] systemd-homework[985]: Shrinking of partition completed.1882machine # [ 31.258703] systemd[988]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1883machine # [ 31.297084] systemd-homework[985]: Wrote LUKS header user record.1884machine # [ 31.298267] systemd-homework[985]: Synchronized disk.1885machine # [ 31.316159] systemd-homework[985]: Resizing completed.1886machine # [ 31.317178] systemd-homework[985]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.1887machine # [ 31.333614] systemd-homed[461]: Home test-homed-user is signed exclusively by our key, accepting.1888machine # [ 31.340605] systemd-homed[461]: test-homed-user: changing state resizing-while-active → active1889machine # [ 31.342616] systemd-homed[461]: Rebalancing complete.1890machine # [ 31.428611] systemd[988]: Queued start job for default target Main User Target.1891machine # [ 31.437278] systemd[988]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1892machine # [ 31.441839] systemd[988]: Created slice User Application Slice.1893machine # [ 31.442946] systemd[988]: Started Daily Cleanup of User's Temporary Directories.1894machine # [ 31.446289] systemd[988]: Reached target Paths.1895machine # [ 31.447165] systemd[988]: Reached target Timers.1896machine # [ 31.447906] systemd[988]: Starting D-Bus User Message Bus Socket...1897machine # [ 31.451116] systemd[988]: Starting Create User Files and Directories...1898machine # [ 31.506503] systemd[988]: Finished Create User Files and Directories.1899machine # [ 31.527278] systemd[988]: Listening on D-Bus User Message Bus Socket.1900machine # [ 31.533615] systemd[988]: Reached target Sockets.1901machine # [ 31.534466] systemd[988]: Reached target Basic System.1902machine # [ 31.535249] systemd[1]: Started User Manager for UID 60388.1903machine # [ 31.546725] systemd[988]: Starting Run user-specific NixOS activation...1904machine # [ 31.547911] systemd[1]: Started Session 1 of User test-homed-user.1905machine # [ 31.599637] systemd[988]: Finished Run user-specific NixOS activation.1906machine # [ 31.602691] systemd[988]: Reached target Main User Target.1907machine # [ 31.603573] systemd[988]: Startup finished in 377ms.1908machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 3.34 seconds)1909machine: sending keys 'whoami > /tmp/2\n'1910machine: (finished: sending keys 'whoami > /tmp/2\n', in 0.17 seconds)1911machine: waiting for file '/tmp/2'1912machine: (finished: waiting for file '/tmp/2', in 1.06 seconds)1913machine: must succeed: cat /tmp/21914machine: (finished: must succeed: cat /tmp/2, in 0.02 seconds)1915(finished: subtest: login as homed user, in 6.00 seconds)1916subtest: login as regular user1917sshClient: waiting for login: to appear on tty 11918sshClient: waiting for the VM to finish booting1919sshClient: Guest shell says: b'Spawning backdoor root shell...\n'1920sshClient: connected to guest root shell1921sshClient: (connecting took 0.00 seconds)1922sshClient: (finished: waiting for the VM to finish booting, in 0.00 seconds)1923sshClient: (finished: waiting for login: to appear on tty 1, in 0.05 seconds)1924sshClient: sending keys 'test-normal-user\n'1925sshClient: (finished: sending keys 'test-normal-user\n', in 0.18 seconds)1926sshClient: waiting for Password: to appear on tty 11927sshClient: (finished: waiting for Password: to appear on tty 1, in 1.11 seconds)1928sshClient: sending keys 'foobarfoo\n'1929sshClient: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)1930sshClient: waiting for success: pgrep -u test-normal-user bash1931sshClient # [ 35.472760] login[664]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0)1932sshClient # [ 35.512650] systemd[1]: Created slice Slice /user/1000.1933sshClient # [ 35.518331] systemd[1]: Starting User Runtime Directory /run/user/1000...1934sshClient # [ 35.555491] systemd-logind[491]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'.1935sshClient # [ 35.588815] systemd[1]: Finished User Runtime Directory /run/user/1000.1936sshClient # [ 35.596004] systemd[1]: Starting User Manager for UID 1000...1937sshClient # [ 35.632200] (systemd)[794]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0)1938sshClient # [ 35.642002] systemd-logind[491]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'.1939sshClient # [ 35.671865] systemd[794]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1940sshClient # [ 35.809114] systemd[794]: Queued start job for default target Main User Target.1941sshClient # [ 35.817155] systemd[794]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1942sshClient # [ 35.821796] systemd[794]: Created slice User Application Slice.1943sshClient # [ 35.823465] systemd[794]: Started Daily Cleanup of User's Temporary Directories.1944sshClient # [ 35.826007] systemd[794]: Reached target Paths.1945sshClient # [ 35.826761] systemd[794]: Reached target Timers.1946sshClient # [ 35.827478] systemd[794]: Starting D-Bus User Message Bus Socket...1947sshClient # [ 35.832269] systemd[794]: Starting Create User Files and Directories...1948sshClient # [ 35.876910] systemd[794]: Finished Create User Files and Directories.1949sshClient # [ 35.894116] systemd[794]: Listening on D-Bus User Message Bus Socket.1950sshClient # [ 35.898192] systemd[794]: Reached target Sockets.1951sshClient # [ 35.899143] systemd[794]: Reached target Basic System.1952sshClient # [ 35.899949] systemd[1]: Started User Manager for UID 1000.1953sshClient # [ 35.905629] systemd[794]: Starting Run user-specific NixOS activation...1954sshClient # [ 35.912478] systemd[1]: Started Session 1 of User test-normal-user.1955sshClient # [ 35.974934] systemd[794]: Finished Run user-specific NixOS activation.1956sshClient # [ 35.978671] systemd[794]: Reached target Main User Target.1957sshClient # [ 35.979650] systemd[794]: Startup finished in 317ms.1958sshClient: (finished: waiting for success: pgrep -u test-normal-user bash, in 1.11 seconds)1959sshClient: sending keys 'whoami > /tmp/1\n'1960sshClient: (finished: sending keys 'whoami > /tmp/1\n', in 0.17 seconds)1961sshClient: waiting for file '/tmp/1'1962sshClient: (finished: waiting for file '/tmp/1', in 1.06 seconds)1963sshClient: must succeed: cat /tmp/11964sshClient: (finished: must succeed: cat /tmp/1, in 0.02 seconds)1965(finished: subtest: login as regular user, in 3.80 seconds)1966subtest: add homed ssh authorized key1967sshClient: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n'1968sshClient: (finished: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n', in 0.49 seconds)1969sshClient: waiting for file '/tmp/id_ed25519.pub'1970sshClient: (finished: waiting for file '/tmp/id_ed25519.pub', in 1.06 seconds)1971sshClient: must succeed: cat /tmp/id_ed25519.pub1972sshClient: (finished: must succeed: cat /tmp/id_ed25519.pub, in 0.03 seconds)1973machine: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIItbmW+082iQ9XHA6aBPlE4E5Wf+76ccAqt9aCFVNipn test-normal-user@sshClient'1974machine # [ 39.358901] systemd-homed[461]: test-homed-user: changing state active → updating-while-active1975machine # [ 39.369916] systemd-homework[1041]: Offline update requested. Not touching embedded records.1976machine # [ 39.398480] systemd-homed[461]: test-homed-user: changing state updating-while-active → active1977machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIItbmW+082iQ9XHA6aBPlE4E5Wf+76ccAqt9aCFVNipn test-normal-user@sshClient', in 0.09 seconds)1978machine: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed255191979machine: (finished: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed25519, in 0.04 seconds)1980(finished: subtest: add homed ssh authorized key, in 1.72 seconds)1981subtest: change homed user password1982machine: sending keys 'passwd; echo $? > /tmp/3\n'1983machine: (finished: sending keys 'passwd; echo $? > /tmp/3\n', in 0.27 seconds)1984machine: waiting for New password: to appear on tty 11985machine: (finished: waiting for New password: to appear on tty 1, in 1.12 seconds)1986machine: sending keys 'barfoobar\n'1987machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds)1988machine: waiting for Retype new password: to appear on tty 11989machine: (finished: waiting for Retype new password: to appear on tty 1, in 0.12 seconds)1990machine: sending keys 'barfoobar\n'1991machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds)1992??? Warning (UserWarning): sleep(): The 'secs' argument is deprecated. Use 'duration' instead.1993 File "/nix/store/hgh6bdm48cnkzdv3ahvrdswdd0azc31a-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 5731994machine: must succeed: sleep 4.01995machine # [ 41.303521] systemd-homed[461]: test-homed-user: changing state active → passwd-while-active1996machine # [ 41.993496] systemd-homework[1074]: No valid password for LUKS superblock.1997machine # [ 44.465598] systemd-homed[461]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.1998machine # [ 44.470521] systemd-homed[461]: test-homed-user: changing state passwd-while-active → active1999machine: (finished: must succeed: sleep 4.0, in 4.03 seconds)2000machine: sending keys 'foobarfoo\n'2001machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)2002machine: waiting for file '/tmp/3'2003machine # [ 45.431649] systemd-homed[461]: test-homed-user: changing state active → passwd-while-active2004machine # [ 46.701689] systemd-homework[1080]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2005machine # [ 46.830072] systemd-homework[1080]: Successfully re-activated LUKS device.2006machine # [ 46.892133] systemd-homework[1080]: Provided password unlocks user record.2007machine # [ 46.893420] systemd-homework[1080]: Discovered used loopback device /dev/loop1.2008machine # [ 46.894475] systemd-homework[1080]: Read embedded .identity file.2009machine # [ 46.952255] systemd-homework[1080]: Provided password unlocks user record.2010machine # [ 46.953568] systemd-homework[1080]: Reconciling header user identity completed (host version was newer).2011machine # [ 46.954896] systemd-homework[1080]: Reconciling embedded user identity completed (host version was newer).2012machine # [ 50.237166] systemd-homework[1080]: Added LUKS key slot 1.2013machine # [ 50.266162] systemd-homework[1080]: Destroyed LUKS key slot 0.2014machine # [ 50.281310] systemd-homework[1080]: Wrote LUKS header user record.2015machine # [ 50.284358] systemd-homework[1080]: Wrote embedded .identity file.2016machine # [ 50.286040] systemd-homework[1080]: Replaced embedded blob dir with contents of system blob dir.2017machine # [ 50.292264] systemd-homework[1080]: Synchronized disk.2018machine # [ 50.300197] systemd-homework[1080]: Everything completed.2019machine # [ 50.321563] systemd-homed[461]: test-homed-user: changing state passwd-while-active → active2020machine # [ 50.323649] passwd[1054]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user.2021machine: (finished: waiting for file '/tmp/3', in 5.20 seconds)2022machine: must succeed: cat /tmp/32023machine: (finished: must succeed: cat /tmp/3, in 0.03 seconds)2024(finished: subtest: change homed user password, in 11.09 seconds)2025subtest: escalate to root from homed user2026machine: sending keys 'sudo id | tee /tmp/4\n'2027machine: (finished: sending keys 'sudo id | tee /tmp/4\n', in 0.22 seconds)2028machine: waiting for password for test-homed-user to appear on tty 12029machine # [ 51.007787] systemd-homed[461]: test-homed-user: changing state active → authenticating-for-acquire2030machine # [ 51.017080] systemd-homework[1118]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2031machine # [ 51.025601] systemd-homed[461]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2032machine # [ 51.030097] systemd-homed[461]: test-homed-user: changing state authenticating-for-acquire → active2033machine: (finished: waiting for password for test-homed-user to appear on tty 1, in 1.11 seconds)2034machine: sending keys 'barfoobar\n'2035machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2036machine: waiting for file '/tmp/4'2037machine: (finished: waiting for file '/tmp/4', in 0.03 seconds)2038machine: waiting for success: grep uid=0 /tmp/42039machine # [ 52.461704] systemd-homed[461]: test-homed-user: changing state active → authenticating-for-acquire2040machine # [ 52.513590] systemd-homework[1134]: Provided password unlocks user record.2041machine # [ 53.160709] systemd-homework[1134]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2042machine # [ 53.289835] systemd-homework[1134]: Successfully re-activated LUKS device.2043machine # [ 53.324134] systemd-homework[1134]: Provided password unlocks user record.2044machine # [ 53.325492] systemd-homework[1134]: Discovered used loopback device /dev/loop1.2045machine # [ 53.326597] systemd-homework[1134]: Read embedded .identity file.2046machine # [ 53.352890] systemd-homework[1134]: Provided password unlocks user record.2047machine # [ 53.354925] systemd-homework[1134]: Reconciling user identities completed (host and header version were identical).2048machine # [ 53.357069] systemd-homework[1134]: Reconciling embedded user identity completed (host and embedded version were identical).2049machine # [ 53.388082] systemd-homework[1134]: Everything completed.2050machine # [ 53.397645] systemd-homed[461]: Home test-homed-user is signed exclusively by our key, accepting.2051machine # [ 53.713709] sudo[1116]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired.2052machine # [ 53.724932] systemd-homed[461]: test-homed-user: changing state authenticating-for-acquire → active2053machine # [ 53.737789] sudo[1116]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id2054machine # [ 53.774650] sudo[1116]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388)2055machine # [ 53.794830] sudo[1116]: pam_unix(sudo:session): session closed for user root2056machine: (finished: waiting for success: grep uid=0 /tmp/4, in 2.11 seconds)2057(finished: subtest: escalate to root from homed user, in 3.58 seconds)2058subtest: log out and deactivate homed user's home area2059machine: sending keys 'exit\n'2060machine: (finished: sending keys 'exit\n', in 0.05 seconds)2061machine: waiting for success: homectl inspect test-homed-user | grep 'State: inactive'2062machine # [ 54.198319] login[905]: pam_unix(login:session): session closed for user test-homed-user2063machine # [ 54.213582] login[905]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used.2064machine # [ 54.233577] systemd[1]: getty@tty1.service: Deactivated successfully.2065machine # [ 54.246929] systemd[1]: session-1.scope: Deactivated successfully.2066machine # [ 54.260514] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.2067machine # [ 54.262524] systemd-logind[508]: Session 1 logged out. Waiting for processes to exit.2068machine # [ 54.263902] systemd[1]: Started Getty on tty1.2069machine # [ 54.285001] systemd-logind[508]: Removed session 1.2070machine # [ 64.413219] systemd[1]: Stopping User Manager for UID 60388...2071machine # [ 64.421635] systemd[988]: Activating special unit Exit the Session...2072machine # [ 64.426892] systemd[988]: Stopped target Main User Target.2073machine # [ 64.432490] systemd[988]: Stopped Run user-specific NixOS activation.2074machine # [ 64.434031] systemd[988]: Stopped target Basic System.2075machine # [ 64.435220] systemd[988]: Stopped target Paths.2076machine # [ 64.437580] systemd[988]: Stopped target Sockets.2077machine # [ 64.438729] systemd[988]: Stopped target Timers.2078machine # [ 64.439829] systemd[988]: Stopped Daily Cleanup of User's Temporary Directories.2079machine # [ 64.441820] systemd[988]: Closed D-Bus User Message Bus Socket.2080machine # [ 64.443203] systemd[988]: Stopped Create User Files and Directories.2081machine # [ 64.444771] systemd[988]: Removed slice User Application Slice.2082machine # [ 64.446110] systemd[988]: Reached target Shutdown.2083machine # [ 64.447243] systemd[988]: Finished Exit the Session.2084machine # [ 64.450853] systemd[988]: Reached target Exit the Session.2085machine # [ 64.454535] systemd-logind[508]: Removed session 2.2086machine # [ 64.465969] systemd-homed[461]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2087machine # [ 64.476894] systemd-homed[461]: test-homed-user: changing state active → deactivating2088machine # [ 64.478860] systemd[1]: user@60388.service: Deactivated successfully.2089machine # [ 64.488461] systemd[1]: Stopped User Manager for UID 60388.2090machine # [ 64.490155] systemd-homework[1214]: Successfully acquired home volume key from kernel keyring.2091machine # [ 64.493234] systemd-homework[1214]: Discarded unused 674.3M.2092machine # [ 64.500853] systemd-homework[1214]: Syncing completed.2093machine # [ 64.545509] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully.2094machine # [ 64.555521] systemd[1]: Stopping User Runtime Directory /run/user/60388...2095machine # [ 64.579892] systemd-homework[1214]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2096machine # [ 64.719855] systemd-homework[1214]: Successfully re-activated LUKS device.2097machine # [ 64.722904] systemd-homework[1214]: Discovered used loopback device /dev/loop1.2098machine # [ 64.724346] systemd-homework[1214]: offset = 1048576, size = 800063488, image = 8021606402099machine # [ 64.735415] systemd[1]: run-user-60388.mount: Deactivated successfully.2100machine # [ 64.743283] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully.2101machine # [ 64.750948] systemd[1]: Stopped User Runtime Directory /run/user/60388.2102machine # [ 64.760469] systemd-homework[1214]: Ready to resize image size 765M → 274M, partition size 763M → 272M, file system size 747M → 256M.2103machine # [ 64.762361] systemd-homework[1214]: Allocated additional 674.3M.2104machine # [ 64.771230] systemd-homework[1214]: File system resizing from 747M to 256M completed.[ 64.786721] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 783286272 to 2684354562105machine # 2106machine # [ 64.779676] systemd-homework[1214]: Synchronized disk.2107machine # [ 64.915219] systemd-homework[1214]: LUKS device shrinking completed.2108machine # [ 64.942507] loop1: detected capacity change from 1562624 to 5570562109machine # [ 64.930158] systemd-homework[1214]: Refreshing loop device size completed.2110machine # [ 64.931389] systemd-homework[1214]: Shrinking of image file completed.2111machine # [ 64.943098] systemd-homework[1214]: Shrinking of partition completed.2112machine # [ 64.945485] systemd-homework[1214]: Synchronized disk.2113machine # [ 64.962960] BTRFS info (device dm-0): last unmount of filesystem 5ffd2fb6-a44f-4bb1-9164-bb6223fb63852114machine # [ 64.950909] systemd-homework[1214]: Resizing completed.2115machine # [ 64.951826] systemd-homework[1214]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M.2116machine # [ 64.955384] systemd-homework[1214]: Unmounting completed.2117machine # [ 64.957082] systemd-homework[1214]: Discovered used LUKS device /dev/mapper/home-test-homed-user.2118machine # [ 64.958358] systemd-homework[1214]: Device home-test-homed-user is not active.2119machine # [ 64.993129] systemd-homed[461]: block device /sys/devices/virtual/block/dm-0 has been removed.2120machine # [ 65.008126] systemd-homework[1214]: Everything completed.2121machine # [ 65.026480] systemd-homed[461]: test-homed-user: changing state deactivating → inactive2122machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 11.71 seconds)2123(finished: subtest: log out and deactivate homed user's home area, in 11.76 seconds)2124subtest: ssh as homed user2125sshClient: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n'2126sshClient: (finished: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n', in 0.78 seconds)2127sshClient: waiting for Please enter password for user to appear on tty 12128machine # [ 67.684614] systemd-homed[461]: test-homed-user: changing state inactive → activating-for-acquire2129machine # [ 67.693829] systemd-homework[1248]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2130machine # [ 67.715155] systemd-homed[461]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2131machine # [ 67.722433] systemd-homed[461]: test-homed-user: changing state activating-for-acquire → inactive2132machine # [ 67.725398] sshd-session[1244]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error2133machine # [ 67.728359] systemd-homed[461]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2134machine # [ 67.730755] sshd-session[1244]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation.2135machine # [ 67.734330] systemd-homed[461]: Home test-homed-user already deactivated, no automatic deactivation needed.2136machine # [ 67.738000] sshd-session[1244]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 43002 ssh2: ED25519 SHA256:BTPOOI9gBEHNPEDy0EosZMdehcL9FPuIfxVAvdvWT0c2137machine # [ 67.789435] sshd-session[1244]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0)2138machine # [ 67.802759] systemd-logind[508]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'.2139machine # [ 67.809500] systemd[1]: Starting User Runtime Directory /run/user/60388...2140machine # [ 67.842768] systemd[1]: Finished User Runtime Directory /run/user/60388.2141machine # [ 67.849962] systemd[1]: Started Session 3 of User test-homed-user.2142machine # [ 67.866383] sshd-session[1244]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2143machine # [ 67.870878] sshd-session[1244]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2144machine # [ 67.887981] systemd-homed[461]: test-homed-user: changing state inactive → activating2145machine # [ 67.894776] systemd-homework[1253]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2146machine # [ 67.900518] systemd-homed[461]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2147machine # [ 67.902957] systemd-homed[461]: test-homed-user: changing state activating → inactive2148sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.15 seconds)2149sshClient: sending keys 'barfoobar\n'2150sshClient: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2151machine: waiting for success: pgrep -u test-homed-user bash2152machine # [ 69.000382] systemd-homed[461]: test-homed-user: changing state inactive → activating2153machine # [ 69.052507] systemd-homework[1258]: Provided password unlocks user record.2154machine # [ 69.058925] systemd-homework[1258]: Successfully locked image file '/home/test-homed-user.home'.2155machine # [ 69.065780] systemd-homework[1258]: Backing file is fully allocated already.2156machine # [ 69.070111] systemd-homework[1258]: Setting up loopback device /dev/loop1 completed.2157machine # [ 69.087355] loop1: detected capacity change from 0 to 5570562158machine # [ 69.980235] systemd-homework[1258]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.2159machine # [ 70.028920] systemd-homework[1258]: Provided password unlocks user record.2160machine # [ 70.041153] systemd-homework[1258]: Probing file system completed (found btrfs).2161machine # [ 70.045182] systemd-homework[1258]: No fsck for file system btrfs installed, ignoring.2162machine # [ 70.066776] BTRFS: device label test-homed-user devid 1 transid 17 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (1258)2163machine # [ 70.068342] BTRFS info (device dm-0): first mount of filesystem 5ffd2fb6-a44f-4bb1-9164-bb6223fb63852164machine # [ 70.068388] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm2165machine # [ 70.063287] systemd-homework[1258]: Mounting file system completed.[ 70.080881] BTRFS info (device dm-0): enabling free space tree2166machine # [ 70.080911] BTRFS info (device dm-0): use zstd compression, level 12167machine # 2168machine # [ 70.072432] systemd-homework[1258]: Discovered used loopback device /dev/loop1.2169machine # [ 70.073524] systemd-homework[1258]: offset = 1048576, size = 285212672, image = 2873098242170machine # [ 70.084346] systemd-homework[1258]: Ready to resize image size 274M → 765M, partition size 272M → 763M, file system size 256M → 747M.2171machine # [ 70.086318] systemd-homework[1258]: Growing of image file from 274M to 765M completed.2172machine # [ 70.095300] systemd-homework[1258]: Refreshing loop device size completed.[ 70.110576] loop1: detected capacity change from 557056 to 15626242173machine # 2174machine # [ 70.110617] systemd-homework[1258]: Growing of partition completed.2175machine # [ 70.160969] systemd-homework[1258]: LUKS device growing completed.2176machine # [ 70.185417] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 7832862722177machine # [ 70.169977] systemd-homework[1258]: File system resizing from 256M to 747M completed.2178machine # [ 70.171206] systemd-homework[1258]: Synchronized disk.2179machine # [ 70.174718] systemd-homework[1258]: Synchronized disk.2180machine # [ 70.175562] systemd-homework[1258]: Resizing completed.2181machine # [ 70.180218] systemd-homework[1258]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.2182machine # [ 70.182025] systemd-homework[1258]: Read embedded .identity file.2183machine # [ 70.206390] systemd-homework[1258]: Provided password unlocks user record.2184machine # [ 70.208973] systemd-homework[1258]: Reconciling user identities completed (host and header version were identical).2185machine # [ 70.210533] systemd-homework[1258]: Reconciling embedded user identity completed (host and embedded version were identical).2186machine # [ 70.215842] systemd-homework[1258]: Recursive changing of ownership not necessary, skipped.2187machine # [ 70.220251] systemd-homework[1258]: Synchronized disk.2188machine # [ 70.224411] systemd-homework[1258]: Moving to final mount point /home/test-homed-user completed.2189machine # [ 70.241065] systemd-homework[1258]: Activation completed.2190machine # [ 70.242436] systemd-homework[1258]: Image size is 763M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.2191machine # [ 70.259883] systemd-homed[461]: Home test-homed-user is signed exclusively by our key, accepting.2192machine # [ 70.265917] systemd-homed[461]: test-homed-user: changing state activating → active2193machine # [ 70.288745] systemd[1]: Starting User Manager for UID 60388...2194machine # [ 70.326927] (systemd)[1301]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.2195machine # [ 70.373192] (systemd)[1301]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)2196machine # [ 70.380889] systemd-logind[508]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'.2197machine # [ 70.411195] systemd[1301]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2198machine # [ 70.521785] systemd[1301]: Queued start job for default target Main User Target.2199machine # [ 70.529131] systemd[1301]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2200machine # [ 70.533489] systemd[1301]: Created slice User Application Slice.2201machine # [ 70.534597] systemd[1301]: Started Daily Cleanup of User's Temporary Directories.2202machine # [ 70.535648] systemd[1301]: Reached target Paths.2203machine # [ 70.536700] systemd[1301]: Reached target Timers.2204machine # [ 70.539344] systemd[1301]: Starting D-Bus User Message Bus Socket...2205machine # [ 70.541622] systemd[1301]: Starting Create User Files and Directories...2206machine # [ 70.586920] systemd[1301]: Listening on D-Bus User Message Bus Socket.2207machine # [ 70.590227] systemd[1301]: Finished Create User Files and Directories.2208machine # [ 70.591273] systemd[1301]: Reached target Sockets.2209machine # [ 70.591997] systemd[1301]: Reached target Basic System.2210machine # [ 70.597500] systemd[1]: Started User Manager for UID 60388.2211machine # [ 70.598369] systemd[1301]: Starting Run user-specific NixOS activation...2212machine # [ 70.651182] systemd[1301]: Finished Run user-specific NixOS activation.2213machine # [ 70.653213] systemd[1301]: Reached target Main User Target.2214machine # [ 70.656336] systemd[1301]: Startup finished in 257ms.2215machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.23 seconds)2216sshClient: sending keys 'whoami > /tmp/5\n'2217sshClient: (finished: sending keys 'whoami > /tmp/5\n', in 0.17 seconds)2218machine: waiting for file '/tmp/5'2219machine: (finished: waiting for file '/tmp/5', in 1.05 seconds)2220machine: must succeed: cat /tmp/52221machine: (finished: must succeed: cat /tmp/5, in 0.03 seconds)2222sshClient: sending keys 'exit\n'2223sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2224sshClient: sending keys 'exit\n'2225machine # [ 72.482569] sshd-session[1251]: Received disconnect from 2001:db8:1::2 port 43002:11: disconnected by user2226machine # [ 72.485646] sshd-session[1251]: Disconnected from user test-homed-user 2001:db8:1::2 port 430022227machine # [ 72.489873] sshd-session[1244]: pam_unix(sshd:session): session closed for user test-homed-user2228sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2229(finished: subtest: ssh as homed user, in 6.62 seconds)2230(finished: run the VM test script, in 73.74 seconds)2231machine # [ 72.492918] sshd-session[1244]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used.2232machine # [ 72.507338] systemd[1]: session-3.scope: Deactivated successfully.2233machine # [ 72.514720] systemd-logind[508]: Session 3 logged out. Waiting for processes to exit.2234machine # [ 72.523381] systemd-logind[508]: Removed session 3.2235sshClient # [ 72.565500] login[664]: pam_unix(login:session): session closed for user test-normal-user2236sshClient # [ 72.577877] systemd[1]: getty@tty1.service: Deactivated successfully.2237sshClient # [ 72.593059] systemd[1]: session-1.scope: Deactivated successfully.2238sshClient # [ 72.608641] systemd[1]: session-1.scope: Consumed 141ms CPU time over 36.686s wall clock time, 10.9M memory peak, 12K written to disk, 25.5K incoming IP traffic, 24.4K outgoing IP traffic.2239sshClient # [ 72.625340] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.2240sshClient # [ 72.627483] systemd-logind[491]: Session 1 logged out. Waiting for processes to exit.2241sshClient # [ 72.644379] systemd[1]: Started Getty on tty1.2242sshClient # [ 72.645459] systemd-logind[491]: Removed session 1.2243test script finished in 73.96s2244cleanup2245kill QemuMachine (pid 47)2246machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2247machine # [2026-10-18T02:44:48Z INFO virtiofsd] Client disconnected, shutting down2248machine # [2026-10-18T02:44:48Z INFO virtiofsd] Client disconnected, shutting down2249machine # [2026-10-18T02:44:48Z INFO virtiofsd] Client disconnected, shutting down2250kill QemuMachine (pid 48)2251sshClient # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2252sshClient # [2026-10-18T02:44:48Z INFO virtiofsd] Client disconnected, shutting down2253sshClient # [2026-10-18T02:44:48Z INFO virtiofsd] Client disconnected, shutting down2254sshClient # [2026-10-18T02:44:48Z INFO virtiofsd] Client disconnected, shutting down2255(finished: cleanup, in 0.30 seconds)2256additionally exposed symbols:2257 machine, sshClient,2258 vlan1,2259 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