vm-test-run-systemd-homed
checks.aarch64-linux.systemd-homed
· build #62
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.01 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7start all VMs8machine: starting vm9sshClient: starting vm10machine # Disk image does not exist, creating the virtualisation disk image...11machine # Formatting '/build/vm-state-machine/tmp.EE797H7Oy3', fmt=raw size=107374182412machine: QEMU running (pid 47)13machine # mke2fs 1.47.4 (6-Mar-2025)14machine # Discarding device blocks: 0/262144 done15machine # Creating filesystem with 262144 4k blocks and 65536 inodes16machine # Filesystem UUID: 5c2a11b9-ee7b-424c-99e7-6fc20b7162e317machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # Starting virtiofs daemons...27machine # [2026-10-20T03:08:29Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-10-20T03:08:29Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-10-20T03:08:29Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)30machine # [2026-10-20T03:08:29Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether31machine # [2026-10-20T03:08:29Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32machine # [2026-10-20T03:08:29Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33machine # [2026-10-20T03:08:29Z INFO virtiofsd] Waiting for vhost-user socket connection...34machine # [2026-10-20T03:08:29Z INFO virtiofsd] Waiting for vhost-user socket connection...35machine # [2026-10-20T03:08:29Z INFO virtiofsd] Waiting for vhost-user socket connection...36machine # [2026-10-20T03:08:29Z INFO virtiofsd] Client connected, servicing requests37machine # [2026-10-20T03:08:29Z INFO virtiofsd] Client connected, servicing requests38machine # [2026-10-20T03:08:29Z INFO virtiofsd] Client connected, servicing requests39sshClient: QEMU running (pid 48)40sshClient # Disk image does not exist, creating the virtualisation disk image...41sshClient # Formatting '/build/vm-state-sshClient/tmp.XjNR3F6GQS', fmt=raw size=107374182442(finished: start all VMs, in 0.81 seconds)43sshClient # mke2fs 1.47.4 (6-Mar-2025)44subtest: create systemd-homed user on first boot prompt45sshClient # Discarding device blocks: 0/262144 done46machine: waiting for unit systemd-homed.service47sshClient # Creating filesystem with 262144 4k blocks and 65536 inodes48machine: waiting for the VM to finish booting49sshClient # Filesystem UUID: b1049575-d96d-440d-8d18-35b7e72be4e050sshClient # Superblock backups stored on blocks:51sshClient # 32768, 98304, 163840, 22937652sshClient # 53sshClient # Allocating group tables: 0/8 done54sshClient # Writing inode tables: 0/8 done55sshClient # Creating journal (8192 blocks): done56sshClient # Writing superblocks and filesystem accounting information: 0/8 done57sshClient # 58sshClient # Virtualisation disk image created.59sshClient # Starting virtiofs daemons...60sshClient # [2026-10-20T03:08:29Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)61sshClient # [2026-10-20T03:08:29Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether62sshClient # [2026-10-20T03:08:29Z INFO virtiofsd] Waiting for vhost-user socket connection...63sshClient # [2026-10-20T03:08:29Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)64sshClient # [2026-10-20T03:08:29Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether65sshClient # [2026-10-20T03:08:29Z INFO virtiofsd] Client connected, servicing requests66sshClient # [2026-10-20T03:08:29Z INFO virtiofsd] Waiting for vhost-user socket connection...67sshClient # [2026-10-20T03:08:29Z INFO virtiofsd] Client connected, servicing requests68sshClient # [2026-10-20T03:08:29Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)69sshClient # [2026-10-20T03:08:29Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether70sshClient # [2026-10-20T03:08:29Z INFO virtiofsd] Waiting for vhost-user socket connection...71sshClient # [2026-10-20T03:08:29Z INFO virtiofsd] Client connected, servicing requests72machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]73machine # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @179205830074machine # [ 0.000000] KASLR enabled75machine # [ 0.000000] random: crng init done76machine # [ 0.000000] Machine model: linux,dummy-virt77machine # [ 0.000000] efi: UEFI not found.78machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT79machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]80machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]81machine # [ 0.000000] Zone ranges:82machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]83machine # [ 0.000000] DMA32 empty84machine # [ 0.000000] Normal empty85machine # [ 0.000000] Device empty86machine # [ 0.000000] Movable zone start for each node87machine # [ 0.000000] Early memory node ranges88machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]89machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]90machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000091machine # [ 0.000000] psci: probing for conduit method from DT.92machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.93machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs94machine # [ 0.000000] psci: Trusted OS migration not required95machine # [ 0.000000] psci: SMC Calling Convention v1.196machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)97machine # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u31129698machine # [ 0.000000] Detected PIPT I-cache on CPU099machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)100machine # [ 0.000000] CPU features: detected: GICv3 CPU interface101machine # [ 0.000000] CPU features: detected: Spectre-v4102machine # [ 0.000000] CPU features: detected: Spectre-BHB103machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38104machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23105machine # [ 0.000000] alternatives: applying boot alternatives106machine # [ 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/4jl3x0ngls3a9gvmalk17qj5260fvjcc-nixos-system-machine-test/init regInfo=/nix/.ro-store/wqzaf1hklfml0dxfqw78hh870ajc970i-closure-info/registration console=ttyAMA0,115200n8 console=tty0107machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/wqzaf1hklfml0dxfqw78hh870ajc970i-closure-info/registration", will be passed to user space.108machine # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes109machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)110machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)111machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB112machine # [ 0.000000] software IO TLB: area num 1.113machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)114machine # [ 0.000000] Fallback order for Node 0: 0115machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144116machine # [ 0.000000] Policy zone: DMA117machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off118machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1119machine # [ 0.000000] allocated 2097152 bytes of page_ext120machine # [ 0.000000] ftrace: allocating 75301 entries in 296 pages121machine # [ 0.000000] ftrace: allocated 296 pages with 3 groups122machine # [ 0.000000] rcu: Hierarchical RCU implementation.123machine # [ 0.000000] rcu: RCU event tracing is enabled.124machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.125machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.126machine # [ 0.000000] Rude variant of Tasks RCU enabled.127machine # [ 0.000000] Tracing variant of Tasks RCU enabled.128machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.129machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1130machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.131machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.132machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.133machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0134machine # [ 0.000000] GICv3: 256 SPIs implemented135machine # [ 0.000000] GICv3: 0 Extended SPIs implemented136machine # [ 0.000000] Root IRQ handler: gic_handle_irq137machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI138machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0139machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000140machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]141machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450f0000 (indirect, esz 8, psz 64K, shr 1)142machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @45100000 (flat, esz 8, psz 64K, shr 1)143machine # [ 0.000000] GICv3: using LPI property table @0x0000000045110000144machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000145machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.146machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns147machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).148machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns149machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns150machine # [ 0.000035] arm-pv: using stolen time PV151machine # [ 0.000538] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)152machine # [ 0.000736] Console: colour dummy device 80x25153machine # [ 0.000747] printk: legacy console [tty0] enabled154machine # [ 0.000950] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)155machine # [ 0.000958] pid_max: default: 32768 minimum: 301156machine # [ 0.001079] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima157machine # [ 0.001355] landlock: Up and running.158machine # [ 0.001359] Yama: becoming mindful.159machine # [ 0.001903] LSM support for eBPF active160machine # [ 0.002056] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)161machine # [ 0.002077] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)162machine # [ 0.004459] rcu: Hierarchical SRCU implementation.163machine # [ 0.004466] rcu: Max phase no-delay instances is 1000.164machine # [ 0.005874] fsl-mc MSI: its@8080000 domain created165machine # [ 0.006003] EFI services will not be available.166machine # [ 0.006118] smp: Bringing up secondary CPUs ...167machine # [ 0.006129] smp: Brought up 1 node, 1 CPU168machine # [ 0.006133] SMP: Total of 1 processors activated.169machine # [ 0.006136] CPU: All CPU(s) started at EL1170machine # [ 0.006147] CPU features: detected: Branch Target Identification171machine # [ 0.006152] CPU features: detected: ARMv8.4 Translation Table Level172machine # [ 0.006154] CPU features: detected: Instruction cache invalidation not required for I/D coherence173machine # [ 0.006158] CPU features: detected: Data cache clean to the PoU not required for I/D coherence174machine # [ 0.006168] CPU features: detected: Common not Private translations175machine # [ 0.006171] CPU features: detected: CRC32 instructions176machine # [ 0.006174] CPU features: detected: Data cache clean to Point of Deep Persistence177machine # [ 0.006177] CPU features: detected: Data cache clean to Point of Persistence178machine # [ 0.006181] CPU features: detected: Data independent timing control (DIT)179machine # [ 0.006184] CPU features: detected: E0PD180machine # [ 0.006186] CPU features: detected: Enhanced Counter Virtualization181machine # [ 0.006189] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)182machine # [ 0.006193] CPU features: detected: Enhanced Virtualization Traps183machine # [ 0.006196] CPU features: detected: Fine Grained Traps184machine # [ 0.006200] CPU features: detected: Generic authentication (architected QARMA5 algorithm)185machine # [ 0.006204] CPU features: detected: RCpc load-acquire (LDAPR)186machine # [ 0.006207] CPU features: detected: LSE atomic instructions187machine # [ 0.006211] CPU features: detected: Privileged Access Never188machine # [ 0.006213] CPU features: detected: PMUv3189machine # [ 0.006216] CPU features: detected: RAS Extension Support190machine # [ 0.006219] CPU features: detected: RASv1p1 Extension Support191machine # [ 0.006221] CPU features: detected: Random Number Generator192machine # [ 0.006228] CPU features: detected: Speculation barrier (SB)193machine # [ 0.006231] CPU features: detected: Stage-2 Force Write-Back194machine # [ 0.006234] CPU features: detected: TLB range maintenance instructions195machine # [ 0.006238] CPU features: detected: Speculative Store Bypassing Safe (SSBS)196machine # [ 0.006276] alternatives: applying system-wide alternatives197machine # [ 0.009595] CPU features: detected: BBM Level 2 without TLB conflict abort198machine # [ 0.009835] Memory: 886672K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 120600K reserved, 32768K cma-reserved)199machine # [ 0.010254] devtmpfs: initialized200machine # [ 0.012104] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)201machine # [ 0.012131] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).202machine # [ 0.012346] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL203machine # [ 0.012351] 0 pages in range for non-PLT usage204machine # [ 0.012352] 506368 pages in range for PLT usage205machine # [ 0.012469] pinctrl core: initialized pinctrl subsystem206machine # [ 0.013401] DMI not present or invalid.207machine # [ 0.017919] NET: Registered PF_NETLINK/PF_ROUTE protocol family208machine # [ 0.020660] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations209machine # [ 0.020803] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations210machine # [ 0.020960] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations211machine # [ 0.020987] audit: initializing netlink subsys (disabled)212machine # [ 0.021668] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1213machine # [ 0.021740] thermal_sys: Registered thermal governor 'fair_share'214machine # [ 0.021742] thermal_sys: Registered thermal governor 'bang_bang'215machine # [ 0.021746] thermal_sys: Registered thermal governor 'step_wise'216machine # [ 0.021749] thermal_sys: Registered thermal governor 'user_space'217machine # [ 0.021752] thermal_sys: Registered thermal governor 'power_allocator'218machine # [ 0.021778] cpuidle: using governor ladder219machine # [ 0.021787] cpuidle: using governor menu220machine # [ 0.021991] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.221machine # [ 0.022011] ASID allocator initialised with 65536 entries222machine # [ 0.023440] Serial: AMBA PL011 UART driver223machine # [ 0.029518] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1224machine # [ 0.029719] printk: console [ttyAMA0] enabled225machine # [ 0.157786] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages226machine # [ 0.157796] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page227machine # [ 0.157799] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages228machine # [ 0.157802] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page229machine # [ 0.157809] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages230machine # [ 0.157812] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page231machine # [ 0.157814] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages232machine # [ 0.157817] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page233machine # [ 0.175302] fbcon: Taking over console234machine # [ 0.175317] ACPI: Interpreter disabled.235machine # [ 0.176309] iommu: Default domain type: Translated236machine # [ 0.176314] iommu: DMA domain TLB invalidation policy: strict mode237machine # [ 0.177172] SCSI subsystem initialized238machine # [ 0.177575] usbcore: registered new interface driver usbfs239machine # [ 0.177607] usbcore: registered new interface driver hub240machine # [ 0.177621] usbcore: registered new device driver usb241machine # [ 0.177915] pps_core: LinuxPPS API ver. 1 registered242machine # [ 0.177919] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>243machine # [ 0.177927] PTP clock support registered244machine # [ 0.177987] EDAC MC: Ver: 3.0.0245machine # [ 0.185944] scmi_core: SCMI protocol bus registered246machine # [ 0.187218] FPGA manager framework247machine # [ 0.190957] vgaarb: loaded248machine # [ 0.192261] clocksource: Switched to clocksource arch_sys_counter249machine # [ 0.198155] VFS: Disk quotas dquot_6.6.0250machine # [ 0.198204] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)251machine # [ 0.198660] netfs: FS-Cache loaded252machine # [ 0.198798] pnp: PnP ACPI: disabled253machine # [ 0.204809] NET: Registered PF_INET protocol family254machine # [ 0.205001] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)255sshClient # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]256sshClient # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1792058300257sshClient # [ 0.000000] KASLR enabled258sshClient # [ 0.000000] random: crng init done259sshClient # [ 0.000000] Machine model: linux,dummy-virt260sshClient # [ 0.000000] efi: UEFI not found.261sshClient # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT262sshClient # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]263sshClient # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]264sshClient # [ 0.000000] Zone ranges:265sshClient # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]266sshClient # [ 0.000000] DMA32 empty267sshClient # [ 0.000000] Normal empty268sshClient # [ 0.000000] Device empty269sshClient # [ 0.000000] Movable zone start for each node270sshClient # [ 0.000000] Early memory node ranges271sshClient # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]272sshClient # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]273sshClient # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000274sshClient # [ 0.000000] psci: probing for conduit method from DT.275sshClient # [ 0.000000] psci: PSCIv1.3 detected in firmware.276sshClient # [ 0.000000] psci: Using standard PSCI v0.2 function IDs277sshClient # [ 0.000000] psci: Trusted OS migration not required278sshClient # [ 0.000000] psci: SMC Calling Convention v1.1279sshClient # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)280sshClient # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u311296281sshClient # [ 0.000000] Detected PIPT I-cache on CPU0282sshClient # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)283sshClient # [ 0.000000] CPU features: detected: GICv3 CPU interface284sshClient # [ 0.000000] CPU features: detected: Spectre-v4285sshClient # [ 0.000000] CPU features: detected: Spectre-BHB286sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38287sshClient # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23288sshClient # [ 0.000000] alternatives: applying boot alternatives289machine # [ 0.237261] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)290sshClient # [ 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/7mk8s03nb07v39gh9yn9jvpz0gadzcbr-nixos-system-sshClient-test/init regInfo=/nix/.ro-store/5kh8cb458ham5k0vyyj63ysnh5pafvvq-closure-info/registration console=ttyAMA0,115200n8 console=tty0291machine # [ 0.237308] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)292machine # [ 0.237337] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)293sshClient # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/5kh8cb458ham5k0vyyj63ysnh5pafvvq-closure-info/registration", will be passed to user space.294machine # [ 0.237385] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)295sshClient # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes296machine # [ 0.237458] TCP: Hash tables configured (established 8192 bind 8192)297sshClient # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)298machine # [ 0.237556] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)299machine # [ 0.237616] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)300sshClient # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)301machine # [ 0.237640] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)302sshClient # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB303sshClient # [ 0.000000] software IO TLB: area num 1.304machine # [ 0.237734] NET: Registered PF_UNIX/PF_LOCAL protocol family305machine # [ 0.237775] NET: Registered PF_XDP protocol family306sshClient # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)307machine # [ 0.237793] PCI: CLS 0 bytes, default 64308sshClient # [ 0.000000] Fallback order for Node 0: 0309machine # [ 0.238059] Trying to unpack rootfs image as initramfs...310sshClient # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144311sshClient # [ 0.000000] Policy zone: DMA312sshClient # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off313sshClient # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1314sshClient # [ 0.000000] allocated 2097152 bytes of page_ext315sshClient # [ 0.000000] ftrace: allocating 75301 entries in 296 pages316sshClient # [ 0.000000] ftrace: allocated 296 pages with 3 groups317sshClient # [ 0.000000] rcu: Hierarchical RCU implementation.318sshClient # [ 0.000000] rcu: RCU event tracing is enabled.319sshClient # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.320sshClient # [ 0.000000] Trampoline variant of Tasks RCU enabled.321sshClient # [ 0.000000] Rude variant of Tasks RCU enabled.322sshClient # [ 0.000000] Tracing variant of Tasks RCU enabled.323sshClient # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.324machine # [ 0.256306] kvm [1]: HYP mode not available325sshClient # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1326sshClient # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.327sshClient # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.328sshClient # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.329sshClient # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0330sshClient # [ 0.000000] GICv3: 256 SPIs implemented331sshClient # [ 0.000000] GICv3: 0 Extended SPIs implemented332sshClient # [ 0.000000] Root IRQ handler: gic_handle_irq333sshClient # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI334sshClient # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0335sshClient # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000336sshClient # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]337sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450f0000 (indirect, esz 8, psz 64K, shr 1)338sshClient # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @45100000 (flat, esz 8, psz 64K, shr 1)339sshClient # [ 0.000000] GICv3: using LPI property table @0x0000000045110000340sshClient # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000341sshClient # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.342sshClient # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns343sshClient # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).344sshClient # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns345sshClient # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns346sshClient # [ 0.000038] arm-pv: using stolen time PV347sshClient # [ 0.000516] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)348sshClient # [ 0.000693] Console: colour dummy device 80x25349sshClient # [ 0.000702] printk: legacy console [tty0] enabled350sshClient # [ 0.000919] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)351sshClient # [ 0.000928] pid_max: default: 32768 minimum: 301352sshClient # [ 0.001028] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima353sshClient # [ 0.001218] landlock: Up and running.354sshClient # [ 0.001221] Yama: becoming mindful.355sshClient # [ 0.001814] LSM support for eBPF active356sshClient # [ 0.001956] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)357sshClient # [ 0.001978] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)358sshClient # [ 0.004229] rcu: Hierarchical SRCU implementation.359sshClient # [ 0.004237] rcu: Max phase no-delay instances is 1000.360sshClient # [ 0.005549] fsl-mc MSI: its@8080000 domain created361sshClient # [ 0.005671] EFI services will not be available.362sshClient # [ 0.005757] smp: Bringing up secondary CPUs ...363sshClient # [ 0.005768] smp: Brought up 1 node, 1 CPU364sshClient # [ 0.005772] SMP: Total of 1 processors activated.365sshClient # [ 0.005775] CPU: All CPU(s) started at EL1366sshClient # [ 0.005787] CPU features: detected: Branch Target Identification367sshClient # [ 0.005791] CPU features: detected: ARMv8.4 Translation Table Level368sshClient # [ 0.005794] CPU features: detected: Instruction cache invalidation not required for I/D coherence369sshClient # [ 0.005798] CPU features: detected: Data cache clean to the PoU not required for I/D coherence370sshClient # [ 0.005807] CPU features: detected: Common not Private translations371sshClient # [ 0.005810] CPU features: detected: CRC32 instructions372sshClient # [ 0.005813] CPU features: detected: Data cache clean to Point of Deep Persistence373sshClient # [ 0.005816] CPU features: detected: Data cache clean to Point of Persistence374sshClient # [ 0.005820] CPU features: detected: Data independent timing control (DIT)375sshClient # [ 0.005823] CPU features: detected: E0PD376sshClient # [ 0.005825] CPU features: detected: Enhanced Counter Virtualization377sshClient # [ 0.005829] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)378sshClient # [ 0.005832] CPU features: detected: Enhanced Virtualization Traps379sshClient # [ 0.005836] CPU features: detected: Fine Grained Traps380sshClient # [ 0.005839] CPU features: detected: Generic authentication (architected QARMA5 algorithm)381sshClient # [ 0.005844] CPU features: detected: RCpc load-acquire (LDAPR)382sshClient # [ 0.005847] CPU features: detected: LSE atomic instructions383sshClient # [ 0.005850] CPU features: detected: Privileged Access Never384sshClient # [ 0.005853] CPU features: detected: PMUv3385sshClient # [ 0.005856] CPU features: detected: RAS Extension Support386sshClient # [ 0.005859] CPU features: detected: RASv1p1 Extension Support387sshClient # [ 0.005862] CPU features: detected: Random Number Generator388sshClient # [ 0.005867] CPU features: detected: Speculation barrier (SB)389sshClient # [ 0.005870] CPU features: detected: Stage-2 Force Write-Back390sshClient # [ 0.005873] CPU features: detected: TLB range maintenance instructions391sshClient # [ 0.005878] CPU features: detected: Speculative Store Bypassing Safe (SSBS)392sshClient # [ 0.005920] alternatives: applying system-wide alternatives393sshClient # [ 0.009152] CPU features: detected: BBM Level 2 without TLB conflict abort394sshClient # [ 0.009340] Memory: 886664K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 120592K reserved, 32768K cma-reserved)395sshClient # [ 0.009780] devtmpfs: initialized396sshClient # [ 0.011811] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)397sshClient # [ 0.011843] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).398sshClient # [ 0.012043] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL399sshClient # [ 0.012049] 0 pages in range for non-PLT usage400sshClient # [ 0.012050] 506368 pages in range for PLT usage401sshClient # [ 0.012186] pinctrl core: initialized pinctrl subsystem402sshClient # [ 0.013066] DMI not present or invalid.403sshClient # [ 0.016671] NET: Registered PF_NETLINK/PF_ROUTE protocol family404sshClient # [ 0.019317] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations405sshClient # [ 0.019474] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations406sshClient # [ 0.019644] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations407sshClient # [ 0.019670] audit: initializing netlink subsys (disabled)408sshClient # [ 0.020387] thermal_sys: Registered thermal governor 'fair_share'409sshClient # [ 0.020390] thermal_sys: Registered thermal governor 'bang_bang'410sshClient # [ 0.020394] thermal_sys: Registered thermal governor 'step_wise'411sshClient # [ 0.020397] thermal_sys: Registered thermal governor 'user_space'412sshClient # [ 0.020400] thermal_sys: Registered thermal governor 'power_allocator'413sshClient # [ 0.020443] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1414sshClient # [ 0.020452] cpuidle: using governor ladder415sshClient # [ 0.020460] cpuidle: using governor menu416sshClient # [ 0.020660] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.417sshClient # [ 0.020678] ASID allocator initialised with 65536 entries418sshClient # [ 0.022088] Serial: AMBA PL011 UART driver419sshClient # [ 0.028035] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1420sshClient # [ 0.028242] printk: console [ttyAMA0] enabled421sshClient # [ 0.159765] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages422sshClient # [ 0.159779] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page423sshClient # [ 0.159783] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages424sshClient # [ 0.159786] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page425sshClient # [ 0.159789] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages426sshClient # [ 0.159791] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page427sshClient # [ 0.159794] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages428sshClient # [ 0.159797] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page429sshClient # [ 0.161205] fbcon: Taking over console430sshClient # [ 0.161216] ACPI: Interpreter disabled.431sshClient # [ 0.175762] iommu: Default domain type: Translated432sshClient # [ 0.175771] iommu: DMA domain TLB invalidation policy: strict mode433sshClient # [ 0.176331] SCSI subsystem initialized434sshClient # [ 0.176576] usbcore: registered new interface driver usbfs435sshClient # [ 0.176609] usbcore: registered new interface driver hub436sshClient # [ 0.176625] usbcore: registered new device driver usb437sshClient # [ 0.176958] pps_core: LinuxPPS API ver. 1 registered438sshClient # [ 0.176963] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>439sshClient # [ 0.176971] PTP clock support registered440sshClient # [ 0.177025] EDAC MC: Ver: 3.0.0441sshClient # [ 0.177207] scmi_core: SCMI protocol bus registered442sshClient # [ 0.177571] FPGA manager framework443sshClient # [ 0.178113] vgaarb: loaded444sshClient # [ 0.178389] clocksource: Switched to clocksource arch_sys_counter445sshClient # [ 0.195458] VFS: Disk quotas dquot_6.6.0446sshClient # [ 0.195497] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)447sshClient # [ 0.197977] netfs: FS-Cache loaded448sshClient # [ 0.198119] pnp: PnP ACPI: disabled449sshClient # [ 0.204444] NET: Registered PF_INET protocol family450sshClient # [ 0.204622] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)451machine # [ 0.404917] Initialise system trusted keyrings452machine # [ 0.404958] Key type blacklist registered453machine # [ 0.405085] workingset: timestamp_bits=42 max_order=18 bucket_order=0454machine # [ 0.405531] squashfs: version 4.0 (2009/01/31) Phillip Lougher455machine # [ 0.405593] 9p: Installing v9fs 9p2000 file system support456machine # [ 0.405720] integrity: Platform Keyring initialized457machine # [ 0.405729] integrity: Machine keyring initialized458sshClient # [ 0.237483] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)459sshClient # [ 0.237534] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)460sshClient # [ 0.237560] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)461sshClient # [ 0.237608] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)462sshClient # [ 0.237681] TCP: Hash tables configured (established 8192 bind 8192)463sshClient # [ 0.237781] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)464sshClient # [ 0.237817] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)465sshClient # [ 0.237869] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)466sshClient # [ 0.237977] NET: Registered PF_UNIX/PF_LOCAL protocol family467machine # [ 0.437851] Key type asymmetric registered468machine # [ 0.437869] Asymmetric key parser 'x509' registered469sshClient # [ 0.238009] NET: Registered PF_XDP protocol family470sshClient # [ 0.238027] PCI: CLS 0 bytes, default 64471machine # [ 0.437953] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)472sshClient # [ 0.238326] Trying to unpack rootfs image as initramfs...473machine # [ 0.438097] io scheduler mq-deadline registered474machine # [ 0.438103] io scheduler kyber registered475sshClient # [ 0.257077] kvm [1]: HYP mode not available476machine # [ 0.451012] pl061_gpio 9030000.pl061: PL061 GPIO chip registered477machine # [ 0.451664] ledtrig-cpu: registered to indicate activity on CPUs478machine # [ 0.452071] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:479machine # [ 0.452094] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000480machine # [ 0.452107] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000481machine # [ 0.452113] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000482machine # [ 0.452135] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits483machine # [ 0.452156] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]484machine # [ 0.452236] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00485machine # [ 0.452244] pci_bus 0000:00: root bus resource [bus 00-ff]486machine # [ 0.452249] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]487machine # [ 0.452252] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]488machine # [ 0.452255] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]489machine # [ 0.460684] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint490machine # [ 0.461466] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint491machine # [ 0.461665] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]492machine # [ 0.461678] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]493machine # [ 0.461705] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]494machine # [ 0.461718] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]495machine # [ 0.462214] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint496machine # [ 0.462419] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]497machine # [ 0.462433] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]498machine # [ 0.462467] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]499machine # [ 0.462974] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint500machine # [ 0.463192] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]501machine # [ 0.463205] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]502machine # [ 0.463232] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]503machine # [ 0.463712] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint504machine # [ 0.463904] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]505machine # [ 0.463916] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]506machine # [ 0.463943] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]507machine # [ 0.463956] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]508machine # [ 0.464442] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint509machine # [ 0.464638] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]510machine # [ 0.464664] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]511machine # [ 0.465133] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint512machine # [ 0.465404] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]513machine # [ 0.465430] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]514machine # [ 0.465864] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint515machine # [ 0.466052] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]516machine # [ 0.466321] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint517machine # [ 0.466520] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]518machine # [ 0.466547] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]519machine # [ 0.467039] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint520machine # [ 0.467238] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]521machine # [ 0.467265] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]522machine # [ 0.467739] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint523machine # [ 0.467935] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]524machine # [ 0.467962] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]525machine # [ 0.512641] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint526machine # [ 0.512996] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]527machine # [ 0.513016] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]528machine # [ 0.513042] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]529machine # [ 0.513640] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint530machine # [ 0.513827] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]531machine # [ 0.513838] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]532machine # [ 0.513865] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]533machine # [ 0.514496] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned534machine # [ 0.514503] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned535machine # [ 0.514507] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned536machine # [ 0.514553] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned537machine # [ 0.514598] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned538machine # [ 0.514645] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned539machine # [ 0.514692] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned540machine # [ 0.514740] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned541machine # [ 0.514785] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned542machine # [ 0.514831] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned543machine # [ 0.514876] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned544machine # [ 0.514922] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned545machine # [ 0.515011] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned546machine # [ 0.515054] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned547machine # [ 0.515072] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned548machine # [ 0.515091] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned549machine # [ 0.515108] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned550machine # [ 0.515126] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned551machine # [ 0.515144] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned552machine # [ 0.515163] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned553machine # [ 0.515182] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned554machine # [ 0.515201] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned555machine # [ 0.515219] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned556machine # [ 0.515237] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned557machine # [ 0.515256] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned558machine # [ 0.515280] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned559machine # [ 0.515298] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned560machine # [ 0.515316] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned561machine # [ 0.515334] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned562machine # [ 0.515351] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned563machine # [ 0.515369] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned564machine # [ 0.515392] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]565machine # [ 0.515397] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]566machine # [ 0.515400] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]567machine # [ 0.560295] pci 0000:00:07.0: enabling device (0000 -> 0002)568machine # [ 0.560757] pci 0000:00:07.0: quirk_usb_early_handoff+0x0/0xa60 took 43450 usecs569sshClient # [ 0.403028] Initialise system trusted keyrings570sshClient # [ 0.403076] Key type blacklist registered571sshClient # [ 0.403210] workingset: timestamp_bits=42 max_order=18 bucket_order=0572sshClient # [ 0.403759] squashfs: version 4.0 (2009/01/31) Phillip Lougher573sshClient # [ 0.403812] 9p: Installing v9fs 9p2000 file system support574sshClient # [ 0.403908] integrity: Platform Keyring initialized575sshClient # [ 0.403918] integrity: Machine keyring initialized576machine # [ 0.585348] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)577sshClient # [ 0.428730] Key type asymmetric registered578sshClient # [ 0.428749] Asymmetric key parser 'x509' registered579sshClient # [ 0.428846] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)580sshClient # [ 0.432729] io scheduler mq-deadline registered581sshClient # [ 0.432743] io scheduler kyber registered582machine # [ 0.621880] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)583sshClient # [ 0.445619] pl061_gpio 9030000.pl061: PL061 GPIO chip registered584sshClient # [ 0.446258] ledtrig-cpu: registered to indicate activity on CPUs585machine # [ 0.633138] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)586sshClient # [ 0.448324] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:587machine # [ 0.635439] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)588sshClient # [ 0.448340] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000589sshClient # [ 0.448354] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000590sshClient # [ 0.448365] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000591machine # [ 0.639545] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)592sshClient # [ 0.448395] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits593sshClient # [ 0.448417] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]594sshClient # [ 0.448516] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00595sshClient # [ 0.448523] pci_bus 0000:00: root bus resource [bus 00-ff]596machine # [ 0.642775] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)597sshClient # [ 0.448528] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]598sshClient # [ 0.448531] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]599sshClient # [ 0.448534] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]600sshClient # [ 0.448593] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint601sshClient # [ 0.449077] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint602sshClient # [ 0.449280] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]603sshClient # [ 0.449294] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]604sshClient # [ 0.449322] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]605sshClient # [ 0.449336] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]606sshClient # [ 0.449883] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint607sshClient # [ 0.450082] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]608sshClient # [ 0.450094] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]609sshClient # [ 0.450121] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]610machine # [ 0.653844] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)611machine # [ 0.655776] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)612machine # [ 0.659445] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)613sshClient # [ 0.478821] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint614sshClient # [ 0.479045] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]615sshClient # [ 0.479060] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]616sshClient # [ 0.479087] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]617sshClient # [ 0.479710] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint618sshClient # [ 0.479907] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]619machine # [ 0.672184] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)620sshClient # [ 0.479919] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]621sshClient # [ 0.479946] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]622sshClient # [ 0.479958] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]623sshClient # [ 0.480471] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint624sshClient # [ 0.480667] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]625sshClient # [ 0.480695] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]626sshClient # [ 0.481165] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint627sshClient # [ 0.481357] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]628sshClient # [ 0.481383] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]629sshClient # [ 0.481793] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint630sshClient # [ 0.481979] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]631sshClient # [ 0.482277] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint632machine # [ 0.682409] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)633sshClient # [ 0.498893] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]634sshClient # [ 0.498923] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]635sshClient # [ 0.499468] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint636sshClient # [ 0.499668] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]637sshClient # [ 0.499695] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]638sshClient # [ 0.500173] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint639sshClient # [ 0.500368] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]640sshClient # [ 0.500395] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]641sshClient # [ 0.500875] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint642machine # [ 0.694472] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled643sshClient # [ 0.501206] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]644sshClient # [ 0.501225] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]645sshClient # [ 0.501252] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]646sshClient # [ 0.501747] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint647machine # [ 0.697478] msm_serial: driver initialized648sshClient # [ 0.501967] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]649machine # [ 0.697642] SuperH (H)SCI(F) driver initialized650sshClient # [ 0.501980] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]651machine # [ 0.697702] STM32 USART driver initialized652sshClient # [ 0.502010] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]653sshClient # [ 0.522824] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned654sshClient # [ 0.522842] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned655sshClient # [ 0.522845] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned656sshClient # [ 0.522898] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned657sshClient # [ 0.522944] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned658sshClient # [ 0.522989] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned659sshClient # [ 0.523035] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned660sshClient # [ 0.523086] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned661sshClient # [ 0.523131] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned662sshClient # [ 0.523177] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned663sshClient # [ 0.523223] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned664sshClient # [ 0.523295] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned665sshClient # [ 0.523458] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned666sshClient # [ 0.523504] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned667sshClient # [ 0.523522] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned668sshClient # [ 0.523540] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned669sshClient # [ 0.523558] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned670sshClient # [ 0.523576] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned671sshClient # [ 0.523594] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned672sshClient # [ 0.523612] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned673sshClient # [ 0.523632] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned674sshClient # [ 0.523650] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned675sshClient # [ 0.523669] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned676sshClient # [ 0.523687] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned677machine # [ 0.740597] loop: module loaded678sshClient # [ 0.523706] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned679machine # [ 0.740849] virtio_blk virtio2: 1/0/0 default/read/poll queues680sshClient # [ 0.523723] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned681machine # [ 0.741776] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)682sshClient # [ 0.523741] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned683sshClient # [ 0.523759] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned684machine # [ 0.747548] megasas: 07.734.00.00-rc1685sshClient # [ 0.523776] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned686sshClient # [ 0.523794] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned687sshClient # [ 0.523811] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned688sshClient # [ 0.523836] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]689sshClient # [ 0.523842] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]690sshClient # [ 0.523845] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]691sshClient # [ 0.524731] pci 0000:00:07.0: enabling device (0000 -> 0002)692machine # [ 0.748917] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]693machine # [ 0.751051] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000694machine # [ 0.751071] Intel/Sharp Extended Query Table at 0x0031695machine # [ 0.765525] Using buffer write method696sshClient # [ 0.579618] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)697machine # [ 0.765608] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]698machine # [ 0.767611] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000699machine # [ 0.767632] Intel/Sharp Extended Query Table at 0x0031700machine # [ 0.801498] Using buffer write method701machine # [ 0.801526] Concatenating MTD devices:702machine # [ 0.801529] (0): "0.flash"703machine # [ 0.801532] (1): "0.flash"704machine # [ 0.801534] into device "0.flash"705sshClient # [ 0.620176] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)706sshClient # [ 0.643192] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)707sshClient # [ 0.645830] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)708sshClient # [ 0.696235] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)709sshClient # [ 0.700483] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)710sshClient # [ 0.710643] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)711sshClient # [ 0.712824] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)712sshClient # [ 0.716468] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)713sshClient # [ 0.736156] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)714sshClient # [ 0.747132] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)715sshClient # [ 0.760044] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled716sshClient # [ 0.762267] msm_serial: driver initialized717sshClient # [ 0.763962] SuperH (H)SCI(F) driver initialized718sshClient # [ 0.764030] STM32 USART driver initialized719sshClient # [ 0.801819] loop: module loaded720sshClient # [ 0.802047] virtio_blk virtio2: 1/0/0 default/read/poll queues721sshClient # [ 0.804370] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)722sshClient # [ 0.819356] megasas: 07.734.00.00-rc1723sshClient # [ 0.820204] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]724sshClient # [ 0.822338] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000725sshClient # [ 0.822363] Intel/Sharp Extended Query Table at 0x0031726sshClient # [ 0.830559] Using buffer write method727sshClient # [ 0.830612] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]728sshClient # [ 0.845021] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000729sshClient # [ 0.845051] Intel/Sharp Extended Query Table at 0x0031730sshClient # [ 0.856480] Using buffer write method731sshClient # [ 0.856513] Concatenating MTD devices:732sshClient # [ 0.856515] (0): "0.flash"733sshClient # [ 0.856518] (1): "0.flash"734sshClient # [ 0.856520] into device "0.flash"735machine # [ 1.106054] Freeing initrd memory: 26060K736machine # [ 1.112861] tun: Universal TUN/TAP device driver, 1.6737machine # [ 1.117470] thunder_xcv, ver 1.0738machine # [ 1.117500] thunder_bgx, ver 1.0739machine # [ 1.117521] nicpf, ver 1.0740machine # [ 1.118174] e1000: Intel(R) PRO/1000 Network Driver741machine # [ 1.118178] e1000: Copyright (c) 1999-2006 Intel Corporation.742machine # [ 1.118206] e1000e: Intel(R) PRO/1000 Network Driver743machine # [ 1.118213] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.744machine # [ 1.118237] igb: Intel(R) Gigabit Ethernet Network Driver745machine # [ 1.118241] igb: Copyright (c) 2007-2014 Intel Corporation.746machine # [ 1.118261] igbvf: Intel(R) Gigabit Virtual Function Network Driver747machine # [ 1.118266] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.748machine # [ 1.118417] sky2: driver version 1.30749machine # [ 1.120106] usbcore: registered new interface driver usb-storage750machine # [ 1.120203] usbcore: registered new interface driver usbserial_generic751machine # [ 1.120218] usbserial: USB Serial support registered for generic752machine # [ 1.120976] ehci-pci 0000:00:07.0: EHCI Host Controller753machine # [ 1.121002] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1754machine # [ 1.121388] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000755machine # [ 1.134131] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00756machine # [ 1.135581] hub 1-0:1.0: USB hub found757machine # [ 1.135597] hub 1-0:1.0: 6 ports detected758machine # [ 1.138701] hv_vmbus: registering driver hyperv_keyboard759machine # [ 1.139663] rtc-pl031 9010000.pl031: registered as rtc0760machine # [ 1.139691] rtc-pl031 9010000.pl031: setting system clock to 2026-10-20T03:08:30 UTC (1792465710)761machine # [ 1.140009] i2c_dev: i2c /dev entries driver762machine # [ 1.146827] sdhci: Secure Digital Host Controller Interface driver763machine # [ 1.146834] sdhci: Copyright(c) Pierre Ossman764machine # [ 1.147144] Synopsys Designware Multimedia Card Interface Driver765machine # [ 1.147529] sdhci-pltfm: SDHCI platform and OF driver helper766machine # [ 1.152214] hid: raw HID events driver (C) Jiri Kosina767machine # [ 1.153330] usbcore: registered new interface driver usbhid768machine # [ 1.153335] usbhid: USB HID core driver769machine # [ 1.154574] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available770machine # [ 1.156244] drop_monitor: Initializing network drop monitor service771machine # [ 1.159921] NET: Registered PF_INET6 protocol family772machine # [ 1.161731] Segment Routing with IPv6773machine # [ 1.161761] In-situ OAM (IOAM) with IPv6774machine # [ 1.161794] NET: Registered PF_PACKET protocol family775machine # [ 1.161877] 9pnet: Installing 9P2000 support776machine # [ 1.161925] Key type dns_resolver registered777machine # [ 1.171597] registered taskstats version 1778machine # [ 1.171772] Loading compiled-in X.509 certificates779machine # [ 1.181188] Demotion targets for Node 0: null780machine # [ 1.181356] Key type .fscrypt registered781machine # [ 1.181360] Key type fscrypt-provisioning registered782machine # [ 1.181473] ima: No TPM chip found, activating TPM-bypass!783machine # [ 1.181497] ima: Allocated hash algorithm: sha1784machine # [ 1.181519] ima: No architecture policies found785machine # [ 1.182364] input: gpio-keys as /devices/platform/gpio-keys/input/input0786machine # [ 1.207895] clk: Disabling unused clocks787machine # [ 1.207915] PM: genpd: Disabling unused power domains788machine # [ 1.214026] Freeing unused kernel memory: 7808K789machine # [ 1.214267] Run /init as init process790machine # [ 1.232738] fuse: module verification failed: signature and/or required key missing - tainting kernel791machine # [ 1.235633] fuse: init (API version 7.45)792machine # [ 1.240607] virtiofs virtio6: discovered new tag: nix-store793machine # [ 1.241594] virtiofs virtio6: virtio_fs_setup_dax: No cache capability794machine # [ 1.258342] virtiofs virtio7: discovered new tag: shared795machine # [ 1.259277] virtiofs virtio7: virtio_fs_setup_dax: No cache capability796machine # [ 1.262486] virtiofs virtio8: discovered new tag: xchg797machine # [ 1.263288] virtiofs virtio8: virtio_fs_setup_dax: No cache capability798machine # [ 1.275683] systemd[1]: Successfully made /usr/ read-only.799machine # [ 1.277317] systemd[1]: systemd 263-cf8ebfc 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)800machine # [ 1.277335] systemd[1]: Detected virtualization qemu.801machine # [ 1.277362] systemd[1]: Detected architecture arm64.802machine # [ 1.277368] systemd[1]: Running in initrd.803machine # [ 1.277754] systemd[1]: Initializing machine ID from random generator.804machine # [ 1.277864] systemd[1]: Hostname set to <machine>.805sshClient # [ 1.136525] Freeing initrd memory: 26052K806sshClient # [ 1.143408] tun: Universal TUN/TAP device driver, 1.6807sshClient # [ 1.148001] thunder_xcv, ver 1.0808sshClient # [ 1.148033] thunder_bgx, ver 1.0809sshClient # [ 1.148053] nicpf, ver 1.0810sshClient # [ 1.148674] e1000: Intel(R) PRO/1000 Network Driver811sshClient # [ 1.148679] e1000: Copyright (c) 1999-2006 Intel Corporation.812sshClient # [ 1.148705] e1000e: Intel(R) PRO/1000 Network Driver813sshClient # [ 1.148714] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.814sshClient # [ 1.148738] igb: Intel(R) Gigabit Ethernet Network Driver815sshClient # [ 1.148742] igb: Copyright (c) 2007-2014 Intel Corporation.816sshClient # [ 1.148762] igbvf: Intel(R) Gigabit Virtual Function Network Driver817sshClient # [ 1.148766] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.818sshClient # [ 1.148902] sky2: driver version 1.30819sshClient # [ 1.159154] ehci-pci 0000:00:07.0: EHCI Host Controller820sshClient # [ 1.159179] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1821sshClient # [ 1.159404] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000822sshClient # [ 1.159831] usbcore: registered new interface driver usb-storage823sshClient # [ 1.160878] usbcore: registered new interface driver usbserial_generic824sshClient # [ 1.160896] usbserial: USB Serial support registered for generic825sshClient # [ 1.161543] hv_vmbus: registering driver hyperv_keyboard826sshClient # [ 1.168298] rtc-pl031 9010000.pl031: registered as rtc0827sshClient # [ 1.168324] rtc-pl031 9010000.pl031: setting system clock to 2026-10-20T03:08:31 UTC (1792465711)828sshClient # [ 1.168701] i2c_dev: i2c /dev entries driver829sshClient # [ 1.174765] sdhci: Secure Digital Host Controller Interface driver830sshClient # [ 1.174773] sdhci: Copyright(c) Pierre Ossman831sshClient # [ 1.175069] Synopsys Designware Multimedia Card Interface Driver832sshClient # [ 1.175499] sdhci-pltfm: SDHCI platform and OF driver helper833sshClient # [ 1.177162] hid: raw HID events driver (C) Jiri Kosina834sshClient # [ 1.177412] usbcore: registered new interface driver usbhid835sshClient # [ 1.177416] usbhid: USB HID core driver836sshClient # [ 1.183530] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00837sshClient # [ 1.183920] hub 1-0:1.0: USB hub found838sshClient # [ 1.183936] hub 1-0:1.0: 6 ports detected839sshClient # [ 1.184641] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available840sshClient # [ 1.189433] drop_monitor: Initializing network drop monitor service841sshClient # [ 1.190210] NET: Registered PF_INET6 protocol family842sshClient # [ 1.192464] Segment Routing with IPv6843sshClient # [ 1.192479] In-situ OAM (IOAM) with IPv6844sshClient # [ 1.192511] NET: Registered PF_PACKET protocol family845sshClient # [ 1.192573] 9pnet: Installing 9P2000 support846sshClient # [ 1.192623] Key type dns_resolver registered847machine # [ 1.380368] usb 1-1: new high-speed USB device number 2 using ehci-pci848sshClient # [ 1.201950] registered taskstats version 1849sshClient # [ 1.202134] Loading compiled-in X.509 certificates850machine # [ 1.388564] systemd[1]: bpf-restrict-fs: LSM BPF program attached851sshClient # [ 1.211948] Demotion targets for Node 0: null852sshClient # [ 1.212098] Key type .fscrypt registered853sshClient # [ 1.212103] Key type fscrypt-provisioning registered854sshClient # [ 1.212215] ima: No TPM chip found, activating TPM-bypass!855sshClient # [ 1.212238] ima: Allocated hash algorithm: sha1856sshClient # [ 1.212263] ima: No architecture policies found857sshClient # [ 1.213047] input: gpio-keys as /devices/platform/gpio-keys/input/input0858sshClient # [ 1.239403] clk: Disabling unused clocks859sshClient # [ 1.239425] PM: genpd: Disabling unused power domains860sshClient # [ 1.245900] Freeing unused kernel memory: 7808K861sshClient # [ 1.246127] Run /init as init process862sshClient # [ 1.264257] fuse: module verification failed: signature and/or required key missing - tainting kernel863machine # [ 1.451353] systemd[1]: Queued start job for default target Initrd Default Target.864sshClient # [ 1.268675] fuse: init (API version 7.45)865machine # [ 1.456080] systemd[1]: Created slice Slice /system/modprobe.866sshClient # [ 1.272356] virtiofs virtio6: discovered new tag: nix-store867machine # [ 1.457284] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.868sshClient # [ 1.273135] virtiofs virtio6: virtio_fs_setup_dax: No cache capability869machine # [ 1.457329] systemd[1]: Expecting device /dev/disk/by-label/nixos...870machine # [ 1.457355] systemd[1]: Reached target Path Units.871machine # [ 1.457371] systemd[1]: Reached target Slice Units.872machine # [ 1.457385] systemd[1]: Reached target Swaps.873machine # [ 1.457399] systemd[1]: Reached target Timer Units.874machine # [ 1.457576] systemd[1]: Listening on D-Bus System Message Bus Socket.875machine # [ 1.457774] systemd[1]: Listening on Journal Socket (/dev/log).876machine # [ 1.457915] systemd[1]: Listening on Journal Sockets.877machine # [ 1.457995] systemd[1]: Listening on udev Kernel Socket.878machine # [ 1.458206] systemd[1]: Listening on udev Varlink Socket.879machine # [ 1.458223] systemd[1]: Reached target Socket Units.880machine # [ 1.459839] systemd[1]: Starting Create List of Static Device Nodes...881machine # [ 1.459908] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs882sshClient # [ 1.288863] virtiofs virtio7: discovered new tag: shared883sshClient # [ 1.289978] virtiofs virtio7: virtio_fs_setup_dax: No cache capability884sshClient # [ 1.293158] virtiofs virtio8: discovered new tag: xchg885sshClient # [ 1.294031] virtiofs virtio8: virtio_fs_setup_dax: No cache capability886machine # [ 1.480585] systemd[1]: Mounting Kernel Configuration File System...887sshClient # [ 1.305801] systemd[1]: Successfully made /usr/ read-only.888sshClient # [ 1.307689] systemd[1]: systemd 263-cf8ebfc 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)889sshClient # [ 1.307713] systemd[1]: Detected virtualization qemu.890sshClient # [ 1.307750] systemd[1]: Detected architecture arm64.891sshClient # [ 1.307757] systemd[1]: Running in initrd.892sshClient # [ 1.308139] systemd[1]: Initializing machine ID from random generator.893machine # [ 1.489584] systemd[1]: Starting Journal Service...894sshClient # [ 1.308254] systemd[1]: Hostname set to <sshClient>.895machine # [ 1.509539] systemd[1]: Starting Load Kernel Modules...896machine # [ 1.509687] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os897machine # [ 1.518841] systemd[1]: Starting Coldplug All udev Devices...898machine # [ 1.532635] systemd[1]: Finished Create List of Static Device Nodes.899machine # [ 1.552935] 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/input1900machine # [ 1.576195] systemd-journald[71]: Collecting audit messages is disabled.901machine # [ 1.578648] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...902machine # [ 1.579424] systemd[1]: Mounted Kernel Configuration File System.903sshClient # [ 1.418342] systemd[1]: bpf-restrict-fs: LSM BPF program attached904machine # [ 1.618523] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.905machine # [ 1.623938] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev906sshClient # [ 1.442518] usb 1-1: new high-speed USB device number 2 using ehci-pci907machine # [ 1.642631] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0908machine # [ 1.642887] [drm] features: -virgl +edid -resource_blob -host_visible909machine # [ 1.642891] [drm] features: -context_init910machine # [ 1.643748] [drm] number of scanouts: 1911machine # [ 1.643760] [drm] number of cap sets: 0912machine # [ 1.656625] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0913machine # [ 1.662842] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.914machine # [ 1.665962] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic915machine # [ 1.665977] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0916sshClient # [ 1.483010] systemd[1]: Queued start job for default target Initrd Default Target.917sshClient # [ 1.492814] systemd[1]: Created slice Slice /system/modprobe.918machine # [ 1.676476] systemd[1]: Starting Create Static Device Nodes in /dev...919sshClient # [ 1.493040] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.920sshClient # [ 1.493070] systemd[1]: Expecting device /dev/disk/by-label/nixos...921sshClient # [ 1.493094] systemd[1]: Reached target Path Units.922sshClient # [ 1.493108] systemd[1]: Reached target Slice Units.923sshClient # [ 1.493123] systemd[1]: Reached target Swaps.924sshClient # [ 1.493138] systemd[1]: Reached target Timer Units.925sshClient # [ 1.493302] systemd[1]: Listening on D-Bus System Message Bus Socket.926sshClient # [ 1.493519] systemd[1]: Listening on Journal Socket (/dev/log).927sshClient # [ 1.493728] systemd[1]: Listening on Journal Sockets.928sshClient # [ 1.493822] systemd[1]: Listening on udev Kernel Socket.929sshClient # [ 1.494044] systemd[1]: Listening on udev Varlink Socket.930sshClient # [ 1.494069] systemd[1]: Reached target Socket Units.931sshClient # [ 1.505863] systemd[1]: Starting Create List of Static Device Nodes...932sshClient # [ 1.505943] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs933sshClient # [ 1.517437] systemd[1]: Mounting Kernel Configuration File System...934machine # [ 1.700715] Console: switching to colour frame buffer device 160x50935machine # [ 1.725063] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device936sshClient # [ 1.543225] systemd[1]: Starting Journal Service...937machine # [ 1.738425] systemd[1]: Finished Load Kernel Modules.938sshClient # [ 1.554734] systemd[1]: Starting Load Kernel Modules...939sshClient # [ 1.554903] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os940machine # [ 1.742571] systemd[1]: Starting Apply Kernel Variables...941sshClient # [ 1.563111] systemd[1]: Starting Coldplug All udev Devices...942machine # [ 1.764681] systemd[1]: Finished Create Static Device Nodes in /dev.943machine # [ 1.765166] systemd[1]: Reached target Preparation for Local File Systems.944machine # [ 1.766283] systemd[1]: Reached target Local File Systems.945machine # [ 1.776492] usb 1-2: new high-speed USB device number 3 using ehci-pci946sshClient # [ 1.594846] systemd[1]: Finished Create List of Static Device Nodes.947machine # [ 1.784962] systemd[1]: Starting Rule-based Manager for Device Events and Files...948sshClient # [ 1.606999] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...949sshClient # [ 1.607450] systemd[1]: Mounted Kernel Configuration File System.950sshClient # [ 1.614212] 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/input1951machine # [ 1.796646] systemd-modules-load[72]: Inserted module 'dm_mod'952machine # [ 1.816073] systemd[1]: Started Journal Service.953machine # [ 1.803347] systemd-modules-load[72]: Module 'virtio_balloon' is built in954machine # [ 1.806225] systemd-modules-load[72]: Module 'virtio_console' is built in955sshClient # [ 1.643612] systemd-journald[71]: Collecting audit messages is disabled.956machine # [ 1.818194] systemd-modules-load[72]: Inserted module 'virtio_gpu'957machine # [ 1.820535] systemd-modules-load[72]: Module 'virtio_rng' is built in958sshClient # [ 1.653806] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.959machine # [ 1.829136] systemd[1]: Finished Apply Kernel Variables.960machine # [ 1.833594] systemd[1]: Starting Create System Files and Directories...961sshClient # [ 1.670610] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev962sshClient # [ 1.690263] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0963sshClient # [ 1.698937] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0964sshClient # [ 1.700459] [drm] features: -virgl +edid -resource_blob -host_visible965sshClient # [ 1.700465] [drm] features: -context_init966sshClient # [ 1.701346] [drm] number of scanouts: 1967sshClient # [ 1.701359] [drm] number of cap sets: 0968sshClient # [ 1.706853] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.969sshClient # [ 1.712135] systemd[1]: Starting Create Static Device Nodes in /dev...970sshClient # [ 1.719028] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic971sshClient # [ 1.719054] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0972machine # [ 1.903279] systemd-udevd[80]: Using default interface naming scheme 'v261'.973machine # [ 1.909078] systemd[1]: Finished Create System Files and Directories.974sshClient # [ 1.747591] Console: switching to colour frame buffer device 160x50975machine # [ 1.939917] 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/input2976machine # [ 1.940260] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0977sshClient # [ 1.767149] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device978sshClient # [ 1.773328] systemd[1]: Finished Load Kernel Modules.979machine # [ 1.954003] systemd[1]: Started Rule-based Manager for Device Events and Files.980sshClient # [ 1.786894] systemd[1]: Starting Apply Kernel Variables...981sshClient # [ 1.807045] systemd[1]: Finished Create Static Device Nodes in /dev.982sshClient # [ 1.807544] systemd[1]: Reached target Preparation for Local File Systems.983sshClient # [ 1.807583] systemd[1]: Reached target Local File Systems.984sshClient # [ 1.818603] usb 1-2: new high-speed USB device number 3 using ehci-pci985sshClient # [ 1.820639] systemd[1]: Starting Rule-based Manager for Device Events and Files...986sshClient # [ 1.844604] systemd-modules-load[72]: Inserted module 'dm_mod'987sshClient # [ 1.846029] systemd-modules-load[72]: Module 'virtio_balloon' is built in988sshClient # [ 1.847618] systemd-modules-load[72]: Module 'virtio_console' is built in989sshClient # [ 1.867559] systemd[1]: Finished Apply Kernel Variables.990sshClient # [ 1.868250] systemd[1]: Started Journal Service.991sshClient # [ 1.861131] systemd-modules-load[72]: Inserted module 'virtio_gpu'992sshClient # [ 1.862408] systemd-modules-load[72]: Module 'virtio_rng' is built in993machine # [ 2.073312] systemd[1]: Starting Virtual Console Setup...994sshClient # [ 1.885923] systemd[1]: Starting Create System Files and Directories...995sshClient # [ 1.930263] systemd-udevd[80]: Using default interface naming scheme 'v261'.996sshClient # [ 1.942839] systemd[1]: Finished Create System Files and Directories.997sshClient # [ 1.974202] 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/input2998sshClient # [ 1.977716] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0999machine # [ 2.164681] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.1000sshClient # [ 1.981463] systemd[1]: Started Rule-based Manager for Device Events and Files.1001machine # [ 2.173713] systemd[1]: Finished Virtual Console Setup.1002sshClient # [ 2.106036] systemd[1]: Starting Virtual Console Setup...1003sshClient # [ 2.192802] systemd-vconsole-setup[98]: Configuration of first virtual console was skipped, ignoring remaining ones.1004sshClient # [ 2.205987] systemd[1]: Finished Virtual Console Setup.1005machine # [ 2.910605] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.1006machine # [ 2.922026] systemd[1]: Finished Coldplug All udev Devices.1007machine # [ 2.923058] systemd[1]: Reached target System Initialization.1008machine # [ 2.923878] systemd[1]: Reached target Basic System.1009machine # [ 2.942905] (udev-worker)[93]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1010machine # [ 2.947830] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.1011machine # [ 2.975164] systemd[1]: Found device /dev/disk/by-label/nixos.1012machine # [ 2.978877] systemd[1]: Reached target Initrd Root Device.1013machine # [ 2.982156] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1014machine # [ 3.042623] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks1015machine # [ 3.095530] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1016machine # [ 3.101928] systemd[1]: Mounting /sysroot...1017machine # [ 3.154321] EXT4-fs (vda): mounted filesystem 5c2a11b9-ee7b-424c-99e7-6fc20b7162e3 r/w with ordered data mode. Quota mode: none.1018machine # [ 3.143128] systemd[1]: Mounted /sysroot.1019machine # [ 3.147148] systemd[1]: Reached target Initrd Root File System.1020sshClient # [ 2.962110] (udev-worker)[103]: Network interface NamePolicy= disabled on kernel command line.1021machine # [ 3.151810] systemd[1]: Starting Mountpoints Configured in the Real Root...1022sshClient # [ 2.982973] systemd[1]: Finished Coldplug All udev Devices.1023sshClient # [ 2.988255] systemd[1]: Reached target System Initialization.1024sshClient # [ 2.989322] systemd[1]: Reached target Basic System.1025sshClient # [ 2.991948] (udev-worker)[105]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1026machine # [ 3.186100] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.1027sshClient # [ 2.998340] (udev-worker)[105]: Network interface NamePolicy= disabled on kernel command line.1028machine # [ 3.192817] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...1029machine # [ 3.197995] systemd[1]: Reloading...1030sshClient # [ 3.017970] systemd[1]: Found device /dev/disk/by-label/nixos.1031sshClient # [ 3.022047] systemd[1]: Reached target Initrd Root Device.1032sshClient # [ 3.026082] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1033sshClient # [ 3.081855] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks1034sshClient # [ 3.090723] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1035sshClient # [ 3.096437] systemd[1]: Mounting /sysroot...1036sshClient # [ 3.155562] EXT4-fs (vda): mounted filesystem b1049575-d96d-440d-8d18-35b7e72be4e0 r/w with ordered data mode. Quota mode: none.1037sshClient # [ 3.143097] systemd[1]: Mounted /sysroot.1038sshClient # [ 3.146072] systemd[1]: Reached target Initrd Root File System.1039sshClient # [ 3.154867] systemd[1]: Starting Mountpoints Configured in the Real Root...1040sshClient # [ 3.201321] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.1041sshClient # [ 3.208083] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...1042sshClient # [ 3.210900] systemd[1]: Reloading...1043machine # [ 3.409212] systemd[1]: Reloading finished in 212 ms.1044machine # [ 3.447079] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...1045machine # [ 3.450235] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...1046machine # [ 3.458623] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1047machine # [ 3.461901] systemd[1]: Finished Mountpoints Configured in the Real Root.1048machine # [ 3.463535] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1049machine # [ 3.509566] systemd[1]: Mounting /sysroot/nix/.ro-store...1050machine # [ 3.526610] systemd[1]: Mounting /sysroot/nix/.rw-store...1051machine # [ 3.531948] systemd[1]: Mounting /sysroot/run...1052machine # [ 3.548833] systemd[1]: Mounting /sysroot/tmp/shared...1053machine # [ 3.582735] systemd[1]: Mounting /sysroot/tmp/xchg...1054sshClient # [ 3.445150] systemd[1]: Reloading finished in 238 ms.1055machine # [ 3.659357] systemd[1]: Mounted /sysroot/nix/.ro-store.1056machine # [ 3.666284] systemd[1]: Mounted /sysroot/nix/.rw-store.1057machine # [ 3.669429] systemd[1]: Mounted /sysroot/run.1058sshClient # [ 3.486542] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...1059machine # [ 3.680763] systemd[1]: Mounted /sysroot/tmp/shared.1060sshClient # [ 3.491630] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...1061sshClient # [ 3.498052] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1062sshClient # [ 3.501147] systemd[1]: Finished Mountpoints Configured in the Real Root.1063machine # [ 3.690625] systemd[1]: Mounted /sysroot/tmp/xchg.1064sshClient # [ 3.503988] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1065machine # [ 3.702253] systemd[1]: Starting rw-sysroot-nix-store.service...1066machine # [ 3.735019] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1067machine # [ 3.737026] systemd[1]: Finished rw-sysroot-nix-store.service.1068sshClient # [ 3.558929] systemd[1]: Mounting /sysroot/nix/.ro-store...1069sshClient # [ 3.574367] systemd[1]: Mounting /sysroot/nix/.rw-store...1070sshClient # [ 3.584773] systemd[1]: Mounting /sysroot/run...1071sshClient # [ 3.611099] systemd[1]: Mounting /sysroot/tmp/shared...1072sshClient # [ 3.641865] systemd[1]: Mounting /sysroot/tmp/xchg...1073sshClient # [ 3.681400] systemd[1]: Mounted /sysroot/nix/.ro-store.1074sshClient # [ 3.718285] systemd[1]: Mounted /sysroot/nix/.rw-store.1075sshClient # [ 3.727577] systemd[1]: Mounted /sysroot/run.1076sshClient # [ 3.743058] systemd[1]: Starting rw-sysroot-nix-store.service...1077sshClient # [ 3.748405] systemd[1]: Mounted /sysroot/tmp/shared.1078sshClient # [ 3.750511] systemd[1]: Mounted /sysroot/tmp/xchg.1079sshClient # [ 3.789299] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1080sshClient # [ 3.791085] systemd[1]: Finished rw-sysroot-nix-store.service.1081machine # [ 4.420841] (udev-worker)[90]: mtd0ro: Failed to find and pin callout binary "/nix/store/rqml5nvpjfjxxijlgvjhvvpy2iwg7axc-systemd-263-cf8ebfc/lib/udev/mtd_probe": No such file or directory1082machine # [ 4.427852] (udev-worker)[90]: 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.460360] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1084machine # [ 4.465755] systemd[1]: Stopped Virtual Console Setup.1085machine # [ 4.468177] systemd[1]: Stopping Virtual Console Setup...1086machine # [ 4.469652] systemd[1]: Starting Virtual Console Setup...1087machine # [ 4.483109] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1088machine # [ 4.485432] systemd[1]: Stopped Virtual Console Setup.1089machine # [ 4.491373] systemd[1]: Starting Virtual Console Setup...1090machine # [ 4.514636] systemd[1]: Mounting /sysroot/nix/store...1091machine # [ 4.541261] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones.1092machine # [ 4.544842] systemd[1]: Finished Virtual Console Setup.1093machine # [ 4.547769] systemd[1]: sysroot-run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully.1094machine # [ 4.572992] systemd[1]: Mounted /sysroot/nix/store.1095machine # [ 4.576292] systemd[1]: Reached target Initrd File Systems.1096machine # [ 4.578777] systemd[1]: Starting Find NixOS closure...1097machine # [ 4.586070] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1098machine # [ 4.624812] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1099machine # [ 4.636690] systemd[1]: Finished Find NixOS closure.1100machine # [ 4.639381] systemd[1]: Reached target Initrd Default Target.1101machine # [ 4.641197] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1102sshClient # [ 4.477603] (udev-worker)[96]: mtd0ro: Failed to find and pin callout binary "/nix/store/rqml5nvpjfjxxijlgvjhvvpy2iwg7axc-systemd-263-cf8ebfc/lib/udev/mtd_probe": No such file or directory1103machine # [ 4.673073] systemd[1]: Stopped target Initrd Default Target.1104machine # [ 4.674238] systemd[1]: Stopped target Basic System.1105sshClient # [ 4.483819] (udev-worker)[96]: 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 directory1106machine # [ 4.675059] systemd[1]: Stopped target Initrd Root Device.1107machine # [ 4.675899] systemd[1]: Stopped target Path Units.1108machine # [ 4.676763] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1109machine # [ 4.679301] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1110machine # [ 4.684697] systemd[1]: Stopped target Slice Units.1111machine # [ 4.685596] systemd[1]: Stopped target Socket Units.1112machine # [ 4.686375] systemd[1]: Stopped target System Initialization.1113machine # [ 4.687282] systemd[1]: Stopped target Swaps.1114machine # [ 4.688001] systemd[1]: Stopped target Timer Units.1115machine # [ 4.696385] systemd[1]: dbus.socket: Deactivated successfully.1116sshClient # [ 4.509222] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1117machine # [ 4.701650] systemd[1]: Closed D-Bus System Message Bus Socket.1118sshClient # [ 4.515364] systemd[1]: Stopped Virtual Console Setup.1119machine # [ 4.704787] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1120sshClient # [ 4.519034] systemd[1]: Stopping Virtual Console Setup...1121sshClient # [ 4.519936] systemd[1]: Starting Virtual Console Setup...1122machine # [ 4.707877] systemd[1]: Stopped Find NixOS closure.1123machine # [ 4.712334] systemd[1]: Starting rw-sysroot-nix-store.service...1124machine # [ 4.715467] systemd[1]: systemd-sysctl.service: Deactivated successfully.1125machine # [ 4.722127] systemd[1]: Stopped Apply Kernel Variables.1126machine # [ 4.723117] systemd[1]: systemd-modules-load.service: Deactivated successfully.1127sshClient # [ 4.537416] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1128sshClient # [ 4.540153] systemd[1]: Stopped Virtual Console Setup.1129machine # [ 4.729639] systemd[1]: Stopped Load Kernel Modules.1130machine # [ 4.730510] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1131machine # [ 4.731726] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1132sshClient # [ 4.544295] systemd[1]: Starting Virtual Console Setup...1133machine # [ 4.740433] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1134machine # [ 4.744749] systemd[1]: Stopped Create System Files and Directories.1135machine # [ 4.747297] systemd[1]: Stopped target Local File Systems.1136sshClient # [ 4.560905] systemd[1]: Mounting /sysroot/nix/store...1137machine # [ 4.750205] systemd[1]: Stopped target Preparation for Local File Systems.1138machine # [ 4.752293] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1139machine # [ 4.756353] systemd[1]: Stopped Coldplug All udev Devices.1140machine # [ 4.757425] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1141machine # [ 4.759251] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1142machine # [ 4.763319] systemd[1]: Stopped Virtual Console Setup.1143machine # [ 4.764555] systemd[1]: systemd-udevd.service: Deactivated successfully.1144machine # [ 4.765721] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1145machine # [ 4.766846] systemd[1]: systemd-udevd.service: Consumed 1.550s CPU time over 2.952s wall clock time, 27M memory peak.1146machine # [ 4.768619] systemd[1]: initrd-cleanup.service: Deactivated successfully.1147machine # [ 4.769749] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1148machine # [ 4.770772] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully.1149machine # [ 4.771909] systemd[1]: Closed udev Varlink Socket.1150machine # [ 4.776418] systemd[1]: Starting Cleanup udev Database...1151sshClient # [ 4.588550] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones.1152machine # [ 4.779928] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1153machine # [ 4.784248] systemd[1]: Stopped Create Static Device Nodes in /dev.1154machine # [ 4.785543] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1155machine # [ 4.788318] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1156sshClient # [ 4.602528] systemd[1]: Finished Virtual Console Setup.1157machine # [ 4.792288] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1158sshClient # [ 4.603900] systemd[1]: sysroot-run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully.1159machine # [ 4.793587] systemd[1]: Stopped Create List of Static Device Nodes.1160machine # [ 4.796173] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1161machine # [ 4.800252] systemd[1]: Finished rw-sysroot-nix-store.service.1162sshClient # [ 4.614964] systemd[1]: Mounted /sysroot/nix/store.1163sshClient # [ 4.617022] systemd[1]: Reached target Initrd File Systems.1164sshClient # [ 4.619951] systemd[1]: Starting Find NixOS closure...1165sshClient # [ 4.628391] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1166machine # [ 4.832761] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1167machine # [ 4.834724] systemd[1]: Finished Cleanup udev Database.1168machine # [ 4.837242] systemd[1]: Reached target Switch Root.1169machine # [ 4.840551] systemd[1]: Starting NixOS Activation...1170sshClient # [ 4.662970] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1171sshClient # [ 4.674794] systemd[1]: Finished Find NixOS closure.1172sshClient # [ 4.677117] systemd[1]: Reached target Initrd Default Target.1173sshClient # [ 4.679970] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1174sshClient # [ 4.708550] systemd[1]: Stopped target Initrd Default Target.1175sshClient # [ 4.710248] systemd[1]: Stopped target Basic System.1176sshClient # [ 4.712288] systemd[1]: Stopped target Initrd Root Device.1177sshClient # [ 4.714280] systemd[1]: Stopped target Path Units.1178sshClient # [ 4.716319] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1179sshClient # [ 4.719722] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1180sshClient # [ 4.722767] systemd[1]: Stopped target Slice Units.1181sshClient # [ 4.724757] systemd[1]: Stopped target Socket Units.1182sshClient # [ 4.728280] systemd[1]: Stopped target System Initialization.1183sshClient # [ 4.729379] systemd[1]: Stopped target Swaps.1184sshClient # [ 4.730841] systemd[1]: Stopped target Timer Units.1185sshClient # [ 4.732731] systemd[1]: dbus.socket: Deactivated successfully.1186sshClient # [ 4.740317] systemd[1]: Closed D-Bus System Message Bus Socket.1187machine # [ 4.931499] initrd-nixos-activation-start[176]: booting system configuration /nix/store/4jl3x0ngls3a9gvmalk17qj5260fvjcc-nixos-system-machine-test1188sshClient # [ 4.746501] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1189sshClient # [ 4.747952] systemd[1]: Stopped Find NixOS closure.1190sshClient # [ 4.750478] systemd[1]: Starting rw-sysroot-nix-store.service...1191sshClient # [ 4.759249] systemd[1]: systemd-sysctl.service: Deactivated successfully.1192sshClient # [ 4.763204] systemd[1]: Stopped Apply Kernel Variables.1193sshClient # [ 4.770206] systemd[1]: systemd-modules-load.service: Deactivated successfully.1194sshClient # [ 4.771414] systemd[1]: Stopped Load Kernel Modules.1195sshClient # [ 4.776002] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1196sshClient # [ 4.777540] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1197sshClient # [ 4.780405] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1198machine # [ 4.969415] initrd-nixos-activation-start[176]: running activation script...1199sshClient # [ 4.784545] systemd[1]: Stopped Create System Files and Directories.1200sshClient # [ 4.785705] systemd[1]: Stopped target Local File Systems.1201sshClient # [ 4.788439] systemd[1]: Stopped target Preparation for Local File Systems.1202sshClient # [ 4.796303] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1203sshClient # [ 4.797617] systemd[1]: Stopped Coldplug All udev Devices.1204sshClient # [ 4.798486] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1205sshClient # [ 4.799644] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1206sshClient # [ 4.802196] systemd[1]: Stopped Virtual Console Setup.1207sshClient # [ 4.803081] systemd[1]: systemd-udevd.service: Deactivated successfully.1208sshClient # [ 4.804197] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1209sshClient # [ 4.805328] systemd[1]: systemd-udevd.service: Consumed 1.527s CPU time over 2.967s wall clock time, 26M memory peak.1210sshClient # [ 4.806860] systemd[1]: initrd-cleanup.service: Deactivated successfully.1211sshClient # [ 4.807860] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1212sshClient # [ 4.812379] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1213sshClient # [ 4.813632] systemd[1]: Finished rw-sysroot-nix-store.service.1214sshClient # [ 4.816451] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully.1215sshClient # [ 4.818911] systemd[1]: Closed udev Varlink Socket.1216sshClient # [ 4.820896] systemd[1]: Starting Cleanup udev Database...1217sshClient # [ 4.824237] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1218sshClient # [ 4.828236] systemd[1]: Stopped Create Static Device Nodes in /dev.1219sshClient # [ 4.829505] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1220sshClient # [ 4.832172] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1221sshClient # [ 4.833353] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1222sshClient # [ 4.836195] systemd[1]: Stopped Create List of Static Device Nodes.1223sshClient # [ 4.870533] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1224sshClient # [ 4.874184] systemd[1]: Finished Cleanup udev Database.1225sshClient # [ 4.875145] systemd[1]: Reached target Switch Root.1226sshClient # [ 4.876560] systemd[1]: Starting NixOS Activation...1227sshClient # [ 4.972397] initrd-nixos-activation-start[175]: booting system configuration /nix/store/7mk8s03nb07v39gh9yn9jvpz0gadzcbr-nixos-system-sshClient-test1228sshClient # [ 5.010448] initrd-nixos-activation-start[175]: running activation script...1229machine # [ 5.237458] initrd-nixos-activation-start[199]: setting up /etc...1230machine # [ 5.386437] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1231machine # [ 5.390283] systemd[1]: Finished NixOS Activation.1232machine # [ 5.391735] systemd[1]: Starting Switch Root...1233machine # [ 5.419427] systemd[1]: Switching root.1234sshClient # [ 5.292708] initrd-nixos-activation-start[198]: setting up /etc...1235machine # [ 5.606575] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1236sshClient # [ 5.442498] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1237sshClient # [ 5.446145] systemd[1]: Finished NixOS Activation.1238sshClient # [ 5.447567] systemd[1]: Starting Switch Root...1239sshClient # [ 5.475470] systemd[1]: Switching root.1240machine # [ 5.698949] NET: Registered PF_VSOCK protocol family1241machine # [ 5.779726] systemd[1]: systemd 263-cf8ebfc 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.787123] systemd[1]: Detected virtualization qemu.1243machine # [ 5.787207] systemd[1]: Detected architecture arm64.1244machine # [ 5.787349] systemd[1]: Detected first boot.1245machine # [ 5.792965] systemd[1]: Initializing machine ID from random generator.1246sshClient # [ 5.671067] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1247sshClient # [ 5.752603] NET: Registered PF_VSOCK protocol family1248machine # [ 5.945392] systemd[1]: bpf-restrict-fs: LSM BPF program attached1249sshClient # [ 5.831855] systemd[1]: systemd 263-cf8ebfc 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)1250sshClient # [ 5.832754] systemd[1]: Detected virtualization qemu.1251sshClient # [ 5.833469] systemd[1]: Detected architecture arm64.1252sshClient # [ 5.833561] systemd[1]: Detected first boot.1253sshClient # [ 5.843012] systemd[1]: Initializing machine ID from random generator.1254machine # [ 6.132226] systemd[1]: Applying preset policy.1255sshClient # [ 5.979871] systemd[1]: bpf-restrict-fs: LSM BPF program attached1256sshClient # [ 6.154278] systemd[1]: Applying preset policy.1257machine # [ 6.431585] systemd[1]: Populated /etc with preset unit settings.1258sshClient # [ 6.415653] systemd[1]: Populated /etc with preset unit settings.1259machine # [ 6.669352] systemd[1]: initrd-switch-root.service: Deactivated successfully.1260machine # [ 6.670632] systemd[1]: Stopped initrd-switch-root.service.1261machine # [ 6.675852] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1262machine # [ 6.680744] systemd[1]: Created slice Slice /system/getty.1263machine # [ 6.682577] systemd[1]: Created slice User and Session Slice.1264machine # [ 6.683246] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1265machine # [ 6.683945] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1266machine # [ 6.688252] systemd[1]: Expecting device /dev/hvc0...1267machine # [ 6.690369] systemd[1]: Expecting device /dev/ttyAMA0...1268machine # [ 6.691113] systemd[1]: Reached target Local Encrypted Volumes.1269machine # [ 6.691770] systemd[1]: Stopped target initrd-fs.target.1270machine # [ 6.695307] systemd[1]: Stopped target initrd-root-fs.target.1271machine # [ 6.695788] systemd[1]: Stopped target initrd-switch-root.target.1272machine # [ 6.699166] systemd[1]: Reached target Virtual Machines and Containers.1273machine # [ 6.699677] systemd[1]: Reached target Path Units.1274machine # [ 6.702841] systemd[1]: Reached target Remote File Systems.1275machine # [ 6.703349] systemd[1]: Reached target Slice Units.1276machine # [ 6.704166] systemd[1]: Reached target Swaps.1277machine # [ 6.709634] systemd[1]: Listening on Query the User Interactively for a Password.1278machine # [ 6.711852] systemd[1]: Listening on Process Core Dump Socket.1279machine # [ 6.716429] systemd[1]: Listening on Credential Encryption/Decryption.1280machine # [ 6.718165] systemd[1]: Listening on Factory Reset Management.1281machine # [ 6.718779] systemd[1]: Listening on Hostname Service Socket.1282machine # [ 6.725513] systemd[1]: Starting Journal Log Access Socket...1283machine # [ 6.727706] systemd[1]: Listening on Journal Audit Socket.1284machine # [ 6.733364] systemd[1]: Listening on Console Output Muting Service Socket.1285machine # [ 6.735306] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1286machine # [ 6.739195] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1287machine # [ 6.739741] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1288machine # [ 6.747689] systemd[1]: Listening on Disk Repartitioning Service Socket.1289machine # [ 6.750142] systemd[1]: Listening on udev Varlink Socket.1290machine # [ 6.750670] systemd[1]: Listening on User Database Manager Socket.1291machine # [ 6.755613] systemd[1]: Mounting Huge Pages File System...1292machine # [ 6.765019] systemd[1]: Mounting POSIX Message Queue File System...1293machine # [ 6.773346] systemd[1]: Mounting Kernel Debug File System...1294machine # [ 6.795331] systemd[1]: Mounting Kernel Trace File System...1295machine # [ 6.807512] systemd[1]: Starting Create List of Static Device Nodes...1296machine # [ 6.811277] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1297sshClient # [ 6.646091] systemd[1]: initrd-switch-root.service: Deactivated successfully.1298machine # [ 6.831435] systemd[1]: Mounting Kernel Configuration File System...1299sshClient # [ 6.648234] systemd[1]: Stopped initrd-switch-root.service.1300machine # [ 6.834145] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1301machine # [ 6.834595] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1302machine # [ 6.835005] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1303sshClient # [ 6.652255] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1304sshClient # [ 6.654151] systemd[1]: Created slice Slice /system/getty.1305sshClient # [ 6.658554] systemd[1]: Created slice User and Session Slice.1306sshClient # [ 6.659232] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1307sshClient # [ 6.659977] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1308sshClient # [ 6.660587] systemd[1]: Expecting device /dev/hvc0...1309sshClient # [ 6.661200] systemd[1]: Expecting device /dev/ttyAMA0...1310sshClient # [ 6.661852] systemd[1]: Reached target Local Encrypted Volumes.1311sshClient # [ 6.668147] systemd[1]: Stopped target initrd-fs.target.1312sshClient # [ 6.668622] systemd[1]: Stopped target initrd-root-fs.target.1313sshClient # [ 6.669430] systemd[1]: Stopped target initrd-switch-root.target.1314sshClient # [ 6.670112] systemd[1]: Reached target Virtual Machines and Containers.1315sshClient # [ 6.674601] systemd[1]: Reached target Path Units.1316sshClient # [ 6.675052] systemd[1]: Reached target Remote File Systems.1317sshClient # [ 6.675876] systemd[1]: Reached target Slice Units.1318sshClient # [ 6.676573] systemd[1]: Reached target Swaps.1319sshClient # [ 6.681445] systemd[1]: Listening on Query the User Interactively for a Password.1320sshClient # [ 6.685085] systemd[1]: Listening on Process Core Dump Socket.1321sshClient # [ 6.687474] systemd[1]: Listening on Credential Encryption/Decryption.1322sshClient # [ 6.688847] systemd[1]: Listening on Factory Reset Management.1323sshClient # [ 6.689398] systemd[1]: Listening on Hostname Service Socket.1324sshClient # [ 6.695652] systemd[1]: Starting Journal Log Access Socket...1325sshClient # [ 6.698101] systemd[1]: Listening on Journal Audit Socket.1326sshClient # [ 6.702036] systemd[1]: Listening on Console Output Muting Service Socket.1327sshClient # [ 6.705041] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1328machine # [ 6.892795] systemd[1]: Mounting FUSE Control File System...1329sshClient # [ 6.707823] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1330sshClient # [ 6.709122] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1331machine # [ 6.895736] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671332sshClient # [ 6.715489] systemd[1]: Listening on Disk Repartitioning Service Socket.1333sshClient # [ 6.716138] systemd[1]: Listening on udev Varlink Socket.1334sshClient # [ 6.717026] systemd[1]: Listening on User Database Manager Socket.1335sshClient # [ 6.722569] systemd[1]: Mounting Huge Pages File System...1336sshClient # [ 6.725756] systemd[1]: Mounting POSIX Message Queue File System...1337machine # [ 6.925770] systemd[1]: Starting Journal Service...1338sshClient # [ 6.739485] systemd[1]: Mounting Kernel Debug File System...1339sshClient # [ 6.751452] systemd[1]: Mounting Kernel Trace File System...1340sshClient # [ 6.766505] systemd[1]: Starting Create List of Static Device Nodes...1341machine # [ 6.955068] systemd[1]: Starting Load Kernel Modules...1342sshClient # [ 6.767115] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1343sshClient # [ 6.787915] systemd[1]: Mounting Kernel Configuration File System...1344sshClient # [ 6.788519] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1345sshClient # [ 6.793954] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1346sshClient # [ 6.798645] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1347machine # [ 7.000253] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1348sshClient # [ 6.822432] systemd[1]: Mounting FUSE Control File System...1349sshClient # [ 6.831063] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671350machine # [ 7.021648] systemd[1]: Starting Remount Root and Kernel File Systems...1351machine # [ 7.022108] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1352machine # [ 7.036372] systemd-journald[270]: Collecting audit messages is enabled.1353machine # [ 7.030700] systemd[1]: Queued start job for default target Multi-User System.1354machine # [ 7.032504] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0.1355machine # [ 7.034737] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.1356machine # [ 7.053385] systemd[1]: Starting Coldplug All udev Devices...1357machine # [ 7.042426] systemd[1]: systemd-journald.service: Deactivated successfully.1358sshClient # [ 6.870587] systemd[1]: Starting Journal Service...1359sshClient # [ 6.887076] systemd[1]: Starting Load Kernel Modules...1360machine # [ 7.063078] systemd-modules-load[271]: Module 'atkbd' is built in1361machine # [ 7.080750] systemd[1]: Started Journal Service.1362machine # [ 7.070712] systemd-modules-load[271]: Module 'loop' is built in1363machine # [ 7.088153] systemd[1]: Listening on Journal Log Access Socket.1364machine # [ 7.097902] systemd[1]: Mounted Huge Pages File System.1365machine # [ 7.098939] systemd[1]: Mounted POSIX Message Queue File System.1366machine # [ 7.103128] systemd[1]: Mounted Kernel Debug File System.1367sshClient # [ 6.936998] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1368machine # [ 7.109668] systemd[1]: Mounted Kernel Trace File System.1369machine # [ 7.117278] systemd[1]: Finished Create List of Static Device Nodes.1370machine # [ 7.130415] systemd[1]: Mounted Kernel Configuration File System.1371machine # [ 7.137262] systemd[1]: Finished Load Kernel Modules.1372sshClient # [ 6.976085] systemd[1]: Starting Remount Root and Kernel File Systems...1373sshClient # [ 6.976543] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1374machine # [ 7.164435] systemd[1]: Starting Firewall...1375machine # [ 7.173987] systemd[1]: Starting Apply Kernel Variables...1376sshClient # [ 7.004581] systemd-journald[269]: Collecting audit messages is enabled.1377machine # [ 7.187749] systemd-oomd[273]: No swap; memory pressure usage will be degraded1378sshClient # [ 7.003053] systemd[1]: Queued start job for default target Multi-User System.1379sshClient # [ 7.024148] systemd[1]: Starting Coldplug All udev Devices...1380sshClient # [ 7.011408] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.1381sshClient # [ 7.016702] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0.1382sshClient # [ 7.018788] systemd[1]: systemd-journald.service: Deactivated successfully.1383machine # [ 7.209884] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1384sshClient # [ 7.040055] systemd[1]: Started Journal Service.1385machine # [ 7.215530] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.[ 7.228430] EXT4-fs (vda): re-mounted 5c2a11b9-ee7b-424c-99e7-6fc20b7162e3.1386machine # 1387sshClient # [ 7.032493] systemd-modules-load[270]: Module 'atkbd' is built in1388sshClient # [ 7.033885] systemd-modules-load[270]: Module 'loop' is built in1389sshClient # [ 7.035031] systemd[1]: Listening on Journal Log Access Socket.1390sshClient # [ 7.045988] systemd[1]: Mounted Huge Pages File System.1391sshClient # [ 7.047241] systemd[1]: Mounted POSIX Message Queue File System.1392machine # [ 7.239463] systemd[1]: Finished Remount Root and Kernel File Systems.1393machine # [ 7.246727] systemd[1]: Listening on Disk Image Download Service Socket.1394sshClient # [ 7.061213] systemd[1]: Mounted Kernel Trace File System.1395sshClient # [ 7.062333] systemd[1]: Finished Create List of Static Device Nodes.1396sshClient # [ 7.063289] systemd[1]: Finished Load Kernel Modules.1397machine # [ 7.270207] systemd[1]: Starting Flush Journal to Persistent Storage...1398machine # [ 7.271342] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1399sshClient # [ 7.093401] systemd[1]: Mounted Kernel Debug File System.1400sshClient # [ 7.114490] systemd-oomd[271]: No swap; memory pressure usage will be degraded1401machine # [ 7.308425] systemd[1]: Starting Load/Save OS Random Seed...1402sshClient # [ 7.120343] systemd[1]: Mounted Kernel Configuration File System.1403machine # [ 7.309559] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1404sshClient # [ 7.122562] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1405machine # [ 7.310869] systemd[1]: Mounted FUSE Control File System.1406sshClient # [ 7.144719] EXT4-fs (vda): re-mounted b1049575-d96d-440d-8d18-35b7e72be4e0.1407sshClient # [ 7.133322] systemd[1]: Starting Firewall...1408sshClient # [ 7.151701] systemd[1]: Starting Apply Kernel Variables...1409sshClient # [ 7.161710] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1410sshClient # [ 7.165505] systemd[1]: Mounted FUSE Control File System.1411sshClient # [ 7.181401] systemd[1]: Finished Remount Root and Kernel File Systems.1412sshClient # [ 7.200180] systemd[1]: Listening on Disk Image Download Service Socket.1413sshClient # [ 7.217207] systemd[1]: Starting Flush Journal to Persistent Storage...1414sshClient # [ 7.222868] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1415machine # [ 7.425467] systemd-journald[270]: Received client request to flush runtime journal.1416sshClient # [ 7.236267] systemd[1]: Starting Load/Save OS Random Seed...1417sshClient # [ 7.238226] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1418machine # [ 7.497167] systemd[1]: Finished Apply Kernel Variables.1419machine # [ 7.498222] systemd[1]: Finished Load/Save OS Random Seed.1420machine # [ 7.508236] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1421machine # [ 7.521636] systemd[1]: Starting Create Static Device Nodes in /dev...1422machine # [ 7.523811] systemd[1]: Finished Flush Journal to Persistent Storage.1423sshClient # [ 7.380583] systemd-journald[269]: Received client request to flush runtime journal.1424sshClient # [ 7.437048] systemd[1]: Finished Apply Kernel Variables.1425sshClient # [ 7.445570] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1426sshClient # [ 7.446895] systemd[1]: Finished Load/Save OS Random Seed.1427sshClient # [ 7.447780] systemd[1]: Starting Create Static Device Nodes in /dev...1428sshClient # [ 7.456444] systemd[1]: Finished Flush Journal to Persistent Storage.1429machine # [ 7.709080] systemd[1]: Finished Create Static Device Nodes in /dev.1430machine # [ 7.716175] systemd[1]: Reached target Preparation for Local File Systems.1431machine # [ 7.726688] systemd[1]: Mounting /run/wrappers...1432machine # [ 7.736305] systemd[1]: Starting Rule-based Manager for Device Events and Files...1433sshClient # [ 7.610516] systemd[1]: Finished Create Static Device Nodes in /dev.1434sshClient # [ 7.611838] systemd[1]: Reached target Preparation for Local File Systems.1435sshClient # [ 7.617844] systemd[1]: Starting Rule-based Manager for Device Events and Files...1436sshClient # [ 7.643834] systemd[1]: Mounting /run/wrappers...1437machine # [ 7.832507] systemd[1]: Mounted /run/wrappers.1438machine # [ 7.833653] systemd[1]: Reached target Local File Systems.1439machine # [ 7.847577] systemd[1]: Listening on Boot Loader Control Service Socket.1440machine # [ 7.854969] systemd[1]: Starting Create SUID/SGID Wrappers...1441machine # [ 7.860534] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1442machine # [ 7.867096] systemd[1]: Starting Create System Files and Directories...1443sshClient # [ 7.743587] systemd[1]: Mounted /run/wrappers.1444sshClient # [ 7.748316] systemd[1]: Reached target Local File Systems.1445sshClient # [ 7.753165] systemd[1]: Listening on Boot Loader Control Service Socket.1446machine # [ 7.950632] systemd-udevd[312]: Using default interface naming scheme 'v261'.1447sshClient # [ 7.767708] systemd[1]: Starting Create SUID/SGID Wrappers...1448sshClient # [ 7.769475] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1449sshClient # [ 7.780857] systemd[1]: Starting Create System Files and Directories...1450sshClient # [ 7.826359] systemd-udevd[307]: Using default interface naming scheme 'v261'.1451machine # [ 8.026954] systemd[1]: Starting User Database Manager...1452sshClient # [ 7.910613] systemd[1]: Starting User Database Manager...1453machine # [ 8.118230] systemd[1]: Finished Create System Files and Directories.1454machine # [ 8.132575] systemd[1]: Starting Rebuild Journal Catalog...1455machine # [ 8.148278] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1456sshClient # [ 8.044469] systemd[1]: Finished Create System Files and Directories.1457sshClient # [ 8.059160] systemd[1]: Starting Rebuild Journal Catalog...1458machine # [ 8.257390] systemd[1]: Started User Database Manager.1459sshClient # [ 8.069147] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1460machine # [ 8.324164] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1461sshClient # [ 8.156349] systemd[1]: Started User Database Manager.1462machine # [ 8.376643] systemd[1]: Finished Rebuild Journal Catalog.1463machine # [ 8.387325] systemd[1]: Starting Update is Completed...1464sshClient # [ 8.259652] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1465sshClient # [ 8.305566] systemd[1]: Finished Rebuild Journal Catalog.1466machine # [ 8.496381] systemd[1]: Finished Update is Completed.1467sshClient # [ 8.328655] systemd[1]: Starting Update is Completed...1468machine # [ 8.525116] systemd[1]: Started Rule-based Manager for Device Events and Files.1469sshClient # [ 8.448837] systemd[1]: Finished Update is Completed.1470sshClient # [ 8.496251] systemd[1]: Started Rule-based Manager for Device Events and Files.1471machine # [ 8.864740] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1472machine # [ 8.936308] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1473sshClient # [ 8.863470] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1474sshClient # [ 8.932748] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1475machine # [ 9.303005] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1476machine # [ 9.316199] systemd[1]: Finished Create SUID/SGID Wrappers.1477sshClient # [ 9.136377] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1478sshClient # [ 9.144568] systemd[1]: Finished Create SUID/SGID Wrappers.1479machine # [ 9.416431] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1480machine # [ 9.424269] systemd[1]: Finished Coldplug All udev Devices.1481machine # [ 9.425773] systemd[1]: Reached target System Initialization.1482machine # [ 9.426800] systemd[1]: Started Discard unused filesystem blocks once a week.1483machine # [ 9.427929] systemd[1]: Started Daily Cleanup of Temporary Directories.1484machine # [ 9.437585] systemd[1]: Reached target Timer Units.1485machine # [ 9.438525] systemd[1]: Listening on D-Bus System Message Bus Socket.1486machine # [ 9.439566] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).1487machine # [ 9.450508] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1488machine # [ 9.451953] systemd[1]: Reached target Socket Units.1489machine # [ 9.461540] systemd[1]: Reached target Basic System.1490machine # [ 9.462417] systemd[1]: Starting Import lastlog data into lastlog2 database...1491machine # [ 9.463630] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1492machine # [ 9.472432] systemd[1]: Starting Post-Boot Actions...1493sshClient # [ 9.284386] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1494sshClient # [ 9.306780] systemd[1]: Finished Coldplug All udev Devices.1495machine # [ 9.496359] systemd[1]: Started Reset console on configuration changes.1496sshClient # [ 9.311151] systemd[1]: Reached target System Initialization.1497sshClient # [ 9.315383] systemd[1]: Started Discard unused filesystem blocks once a week.1498sshClient # [ 9.320750] systemd[1]: Started Daily Cleanup of Temporary Directories.1499sshClient # [ 9.321899] systemd[1]: Reached target Timer Units.1500sshClient # [ 9.322735] systemd[1]: Listening on D-Bus System Message Bus Socket.1501sshClient # [ 9.323720] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1502sshClient # [ 9.333337] systemd[1]: Reached target Socket Units.1503sshClient # [ 9.338258] systemd[1]: Reached target Basic System.1504sshClient # [ 9.339269] systemd[1]: Starting Import lastlog data into lastlog2 database...1505sshClient # [ 9.345873] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1506machine # [ 9.541046] systemd[1]: Starting resolvconf update...1507sshClient # [ 9.356161] systemd[1]: Starting Post-Boot Actions...1508sshClient # [ 9.375461] systemd[1]: Started Reset console on configuration changes.1509machine # [ 9.596430] systemd[1]: Starting SSH Host Keys Generation...1510sshClient # [ 9.414106] systemd[1]: Starting resolvconf update...1511machine # [ 9.642748] systemd[1]: Starting Home Area Manager...1512sshClient # [ 9.499615] systemd[1]: Starting Home Area Manager...1513sshClient # [ 9.521003] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1514machine # [ 9.729347] systemd[1]: Finished Post-Boot Actions.1515machine # [ 9.747536] nsncd[450]: Oct 20 03:08:39.121 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1516sshClient # [ 9.562662] nsncd[442]: Oct 20 03:08:39.911 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1517machine # [ 9.767486] systemd[1]: Started Name Service Cache Daemon (nsncd).1518sshClient # [ 9.581577] systemd[1]: Started backdoor.service.1519sshClient # [ 9.618689] systemd[1]: Starting D-Bus System Message Bus...1520sshClient # [ 9.622555] systemd[1]: Started Name Service Cache Daemon (nsncd).1521machine # [ 9.841837] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1522machine # [ 9.843228] systemd[1]: Reached target Host and Network Name Lookups.1523machine # [ 9.857506] systemd[1]: Reached target User and Group Name Lookups.1524machine # [ 9.869184] systemd[1]: Started backdoor.service.1525sshClient # [ 9.690829] systemd[1]: Finished Post-Boot Actions.1526machine # [ 9.902960] systemd[1]: Starting D-Bus System Message Bus...1527sshClient # [ 9.751787] systemd[1]: Reached target Host and Network Name Lookups.1528sshClient # [ 9.776595] systemd[1]: Reached target User and Group Name Lookups.1529machine # [ 9.982449] (udev-worker)[375]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1530sshClient # [ 9.805756] (udev-worker)[415]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1531machine # [ 9.996986] (udev-worker)[375]: Network interface NamePolicy= disabled on kernel command line.1532machine # [ 10.009791] (udev-worker)[383]: Network interface NamePolicy= disabled on kernel command line.1533machine # [ 10.011286] systemd[1]: Starting User Login Management...1534sshClient # [ 9.832998] (udev-worker)[407]: Network interface NamePolicy= disabled on kernel command line.1535sshClient # [ 9.834710] systemd-homed[451]: Watching /home.1536sshClient # [ 9.835572] (udev-worker)[415]: Network interface NamePolicy= disabled on kernel command line.1537machine # [ 10.031280] systemd[1]: Finished Import lastlog data into lastlog2 database.1538sshClient # [ 9.867225] systemd[1]: Starting User Login Management...1539sshClient # connecting to host...1540machine # [ 10.090003] systemd-homed[457]: Watching /home.1541sshClient # [ 9.928910] systemd[1]: Finished Import lastlog data into lastlog2 database.1542sshClient # [ 10.031859] dbus-broker-launch[457]: Looking up NSS user entry for 'systemd-timesync'...1543machine # connecting to host...1544sshClient # [ 10.044970] dbus-broker-launch[457]: NSS returned no entry for 'systemd-timesync'1545sshClient # [ 10.046185] dbus-broker-launch[457]: Invalid user-name in /nix/store/q6s3p58sx983nlrg276kqb2kyw20w3k1-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1546sshClient # [ 10.106804] systemd[1]: Started D-Bus System Message Bus.1547machine: Guest shell says: b'Spawning backdoor root shell...\n'1548sshClient # [ 10.160529] systemd-logind[482]: New seat seat0.1549sshClient # [ 10.161769] systemd[1]: Started User Login Management.1550machine: connected to guest root shell1551sshClient # [ 10.174602] systemd[1]: Starting linger-users.service...1552machine: (connecting took 10.52 seconds)1553sshClient # [ 10.179248] dbus-broker-launch[457]: Ready1554machine: (finished: waiting for the VM to finish booting, in 10.52 seconds)1555machine # [ 10.370475] dbus-broker-launch[483]: Looking up NSS user entry for 'systemd-timesync'...1556sshClient # [ 10.189261] systemd[1]: Stopped target Host and Network Name Lookups.1557sshClient # [ 10.190506] systemd[1]: Stopping Host and Network Name Lookups...1558sshClient # [ 10.191474] systemd[1]: Stopped target User and Group Name Lookups.1559machine # [ 10.385402] dbus-broker-launch[483]: NSS returned no entry for 'systemd-timesync'1560machine # [ 10.386720] dbus-broker-launch[483]: Invalid user-name in /nix/store/1cg9djm6dps1b0cznxafh9p8nd9s0ynq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1561sshClient # [ 10.202592] systemd[1]: Stopping User and Group Name Lookups...1562sshClient # [ 10.203646] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1563sshClient # [ 10.209458] systemd[1]: nscd.service: Deactivated successfully.1564sshClient # [ 10.215572] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1565machine # [ 10.439906] systemd[1]: Stopped target Host and Network Name Lookups.1566machine # [ 10.454259] systemd[1]: Stopping Host and Network Name Lookups...1567sshClient # [ 10.266872] systemd[1]: Started Home Area Manager.1568machine # [ 10.455404] systemd[1]: Stopped target User and Group Name Lookups.1569machine # [ 10.470552] systemd[1]: Stopping User and Group Name Lookups...1570machine # [ 10.471697] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1571sshClient # [ 10.295326] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1572sshClient # [ 10.302003] systemd[1]: Finished Home Area Activation.1573machine # [ 10.489034] systemd[1]: nscd.service: Deactivated successfully.1574machine # [ 10.503617] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1575sshClient # [ 10.320450] systemd[1]: Starting First Boot Home Area Wizard...1576machine # [ 10.517157] systemd-logind[492]: New seat seat0.1577machine # [ 10.536765] systemd[1]: Started D-Bus System Message Bus.1578machine # [ 10.537830] systemd[1]: Started User Login Management.1579sshClient # [ 10.359414] systemd[1]: linger-users.service: Deactivated successfully.1580sshClient # [ 10.366953] systemd[1]: Finished linger-users.service.1581machine # [ 10.574470] systemd[1]: Starting linger-users.service...1582machine # [ 10.596390] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1583machine # [ 10.606639] dbus-broker-launch[483]: Ready1584sshClient # [ 10.439777] systemd[1]: Started Name Service Cache Daemon (nsncd).1585sshClient # [ 10.447419] systemd[1]: Reached target Host and Network Name Lookups.1586sshClient # [ 10.457858] nsncd[551]: Oct 20 03:08:40.791 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1587sshClient # [ 10.472825] systemd[1]: Reached target User and Group Name Lookups.1588sshClient # [ 10.493936] systemd[1]: Finished resolvconf update.1589sshClient # [ 10.508409] systemd[1]: Starting DHCP Client...1590sshClient # [ 10.536431] systemd[1]: Finished First Boot Home Area Wizard.1591sshClient # [ 10.559499] systemd[1]: Reached target First Boot Complete.1592sshClient # [ 10.569034] systemd[1]: Starting Save Transient machine-id to Disk...1593machine # [ 10.800168] systemd[1]: linger-users.service: Deactivated successfully.1594machine # [ 10.806080] systemd[1]: Finished linger-users.service.1595machine # [ 10.823833] systemd[1]: Finished resolvconf update.1596sshClient # [ 10.641760] systemd[1]: Condition check resulted in Virtio network device being skipped.1597sshClient # [ 10.643099] systemd[1]: Condition check resulted in Virtio network device being skipped.1598machine # [ 10.832293] systemd[1]: Started Home Area Manager.1599machine # [ 10.859531] nsncd[569]: Oct 20 03:08:40.233 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1600machine # [ 10.875502] systemd[1]: Starting DHCP Client...1601sshClient # [ 10.689074] systemd[1]: Finished Firewall.1602machine # [ 10.880708] systemd[1]: Finished Home Area Activation.1603machine # [ 10.881806] systemd[1]: Starting First Boot Home Area Wizard...1604machine # [ 10.882695] systemd[1]: Started Name Service Cache Daemon (nsncd).1605sshClient # [ 10.696677] systemd[1]: Reached target Preparation for Network.1606sshClient # [ 10.705207] systemd[1]: Starting Address configuration of eth1...1607machine # [ 10.897098] systemd[1]: Reached target Host and Network Name Lookups.1608machine # [ 10.898224] systemd[1]: Reached target User and Group Name Lookups.1609sshClient # [ 10.724336] systemd[1]: Starting Extra networking commands....1610sshClient # [ 10.739773] systemd-logind[482]: Watching system buttons on /dev/input/event0 (gpio-keys)1611machine # [ 11.023251] systemd[1]: Condition check resulted in Virtio network device being skipped.1612machine # [ 11.030511] systemd[1]: Condition check resulted in Virtio network device being skipped.1613machine # [ 11.111452] systemd[1]: Created slice Slice /system/systemd-mute-console.1614machine # [ 11.125968] systemd[1]: Started Console Output Muting Service (PID 601/UID 0).1615machine # [ 11.157427] systemd[1]: Finished Firewall.1616machine # [ 11.162566] systemd[1]: Reached target Preparation for Network.1617machine # [ 11.180189] systemd[1]: Starting Address configuration of eth1...1618sshClient # [ 11.009446] dhcpcd[596]: dhcpcd-10.3.2 starting1619machine # [ 11.198728] systemd[1]: Starting Extra networking commands....1620sshClient # [ 11.022657] network-addresses-eth1-start[594]: adding address 192.168.1.2/24... done1621sshClient # [ 11.036176] dhcpcd[611]: dev: loaded udev1622sshClient # [ 11.057539] network-addresses-eth1-start[594]: adding address 2001:db8:1::2/64... done1623sshClient # [ 11.104507] 8021q: 802.1Q VLAN Support v1.81624sshClient # [ 11.104941] 8021q: adding VLAN 0 to HW filter on device eth11625sshClient # [ 11.102320] systemd[1]: Finished Address configuration of eth1.1626machine # [ 11.442917] dhcpcd[610]: dhcpcd-10.3.2 starting1627machine # [ 11.467018] dhcpcd[635]: dev: loaded udev1628sshClient # [ 11.304292] cfg80211: Loading compiled-in X.509 certificates for regulatory database1629machine # [ 11.477120] network-addresses-eth1-start[614]: adding address 192.168.1.1/24... done1630sshClient # [ 11.313461] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1631sshClient # [ 11.314008] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1632sshClient # [ 11.319672] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21633sshClient # [ 11.320060] cfg80211: failed to load regulatory.db1634machine # [ 11.504059] network-addresses-eth1-start[614]: adding address 2001:db8:1::1/64... done1635sshClient # [ 11.352915] systemd[1]: Finished Extra networking commands..1636sshClient # [ 11.354220] systemd[1]: Reached target Network.1637sshClient # [ 11.371863] systemd[1]: Starting Permit User Sessions...[ 11.389313] mousedev: PS/2 mouse device common for all mice1638machine # [ 11.568226] systemd-logind[492]: Watching system buttons on /dev/input/event0 (gpio-keys)1639sshClient # 1640machine # [ 11.587602] systemd[1]: Finished Address configuration of eth1.1641sshClient # [ 11.465464] dhcpcd[611]: eth0: waiting for carrier1642sshClient # [ 11.468231] dhcpcd[611]: eth0: carrier acquired1643sshClient # [ 11.483096] 8021q: adding VLAN 0 to HW filter on device eth01644sshClient # [ 11.475853] systemd[1]: Finished Permit User Sessions.1645sshClient # [ 11.483773] systemd[1]: Started Getty on tty1.1646sshClient # [ 11.489920] systemd[1]: Reached target Login Prompts.1647sshClient # [ 11.504231] dhcpcd[611]: DUID 00:01:00:01:32:69:97:b9:52:54:00:12:34:561648sshClient # [ 11.505424] dhcpcd[611]: eth0: IAID 00:12:34:561649sshClient # [ 11.506158] dhcpcd[611]: eth0: adding address fe80::5054:ff:fe12:34561650sshClient # [ 11.545674] systemd-logind[482]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1651machine # [ 11.925184] systemd[1]: Finished Extra networking commands..1652machine # [ 11.928469] systemd[1]: Reached target Network.1653machine: (finished: waiting for unit systemd-homed.service, in 12.16 seconds)1654machine: waiting for Please enter user name to create to appear on tty 11655machine # [ 12.081370] dhcpcd[635]: eth0: waiting for carrier1656machine # [ 12.085930] dhcpcd[635]: eth0: carrier acquired1657machine # [ 12.126908] dhcpcd[635]: DUID 00:01:00:01:32:69:97:b9:52:54:00:12:34:561658machine # [ 12.130451] dhcpcd[635]: eth0: IAID 00:12:34:561659machine # [ 12.131236] dhcpcd[635]: eth0: adding address fe80::5054:ff:fe12:34561660sshClient # [ 12.057101] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1661sshClient # [ 12.064901] systemd[1]: Finished Save Transient machine-id to Disk.1662machine: (finished: waiting for Please enter user name to create to appear on tty 1, in 0.25 seconds)1663machine: sending keys 'test-homed-user\n'1664machine # [ 12.285564] sshd-keygen-start[500]: Generating public/private rsa key pair.1665machine # [ 12.287010] sshd-keygen-start[500]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key1666machine # [ 12.292880] sshd-keygen-start[500]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub1667machine # [ 12.294308] sshd-keygen-start[500]: The key fingerprint is:1668machine # [ 12.295143] sshd-keygen-start[500]: SHA256:KVTG38VyNP6khUvr1vFEvyCZE5rbkM/qXpDRh5jI4e4 root@machine1669machine # [ 12.302430] sshd-keygen-start[500]: The key's randomart image is:1670machine # [ 12.303723] sshd-keygen-start[500]: +---[RSA 4096]----+1671machine # [ 12.307932] sshd-keygen-start[500]: | oo oo |1672machine # [ 12.310848] sshd-keygen-start[500]: | oo+ + o.+o |1673machine # [ 12.311751] sshd-keygen-start[500]: | .+ = = =+ +|1674machine # [ 12.314391] sshd-keygen-start[500]: | .. .B *. O.|1675machine # [ 12.315226] sshd-keygen-start[500]: | ..SB = .+.+|1676machine # [ 12.315956] sshd-keygen-start[500]: | .. B o..o+|1677machine # [ 12.321766] sshd-keygen-start[500]: | E . = o.o|1678machine # [ 12.322659] sshd-keygen-start[500]: | o . |1679machine # [ 12.323524] sshd-keygen-start[500]: | o+ |1680machine # [ 12.328354] sshd-keygen-start[500]: +----[SHA256]-----+1681machine # [ 12.329334] systemd-logind[492]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1682machine # [ 12.381769] sshd-keygen-start[713]: Generating public/private ed25519 key pair.1683machine # [ 12.382898] sshd-keygen-start[713]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key1684machine # [ 12.388245] sshd-keygen-start[713]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub1685machine # [ 12.389708] sshd-keygen-start[713]: The key fingerprint is:1686machine # [ 12.390922] sshd-keygen-start[713]: SHA256:Af+/5tx6WF7XGGFKGUKeIa2ML0fWBAojEdG8b2vHXDU root@machine1687machine # [ 12.398964] sshd-keygen-start[713]: The key's randomart image is:1688machine # [ 12.399996] sshd-keygen-start[713]: +--[ED25519 256]--+1689machine # [ 12.402778] sshd-keygen-start[713]: | =*o . o++ .o |1690machine # [ 12.403605] sshd-keygen-start[713]: | .oo + oo+o o |1691machine # [ 12.408461] sshd-keygen-start[713]: | ..oo+o. o . |1692machine # [ 12.409507] sshd-keygen-start[713]: | . . =o. E . |1693machine # [ 12.410296] sshd-keygen-start[713]: | . +S .. . o.|1694machine # [ 12.411110] sshd-keygen-start[713]: | + o .. o +|1695machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds)1696machine # [ 12.421364] sshd-keygen-start[713]: | . * . .+ ..|1697machine: waiting for Please enter new password for user test-homed-user: to appear on tty 11698machine # [ 12.428292] sshd-keygen-start[713]: | o + .ooo |1699machine # [ 12.429207] sshd-keygen-start[713]: | . . o=o. |1700machine # [ 12.433657] sshd-keygen-start[713]: +----[SHA256]-----+1701machine # [ 12.434632] systemd[1]: sshd-keygen.service: Deactivated successfully.1702machine # [ 12.435682] systemd[1]: Finished SSH Host Keys Generation.1703machine # [ 12.444386] systemd[1]: Starting SSH Daemon...1704machine # [ 12.533612] sshd[715]: Server listening on 0.0.0.0 port 22.1705machine # [ 12.534639] sshd[715]: Server listening on :: port 22.1706machine # [ 12.539907] systemd[1]: Started SSH Daemon.1707sshClient # [ 12.401435] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31708sshClient # [ 12.734945] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1709sshClient # [ 12.756360] systemd[1]: Starting Virtual Console Setup...1710sshClient # [ 12.788134] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1711sshClient # [ 12.792283] systemd[1]: Stopped Virtual Console Setup.1712sshClient # [ 12.799954] systemd[1]: Starting Virtual Console Setup...1713sshClient # [ 12.863545] systemd-logind[482]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1714sshClient # [ 12.962526] systemd-vconsole-setup[689]: Configuration of first virtual console was skipped, ignoring remaining ones.1715sshClient # [ 12.968241] systemd[1]: Finished Virtual Console Setup.1716sshClient # [ 13.271829] dhcpcd[611]: eth0: soliciting a DHCP lease1717sshClient # [ 13.276915] dhcpcd[611]: eth0: offered 10.0.2.15 from 10.0.2.21718sshClient # [ 13.280831] dhcpcd[611]: eth0: probing address 10.0.2.15/241719machine # [ 13.692609] dhcpcd[635]: eth0: soliciting a DHCP lease1720machine # [ 13.697224] dhcpcd[635]: eth0: offered 10.0.2.15 from 10.0.2.21721machine # [ 13.708606] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1722machine # [ 13.710057] dhcpcd[635]: eth0: probing address 10.0.2.15/241723machine # [ 13.729261] dhcpcd[635]: eth0: soliciting an IPv6 router1724machine: (finished: waiting for Please enter new password for user test-homed-user: to appear on tty 1, in 1.31 seconds)1725machine # [ 13.732667] dhcpcd[635]: eth0: Router Advertisement from fe80::21726machine: sending keys 'foobarfoo\n'1727machine # [ 13.733865] dhcpcd[635]: eth0: adding address fec0::5054:ff:fe12:3456/641728machine # [ 13.744615] dhcpcd[635]: eth0: adding route to fec0::/641729sshClient # [ 13.556568] dhcpcd[611]: eth0: soliciting an IPv6 router1730machine # [ 13.746043] dhcpcd[635]: eth0: adding default route via fe80::21731sshClient # [ 13.559581] dhcpcd[611]: eth0: Router Advertisement from fe80::21732sshClient # [ 13.561651] dhcpcd[611]: eth0: adding address fec0::5054:ff:fe12:3456/641733machine # [ 13.752565] systemd[1]: Starting Virtual Console Setup...1734sshClient # [ 13.564450] dhcpcd[611]: eth0: adding route to fec0::/641735sshClient # [ 13.566075] dhcpcd[611]: eth0: adding default route via fe80::21736machine # [ 13.831528] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1737machine # [ 13.838214] systemd[1]: Stopped Virtual Console Setup.1738machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1739machine: waiting for (repeat) to appear on tty 11740machine # [ 13.851236] systemd[1]: Starting Virtual Console Setup...1741machine # [ 14.044988] systemd-logind[492]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1742machine: (finished: waiting for (repeat) to appear on tty 1, in 0.26 seconds)1743machine: sending keys 'foobarfoo\n'1744machine: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1745machine: waiting for unit systemd-homed-firstboot.service1746machine # [ 14.623303] systemd-vconsole-setup[736]: Configuration of first virtual console was skipped, ignoring remaining ones.1747machine # [ 14.628911] systemd[1]: Finished Virtual Console Setup.1748sshClient # [ 18.021816] dhcpcd[611]: eth0: leased 10.0.2.15 for 86400 seconds1749sshClient # [ 18.022144] dhcpcd[611]: eth0: adding route to 10.0.2.0/241750sshClient # [ 18.022229] dhcpcd[611]: eth0: adding default route via 10.0.2.21751sshClient # [ 18.234720] systemd[1]: Started DHCP Client.1752sshClient # [ 18.237379] systemd[1]: Reached target Multi-User System.1753sshClient # [ 18.238908] systemd[1]: Startup finished in 1.233s (kernel) + 4.480s (initrd) + 12.524s (userspace) = 18.238s.1754machine # [ 19.234710] dhcpcd[635]: eth0: leased 10.0.2.15 for 86400 seconds1755machine # [ 19.237363] dhcpcd[635]: eth0: adding route to 10.0.2.0/241756machine # [ 19.238773] dhcpcd[635]: eth0: adding default route via 10.0.2.21757machine # [ 19.363570] systemd[1]: Started DHCP Client.1758machine # [ 24.535752] systemd-homed[457]: Generating key pair for signing local user identity records.1759machine # [ 24.539793] systemd-homed[457]: Successfully created Ed25519 key pair.1760machine # [ 24.627386] systemd-homed[457]: test-homed-user: changing state absent → creating1761machine # [ 24.727937] systemd-homework[848]: Using automatic default storage of 'luks'.1762machine # [ 24.735331] systemd-homework[848]: Sizing home to 83% of available disk space, which is 744M.1763machine # [ 24.737178] systemd-homework[848]: Allocating image file completed.1764machine # [ 24.759122] systemd-homework[848]: Writing of partition table completed.1765machine # [ 24.764272] systemd-homework[848]: Setting up loopback device /dev/loop0 completed.1766machine # [ 26.078388] systemd-homework[848]: LUKS formatting completed.1767machine # [ 28.352541] systemd-homework[848]: Writing password to LUKS keyslot 0 completed.1768machine # [ 28.568522] systemd-homework[848]: LUKS activation by volume key succeeded.1769machine # [ 28.585859] systemd-homework[848]: Writing user record as LUKS token completed.1770machine # [ 28.587142] systemd-homework[848]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1771machine # [ 28.590802] systemd-homework[848]: Formatting /dev/mapper/home-test-homed-user as btrfs1772machine # [ 29.458506] systemd[1]: proc-877-mounts.mount: Deactivated successfully.1773machine # [ 29.459613] systemd-homework[848]: /dev/mapper/home-test-homed-user successfully formatted as btrfs (label "test-homed-user", uuid ea2d6ea1-a3dd-4761-aa77-ea405039356b)1774machine # [ 29.459696] systemd-homework[848]: Formatting file system completed.1775machine # [ 29.516572] systemd-homework[848]: Mounting file system completed.1776machine # [ 29.519962] systemd-homework[848]: Skeleton directory /etc/skel missing, ignoring.1777machine # [ 29.521379] systemd-homework[848]: Wrote embedded .identity file.1778machine # [ 29.522311] systemd-homework[848]: Replaced embedded blob dir with contents of system blob dir.1779machine # [ 29.523538] systemd-homework[848]: Recursive changing of ownership completed.1780machine # [ 29.525678] systemd-homework[848]: Changed top-level directory access mode to 0700.1781machine # [ 29.895141] systemd-homework[848]: Synchronized disk.1782machine # [ 29.898155] systemd-homework[848]: Discarded unused 652.8M.1783machine # [ 29.915895] systemd-homed[457]: block device /sys/devices/virtual/block/dm-0 has been removed.1784machine # [ 29.955681] systemd-homed[457]: block device /sys/devices/virtual/block/dm-0 has been removed.1785machine # [ 30.006599] (udev-worker)[866]: loop0: Failed to add device watch, ignoring: No such file or directory1786machine # [ 30.013695] (udev-worker)[866]: loop0: Failed to add device watch, ignoring: No such file or directory1787machine # [ 30.016456] systemd-homed[457]: block device /sys/devices/virtual/block/loop0 has been removed.1788machine # [ 30.028368] systemd-homework[848]: Moved image file into place.1789machine # [ 30.033708] systemd-homework[848]: Creation completed.1790machine # [ 30.035144] systemd-homework[848]: Image size is 744M, file system size is 726M, file system payload size is 726M, file system free is 719.9M.1791machine # [ 30.048810] systemd-homed[457]: test-homed-user: changing state creating → inactive1792machine # [ 30.063242] systemd[1]: Finished First Boot Home Area Wizard.1793machine # [ 30.068942] systemd[1]: systemd-mute-console@0-1-601_602-0.service: Deactivated successfully.1794machine # [ 30.076842] systemd[1]: Reached target First Boot Complete.1795machine # [ 30.084242] systemd[1]: Starting Save Transient machine-id to Disk...1796machine # [ 30.088444] systemd[1]: Starting Permit User Sessions...1797machine # [ 30.143397] systemd[1]: Finished Permit User Sessions.1798machine # [ 30.149405] systemd[1]: Started Getty on tty1.1799machine # [ 30.151521] systemd[1]: Reached target Login Prompts.1800machine # [ 30.154634] systemd[1]: Reached target Multi-User System.1801machine # [ 30.186680] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1802machine # [ 30.191131] systemd[1]: Finished Save Transient machine-id to Disk.1803machine # [ 30.205601] systemd[1]: Startup finished in 1.203s (kernel) + 4.458s (initrd) + 24.542s (userspace) = 30.204s.1804machine: (finished: waiting for unit systemd-homed-firstboot.service, in 16.28 seconds)1805(finished: subtest: create systemd-homed user on first boot prompt, in 30.64 seconds)1806machine: must succeed: homectl update test-homed-user --offline -G wheel1807machine # [ 30.525261] systemd-homed[457]: test-homed-user: changing state inactive → updating1808machine # [ 30.534155] systemd-homework[923]: Offline update requested. Not touching embedded records.1809machine # [ 30.538379] systemd-homed[457]: Home test-homed-user is signed exclusively by our key, accepting.1810machine # [ 30.547769] systemd-homed[457]: test-homed-user: changing state updating → inactive1811machine: (finished: must succeed: homectl update test-homed-user --offline -G wheel, in 0.06 seconds)1812subtest: login as homed user1813machine: waiting for login: to appear on tty 11814machine: (finished: waiting for login: to appear on tty 1, in 0.04 seconds)1815machine: sending keys 'test-homed-user\n'1816machine: (finished: sending keys 'test-homed-user\n', in 0.17 seconds)1817machine: waiting for Password: to appear on tty 11818machine # [ 30.933663] systemd-homed[457]: test-homed-user: changing state inactive → activating-for-acquire1819machine # [ 30.943116] systemd-homework[938]: None of the supplied plaintext passwords unlock the user record's hashed passwords.1820machine # [ 30.954054] systemd-homed[457]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.1821machine # [ 30.958171] systemd-homed[457]: test-homed-user: changing state activating-for-acquire → inactive1822machine # [ 30.961122] systemd-homed[457]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.1823machine # [ 30.963583] systemd-homed[457]: Home test-homed-user already deactivated, no automatic deactivation needed.1824machine: (finished: waiting for Password: to appear on tty 1, in 1.10 seconds)1825machine: sending keys 'foobarfoo\n'1826machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)1827machine: waiting for success: pgrep -u test-homed-user -t tty1 bash1828machine # [ 32.230551] systemd-homed[457]: test-homed-user: changing state inactive → activating-for-acquire1829machine # [ 32.291590] systemd-homework[951]: Provided password unlocks user record.1830machine # [ 32.299583] systemd-homework[951]: Successfully locked image file '/home/test-homed-user.home'.1831machine # [ 32.309262] systemd-homework[951]: Allocated additional 652.8M.1832machine # [ 32.330962] loop1: detected capacity change from 0 to 15196161833machine # [ 32.317358] systemd-homework[951]: Setting up loopback device /dev/loop1 completed.1834machine # [ 33.335479] systemd-homework[951]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.1835machine # [ 33.382425] systemd-homework[951]: Provided password unlocks user record.1836machine # [ 33.388672] systemd-homework[951]: Probing file system completed (found btrfs).1837machine # [ 33.391012] systemd-homework[951]: No fsck for file system btrfs installed, ignoring.1838machine # [ 33.409981] BTRFS: device label test-homed-user devid 1 transid 9 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (951)1839machine # [ 33.414634] BTRFS info (device dm-0): first mount of filesystem ea2d6ea1-a3dd-4761-aa77-ea405039356b1840machine # [ 33.414696] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm1841machine # [ 33.405504] systemd-homework[951]: Mounting file system completed.1842machine # [ 33.421421] BTRFS info (device dm-0): enabling free space tree1843machine # [ 33.421449] BTRFS info (device dm-0): use zstd compression, level 11844machine # [ 33.410768] systemd-homework[951]: Discovered used loopback device /dev/loop1.1845machine # [ 33.411922] systemd-homework[951]: offset = 1048576, size = 778043392, image = 7801405441846machine # [ 33.422396] systemd-homework[951]: Ready to resize image size 744M → 897M, partition size 742M → 895M, file system size 726M → 879M.1847machine # [ 33.425067] systemd-homework[951]: Growing of image file from 744M to 897M completed.1848machine # [ 33.452334] loop1: detected capacity change from 1519616 to 18329601849machine # [ 33.441436] systemd-homework[951]: Refreshing loop device size completed.1850machine # [ 33.456759] systemd-homework[951]: Growing of partition completed.1851machine # [ 33.500422] async_tx: api initialized (async)1852machine # [ 33.557219] systemd-homework[951]: LUKS device growing completed.1853machine # [ 33.583893] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 761266176 to 9216983041854machine # [ 33.572203] systemd-homework[951]: File system resizing from 726M to 879M completed.1855machine # [ 33.573908] systemd-homework[951]: Synchronized disk.1856machine # [ 33.574758] systemd-homework[951]: Synchronized disk.1857machine # [ 33.575556] systemd-homework[951]: Resizing completed.1858machine # [ 33.580384] systemd-homework[951]: Image size is 897M, file system size is 879M, file system payload size is 879M, file system free is 872.9M.1859machine # [ 33.582496] systemd-homework[951]: Read embedded .identity file.1860machine # [ 33.611770] systemd-homework[951]: Provided password unlocks user record.1861machine # [ 33.615220] systemd-homework[951]: Reconciling header user identity completed (host version was newer).1862machine # [ 33.617417] systemd-homework[951]: Reconciling embedded user identity completed (host version was newer).1863machine # [ 33.631849] systemd-homework[951]: Wrote LUKS header user record.1864machine # [ 33.633976] systemd-homework[951]: Wrote embedded .identity file.1865machine # [ 33.635009] systemd-homework[951]: Replaced embedded blob dir with contents of system blob dir.1866machine # [ 33.637523] systemd-homework[951]: Recursive changing of ownership not necessary, skipped.1867machine # [ 33.642708] systemd-homework[951]: Synchronized disk.1868machine # [ 33.643634] systemd-homework[951]: Moving to final mount point /home/test-homed-user completed.1869machine # [ 33.673238] systemd-homework[951]: Activation completed.1870machine # [ 33.676233] systemd-homework[951]: Image size is 895M, file system size is 879M, file system payload size is 879M, file system free is 872.9M.1871machine # [ 33.698767] systemd-homed[457]: Home test-homed-user is signed exclusively by our key, accepting.1872machine # [ 33.709580] login[914]: pam_systemd_home(login:auth): Home for user test-homed-user successfully acquired.1873machine # [ 33.720569] systemd-homed[457]: test-homed-user: changing state activating-for-acquire → active1874machine # [ 33.725839] systemd-homed[457]: test-homed-user: changing state active → resizing-while-active1875machine # [ 33.756926] systemd-homework[993]: Successfully acquired home volume key from kernel keyring.1876machine # [ 33.758400] systemd-homework[993]: LUKS volume key from keyring unlocks user record.1877machine # [ 33.851400] login[914]: pam_unix(login:session): session opened for user test-homed-user(uid=60388) by LOGIN(uid=0)1878machine # [ 33.893823] systemd-homework[993]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.1879machine # [ 34.045450] systemd-homework[993]: Successfully re-activated LUKS device.1880machine # [ 34.049008] systemd-homework[993]: LUKS volume key from keyring unlocks user record.1881machine # [ 34.052191] systemd-homework[993]: Discovered used loopback device /dev/loop1.1882machine # [ 34.053428] systemd-homework[993]: Read embedded .identity file.1883machine # [ 34.054340] systemd-homework[993]: LUKS volume key from keyring unlocks user record.1884machine # [ 34.055477] systemd-homework[993]: Reconciling header user identity completed (host version was newer).1885machine # [ 34.067583] systemd-homework[993]: Reconciling embedded user identity completed (host version was newer).1886machine # [ 34.072476] systemd-homework[993]: offset = 1048576, size = 938475520, image = 9405726721887machine # [ 34.076601] systemd[1]: Created slice Slice /user/60388.1888machine # [ 34.081902] systemd[1]: Starting User Runtime Directory /run/user/60388...1889machine # [ 34.083120] systemd-homework[993]: Ready to resize image size 897M → 765M, partition size 895M → 763M, file system size 879M → 747M.1890machine # [ 34.089110] systemd-homework[993]: Wrote embedded .identity file.1891machine # [ 34.093512] systemd-homework[993]: Replaced embedded blob dir with contents of system blob dir.1892machine # [ 34.108432] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 921698304 to 7832862721893machine # [ 34.098422] systemd-homework[993]: Backing file is fully allocated already.1894machine # [ 34.101014] systemd-homework[993]: File system resizing from 879M to 747M completed.1895machine # [ 34.111932] systemd-homework[993]: Synchronized disk.1896machine # [ 34.121198] systemd-logind[492]: New session '1' of user 'test-homed-user' with class 'user' and type 'tty'.1897machine # [ 34.164206] systemd[1]: Finished User Runtime Directory /run/user/60388.1898machine # [ 34.180132] systemd[1]: Starting User Manager for UID 60388...1899machine # [ 34.234901] systemd-homework[993]: LUKS device shrinking completed.1900machine # [ 34.243577] (systemd)[1001]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.1901machine # [ 34.250922] systemd-homework[993]: Refreshing loop device size completed.1902machine # [ 34.264653] loop1: detected capacity change from 1832960 to 15626241903machine # [ 34.258323] systemd-homework[993]: Shrinking of image file completed.1904machine # [ 34.295156] systemd-homework[993]: Shrinking of partition completed.1905machine # [ 34.369527] (systemd)[1001]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)1906machine # [ 34.382920] systemd-homework[993]: Wrote LUKS header user record.1907machine # [ 34.392586] systemd-homework[993]: Synchronized disk.1908machine # [ 34.393739] systemd-homework[993]: Resizing completed.1909machine # [ 34.394522] systemd-homework[993]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.1910machine # [ 34.417974] systemd-logind[492]: New session '2' of user 'test-homed-user' with class 'manager' and type 'unspecified'.1911machine # [ 34.436866] systemd-homed[457]: Home test-homed-user is signed exclusively by our key, accepting.1912machine # [ 34.438287] systemd-homed[457]: test-homed-user: changing state resizing-while-active → active1913machine # [ 34.439539] systemd-homed[457]: Rebalancing complete.1914machine # [ 34.468495] systemd[1001]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1915machine # [ 34.599070] systemd[1001]: Queued start job for default target Main User Target.1916machine # [ 34.609305] systemd[1001]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1917machine # [ 34.613781] systemd[1001]: Created slice User Application Slice.1918machine # [ 34.614916] systemd[1001]: Started Daily Cleanup of User's Temporary Directories.1919machine # [ 34.618232] systemd[1001]: Reached target Paths.1920machine # [ 34.619108] systemd[1001]: Reached target Timers.1921machine # [ 34.619865] systemd[1001]: Starting D-Bus User Message Bus Socket...1922machine # [ 34.623175] systemd[1001]: Starting Create User Files and Directories...1923machine # [ 34.668833] systemd[1001]: Finished Create User Files and Directories.1924machine # [ 34.693877] systemd[1001]: Listening on D-Bus User Message Bus Socket.1925machine # [ 34.695060] systemd[1001]: Reached target Sockets.1926machine # [ 34.695830] systemd[1001]: Reached target Basic System.1927machine # [ 34.703378] systemd[1]: Started User Manager for UID 60388.1928machine # [ 34.708331] systemd[1001]: Starting Run user-specific NixOS activation...1929machine # [ 34.712759] systemd[1]: Started Session 1 of User test-homed-user.1930machine # [ 34.762535] systemd[1001]: Finished Run user-specific NixOS activation.1931machine # [ 34.766080] systemd[1001]: Reached target Main User Target.1932machine # [ 34.767082] systemd[1001]: Startup finished in 334ms.1933machine: (finished: waiting for success: pgrep -u test-homed-user -t tty1 bash, in 3.55 seconds)1934machine: sending keys 'whoami > /tmp/2\n'1935machine: (finished: sending keys 'whoami > /tmp/2\n', in 0.17 seconds)1936machine: waiting for file '/tmp/2'1937machine: (finished: waiting for file '/tmp/2', in 1.06 seconds)1938machine: must succeed: cat /tmp/21939machine: (finished: must succeed: cat /tmp/2, in 0.03 seconds)1940(finished: subtest: login as homed user, in 6.23 seconds)1941subtest: login as regular user1942sshClient: waiting for login: to appear on tty 11943sshClient: waiting for the VM to finish booting1944sshClient: Guest shell says: b'Spawning backdoor root shell...\n'1945sshClient: connected to guest root shell1946sshClient: (connecting took 0.00 seconds)1947sshClient: (finished: waiting for the VM to finish booting, in 0.00 seconds)1948sshClient: (finished: waiting for login: to appear on tty 1, in 0.07 seconds)1949sshClient: sending keys 'test-normal-user\n'1950sshClient: (finished: sending keys 'test-normal-user\n', in 0.18 seconds)1951sshClient: waiting for Password: to appear on tty 11952sshClient: (finished: waiting for Password: to appear on tty 1, in 1.12 seconds)1953sshClient: sending keys 'foobarfoo\n'1954sshClient: (finished: sending keys 'foobarfoo\n', in 0.11 seconds)1955sshClient: waiting for success: pgrep -u test-normal-user bash1956sshClient # [ 38.169841] login[680]: pam_unix(login:session): session opened for user test-normal-user(uid=1000) by LOGIN(uid=0)1957sshClient # [ 38.214635] systemd[1]: Created slice Slice /user/1000.1958sshClient # [ 38.217521] systemd[1]: Starting User Runtime Directory /run/user/1000...1959sshClient # [ 38.262365] systemd-logind[482]: New session '1' of user 'test-normal-user' with class 'user' and type 'tty'.1960sshClient # [ 38.297218] systemd[1]: Finished User Runtime Directory /run/user/1000.1961sshClient # [ 38.304459] systemd[1]: Starting User Manager for UID 1000...1962sshClient # [ 38.338584] (systemd)[793]: pam_unix(systemd-user:session): session opened for user test-normal-user(uid=1000) by (uid=0)1963sshClient # [ 38.347292] systemd-logind[482]: New session '2' of user 'test-normal-user' with class 'manager' and type 'unspecified'.1964sshClient # [ 38.379805] systemd[793]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1965sshClient # [ 38.518635] systemd[793]: Queued start job for default target Main User Target.1966sshClient # [ 38.529199] systemd[793]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1967sshClient # [ 38.533366] systemd[793]: Created slice User Application Slice.1968sshClient # [ 38.534404] systemd[793]: Started Daily Cleanup of User's Temporary Directories.1969sshClient # [ 38.535466] systemd[793]: Reached target Paths.1970sshClient # [ 38.536481] systemd[793]: Reached target Timers.1971sshClient # [ 38.538941] systemd[793]: Starting D-Bus User Message Bus Socket...1972sshClient # [ 38.541187] systemd[793]: Starting Create User Files and Directories...1973sshClient # [ 38.585702] systemd[793]: Finished Create User Files and Directories.1974sshClient # [ 38.609962] systemd[793]: Listening on D-Bus User Message Bus Socket.1975sshClient # [ 38.613714] systemd[793]: Reached target Sockets.1976sshClient # [ 38.614605] systemd[793]: Reached target Basic System.1977sshClient # [ 38.615402] systemd[1]: Started User Manager for UID 1000.1978sshClient # [ 38.624780] systemd[793]: Starting Run user-specific NixOS activation...1979sshClient # [ 38.628694] systemd[1]: Started Session 1 of User test-normal-user.1980sshClient # [ 38.687589] systemd[793]: Finished Run user-specific NixOS activation.1981sshClient # [ 38.692855] systemd[793]: Reached target Main User Target.1982sshClient # [ 38.693745] systemd[793]: Startup finished in 325ms.1983sshClient: (finished: waiting for success: pgrep -u test-normal-user bash, in 1.11 seconds)1984sshClient: sending keys 'whoami > /tmp/1\n'1985sshClient: (finished: sending keys 'whoami > /tmp/1\n', in 0.17 seconds)1986sshClient: waiting for file '/tmp/1'1987sshClient: (finished: waiting for file '/tmp/1', in 1.05 seconds)1988sshClient: must succeed: cat /tmp/11989sshClient: (finished: must succeed: cat /tmp/1, in 0.02 seconds)1990(finished: subtest: login as regular user, in 3.83 seconds)1991subtest: add homed ssh authorized key1992sshClient: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n'1993sshClient: (finished: sending keys 'ssh-keygen -t ed25519 -f /tmp/id_ed25519 -N ""\n', in 0.49 seconds)1994sshClient: waiting for file '/tmp/id_ed25519.pub'1995sshClient: (finished: waiting for file '/tmp/id_ed25519.pub', in 1.06 seconds)1996sshClient: must succeed: cat /tmp/id_ed25519.pub1997sshClient: (finished: must succeed: cat /tmp/id_ed25519.pub, in 0.03 seconds)1998machine: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIJkTskOgTHM+uwAHFn501bisyvjcMUzW4KHB2PBiSZuN test-normal-user@sshClient'1999machine # [ 42.235292] systemd-homed[457]: test-homed-user: changing state active → updating-while-active2000machine # [ 42.243979] systemd-homework[1051]: Offline update requested. Not touching embedded records.2001machine # [ 42.253184] systemd-homed[457]: test-homed-user: changing state updating-while-active → active2002machine: (finished: must succeed: homectl update test-homed-user --offline --ssh-authorized-keys 'ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIJkTskOgTHM+uwAHFn501bisyvjcMUzW4KHB2PBiSZuN test-normal-user@sshClient', in 0.06 seconds)2003machine: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed255192004machine: (finished: must succeed: userdbctl ssh-authorized-keys test-homed-user | grep ed25519, in 0.03 seconds)2005(finished: subtest: add homed ssh authorized key, in 1.68 seconds)2006subtest: change homed user password2007machine: sending keys 'passwd; echo $? > /tmp/3\n'2008machine: (finished: sending keys 'passwd; echo $? > /tmp/3\n', in 0.26 seconds)2009machine: waiting for New password: to appear on tty 12010machine: (finished: waiting for New password: to appear on tty 1, in 1.09 seconds)2011machine: sending keys 'barfoobar\n'2012machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2013machine: waiting for Retype new password: to appear on tty 12014machine: (finished: waiting for Retype new password: to appear on tty 1, in 1.10 seconds)2015machine: sending keys 'barfoobar\n'2016machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2017??? Warning (UserWarning): sleep(): The 'secs' argument is deprecated. Use 'duration' instead.2018 File "/nix/store/0yd36fpwpnbc3yrryy7qi9hd645vflhd-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 5732019machine: must succeed: sleep 4.02020machine # [ 45.088749] systemd-homed[457]: test-homed-user: changing state active → passwd-while-active2021machine # [ 45.855033] systemd-homework[1089]: No valid password for LUKS superblock.2022machine # [ 48.110152] systemd-homed[457]: Change operation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2023machine # [ 48.114253] systemd-homed[457]: test-homed-user: changing state passwd-while-active → active2024machine: (finished: must succeed: sleep 4.0, in 4.04 seconds)2025machine: sending keys 'foobarfoo\n'2026machine: (finished: sending keys 'foobarfoo\n', in 0.10 seconds)2027machine: waiting for file '/tmp/3'2028machine # [ 49.235094] systemd-homed[457]: test-homed-user: changing state active → passwd-while-active2029machine # [ 50.494551] systemd-homework[1096]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2030machine # [ 50.622172] systemd-homework[1096]: Successfully re-activated LUKS device.2031machine # [ 50.678177] systemd-homework[1096]: Provided password unlocks user record.2032machine # [ 50.680215] systemd-homework[1096]: Discovered used loopback device /dev/loop1.2033machine # [ 50.684112] systemd-homework[1096]: Read embedded .identity file.2034machine # [ 50.734829] systemd-homework[1096]: Provided password unlocks user record.2035machine # [ 50.740264] systemd-homework[1096]: Reconciling header user identity completed (host version was newer).2036machine # [ 50.741695] systemd-homework[1096]: Reconciling embedded user identity completed (host version was newer).2037machine # [ 53.825192] systemd-homework[1096]: Added LUKS key slot 1.2038machine # [ 53.843331] systemd-homework[1096]: Destroyed LUKS key slot 0.2039machine # [ 53.853272] systemd-homework[1096]: Wrote LUKS header user record.2040machine # [ 53.854781] systemd-homework[1096]: Wrote embedded .identity file.2041machine # [ 53.855853] systemd-homework[1096]: Replaced embedded blob dir with contents of system blob dir.2042machine # [ 53.859792] systemd-homework[1096]: Synchronized disk.2043machine # [ 53.865428] systemd-homework[1096]: Everything completed.2044machine # [ 53.876735] passwd[1064]: pam_systemd_home(passwd:chauthtok): Successfully changed password for user test-homed-user.2045machine # [ 53.882783] systemd-homed[457]: test-homed-user: changing state passwd-while-active → active2046machine: (finished: waiting for file '/tmp/3', in 5.18 seconds)2047machine: must succeed: cat /tmp/32048machine: (finished: must succeed: cat /tmp/3, in 0.02 seconds)2049(finished: subtest: change homed user password, in 12.02 seconds)2050subtest: escalate to root from homed user2051machine: sending keys 'sudo id | tee /tmp/4\n'2052machine: (finished: sending keys 'sudo id | tee /tmp/4\n', in 0.22 seconds)2053machine: waiting for password for test-homed-user to appear on tty 12054machine # [ 54.784214] systemd-homed[457]: test-homed-user: changing state active → authenticating-for-acquire2055machine # [ 54.793744] systemd-homework[1134]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2056machine # [ 54.801351] systemd-homed[457]: Authentication failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2057machine # [ 54.805812] systemd-homed[457]: test-homed-user: changing state authenticating-for-acquire → active2058machine: (finished: waiting for password for test-homed-user to appear on tty 1, in 1.11 seconds)2059machine: sending keys 'barfoobar\n'2060machine: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2061machine: waiting for file '/tmp/4'2062machine: (finished: waiting for file '/tmp/4', in 0.03 seconds)2063machine: waiting for success: grep uid=0 /tmp/42064machine # [ 55.831597] systemd-homed[457]: test-homed-user: changing state active → authenticating-for-acquire2065machine # [ 55.878663] systemd-homework[1150]: Provided password unlocks user record.2066machine # [ 56.509893] systemd-homework[1150]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2067machine # [ 56.637296] systemd-homework[1150]: Successfully re-activated LUKS device.2068machine # [ 56.666430] systemd-homework[1150]: Provided password unlocks user record.2069machine # [ 56.668563] systemd-homework[1150]: Discovered used loopback device /dev/loop1.2070machine # [ 56.672269] systemd-homework[1150]: Read embedded .identity file.2071machine # [ 56.698045] systemd-homework[1150]: Provided password unlocks user record.2072machine # [ 56.700223] systemd-homework[1150]: Reconciling user identities completed (host and header version were identical).2073machine # [ 56.702279] systemd-homework[1150]: Reconciling embedded user identity completed (host and embedded version were identical).2074machine # [ 56.708129] systemd-homework[1150]: Everything completed.2075machine # [ 56.715147] systemd-homed[457]: Home test-homed-user is signed exclusively by our key, accepting.2076machine # [ 56.720828] sudo[1132]: pam_systemd_home(sudo:auth): Home for user test-homed-user successfully acquired.2077machine # [ 56.722420] systemd-homed[457]: test-homed-user: changing state authenticating-for-acquire → active2078machine # [ 56.728362] sudo[1132]: test-homed-user : TTY=tty1 ; PWD=/home/test-homed-user ; USER=root ; COMMAND=/run/current-system/sw/bin/id2079machine # [ 56.742331] sudo[1132]: pam_unix(sudo:session): session opened for user root(uid=0) by test-homed-user(uid=60388)2080machine # [ 56.750865] sudo[1132]: pam_unix(sudo:session): session closed for user root2081machine: (finished: waiting for success: grep uid=0 /tmp/4, in 1.07 seconds)2082(finished: subtest: escalate to root from homed user, in 2.52 seconds)2083subtest: log out and deactivate homed user's home area2084machine: sending keys 'exit\n'2085machine: (finished: sending keys 'exit\n', in 0.05 seconds)2086machine: waiting for success: homectl inspect test-homed-user | grep 'State: inactive'2087machine # [ 56.929261] login[914]: pam_unix(login:session): session closed for user test-homed-user2088machine # [ 56.940244] login[914]: pam_systemd_home(login:session): Not deactivating home directory of test-homed-user, as it is still used.2089machine # [ 56.965447] systemd[1]: getty@tty1.service: Deactivated successfully.2090machine # [ 56.966988] systemd[1]: session-1.scope: Deactivated successfully.2091machine # [ 56.973312] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.2092machine # [ 56.975311] systemd-logind[492]: Session 1 logged out. Waiting for processes to exit.2093machine # [ 56.986535] systemd[1]: Started Getty on tty1.2094machine # [ 56.987473] systemd-logind[492]: Removed session 1.2095machine # [ 67.120687] systemd[1]: Stopping User Manager for UID 60388...2096machine # [ 67.128698] systemd[1001]: Activating special unit Exit the Session...2097machine # [ 67.135164] systemd[1001]: Stopped target Main User Target.2098machine # [ 67.139228] systemd[1001]: Stopped Run user-specific NixOS activation.2099machine # [ 67.141217] systemd[1001]: Stopped target Basic System.2100machine # [ 67.142487] systemd[1001]: Stopped target Paths.2101machine # [ 67.143604] systemd[1001]: Stopped target Sockets.2102machine # [ 67.145131] systemd[1001]: Stopped target Timers.2103machine # [ 67.146277] systemd[1001]: Stopped Daily Cleanup of User's Temporary Directories.2104machine # [ 67.147918] systemd[1001]: Closed D-Bus User Message Bus Socket.2105machine # [ 67.149448] systemd[1001]: Stopped Create User Files and Directories.2106machine # [ 67.150887] systemd[1001]: Removed slice User Application Slice.2107machine # [ 67.153321] systemd[1001]: Reached target Shutdown.2108machine # [ 67.154534] systemd[1001]: Finished Exit the Session.2109machine # [ 67.155739] systemd[1001]: Reached target Exit the Session.2110machine # [ 67.180980] systemd-homed[457]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2111machine # [ 67.191038] systemd-logind[492]: Removed session 2.2112machine # [ 67.196479] systemd-homed[457]: test-homed-user: changing state active → deactivating2113machine # [ 67.198336] systemd[1]: user@60388.service: Deactivated successfully.2114machine # [ 67.199794] systemd[1]: Stopped User Manager for UID 60388.2115machine # [ 67.208817] systemd-homework[1224]: Successfully acquired home volume key from kernel keyring.2116machine # [ 67.212818] systemd-homework[1224]: Discarded unused 674.5M.2117machine # [ 67.218878] systemd-homework[1224]: Syncing completed.2118machine # [ 67.266562] systemd[1]: home-test\x2dhomed\x2duser.mount: Deactivated successfully.2119machine # [ 67.277234] systemd[1]: Stopping User Runtime Directory /run/user/60388...2120machine # [ 67.298268] systemd-homework[1224]: Discovered used LUKS device /dev/mapper/home-test-homed-user, and validated password.2121machine # [ 67.416249] systemd[1]: run-user-60388.mount: Deactivated successfully.2122machine # [ 67.426884] systemd[1]: user-runtime-dir@60388.service: Deactivated successfully.2123machine # [ 67.436233] systemd[1]: Stopped User Runtime Directory /run/user/60388.2124machine # [ 67.467259] systemd-homework[1224]: Successfully re-activated LUKS device.2125machine # [ 67.470930] systemd-homework[1224]: Discovered used loopback device /dev/loop1.2126machine # [ 67.472219] systemd-homework[1224]: offset = 1048576, size = 800063488, image = 8021606402127machine # [ 67.488559] systemd-homework[1224]: Ready to resize image size 765M → 274M, partition size 763M → 272M, file system size 747M → 256M.2128machine # [ 67.494639] systemd-homework[1224]: Allocated additional 674.5M.2129machine # [ 67.514458] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 783286272 to 2684354562130machine # [ 67.501861] systemd-homework[1224]: File system resizing from 747M to 256M completed.2131machine # [ 67.503063] systemd-homework[1224]: Synchronized disk.2132machine # [ 67.618260] systemd-homework[1224]: LUKS device shrinking completed.2133machine # [ 67.644353] loop1: detected capacity change from 1562624 to 5570562134machine # [ 67.633827] systemd-homework[1224]: Refreshing loop device size completed.2135machine # [ 67.635001] systemd-homework[1224]: Shrinking of image file completed.2136machine # [ 67.646124] systemd-homework[1224]: Shrinking of partition completed.2137machine # [ 67.663746] BTRFS info (device dm-0): last unmount of filesystem ea2d6ea1-a3dd-4761-aa77-ea405039356b2138machine # [ 67.651710] systemd-homework[1224]: Synchronized disk.2139machine # [ 67.654109] systemd-homework[1224]: Resizing completed.2140machine # [ 67.654926] systemd-homework[1224]: Image size is 274M, file system size is 256M, file system payload size is 256M, file system free is 249.9M.2141machine # [ 67.657260] systemd-homework[1224]: Unmounting completed.2142machine # [ 67.658080] systemd-homework[1224]: Discovered used LUKS device /dev/mapper/home-test-homed-user.2143machine # [ 67.659328] systemd-homework[1224]: Device home-test-homed-user is not active.2144machine # [ 67.692174] systemd-homed[457]: block device /sys/devices/virtual/block/dm-0 has been removed.2145machine # [ 67.696608] systemd-homework[1224]: Everything completed.2146machine # [ 67.710035] systemd-homed[457]: test-homed-user: changing state deactivating → inactive2147machine: (finished: waiting for success: homectl inspect test-homed-user | grep 'State: inactive', in 11.67 seconds)2148(finished: subtest: log out and deactivate homed user's home area, in 11.72 seconds)2149subtest: ssh as homed user2150sshClient: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n'2151sshClient: (finished: sending keys 'ssh -o StrictHostKeyChecking=no -i /tmp/id_ed25519 test-homed-user@machine\n', in 0.78 seconds)2152sshClient: waiting for Please enter password for user to appear on tty 12153machine # [ 70.377528] systemd-homed[457]: test-homed-user: changing state inactive → activating-for-acquire2154machine # [ 70.386640] systemd-homework[1259]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2155machine # [ 70.393967] systemd-homed[457]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2156machine # [ 70.398936] systemd-homed[457]: test-homed-user: changing state activating-for-acquire → inactive2157machine # [ 70.403919] sshd-session[1254]: pam_systemd_home(sshd:account): Conversation function failed: Conversation error2158machine # [ 70.406976] systemd-homed[457]: Got notification that all sessions of user test-homed-user ended, deactivating automatically.2159machine # [ 70.410062] sshd-session[1254]: pam_systemd_home(sshd:account): Home for user test-homed-user acquired in incomplete mode, requires later activation.2160machine # [ 70.413365] systemd-homed[457]: Home test-homed-user already deactivated, no automatic deactivation needed.2161machine # [ 70.415256] sshd-session[1254]: Accepted publickey for test-homed-user from 2001:db8:1::2 port 47644 ssh2: ED25519 SHA256:emNUYr1HupZgmW9yBPSfbkqqwz84hXQvwciIYMTUoyU2162machine # [ 70.460825] sshd-session[1254]: pam_unix(sshd:session): session opened for user test-homed-user(uid=60388) by (uid=0)2163machine # [ 70.480528] systemd-logind[492]: New session '3' of user 'test-homed-user' with class 'user-incomplete' and type 'tty'.2164machine # [ 70.486768] systemd[1]: Starting User Runtime Directory /run/user/60388...2165machine # [ 70.519554] systemd[1]: Finished User Runtime Directory /run/user/60388.2166machine # [ 70.529266] systemd[1]: Started Session 3 of User test-homed-user.2167machine # [ 70.547109] sshd-session[1254]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2168machine # [ 70.550520] sshd-session[1254]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory2169machine # [ 70.569428] systemd-homed[457]: test-homed-user: changing state inactive → activating2170machine # [ 70.575283] systemd-homework[1265]: None of the supplied plaintext passwords unlock the user record's hashed passwords.2171machine # [ 70.580717] systemd-homed[457]: Activation failed: Password for home test-homed-user is incorrect or not sufficient for authentication.2172machine # [ 70.583172] systemd-homed[457]: test-homed-user: changing state activating → inactive2173sshClient: (finished: waiting for Please enter password for user to appear on tty 1, in 2.15 seconds)2174sshClient: sending keys 'barfoobar\n'2175sshClient: (finished: sending keys 'barfoobar\n', in 0.10 seconds)2176machine: waiting for success: pgrep -u test-homed-user bash2177machine # [ 71.691968] systemd-homed[457]: test-homed-user: changing state inactive → activating2178machine # [ 71.740529] systemd-homework[1271]: Provided password unlocks user record.2179machine # [ 71.746321] systemd-homework[1271]: Successfully locked image file '/home/test-homed-user.home'.2180machine # [ 71.751896] systemd-homework[1271]: Backing file is fully allocated already.2181machine # [ 71.771394] loop1: detected capacity change from 0 to 5570562182machine # [ 71.757609] systemd-homework[1271]: Setting up loopback device /dev/loop1 completed.2183machine # [ 72.612493] systemd-homework[1271]: Setting up LUKS device /dev/mapper/home-test-homed-user completed.2184machine # [ 72.648521] systemd-homework[1271]: Provided password unlocks user record.2185machine # [ 72.654493] systemd-homework[1271]: Probing file system completed (found btrfs).2186machine # [ 72.657456] systemd-homework[1271]: No fsck for file system btrfs installed, ignoring.2187machine # [ 72.677152] BTRFS: device label test-homed-user devid 1 transid 18 /dev/mapper/home-test-homed-user (253:0) scanned by systemd-homewor (1271)2188machine # [ 72.679723] BTRFS info (device dm-0): first mount of filesystem ea2d6ea1-a3dd-4761-aa77-ea405039356b2189machine # [ 72.679773] BTRFS info (device dm-0): using crc32c (crc32c-lib) checksum algorithm2190machine # [ 72.673108] systemd-homework[1271]: Mounting file system completed.2191machine # [ 72.688778] BTRFS info (device dm-0): enabling free space tree2192machine # [ 72.688801] BTRFS info (device dm-0): use zstd compression, level 12193machine # [ 72.680230] systemd-homework[1271]: Discovered used loopback device /dev/loop1.2194machine # [ 72.681470] systemd-homework[1271]: offset = 1048576, size = 285212672, image = 2873098242195machine # [ 72.692719] systemd-homework[1271]: Ready to resize image size 274M → 765M, partition size 272M → 763M, file system size 256M → 747M.2196machine # [ 72.694703] systemd-homework[1271]: Growing of image file from 274M to 765M completed.2197machine # [ 72.704600] systemd-homework[1271]: Refreshing loop device size completed.2198machine # [ 72.716345] loop1: detected capacity change from 557056 to 15626242199machine # [ 72.725224] systemd-homework[1271]: Growing of partition completed.2200machine # [ 72.786372] systemd-homework[1271]: LUKS device growing completed.2201machine # [ 72.809099] BTRFS info (device dm-0): resize device /dev/mapper/home-test-homed-user (devid 1) from 268435456 to 7832862722202machine # [ 72.797729] systemd-homework[1271]: File system resizing from 256M to 747M completed.2203machine # [ 72.800280] systemd-homework[1271]: Synchronized disk.2204machine # [ 72.801142] systemd-homework[1271]: Synchronized disk.2205machine # [ 72.801950] systemd-homework[1271]: Resizing completed.2206machine # [ 72.802754] systemd-homework[1271]: Image size is 765M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.2207machine # [ 72.812208] systemd-homework[1271]: Read embedded .identity file.2208machine # [ 72.828773] systemd-homework[1271]: Provided password unlocks user record.2209machine # [ 72.830143] systemd-homework[1271]: Reconciling user identities completed (host and header version were identical).2210machine # [ 72.833219] systemd-homework[1271]: Reconciling embedded user identity completed (host and embedded version were identical).2211machine # [ 72.836895] systemd-homework[1271]: Recursive changing of ownership not necessary, skipped.2212machine # [ 72.838179] systemd-homework[1271]: Synchronized disk.2213machine # [ 72.838945] systemd-homework[1271]: Moving to final mount point /home/test-homed-user completed.2214machine # [ 72.860931] systemd-homework[1271]: Activation completed.2215machine # [ 72.862138] systemd-homework[1271]: Image size is 763M, file system size is 747M, file system payload size is 747M, file system free is 740.9M.2216machine # [ 72.878276] systemd-homed[457]: Home test-homed-user is signed exclusively by our key, accepting.2217machine # [ 72.883436] systemd-homed[457]: test-homed-user: changing state activating → active2218machine # [ 72.903084] systemd[1]: Starting User Manager for UID 60388...2219machine # [ 72.946412] (systemd)[1313]: pam_systemd_home(systemd-user:account): Home for user test-homed-user successfully acquired.2220machine # [ 72.985951] (systemd)[1313]: pam_unix(systemd-user:session): session opened for user test-homed-user(uid=60388) by (uid=0)2221machine # [ 72.992445] systemd-logind[492]: New session '4' of user 'test-homed-user' with class 'manager' and type 'unspecified'.2222machine # [ 73.018901] systemd[1313]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2223machine # [ 73.125802] systemd[1313]: Queued start job for default target Main User Target.2224machine # [ 73.133141] systemd[1313]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2225machine # [ 73.137100] systemd[1313]: Created slice User Application Slice.2226machine # [ 73.138094] systemd[1313]: Started Daily Cleanup of User's Temporary Directories.2227machine # [ 73.139141] systemd[1313]: Reached target Paths.2228machine # [ 73.139817] systemd[1313]: Reached target Timers.2229machine # [ 73.142923] systemd[1313]: Starting D-Bus User Message Bus Socket...2230machine # [ 73.145062] systemd[1313]: Starting Create User Files and Directories...2231machine # [ 73.185630] systemd[1313]: Finished Create User Files and Directories.2232machine # [ 73.190042] systemd[1313]: Listening on D-Bus User Message Bus Socket.2233machine # [ 73.191179] systemd[1313]: Reached target Sockets.2234machine # [ 73.191896] systemd[1313]: Reached target Basic System.2235machine # [ 73.199895] systemd[1]: Started User Manager for UID 60388.2236machine # [ 73.201155] systemd[1313]: Starting Run user-specific NixOS activation...2237machine # [ 73.247655] systemd[1313]: Finished Run user-specific NixOS activation.2238machine # [ 73.251132] systemd[1313]: Reached target Main User Target.2239machine # [ 73.253241] systemd[1313]: Startup finished in 242ms.2240machine: (finished: waiting for success: pgrep -u test-homed-user bash, in 2.21 seconds)2241sshClient: sending keys 'whoami > /tmp/5\n'2242sshClient: (finished: sending keys 'whoami > /tmp/5\n', in 0.17 seconds)2243machine: waiting for file '/tmp/5'2244machine: (finished: waiting for file '/tmp/5', in 1.05 seconds)2245machine: must succeed: cat /tmp/52246machine: (finished: must succeed: cat /tmp/5, in 0.02 seconds)2247sshClient: sending keys 'exit\n'2248sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2249sshClient: sending keys 'exit\n'2250machine # [ 75.149697] sshd-session[1263]: Received disconnect from 2001:db8:1::2 port 47644:11: disconnected by user2251machine # [ 75.153934] sshd-session[1263]: Disconnected from user test-homed-user 2001:db8:1::2 port 476442252machine # [ 75.157207] sshd-session[1254]: pam_unix(sshd:session): session closed for user test-homed-user2253sshClient: (finished: sending keys 'exit\n', in 0.05 seconds)2254(finished: subtest: ssh as homed user, in 6.60 seconds)2255(finished: run the VM test script, in 76.13 seconds)2256machine # [ 75.161946] sshd-session[1254]: pam_systemd_home(sshd:session): Not deactivating home directory of test-homed-user, as it is still used.2257machine # [ 75.174713] systemd[1]: session-3.scope: Deactivated successfully.2258machine # [ 75.181219] systemd-logind[492]: Session 3 logged out. Waiting for processes to exit.2259machine # [ 75.182876] systemd-logind[492]: Removed session 3.2260sshClient # [ 75.053659] login[680]: pam_unix(login:session): session closed for user test-normal-user2261sshClient # [ 75.065042] systemd[1]: getty@tty1.service: Deactivated successfully.2262sshClient # [ 75.080303] systemd[1]: session-1.scope: Deactivated successfully.2263sshClient # [ 75.089470] systemd[1]: session-1.scope: Consumed 131ms CPU time over 36.455s wall clock time, 8.7M memory peak, 12K written to disk, 27.2K incoming IP traffic, 26K outgoing IP traffic.2264sshClient # [ 75.107984] systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.2265sshClient # [ 75.110042] systemd[1]: Started Getty on tty1.2266sshClient # [ 75.111460] systemd-logind[482]: Session 1 logged out. Waiting for processes to exit.2267test script finished in 76.29s2268sshClient # [ 75.127783] systemd-logind[482]: Removed session 1.2269cleanup2270kill QemuMachine (pid 47)2271machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14)2272machine # [2026-10-20T03:09:45Z INFO virtiofsd] Client disconnected, shutting down2273machine # [2026-10-20T03:09:45Z INFO virtiofsd] Client disconnected, shutting down2274machine # [2026-10-20T03:09:45Z INFO virtiofsd] Client disconnected, shutting down2275kill QemuMachine (pid 48)2276sshClient # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14)2277sshClient # [2026-10-20T03:09:45Z INFO virtiofsd] Client disconnected, shutting down2278sshClient # [2026-10-20T03:09:45Z INFO virtiofsd] Client disconnected, shutting down2279sshClient # [2026-10-20T03:09:45Z INFO virtiofsd] Client disconnected, shutting down2280(finished: cleanup, in 0.35 seconds)2281additionally exposed symbols:2282 machine, sshClient,2283 vlan1,2284 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