vm-test-run-systemd-homed
checks.aarch64-linux.systemd-homed
· build #66
· 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...11sshClient # Disk image does not exist, creating the virtualisation disk image...12sshClient: QEMU running (pid 48)13machine: QEMU running (pid 47)14machine # Formatting '/build/vm-state-machine/tmp.RrKoxSsSrL', fmt=raw size=107374182415sshClient # Formatting '/build/vm-state-sshClient/tmp.sRKmDFmMC0', fmt=raw size=107374182416machine # mke2fs 1.47.4 (6-Mar-2025)17sshClient # mke2fs 1.47.4 (6-Mar-2025)18machine # Discarding device blocks: 0/262144 done19sshClient # Discarding device blocks: 0/262144 done20machine # Creating filesystem with 262144 4k blocks and 65536 inodes21sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes22machine # Filesystem UUID: d7841fcb-efde-4384-a049-60aa363e73d923sshClient # Filesystem UUID: 28c9ebd2-6f8c-431a-a998-66016d819d6c24machine # Superblock backups stored on blocks:25sshClient # Superblock backups stored on blocks:26machine # 32768, 98304, 163840, 22937627sshClient # 32768, 98304, 163840, 22937628machine # 29sshClient # 30machine # Allocating group tables: 0/8 done31sshClient # Allocating group tables: 0/8 done32machine # Writing inode tables: 0/8 done33sshClient # Writing inode tables: 0/8 done34machine # Creating journal (8192 blocks): done35sshClient # Creating journal (8192 blocks): done36machine # Writing superblocks and filesystem accounting information: 0/8 done37sshClient # Writing superblocks and filesystem accounting information: 0/8 done38machine # 39sshClient # 40machine # Virtualisation disk image created.41sshClient # Virtualisation disk image created.42machine # Starting virtiofs daemons...43sshClient # Starting virtiofs daemons...44machine # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)45sshClient # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)46machine # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether47sshClient # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether48machine # [2026-10-24T03:01:48Z INFO virtiofsd] Waiting for vhost-user socket connection...49sshClient # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)50machine # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)51sshClient # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether52machine # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether53sshClient # [2026-10-24T03:01:48Z INFO virtiofsd] Waiting for vhost-user socket connection...54machine # [2026-10-24T03:01:48Z INFO virtiofsd] Waiting for vhost-user socket connection...55sshClient # [2026-10-24T03:01:48Z INFO virtiofsd] Waiting for vhost-user socket connection...56machine # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)57sshClient # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)58machine # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether59sshClient # [2026-10-24T03:01:48Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether60machine # [2026-10-24T03:01:48Z INFO virtiofsd] Waiting for vhost-user socket connection...61sshClient # [2026-10-24T03:01:48Z INFO virtiofsd] Waiting for vhost-user socket connection...62machine # [2026-10-24T03:01:48Z INFO virtiofsd] Client connected, servicing requests63sshClient # [2026-10-24T03:01:48Z INFO virtiofsd] Client connected, servicing requests64machine # [2026-10-24T03:01:48Z INFO virtiofsd] Client connected, servicing requests65sshClient # [2026-10-24T03:01:48Z INFO virtiofsd] Client connected, servicing requests66machine # [2026-10-24T03:01:48Z INFO virtiofsd] Client connected, servicing requests67sshClient # [2026-10-24T03:01:48Z INFO virtiofsd] Client connected, servicing requests68(finished: start all VMs, in 0.56 seconds)69subtest: create systemd-homed user on first boot prompt70machine: waiting for unit systemd-homed.service71machine: waiting for the VM to finish booting72machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]73machine # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @179205830074machine # [ 0.000000] KASLR enabled75machine # [ 0.000000] random: crng init done76machine # [ 0.000000] Machine model: linux,dummy-virt77sshClient # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]78machine # [ 0.000000] efi: UEFI not found.79machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT80machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]81sshClient # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @179205830082machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]83sshClient # [ 0.000000] KASLR enabled84machine # [ 0.000000] Zone ranges:85sshClient # [ 0.000000] random: crng init done86machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]87sshClient # [ 0.000000] Machine model: linux,dummy-virt88machine # [ 0.000000] DMA32 empty89sshClient # [ 0.000000] efi: UEFI not found.90machine # [ 0.000000] Normal empty91machine # [ 0.000000] Device empty92sshClient # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT93machine # [ 0.000000] Movable zone start for each node94machine # [ 0.000000] Early memory node ranges95sshClient # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]96machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]97sshClient # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]98sshClient # [ 0.000000] Zone ranges:99machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]100sshClient # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]101machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000102sshClient # [ 0.000000] DMA32 empty103sshClient # [ 0.000000] Normal empty104machine # [ 0.000000] psci: probing for conduit method from DT.105sshClient # [ 0.000000] Device empty106machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.107sshClient # [ 0.000000] Movable zone start for each node108machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs109sshClient # [ 0.000000] Early memory node ranges110machine # [ 0.000000] psci: Trusted OS migration not required111sshClient # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]112machine # [ 0.000000] psci: SMC Calling Convention v1.1113sshClient # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]114machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)115sshClient # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000116sshClient # [ 0.000000] psci: probing for conduit method from DT.117machine # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u311296118sshClient # [ 0.000000] psci: PSCIv1.3 detected in firmware.119machine # [ 0.000000] Detected PIPT I-cache on CPU0120sshClient # [ 0.000000] psci: Using standard PSCI v0.2 function IDs121machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)122sshClient # [ 0.000000] psci: Trusted OS migration not required123machine # [ 0.000000] CPU features: detected: GICv3 CPU interface124sshClient # [ 0.000000] psci: SMC Calling Convention v1.1125machine # [ 0.000000] CPU features: detected: Spectre-v4126machine # [ 0.000000] CPU features: detected: Spectre-BHB127sshClient # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)128machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38129sshClient # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u311296130sshClient # [ 0.000000] Detected PIPT I-cache on CPU0131machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23132machine # [ 0.000000] alternatives: applying boot alternatives133sshClient # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)134sshClient # [ 0.000000] CPU features: detected: GICv3 CPU interface135sshClient # [ 0.000000] CPU features: detected: Spectre-v4136sshClient # [ 0.000000] CPU features: detected: Spectre-BHB137sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38138sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23139machine # [ 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/8ayak4q3g5jkd2lbri6jg1vyq03hs2gm-nixos-system-machine-test/init regInfo=/nix/.ro-store/j1jrigfa6k7c2dj3g3z6wckk1m6bn092-closure-info/registration console=ttyAMA0,115200n8 console=tty0140sshClient # [ 0.000000] alternatives: applying boot alternatives141machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/j1jrigfa6k7c2dj3g3z6wckk1m6bn092-closure-info/registration", will be passed to user space.142machine # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes143machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)144sshClient # [ 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/bwks7k3pfs60grb8wmcagz85qsrls588-nixos-system-sshClient-test/init regInfo=/nix/.ro-store/g9bc1s9i3i8fi927f95n1ikm32q4lmrr-closure-info/registration console=ttyAMA0,115200n8 console=tty0145machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)146machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB147sshClient # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/g9bc1s9i3i8fi927f95n1ikm32q4lmrr-closure-info/registration", will be passed to user space.148machine # [ 0.000000] software IO TLB: area num 1.149sshClient # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes150machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)151machine # [ 0.000000] Fallback order for Node 0: 0152sshClient # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)153machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144154sshClient # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)155machine # [ 0.000000] Policy zone: DMA156machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off157sshClient # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB158sshClient # [ 0.000000] software IO TLB: area num 1.159machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1160machine # [ 0.000000] allocated 2097152 bytes of page_ext161sshClient # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)162machine # [ 0.000000] ftrace: allocating 75301 entries in 296 pages163sshClient # [ 0.000000] Fallback order for Node 0: 0164machine # [ 0.000000] ftrace: allocated 296 pages with 3 groups165sshClient # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144166sshClient # [ 0.000000] Policy zone: DMA167machine # [ 0.000000] rcu: Hierarchical RCU implementation.168machine # [ 0.000000] rcu: RCU event tracing is enabled.169sshClient # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off170machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.171sshClient # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1172machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.173sshClient # [ 0.000000] allocated 2097152 bytes of page_ext174machine # [ 0.000000] Rude variant of Tasks RCU enabled.175sshClient # [ 0.000000] ftrace: allocating 75301 entries in 296 pages176machine # [ 0.000000] Tracing variant of Tasks RCU enabled.177sshClient # [ 0.000000] ftrace: allocated 296 pages with 3 groups178sshClient # [ 0.000000] rcu: Hierarchical RCU implementation.179machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.180sshClient # [ 0.000000] rcu: RCU event tracing is enabled.181machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1182sshClient # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.183sshClient # [ 0.000000] Trampoline variant of Tasks RCU enabled.184machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.185sshClient # [ 0.000000] Rude variant of Tasks RCU enabled.186machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.187sshClient # [ 0.000000] Tracing variant of Tasks RCU enabled.188sshClient # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.189machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.190machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0191sshClient # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1192machine # [ 0.000000] GICv3: 256 SPIs implemented193machine # [ 0.000000] GICv3: 0 Extended SPIs implemented194sshClient # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.195machine # [ 0.000000] Root IRQ handler: gic_handle_irq196machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI197sshClient # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.198machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0199sshClient # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.200machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000201sshClient # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0202machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]203sshClient # [ 0.000000] GICv3: 256 SPIs implemented204sshClient # [ 0.000000] GICv3: 0 Extended SPIs implemented205machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450f0000 (indirect, esz 8, psz 64K, shr 1)206sshClient # [ 0.000000] Root IRQ handler: gic_handle_irq207sshClient # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI208machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @45100000 (flat, esz 8, psz 64K, shr 1)209sshClient # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0210machine # [ 0.000000] GICv3: using LPI property table @0x0000000045110000211sshClient # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000212sshClient # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]213machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000214machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.215sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450f0000 (indirect, esz 8, psz 64K, shr 1)216machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns217sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @45100000 (flat, esz 8, psz 64K, shr 1)218machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).219sshClient # [ 0.000000] GICv3: using LPI property table @0x0000000045110000220sshClient # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000221machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns222sshClient # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.223machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns224machine # [ 0.000059] arm-pv: using stolen time PV225sshClient # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns226sshClient # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).227machine # [ 0.000580] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)228machine # [ 0.000800] Console: colour dummy device 80x25229machine # [ 0.000812] printk: legacy console [tty0] enabled230sshClient # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns231sshClient # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns232machine # [ 0.001016] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)233sshClient # [ 0.000034] arm-pv: using stolen time PV234machine # [ 0.001024] pid_max: default: 32768 minimum: 301235machine # [ 0.001144] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima236sshClient # [ 0.000445] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)237machine # [ 0.001273] landlock: Up and running.238sshClient # [ 0.000628] Console: colour dummy device 80x25239machine # [ 0.001277] Yama: becoming mindful.240sshClient # [ 0.000636] printk: legacy console [tty0] enabled241machine # [ 0.001765] LSM support for eBPF active242machine # [ 0.001903] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)243sshClient # [ 0.000834] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)244sshClient # [ 0.000841] pid_max: default: 32768 minimum: 301245machine # [ 0.001932] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)246machine # [ 0.004332] rcu: Hierarchical SRCU implementation.247sshClient # [ 0.000929] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima248sshClient # [ 0.001080] landlock: Up and running.249machine # [ 0.004340] rcu: Max phase no-delay instances is 1000.250sshClient # [ 0.001084] Yama: becoming mindful.251machine # [ 0.005716] fsl-mc MSI: its@8080000 domain created252sshClient # [ 0.001587] LSM support for eBPF active253machine # [ 0.005843] EFI services will not be available.254sshClient # [ 0.001721] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)255machine # [ 0.005952] smp: Bringing up secondary CPUs ...256sshClient # [ 0.001750] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)257machine # [ 0.005964] smp: Brought up 1 node, 1 CPU258sshClient # [ 0.003898] rcu: Hierarchical SRCU implementation.259machine # [ 0.005967] SMP: Total of 1 processors activated.260sshClient # [ 0.003905] rcu: Max phase no-delay instances is 1000.261machine # [ 0.005970] CPU: All CPU(s) started at EL1262sshClient # [ 0.005232] fsl-mc MSI: its@8080000 domain created263machine # [ 0.005981] CPU features: detected: Branch Target Identification264sshClient # [ 0.005355] EFI services will not be available.265sshClient # [ 0.005433] smp: Bringing up secondary CPUs ...266machine # [ 0.005986] CPU features: detected: ARMv8.4 Translation Table Level267sshClient # [ 0.005443] smp: Brought up 1 node, 1 CPU268sshClient # [ 0.005446] SMP: Total of 1 processors activated.269machine # [ 0.005989] CPU features: detected: Instruction cache invalidation not required for I/D coherence270sshClient # [ 0.005449] CPU: All CPU(s) started at EL1271machine # [ 0.005993] CPU features: detected: Data cache clean to the PoU not required for I/D coherence272sshClient # [ 0.005460] CPU features: detected: Branch Target Identification273machine # [ 0.006002] CPU features: detected: Common not Private translations274sshClient # [ 0.005465] CPU features: detected: ARMv8.4 Translation Table Level275machine # [ 0.006006] CPU features: detected: CRC32 instructions276sshClient # [ 0.005468] CPU features: detected: Instruction cache invalidation not required for I/D coherence277machine # [ 0.006008] CPU features: detected: Data cache clean to Point of Deep Persistence278sshClient # [ 0.005472] CPU features: detected: Data cache clean to the PoU not required for I/D coherence279machine # [ 0.006012] CPU features: detected: Data cache clean to Point of Persistence280sshClient # [ 0.005480] CPU features: detected: Common not Private translations281machine # [ 0.006015] CPU features: detected: Data independent timing control (DIT)282sshClient # [ 0.005484] CPU features: detected: CRC32 instructions283machine # [ 0.006018] CPU features: detected: E0PD284sshClient # [ 0.005487] CPU features: detected: Data cache clean to Point of Deep Persistence285machine # [ 0.006021] CPU features: detected: Enhanced Counter Virtualization286sshClient # [ 0.005490] CPU features: detected: Data cache clean to Point of Persistence287machine # [ 0.006023] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)288machine # [ 0.006027] CPU features: detected: Enhanced Virtualization Traps289sshClient # [ 0.005493] CPU features: detected: Data independent timing control (DIT)290sshClient # [ 0.005496] CPU features: detected: E0PD291machine # [ 0.006030] CPU features: detected: Fine Grained Traps292sshClient # [ 0.005499] CPU features: detected: Enhanced Counter Virtualization293machine # [ 0.006033] CPU features: detected: Generic authentication (architected QARMA5 algorithm)294sshClient # [ 0.005502] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)295machine # [ 0.006038] CPU features: detected: RCpc load-acquire (LDAPR)296sshClient # [ 0.005505] CPU features: detected: Enhanced Virtualization Traps297machine # [ 0.006041] CPU features: detected: LSE atomic instructions298sshClient # [ 0.005509] CPU features: detected: Fine Grained Traps299machine # [ 0.006044] CPU features: detected: Privileged Access Never300machine # [ 0.006046] CPU features: detected: PMUv3301sshClient # [ 0.005513] CPU features: detected: Generic authentication (architected QARMA5 algorithm)302machine # [ 0.006049] CPU features: detected: RAS Extension Support303sshClient # [ 0.005518] CPU features: detected: RCpc load-acquire (LDAPR)304machine # [ 0.006052] CPU features: detected: RASv1p1 Extension Support305sshClient # [ 0.005522] CPU features: detected: LSE atomic instructions306machine # [ 0.006055] CPU features: detected: Random Number Generator307sshClient # [ 0.005525] CPU features: detected: Privileged Access Never308machine # [ 0.006060] CPU features: detected: Speculation barrier (SB)309sshClient # [ 0.005527] CPU features: detected: PMUv3310machine # [ 0.006063] CPU features: detected: Stage-2 Force Write-Back311sshClient # [ 0.005530] CPU features: detected: RAS Extension Support312sshClient # [ 0.005533] CPU features: detected: RASv1p1 Extension Support313machine # [ 0.006066] CPU features: detected: TLB range maintenance instructions314sshClient # [ 0.005536] CPU features: detected: Random Number Generator315machine # [ 0.006071] CPU features: detected: Speculative Store Bypassing Safe (SSBS)316sshClient # [ 0.005546] CPU features: detected: Speculation barrier (SB)317machine # [ 0.006109] alternatives: applying system-wide alternatives318sshClient # [ 0.005549] CPU features: detected: Stage-2 Force Write-Back319machine # [ 0.009259] CPU features: detected: BBM Level 2 without TLB conflict abort320sshClient # [ 0.005552] CPU features: detected: TLB range maintenance instructions321sshClient # [ 0.005556] CPU features: detected: Speculative Store Bypassing Safe (SSBS)322machine # [ 0.009474] Memory: 886676K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 120600K reserved, 32768K cma-reserved)323sshClient # [ 0.005595] alternatives: applying system-wide alternatives324machine # [ 0.009900] devtmpfs: initialized325sshClient # [ 0.009070] CPU features: detected: BBM Level 2 without TLB conflict abort326machine # [ 0.012475] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)327machine # [ 0.012503] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).328sshClient # [ 0.009246] Memory: 886664K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 120600K reserved, 32768K cma-reserved)329machine # [ 0.012719] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL330sshClient # [ 0.009706] devtmpfs: initialized331machine # [ 0.012724] 0 pages in range for non-PLT usage332sshClient # [ 0.011630] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)333machine # [ 0.012726] 506368 pages in range for PLT usage334machine # [ 0.012875] pinctrl core: initialized pinctrl subsystem335sshClient # [ 0.011657] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).336machine # [ 0.013887] DMI not present or invalid.337sshClient # [ 0.011886] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL338machine # [ 0.017651] NET: Registered PF_NETLINK/PF_ROUTE protocol family339sshClient # [ 0.011892] 0 pages in range for non-PLT usage340sshClient # [ 0.011893] 506368 pages in range for PLT usage341machine # [ 0.020177] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations342sshClient # [ 0.012012] pinctrl core: initialized pinctrl subsystem343machine # [ 0.020326] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations344sshClient # [ 0.012939] DMI not present or invalid.345sshClient # [ 0.016551] NET: Registered PF_NETLINK/PF_ROUTE protocol family346machine # [ 0.020497] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations347machine # [ 0.020524] audit: initializing netlink subsys (disabled)348sshClient # [ 0.019463] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations349machine # [ 0.021198] thermal_sys: Registered thermal governor 'fair_share'350sshClient # [ 0.019682] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations351machine # [ 0.021201] thermal_sys: Registered thermal governor 'bang_bang'352sshClient # [ 0.019850] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations353machine # [ 0.021204] thermal_sys: Registered thermal governor 'step_wise'354sshClient # [ 0.019881] audit: initializing netlink subsys (disabled)355machine # [ 0.021208] thermal_sys: Registered thermal governor 'user_space'356sshClient # [ 0.020541] thermal_sys: Registered thermal governor 'fair_share'357machine # [ 0.021210] thermal_sys: Registered thermal governor 'power_allocator'358sshClient # [ 0.020544] thermal_sys: Registered thermal governor 'bang_bang'359machine # [ 0.021247] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1360sshClient # [ 0.020548] thermal_sys: Registered thermal governor 'step_wise'361machine # [ 0.021258] cpuidle: using governor ladder362sshClient # [ 0.020551] thermal_sys: Registered thermal governor 'user_space'363machine # [ 0.021265] cpuidle: using governor menu364sshClient # [ 0.020554] thermal_sys: Registered thermal governor 'power_allocator'365machine # [ 0.021487] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.366machine # [ 0.021508] ASID allocator initialised with 65536 entries367sshClient # [ 0.020595] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1368machine # [ 0.022836] Serial: AMBA PL011 UART driver369sshClient # [ 0.020605] cpuidle: using governor ladder370sshClient # [ 0.020612] cpuidle: using governor menu371machine # [ 0.028963] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1372sshClient # [ 0.020840] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.373machine # [ 0.029138] printk: console [ttyAMA0] enabled374sshClient # [ 0.020859] ASID allocator initialised with 65536 entries375sshClient # [ 0.022272] Serial: AMBA PL011 UART driver376sshClient # [ 0.028235] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1377sshClient # [ 0.028429] printk: console [ttyAMA0] enabled378machine # [ 0.159917] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages379machine # [ 0.159931] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page380machine # [ 0.159934] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages381machine # [ 0.159937] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page382machine # [ 0.159940] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages383sshClient # [ 0.158341] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages384machine # [ 0.159942] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page385sshClient # [ 0.158355] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page386machine # [ 0.159945] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages387sshClient # [ 0.158359] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages388machine # [ 0.159948] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page389sshClient # [ 0.158362] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page390machine # [ 0.161314] fbcon: Taking over console391machine # [ 0.161326] ACPI: Interpreter disabled.392sshClient # [ 0.158364] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages393sshClient # [ 0.158367] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page394machine # [ 0.171686] iommu: Default domain type: Translated395sshClient # [ 0.158370] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages396machine # [ 0.171693] iommu: DMA domain TLB invalidation policy: strict mode397sshClient # [ 0.158373] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page398machine # [ 0.172230] SCSI subsystem initialized399sshClient # [ 0.159787] fbcon: Taking over console400sshClient # [ 0.159799] ACPI: Interpreter disabled.401sshClient # [ 0.169718] iommu: Default domain type: Translated402machine # [ 0.174387] usbcore: registered new interface driver usbfs403sshClient # [ 0.169725] iommu: DMA domain TLB invalidation policy: strict mode404machine # [ 0.174421] usbcore: registered new interface driver hub405sshClient # [ 0.170176] SCSI subsystem initialized406machine # [ 0.174437] usbcore: registered new device driver usb407machine # [ 0.174763] pps_core: LinuxPPS API ver. 1 registered408machine # [ 0.174767] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>409sshClient # [ 0.172434] usbcore: registered new interface driver usbfs410machine # [ 0.174789] PTP clock support registered411machine # [ 0.174833] EDAC MC: Ver: 3.0.0412sshClient # [ 0.172467] usbcore: registered new interface driver hub413sshClient # [ 0.172491] usbcore: registered new device driver usb414sshClient # [ 0.172820] pps_core: LinuxPPS API ver. 1 registered415sshClient # [ 0.172823] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>416sshClient # [ 0.172840] PTP clock support registered417sshClient # [ 0.172882] EDAC MC: Ver: 3.0.0418machine # [ 0.181108] scmi_core: SCMI protocol bus registered419machine # [ 0.181542] FPGA manager framework420machine # [ 0.184629] vgaarb: loaded421sshClient # [ 0.179155] scmi_core: SCMI protocol bus registered422sshClient # [ 0.179539] FPGA manager framework423machine # [ 0.186392] clocksource: Switched to clocksource arch_sys_counter424sshClient # [ 0.182692] vgaarb: loaded425sshClient # [ 0.184475] clocksource: Switched to clocksource arch_sys_counter426machine # [ 0.193528] VFS: Disk quotas dquot_6.6.0427machine # [ 0.193566] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)428sshClient # [ 0.191036] VFS: Disk quotas dquot_6.6.0429sshClient # [ 0.191077] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)430machine # [ 0.196164] netfs: FS-Cache loaded431machine # [ 0.196305] pnp: PnP ACPI: disabled432sshClient # [ 0.194223] netfs: FS-Cache loaded433sshClient # [ 0.194372] pnp: PnP ACPI: disabled434sshClient # [ 0.199704] NET: Registered PF_INET protocol family435machine # [ 0.202438] NET: Registered PF_INET protocol family436sshClient # [ 0.199891] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)437machine # [ 0.202619] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)438sshClient # [ 0.233771] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)439sshClient # [ 0.233819] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)440sshClient # [ 0.233845] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)441sshClient # [ 0.233893] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)442sshClient # [ 0.233966] TCP: Hash tables configured (established 8192 bind 8192)443machine # [ 0.237490] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)444sshClient # [ 0.234062] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)445machine # [ 0.237538] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)446sshClient # [ 0.234127] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)447sshClient # [ 0.234204] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)448machine # [ 0.237564] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)449sshClient # [ 0.234284] NET: Registered PF_UNIX/PF_LOCAL protocol family450machine # [ 0.237617] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)451sshClient # [ 0.234310] NET: Registered PF_XDP protocol family452machine # [ 0.237690] TCP: Hash tables configured (established 8192 bind 8192)453sshClient # [ 0.234327] PCI: CLS 0 bytes, default 64454sshClient # [ 0.234775] Trying to unpack rootfs image as initramfs...455machine # [ 0.237787] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)456machine # [ 0.237854] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)457machine # [ 0.237878] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)458sshClient # [ 0.246899] kvm [1]: HYP mode not available459machine # [ 0.238012] NET: Registered PF_UNIX/PF_LOCAL protocol family460machine # [ 0.238033] NET: Registered PF_XDP protocol family461machine # [ 0.238050] PCI: CLS 0 bytes, default 64462machine # [ 0.238318] Trying to unpack rootfs image as initramfs...463machine # [ 0.257048] kvm [1]: HYP mode not available464sshClient # [ 0.422866] Initialise system trusted keyrings465sshClient # [ 0.422907] Key type blacklist registered466sshClient # [ 0.425713] workingset: timestamp_bits=42 max_order=18 bucket_order=0467machine # [ 0.431027] Initialise system trusted keyrings468sshClient # [ 0.426195] squashfs: version 4.0 (2009/01/31) Phillip Lougher469machine # [ 0.431067] Key type blacklist registered470sshClient # [ 0.426248] 9p: Installing v9fs 9p2000 file system support471sshClient # [ 0.426349] integrity: Platform Keyring initialized472sshClient # [ 0.426360] integrity: Machine keyring initialized473machine # [ 0.435629] workingset: timestamp_bits=42 max_order=18 bucket_order=0474machine # [ 0.436281] squashfs: version 4.0 (2009/01/31) Phillip Lougher475machine # [ 0.436335] 9p: Installing v9fs 9p2000 file system support476machine # [ 0.436433] integrity: Platform Keyring initialized477machine # [ 0.436446] integrity: Machine keyring initialized478sshClient # [ 0.454346] Key type asymmetric registered479machine # [ 0.460108] Key type asymmetric registered480sshClient # [ 0.454363] Asymmetric key parser 'x509' registered481machine # [ 0.460125] Asymmetric key parser 'x509' registered482sshClient # [ 0.454448] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)483sshClient # [ 0.454584] io scheduler mq-deadline registered484machine # [ 0.460213] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)485sshClient # [ 0.454589] io scheduler kyber registered486machine # [ 0.464770] io scheduler mq-deadline registered487machine # [ 0.464782] io scheduler kyber registered488sshClient # [ 0.467460] pl061_gpio 9030000.pl061: PL061 GPIO chip registered489machine # [ 0.477533] pl061_gpio 9030000.pl061: PL061 GPIO chip registered490machine # [ 0.478139] ledtrig-cpu: registered to indicate activity on CPUs491machine # [ 0.480331] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:492machine # [ 0.480361] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000493machine # [ 0.480376] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000494sshClient # [ 0.469317] ledtrig-cpu: registered to indicate activity on CPUs495machine # [ 0.480383] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000496sshClient # [ 0.469746] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:497machine # [ 0.480402] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits498sshClient # [ 0.469762] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000499machine # [ 0.480425] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]500sshClient # [ 0.469777] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000501machine # [ 0.480509] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00502machine # [ 0.480516] pci_bus 0000:00: root bus resource [bus 00-ff]503sshClient # [ 0.469783] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000504machine # [ 0.480521] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]505sshClient # [ 0.469804] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits506machine # [ 0.480524] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]507sshClient # [ 0.469835] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]508machine # [ 0.480527] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]509sshClient # [ 0.469942] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00510machine # [ 0.480588] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint511sshClient # [ 0.469950] pci_bus 0000:00: root bus resource [bus 00-ff]512machine # [ 0.481077] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint513sshClient # [ 0.469954] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]514machine # [ 0.481271] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]515sshClient # [ 0.469958] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]516machine # [ 0.481283] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]517sshClient # [ 0.469961] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]518machine # [ 0.481309] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]519sshClient # [ 0.470032] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint520machine # [ 0.481321] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]521sshClient # [ 0.470492] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint522machine # [ 0.481804] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint523sshClient # [ 0.470722] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]524machine # [ 0.481997] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]525sshClient # [ 0.470734] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]526machine # [ 0.482010] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]527sshClient # [ 0.470763] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]528machine # [ 0.482038] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]529sshClient # [ 0.470776] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]530sshClient # [ 0.471260] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint531sshClient # [ 0.471457] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]532sshClient # [ 0.471468] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]533sshClient # [ 0.471495] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]534sshClient # [ 0.471970] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint535sshClient # [ 0.472157] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]536sshClient # [ 0.472169] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]537sshClient # [ 0.472195] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]538sshClient # [ 0.504697] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint539machine # [ 0.510658] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint540sshClient # [ 0.504904] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]541machine # [ 0.510868] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]542machine # [ 0.510882] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]543machine # [ 0.510908] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]544sshClient # [ 0.504917] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]545sshClient # [ 0.504945] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]546sshClient # [ 0.504957] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]547machine # [ 0.511433] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint548sshClient # [ 0.505446] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint549machine # [ 0.511632] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]550sshClient # [ 0.505637] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]551machine # [ 0.511644] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]552sshClient # [ 0.505663] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]553machine # [ 0.511671] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]554sshClient # [ 0.506142] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint555machine # [ 0.511684] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]556sshClient # [ 0.506345] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]557machine # [ 0.512224] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint558sshClient # [ 0.506377] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]559machine # [ 0.512425] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]560sshClient # [ 0.506895] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint561machine # [ 0.512454] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]562sshClient # [ 0.507085] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]563machine # [ 0.512940] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint564sshClient # [ 0.507356] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint565machine # [ 0.513150] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]566sshClient # [ 0.507548] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]567machine # [ 0.513179] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]568sshClient # [ 0.507575] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]569machine # [ 0.513614] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint570sshClient # [ 0.508085] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint571machine # [ 0.513805] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]572sshClient # [ 0.508284] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]573machine # [ 0.514074] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint574sshClient # [ 0.508312] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]575machine # [ 0.514269] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]576machine # [ 0.514295] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]577sshClient # [ 0.525859] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint578machine # [ 0.532675] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint579sshClient # [ 0.526060] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]580machine # [ 0.532872] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]581sshClient # [ 0.526087] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]582machine # [ 0.532899] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]583sshClient # [ 0.526561] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint584machine # [ 0.533373] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint585sshClient # [ 0.526857] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]586machine # [ 0.533567] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]587sshClient # [ 0.526874] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]588machine # [ 0.533595] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]589sshClient # [ 0.526900] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]590machine # [ 0.534081] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint591sshClient # [ 0.527372] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint592machine # [ 0.534383] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]593sshClient # [ 0.527563] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]594sshClient # [ 0.527574] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]595machine # [ 0.541732] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]596sshClient # [ 0.527600] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]597machine # [ 0.541759] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]598sshClient # [ 0.528258] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned599machine # [ 0.542254] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint600sshClient # [ 0.528265] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned601sshClient # [ 0.528269] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned602sshClient # [ 0.528319] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned603sshClient # [ 0.528367] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned604sshClient # [ 0.528413] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned605sshClient # [ 0.528460] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned606machine # [ 0.550516] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]607machine # [ 0.550538] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]608machine # [ 0.550564] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]609machine # [ 0.551295] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned610machine # [ 0.551302] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned611machine # [ 0.551306] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned612machine # [ 0.551353] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned613machine # [ 0.551398] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned614sshClient # [ 0.552605] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned615machine # [ 0.551445] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned616sshClient # [ 0.552668] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned617machine # [ 0.551490] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned618sshClient # [ 0.552715] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned619machine # [ 0.551540] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned620machine # [ 0.551587] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned621sshClient # [ 0.552766] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned622machine # [ 0.551633] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned623sshClient # [ 0.552812] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned624machine # [ 0.551678] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned625sshClient # [ 0.552956] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned626sshClient # [ 0.553039] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned627machine # [ 0.551724] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned628sshClient # [ 0.553065] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned629machine # [ 0.551858] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned630sshClient # [ 0.553084] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned631machine # [ 0.551903] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned632sshClient # [ 0.553104] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned633machine # [ 0.551921] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned634sshClient # [ 0.553124] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned635machine # [ 0.551940] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned636sshClient # [ 0.553142] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned637machine # [ 0.551958] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned638sshClient # [ 0.553160] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned639machine # [ 0.551976] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned640sshClient # [ 0.553180] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned641machine # [ 0.551994] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned642sshClient # [ 0.553199] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned643machine # [ 0.552012] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned644sshClient # [ 0.553217] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned645machine # [ 0.552047] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned646sshClient # [ 0.553238] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned647machine # [ 0.552066] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned648sshClient # [ 0.553256] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned649machine # [ 0.552084] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned650sshClient # [ 0.553274] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned651machine # [ 0.552103] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned652sshClient # [ 0.553292] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned653machine # [ 0.552122] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned654sshClient # [ 0.553309] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned655machine # [ 0.552140] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned656sshClient # [ 0.553327] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned657machine # [ 0.552158] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned658sshClient # [ 0.553344] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned659machine # [ 0.552175] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned660sshClient # [ 0.553362] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned661machine # [ 0.552193] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned662sshClient # [ 0.553392] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]663machine # [ 0.552211] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned664sshClient # [ 0.553397] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]665machine # [ 0.552229] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned666sshClient # [ 0.553400] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]667machine # [ 0.552254] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]668sshClient # [ 0.554287] pci 0000:00:07.0: enabling device (0000 -> 0002)669machine # [ 0.552260] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]670machine # [ 0.552262] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]671machine # [ 0.553158] pci 0000:00:07.0: enabling device (0000 -> 0002)672sshClient # [ 0.600955] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)673machine # [ 0.608838] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)674sshClient # [ 0.603213] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)675machine # [ 0.619187] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)676machine # [ 0.622040] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)677sshClient # [ 0.621231] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)678sshClient # [ 0.623493] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)679sshClient # [ 0.627426] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)680machine # [ 0.631891] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)681sshClient # [ 0.630791] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)682machine # [ 0.642496] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)683machine # [ 0.644830] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)684sshClient # [ 0.641656] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)685machine # [ 0.648526] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)686sshClient # [ 0.643749] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)687sshClient # [ 0.647747] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)688machine # [ 0.658542] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)689machine # [ 0.660985] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)690sshClient # [ 0.658108] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)691machine # [ 0.664876] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)692sshClient # [ 0.661716] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)693machine # [ 0.668886] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)694sshClient # [ 0.675665] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled695sshClient # [ 0.678758] msm_serial: driver initialized696sshClient # [ 0.678906] SuperH (H)SCI(F) driver initialized697sshClient # [ 0.678976] STM32 USART driver initialized698machine # [ 0.691949] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled699machine # [ 0.694096] msm_serial: driver initialized700machine # [ 0.694257] SuperH (H)SCI(F) driver initialized701machine # [ 0.694317] STM32 USART driver initialized702sshClient # [ 0.719143] loop: module loaded703sshClient # [ 0.719372] virtio_blk virtio2: 1/0/0 default/read/poll queues704sshClient # [ 0.720391] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)705machine # [ 0.736000] loop: module loaded706machine # [ 0.736276] virtio_blk virtio2: 1/0/0 default/read/poll queues707machine # [ 0.737144] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)708sshClient # [ 0.732646] megasas: 07.734.00.00-rc1709sshClient # [ 0.733758] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]710sshClient # [ 0.736014] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000711sshClient # [ 0.736041] Intel/Sharp Extended Query Table at 0x0031712machine # [ 0.743216] megasas: 07.734.00.00-rc1713machine # [ 0.744007] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]714machine # [ 0.746229] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000715sshClient # [ 0.741446] Using buffer write method716machine # [ 0.746252] Intel/Sharp Extended Query Table at 0x0031717sshClient # [ 0.741499] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]718sshClient # [ 0.743441] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000719sshClient # [ 0.743464] Intel/Sharp Extended Query Table at 0x0031720machine # [ 0.759671] Using buffer write method721machine # [ 0.759758] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]722machine # [ 0.761732] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000723machine # [ 0.761755] Intel/Sharp Extended Query Table at 0x0031724sshClient # [ 0.758586] Using buffer write method725sshClient # [ 0.758614] Concatenating MTD devices:726sshClient # [ 0.758616] (0): "0.flash"727sshClient # [ 0.758747] (1): "0.flash"728sshClient # [ 0.758749] into device "0.flash"729machine # [ 0.767090] Using buffer write method730machine # [ 0.767116] Concatenating MTD devices:731machine # [ 0.767118] (0): "0.flash"732machine # [ 0.767121] (1): "0.flash"733machine # [ 0.767123] into device "0.flash"734sshClient # [ 1.067760] Freeing initrd memory: 26060K735machine # [ 1.072595] Freeing initrd memory: 26060K736sshClient # [ 1.074813] tun: Universal TUN/TAP device driver, 1.6737machine # [ 1.079822] tun: Universal TUN/TAP device driver, 1.6738sshClient # [ 1.079464] thunder_xcv, ver 1.0739sshClient # [ 1.079526] thunder_bgx, ver 1.0740machine # [ 1.084845] thunder_xcv, ver 1.0741sshClient # [ 1.079548] nicpf, ver 1.0742machine # [ 1.084882] thunder_bgx, ver 1.0743machine # [ 1.084903] nicpf, ver 1.0744sshClient # [ 1.080141] e1000: Intel(R) PRO/1000 Network Driver745machine # [ 1.085497] e1000: Intel(R) PRO/1000 Network Driver746sshClient # [ 1.080147] e1000: Copyright (c) 1999-2006 Intel Corporation.747machine # [ 1.085502] e1000: Copyright (c) 1999-2006 Intel Corporation.748machine # [ 1.085529] e1000e: Intel(R) PRO/1000 Network Driver749sshClient # [ 1.080175] e1000e: Intel(R) PRO/1000 Network Driver750sshClient # [ 1.080183] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.751machine # [ 1.085540] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.752sshClient # [ 1.080210] igb: Intel(R) Gigabit Ethernet Network Driver753sshClient # [ 1.080214] igb: Copyright (c) 2007-2014 Intel Corporation.754machine # [ 1.085566] igb: Intel(R) Gigabit Ethernet Network Driver755machine # [ 1.085569] igb: Copyright (c) 2007-2014 Intel Corporation.756sshClient # [ 1.080236] igbvf: Intel(R) Gigabit Virtual Function Network Driver757sshClient # [ 1.080240] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.758machine # [ 1.085590] igbvf: Intel(R) Gigabit Virtual Function Network Driver759sshClient # [ 1.080396] sky2: driver version 1.30760machine # [ 1.085594] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.761machine # [ 1.085736] sky2: driver version 1.30762sshClient # [ 1.089816] usbcore: registered new interface driver usb-storage763machine # [ 1.095320] usbcore: registered new interface driver usb-storage764sshClient # [ 1.089878] usbcore: registered new interface driver usbserial_generic765machine # [ 1.095446] usbcore: registered new interface driver usbserial_generic766sshClient # [ 1.089890] usbserial: USB Serial support registered for generic767machine # [ 1.095459] usbserial: USB Serial support registered for generic768sshClient # [ 1.090547] hv_vmbus: registering driver hyperv_keyboard769machine # [ 1.096280] hv_vmbus: registering driver hyperv_keyboard770sshClient # [ 1.092157] ehci-pci 0000:00:07.0: EHCI Host Controller771sshClient # [ 1.092187] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1772machine # [ 1.097832] ehci-pci 0000:00:07.0: EHCI Host Controller773machine # [ 1.097877] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1774sshClient # [ 1.092429] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000775machine # [ 1.098134] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000776machine # [ 1.104192] rtc-pl031 9010000.pl031: registered as rtc0777machine # [ 1.104225] rtc-pl031 9010000.pl031: setting system clock to 2026-10-24T03:01:49 UTC (1792810909)778machine # [ 1.104715] i2c_dev: i2c /dev entries driver779machine # [ 1.106483] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00780sshClient # [ 1.098522] rtc-pl031 9010000.pl031: registered as rtc0781machine # [ 1.106891] hub 1-0:1.0: USB hub found782machine # [ 1.106910] hub 1-0:1.0: 6 ports detected783sshClient # [ 1.098549] rtc-pl031 9010000.pl031: setting system clock to 2026-10-24T03:01:49 UTC (1792810909)784sshClient # [ 1.099032] i2c_dev: i2c /dev entries driver785sshClient # [ 1.102551] sdhci: Secure Digital Host Controller Interface driver786sshClient # [ 1.102559] sdhci: Copyright(c) Pierre Ossman787sshClient # [ 1.102970] Synopsys Designware Multimedia Card Interface Driver788sshClient # [ 1.103366] sdhci-pltfm: SDHCI platform and OF driver helper789sshClient # [ 1.104546] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00790sshClient # [ 1.104897] hub 1-0:1.0: USB hub found791sshClient # [ 1.104915] hub 1-0:1.0: 6 ports detected792machine # [ 1.114593] sdhci: Secure Digital Host Controller Interface driver793machine # [ 1.114607] sdhci: Copyright(c) Pierre Ossman794machine # [ 1.114914] Synopsys Designware Multimedia Card Interface Driver795machine # [ 1.115320] sdhci-pltfm: SDHCI platform and OF driver helper796machine # [ 1.117167] hid: raw HID events driver (C) Jiri Kosina797sshClient # [ 1.113343] hid: raw HID events driver (C) Jiri Kosina798machine # [ 1.117452] usbcore: registered new interface driver usbhid799sshClient # [ 1.113613] usbcore: registered new interface driver usbhid800sshClient # [ 1.113617] usbhid: USB HID core driver801machine # [ 1.117457] usbhid: USB HID core driver802sshClient # [ 1.114933] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available803sshClient # [ 1.119791] drop_monitor: Initializing network drop monitor service804sshClient # [ 1.120020] NET: Registered PF_INET6 protocol family805machine # [ 1.123915] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available806machine # [ 1.125809] drop_monitor: Initializing network drop monitor service807machine # [ 1.125981] NET: Registered PF_INET6 protocol family808sshClient # [ 1.122118] Segment Routing with IPv6809sshClient # [ 1.122146] In-situ OAM (IOAM) with IPv6810machine # [ 1.129444] Segment Routing with IPv6811machine # [ 1.129461] In-situ OAM (IOAM) with IPv6812sshClient # [ 1.122175] NET: Registered PF_PACKET protocol family813sshClient # [ 1.122243] 9pnet: Installing 9P2000 support814machine # [ 1.129494] NET: Registered PF_PACKET protocol family815sshClient # [ 1.122292] Key type dns_resolver registered816machine # [ 1.129582] 9pnet: Installing 9P2000 support817machine # [ 1.129634] Key type dns_resolver registered818sshClient # [ 1.131951] registered taskstats version 1819sshClient # [ 1.132104] Loading compiled-in X.509 certificates820machine # [ 1.139278] registered taskstats version 1821machine # [ 1.139434] Loading compiled-in X.509 certificates822sshClient # [ 1.141380] Demotion targets for Node 0: null823machine # [ 1.148912] Demotion targets for Node 0: null824sshClient # [ 1.141502] Key type .fscrypt registered825machine # [ 1.149051] Key type .fscrypt registered826sshClient # [ 1.141506] Key type fscrypt-provisioning registered827machine # [ 1.149055] Key type fscrypt-provisioning registered828sshClient # [ 1.141618] ima: No TPM chip found, activating TPM-bypass!829machine # [ 1.149168] ima: No TPM chip found, activating TPM-bypass!830sshClient # [ 1.141641] ima: Allocated hash algorithm: sha1831machine # [ 1.149193] ima: Allocated hash algorithm: sha1832sshClient # [ 1.141664] ima: No architecture policies found833machine # [ 1.149226] ima: No architecture policies found834sshClient # [ 1.142429] input: gpio-keys as /devices/platform/gpio-keys/input/input0835machine # [ 1.150071] input: gpio-keys as /devices/platform/gpio-keys/input/input0836sshClient # [ 1.166828] clk: Disabling unused clocks837sshClient # [ 1.166848] PM: genpd: Disabling unused power domains838machine # [ 1.174919] clk: Disabling unused clocks839machine # [ 1.174942] PM: genpd: Disabling unused power domains840sshClient # [ 1.172852] Freeing unused kernel memory: 7808K841sshClient # [ 1.173096] Run /init as init process842machine # [ 1.181163] Freeing unused kernel memory: 7808K843machine # [ 1.181390] Run /init as init process844sshClient # [ 1.191040] fuse: module verification failed: signature and/or required key missing - tainting kernel845sshClient # [ 1.195034] fuse: init (API version 7.45)846machine # [ 1.199588] fuse: module verification failed: signature and/or required key missing - tainting kernel847sshClient # [ 1.198850] virtiofs virtio6: discovered new tag: nix-store848machine # [ 1.203845] fuse: init (API version 7.45)849sshClient # [ 1.199683] virtiofs virtio6: virtio_fs_setup_dax: No cache capability850machine # [ 1.207762] virtiofs virtio6: discovered new tag: nix-store851machine # [ 1.208611] virtiofs virtio6: virtio_fs_setup_dax: No cache capability852sshClient # [ 1.214244] virtiofs virtio7: discovered new tag: shared853sshClient # [ 1.215110] virtiofs virtio7: virtio_fs_setup_dax: No cache capability854sshClient # [ 1.216472] virtiofs virtio8: discovered new tag: xchg855sshClient # [ 1.219532] virtiofs virtio8: virtio_fs_setup_dax: No cache capability856machine # [ 1.224401] virtiofs virtio7: discovered new tag: shared857machine # [ 1.225305] virtiofs virtio7: virtio_fs_setup_dax: No cache capability858machine # [ 1.228290] virtiofs virtio8: discovered new tag: xchg859machine # [ 1.229113] virtiofs virtio8: virtio_fs_setup_dax: No cache capability860sshClient # [ 1.231403] systemd[1]: Successfully made /usr/ read-only.861sshClient # [ 1.231893] systemd[1]: systemd 263-0f0682b 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)862machine # [ 1.241558] systemd[1]: Successfully made /usr/ read-only.863sshClient # [ 1.231908] systemd[1]: Detected virtualization qemu.864sshClient # [ 1.231930] systemd[1]: Detected architecture arm64.865sshClient # [ 1.231936] systemd[1]: Running in initrd.866sshClient # [ 1.232251] systemd[1]: Initializing machine ID from random generator.867sshClient # [ 1.232349] systemd[1]: Hostname set to <sshClient>.868machine # [ 1.242158] systemd[1]: systemd 263-0f0682b 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)869machine # [ 1.242175] systemd[1]: Detected virtualization qemu.870machine # [ 1.242196] systemd[1]: Detected architecture arm64.871machine # [ 1.242202] systemd[1]: Running in initrd.872machine # [ 1.249703] systemd[1]: Initializing machine ID from random generator.873machine # [ 1.249838] systemd[1]: Hostname set to <machine>.874sshClient # [ 1.341932] systemd[1]: bpf-restrict-fs: LSM BPF program attached875machine # [ 1.351730] systemd[1]: bpf-restrict-fs: LSM BPF program attached876machine # [ 1.358549] usb 1-1: new high-speed USB device number 2 using ehci-pci877sshClient # [ 1.360589] usb 1-1: new high-speed USB device number 2 using ehci-pci878sshClient # [ 1.408241] systemd[1]: Queued start job for default target Initrd Default Target.879machine # [ 1.416644] systemd[1]: Queued start job for default target Initrd Default Target.880machine # [ 1.421945] systemd[1]: Created slice Slice /system/modprobe.881machine # [ 1.422173] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.882machine # [ 1.422203] systemd[1]: Expecting device /dev/disk/by-label/nixos...883machine # [ 1.422227] systemd[1]: Reached target Path Units.884machine # [ 1.422242] systemd[1]: Reached target Slice Units.885machine # [ 1.422255] systemd[1]: Reached target Swaps.886machine # [ 1.422270] systemd[1]: Reached target Timer Units.887sshClient # [ 1.418845] systemd[1]: Created slice Slice /system/modprobe.888sshClient # [ 1.419051] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.889sshClient # [ 1.419082] systemd[1]: Expecting device /dev/disk/by-label/nixos...890sshClient # [ 1.419111] systemd[1]: Reached target Path Units.891sshClient # [ 1.419127] systemd[1]: Reached target Slice Units.892sshClient # [ 1.419144] systemd[1]: Reached target Swaps.893sshClient # [ 1.419160] systemd[1]: Reached target Timer Units.894machine # [ 1.427910] systemd[1]: Listening on D-Bus System Message Bus Socket.895sshClient # [ 1.419308] systemd[1]: Listening on D-Bus System Message Bus Socket.896machine # [ 1.428239] systemd[1]: Listening on Journal Socket (/dev/log).897machine # [ 1.428373] systemd[1]: Listening on Journal Sockets.898sshClient # [ 1.419478] systemd[1]: Listening on Journal Socket (/dev/log).899sshClient # [ 1.419604] systemd[1]: Listening on Journal Sockets.900machine # [ 1.428450] systemd[1]: Listening on udev Kernel Socket.901sshClient # [ 1.419679] systemd[1]: Listening on udev Kernel Socket.902machine # [ 1.428653] systemd[1]: Listening on udev Varlink Socket.903machine # [ 1.428672] systemd[1]: Reached target Socket Units.904sshClient # [ 1.419871] systemd[1]: Listening on udev Varlink Socket.905sshClient # [ 1.419889] systemd[1]: Reached target Socket Units.906machine # [ 1.430234] systemd[1]: Starting Create List of Static Device Nodes...907machine # [ 1.430344] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs908sshClient # [ 1.431184] systemd[1]: Starting Create List of Static Device Nodes...909sshClient # [ 1.431296] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs910machine # [ 1.445392] systemd[1]: Mounting Kernel Configuration File System...911sshClient # [ 1.440712] systemd[1]: Mounting Kernel Configuration File System...912sshClient # [ 1.448752] systemd[1]: Starting Journal Service...913sshClient # [ 1.456752] systemd[1]: Starting Load Kernel Modules...914sshClient # [ 1.456922] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os915machine # [ 1.470683] systemd[1]: Starting Journal Service...916machine # [ 1.479124] systemd[1]: Starting Load Kernel Modules...917machine # [ 1.479279] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os918machine # [ 1.494684] systemd[1]: Starting Coldplug All udev Devices...919sshClient # [ 1.493294] systemd[1]: Starting Coldplug All udev Devices...920machine # [ 1.508929] systemd[1]: Finished Create List of Static Device Nodes.921machine # [ 1.510164] systemd[1]: Mounted Kernel Configuration File System.922sshClient # [ 1.511007] systemd-journald[71]: Collecting audit messages is disabled.923sshClient # [ 1.514187] systemd[1]: Finished Create List of Static Device Nodes.924sshClient # [ 1.526282] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...925sshClient # [ 1.526791] systemd[1]: Mounted Kernel Configuration File System.926machine # [ 1.535264] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...927machine # [ 1.541222] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1928sshClient # [ 1.538107] 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/input1929machine # [ 1.555774] systemd-journald[71]: Collecting audit messages is disabled.930machine # [ 1.586744] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.931sshClient # [ 1.589454] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.932machine # [ 1.602651] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev933sshClient # [ 1.604676] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev934machine # [ 1.622085] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0935machine # [ 1.622329] [drm] features: -virgl +edid -resource_blob -host_visible936machine # [ 1.622332] [drm] features: -context_init937machine # [ 1.625774] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.938machine # [ 1.626786] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0939sshClient # [ 1.617084] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.940sshClient # [ 1.626555] systemd[1]: Starting Create Static Device Nodes in /dev...941machine # [ 1.635051] systemd[1]: Starting Create Static Device Nodes in /dev...942sshClient # [ 1.632974] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0943machine # [ 1.643402] [drm] number of scanouts: 1944machine # [ 1.643423] [drm] number of cap sets: 0945machine # [ 1.644191] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic946machine # [ 1.644205] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0947sshClient # [ 1.641221] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0948sshClient # [ 1.641499] [drm] features: -virgl +edid -resource_blob -host_visible949sshClient # [ 1.641504] [drm] features: -context_init950sshClient # [ 1.642584] [drm] number of scanouts: 1951sshClient # [ 1.642601] [drm] number of cap sets: 0952sshClient # [ 1.652778] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic953sshClient # [ 1.652806] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0954machine # [ 1.678830] Console: switching to colour frame buffer device 160x50955machine # [ 1.695241] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device956sshClient # [ 1.689045] Console: switching to colour frame buffer device 160x50957machine # [ 1.710458] systemd[1]: Finished Load Kernel Modules.958machine # [ 1.715415] systemd[1]: Starting Apply Kernel Variables...959sshClient # [ 1.709234] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device960sshClient # [ 1.712328] systemd[1]: Finished Create Static Device Nodes in /dev.961sshClient # [ 1.720779] systemd[1]: Finished Load Kernel Modules.962sshClient # [ 1.721215] systemd[1]: Reached target Preparation for Local File Systems.963sshClient # [ 1.722019] systemd[1]: Reached target Local File Systems.964machine # [ 1.730696] systemd[1]: Finished Create Static Device Nodes in /dev.965machine # [ 1.731025] systemd[1]: Reached target Preparation for Local File Systems.966machine # [ 1.731058] systemd[1]: Reached target Local File Systems.967sshClient # [ 1.729668] systemd[1]: Starting Apply Kernel Variables...968machine # [ 1.737385] systemd[1]: Starting Rule-based Manager for Device Events and Files...969sshClient # [ 1.725926] systemd-modules-load[72]: Inserted module 'dm_mod'970sshClient # [ 1.728256] systemd-modules-load[72]: Module 'virtio_balloon' is built in971machine # [ 1.746505] usb 1-2: new high-speed USB device number 3 using ehci-pci972sshClient # [ 1.733171] systemd-modules-load[72]: Module 'virtio_console' is built in973sshClient # [ 1.751223] systemd[1]: Starting Rule-based Manager for Device Events and Files...974sshClient # [ 1.751721] systemd[1]: Started Journal Service.975sshClient # [ 1.738922] systemd-modules-load[72]: Inserted module 'virtio_gpu'976sshClient # [ 1.756547] usb 1-2: new high-speed USB device number 3 using ehci-pci977sshClient # [ 1.748839] systemd-modules-load[72]: Module 'virtio_rng' is built in978machine # [ 1.752547] systemd-modules-load[72]: Inserted module 'dm_mod'979machine # [ 1.756330] systemd-modules-load[72]: Module 'virtio_balloon' is built in980machine # [ 1.757702] systemd-modules-load[72]: Module 'virtio_console' is built in981machine # [ 1.758885] systemd-modules-load[72]: Inserted module 'virtio_gpu'982machine # [ 1.778824] systemd[1]: Started Journal Service.983machine # [ 1.776223] systemd-modules-load[72]: Module 'virtio_rng' is built in984machine # [ 1.794056] systemd[1]: Starting Create System Files and Directories...985sshClient # [ 1.800114] systemd[1]: Starting Create System Files and Directories...986sshClient # [ 1.805989] systemd[1]: Finished Apply Kernel Variables.987machine # [ 1.810042] systemd[1]: Finished Apply Kernel Variables.988machine # [ 1.848581] systemd-udevd[79]: Using default interface naming scheme 'v261'.989machine # [ 1.856841] systemd[1]: Finished Create System Files and Directories.990sshClient # [ 1.857988] systemd[1]: Finished Create System Files and Directories.991sshClient # [ 1.873813] systemd-udevd[79]: Using default interface naming scheme 'v261'.992machine # [ 1.900603] 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/input2993machine # [ 1.900918] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0994machine # [ 1.898750] systemd[1]: Started Rule-based Manager for Device Events and Files.995sshClient # [ 1.911201] 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/input2996sshClient # [ 1.911735] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0997sshClient # [ 1.923068] systemd[1]: Started Rule-based Manager for Device Events and Files.998machine # [ 2.020547] systemd[1]: Starting Virtual Console Setup...999sshClient # [ 2.034813] systemd[1]: Starting Virtual Console Setup...1000machine # [ 2.104620] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones.1001machine # [ 2.116158] systemd[1]: Finished Virtual Console Setup.1002sshClient # [ 2.129037] systemd-vconsole-setup[98]: Configuration of first virtual console was skipped, ignoring remaining ones.1003sshClient # [ 2.144964] systemd[1]: Finished Virtual Console Setup.1004machine # [ 2.780327] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.1005sshClient # [ 2.792173] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.1006sshClient # [ 2.842837] (udev-worker)[103]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1007machine # [ 2.850936] (udev-worker)[90]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1008sshClient # [ 2.852567] (udev-worker)[103]: Network interface NamePolicy= disabled on kernel command line.1009machine # [ 2.862529] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.1010machine # [ 2.865165] systemd[1]: Finished Coldplug All udev Devices.1011machine # [ 2.869389] systemd[1]: Reached target System Initialization.1012machine # [ 2.872859] systemd[1]: Reached target Basic System.1013sshClient # [ 2.876480] systemd[1]: Finished Coldplug All udev Devices.1014sshClient # [ 2.878345] systemd[1]: Reached target System Initialization.1015sshClient # [ 2.880196] systemd[1]: Reached target Basic System.1016machine # [ 2.903990] systemd[1]: Found device /dev/disk/by-label/nixos.1017machine # [ 2.909862] systemd[1]: Reached target Initrd Root Device.1018machine # [ 2.916148] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1019sshClient # [ 2.919151] systemd[1]: Found device /dev/disk/by-label/nixos.1020sshClient # [ 2.920734] systemd[1]: Reached target Initrd Root Device.1021sshClient # [ 2.926286] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1022machine # [ 2.973469] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks1023sshClient # [ 2.977813] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks1024machine # [ 3.123763] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1025sshClient # [ 3.124166] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1026sshClient # [ 3.128612] systemd[1]: Mounting /sysroot...1027machine # [ 3.140327] systemd[1]: Mounting /sysroot...1028sshClient # [ 3.297351] EXT4-fs (vda): mounted filesystem 28c9ebd2-6f8c-431a-a998-66016d819d6c r/w with ordered data mode. Quota mode: none.1029machine # [ 3.303406] EXT4-fs (vda): mounted filesystem d7841fcb-efde-4384-a049-60aa363e73d9 r/w with ordered data mode. Quota mode: none.1030sshClient # [ 3.286815] systemd[1]: Mounted /sysroot.1031machine # [ 3.291777] systemd[1]: Mounted /sysroot.1032sshClient # [ 3.289505] systemd[1]: Reached target Initrd Root File System.1033machine # [ 3.294877] systemd[1]: Reached target Initrd Root File System.1034sshClient # [ 3.295147] systemd[1]: Starting Mountpoints Configured in the Real Root...1035machine # [ 3.299393] systemd[1]: Starting Mountpoints Configured in the Real Root...1036sshClient # [ 3.327856] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.1037machine # [ 3.331457] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.1038machine # [ 3.336976] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...1039sshClient # [ 3.334611] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...1040machine # [ 3.341210] systemd[1]: Reloading...1041sshClient # [ 3.339838] systemd[1]: Reloading...1042machine # [ 3.566114] systemd[1]: Reloading finished in 224 ms.1043sshClient # [ 3.563628] systemd[1]: Reloading finished in 225 ms.1044machine # [ 3.601190] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1045sshClient # [ 3.597584] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1046machine # [ 3.606185] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1047sshClient # [ 3.603010] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1048machine # [ 3.613078] systemd[1]: Mounting /sysroot/nix/.ro-store...1049sshClient # [ 3.611431] systemd[1]: Mounting /sysroot/nix/.ro-store...1050machine # [ 3.626707] systemd[1]: Mounting /sysroot/nix/.rw-store...1051sshClient # [ 3.624138] systemd[1]: Mounting /sysroot/nix/.rw-store...1052sshClient # [ 3.644184] systemd[1]: Mounting /sysroot/run...1053machine # [ 3.647951] systemd[1]: Mounting /sysroot/run...1054sshClient # [ 3.669710] systemd[1]: Mounting /sysroot/tmp/shared...1055machine # [ 3.677955] systemd[1]: Mounting /sysroot/tmp/shared...1056sshClient # [ 3.695596] systemd[1]: Mounting /sysroot/tmp/xchg...1057machine # [ 3.703199] systemd[1]: Mounting /sysroot/tmp/xchg...1058sshClient # [ 3.709669] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1059machine # [ 3.724924] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1060machine # [ 3.737829] systemd[1]: Finished Mountpoints Configured in the Real Root.1061sshClient # [ 3.738252] systemd[1]: Finished Mountpoints Configured in the Real Root.1062machine # [ 3.741576] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1063sshClient # [ 3.740748] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1064machine # [ 3.761580] systemd[1]: Mounted /sysroot/nix/.ro-store.1065sshClient # [ 3.763636] systemd[1]: Mounted /sysroot/nix/.ro-store.1066machine # [ 3.771140] systemd[1]: Mounted /sysroot/nix/.rw-store.1067sshClient # [ 3.775870] systemd[1]: Mounted /sysroot/nix/.rw-store.1068sshClient # [ 3.778581] systemd[1]: Mounted /sysroot/run.1069machine # [ 3.806111] systemd[1]: Mounted /sysroot/run.1070machine # [ 3.808771] systemd[1]: Mounted /sysroot/tmp/shared.1071sshClient # [ 3.813518] systemd[1]: Mounted /sysroot/tmp/shared.1072machine # [ 3.822992] systemd[1]: Starting rw-sysroot-nix-store.service...1073machine # [ 3.827646] systemd[1]: Mounted /sysroot/tmp/xchg.1074sshClient # [ 3.827649] systemd[1]: Starting rw-sysroot-nix-store.service...1075sshClient # [ 3.831860] systemd[1]: Mounted /sysroot/tmp/xchg.1076machine # [ 3.865162] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1077machine # [ 3.868395] systemd[1]: Finished rw-sysroot-nix-store.service.1078sshClient # [ 3.866357] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1079sshClient # [ 3.868283] systemd[1]: Finished rw-sysroot-nix-store.service.1080sshClient # [ 4.341267] (udev-worker)[104]: mtd0ro: Failed to find and pin callout binary "/nix/store/2g8v5yf619hqwad8g5jcynn5sz166dvl-systemd-263-0f0682b/lib/udev/mtd_probe": No such file or directory1081machine # [ 4.349279] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/2g8v5yf619hqwad8g5jcynn5sz166dvl-systemd-263-0f0682b/lib/udev/mtd_probe": No such file or directory1082sshClient # [ 4.347646] (udev-worker)[104]: 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 directory1083machine # [ 4.356226] (udev-worker)[93]: 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 directory1084sshClient # [ 4.383217] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1085sshClient # [ 4.386772] systemd[1]: Stopped Virtual Console Setup.1086machine # [ 4.393132] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1087sshClient # [ 4.392311] systemd[1]: Stopping Virtual Console Setup...1088machine # [ 4.396089] systemd[1]: Stopped Virtual Console Setup.1089sshClient # [ 4.393326] systemd[1]: Starting Virtual Console Setup...1090machine # [ 4.397536] systemd[1]: Stopping Virtual Console Setup...1091machine # [ 4.400340] systemd[1]: Starting Virtual Console Setup...1092sshClient # [ 4.425225] systemd-vconsole-setup[150]: Configuration of first virtual console was skipped, ignoring remaining ones.1093sshClient # [ 4.429279] systemd[1]: Finished Virtual Console Setup.1094machine # [ 4.432918] systemd-vconsole-setup[150]: Configuration of first virtual console was skipped, ignoring remaining ones.1095machine # [ 4.437062] systemd[1]: Finished Virtual Console Setup.1096machine # [ 4.606933] systemd[1]: Mounting /sysroot/nix/store...1097sshClient # [ 4.604896] systemd[1]: Mounting /sysroot/nix/store...1098machine # [ 4.649243] systemd[1]: Mounted /sysroot/nix/store.1099machine # [ 4.651969] systemd[1]: Reached target Initrd File Systems.1100sshClient # [ 4.650672] systemd[1]: Mounted /sysroot/nix/store.1101machine # [ 4.656183] systemd[1]: Starting Find NixOS closure...1102sshClient # [ 4.653230] systemd[1]: Reached target Initrd File Systems.1103sshClient # [ 4.657770] systemd[1]: Starting Find NixOS closure...1104machine # [ 4.661940] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1105sshClient # [ 4.665700] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1106machine # [ 4.710108] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1107machine # [ 4.711555] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1108machine # [ 4.718415] systemd[1]: Finished Find NixOS closure.1109machine # [ 4.720712] systemd[1]: Reached target Initrd Default Target.1110sshClient # [ 4.717186] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1111machine # [ 4.724163] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1112sshClient # [ 4.724130] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1113sshClient # [ 4.733093] systemd[1]: Finished Find NixOS closure.1114sshClient # [ 4.736286] systemd[1]: Reached target Initrd Default Target.1115sshClient # [ 4.737968] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1116machine # [ 4.752879] systemd[1]: Stopped target Initrd Default Target.1117machine # [ 4.754672] systemd[1]: Stopped target Basic System.1118machine # [ 4.756630] systemd[1]: Stopped target Initrd Root Device.1119machine # [ 4.760455] systemd[1]: Stopped target Path Units.1120machine # [ 4.761380] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1121machine # [ 4.763381] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1122machine # [ 4.766100] systemd[1]: Stopped target Slice Units.1123machine # [ 4.768189] systemd[1]: Stopped target Socket Units.1124machine # [ 4.769743] systemd[1]: Stopped target System Initialization.1125machine # [ 4.773452] systemd[1]: Stopped target Swaps.1126sshClient # [ 4.771111] systemd[1]: Stopped target Initrd Default Target.1127machine # [ 4.776204] systemd[1]: Stopped target Timer Units.1128sshClient # [ 4.772960] systemd[1]: Stopped target Basic System.1129machine # [ 4.778802] systemd[1]: dbus.socket: Deactivated successfully.1130sshClient # [ 4.776440] systemd[1]: Stopped target Initrd Root Device.1131sshClient # [ 4.777571] systemd[1]: Stopped target Path Units.1132sshClient # [ 4.779328] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1133machine # [ 4.786054] systemd[1]: Closed D-Bus System Message Bus Socket.1134sshClient # [ 4.783288] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1135machine # [ 4.788146] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1136sshClient # [ 4.785578] systemd[1]: Stopped target Slice Units.1137machine # [ 4.789305] systemd[1]: Stopped Find NixOS closure.1138machine # [ 4.790109] systemd[1]: Starting rw-sysroot-nix-store.service...1139machine # [ 4.790997] systemd[1]: systemd-sysctl.service: Deactivated successfully.1140sshClient # [ 4.788178] systemd[1]: Stopped target Socket Units.1141sshClient # [ 4.789766] systemd[1]: Stopped target System Initialization.1142sshClient # [ 4.792173] systemd[1]: Stopped target Swaps.1143machine # [ 4.791980] systemd[1]: Stopped Apply Kernel Variables.1144machine # [ 4.798672] systemd[1]: systemd-modules-load.service: Deactivated successfully.1145sshClient # [ 4.796219] systemd[1]: Stopped target Timer Units.1146sshClient # [ 4.797135] systemd[1]: dbus.socket: Deactivated successfully.1147sshClient # [ 4.798063] systemd[1]: Closed D-Bus System Message Bus Socket.1148sshClient # [ 4.800129] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1149machine # [ 4.808500] systemd[1]: Stopped Load Kernel Modules.1150machine # [ 4.809541] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1151sshClient # [ 4.809490] systemd[1]: Stopped Find NixOS closure.1152sshClient # [ 4.811430] systemd[1]: Starting rw-sysroot-nix-store.service...1153machine # [ 4.815885] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1154sshClient # [ 4.813782] systemd[1]: systemd-sysctl.service: Deactivated successfully.1155machine # [ 4.820636] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1156sshClient # [ 4.817490] systemd[1]: Stopped Apply Kernel Variables.1157machine # [ 4.821825] systemd[1]: Stopped Create System Files and Directories.1158machine # [ 4.822767] systemd[1]: Stopped target Local File Systems.1159machine # [ 4.823597] systemd[1]: Stopped target Preparation for Local File Systems.1160machine # [ 4.828711] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1161sshClient # [ 4.825828] systemd[1]: systemd-modules-load.service: Deactivated successfully.1162sshClient # [ 4.828414] systemd[1]: Stopped Load Kernel Modules.1163machine # [ 4.832196] systemd[1]: Stopped Coldplug All udev Devices.1164machine # [ 4.833210] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1165sshClient # [ 4.831125] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1166machine # [ 4.836251] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1167machine # [ 4.840243] systemd[1]: Stopped Virtual Console Setup.1168machine # [ 4.841138] systemd[1]: initrd-cleanup.service: Deactivated successfully.1169machine # [ 4.842386] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1170machine # [ 4.843682] systemd[1]: systemd-udevd.service: Deactivated successfully.1171sshClient # [ 4.840533] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1172machine # [ 4.844912] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1173sshClient # [ 4.841769] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1174sshClient # [ 4.842917] systemd[1]: Stopped Create System Files and Directories.1175machine # [ 4.846010] systemd[1]: systemd-udevd.service: Consumed 1.502s CPU time over 3.098s wall clock time, 25.1M memory peak.1176sshClient # [ 4.843869] systemd[1]: Stopped target Local File Systems.1177machine # [ 4.847524] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1178sshClient # [ 4.846456] systemd[1]: Stopped target Preparation for Local File Systems.1179machine # [ 4.852317] systemd[1]: Finished rw-sysroot-nix-store.service.1180machine # [ 4.853329] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully.1181machine # [ 4.854868] systemd[1]: Closed udev Varlink Socket.1182sshClient # [ 4.852332] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1183sshClient # [ 4.853597] systemd[1]: Stopped Coldplug All udev Devices.1184machine # [ 4.857799] systemd[1]: Starting Cleanup udev Database...1185sshClient # [ 4.854575] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1186sshClient # [ 4.856257] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1187machine # [ 4.860253] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1188machine # [ 4.861503] systemd[1]: Stopped Create Static Device Nodes in /dev.1189sshClient # [ 4.858911] systemd[1]: Stopped Virtual Console Setup.1190sshClient # [ 4.860656] systemd[1]: systemd-udevd.service: Deactivated successfully.1191machine # [ 4.864134] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1192sshClient # [ 4.863306] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1193machine # [ 4.868518] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1194sshClient # [ 4.865079] systemd[1]: systemd-udevd.service: Consumed 1.487s CPU time over 3.095s wall clock time, 23.9M memory peak.1195machine # [ 4.869721] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1196sshClient # [ 4.866677] systemd[1]: initrd-cleanup.service: Deactivated successfully.1197sshClient # [ 4.867698] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1198machine # [ 4.872115] systemd[1]: Stopped Create List of Static Device Nodes.1199sshClient # [ 4.869022] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully.1200sshClient # [ 4.870107] systemd[1]: Closed udev Varlink Socket.1201sshClient # [ 4.870906] systemd[1]: Starting Cleanup udev Database...1202sshClient # [ 4.871776] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1203sshClient # [ 4.876408] systemd[1]: Stopped Create Static Device Nodes in /dev.1204sshClient # [ 4.879620] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1205sshClient # [ 4.884598] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1206sshClient # [ 4.885734] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1207sshClient # [ 4.886923] systemd[1]: Stopped Create List of Static Device Nodes.1208sshClient # [ 4.887854] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1209sshClient # [ 4.892420] systemd[1]: Finished rw-sysroot-nix-store.service.1210machine # [ 4.907458] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1211machine # [ 4.910550] systemd[1]: Finished Cleanup udev Database.1212machine # [ 4.913484] systemd[1]: Reached target Switch Root.1213machine # [ 4.915004] systemd[1]: Starting NixOS Activation...1214sshClient # [ 4.931300] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1215sshClient # [ 4.935061] systemd[1]: Finished Cleanup udev Database.1216sshClient # [ 4.935928] systemd[1]: Reached target Switch Root.1217sshClient # [ 4.938180] systemd[1]: Starting NixOS Activation...1218machine # [ 5.014219] initrd-nixos-activation-start[173]: booting system configuration /nix/store/8ayak4q3g5jkd2lbri6jg1vyq03hs2gm-nixos-system-machine-test1219sshClient # [ 5.026553] initrd-nixos-activation-start[174]: booting system configuration /nix/store/bwks7k3pfs60grb8wmcagz85qsrls588-nixos-system-sshClient-test1220machine # [ 5.050388] initrd-nixos-activation-start[173]: running activation script...1221sshClient # [ 5.060424] initrd-nixos-activation-start[174]: running activation script...1222machine # [ 5.284935] initrd-nixos-activation-start[196]: setting up /etc...1223sshClient # [ 5.299868] initrd-nixos-activation-start[197]: setting up /etc...1224machine # [ 5.419117] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1225machine # [ 5.422509] systemd[1]: Finished NixOS Activation.1226machine # [ 5.423862] systemd[1]: Starting Switch Root...1227sshClient # [ 5.425589] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1228sshClient # [ 5.428228] systemd[1]: Finished NixOS Activation.1229sshClient # [ 5.429969] systemd[1]: Starting Switch Root...1230machine # [ 5.450026] systemd[1]: Switching root.1231sshClient # [ 5.453104] systemd[1]: Switching root.1232machine # [ 5.640304] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1233sshClient # [ 5.638281] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1234machine # [ 5.738164] NET: Registered PF_VSOCK protocol family1235sshClient # [ 5.733338] NET: Registered PF_VSOCK protocol family1236sshClient # [ 5.804130] systemd[1]: systemd 263-0f0682b 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)1237sshClient # [ 5.812651] systemd[1]: Detected virtualization qemu.1238sshClient # [ 5.812726] systemd[1]: Detected architecture arm64.1239sshClient # [ 5.812840] systemd[1]: Detected first boot.1240sshClient # [ 5.814372] systemd[1]: Initializing machine ID from random generator.1241machine # [ 5.818290] systemd[1]: systemd 263-0f0682b 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)1242machine # [ 5.826870] systemd[1]: Detected virtualization qemu.1243machine # [ 5.826933] systemd[1]: Detected architecture arm64.1244machine # [ 5.827036] systemd[1]: Detected first boot.1245machine # [ 5.828501] systemd[1]: Initializing machine ID from random generator.1246sshClient # [ 6.039621] systemd[1]: bpf-restrict-fs: LSM BPF program attached1247machine # [ 6.044923] systemd[1]: bpf-restrict-fs: LSM BPF program attached1248sshClient # [ 6.198322] systemd[1]: Applying preset policy.1249machine # [ 6.208716] systemd[1]: Applying preset policy.1250sshClient # [ 6.430117] systemd[1]: Populated /etc with preset unit settings.1251machine # [ 6.446221] systemd[1]: Populated /etc with preset unit settings.1252sshClient # [ 6.643576] systemd[1]: initrd-switch-root.service: Deactivated successfully.1253sshClient # [ 6.646254] systemd[1]: Stopped initrd-switch-root.service.1254sshClient # [ 6.649921] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1255sshClient # [ 6.651920] systemd[1]: Created slice Slice /system/getty.1256sshClient # [ 6.656138] systemd[1]: Created slice User and Session Slice.1257sshClient # [ 6.658918] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1258sshClient # [ 6.659557] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1259sshClient # [ 6.663501] systemd[1]: Expecting device /dev/hvc0...1260sshClient # [ 6.664036] systemd[1]: Expecting device /dev/ttyAMA0...1261sshClient # [ 6.667181] systemd[1]: Reached target Local Encrypted Volumes.1262sshClient # [ 6.667725] systemd[1]: Stopped target initrd-fs.target.1263machine # [ 6.674061] systemd[1]: initrd-switch-root.service: Deactivated successfully.1264sshClient # [ 6.670844] systemd[1]: Stopped target initrd-root-fs.target.1265machine # [ 6.677561] systemd[1]: Stopped initrd-switch-root.service.1266sshClient # [ 6.671348] systemd[1]: Stopped target initrd-switch-root.target.1267sshClient # [ 6.672289] systemd[1]: Reached target Virtual Machines and Containers.1268sshClient # [ 6.676251] systemd[1]: Reached target Path Units.1269machine # [ 6.682076] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1270sshClient # [ 6.678366] systemd[1]: Reached target Remote File Systems.1271sshClient # [ 6.678884] systemd[1]: Reached target Slice Units.1272sshClient # [ 6.679805] systemd[1]: Reached target Swaps.1273machine # [ 6.687575] systemd[1]: Created slice Slice /system/getty.1274machine # [ 6.689181] systemd[1]: Created slice User and Session Slice.1275machine # [ 6.689838] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1276sshClient # [ 6.684986] systemd[1]: Listening on Query the User Interactively for a Password.1277sshClient # [ 6.687155] systemd[1]: Listening on Process Core Dump Socket.1278machine # [ 6.694051] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1279sshClient # [ 6.690707] systemd[1]: Listening on Credential Encryption/Decryption.1280sshClient # [ 6.692251] systemd[1]: Listening on Factory Reset Management.1281machine # [ 6.696877] systemd[1]: Expecting device /dev/hvc0...1282machine # [ 6.697379] systemd[1]: Expecting device /dev/ttyAMA0...1283machine # [ 6.698340] systemd[1]: Reached target Local Encrypted Volumes.1284sshClient # [ 6.694613] systemd[1]: Listening on Hostname Service Socket.1285machine # [ 6.702204] systemd[1]: Stopped target initrd-fs.target.1286sshClient # [ 6.699447] systemd[1]: Starting Journal Log Access Socket...1287machine # [ 6.704446] systemd[1]: Stopped target initrd-root-fs.target.1288machine # [ 6.704900] systemd[1]: Stopped target initrd-switch-root.target.1289machine # [ 6.705838] systemd[1]: Reached target Virtual Machines and Containers.1290sshClient # [ 6.703097] systemd[1]: Listening on Journal Audit Socket.1291machine # [ 6.709883] systemd[1]: Reached target Path Units.1292machine # [ 6.710348] systemd[1]: Reached target Remote File Systems.1293sshClient # [ 6.706522] systemd[1]: Listening on Console Output Muting Service Socket.1294sshClient # [ 6.707599] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1295machine # [ 6.713745] systemd[1]: Reached target Slice Units.1296machine # [ 6.714254] systemd[1]: Reached target Swaps.1297sshClient # [ 6.708182] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1298sshClient # [ 6.712261] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1299machine # [ 6.718939] systemd[1]: Listening on Query the User Interactively for a Password.1300machine # [ 6.721755] systemd[1]: Listening on Process Core Dump Socket.1301sshClient # [ 6.720803] systemd[1]: Listening on Disk Repartitioning Service Socket.1302machine # [ 6.726539] systemd[1]: Listening on Credential Encryption/Decryption.1303sshClient # [ 6.721564] systemd[1]: Listening on udev Varlink Socket.1304machine # [ 6.728234] systemd[1]: Listening on Factory Reset Management.1305sshClient # [ 6.722519] systemd[1]: Listening on User Database Manager Socket.1306machine # [ 6.728844] systemd[1]: Listening on Hostname Service Socket.1307sshClient # [ 6.728966] systemd[1]: Mounting Huge Pages File System...1308machine # [ 6.735068] systemd[1]: Starting Journal Log Access Socket...1309machine # [ 6.737301] systemd[1]: Listening on Journal Audit Socket.1310sshClient # [ 6.733665] systemd[1]: Mounting POSIX Message Queue File System...1311machine # [ 6.741747] systemd[1]: Listening on Console Output Muting Service Socket.1312machine # [ 6.745484] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1313machine # [ 6.749725] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1314machine # [ 6.750151] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1315machine # [ 6.756689] systemd[1]: Listening on Disk Repartitioning Service Socket.1316machine # [ 6.757445] systemd[1]: Listening on udev Varlink Socket.1317machine # [ 6.758254] systemd[1]: Listening on User Database Manager Socket.1318sshClient # [ 6.752691] systemd[1]: Mounting Kernel Debug File System...1319machine # [ 6.764113] systemd[1]: Mounting Huge Pages File System...1320machine # [ 6.769718] systemd[1]: Mounting POSIX Message Queue File System...1321sshClient # [ 6.765848] systemd[1]: Mounting Kernel Trace File System...1322sshClient # [ 6.779540] systemd[1]: Starting Create List of Static Device Nodes...1323machine # [ 6.786614] systemd[1]: Mounting Kernel Debug File System...1324sshClient # [ 6.788699] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1325machine # [ 6.803432] systemd[1]: Mounting Kernel Trace File System...1326sshClient # [ 6.803475] systemd[1]: Mounting Kernel Configuration File System...1327sshClient # [ 6.809247] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1328machine # [ 6.820714] systemd[1]: Starting Create List of Static Device Nodes...1329sshClient # [ 6.815310] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1330sshClient # [ 6.820747] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1331machine # [ 6.826898] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1332machine # [ 6.846844] systemd[1]: Mounting Kernel Configuration File System...1333machine # [ 6.847516] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1334sshClient # [ 6.851366] systemd[1]: Mounting FUSE Control File System...1335machine # [ 6.856373] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1336sshClient # [ 6.851843] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671337machine # [ 6.856989] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1338machine # [ 6.881421] systemd[1]: Mounting FUSE Control File System...1339machine # [ 6.881908] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671340sshClient # [ 6.882758] systemd[1]: Starting Journal Service...1341sshClient # [ 6.912584] systemd[1]: Starting Load Kernel Modules...1342machine # [ 6.925310] systemd[1]: Starting Journal Service...1343machine # [ 6.943535] systemd[1]: Starting Load Kernel Modules...1344sshClient # [ 6.940933] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1345sshClient # [ 6.952755] systemd[1]: Starting Remount Root and Kernel File Systems...1346sshClient # [ 6.953221] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1347sshClient # [ 6.970859] systemd[1]: Starting Coldplug All udev Devices...1348machine # [ 6.978756] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1349machine # [ 6.988703] systemd[1]: Starting Remount Root and Kernel File Systems...1350machine # [ 6.989140] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1351sshClient # [ 7.003303] systemd[1]: Listening on Journal Log Access Socket.1352sshClient # [ 7.003914] systemd[1]: Mounted Huge Pages File System.1353sshClient # [ 7.004442] systemd[1]: Mounted POSIX Message Queue File System.1354sshClient # [ 7.010848] systemd-journald[268]: Collecting audit messages is enabled.1355sshClient # [ 7.024207] systemd[1]: Mounted Kernel Debug File System.1356machine # [ 7.027424] systemd[1]: Starting Coldplug All udev Devices...1357sshClient # [ 7.028219] systemd[1]: Mounted Kernel Trace File System.1358machine # [ 7.050124] systemd-journald[267]: Collecting audit messages is enabled.1359sshClient # [ 7.033456] systemd[1]: Queued start job for default target Multi-User System.1360sshClient # [ 7.052034] systemd[1]: Started Journal Service.1361sshClient # [ 7.037769] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.1362sshClient # [ 7.039355] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0.1363machine # [ 7.059415] systemd[1]: Listening on Journal Log Access Socket.1364machine # [ 7.060153] systemd[1]: Mounted Huge Pages File System.1365machine # [ 7.060700] systemd[1]: Mounted POSIX Message Queue File System.1366machine # [ 7.061149] systemd[1]: Mounted Kernel Debug File System.1367machine # [ 7.050778] systemd[1]: Queued start job for default target Multi-User System.1368machine # [ 7.056238] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.1369machine # [ 7.059031] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0.[ 7.076589] systemd[1]: Finished Create List of Static Device Nodes.1370sshClient # [ 7.062865] systemd[1]: systemd-journald.service: Deactivated successfully.1371machine # 1372sshClient # [ 7.068601] systemd-modules-load[269]: Module 'atkbd' is built in1373machine # [ 7.072841] systemd[1]: systemd-journald.service: Deactivated successfully.1374sshClient # [ 7.069828] systemd-modules-load[269]: Module 'loop' is built in1375machine # [ 7.074509] systemd-modules-load[268]: Module 'atkbd' is built in1376sshClient # [ 7.071264] systemd[1]: Finished Create List of Static Device Nodes.1377machine # [ 7.075629] systemd-modules-load[268]: Module 'loop' is built in1378machine # [ 7.099301] systemd[1]: Started Journal Service.1379sshClient # [ 7.083466] systemd[1]: Mounted Kernel Configuration File System.1380machine # [ 7.088872] systemd[1]: Mounted Kernel Trace File System.1381sshClient # [ 7.086006] systemd[1]: Finished Load Kernel Modules.1382machine # [ 7.090119] systemd[1]: Finished Load Kernel Modules.1383sshClient # [ 7.095238] systemd[1]: Starting Firewall...1384sshClient # [ 7.103373] systemd[1]: Starting Apply Kernel Variables...1385sshClient # [ 7.112591] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1386machine # [ 7.137520] systemd[1]: Mounted Kernel Configuration File System.1387machine # [ 7.145536] systemd-oomd[270]: No swap; memory pressure usage will be degraded1388sshClient # [ 7.156693] EXT4-fs (vda): re-mounted 28c9ebd2-6f8c-431a-a998-66016d819d6c.1389machine # [ 7.154233] systemd[1]: Starting Firewall...1390machine # [ 7.160295] systemd[1]: Starting Apply Kernel Variables...1391sshClient # [ 7.181931] systemd[1]: Finished Remount Root and Kernel File Systems.1392machine # [ 7.187134] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1393machine # [ 7.196508] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1394machine # [ 7.226502] EXT4-fs (vda): re-mounted d7841fcb-efde-4384-a049-60aa363e73d9.1395sshClient # [ 7.209693] systemd[1]: Listening on Disk Image Download Service Socket.1396sshClient # [ 7.219884] systemd-oomd[271]: No swap; memory pressure usage will be degraded1397machine # [ 7.232122] systemd[1]: Finished Remount Root and Kernel File Systems.1398machine # [ 7.233289] systemd[1]: Listening on Disk Image Download Service Socket.1399sshClient # [ 7.236541] systemd[1]: Starting Flush Journal to Persistent Storage...1400sshClient # [ 7.237639] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1401machine # [ 7.248448] systemd[1]: Starting Flush Journal to Persistent Storage...1402sshClient # [ 7.253943] systemd[1]: Starting Load/Save OS Random Seed...1403sshClient # [ 7.255126] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1404machine # [ 7.259240] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1405sshClient # [ 7.265758] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1406sshClient # [ 7.267055] systemd[1]: Mounted FUSE Control File System.1407machine # [ 7.275023] systemd[1]: Starting Load/Save OS Random Seed...1408machine # [ 7.277064] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1409machine # [ 7.299754] systemd[1]: Mounted FUSE Control File System.1410sshClient # [ 7.374492] systemd-journald[268]: Received client request to flush runtime journal.1411machine # [ 7.403578] systemd-journald[267]: Received client request to flush runtime journal.1412sshClient # [ 7.469368] systemd[1]: Finished Apply Kernel Variables.1413sshClient # [ 7.472375] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1414sshClient # [ 7.482970] systemd[1]: Starting Create Static Device Nodes in /dev...1415sshClient # [ 7.488937] systemd[1]: Finished Flush Journal to Persistent Storage.1416sshClient # [ 7.653999] systemd[1]: Finished Create Static Device Nodes in /dev.1417sshClient # [ 7.663594] systemd[1]: Reached target Preparation for Local File Systems.1418sshClient # [ 7.667837] systemd[1]: Mounting /run/wrappers...1419sshClient # [ 7.673736] systemd[1]: Starting Rule-based Manager for Device Events and Files...1420sshClient # [ 7.730372] systemd[1]: Finished Load/Save OS Random Seed.1421sshClient # [ 7.778696] systemd[1]: Mounted /run/wrappers.1422sshClient # [ 7.779582] systemd[1]: Reached target Local File Systems.1423machine # [ 7.784918] systemd[1]: Finished Apply Kernel Variables.1424sshClient # [ 7.786825] systemd[1]: Listening on Boot Loader Control Service Socket.1425machine # [ 7.794800] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1426sshClient # [ 7.799170] systemd[1]: Starting Create SUID/SGID Wrappers...1427machine # [ 7.803432] systemd[1]: Starting Create Static Device Nodes in /dev...1428sshClient # [ 7.800694] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1429sshClient # [ 7.806295] systemd[1]: Starting Create System Files and Directories...1430machine # [ 7.812569] systemd[1]: Finished Create Static Device Nodes in /dev.1431machine # [ 7.813684] systemd[1]: Reached target Preparation for Local File Systems.1432machine # [ 7.814695] systemd[1]: Starting Rule-based Manager for Device Events and Files...1433machine # [ 7.815762] systemd[1]: Mounting /run/wrappers...1434machine # [ 7.825164] systemd[1]: Finished Load/Save OS Random Seed.1435machine # [ 7.826202] systemd[1]: Finished Flush Journal to Persistent Storage.1436machine # [ 7.827194] systemd[1]: Mounted /run/wrappers.1437machine # [ 7.827905] systemd[1]: Reached target Local File Systems.1438machine # [ 7.844513] systemd[1]: Listening on Boot Loader Control Service Socket.1439machine # [ 7.845632] systemd[1]: Starting Create SUID/SGID Wrappers...1440machine # [ 7.846511] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1441machine # [ 7.847795] systemd[1]: Starting Create System Files and Directories...1442machine # [ 7.863381] systemd-udevd[303]: Using default interface naming scheme 'v261'.1443sshClient # [ 7.880402] systemd-udevd[308]: Using default interface naming scheme 'v261'.1444machine # [ 7.947349] systemd[1]: Starting User Database Manager...1445sshClient # [ 7.972253] systemd[1]: Starting User Database Manager...1446sshClient # [ 8.009237] systemd[1]: Finished Create System Files and Directories.1447sshClient # [ 8.023584] systemd[1]: Starting Rebuild Journal Catalog...1448sshClient # [ 8.037554] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1449machine # [ 8.096449] systemd[1]: Finished Create System Files and Directories.1450machine # [ 8.111707] systemd[1]: Starting Rebuild Journal Catalog...1451machine # [ 8.118870] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1452machine # [ 8.158277] systemd[1]: Started User Database Manager.1453sshClient # [ 8.197793] systemd[1]: Started User Database Manager.1454sshClient # [ 8.204855] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1455sshClient # [ 8.258449] systemd[1]: Finished Rebuild Journal Catalog.1456sshClient # [ 8.270803] systemd[1]: Starting Update is Completed...1457machine # [ 8.282370] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1458machine # [ 8.314779] systemd[1]: Finished Rebuild Journal Catalog.1459machine # [ 8.325067] systemd[1]: Starting Update is Completed...1460sshClient # [ 8.404302] systemd[1]: Finished Update is Completed.1461machine # [ 8.432715] systemd[1]: Finished Update is Completed.1462sshClient # [ 8.444339] systemd[1]: Started Rule-based Manager for Device Events and Files.1463machine # [ 8.452708] systemd[1]: Started Rule-based Manager for Device Events and Files.1464sshClient # [ 8.676805] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1465sshClient # [ 8.745568] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1466machine # [ 8.766200] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1467machine # [ 8.823776] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1468sshClient # [ 9.035000] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1469sshClient # [ 9.041756] systemd[1]: Finished Create SUID/SGID Wrappers.1470sshClient # [ 9.120297] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1471sshClient # [ 9.132388] systemd[1]: Finished Coldplug All udev Devices.1472sshClient # [ 9.141076] systemd[1]: Reached target System Initialization.1473sshClient # [ 9.142195] systemd[1]: Started Discard unused filesystem blocks once a week.1474sshClient # [ 9.143261] systemd[1]: Started Daily Cleanup of Temporary Directories.1475sshClient # [ 9.151377] systemd[1]: Reached target Timer Units.1476sshClient # [ 9.153411] systemd[1]: Listening on D-Bus System Message Bus Socket.1477sshClient # [ 9.154506] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1478sshClient # [ 9.164352] systemd[1]: Reached target Socket Units.1479sshClient # [ 9.165295] systemd[1]: Reached target Basic System.1480sshClient # [ 9.166239] systemd[1]: Starting Import lastlog data into lastlog2 database...1481sshClient # [ 9.167304] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1482sshClient # [ 9.178644] systemd[1]: Starting Post-Boot Actions...1483machine # [ 9.191966] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1484machine # [ 9.193249] systemd[1]: Finished Create SUID/SGID Wrappers.1485sshClient # [ 9.197015] systemd[1]: Started Reset console on configuration changes.1486machine # [ 9.244942] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1487sshClient # [ 9.248643] systemd[1]: Starting resolvconf update...1488machine # [ 9.256474] systemd[1]: Finished Coldplug All udev Devices.1489machine # [ 9.260450] systemd[1]: Reached target System Initialization.1490machine # [ 9.261491] systemd[1]: Started Discard unused filesystem blocks once a week.1491machine # [ 9.262591] systemd[1]: Started Daily Cleanup of Temporary Directories.1492machine # [ 9.263551] systemd[1]: Reached target Timer Units.1493machine # [ 9.274123] systemd[1]: Listening on D-Bus System Message Bus Socket.1494machine # [ 9.275261] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).1495sshClient # [ 9.274589] systemd[1]: Starting Home Area Manager...1496machine # [ 9.283669] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1497machine # [ 9.289753] systemd[1]: Reached target Socket Units.1498machine # [ 9.290579] systemd[1]: Reached target Basic System.1499machine # [ 9.291376] systemd[1]: Starting Import lastlog data into lastlog2 database...1500sshClient # [ 9.295484] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1501machine # [ 9.301879] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1502machine # [ 9.315127] systemd[1]: Starting Post-Boot Actions...1503machine # [ 9.327497] systemd[1]: Started Reset console on configuration changes.1504sshClient # [ 9.355435] systemd[1]: Started backdoor.service.1505machine # [ 9.370132] systemd[1]: Starting resolvconf update...1506sshClient # [ 9.392533] systemd[1]: Starting D-Bus System Message Bus...1507machine # [ 9.420323] systemd[1]: Starting SSH Host Keys Generation...1508sshClient # [ 9.441362] systemd[1]: Finished Post-Boot Actions.1509sshClient # [ 9.442404] systemd[1]: Started Name Service Cache Daemon (nsncd).1510sshClient # [ 9.480341] systemd[1]: Reached target Host and Network Name Lookups.1511sshClient # [ 9.489435] nsncd[440]: Oct 24 03:01:57.905 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1512machine # [ 9.505478] systemd[1]: Starting Home Area Manager...1513machine # [ 9.549258] systemd[1]: Finished Post-Boot Actions.1514sshClient # [ 9.545829] systemd[1]: Reached target User and Group Name Lookups.1515sshClient # [ 9.569159] systemd-homed[448]: Watching /home.1516machine # [ 9.580534] systemd[1]: Started Name Service Cache Daemon (nsncd).1517machine # [ 9.582136] nsncd[449]: Oct 24 03:01:57.982 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1518sshClient # [ 9.591182] systemd[1]: Starting User Login Management...1519machine # [ 9.601260] (udev-worker)[384]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1520machine # [ 9.626097] (udev-worker)[384]: Network interface NamePolicy= disabled on kernel command line.1521machine # [ 9.636199] (udev-worker)[379]: Network interface NamePolicy= disabled on kernel command line.1522machine # [ 9.648440] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1523machine # [ 9.659382] systemd[1]: Reached target Host and Network Name Lookups.1524machine # [ 9.670434] systemd[1]: Reached target User and Group Name Lookups.1525sshClient # [ 9.680442] (udev-worker)[387]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1526machine # [ 9.690432] systemd[1]: Started backdoor.service.1527sshClient # [ 9.695100] systemd[1]: Finished Import lastlog data into lastlog2 database.1528machine # [ 9.704365] systemd[1]: Starting D-Bus System Message Bus...1529sshClient # [ 9.738626] (udev-worker)[387]: Network interface NamePolicy= disabled on kernel command line.1530sshClient # connecting to host...1531machine # [ 9.759330] systemd-homed[459]: Watching /home.1532sshClient # [ 9.756849] (udev-worker)[397]: Network interface NamePolicy= disabled on kernel command line.1533machine # [ 9.766913] systemd[1]: Starting User Login Management...1534sshClient # [ 9.784609] dbus-broker-launch[453]: Looking up NSS user entry for 'systemd-timesync'...1535sshClient # [ 9.801987] dbus-broker-launch[453]: NSS returned no entry for 'systemd-timesync'1536sshClient # [ 9.803259] dbus-broker-launch[453]: Invalid user-name in /nix/store/vsfq21l6ig5hfphri57fsrnlmi94bl5v-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1537machine # [ 9.810132] systemd[1]: Finished Import lastlog data into lastlog2 database.1538sshClient # [ 9.851193] systemd[1]: Started D-Bus System Message Bus.1539sshClient # [ 9.904095] dbus-broker-launch[453]: Ready1540sshClient # [ 9.919624] systemd[1]: Started Home Area Manager.1541sshClient # [ 9.926624] systemd[1]: Finished Home Area Activation.1542sshClient # [ 9.942736] systemd[1]: Starting First Boot Home Area Wizard...1543sshClient # [ 9.953547] systemd-logind[473]: New seat seat0.1544sshClient # [ 9.970794] systemd[1]: Started User Login Management.1545machine # connecting to host...1546sshClient # [ 10.002619] systemd[1]: Starting linger-users.service...1547sshClient # [ 10.067261] systemd[1]: Stopped target Host and Network Name Lookups.1548sshClient # [ 10.074294] systemd[1]: Stopping Host and Network Name Lookups...1549sshClient # [ 10.075393] systemd[1]: Stopped target User and Group Name Lookups.1550sshClient # [ 10.088899] systemd[1]: Stopping User and Group Name Lookups...1551sshClient # [ 10.089894] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1552sshClient # [ 10.090846] systemd[1]: nscd.service: Deactivated successfully.1553sshClient # [ 10.107682] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1554machine: Guest shell says: b'Spawning backdoor root shell...\n'1555machine # [ 10.128313] dbus-broker-launch[488]: Looking up NSS user entry for 'systemd-timesync'...1556machine # [ 10.143635] dbus-broker-launch[488]: NSS returned no entry for 'systemd-timesync'1557machine # [ 10.150164] dbus-broker-launch[488]: Invalid user-name in /nix/store/vf2vfspyf9pk8mm10gmj0pyh9kcqn8bi-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1558machine: connected to guest root shell1559machine: (connecting took 10.60 seconds)1560machine: (finished: waiting for the VM to finish booting, in 10.60 seconds)1561sshClient # [ 10.164257] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1562sshClient # [ 10.175030] systemd[1]: Finished First Boot Home Area Wizard.1563sshClient # [ 10.191829] systemd[1]: linger-users.service: Deactivated successfully.1564machine # [ 10.199196] systemd-logind[491]: New seat seat0.1565machine # [ 10.208560] systemd[1]: Started User Login Management.1566sshClient # [ 10.206902] systemd[1]: Finished linger-users.service.1567sshClient # [ 10.230650] systemd[1]: Reached target First Boot Complete.1568machine # [ 10.237960] systemd[1]: Starting linger-users.service...1569sshClient # [ 10.260160] systemd[1]: Starting Save Transient machine-id to Disk...1570machine # [ 10.276978] systemd[1]: Started D-Bus System Message Bus.1571sshClient # [ 10.321538] systemd[1]: Started Name Service Cache Daemon (nsncd).1572sshClient # [ 10.330406] systemd[1]: Reached target Host and Network Name Lookups.1573sshClient # [ 10.331485] systemd[1]: Reached target User and Group Name Lookups.1574machine # [ 10.334991] systemd[1]: Stopped target Host and Network Name Lookups.1575machine # [ 10.344578] systemd[1]: Stopping Host and Network Name Lookups...1576machine # [ 10.345839] systemd[1]: Stopped target User and Group Name Lookups.1577sshClient # [ 10.340939] nsncd[550]: Oct 24 03:01:58.738 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1578machine # [ 10.346810] systemd[1]: Stopping User and Group Name Lookups...1579machine # [ 10.347645] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1580machine # [ 10.372821] dbus-broker-launch[488]: Ready1581machine # [ 10.373674] systemd[1]: nscd.service: Deactivated successfully.1582sshClient # [ 10.376264] systemd[1]: Finished resolvconf update.1583machine # [ 10.388327] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1584sshClient # [ 10.400488] systemd[1]: Starting DHCP Client...1585sshClient # [ 10.488943] systemd[1]: Condition check resulted in Virtio network device being skipped.1586sshClient # [ 10.490382] systemd[1]: Condition check resulted in Virtio network device being skipped.1587machine # [ 10.495354] systemd[1]: linger-users.service: Deactivated successfully.1588machine # [ 10.500304] systemd[1]: Finished linger-users.service.1589machine # [ 10.519102] systemd[1]: Started Home Area Manager.1590machine # [ 10.536198] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1591sshClient # [ 10.539555] systemd-logind[473]: Watching system buttons on /dev/input/event0 (gpio-keys)1592sshClient # [ 10.544126] systemd[1]: Finished Firewall.1593machine # [ 10.548943] systemd[1]: Finished Home Area Activation.1594sshClient # [ 10.547965] systemd[1]: Reached target Preparation for Network.1595machine # [ 10.561704] systemd[1]: Starting First Boot Home Area Wizard...1596sshClient # [ 10.561279] systemd[1]: Starting Address configuration of eth1...1597sshClient # [ 10.584512] systemd[1]: Starting Extra networking commands....1598machine # [ 10.635632] systemd[1]: Condition check resulted in Virtio network device being skipped.1599machine # [ 10.646534] systemd[1]: Condition check resulted in Virtio network device being skipped.1600machine # [ 10.688399] systemd[1]: Finished resolvconf update.1601machine # [ 10.722150] systemd[1]: Starting DHCP Client...1602machine # [ 10.723026] systemd[1]: Started Name Service Cache Daemon (nsncd).1603machine # [ 10.733286] systemd[1]: Finished Firewall.1604machine # [ 10.740975] nsncd[582]: Oct 24 03:01:59.128 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1605machine # [ 10.757486] systemd[1]: Reached target Preparation for Network.1606machine # [ 10.768566] systemd[1]: Reached target Host and Network Name Lookups.1607machine # [ 10.769719] systemd[1]: Reached target User and Group Name Lookups.1608machine # [ 10.791144] systemd[1]: Starting Address configuration of eth1...1609machine # [ 10.799094] systemd[1]: Starting Extra networking commands....1610sshClient # [ 10.810734] dhcpcd[594]: dhcpcd-10.3.2 starting1611sshClient # [ 10.821679] dhcpcd[610]: dev: loaded udev1612sshClient # [ 10.830739] network-addresses-eth1-start[596]: adding address 192.168.1.2/24... done1613machine # [ 10.851199] systemd-logind[491]: Watching system buttons on /dev/input/event0 (gpio-keys)1614sshClient # [ 10.852323] network-addresses-eth1-start[596]: adding address 2001:db8:1::2/64... done1615machine # [ 10.877255] systemd[1]: Created slice Slice /system/systemd-mute-console.1616sshClient # [ 10.897675] mousedev: PS/2 mouse device common for all mice1617sshClient # [ 10.908622] 8021q: 802.1Q VLAN Support v1.81618machine # [ 10.900310] systemd[1]: Started Console Output Muting Service (PID 585/UID 0).1619sshClient # [ 10.909011] 8021q: adding VLAN 0 to HW filter on device eth11620sshClient # [ 10.908261] systemd[1]: Finished Address configuration of eth1.1621sshClient # [ 11.048794] cfg80211: Loading compiled-in X.509 certificates for regulatory database1622sshClient # [ 11.051799] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1623sshClient # [ 11.052309] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1624sshClient # [ 11.060457] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21625sshClient # [ 11.060908] cfg80211: failed to load regulatory.db1626sshClient # [ 11.107643] systemd[1]: Finished Extra networking commands..1627sshClient # [ 11.109467] systemd[1]: Reached target Network.1628sshClient # [ 11.114030] systemd[1]: Starting Permit User Sessions...1629machine # [ 11.123234] network-addresses-eth1-start[606]: adding address 192.168.1.1/24... done1630machine # [ 11.159271] network-addresses-eth1-start[606]: adding address 2001:db8:1::1/64... done1631sshClient # [ 11.181699] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1632sshClient # [ 11.195200] systemd[1]: Finished Save Transient machine-id to Disk.1633sshClient # [ 11.198215] dhcpcd[610]: eth0: waiting for carrier1634sshClient # [ 11.199436] dhcpcd[610]: eth0: carrier acquired[ 11.213034] 8021q: adding VLAN 0 to HW filter on device eth01635sshClient # 1636sshClient # [ 11.210240] systemd[1]: Finished Permit User Sessions.1637sshClient # [ 11.225144] systemd[1]: Started Getty on tty1.1638machine # [ 11.229916] systemd[1]: Finished Address configuration of eth1.1639sshClient # [ 11.229969] systemd[1]: Reached target Login Prompts.1640sshClient # [ 11.236146] dhcpcd[610]: DUID 00:01:00:01:32:6e:dc:27:52:54:00:12:34:561641sshClient # [ 11.237421] dhcpcd[610]: eth0: IAID 00:12:34:561642sshClient # [ 11.238214] dhcpcd[610]: eth0: adding address fe80::5054:ff:fe12:34561643sshClient # [ 11.285617] systemd-logind[473]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1644machine # [ 11.301793] dhcpcd[616]: dhcpcd-10.3.2 starting1645machine # [ 11.318486] dhcpcd[655]: dev: loaded udev1646machine # [ 11.498105] systemd[1]: Finished Extra networking commands..1647machine # [ 11.502326] systemd[1]: Reached target Network.1648machine: (finished: waiting for unit systemd-homed.service, in 12.20 seconds)1649machine: waiting for Please enter user name to create to appear on tty 11650machine # [ 11.960713] dhcpcd[655]: eth0: waiting for carrier1651machine # [ 11.962659] systemd-logind[491]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1652machine # [ 11.971032] dhcpcd[655]: eth0: carrier acquired1653machine # [ 12.028569] dhcpcd[655]: DUID 00:01:00:01:32:6e:dc:28:52:54:00:12:34:561654machine # [ 12.029757] dhcpcd[655]: eth0: IAID 00:12:34:561655machine # [ 12.030459] dhcpcd[655]: eth0: adding address fe80::5054:ff:fe12:34561656sshClient # [ 12.041026] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31657machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.27 seconds)1658machine: sending keys 'test-homed-user\n'1659machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds)1660machine: waiting for Please enter new password for user test-homed-user: to appear on tty 11661sshClient # [ 12.373626] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1662sshClient # [ 12.413929] systemd[1]: Starting Virtual Console Setup...1663sshClient # [ 12.471348] systemd-logind[473]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1664sshClient # [ 12.565980] systemd-vconsole-setup[688]: Configuration of first virtual console was skipped, ignoring remaining ones.1665sshClient # [ 12.571710] systemd[1]: Finished Virtual Console Setup.1666sshClient # [ 12.876887] dhcpcd[610]: eth0: soliciting a DHCP lease1667sshClient # [ 12.881004] dhcpcd[610]: eth0: offered 10.0.2.15 from 10.0.2.21668sshClient # [ 12.888357] dhcpcd[610]: eth0: probing address 10.0.2.15/241669machine # [ 12.921853] sshd-keygen-start[503]: Generating public/private rsa key pair.1670machine # [ 12.923710] sshd-keygen-start[503]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key1671machine # [ 12.928270] sshd-keygen-start[503]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub1672machine # [ 12.929837] sshd-keygen-start[503]: The key fingerprint is:1673machine # [ 12.930679] sshd-keygen-start[503]: SHA256:GCYtutT4doSgEVLlo3p0VX6GM0ihLkQHKoOwuB4ZsnU root@machine1674machine # [ 12.937840] sshd-keygen-start[503]: The key's randomart image is:1675machine # [ 12.938796] sshd-keygen-start[503]: +---[RSA 4096]----+1676machine # [ 12.939501] sshd-keygen-start[503]: |+.+oo o.. |1677machine # [ 12.943023] sshd-keygen-start[503]: |== o + + . |1678machine # [ 12.943893] sshd-keygen-start[503]: |X.+ E * = o |1679machine # [ 12.948962] sshd-keygen-start[503]: |oOoO B o = |1680machine # [ 12.949995] sshd-keygen-start[503]: |+oB = o S |1681machine # [ 12.950739] sshd-keygen-start[503]: |.+.= . |1682machine # [ 12.951478] sshd-keygen-start[503]: |..o o . |1683machine # [ 12.956116] sshd-keygen-start[503]: | . . . |1684machine # [ 12.956960] sshd-keygen-start[503]: | |1685machine # [ 12.957757] sshd-keygen-start[503]: +----[SHA256]-----+1686machine # [ 13.023539] sshd-keygen-start[718]: Generating public/private ed25519 key pair.1687machine # [ 13.026648] sshd-keygen-start[718]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key1688machine # [ 13.030204] sshd-keygen-start[718]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub1689machine # [ 13.031585] sshd-keygen-start[718]: The key fingerprint is:1690machine # [ 13.036702] sshd-keygen-start[718]: SHA256:s5mfsHtA4ybU2jUPeDpnK96rXz7+iwQYG3Xnt7J2pJk root@machine1691machine # [ 13.038081] sshd-keygen-start[718]: The key's randomart image is:1692machine # [ 13.038937] sshd-keygen-start[718]: +--[ED25519 256]--+1693machine # [ 13.039678] sshd-keygen-start[718]: | . . . |1694machine # [ 13.047328] sshd-keygen-start[718]: | . . o |1695machine # [ 13.049942] sshd-keygen-start[718]: | .o. . .|1696machine # [ 13.050808] sshd-keygen-start[718]: | . === ..|1697machine # [ 13.051568] sshd-keygen-start[718]: | . =S=.+ . o |1698machine # [ 13.060217] sshd-keygen-start[718]: | o B=o.. B |1699machine # [ 13.061066] sshd-keygen-start[718]: | o== .oE . |1700machine # [ 13.061849] sshd-keygen-start[718]: | .=o=o.. |1701machine # [ 13.062605] sshd-keygen-start[718]: | .=**o+oo. |1702machine # [ 13.063339] sshd-keygen-start[718]: +----[SHA256]-----+1703machine # [ 13.072440] systemd[1]: sshd-keygen.service: Deactivated successfully.1704machine # [ 13.073682] systemd[1]: Finished SSH Host Keys Generation.1705machine # [ 13.074497] systemd[1]: Starting SSH Daemon...1706machine # [ 13.169781] sshd[720]: Server listening on 0.0.0.0 port 22.1707machine # [ 13.170790] systemd[1]: Started SSH Daemon.1708machine # [ 13.171563] sshd[720]: Server listening on :: port 22.1709machine: (finished: waiting for Please enter new password for user test-homed-user: to appear on tty 1, in 1.35 seconds)1710machine: sending keys 'foobarfoo\n'1711machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1712machine: waiting for (repeat) to appear on tty 11713machine # [ 13.663501] dhcpcd[655]: eth0: soliciting an IPv6 router1714machine # [ 13.668835] dhcpcd[655]: eth0: Router Advertisement from fe80::21715machine # [ 13.669945] dhcpcd[655]: eth0: adding address fec0::5054:ff:fe12:3456/641716machine # [ 13.670962] dhcpcd[655]: eth0: adding route to fec0::/641717machine # [ 13.671741] dhcpcd[655]: eth0: adding default route via fe80::21718sshClient # [ 13.668694] dhcpcd[610]: eth0: soliciting an IPv6 router1719sshClient # [ 13.671043] dhcpcd[610]: eth0: Router Advertisement from fe80::21720sshClient # [ 13.673225] dhcpcd[610]: eth0: adding address fec0::5054:ff:fe12:3456/641721sshClient # [ 13.674816] dhcpcd[610]: eth0: adding route to fec0::/641722sshClient # [ 13.676115] dhcpcd[610]: eth0: adding default route via fe80::21723machine: (finished: waiting for (repeat) to appear on tty 1, in 0.16 seconds)1724machine: sending keys 'foobarfoo\n'1725machine # [ 13.827810] dhcpcd[655]: eth0: soliciting a DHCP lease1726machine # [ 13.832685] dhcpcd[655]: eth0: offered 10.0.2.15 from 10.0.2.21727machine # [ 13.840366] dhcpcd[655]: eth0: probing address 10.0.2.15/241728machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1729machine: waiting for unit systemd-homed-firstboot.service1730machine # [ 14.191215] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1731machine # [ 14.232490] systemd[1]: Starting Virtual Console Setup...1732machine # [ 14.263884] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1733machine # [ 14.267203] systemd[1]: Stopped Virtual Console Setup.1734machine # [ 14.277928] systemd[1]: Starting Virtual Console Setup...1735machine # [ 14.311095] systemd-logind[491]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1736machine # [ 14.411680] systemd-vconsole-setup[755]: Configuration of first virtual console was skipped, ignoring remaining ones.1737machine # [ 14.416287] systemd[1]: Finished Virtual Console Setup.1738sshClient # [ 17.629871] dhcpcd[610]: eth0: leased 10.0.2.15 for 86400 seconds1739sshClient # [ 17.632214] dhcpcd[610]: eth0: adding route to 10.0.2.0/241740sshClient # [ 17.633599] dhcpcd[610]: eth0: adding default route via 10.0.2.21741sshClient # [ 17.832234] systemd[1]: Started DHCP Client.1742sshClient # [ 17.834843] systemd[1]: Reached target Multi-User System.1743sshClient # [ 17.836711] systemd[1]: Startup finished in 1.161s (kernel) + 4.536s (initrd) + 12.137s (userspace) = 17.835s.1744machine # [ 18.620566] dhcpcd[655]: eth0: leased 10.0.2.15 for 86400 seconds1745machine # [ 18.620879] dhcpcd[655]: eth0: adding route to 10.0.2.0/241746machine # [ 18.620964] dhcpcd[655]: eth0: adding default route via 10.0.2.21747machine # [ 18.752685] systemd[1]: Started DHCP Client.1748machine # [ 24.229862] systemd-homed[459]: Generating key pair for signing local user identity records.1749machine # [ 24.234328] systemd-homed[459]: Successfully created Ed25519 key pair.1750machine # [ 24.250298] systemd-homed[459]: test-homed-user: changing state absent → creating1751machine # [ 24.360273] systemd-homework[854]: Using automatic default storage of 'luks'.1752machine # [ 24.367213] systemd-homework[854]: Sizing home to 83% of available disk space, which is 744M.1753machine # [ 24.368998] systemd-homework[854]: Allocating image file completed.1754machine # [ 24.388244] systemd-homework[854]: Writing of partition table completed.1755machine # [ 24.396298] systemd-homework[854]: Setting up loopback device /dev/loop0 completed.1756machine # [ 25.824584] systemd-homework[854]: LUKS formatting completed.1757machine # [ 28.204499] systemd-homework[854]: Writing password to LUKS keyslot 0 completed.1758machine # [ 28.421831] systemd-homework[854]: LUKS activation by volume key succeeded.1759machine # [ 28.447865] systemd-homework[854]: Writing user record as LUKS token completed.1760machine # [ 28.450349] systemd-homework[854]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1761machine # [ 28.451824] systemd-homework[854]: Formatting /dev/mapper/home-test-homed-user as btrfs1762machine # [ 29.315932] systemd-homework[854]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid c84feec6-7304-42fa-812c-340d4c015a52)1763machine # [ 29.316160] systemd-homework[854]: Formatting file system completed.1764machine # [ 29.317655] systemd[1]: proc-886-mounts.mount: Deactivated successfully.1765machine # [ 29.404112] systemd-homework[854]: Mounting file system completed.1766machine # [ 29.406808] systemd-homework[854]: Skeleton directory /etc/skel missing, ignoring.1767machine # [ 29.410062] systemd-homework[854]: Wrote embedded .identity file.1768machine # [ 29.412496] systemd-homework[854]: Replaced embedded blob dir with contents of system blob dir.1769machine # [ 29.414330] systemd-homework[854]: Recursive changing of ownership completed.1770machine # [ 29.415502] systemd-homework[854]: Changed top-level directory access mode to 0700.1771machine # [ 29.422539] systemd-homework[854]: Synchronized disk.1772machine # [ 29.423464] systemd-homework[854]: Discarded unused 652.8M.1773machine # [ 29.438203] systemd-homed[459]: block device /sys/devices/virtual/block/dm-0 has been removed.1774machine # [ 29.469353] systemd-homed[459]: block device /sys/devices/virtual/block/dm-0 has been removed.1775machine # [ 29.503337] (udev-worker)[916]: loop0: Failed to add device watch, ignoring: No such file or directory1776machine # [ 29.508414] (udev-worker)[916]: loop0: Failed to add device watch, ignoring: No such file or directory1777machine # [ 29.511574] systemd-homed[459]: block device /sys/devices/virtual/block/loop0 has been removed.1778machine # [ 29.524906] systemd-homework[854]: Moved image file into place.1779machine # [ 29.526195] systemd-homework[854]: Creation completed.1780machine # [ 29.527032] systemd-homework[854]: Image size is 744M, file system size is 726M, file system payload size is 726M, file system free is 719.9M.1781machine # [ 29.537824] systemd-homed[459]: test-homed-user: changing state creating → inactive1782machine # [ 29.550095] systemd[1]: Finished First Boot Home Area Wizard.1783machine # [ 29.554854] systemd[1]: systemd-mute-console@0-1-585_586-0.service: Deactivated successfully.1784machine # [ 29.562541] systemd[1]: Reached target First Boot Complete.1785machine # [ 29.568529] systemd[1]: Starting Save Transient machine-id to Disk...1786machine # [ 29.578474] systemd[1]: Starting Permit User Sessions...1787machine # [ 29.622939] systemd[1]: Finished Permit User Sessions.1788machine # [ 29.630373] systemd[1]: Started Getty on tty1.1789machine # [ 29.632998] systemd[1]: Reached target Login Prompts.1790machine # [ 29.640255] systemd[1]: Reached target Multi-User System.1791machine # [ 29.822381] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1792machine # [ 29.828675] systemd[1]: Finished Save Transient machine-id to Disk.1793machine # [ 29.846227] systemd[1]: Startup finished in 1.168s (kernel) + 4.531s (initrd) + 24.144s (userspace) = 29.844s.1794machine: (finished: waiting for unit systemd-homed-firstboot.service, in 16.48 seconds)1795(finished: subtest: create systemd-homed user on first boot prompt, in 30.84 seconds)1796machine: must succeed: homectl update test-homed-user --offline -G wheel1797machine # [ 30.449893] systemd-homed[459]: test-homed-user: changing state inactive → updating1798machine # [ 30.459567] systemd-homework[933]: Offline update requested. Not touching embedded records.1799machine # [ 30.464223] systemd-homed[459]: Home test-homed-user is signed exclusively by our key, accepting.1800machine # [ 30.469926] systemd-homed[459]: test-homed-user: changing state updating → inactive1801machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.07 seconds)1802subtest: login as homed user1803machine: waiting for login: to appear on tty 11804machine: (finished: waiting for login: to appear on tty 1, in 0.05 seconds)1805machine: sending keys 'test-homed-user\n'1806machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds)1807machine: waiting for Password: to appear on tty 11808machine # [ 30.862615] systemd-homed[459]: test-homed-user: changing state inactive → activating-for-acquire1809machine # [ 30.871176] systemd-homework[948]: None of the supplied plaintext passwords unlock the user record's hashed passwords.1810machine # [ 30.878746] systemd-homed[459]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.1811machine # [ 30.881983] systemd-homed[459]: test-homed-user: changing state activating-for-acquire → inactive1812machine # [ 30.884456] systemd-homed[459]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.1813machine # [ 30.886173] systemd-homed[459]: Home test-homed-user already deactivated, no automatic deactivation needed.1814machine: (finished: waiting for Password: to appear on tty 1, in 1.15 seconds)1815machine: sending keys 'foobarfoo\n'1816machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1817machine: waiting for success: pgrep -u test-homed-user -t tty1 bash1818machine # [ 32.027396] systemd-homed[459]: test-homed-user: changing state inactive → activating-for-acquire1819machine # [ 32.083651] systemd-homework[961]: Provided password unlocks user record.1820machine # [ 32.089662] systemd-homework[961]: Successfully locked image file '/home/test-homed-user.home'.1821machine # [ 32.098170] systemd-homework[961]: Allocated additional 652.8M.1822machine # [ 32.103533] systemd-homework[961]: Setting up loopback device /dev/loop1 completed.[ 32.119853] loop1: detected capacity change from 0 to 15196161823machine # 1824machine # [ 32.949257] systemd-homework[961]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1825machine # [ 32.991129] systemd-homework[961]: Provided password unlocks user record.1826machine # [ 32.997531] systemd-homework[961]: Probing file system completed (found btrfs).1827machine # [ 33.000268] systemd-homework[961]: No fsck for file system btrfs installed, ignoring.1828machine # [ 33.021600] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (961)1829machine # [ 33.027255] BTRFS info (device dm-0): first mount of filesystem c84feec6-7304-42fa-812c-340d4c015a521830machine # [ 33.027322] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm1831machine # [ 33.039072] BTRFS info (device dm-0): enabling free space tree1832machine # [ 33.039111] BTRFS info (device dm-0): use zstd compression, level 11833machine # [ 33.026003] systemd-homework[961]: Mounting file system completed.1834machine # [ 33.027083] systemd-homework[961]: Discovered used loopback device /dev/loop1.1835machine # [ 33.034098] systemd-homework[961]: offset = 1048576, size = 778043392, image = 7801405441836machine # [ 33.045386] systemd-homework[961]: Ready to resize image size 744M → 897M, partition size 742M → 895M, file system size 726M → 879M.1837machine # [ 33.047413] systemd-homework[961]: Growing of image file from 744M to 897M completed.1838machine # [ 33.074464] loop1: detected capacity change from 1519616 to 18329601839machine # [ 33.062162] systemd-homework[961]: Refreshing loop device size completed.1840machine # [ 33.085280] systemd-homework[961]: Growing of partition completed.1841machine # [ 33.137275] async_tx: api initialized (async)1842machine # [ 33.180633] systemd-homework[961]: LUKS device growing completed.1843machine # [ 33.205976] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 761266176 to 9216983041844machine # [ 33.192345] systemd-homework[961]: File system resizing from 726M to 879M completed.1845machine # [ 33.196408] systemd-homework[961]: Synchronized disk.1846machine # [ 33.197281] systemd-homework[961]: Synchronized disk.1847machine # [ 33.198196] systemd-homework[961]: Resizing completed.1848machine # [ 33.198969] systemd-homework[961]: Image size is 897M, file system size is 879M, file system payload size is 879M, file system free is 872.9M.1849machine # [ 33.208393] systemd-homework[961]: Read embedded .identity file.1850machine # [ 33.228404] systemd-homework[961]: Provided password unlocks user record.1851machine # [ 33.229796] systemd-homework[961]: Reconciling header user identity completed (host version was newer).1852machine # [ 33.231997] systemd-homework[961]: Reconciling embedded user identity completed (host version was newer).1853machine # [ 33.248829] systemd-homework[961]: Wrote LUKS header user record.1854machine # [ 33.249982] systemd-homework[961]: Wrote embedded .identity file.1855machine # [ 33.250879] systemd-homework[961]: Replaced embedded blob dir with contents of system blob dir.1856machine # [ 33.253000] systemd-homework[961]: Recursive changing of ownership not necessary, skipped.1857machine # [ 33.258806] systemd-homework[961]: Synchronized disk.1858machine # [ 33.259713] systemd-homework[961]: Moving to final mount point /home/test-homed-user completed.1859machine # [ 33.286045] systemd-homework[961]: Activation completed.1860machine # [ 33.288776] systemd-homework[961]: Image size is 895M, file system size is 879M, file system payload size is 879M, file system free is 872.9M.1861machine # [ 33.308346] systemd-homed[459]: Home test-homed-user is signed exclusively by our key, accepting.1862machine # [ 33.317671] systemd-homed[459]: test-homed-user: changing state activating-for-acquire → active1863machine # [ 33.321552] login[924]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired.1864machine # [ 33.329141] systemd-homed[459]: test-homed-user: changing state active → resizing-while-active1865machine # [ 33.339772] systemd-homework[1003]: Successfully acquired home volume key from kernel keyring.1866machine # [ 33.348369] systemd-homework[1003]: LUKS volume key from keyring unlocks user record.1867machine # [ 33.460261] systemd-homework[1003]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.1868machine # [ 33.465229] login[924]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0)1869machine # [ 33.578852] systemd[1]: Created slice Slice /user/60388.1870machine # [ 33.586307] systemd[1]: Starting User Runtime Directory /run/user/60388...1871machine # [ 33.615677] systemd-logind[491]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'.1872machine # [ 33.649296] systemd-homework[1003]: Successfully re-activated LUKS device.1873machine # [ 33.654587] systemd-homework[1003]: LUKS volume key from keyring unlocks user record.1874machine # [ 33.655870] systemd-homework[1003]: Discovered used loopback device /dev/loop1.1875machine # [ 33.659234] systemd-homework[1003]: Read embedded .identity file.1876machine # [ 33.660505] systemd-homework[1003]: LUKS volume key from keyring unlocks user record.1877machine # [ 33.661775] systemd-homework[1003]: Reconciling header user identity completed (host version was newer).1878machine # [ 33.663090] systemd-homework[1003]: Reconciling embedded user identity completed (host version was newer).1879machine # [ 33.668521] systemd-homework[1003]: offset = 1048576, size = 938475520, image = 9405726721880machine # [ 33.678972] systemd-homework[1003]: Ready to resize image size 897M → 765M, partition size 895M → 763M, file system size 879M → 747M.1881machine # [ 33.682942] systemd-homework[1003]: Wrote embedded .identity file.1882machine # [ 33.686508] systemd-homework[1003]: Replaced embedded blob dir with contents of system blob dir.1883machine # [ 33.687823] systemd-homework[1003]: Backing file is fully allocated already.1884machine # [ 33.708261] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 921698304 to 7832862721885machine # [ 33.698071] systemd-homework[1003]: File system resizing from 879M to 747M completed.1886machine # [ 33.699398] systemd-homework[1003]: Synchronized disk.1887machine # [ 33.716488] systemd[1]: Finished User Runtime Directory /run/user/60388.1888machine # [ 33.734177] systemd[1]: Starting User Manager for UID 60388...1889machine # [ 33.799200] systemd-homework[1003]: LUKS device shrinking completed.1890machine # [ 33.807704] (systemd)[1010]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.1891machine # [ 33.828456] loop1: detected capacity change from 1832960 to 15626241892machine # [ 33.813912] systemd-homework[1003]: Refreshing loop device size completed.1893machine # [ 33.822704] systemd-homework[1003]: Shrinking of image file completed.1894machine # [ 33.841671] systemd-homework[1003]: Shrinking of partition completed.1895machine # [ 33.879149] systemd-homework[1003]: Wrote LUKS header user record.1896machine # [ 33.888481] systemd-homework[1003]: Synchronized disk.1897machine # [ 33.889516] systemd-homework[1003]: Resizing completed.1898machine # [ 33.890345] systemd-homework[1003]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.1899machine # [ 33.906153] systemd-homed[459]: Home test-homed-user is signed exclusively by our key, accepting.1900machine # [ 33.915441] systemd-homed[459]: test-homed-user: changing state resizing-while-active → active1901machine # [ 33.922466] systemd-homed[459]: Rebalancing complete.1902machine # [ 33.932812] (systemd)[1010]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)1903machine # [ 33.938925] systemd-logind[491]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'.1904machine # [ 33.971511] systemd[1010]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1905machine # [ 34.108611] systemd[1010]: Queued start job for default target Main User Target.1906machine # [ 34.118011] systemd[1010]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1907machine # [ 34.123207] systemd[1010]: Created slice User Application Slice.1908machine # [ 34.127942] systemd[1010]: Started Daily Cleanup of User's Temporary Directories.1909machine # [ 34.136725] systemd[1010]: Reached target Paths.1910machine # [ 34.137577] systemd[1010]: Reached target Timers.1911machine # [ 34.138310] systemd[1010]: Starting D-Bus User Message Bus Socket...1912machine # [ 34.139275] systemd[1010]: Starting Create User Files and Directories...1913machine # [ 34.199918] systemd[1010]: Finished Create User Files and Directories.1914machine # [ 34.220231] systemd[1010]: Listening on D-Bus User Message Bus Socket.1915machine # [ 34.221614] systemd[1010]: Reached target Sockets.1916machine # [ 34.222381] systemd[1010]: Reached target Basic System.1917machine # [ 34.231166] systemd[1]: Started User Manager for UID 60388.1918machine # [ 34.236377] systemd[1010]: Starting Run user-specific NixOS activation...1919machine # [ 34.240604] systemd[1]: Started Session 1 of User test-homed-user.1920machine # [ 34.292191] systemd[1010]: Finished Run user-specific NixOS activation.1921machine # [ 34.296312] systemd[1010]: Reached target Main User Target.1922machine # [ 34.297494] systemd[1010]: Startup finished in 337ms.1923machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 3.31 seconds)1924machine: sending keys 'whoami > /tmp/2\n'1925machine: (finished: sending keys 'whoami > /tmp/2\n', in 0.17 seconds)1926machine: waiting for file '/tmp/2'1927machine: (finished: waiting for file '/tmp/2', in 1.06 seconds)1928machine: must succeed: cat /tmp/21929machine: (finished: must succeed: cat /tmp/2, in 0.03 seconds)1930(finished: subtest: login as homed user, in 6.03 seconds)1931subtest: login as regular user1932sshClient: waiting for login: to appear on tty 11933sshClient: waiting for the VM to finish booting1934sshClient: Guest shell says: b'Spawning backdoor root shell...\n'1935sshClient: connected to guest root shell1936sshClient: (connecting took 0.00 seconds)1937sshClient: (finished: waiting for the VM to finish booting, in 0.00 seconds)1938sshClient: (finished: waiting for login: to appear on tty 1, in 0.06 seconds)1939sshClient: sending keys 'test-normal-user\n'1940sshClient: (finished: sending keys 'test-normal-user\n', in 0.18 seconds)1941sshClient: waiting for Password: to appear on tty 11942sshClient: (finished: waiting for Password: to appear on tty 1, in 1.11 seconds)1943sshClient: sending keys 'foobarfoo\n'1944sshClient: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)1945sshClient: waiting for success: pgrep -u test-normal-user bash1946sshClient # [ 38.072204] login[682]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0)1947sshClient # [ 38.120665] systemd[1]: Created slice Slice /user/1000.1948sshClient # [ 38.125778] systemd[1]: Starting User Runtime Directory /run/user/1000...1949sshClient # [ 38.172563] systemd-logind[473]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'.1950sshClient # [ 38.201969] systemd[1]: Finished User Runtime Directory /run/user/1000.1951sshClient # [ 38.208679] systemd[1]: Starting User Manager for UID 1000...1952sshClient # [ 38.240891] (systemd)[791]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0)1953sshClient # [ 38.249508] systemd-logind[473]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'.1954sshClient # [ 38.279942] systemd[791]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1955sshClient # [ 38.409846] systemd[791]: Queued start job for default target Main User Target.1956sshClient # [ 38.417192] systemd[791]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1957sshClient # [ 38.421678] systemd[791]: Created slice User Application Slice.1958sshClient # [ 38.422780] systemd[791]: Started Daily Cleanup of User's Temporary Directories.1959sshClient # [ 38.423876] systemd[791]: Reached target Paths.1960sshClient # [ 38.424971] systemd[791]: Reached target Timers.1961sshClient # [ 38.427705] systemd[791]: Starting D-Bus User Message Bus Socket...1962sshClient # [ 38.430169] systemd[791]: Starting Create User Files and Directories...1963sshClient # [ 38.479985] systemd[791]: Finished Create User Files and Directories.1964sshClient # [ 38.500273] systemd[791]: Listening on D-Bus User Message Bus Socket.1965sshClient # [ 38.505419] systemd[791]: Reached target Sockets.1966sshClient # [ 38.506388] systemd[791]: Reached target Basic System.1967sshClient # [ 38.507218] systemd[1]: Started User Manager for UID 1000.1968sshClient # [ 38.510717] systemd[791]: Starting Run user-specific NixOS activation...1969sshClient # [ 38.516202] systemd[1]: Started Session 1 of User test-normal-user.1970sshClient # [ 38.581252] systemd[791]: Finished Run user-specific NixOS activation.1971sshClient # [ 38.582600] systemd[791]: Reached target Main User Target.1972sshClient # [ 38.583445] systemd[791]: Startup finished in 314ms.1973sshClient: (finished: waiting for success: pgrep -u test-normal-user bash, in 1.11 seconds)1974sshClient: sending keys 'whoami > /tmp/1\n'1975sshClient: (finished: sending keys 'whoami > /tmp/1\n', in 0.17 seconds)1976sshClient: waiting for file '/tmp/1'1977sshClient: (finished: waiting for file '/tmp/1', in 1.06 seconds)1978sshClient: must succeed: cat /tmp/11979sshClient: (finished: must succeed: cat /tmp/1, in 0.03 seconds)1980(finished: subtest: login as regular user, in 3.82 seconds)1981subtest: add homed ssh authorized key1982sshClient: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n'1983sshClient: (finished: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n', in 0.49 seconds)1984sshClient: waiting for file '/tmp/id_ed25519.pub'1985sshClient: (finished: waiting for file '/tmp/id_ed25519.pub', in 1.06 seconds)1986sshClient: must succeed: cat /tmp/id_ed25519.pub1987sshClient: (finished: must succeed: cat /tmp/id_ed25519.pub, in 0.03 seconds)1988machine: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAxNmMX70zsoSR57RHEMefz0Maruk4xNr3jzLsZplqGi test-normal-user@sshClient'1989machine # [ 41.952773] systemd-homed[459]: test-homed-user: changing state active → updating-while-active1990machine # [ 41.962490] systemd-homework[1061]: Offline update requested. Not touching embedded records.1991machine # [ 41.971957] systemd-homed[459]: test-homed-user: changing state updating-while-active → active1992machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAxNmMX70zsoSR57RHEMefz0Maruk4xNr3jzLsZplqGi test-normal-user@sshClient', in 0.07 seconds)1993machine: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed255191994machine: (finished: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed25519, in 0.03 seconds)1995(finished: subtest: add homed ssh authorized key, in 1.69 seconds)1996subtest: change homed user password1997machine: sending keys 'passwd; echo $? > /tmp/3\n'1998machine: (finished: sending keys 'passwd; echo $? > /tmp/3\n', in 0.26 seconds)1999machine: waiting for New password: to appear on tty 12000machine: (finished: waiting for New password: to appear on tty 1, in 1.11 seconds)2001machine: sending keys 'barfoobar\n'2002machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2003machine: waiting for Retype new password: to appear on tty 12004machine: (finished: waiting for Retype new password: to appear on tty 1, in 1.11 seconds)2005machine: sending keys 'barfoobar\n'2006machine: (finished: sending keys 'barfoobar\n', in 0.11 seconds)2007??? Warning (UserWarning): sleep(): The 'secs' argument is deprecated. Use 'duration' instead.2008 File "/nix/store/0yd36fpwpnbc3yrryy7qi9hd645vflhd-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 5732009machine: must succeed: sleep 4.02010machine # [ 44.844665] systemd-homed[459]: test-homed-user: changing state active → passwd-while-active2011machine # [ 45.628140] systemd-homework[1099]: No valid password for LUKS superblock.2012machine # [ 47.862617] systemd-homed[459]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2013machine # [ 47.866760] systemd-homed[459]: test-homed-user: changing state passwd-while-active → active2014machine: (finished: must succeed: sleep 4.0, in 4.04 seconds)2015machine: sending keys 'foobarfoo\n'2016machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)2017machine: waiting for file '/tmp/3'2018machine # [ 48.976677] systemd-homed[459]: test-homed-user: changing state active → passwd-while-active2019machine # [ 50.245786] systemd-homework[1105]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2020machine # [ 50.373389] systemd-homework[1105]: Successfully re-activated LUKS device.2021machine # [ 50.430685] systemd-homework[1105]: Provided password unlocks user record.2022machine # [ 50.436123] systemd-homework[1105]: Discovered used loopback device /dev/loop1.2023machine # [ 50.437360] systemd-homework[1105]: Read embedded .identity file.2024machine # [ 50.490465] systemd-homework[1105]: Provided password unlocks user record.2025machine # [ 50.496157] systemd-homework[1105]: Reconciling header user identity completed (host version was newer).2026machine # [ 50.497646] systemd-homework[1105]: Reconciling embedded user identity completed (host version was newer).2027machine # [ 53.627291] systemd-homework[1105]: Added LUKS key slot 1.2028machine # [ 53.651911] systemd-homework[1105]: Destroyed LUKS key slot 0.2029machine # [ 53.663842] systemd-homework[1105]: Wrote LUKS header user record.2030machine # [ 53.666008] systemd-homework[1105]: Wrote embedded .identity file.2031machine # [ 53.668331] systemd-homework[1105]: Replaced embedded blob dir with contents of system blob dir.2032machine # [ 53.672611] systemd-homework[1105]: Synchronized disk.2033machine # [ 53.678512] systemd-homework[1105]: Everything completed.2034machine # [ 53.690600] passwd[1074]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user.2035machine # [ 53.698745] systemd-homed[459]: test-homed-user: changing state passwd-while-active → active2036machine: (finished: waiting for file '/tmp/3', in 5.19 seconds)2037machine: must succeed: cat /tmp/32038machine: (finished: must succeed: cat /tmp/3, in 0.03 seconds)2039(finished: subtest: change homed user password, in 12.05 seconds)2040subtest: escalate to root from homed user2041machine: sending keys 'sudo id | tee /tmp/4\n'2042machine: (finished: sending keys 'sudo id | tee /tmp/4\n', in 0.22 seconds)2043machine: waiting for password for test-homed-user to appear on tty 12044machine # [ 54.534516] systemd-homed[459]: test-homed-user: changing state active → authenticating-for-acquire2045machine # [ 54.543444] systemd-homework[1142]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2046machine # [ 54.551783] systemd-homed[459]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2047machine # [ 54.555995] systemd-homed[459]: test-homed-user: changing state authenticating-for-acquire → active2048machine: (finished: waiting for password for test-homed-user to appear on tty 1, in 1.10 seconds)2049machine: sending keys 'barfoobar\n'2050machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2051machine: waiting for file '/tmp/4'2052machine: (finished: waiting for file '/tmp/4', in 0.03 seconds)2053machine: waiting for success: grep uid=0 /tmp/42054machine # [ 55.580434] systemd-homed[459]: test-homed-user: changing state active → authenticating-for-acquire2055machine # [ 55.628518] systemd-homework[1158]: Provided password unlocks user record.2056machine # [ 56.275054] systemd-homework[1158]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2057machine # [ 56.404067] systemd-homework[1158]: Successfully re-activated LUKS device.2058machine # [ 56.434691] systemd-homework[1158]: Provided password unlocks user record.2059machine # [ 56.436674] systemd-homework[1158]: Discovered used loopback device /dev/loop1.2060machine # [ 56.440101] systemd-homework[1158]: Read embedded .identity file.2061machine # [ 56.466041] systemd-homework[1158]: Provided password unlocks user record.2062machine # [ 56.468280] systemd-homework[1158]: Reconciling user identities completed (host and header version were identical).2063machine # [ 56.470382] systemd-homework[1158]: Reconciling embedded user identity completed (host and embedded version were identical).2064machine # [ 56.478392] systemd-homework[1158]: Everything completed.2065machine # [ 56.485751] systemd-homed[459]: Home test-homed-user is signed exclusively by our key, accepting.2066machine # [ 56.494813] systemd-homed[459]: test-homed-user: changing state authenticating-for-acquire → active2067machine # [ 56.497376] sudo[1140]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired.2068machine # [ 56.503103] sudo[1140]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id2069machine # [ 56.518167] sudo[1140]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388)2070machine # [ 56.528237] sudo[1140]: pam_unix(sudo:session): session closed for user root2071machine: (finished: waiting for success: grep uid=0 /tmp/4, in 1.06 seconds)2072(finished: subtest: escalate to root from homed user, in 2.52 seconds)2073subtest: log out and deactivate homed user's home area2074machine: sending keys 'exit\n'2075machine: (finished: sending keys 'exit\n', in 0.05 seconds)2076machine: waiting for success: homectl inspect test-homed-user | grep 'State: inactive'2077machine # [ 56.673923] login[924]: pam_unix(login:session): session closed for user test-homed-user2078machine # [ 56.691174] login[924]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used.2079machine # [ 56.717413] systemd[1]: getty@tty1.service: Deactivated successfully.2080machine # [ 56.718765] systemd[1]: session-1.scope: Deactivated successfully.2081machine # [ 56.719905] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.2082machine # [ 56.745262] systemd[1]: Started Getty on tty1.2083machine # [ 56.746465] systemd-logind[491]: Session 1 logged out. Waiting for processes to exit.2084machine # [ 56.747693] systemd-logind[491]: Removed session 1.2085machine # [ 66.896682] systemd[1]: Stopping User Manager for UID 60388...2086machine # [ 66.905556] systemd[1010]: Activating special unit Exit the Session...2087machine # [ 66.912220] systemd[1010]: Stopped target Main User Target.2088machine # [ 66.913850] systemd[1010]: Stopped Run user-specific NixOS activation.2089machine # [ 66.915340] systemd[1010]: Stopped target Basic System.2090machine # [ 66.920543] systemd[1010]: Stopped target Paths.2091machine # [ 66.921860] systemd[1010]: Stopped target Sockets.2092machine # [ 66.922985] systemd[1010]: Stopped target Timers.2093machine # [ 66.924198] systemd[1010]: Stopped Daily Cleanup of User's Temporary Directories.2094machine # [ 66.925872] systemd[1010]: Closed D-Bus User Message Bus Socket.2095machine # [ 66.927208] systemd[1010]: Stopped Create User Files and Directories.2096machine # [ 66.928840] systemd[1010]: Removed slice User Application Slice.2097machine # [ 66.930281] systemd[1010]: Reached target Shutdown.2098machine # [ 66.931418] systemd[1010]: Finished Exit the Session.2099machine # [ 66.932861] systemd[1010]: Reached target Exit the Session.2100machine # [ 66.956777] systemd-homed[459]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2101machine # [ 66.973821] systemd-logind[491]: Removed session 2.2102machine # [ 66.975163] systemd-homed[459]: test-homed-user: changing state active → deactivating2103machine # [ 66.979500] systemd[1]: user@60388.service: Deactivated successfully.2104machine # [ 66.983319] systemd[1]: Stopped User Manager for UID 60388.2105machine # [ 66.988582] systemd-homework[1232]: Successfully acquired home volume key from kernel keyring.2106machine # [ 66.990483] systemd-homework[1232]: Discarded unused 674.3M.2107machine # [ 66.995583] systemd-homework[1232]: Syncing completed.2108machine # [ 67.042902] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully.2109machine # [ 67.054321] systemd[1]: Stopping User Runtime Directory /run/user/60388...2110machine # [ 67.092126] systemd-homework[1232]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2111machine # [ 67.205382] systemd[1]: run-user-60388.mount: Deactivated successfully.2112machine # [ 67.215215] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully.2113machine # [ 67.221774] systemd[1]: Stopped User Runtime Directory /run/user/60388.2114machine # [ 67.262204] systemd-homework[1232]: Successfully re-activated LUKS device.2115machine # [ 67.263368] systemd-homework[1232]: Discovered used loopback device /dev/loop1.2116machine # [ 67.267763] systemd-homework[1232]: offset = 1048576, size = 800063488, image = 8021606402117machine # [ 67.279629] systemd-homework[1232]: Ready to resize image size 765M → 274M, partition size 763M → 272M, file system size 747M → 256M.2118machine # [ 67.284467] systemd-homework[1232]: Allocated additional 674.3M.2119machine # [ 67.290647] systemd-homework[1232]: File system resizing from 747M to 256M completed.[ 67.307587] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 783286272 to 2684354562120machine # 2121machine # [ 67.299278] systemd-homework[1232]: Synchronized disk.2122machine # [ 67.428923] systemd-homework[1232]: LUKS device shrinking completed.2123machine # [ 67.458489] loop1: detected capacity change from 1562624 to 5570562124machine # [ 67.446046] systemd-homework[1232]: Refreshing loop device size completed.2125machine # [ 67.447260] systemd-homework[1232]: Shrinking of image file completed.2126machine # [ 67.460469] systemd-homework[1232]: Shrinking of partition completed.2127machine # [ 67.462795] systemd-homework[1232]: Synchronized disk.2128machine # [ 67.480524] BTRFS info (device dm-0): last unmount of filesystem c84feec6-7304-42fa-812c-340d4c015a522129machine # [ 67.468628] systemd-homework[1232]: Resizing completed.2130machine # [ 67.469675] systemd-homework[1232]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M.2131machine # [ 67.471412] systemd-homework[1232]: Unmounting completed.2132machine # [ 67.473350] systemd-homework[1232]: Discovered used LUKS device /dev/mapper/home-test-homed-user.2133machine # [ 67.474658] systemd-homework[1232]: Device home-test-homed-user is not active.2134machine # [ 67.513084] systemd-homed[459]: block device /sys/devices/virtual/block/dm-0 has been removed.2135machine # [ 67.517807] systemd-homework[1232]: Everything completed.2136machine # [ 67.535771] systemd-homed[459]: test-homed-user: changing state deactivating → inactive2137machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 11.73 seconds)2138(finished: subtest: log out and deactivate homed user's home area, in 11.79 seconds)2139subtest: ssh as homed user2140sshClient: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n'2141sshClient: (finished: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n', in 0.78 seconds)2142sshClient: waiting for Please enter password for user to appear on tty 12143machine # [ 70.190465] systemd-homed[459]: test-homed-user: changing state inactive → activating-for-acquire2144machine # [ 70.199033] systemd-homework[1266]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2145machine # [ 70.207037] systemd-homed[459]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2146machine # [ 70.212465] systemd-homed[459]: test-homed-user: changing state activating-for-acquire → inactive2147machine # [ 70.215517] sshd-session[1262]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error2148machine # [ 70.220885] systemd-homed[459]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2149machine # [ 70.223590] sshd-session[1262]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation.2150machine # [ 70.227027] systemd-homed[459]: Home test-homed-user already deactivated, no automatic deactivation needed.2151machine # [ 70.229349] sshd-session[1262]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 36038 ssh2: ED25519 SHA256:zD6owfigmJuVr9sTJohdLFU7PV/Q3PBHXLz5owGLiQs2152machine # [ 70.278086] sshd-session[1262]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0)2153machine # [ 70.292737] systemd-logind[491]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'.2154machine # [ 70.299730] systemd[1]: Starting User Runtime Directory /run/user/60388...2155machine # [ 70.334348] systemd[1]: Finished User Runtime Directory /run/user/60388.2156machine # [ 70.342167] systemd[1]: Started Session 3 of User test-homed-user.2157machine # [ 70.360591] sshd-session[1262]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2158machine # [ 70.365312] sshd-session[1262]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2159machine # [ 70.387153] systemd-homed[459]: test-homed-user: changing state inactive → activating2160machine # [ 70.394830] systemd-homework[1272]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2161machine # [ 70.402127] systemd-homed[459]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2162machine # [ 70.404818] systemd-homed[459]: test-homed-user: changing state activating → inactive2163sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.15 seconds)2164sshClient: sending keys 'barfoobar\n'2165sshClient: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2166machine: waiting for success: pgrep -u test-homed-user bash2167machine # [ 71.504142] systemd-homed[459]: test-homed-user: changing state inactive → activating2168machine # [ 71.559327] systemd-homework[1278]: Provided password unlocks user record.2169machine # [ 71.565374] systemd-homework[1278]: Successfully locked image file '/home/test-homed-user.home'.2170machine # [ 71.570901] systemd-homework[1278]: Backing file is fully allocated already.2171machine # [ 71.575118] systemd-homework[1278]: Setting up loopback device /dev/loop1 completed.[ 71.592448] loop1: detected capacity change from 0 to 5570562172machine # 2173machine # [ 72.448122] systemd-homework[1278]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.2174machine # [ 72.498484] systemd-homework[1278]: Provided password unlocks user record.2175machine # [ 72.510251] systemd-homework[1278]: Probing file system completed (found btrfs).2176machine # [ 72.512139] systemd-homework[1278]: No fsck for file system btrfs installed, ignoring.2177machine # [ 72.534339] BTRFS: device label test-homed-user devid 1 transid 17 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (1278)2178machine # [ 72.539261] BTRFS info (device dm-0): first mount of filesystem c84feec6-7304-42fa-812c-340d4c015a522179machine # [ 72.539324] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm2180machine # [ 72.530523] systemd-homework[1278]: Mounting file system completed.2181machine # [ 72.548304] BTRFS info (device dm-0): enabling free space tree2182machine # [ 72.548331] BTRFS info (device dm-0): use zstd compression, level 12183machine # [ 72.538571] systemd-homework[1278]: Discovered used loopback device /dev/loop1.2184machine # [ 72.539700] systemd-homework[1278]: offset = 1048576, size = 285212672, image = 2873098242185machine # [ 72.554582] systemd-homework[1278]: Ready to resize image size 274M → 765M, partition size 272M → 763M, file system size 256M → 747M.2186machine # [ 72.556895] systemd-homework[1278]: Growing of image file from 274M to 765M completed.2187machine # [ 72.582536] loop1: detected capacity change from 557056 to 15626242188machine # [ 72.569746] systemd-homework[1278]: Refreshing loop device size completed.2189machine # [ 72.581236] systemd-homework[1278]: Growing of partition completed.2190machine # [ 72.627613] systemd-homework[1278]: LUKS device growing completed.2191machine # [ 72.634262] systemd-homework[1278]: File system resizing from 256M to 747M completed.2192machine # [ 72.635508] systemd-homework[1278]: Synchronized disk.[ 72.651504] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 7832862722193machine # 2194machine # [ 72.639401] systemd-homework[1278]: Synchronized disk.2195machine # [ 72.644265] systemd-homework[1278]: Resizing completed.2196machine # [ 72.645164] systemd-homework[1278]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.2197machine # [ 72.646873] systemd-homework[1278]: Read embedded .identity file.2198machine # [ 72.669259] systemd-homework[1278]: Provided password unlocks user record.2199machine # [ 72.674080] systemd-homework[1278]: Reconciling user identities completed (host and header version were identical).2200machine # [ 72.675706] systemd-homework[1278]: Reconciling embedded user identity completed (host and embedded version were identical).2201machine # [ 72.681469] systemd-homework[1278]: Recursive changing of ownership not necessary, skipped.2202machine # [ 72.682769] systemd-homework[1278]: Synchronized disk.2203machine # [ 72.684155] systemd-homework[1278]: Moving to final mount point /home/test-homed-user completed.2204machine # [ 72.705334] systemd-homework[1278]: Activation completed.2205machine # [ 72.706403] systemd-homework[1278]: Image size is 763M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.2206machine # [ 72.721847] systemd-homed[459]: Home test-homed-user is signed exclusively by our key, accepting.2207machine # [ 72.726775] systemd-homed[459]: test-homed-user: changing state activating → active2208machine # [ 72.747198] systemd[1]: Starting User Manager for UID 60388...2209machine # [ 72.790904] (systemd)[1318]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.2210machine # [ 72.830963] (systemd)[1318]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)2211machine # [ 72.841102] systemd-logind[491]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'.2212machine # [ 72.871299] systemd[1318]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2213machine # [ 72.981032] systemd[1318]: Queued start job for default target Main User Target.2214machine # [ 72.985087] systemd[1318]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2215machine # [ 72.989388] systemd[1318]: Created slice User Application Slice.2216machine # [ 72.990441] systemd[1318]: Started Daily Cleanup of User's Temporary Directories.2217machine # [ 72.991473] systemd[1318]: Reached target Paths.2218machine # [ 72.994127] systemd[1318]: Reached target Timers.2219machine # [ 72.994899] systemd[1318]: Starting D-Bus User Message Bus Socket...2220machine # [ 72.997348] systemd[1318]: Starting Create User Files and Directories...2221machine # [ 73.041753] systemd[1318]: Finished Create User Files and Directories.2222machine # [ 73.044054] systemd[1318]: Listening on D-Bus User Message Bus Socket.2223machine # [ 73.049275] systemd[1318]: Reached target Sockets.2224machine # [ 73.050162] systemd[1318]: Reached target Basic System.2225machine # [ 73.050935] systemd[1]: Started User Manager for UID 60388.2226machine # [ 73.056319] systemd[1318]: Starting Run user-specific NixOS activation...2227machine # [ 73.108385] systemd[1318]: Finished Run user-specific NixOS activation.2228machine # [ 73.112661] systemd[1318]: Reached target Main User Target.2229machine # [ 73.113721] systemd[1318]: Startup finished in 252ms.2230machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.20 seconds)2231sshClient: sending keys 'whoami > /tmp/5\n'2232sshClient: (finished: sending keys 'whoami > /tmp/5\n', in 0.17 seconds)2233machine: waiting for file '/tmp/5'2234machine: (finished: waiting for file '/tmp/5', in 1.05 seconds)2235machine: must succeed: cat /tmp/52236machine: (finished: must succeed: cat /tmp/5, in 0.03 seconds)2237sshClient: sending keys 'exit\n'2238sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2239sshClient: sending keys 'exit\n'2240sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2241(finished: subtest: ssh as homed user, in 6.60 seconds)2242(finished: run the VM test script, in 75.96 seconds)2243machine # [ 74.964835] sshd-session[1269]: Received disconnect from 2001:db8:1::2 port 36038:11: disconnected by user2244machine # [ 74.968702] sshd-session[1269]: Disconnected from user test-homed-user 2001:db8:1::2 port 360382245machine # [ 74.983696] sshd-session[1262]: pam_unix(sshd:session): session closed for user test-homed-user2246machine # [ 74.994451] sshd-session[1262]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used.2247machine # [ 75.008909] systemd[1]: session-3.scope: Deactivated successfully.2248machine # [ 75.011054] systemd-logind[491]: Session 3 logged out. Waiting for processes to exit.2249machine # [ 75.015839] systemd-logind[491]: Removed session 3.2250test script finished in 76.62s2251cleanup2252kill QemuMachine (pid 47)2253machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14)2254machine # [2026-10-24T03:03:04Z INFO virtiofsd] Client disconnected, shutting down2255machine # [2026-10-24T03:03:04Z INFO virtiofsd] Client disconnected, shutting down2256machine # [2026-10-24T03:03:04Z INFO virtiofsd] Client disconnected, shutting down2257kill QemuMachine (pid 48)2258sshClient # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14)2259sshClient # [2026-10-24T03:03:04Z INFO virtiofsd] Client disconnected, shutting down2260sshClient # [2026-10-24T03:03:04Z INFO virtiofsd] Client disconnected, shutting down2261sshClient # [2026-10-24T03:03:04Z INFO virtiofsd] Client disconnected, shutting down2262(finished: cleanup, in 0.34 seconds)2263additionally exposed symbols:2264 machine, sshClient,2265 vlan1,2266 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