Oct 29 04:38:54.714657 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 29 04:38:54.714675 kernel: Linux version 5.10.107-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP PREEMPT Tue Mar 22 19:39:09 -00 2022 Oct 29 04:38:54.714683 kernel: efi: EFI v2.70 by EDK II Oct 29 04:38:54.714688 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 29 04:38:54.714693 kernel: efi: seeding entropy pool Oct 29 04:38:54.714698 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 29 04:38:54.714704 kernel: ACPI: Early table checksum verification disabled Oct 29 04:38:54.714709 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 29 04:38:54.714714 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 29 04:38:54.714719 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:38:54.714726 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:38:54.714731 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:38:54.714736 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:38:54.714741 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:38:54.714747 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:38:54.714752 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:38:54.714759 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:38:54.714764 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:38:54.714769 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 29 04:38:54.714775 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 29 04:38:54.714780 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 04:38:54.714785 kernel: NUMA: NODE_DATA [mem 0xdc959900-0xdc95efff] Oct 29 04:38:54.714790 kernel: Zone ranges: Oct 29 04:38:54.714795 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 04:38:54.714800 kernel: DMA32 empty Oct 29 04:38:54.714806 kernel: Normal empty Oct 29 04:38:54.714811 kernel: Movable zone start for each node Oct 29 04:38:54.714817 kernel: Early memory node ranges Oct 29 04:38:54.714822 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 29 04:38:54.714827 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 29 04:38:54.714833 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 29 04:38:54.714838 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 29 04:38:54.714843 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 29 04:38:54.714848 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 29 04:38:54.714853 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 29 04:38:54.714859 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 29 04:38:54.714864 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 29 04:38:54.714869 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 29 04:38:54.714874 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 29 04:38:54.714880 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 04:38:54.714889 kernel: On node 0 totalpages: 643072 Oct 29 04:38:54.714895 kernel: DMA zone: 10048 pages used for memmap Oct 29 04:38:54.714900 kernel: DMA zone: 0 pages reserved Oct 29 04:38:54.714905 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 29 04:38:54.714911 kernel: psci: probing for conduit method from ACPI. Oct 29 04:38:54.714916 kernel: psci: PSCIv1.1 detected in firmware. Oct 29 04:38:54.714922 kernel: psci: Using standard PSCI v0.2 function IDs Oct 29 04:38:54.714928 kernel: psci: Trusted OS migration not required Oct 29 04:38:54.714934 kernel: psci: SMC Calling Convention v1.1 Oct 29 04:38:54.714940 kernel: ACPI: SRAT not present Oct 29 04:38:54.714945 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 29 04:38:54.714951 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 29 04:38:54.714956 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 29 04:38:54.714962 kernel: Detected PIPT I-cache on CPU0 Oct 29 04:38:54.714967 kernel: CPU features: detected: GIC system register CPU interface Oct 29 04:38:54.714973 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 29 04:38:54.714988 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 29 04:38:54.714994 kernel: CPU features: detected: Hardware dirty bit management Oct 29 04:38:54.715000 kernel: CPU features: detected: Spectre-v4 Oct 29 04:38:54.715007 kernel: CPU features: detected: ARM erratum 1418040 Oct 29 04:38:54.715013 kernel: CPU features: detected: Spectre-BHB Oct 29 04:38:54.715019 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 29 04:38:54.715024 kernel: Policy zone: DMA Oct 29 04:38:54.715031 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 29 04:38:54.715037 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 29 04:38:54.715042 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 29 04:38:54.715048 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 29 04:38:54.715054 kernel: Memory: 2452096K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120192K reserved, 0K cma-reserved) Oct 29 04:38:54.715060 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 29 04:38:54.715065 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 29 04:38:54.715072 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 29 04:38:54.715078 kernel: rcu: RCU event tracing is enabled. Oct 29 04:38:54.715084 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 29 04:38:54.715090 kernel: Trampoline variant of Tasks RCU enabled. Oct 29 04:38:54.715096 kernel: Tracing variant of Tasks RCU enabled. Oct 29 04:38:54.715101 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 29 04:38:54.715107 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 29 04:38:54.715113 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 29 04:38:54.715118 kernel: GICv3: 256 SPIs implemented Oct 29 04:38:54.715124 kernel: GICv3: 0 Extended SPIs implemented Oct 29 04:38:54.715130 kernel: GICv3: Distributor has no Range Selector support Oct 29 04:38:54.715135 kernel: GICv3: 16 PPIs implemented Oct 29 04:38:54.715142 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 29 04:38:54.715147 kernel: ACPI: SRAT not present Oct 29 04:38:54.715152 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 29 04:38:54.715158 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 29 04:38:54.715164 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 29 04:38:54.715170 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 29 04:38:54.715175 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 29 04:38:54.715181 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 04:38:54.715187 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 29 04:38:54.715192 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 29 04:38:54.715198 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 29 04:38:54.715204 kernel: arm-pv: using stolen time PV Oct 29 04:38:54.715211 kernel: Console: colour dummy device 80x25 Oct 29 04:38:54.715217 kernel: ACPI: Core revision 20200925 Oct 29 04:38:54.715222 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 29 04:38:54.715228 kernel: pid_max: default: 32768 minimum: 301 Oct 29 04:38:54.715234 kernel: LSM: Security Framework initializing Oct 29 04:38:54.715240 kernel: SELinux: Initializing. Oct 29 04:38:54.715246 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 04:38:54.715252 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 04:38:54.715258 kernel: rcu: Hierarchical SRCU implementation. Oct 29 04:38:54.715265 kernel: Platform MSI: ITS@0x8080000 domain created Oct 29 04:38:54.715271 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 29 04:38:54.715276 kernel: Remapping and enabling EFI services. Oct 29 04:38:54.715282 kernel: smp: Bringing up secondary CPUs ... Oct 29 04:38:54.715288 kernel: Detected PIPT I-cache on CPU1 Oct 29 04:38:54.715294 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 29 04:38:54.715300 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 29 04:38:54.715306 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 04:38:54.715311 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 29 04:38:54.715317 kernel: Detected PIPT I-cache on CPU2 Oct 29 04:38:54.715324 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 29 04:38:54.715330 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 29 04:38:54.715336 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 04:38:54.715342 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 29 04:38:54.715348 kernel: Detected PIPT I-cache on CPU3 Oct 29 04:38:54.715357 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 29 04:38:54.715363 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 29 04:38:54.715371 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 04:38:54.715376 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 29 04:38:54.715383 kernel: smp: Brought up 1 node, 4 CPUs Oct 29 04:38:54.715400 kernel: SMP: Total of 4 processors activated. Oct 29 04:38:54.715406 kernel: CPU features: detected: Privileged Access Never Oct 29 04:38:54.715412 kernel: CPU features: detected: LSE atomic instructions Oct 29 04:38:54.715418 kernel: CPU features: detected: User Access Override Oct 29 04:38:54.715424 kernel: CPU features: detected: 32-bit EL0 Support Oct 29 04:38:54.715430 kernel: CPU features: detected: Common not Private translations Oct 29 04:38:54.715436 kernel: CPU features: detected: RAS Extension Support Oct 29 04:38:54.715444 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 29 04:38:54.715450 kernel: CPU features: detected: CRC32 instructions Oct 29 04:38:54.715456 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 29 04:38:54.715463 kernel: CPU: All CPU(s) started at EL1 Oct 29 04:38:54.715469 kernel: alternatives: patching kernel code Oct 29 04:38:54.715476 kernel: devtmpfs: initialized Oct 29 04:38:54.715482 kernel: KASLR enabled Oct 29 04:38:54.715488 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 29 04:38:54.715494 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 29 04:38:54.715500 kernel: pinctrl core: initialized pinctrl subsystem Oct 29 04:38:54.715506 kernel: SMBIOS 3.0.0 present. Oct 29 04:38:54.715512 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 29 04:38:54.715518 kernel: NET: Registered protocol family 16 Oct 29 04:38:54.715524 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 29 04:38:54.715530 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 29 04:38:54.715537 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 29 04:38:54.715543 kernel: audit: initializing netlink subsys (disabled) Oct 29 04:38:54.715549 kernel: audit: type=2000 audit(0.032:1): state=initialized audit_enabled=0 res=1 Oct 29 04:38:54.715556 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 29 04:38:54.715562 kernel: cpuidle: using governor menu Oct 29 04:38:54.715568 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 29 04:38:54.715574 kernel: ASID allocator initialised with 32768 entries Oct 29 04:38:54.715580 kernel: ACPI: bus type PCI registered Oct 29 04:38:54.715586 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 29 04:38:54.715593 kernel: Serial: AMBA PL011 UART driver Oct 29 04:38:54.715599 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 29 04:38:54.715605 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 29 04:38:54.715611 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 29 04:38:54.715617 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 29 04:38:54.715623 kernel: cryptd: max_cpu_qlen set to 1000 Oct 29 04:38:54.715629 kernel: ACPI: Added _OSI(Module Device) Oct 29 04:38:54.715636 kernel: ACPI: Added _OSI(Processor Device) Oct 29 04:38:54.715642 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 29 04:38:54.715649 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 29 04:38:54.715655 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 29 04:38:54.715661 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 29 04:38:54.715667 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 29 04:38:54.715673 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 29 04:38:54.715679 kernel: ACPI: Interpreter enabled Oct 29 04:38:54.715685 kernel: ACPI: Using GIC for interrupt routing Oct 29 04:38:54.715691 kernel: ACPI: MCFG table detected, 1 entries Oct 29 04:38:54.715697 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 29 04:38:54.715703 kernel: printk: console [ttyAMA0] enabled Oct 29 04:38:54.715711 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 29 04:38:54.715841 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 29 04:38:54.715902 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 29 04:38:54.715956 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 29 04:38:54.716025 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 29 04:38:54.716081 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 29 04:38:54.716089 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 29 04:38:54.716097 kernel: PCI host bridge to bus 0000:00 Oct 29 04:38:54.716163 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 29 04:38:54.716216 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 29 04:38:54.716266 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 29 04:38:54.716315 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 29 04:38:54.716383 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 29 04:38:54.716481 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 29 04:38:54.716543 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 29 04:38:54.716601 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 29 04:38:54.716657 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 04:38:54.716712 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 04:38:54.716769 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 29 04:38:54.716828 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 29 04:38:54.716878 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 29 04:38:54.716928 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 29 04:38:54.716992 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 29 04:38:54.717002 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 29 04:38:54.717008 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 29 04:38:54.717014 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 29 04:38:54.717020 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 29 04:38:54.717026 kernel: vgaarb: loaded Oct 29 04:38:54.717032 kernel: Registered efivars operations Oct 29 04:38:54.717038 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 29 04:38:54.717047 kernel: VFS: Disk quotas dquot_6.6.0 Oct 29 04:38:54.717053 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 29 04:38:54.717059 kernel: pnp: PnP ACPI init Oct 29 04:38:54.717127 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 29 04:38:54.717180 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 29 04:38:54.717188 kernel: pnp: PnP ACPI: found 1 devices Oct 29 04:38:54.717194 kernel: NET: Registered protocol family 2 Oct 29 04:38:54.717200 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 29 04:38:54.717208 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 29 04:38:54.717215 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 29 04:38:54.717221 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 29 04:38:54.717227 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 29 04:38:54.717233 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 04:38:54.717239 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 04:38:54.717246 kernel: NET: Registered protocol family 1 Oct 29 04:38:54.717252 kernel: PCI: CLS 0 bytes, default 64 Oct 29 04:38:54.717258 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 29 04:38:54.717266 kernel: kvm [1]: HYP mode not available Oct 29 04:38:54.717272 kernel: Initialise system trusted keyrings Oct 29 04:38:54.717278 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 29 04:38:54.717285 kernel: Key type asymmetric registered Oct 29 04:38:54.717291 kernel: Asymmetric key parser 'x509' registered Oct 29 04:38:54.717297 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 29 04:38:54.717304 kernel: io scheduler mq-deadline registered Oct 29 04:38:54.717310 kernel: io scheduler kyber registered Oct 29 04:38:54.717316 kernel: io scheduler bfq registered Oct 29 04:38:54.717324 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 29 04:38:54.717330 kernel: ACPI: Power Button [PWRB] Oct 29 04:38:54.717337 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 29 04:38:54.717414 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 29 04:38:54.717423 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 29 04:38:54.717430 kernel: msm_serial: driver initialized Oct 29 04:38:54.717436 kernel: random: fast init done Oct 29 04:38:54.717443 kernel: random: crng init done Oct 29 04:38:54.717449 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 29 04:38:54.717456 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 29 04:38:54.717464 kernel: thunder_xcv, ver 1.0 Oct 29 04:38:54.717470 kernel: thunder_bgx, ver 1.0 Oct 29 04:38:54.717476 kernel: nicpf, ver 1.0 Oct 29 04:38:54.717482 kernel: nicvf, ver 1.0 Oct 29 04:38:54.717550 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 29 04:38:54.717603 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-29T04:38:54 UTC (1761712734) Oct 29 04:38:54.717611 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 29 04:38:54.717618 kernel: NET: Registered protocol family 10 Oct 29 04:38:54.717625 kernel: Segment Routing with IPv6 Oct 29 04:38:54.717632 kernel: NET: Registered protocol family 17 Oct 29 04:38:54.717638 kernel: Key type dns_resolver registered Oct 29 04:38:54.717644 kernel: registered taskstats version 1 Oct 29 04:38:54.717650 kernel: Loading compiled-in X.509 certificates Oct 29 04:38:54.717656 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 29 04:38:54.717662 kernel: Key type ._fscrypt registered Oct 29 04:38:54.717669 kernel: Key type .fscrypt registered Oct 29 04:38:54.717675 kernel: Key type fscrypt-provisioning registered Oct 29 04:38:54.717682 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 29 04:38:54.717688 kernel: ima: Allocated hash algorithm: sha1 Oct 29 04:38:54.717694 kernel: ima: No architecture policies found Oct 29 04:38:54.717700 kernel: Freeing unused kernel memory: 34240K Oct 29 04:38:54.717707 kernel: Run /init as init process Oct 29 04:38:54.717713 kernel: with arguments: Oct 29 04:38:54.717718 kernel: /init Oct 29 04:38:54.717725 kernel: with environment: Oct 29 04:38:54.717730 kernel: HOME=/ Oct 29 04:38:54.717736 kernel: TERM=linux Oct 29 04:38:54.717744 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 29 04:38:54.717752 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 29 04:38:54.717760 systemd[1]: Detected virtualization kvm. Oct 29 04:38:54.717767 systemd[1]: Detected architecture arm64. Oct 29 04:38:54.717774 systemd[1]: Running in initial RAM disk. Oct 29 04:38:54.717780 systemd[1]: No hostname configured, using default hostname. Oct 29 04:38:54.717786 systemd[1]: Hostname set to . Oct 29 04:38:54.717795 systemd[1]: Initializing machine ID from VM UUID. Oct 29 04:38:54.717801 systemd[1]: Queued start job for default target Initrd Default Target. Oct 29 04:38:54.717808 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 04:38:54.717815 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 04:38:54.717821 systemd[1]: Reached target Path Units. Oct 29 04:38:54.717828 systemd[1]: Reached target Slice Units. Oct 29 04:38:54.717834 systemd[1]: Reached target Swaps. Oct 29 04:38:54.717841 systemd[1]: Reached target Timer Units. Oct 29 04:38:54.717849 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 29 04:38:54.717856 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 29 04:38:54.717862 systemd[1]: Listening on Journal Audit Socket. Oct 29 04:38:54.717869 systemd[1]: Listening on Journal Socket (/dev/log). Oct 29 04:38:54.717875 systemd[1]: Listening on Journal Socket. Oct 29 04:38:54.717882 systemd[1]: Listening on Network Service Netlink Socket. Oct 29 04:38:54.717889 systemd[1]: Listening on udev Control Socket. Oct 29 04:38:54.717895 systemd[1]: Listening on udev Kernel Socket. Oct 29 04:38:54.717902 systemd[1]: Reached target Socket Units. Oct 29 04:38:54.717910 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 04:38:54.717916 systemd[1]: Finished Network Cleanup. Oct 29 04:38:54.717923 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 29 04:38:54.717930 systemd[1]: Starting Journal Service... Oct 29 04:38:54.717936 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 04:38:54.717943 systemd[1]: Starting Apply Kernel Variables... Oct 29 04:38:54.717950 systemd[1]: Starting Setup Virtual Console... Oct 29 04:38:54.717957 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 04:38:54.717963 systemd[1]: Finished Setup Virtual Console. Oct 29 04:38:54.717970 systemd[1]: Finished Apply Kernel Variables. Oct 29 04:38:54.717985 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 29 04:38:54.717999 systemd-journald[279]: Journal started Oct 29 04:38:54.718039 systemd-journald[279]: Runtime Journal (/run/log/journal/24c2f1393fab4f32a2d1741936dad9bf) is 6.0M, max 48.5M, 42.4M free. Oct 29 04:38:54.721755 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 04:38:54.723408 systemd[1]: Started Journal Service. Oct 29 04:38:54.723761 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 04:38:54.737272 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 29 04:38:54.739213 systemd[1]: Starting dracut cmdline hook... Oct 29 04:38:54.748599 dracut-cmdline[297]: dracut-dracut-053 Oct 29 04:38:54.751080 dracut-cmdline[297]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 29 04:38:54.815027 kernel: SCSI subsystem initialized Oct 29 04:38:54.816408 kernel: Loading iSCSI transport class v2.0-870. Oct 29 04:38:54.822411 kernel: iscsi: registered transport (tcp) Oct 29 04:38:54.834409 kernel: iscsi: registered transport (qla4xxx) Oct 29 04:38:54.834424 kernel: QLogic iSCSI HBA Driver Oct 29 04:38:54.844149 systemd[1]: Finished dracut cmdline hook. Oct 29 04:38:54.845790 systemd[1]: Starting dracut pre-udev hook... Oct 29 04:38:54.859288 kernel: device-mapper: uevent: version 1.0.3 Oct 29 04:38:54.859322 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 29 04:38:54.894424 kernel: raid6: neonx8 gen() 13729 MB/s Oct 29 04:38:54.913995 kernel: raid6: neonx8 xor() 10743 MB/s Oct 29 04:38:54.928448 kernel: raid6: neonx4 gen() 13441 MB/s Oct 29 04:38:54.945417 kernel: raid6: neonx4 xor() 11515 MB/s Oct 29 04:38:54.962415 kernel: raid6: neonx2 gen() 13000 MB/s Oct 29 04:38:54.979415 kernel: raid6: neonx2 xor() 9876 MB/s Oct 29 04:38:54.996416 kernel: raid6: neonx1 gen() 10869 MB/s Oct 29 04:38:55.013413 kernel: raid6: neonx1 xor() 8835 MB/s Oct 29 04:38:55.030418 kernel: raid6: int64x8 gen() 6751 MB/s Oct 29 04:38:55.047414 kernel: raid6: int64x8 xor() 3774 MB/s Oct 29 04:38:55.064413 kernel: raid6: int64x4 gen() 6911 MB/s Oct 29 04:38:55.081413 kernel: raid6: int64x4 xor() 3833 MB/s Oct 29 04:38:55.098415 kernel: raid6: int64x2 gen() 6159 MB/s Oct 29 04:38:55.115417 kernel: raid6: int64x2 xor() 3315 MB/s Oct 29 04:38:55.132429 kernel: raid6: int64x1 gen() 4965 MB/s Oct 29 04:38:55.149624 kernel: raid6: int64x1 xor() 2589 MB/s Oct 29 04:38:55.149635 kernel: raid6: using algorithm neonx8 gen() 13729 MB/s Oct 29 04:38:55.149643 kernel: raid6: .... xor() 10743 MB/s, rmw enabled Oct 29 04:38:55.150808 kernel: raid6: using neon recovery algorithm Oct 29 04:38:55.160416 kernel: xor: measuring software checksum speed Oct 29 04:38:55.162479 kernel: 8regs : 17148 MB/sec Oct 29 04:38:55.162497 kernel: 32regs : 20765 MB/sec Oct 29 04:38:55.164149 kernel: arm64_neon : 29111 MB/sec Oct 29 04:38:55.164161 kernel: xor: using function: arm64_neon (29111 MB/sec) Oct 29 04:38:55.210418 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 29 04:38:55.220252 systemd[1]: Finished dracut pre-udev hook. Oct 29 04:38:55.221000 audit: BPF prog-id=6 op=LOAD Oct 29 04:38:55.222231 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 04:38:55.225415 kernel: audit: type=1334 audit(1761712735.221:2): prog-id=6 op=LOAD Oct 29 04:38:55.225436 kernel: audit: type=1334 audit(1761712735.221:3): prog-id=7 op=LOAD Oct 29 04:38:55.225444 kernel: audit: type=1334 audit(1761712735.221:4): prog-id=8 op=LOAD Oct 29 04:38:55.221000 audit: BPF prog-id=7 op=LOAD Oct 29 04:38:55.221000 audit: BPF prog-id=8 op=LOAD Oct 29 04:38:55.236122 systemd-udevd[417]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 04:38:55.237861 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 04:38:55.239784 systemd[1]: Starting dracut pre-trigger hook... Oct 29 04:38:55.240000 audit: BPF prog-id=9 op=LOAD Oct 29 04:38:55.242185 systemd[1]: Starting Network Configuration... Oct 29 04:38:55.244040 kernel: audit: type=1334 audit(1761712735.240:5): prog-id=9 op=LOAD Oct 29 04:38:55.254314 dracut-pre-trigger[424]: rd.md=0: removing MD RAID activation Oct 29 04:38:55.259710 systemd-networkd[431]: lo: Link UP Oct 29 04:38:55.259717 systemd-networkd[431]: lo: Gained carrier Oct 29 04:38:55.260075 systemd-networkd[431]: Enumeration completed Oct 29 04:38:55.261000 audit: BPF prog-id=10 op=LOAD Oct 29 04:38:55.260154 systemd[1]: Started Network Configuration. Oct 29 04:38:55.265608 kernel: audit: type=1334 audit(1761712735.261:6): prog-id=10 op=LOAD Oct 29 04:38:55.262080 systemd[1]: Starting Network Name Resolution... Oct 29 04:38:55.283206 systemd[1]: Finished dracut pre-trigger hook. Oct 29 04:38:55.284803 systemd[1]: Starting Coldplug All udev Devices... Oct 29 04:38:55.294216 systemd-resolved[449]: Positive Trust Anchors: Oct 29 04:38:55.294234 systemd-resolved[449]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 04:38:55.294263 systemd-resolved[449]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 29 04:38:55.294640 systemd-resolved[449]: Defaulting to hostname 'linux'. Oct 29 04:38:55.295384 systemd[1]: Started Network Name Resolution. Oct 29 04:38:55.295443 systemd-udevd[417]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 04:38:55.303725 systemd[1]: Reached target Network. Oct 29 04:38:55.305042 systemd[1]: Reached target Host and Network Name Lookups. Oct 29 04:38:55.308967 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 29 04:38:55.314753 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 29 04:38:55.316366 systemd[1]: Starting Open-iSCSI... Oct 29 04:38:55.318945 iscsid[489]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 29 04:38:55.318945 iscsid[489]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 29 04:38:55.318945 iscsid[489]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 29 04:38:55.318945 iscsid[489]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 29 04:38:55.318945 iscsid[489]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 29 04:38:55.331871 iscsid[489]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 29 04:38:55.322546 systemd[1]: Started Open-iSCSI. Oct 29 04:38:55.326896 systemd[1]: Finished Coldplug All udev Devices. Oct 29 04:38:55.329257 systemd[1]: Starting dracut initqueue hook... Oct 29 04:38:55.352420 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 29 04:38:55.352584 kernel: vda: detected capacity change from 0 to 4756340736 Oct 29 04:38:55.364921 systemd-udevd[468]: Using default interface naming scheme 'v249'. Oct 29 04:38:55.368426 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 04:38:55.371130 systemd-networkd[431]: eth0: Link UP Oct 29 04:38:55.393415 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (467) Oct 29 04:38:55.394384 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 29 04:38:55.398677 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 29 04:38:55.402593 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 29 04:38:55.403908 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 29 04:38:55.406663 systemd[1]: Finished dracut initqueue hook. Oct 29 04:38:55.410340 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 29 04:38:55.411413 systemd[1]: Reached target Initrd Root Device. Oct 29 04:38:55.413064 systemd[1]: Reached target Preparation for Remote File Systems. Oct 29 04:38:55.414837 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 04:38:55.416612 systemd[1]: Reached target Remote File Systems. Oct 29 04:38:55.419090 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 29 04:38:55.420946 systemd[1]: Starting dracut pre-mount hook... Oct 29 04:38:55.431408 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 04:38:55.432229 systemd[1]: Finished dracut pre-mount hook. Oct 29 04:38:56.415431 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 29 04:38:56.415643 systemd-networkd[431]: eth0: Gained carrier Oct 29 04:38:56.427601 systemd-networkd[431]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Oct 29 04:38:56.441354 disk-uuid[512]: The operation has completed successfully. Oct 29 04:38:56.442424 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 04:38:56.464433 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 29 04:38:56.464555 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 29 04:38:56.467154 systemd[1]: Starting Ignition (setup)... Oct 29 04:38:56.474897 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 29 04:38:56.474931 kernel: BTRFS info (device vda6): has skinny extents Oct 29 04:38:56.480686 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 29 04:38:56.485805 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 29 04:38:56.485914 systemd[1]: Finished Ignition (setup). Oct 29 04:38:56.488059 systemd[1]: Starting Ignition (disks)... Oct 29 04:38:56.497643 ignition[546]: Ignition v0.36.1-15-gde4e6cc9 Oct 29 04:38:56.497657 ignition[546]: Stage: disks Oct 29 04:38:56.497667 ignition[546]: reading system config file "/usr/lib/ignition/base.ign" Oct 29 04:38:56.497676 ignition[546]: no config at "/usr/lib/ignition/base.ign" Oct 29 04:38:56.497717 ignition[546]: parsed url from cmdline: "" Oct 29 04:38:56.497721 ignition[546]: no config URL provided Oct 29 04:38:56.497725 ignition[546]: reading system config file "/usr/lib/ignition/user.ign" Oct 29 04:38:56.497732 ignition[546]: no config at "/usr/lib/ignition/user.ign" Oct 29 04:38:56.497750 ignition[546]: op(1): [started] loading QEMU firmware config module Oct 29 04:38:56.497755 ignition[546]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 29 04:38:56.498558 ignition[546]: op(1): [finished] loading QEMU firmware config module Oct 29 04:38:56.512231 ignition[546]: parsing config with SHA512: de63e50686169f9ee2a79309d0d01c64f157de61fdf0cc5d7964177f25ee2ca37fd41f6abeba934587a8971a08057bd0c534e5ee7cce85f1a5136db4f1d0a3d9 Oct 29 04:38:56.514166 ignition[546]: disks: disks passed Oct 29 04:38:56.514180 ignition[546]: Ignition finished successfully Oct 29 04:38:56.514783 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 29 04:38:56.514896 systemd[1]: Finished Ignition (disks). Oct 29 04:38:56.515733 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 04:38:56.517877 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 29 04:38:56.519702 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 29 04:38:56.528946 systemd-fsck[559]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 29 04:38:56.531412 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 29 04:38:56.532607 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 29 04:38:56.534849 systemd[1]: Mounting /sysroot... Oct 29 04:38:56.550998 systemd[1]: Found device /dev/mapper/usr. Oct 29 04:38:56.552930 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 29 04:38:56.555692 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 29 04:38:56.562229 systemd-fsck[573]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 29 04:38:56.562229 systemd-fsck[573]: You must have r/w access to the filesystem or be root Oct 29 04:38:56.563072 systemd-fsck[570]: fsck failed with exit status 8. Oct 29 04:38:56.563075 systemd-fsck[570]: Ignoring error. Oct 29 04:38:56.564658 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 29 04:38:56.566314 systemd[1]: Mounting /sysusr/usr... Oct 29 04:38:56.578414 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 29 04:38:56.578453 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 29 04:38:56.579921 systemd[1]: Mounted /sysroot. Oct 29 04:38:56.580676 systemd[1]: Reached target Initrd Root File System. Oct 29 04:38:56.596404 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 29 04:38:56.596909 systemd[1]: Mounted /sysusr/usr. Oct 29 04:38:56.597709 systemd[1]: Reached target Local File Systems. Oct 29 04:38:56.598979 systemd[1]: Reached target System Initialization. Oct 29 04:38:56.600442 systemd[1]: Reached target Basic System. Oct 29 04:38:56.602853 systemd[1]: Mounting /sysroot/usr... Oct 29 04:38:56.605033 systemd[1]: Mounted /sysroot/usr. Oct 29 04:38:56.606383 systemd[1]: Starting Root filesystem setup... Oct 29 04:38:56.631147 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 29 04:38:56.631285 systemd[1]: Finished Root filesystem setup. Oct 29 04:38:56.633795 systemd[1]: Starting Ignition (files)... Oct 29 04:38:56.635010 systemd[1]: Starting /sysroot/boot... Oct 29 04:38:56.637158 ignition[593]: Ignition v0.36.1-15-gde4e6cc9 Oct 29 04:38:56.637170 ignition[593]: Stage: files Oct 29 04:38:56.637180 ignition[593]: reading system config file "/usr/lib/ignition/base.ign" Oct 29 04:38:56.637211 ignition[593]: no config at "/usr/lib/ignition/base.ign" Oct 29 04:38:56.637985 ignition[593]: files: compiled without relabeling support, skipping Oct 29 04:38:56.649741 systemd[1]: Finished /sysroot/boot. Oct 29 04:38:56.654579 ignition[593]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 29 04:38:56.654591 ignition[593]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 29 04:38:56.655806 ignition[593]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 29 04:38:56.655812 ignition[593]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 29 04:38:56.656973 ignition[593]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 29 04:38:56.659452 systemd[1]: ignition-files.service: Deactivated successfully. Oct 29 04:38:56.656994 ignition[593]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 29 04:38:56.659550 systemd[1]: Finished Ignition (files). Oct 29 04:38:56.657313 ignition[593]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 29 04:38:56.661273 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 29 04:38:56.657320 ignition[593]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 29 04:38:56.669000 audit: BPF prog-id=3 op=UNLOAD Oct 29 04:38:56.661923 systemd[1]: Starting Ignition (record completion)... Oct 29 04:38:56.672486 kernel: audit: type=1334 audit(1761712736.669:7): prog-id=3 op=UNLOAD Oct 29 04:38:56.657434 ignition[593]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 29 04:38:56.663407 systemd[1]: Starting Reload Configuration from the Real Root... Oct 29 04:38:56.657859 ignition[593]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 29 04:38:56.678607 kernel: audit: type=1334 audit(1761712736.676:8): prog-id=10 op=UNLOAD Oct 29 04:38:56.678629 kernel: audit: type=1334 audit(1761712736.677:9): prog-id=9 op=UNLOAD Oct 29 04:38:56.678638 kernel: audit: type=1334 audit(1761712736.677:10): prog-id=6 op=UNLOAD Oct 29 04:38:56.676000 audit: BPF prog-id=10 op=UNLOAD Oct 29 04:38:56.677000 audit: BPF prog-id=9 op=UNLOAD Oct 29 04:38:56.677000 audit: BPF prog-id=6 op=UNLOAD Oct 29 04:38:56.665476 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 29 04:38:56.657865 ignition[593]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 29 04:38:56.665582 systemd[1]: Finished Ignition (record completion). Oct 29 04:38:56.657870 ignition[593]: files: files passed Oct 29 04:38:56.667976 systemd[1]: Reloading. Oct 29 04:38:56.657873 ignition[593]: Ignition finished successfully Oct 29 04:38:56.758000 audit: BPF prog-id=11 op=LOAD Oct 29 04:38:56.758000 audit: BPF prog-id=12 op=LOAD Oct 29 04:38:56.758000 audit: BPF prog-id=13 op=LOAD Oct 29 04:38:56.758000 audit: BPF prog-id=4 op=UNLOAD Oct 29 04:38:56.758000 audit: BPF prog-id=5 op=UNLOAD Oct 29 04:38:56.759000 audit: BPF prog-id=14 op=LOAD Oct 29 04:38:56.759000 audit: BPF prog-id=15 op=LOAD Oct 29 04:38:56.759000 audit: BPF prog-id=16 op=LOAD Oct 29 04:38:56.759000 audit: BPF prog-id=17 op=LOAD Oct 29 04:38:56.759000 audit: BPF prog-id=18 op=LOAD Oct 29 04:38:56.759000 audit: BPF prog-id=7 op=UNLOAD Oct 29 04:38:56.759000 audit: BPF prog-id=8 op=UNLOAD Oct 29 04:38:56.774367 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 29 04:38:56.774508 systemd[1]: Finished Reload Configuration from the Real Root. Oct 29 04:38:56.776500 systemd[1]: Reached target Initrd File Systems. Oct 29 04:38:56.778303 systemd[1]: Reached target Initrd Default Target. Oct 29 04:38:56.780103 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 29 04:38:56.780842 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 29 04:38:56.791102 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 29 04:38:56.792824 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 29 04:38:56.799009 systemd[1]: Stopped target Host and Network Name Lookups. Oct 29 04:38:56.800141 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 29 04:38:56.801956 systemd[1]: Stopped target Timer Units. Oct 29 04:38:56.803498 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 29 04:38:56.803617 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 29 04:38:56.805180 systemd[1]: Stopped target Initrd Default Target. Oct 29 04:38:56.806827 systemd[1]: Stopped target Basic System. Oct 29 04:38:56.808261 systemd[1]: Stopped target Initrd Root Device. Oct 29 04:38:56.809844 systemd[1]: Stopped target Path Units. Oct 29 04:38:56.811228 systemd[1]: Stopped target Remote File Systems. Oct 29 04:38:56.812890 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 29 04:38:56.814518 systemd[1]: Stopped target Slice Units. Oct 29 04:38:56.816034 systemd[1]: Stopped target Socket Units. Oct 29 04:38:56.817768 systemd[1]: Stopped target System Initialization. Oct 29 04:38:56.819299 systemd[1]: Stopped target Local File Systems. Oct 29 04:38:56.820858 systemd[1]: Stopped target Preparation for Local File Systems. Oct 29 04:38:56.822521 systemd[1]: Stopped target Swaps. Oct 29 04:38:56.823936 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 29 04:38:56.824064 systemd[1]: Stopped dracut pre-mount hook. Oct 29 04:38:56.825663 systemd[1]: Stopped target Local Encrypted Volumes. Oct 29 04:38:56.826934 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 04:38:56.832429 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 29 04:38:56.833646 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 29 04:38:56.833751 systemd[1]: Stopped dracut initqueue hook. Oct 29 04:38:56.837501 iscsid[489]: iscsid shutting down. Oct 29 04:38:56.835521 systemd[1]: Stopping Open-iSCSI... Oct 29 04:38:56.837562 systemd[1]: Stopping /sysroot/boot... Oct 29 04:38:56.838205 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 29 04:38:56.838340 systemd[1]: Stopped Coldplug All udev Devices. Oct 29 04:38:56.839689 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 29 04:38:56.839774 systemd[1]: Stopped dracut pre-trigger hook. Oct 29 04:38:56.842218 systemd[1]: iscsid.service: Deactivated successfully. Oct 29 04:38:56.842342 systemd[1]: Stopped Open-iSCSI. Oct 29 04:38:56.844363 systemd[1]: iscsid.socket: Deactivated successfully. Oct 29 04:38:56.844442 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 29 04:38:56.845706 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 29 04:38:56.847759 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 29 04:38:56.847874 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 29 04:38:56.849511 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 29 04:38:56.849614 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 29 04:38:56.851467 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 29 04:38:56.851580 systemd[1]: Stopped /sysroot/boot. Oct 29 04:38:56.853142 systemd[1]: Stopped target Network. Oct 29 04:38:56.853995 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 29 04:38:56.854063 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 29 04:38:56.855518 systemd[1]: Stopping Network Name Resolution... Oct 29 04:38:56.861357 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 29 04:38:56.861591 systemd[1]: Stopped Network Name Resolution. Oct 29 04:38:56.868000 audit: BPF prog-id=14 op=UNLOAD Oct 29 04:38:56.863211 systemd[1]: Stopping Network Configuration... Oct 29 04:38:56.868594 systemd-networkd[431]: eth0: DHCP lease lost Oct 29 04:38:56.876500 systemd-networkd[431]: eth0: DHCPv6 lease lost Oct 29 04:38:56.877619 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 29 04:38:56.877748 systemd[1]: Stopped Network Configuration. Oct 29 04:38:56.879574 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 29 04:38:56.879606 systemd[1]: Closed Network Service Netlink Socket. Oct 29 04:38:56.881651 systemd[1]: Stopping Network Cleanup... Oct 29 04:38:56.885000 audit: BPF prog-id=15 op=UNLOAD Oct 29 04:38:56.882523 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 04:38:56.882592 systemd[1]: Stopped Apply Kernel Variables. Oct 29 04:38:56.884203 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 29 04:38:56.889311 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 29 04:38:56.889465 systemd[1]: Stopped Network Cleanup. Oct 29 04:38:56.893751 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 29 04:38:56.893891 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 29 04:38:56.895973 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 29 04:38:56.896018 systemd[1]: Closed udev Control Socket. Oct 29 04:38:56.897507 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 29 04:38:56.897542 systemd[1]: Closed udev Kernel Socket. Oct 29 04:38:56.899153 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 29 04:38:56.899197 systemd[1]: Stopped dracut pre-udev hook. Oct 29 04:38:56.905000 audit: BPF prog-id=16 op=UNLOAD Oct 29 04:38:56.900554 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 29 04:38:56.900596 systemd[1]: Stopped dracut cmdline hook. Oct 29 04:38:56.902223 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 29 04:38:56.902267 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 29 04:38:56.904651 systemd[1]: Starting Cleanup udev Database... Oct 29 04:38:56.905758 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 29 04:38:56.907259 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 04:38:56.907316 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 29 04:38:56.909155 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 29 04:38:56.909197 systemd[1]: Stopped Create List of Static Device Nodes. Oct 29 04:38:56.910758 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 04:38:56.910806 systemd[1]: Stopped Setup Virtual Console. Oct 29 04:38:56.923000 audit: BPF prog-id=11 op=UNLOAD Oct 29 04:38:56.912638 systemd[1]: rngd.service: Deactivated successfully. Oct 29 04:38:56.912763 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 29 04:38:56.914644 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 29 04:38:56.914754 systemd[1]: Finished Cleanup udev Database. Oct 29 04:38:56.916111 systemd[1]: Reached target Switch Root. Oct 29 04:38:56.918340 systemd[1]: Starting Switch Root... Oct 29 04:38:56.923764 systemd[1]: Switching root. Oct 29 04:38:56.940726 systemd-journald[279]: Journal stopped Oct 29 04:38:59.103899 systemd-journald[279]: Received SIGTERM from PID 1 (systemd). Oct 29 04:38:59.103964 kernel: SELinux: policy capability network_peer_controls=1 Oct 29 04:38:59.103980 kernel: SELinux: policy capability open_perms=1 Oct 29 04:38:59.103991 kernel: SELinux: policy capability extended_socket_class=1 Oct 29 04:38:59.104001 kernel: SELinux: policy capability always_check_network=0 Oct 29 04:38:59.104010 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 29 04:38:59.104019 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 29 04:38:59.104029 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 29 04:38:59.104040 systemd[1]: Successfully loaded SELinux policy in 34.119ms. Oct 29 04:38:59.104057 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.599ms. Oct 29 04:38:59.104070 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 29 04:38:59.104080 systemd[1]: Detected virtualization kvm. Oct 29 04:38:59.104089 systemd[1]: Detected architecture arm64. Oct 29 04:38:59.104100 systemd[1]: Detected first boot. Oct 29 04:38:59.104110 systemd[1]: Initializing machine ID from VM UUID. Oct 29 04:38:59.104120 systemd[1]: Populated /etc with preset unit settings. Oct 29 04:38:59.104130 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 29 04:38:59.104141 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 29 04:38:59.104152 kernel: kauditd_printk_skb: 24 callbacks suppressed Oct 29 04:38:59.104162 kernel: audit: type=1334 audit(1761712738.976:35): prog-id=21 op=LOAD Oct 29 04:38:59.104171 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 29 04:38:59.104181 kernel: audit: type=1334 audit(1761712738.976:36): prog-id=22 op=LOAD Oct 29 04:38:59.104191 systemd[1]: Stopped Switch Root. Oct 29 04:38:59.104200 kernel: audit: type=1334 audit(1761712738.976:37): prog-id=23 op=LOAD Oct 29 04:38:59.104210 kernel: audit: type=1334 audit(1761712738.976:38): prog-id=12 op=UNLOAD Oct 29 04:38:59.104218 kernel: audit: type=1334 audit(1761712738.976:39): prog-id=13 op=UNLOAD Oct 29 04:38:59.104230 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 29 04:38:59.104240 kernel: audit: type=1334 audit(1761712738.985:40): prog-id=21 op=UNLOAD Oct 29 04:38:59.104249 systemd[1]: Created slice Slice /system/addon-config. Oct 29 04:38:59.104259 systemd[1]: Created slice Slice /system/addon-run. Oct 29 04:38:59.104270 systemd[1]: Created slice Slice /system/getty. Oct 29 04:38:59.104280 systemd[1]: Created slice Slice /system/modprobe. Oct 29 04:38:59.104289 systemd[1]: Created slice Slice /system/serial-getty. Oct 29 04:38:59.104299 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 29 04:38:59.104309 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 29 04:38:59.104318 systemd[1]: Created slice User and Session Slice. Oct 29 04:38:59.104329 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 04:38:59.104339 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 29 04:38:59.104349 systemd[1]: Set up automount Boot partition Automount Point. Oct 29 04:38:59.104359 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 29 04:38:59.104372 systemd[1]: Stopped target Switch Root. Oct 29 04:38:59.104381 systemd[1]: Stopped target Initrd File Systems. Oct 29 04:38:59.104402 systemd[1]: Stopped target Initrd Root File System. Oct 29 04:38:59.104414 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 04:38:59.104424 systemd[1]: Reached target Remote File Systems. Oct 29 04:38:59.104435 systemd[1]: Reached target Slice Units. Oct 29 04:38:59.104460 systemd[1]: Reached target Swaps. Oct 29 04:38:59.104471 systemd[1]: Reached target Verify torcx succeeded. Oct 29 04:38:59.104481 systemd[1]: Reached target Local Verity Protected Volumes. Oct 29 04:38:59.104491 systemd[1]: Listening on Process Core Dump Socket. Oct 29 04:38:59.104501 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 29 04:38:59.104510 systemd[1]: Listening on Network Service Netlink Socket. Oct 29 04:38:59.104520 systemd[1]: Listening on udev Control Socket. Oct 29 04:38:59.104530 systemd[1]: Listening on udev Kernel Socket. Oct 29 04:38:59.104540 systemd[1]: Mounting Huge Pages File System... Oct 29 04:38:59.104551 systemd[1]: Mounting POSIX Message Queue File System... Oct 29 04:38:59.104561 systemd[1]: Mounting External Media Directory... Oct 29 04:38:59.104570 systemd[1]: Mounting Kernel Debug File System... Oct 29 04:38:59.104580 systemd[1]: Mounting Kernel Trace File System... Oct 29 04:38:59.104622 systemd[1]: Mounting Temporary Directory /tmp... Oct 29 04:38:59.104634 systemd[1]: Starting Create missing system files... Oct 29 04:38:59.104643 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 04:38:59.104653 systemd[1]: Starting Load Kernel Module configfs... Oct 29 04:38:59.104662 systemd[1]: Starting Load Kernel Module drm... Oct 29 04:38:59.104674 systemd[1]: Starting Load Kernel Module fuse... Oct 29 04:38:59.104683 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 29 04:38:59.104693 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 29 04:38:59.104703 systemd[1]: Stopped File System Check on Root Device. Oct 29 04:38:59.104712 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 29 04:38:59.104722 systemd[1]: Stopped systemd-fsck-usr.service. Oct 29 04:38:59.104731 kernel: fuse: init (API version 7.32) Oct 29 04:38:59.104740 systemd[1]: Stopped Journal Service. Oct 29 04:38:59.104750 kernel: audit: type=1334 audit(1761712739.086:41): prog-id=24 op=LOAD Oct 29 04:38:59.104758 kernel: audit: type=1334 audit(1761712739.087:42): prog-id=25 op=LOAD Oct 29 04:38:59.104769 kernel: audit: type=1334 audit(1761712739.088:43): prog-id=26 op=LOAD Oct 29 04:38:59.104779 systemd[1]: Starting Journal Service... Oct 29 04:38:59.104789 kernel: audit: type=1334 audit(1761712739.088:44): prog-id=22 op=UNLOAD Oct 29 04:38:59.104798 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 04:38:59.104807 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 29 04:38:59.104817 systemd[1]: Starting Apply Kernel Variables... Oct 29 04:38:59.104827 systemd[1]: Starting Coldplug All udev Devices... Oct 29 04:38:59.104837 systemd[1]: verity-setup.service: Deactivated successfully. Oct 29 04:38:59.104847 systemd[1]: Stopped verity-setup.service. Oct 29 04:38:59.104858 systemd-journald[809]: Journal started Oct 29 04:38:59.104895 systemd-journald[809]: Runtime Journal (/run/log/journal/24c2f1393fab4f32a2d1741936dad9bf) is 6.0M, max 48.5M, 42.4M free. Oct 29 04:38:57.008000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 29 04:38:57.042000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 29 04:38:57.042000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 29 04:38:57.042000 audit: BPF prog-id=19 op=LOAD Oct 29 04:38:57.042000 audit: BPF prog-id=19 op=UNLOAD Oct 29 04:38:57.042000 audit: BPF prog-id=20 op=LOAD Oct 29 04:38:57.042000 audit: BPF prog-id=20 op=UNLOAD Oct 29 04:38:57.071000 audit[759]: AVC avc: denied { associate } for pid=759 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 29 04:38:58.976000 audit: BPF prog-id=21 op=LOAD Oct 29 04:38:58.976000 audit: BPF prog-id=22 op=LOAD Oct 29 04:38:58.976000 audit: BPF prog-id=23 op=LOAD Oct 29 04:38:58.976000 audit: BPF prog-id=12 op=UNLOAD Oct 29 04:38:58.976000 audit: BPF prog-id=13 op=UNLOAD Oct 29 04:38:58.985000 audit: BPF prog-id=21 op=UNLOAD Oct 29 04:38:59.086000 audit: BPF prog-id=24 op=LOAD Oct 29 04:38:59.087000 audit: BPF prog-id=25 op=LOAD Oct 29 04:38:59.088000 audit: BPF prog-id=26 op=LOAD Oct 29 04:38:59.088000 audit: BPF prog-id=22 op=UNLOAD Oct 29 04:38:59.088000 audit: BPF prog-id=23 op=UNLOAD Oct 29 04:38:59.102000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 29 04:38:58.963642 systemd[1]: Queued start job for default target Multi-User System. Oct 29 04:38:57.070450 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 29 04:38:58.977497 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 29 04:38:57.070720 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 04:38:57.070738 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 04:38:57.070849 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 29 04:38:57.070860 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 29 04:38:57.070890 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 29 04:38:57.070903 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 29 04:38:57.071114 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 29 04:38:57.071148 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 04:38:57.071167 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 04:38:57.071624 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 29 04:38:57.071657 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 29 04:38:57.071679 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 29 04:38:57.071695 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 29 04:38:57.071713 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 29 04:38:57.071728 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:57Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 29 04:38:58.778401 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:58Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 04:38:59.106811 systemd[1]: Started Journal Service. Oct 29 04:38:58.778685 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:58Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 04:38:58.778789 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:58Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 04:38:58.778916 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:58Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 04:38:58.778982 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:58Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 29 04:38:58.779043 /usr/lib/systemd/system-generators/torcx-generator[759]: time="2025-10-29T04:38:58Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 29 04:38:59.107365 systemd[1]: Mounted Huge Pages File System. Oct 29 04:38:59.108293 systemd[1]: Mounted POSIX Message Queue File System. Oct 29 04:38:59.109342 systemd[1]: Mounted External Media Directory. Oct 29 04:38:59.110321 systemd[1]: Mounted Kernel Debug File System. Oct 29 04:38:59.111293 systemd[1]: Mounted Kernel Trace File System. Oct 29 04:38:59.112262 systemd[1]: Mounted Temporary Directory /tmp. Oct 29 04:38:59.113515 systemd[1]: Finished Create missing system files. Oct 29 04:38:59.114646 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 04:38:59.115861 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 29 04:38:59.116054 systemd[1]: Finished Load Kernel Module configfs. Oct 29 04:38:59.117106 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 04:38:59.117290 systemd[1]: Finished Load Kernel Module drm. Oct 29 04:38:59.118349 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 29 04:38:59.118563 systemd[1]: Finished Load Kernel Module fuse. Oct 29 04:38:59.128824 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 29 04:38:59.130062 systemd[1]: Finished Apply Kernel Variables. Oct 29 04:38:59.132935 systemd[1]: Mounting FUSE Control File System... Oct 29 04:38:59.134742 systemd[1]: Mounting Kernel Configuration File System... Oct 29 04:38:59.135593 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 29 04:38:59.137239 systemd[1]: Starting Rebuild Hardware Database... Oct 29 04:38:59.139253 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 29 04:38:59.140306 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 29 04:38:59.143180 systemd-journald[809]: Time spent on flushing to /var/log/journal/24c2f1393fab4f32a2d1741936dad9bf is 8.020ms for 770 entries. Oct 29 04:38:59.143180 systemd-journald[809]: System Journal (/var/log/journal/24c2f1393fab4f32a2d1741936dad9bf) is 8.0M, max 203.0M, 195.0M free. Oct 29 04:38:59.141740 systemd[1]: Starting Load/Save Random Seed... Oct 29 04:38:59.144054 systemd[1]: Starting Create System Users... Oct 29 04:38:59.148144 systemd[1]: Mounted FUSE Control File System. Oct 29 04:38:59.149433 systemd[1]: Mounted Kernel Configuration File System. Oct 29 04:38:59.152164 systemd[1]: Finished Load/Save Random Seed. Oct 29 04:38:59.153101 systemd[1]: Reached target First Boot Complete. Oct 29 04:38:59.155530 systemd[1]: Finished Coldplug All udev Devices. Oct 29 04:38:59.162207 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 29 04:38:59.164296 systemd-sysusers[821]: Creating group sgx with gid 999. Oct 29 04:38:59.165056 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 29 04:38:59.166846 systemd-sysusers[821]: Creating group systemd-oom with gid 998. Oct 29 04:38:59.167312 systemd-sysusers[821]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 29 04:38:59.167933 systemd-sysusers[821]: Creating group systemd-timesync with gid 997. Oct 29 04:38:59.168385 systemd-sysusers[821]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 29 04:38:59.168974 systemd-sysusers[821]: Creating group systemd-coredump with gid 996. Oct 29 04:38:59.169634 systemd-sysusers[821]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 29 04:38:59.178977 systemd[1]: Finished Create System Users. Oct 29 04:38:59.189275 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 04:38:59.202936 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 04:38:59.373845 systemd[1]: Finished Rebuild Hardware Database. Oct 29 04:38:59.380000 audit: BPF prog-id=27 op=LOAD Oct 29 04:38:59.380000 audit: BPF prog-id=28 op=LOAD Oct 29 04:38:59.380000 audit: BPF prog-id=29 op=LOAD Oct 29 04:38:59.380000 audit: BPF prog-id=17 op=UNLOAD Oct 29 04:38:59.380000 audit: BPF prog-id=18 op=UNLOAD Oct 29 04:38:59.381835 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 04:38:59.406223 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 04:38:59.413000 audit: BPF prog-id=30 op=LOAD Oct 29 04:38:59.414564 systemd[1]: Starting Network Configuration... Oct 29 04:38:59.437001 systemd-udevd[845]: Using default interface naming scheme 'v249'. Oct 29 04:38:59.448520 systemd-networkd[843]: lo: Link UP Oct 29 04:38:59.448528 systemd-networkd[843]: lo: Gained carrier Oct 29 04:38:59.449171 systemd-networkd[843]: Enumeration completed Oct 29 04:38:59.449532 systemd[1]: Started Network Configuration. Oct 29 04:38:59.451708 systemd-networkd[843]: eth0: Link UP Oct 29 04:38:59.456450 systemd-networkd[843]: eth0: Gained carrier Oct 29 04:38:59.462551 systemd-networkd[843]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Oct 29 04:38:59.467520 udevadm[823]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 29 04:38:59.493523 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 29 04:38:59.509297 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 04:38:59.518174 lvm[853]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 04:38:59.545258 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 04:38:59.546359 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 04:38:59.559313 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 04:38:59.562265 lvm[854]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 04:38:59.592276 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 04:38:59.593372 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 04:38:59.594418 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 29 04:38:59.594443 systemd[1]: Reached target Containers. Oct 29 04:38:59.606657 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 29 04:38:59.619696 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 29 04:38:59.621935 systemd[1]: Mounting /usr/share/oem... Oct 29 04:38:59.628052 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 29 04:38:59.628110 kernel: BTRFS info (device vda6): has skinny extents Oct 29 04:38:59.630539 systemd[1]: Mounted /usr/share/oem. Oct 29 04:38:59.631352 systemd[1]: Reached target Local File Systems. Oct 29 04:38:59.644281 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 29 04:38:59.645868 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 29 04:38:59.646016 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 29 04:38:59.647460 systemd[1]: Starting Commit a transient machine-id on disk... Oct 29 04:38:59.649334 systemd[1]: Starting Create Volatile Files and Directories... Oct 29 04:38:59.655367 systemd-tmpfiles[879]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 29 04:38:59.656749 systemd-tmpfiles[879]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 29 04:38:59.665464 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 29 04:38:59.666060 systemd[1]: Finished Commit a transient machine-id on disk. Oct 29 04:38:59.688163 systemd-tmpfiles[879]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 04:38:59.688280 systemd-tmpfiles[879]: Skipping /boot Oct 29 04:38:59.693653 systemd-tmpfiles[879]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 04:38:59.693747 systemd-tmpfiles[879]: Skipping /boot Oct 29 04:38:59.725903 systemd[1]: Finished Create Volatile Files and Directories. Oct 29 04:38:59.736474 systemd[1]: Starting Load Security Auditing Rules... Oct 29 04:38:59.738284 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 29 04:38:59.740892 systemd[1]: Starting Rebuild Journal Catalog... Oct 29 04:38:59.742000 audit: BPF prog-id=31 op=LOAD Oct 29 04:38:59.743327 systemd[1]: Starting Network Name Resolution... Oct 29 04:38:59.744000 audit: BPF prog-id=32 op=LOAD Oct 29 04:38:59.747208 systemd[1]: Starting Network Time Synchronization... Oct 29 04:38:59.749501 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 29 04:38:59.751321 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 29 04:38:59.752824 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 29 04:38:59.755764 systemd[1]: Finished Rebuild Journal Catalog. Oct 29 04:38:59.763317 systemd[1]: Starting Update is Completed... Oct 29 04:38:59.764035 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 29 04:38:59.765241 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 29 04:38:59.768000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 29 04:38:59.769121 augenrules[902]: No rules Oct 29 04:38:59.769909 systemd[1]: Finished Load Security Auditing Rules. Oct 29 04:38:59.771010 systemd[1]: Finished Update is Completed. Oct 29 04:38:59.792801 systemd[1]: Started Network Time Synchronization. Oct 29 04:38:59.793773 systemd[1]: Reached target System Initialization. Oct 29 04:38:59.794745 systemd[1]: Started Watch for update engine configuration changes. Oct 29 04:38:59.795767 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 04:38:59.797101 systemd-resolved[884]: Positive Trust Anchors: Oct 29 04:38:59.797276 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 29 04:38:59.797311 systemd-timesyncd[890]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 29 04:38:59.797526 systemd-resolved[884]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 04:38:59.797606 systemd-resolved[884]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 29 04:38:59.798480 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 29 04:38:59.798510 systemd[1]: Reached target Path Units. Oct 29 04:38:59.799345 systemd[1]: Reached target System Time Set. Oct 29 04:38:59.799682 systemd-resolved[884]: Defaulting to hostname 'linux'. Oct 29 04:38:59.800364 systemd[1]: Started Daily Log Rotation. Oct 29 04:38:59.801281 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 29 04:38:59.802430 systemd[1]: Reached target Timer Units. Oct 29 04:38:59.803472 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 29 04:38:59.812929 systemd[1]: Starting Docker Socket for the API... Oct 29 04:38:59.815977 systemd[1]: Listening on OpenSSH Server Socket. Oct 29 04:38:59.817259 systemd[1]: Started Network Name Resolution. Oct 29 04:38:59.818224 systemd[1]: Listening on Docker Socket for the API. Oct 29 04:38:59.819189 systemd[1]: Reached target Network. Oct 29 04:38:59.819937 systemd[1]: Reached target Host and Network Name Lookups. Oct 29 04:38:59.820906 systemd[1]: Reached target Socket Units. Oct 29 04:38:59.821712 systemd[1]: Reached target Basic System. Oct 29 04:38:59.822494 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 29 04:38:59.822525 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 29 04:38:59.823448 systemd[1]: Started D-Bus System Message Bus. Oct 29 04:38:59.826549 systemd[1]: Starting Extend Filesystems... Oct 29 04:38:59.827372 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 29 04:38:59.828284 systemd[1]: Starting Generate /run/flatcar/motd... Oct 29 04:38:59.830193 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 29 04:38:59.832134 systemd[1]: Starting Generate sshd host keys... Oct 29 04:38:59.833009 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 29 04:38:59.833047 systemd[1]: Reached target Load system-provided cloud configs. Oct 29 04:38:59.835850 systemd[1]: Starting User Login Management... Oct 29 04:38:59.837027 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 29 04:38:59.837323 extend-filesystems[911]: Found vda Oct 29 04:38:59.837611 systemd[1]: Starting Update Engine... Oct 29 04:38:59.839549 extend-filesystems[911]: Found vda1 Oct 29 04:38:59.839549 extend-filesystems[911]: Found vda2 Oct 29 04:38:59.839549 extend-filesystems[911]: Found vda3 Oct 29 04:38:59.839549 extend-filesystems[911]: Found usr Oct 29 04:38:59.839549 extend-filesystems[911]: Found vda4 Oct 29 04:38:59.839549 extend-filesystems[911]: Found vda6 Oct 29 04:38:59.839549 extend-filesystems[911]: Found vda7 Oct 29 04:38:59.839549 extend-filesystems[911]: Found vda9 Oct 29 04:38:59.839549 extend-filesystems[911]: Checking size of /dev/vda9 Oct 29 04:38:59.839224 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 29 04:38:59.856713 extend-filesystems[911]: Old size kept for /dev/vda9 Oct 29 04:38:59.839253 systemd[1]: Reached target Load user-provided cloud configs. Oct 29 04:38:59.840995 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 29 04:38:59.841207 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 29 04:38:59.845185 systemd[1]: motdgen.service: Deactivated successfully. Oct 29 04:38:59.845497 systemd[1]: Finished Generate /run/flatcar/motd. Oct 29 04:38:59.855838 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 29 04:38:59.856052 systemd[1]: Finished Extend Filesystems. Oct 29 04:38:59.877555 update_engine[922]: I1029 04:38:59.877239 922 main.cc:89] Flatcar Update Engine starting Oct 29 04:38:59.879891 systemd[1]: Started Update Engine. Oct 29 04:38:59.880124 update_engine[922]: I1029 04:38:59.880091 922 update_check_scheduler.cc:74] Next update check in 2m40s Oct 29 04:38:59.893548 systemd[1]: Started Cluster reboot manager. Oct 29 04:38:59.897921 systemd-logind[920]: Watching system buttons on /dev/input/event0 (Power Button) Oct 29 04:38:59.898225 systemd-logind[920]: New seat seat0. Oct 29 04:38:59.899563 systemd[1]: Started User Login Management. Oct 29 04:38:59.928226 locksmithd[935]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 29 04:39:00.312391 sshd_keygen[928]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 29 04:39:00.331150 systemd[1]: Finished Generate sshd host keys. Oct 29 04:39:00.342587 systemd[1]: Starting Generate /run/issue... Oct 29 04:39:00.346853 systemd[1]: issuegen.service: Deactivated successfully. Oct 29 04:39:00.347062 systemd[1]: Finished Generate /run/issue. Oct 29 04:39:00.349090 systemd[1]: Starting Permit User Sessions... Oct 29 04:39:00.353869 systemd[1]: Finished Permit User Sessions. Oct 29 04:39:00.355874 systemd[1]: Started Getty on tty1. Oct 29 04:39:00.357652 systemd[1]: Started Serial Getty on ttyAMA0. Oct 29 04:39:00.358598 systemd[1]: Reached target Login Prompts. Oct 29 04:39:00.359514 systemd[1]: Reached target Multi-User System. Oct 29 04:39:00.361498 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 29 04:39:00.366341 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 29 04:39:00.366570 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 29 04:39:00.367566 systemd[1]: Startup finished in 738ms (kernel) + 2.412s (initrd) + 3.394s (userspace) = 6.545s. Oct 29 04:39:00.426966 systemd[1]: Created slice Slice /system/sshd. Oct 29 04:39:00.428142 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40894). Oct 29 04:39:00.476266 sshd[956]: Accepted publickey for core from 10.0.0.1 port 40894 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:00.478392 sshd[956]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:00.497481 systemd[1]: Created slice User Slice of UID 500. Oct 29 04:39:00.498549 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 29 04:39:00.500065 systemd-logind[920]: New session 1 of user core. Oct 29 04:39:00.504657 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 29 04:39:00.505761 systemd[1]: Starting User Manager for UID 500... Oct 29 04:39:00.507982 systemd[959]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:00.554580 systemd[959]: Queued start job for default target Main User Target. Oct 29 04:39:00.554735 systemd[959]: Reached target Paths. Oct 29 04:39:00.554749 systemd[959]: Reached target Sockets. Oct 29 04:39:00.554761 systemd[959]: Reached target Timers. Oct 29 04:39:00.554771 systemd[959]: Reached target Basic System. Oct 29 04:39:00.554860 systemd[1]: Started User Manager for UID 500. Oct 29 04:39:00.555477 systemd[959]: Reached target Main User Target. Oct 29 04:39:00.555500 systemd[959]: Startup finished in 43ms. Oct 29 04:39:00.580829 systemd[1]: Started Session 1 of User core. Oct 29 04:39:00.644757 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36878). Oct 29 04:39:00.687307 sshd[968]: Accepted publickey for core from 10.0.0.1 port 36878 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:00.688838 sshd[968]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:00.691765 systemd-logind[920]: New session 2 of user core. Oct 29 04:39:00.704695 systemd[1]: Started Session 2 of User core. Oct 29 04:39:00.759990 sshd[968]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:00.779254 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36894). Oct 29 04:39:00.779743 systemd[1]: sshd@1-10.0.0.3:22-10.0.0.1:36878.service: Deactivated successfully. Oct 29 04:39:00.780345 systemd[1]: session-2.scope: Deactivated successfully. Oct 29 04:39:00.780998 systemd-logind[920]: Session 2 logged out. Waiting for processes to exit. Oct 29 04:39:00.781823 systemd-logind[920]: Removed session 2. Oct 29 04:39:00.822987 sshd[973]: Accepted publickey for core from 10.0.0.1 port 36894 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:00.824538 sshd[973]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:00.827097 systemd-logind[920]: New session 3 of user core. Oct 29 04:39:00.836931 systemd[1]: Started Session 3 of User core. Oct 29 04:39:00.891953 sshd[973]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:00.893733 systemd-networkd[843]: eth0: Gained IPv6LL Oct 29 04:39:00.903721 systemd[1]: sshd@2-10.0.0.3:22-10.0.0.1:36894.service: Deactivated successfully. Oct 29 04:39:00.904250 systemd[1]: session-3.scope: Deactivated successfully. Oct 29 04:39:00.904863 systemd-logind[920]: Session 3 logged out. Waiting for processes to exit. Oct 29 04:39:00.905843 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36902). Oct 29 04:39:00.906740 systemd-logind[920]: Removed session 3. Oct 29 04:39:00.950334 sshd[980]: Accepted publickey for core from 10.0.0.1 port 36902 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:00.951271 sshd[980]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:00.953672 systemd-logind[920]: New session 4 of user core. Oct 29 04:39:00.968648 systemd[1]: Started Session 4 of User core. Oct 29 04:39:01.025654 sshd[980]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:01.042102 systemd[1]: sshd@3-10.0.0.3:22-10.0.0.1:36902.service: Deactivated successfully. Oct 29 04:39:01.042687 systemd[1]: session-4.scope: Deactivated successfully. Oct 29 04:39:01.043292 systemd-logind[920]: Session 4 logged out. Waiting for processes to exit. Oct 29 04:39:01.044321 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36910). Oct 29 04:39:01.044941 systemd-logind[920]: Removed session 4. Oct 29 04:39:01.084702 sshd[986]: Accepted publickey for core from 10.0.0.1 port 36910 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:01.085884 sshd[986]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:01.088409 systemd-logind[920]: New session 5 of user core. Oct 29 04:39:01.098552 systemd[1]: Started Session 5 of User core. Oct 29 04:39:01.153187 sudo[989]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 29 04:39:01.153419 sudo[989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 04:39:01.162950 sudo[989]: pam_unix(sudo:session): session closed for user root Oct 29 04:39:01.165103 sshd[986]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:01.174078 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36924). Oct 29 04:39:01.176252 dbus-daemon[910]: [system] Reloaded configuration Oct 29 04:39:01.178510 systemd[1]: sshd@4-10.0.0.3:22-10.0.0.1:36910.service: Deactivated successfully. Oct 29 04:39:01.179121 systemd[1]: session-5.scope: Deactivated successfully. Oct 29 04:39:01.179715 systemd-logind[920]: Session 5 logged out. Waiting for processes to exit. Oct 29 04:39:01.180326 systemd-logind[920]: Removed session 5. Oct 29 04:39:01.216034 sshd[992]: Accepted publickey for core from 10.0.0.1 port 36924 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:01.217245 sshd[992]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:01.219618 systemd-logind[920]: New session 6 of user core. Oct 29 04:39:01.228541 systemd[1]: Started Session 6 of User core. Oct 29 04:39:01.278691 sudo[997]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 29 04:39:01.278918 sudo[997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 04:39:01.281228 sudo[997]: pam_unix(sudo:session): session closed for user root Oct 29 04:39:01.284800 sudo[996]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 29 04:39:01.285001 sudo[996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 04:39:01.299323 systemd[1]: Stopping Load Security Auditing Rules... Oct 29 04:39:01.299000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 29 04:39:01.300599 auditctl[1000]: No rules Oct 29 04:39:01.300848 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 04:39:01.301089 systemd[1]: Stopped Load Security Auditing Rules. Oct 29 04:39:01.302390 systemd[1]: Starting Load Security Auditing Rules... Oct 29 04:39:01.317262 augenrules[1017]: No rules Oct 29 04:39:01.318221 systemd[1]: Finished Load Security Auditing Rules. Oct 29 04:39:01.318939 sudo[996]: pam_unix(sudo:session): session closed for user root Oct 29 04:39:01.319987 sshd[992]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:01.330991 systemd[1]: sshd@5-10.0.0.3:22-10.0.0.1:36924.service: Deactivated successfully. Oct 29 04:39:01.331534 systemd[1]: session-6.scope: Deactivated successfully. Oct 29 04:39:01.332098 systemd-logind[920]: Session 6 logged out. Waiting for processes to exit. Oct 29 04:39:01.333065 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36934). Oct 29 04:39:01.333751 systemd-logind[920]: Removed session 6. Oct 29 04:39:01.375842 sshd[1023]: Accepted publickey for core from 10.0.0.1 port 36934 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:01.375569 sshd[1023]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:01.378483 systemd-logind[920]: New session 7 of user core. Oct 29 04:39:01.388551 systemd[1]: Started Session 7 of User core. Oct 29 04:39:01.437508 sudo[1026]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/bash -c cat >/etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem </etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem < Oct 29 04:39:04.261804 update_engine[1274]: Oct 29 04:39:04.261804 update_engine[1274]: Oct 29 04:39:04.261804 update_engine[1274]: Oct 29 04:39:04.261804 update_engine[1274]: Oct 29 04:39:04.261804 update_engine[1274]: Oct 29 04:39:04.261804 update_engine[1274]: Oct 29 04:39:04.261804 update_engine[1274]: Oct 29 04:39:04.261804 update_engine[1274]: Oct 29 04:39:04.261804 update_engine[1274]: I1029 04:39:04.261802 1274 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 29 04:39:04.267359 update_engine[1274]: I1029 04:39:04.267035 1274 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 29 04:39:04.267359 update_engine[1274]: I1029 04:39:04.267274 1274 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 29 04:39:04.268358 update_engine[1274]: I1029 04:39:04.268330 1274 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 29 04:39:04.268458 update_engine[1274]: I1029 04:39:04.268440 1274 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Oct 29 04:39:04.268458 update_engine[1274]: I1029 04:39:04.268453 1274 omaha_request_action.cc:619] Omaha request response: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.268458 update_engine[1274]: Oct 29 04:39:04.272641 update_engine[1274]: I1029 04:39:04.272609 1274 omaha_request_action.cc:447] Omaha Response manifest version = Oct 29 04:39:04.272641 update_engine[1274]: I1029 04:39:04.272644 1274 omaha_request_action.cc:470] Found 1 url(s) Oct 29 04:39:04.272728 update_engine[1274]: I1029 04:39:04.272660 1274 omaha_request_action.cc:506] Processing first of 1 package(s) Oct 29 04:39:04.272728 update_engine[1274]: I1029 04:39:04.272663 1274 omaha_request_action.cc:513] Omaha Response package name = update.gz Oct 29 04:39:04.272728 update_engine[1274]: I1029 04:39:04.272666 1274 omaha_request_action.cc:529] Url0: http://10.0.0.2:34567/packages/update.gz Oct 29 04:39:04.272728 update_engine[1274]: I1029 04:39:04.272675 1274 omaha_request_action.cc:541] Payload size = 439474134 bytes Oct 29 04:39:04.272728 update_engine[1274]: I1029 04:39:04.272684 1274 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Oct 29 04:39:04.272728 update_engine[1274]: I1029 04:39:04.272694 1274 payload_state.cc:51] Resetting all persisted state as this is a new response Oct 29 04:39:04.272728 update_engine[1274]: I1029 04:39:04.272698 1274 payload_state.cc:360] Current Response Signature = Oct 29 04:39:04.272728 update_engine[1274]: NumURLs = 1 Oct 29 04:39:04.272728 update_engine[1274]: Url0 = http://10.0.0.2:34567/packages/update.gz Oct 29 04:39:04.272728 update_engine[1274]: Payload Size = 439474134 Oct 29 04:39:04.272728 update_engine[1274]: Payload Sha256 Hash = 1W6DmQyJ99PNHJd8mThbRqG9pYJ4dSvmM+KW8Iw2VW0= Oct 29 04:39:04.272728 update_engine[1274]: Is Delta Payload = 0 Oct 29 04:39:04.272728 update_engine[1274]: Max Failure Count Per Url = 10 Oct 29 04:39:04.272728 update_engine[1274]: Disable Payload Backoff = 1 Oct 29 04:39:04.272955 update_engine[1274]: I1029 04:39:04.272783 1274 payload_state.cc:381] Payload Attempt Number = 0 Oct 29 04:39:04.272955 update_engine[1274]: I1029 04:39:04.272821 1274 payload_state.cc:404] Current URL Index = 0 Oct 29 04:39:04.272955 update_engine[1274]: I1029 04:39:04.272850 1274 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Oct 29 04:39:04.272955 update_engine[1274]: I1029 04:39:04.272879 1274 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Oct 29 04:39:04.272955 update_engine[1274]: I1029 04:39:04.272882 1274 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 29 04:39:04.272955 update_engine[1274]: I1029 04:39:04.272914 1274 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Oct 29 04:39:04.272955 update_engine[1274]: I1029 04:39:04.272930 1274 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 29 04:39:04.272955 update_engine[1274]: I1029 04:39:04.272934 1274 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Oct 29 04:39:04.272955 update_engine[1274]: I1029 04:39:04.272948 1274 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Oct 29 04:39:04.272955 update_engine[1274]: E1029 04:39:04.272953 1274 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Oct 29 04:39:04.273529 update_engine[1274]: I1029 04:39:04.273509 1274 omaha_response_handler_action.cc:85] Using this install plan: Oct 29 04:39:04.273529 update_engine[1274]: I1029 04:39:04.273525 1274 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.2:34567/packages/update.gz, payload size: 439474134, payload hash: 1W6DmQyJ99PNHJd8mThbRqG9pYJ4dSvmM+KW8Iw2VW0=, partition_path: /dev/vda4, kernel_path: /boot/flatcar/vmlinuz-b, pcr_policy_path: /var/lib/update_engine/pcrs-b.zip, old_partition_path: /dev/vda3, old_kernel_path: /boot/flatcar/vmlinuz-a Oct 29 04:39:04.273618 update_engine[1274]: I1029 04:39:04.273602 1274 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Oct 29 04:39:04.275382 update_engine[1274]: I1029 04:39:04.275357 1274 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Oct 29 04:39:04.308958 sshd[1294]: Accepted publickey for core from 10.0.0.1 port 37106 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:04.310259 sshd[1294]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:04.312553 kernel: audit: type=1006 audit(1761712744.308:352): pid=1294 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=25 res=1 Oct 29 04:39:04.316981 systemd-logind[920]: New session 25 of user core. Oct 29 04:39:04.327559 systemd[1]: Started Session 25 of User core. Oct 29 04:39:04.381297 sshd[1294]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:04.383878 systemd[1]: sshd@24-10.0.0.3:22-10.0.0.1:37106.service: Deactivated successfully. Oct 29 04:39:04.384510 systemd[1]: session-25.scope: Deactivated successfully. Oct 29 04:39:04.385093 systemd-logind[920]: Session 25 logged out. Waiting for processes to exit. Oct 29 04:39:04.385861 systemd-logind[920]: Removed session 25. Oct 29 04:39:06.242112 update_engine[1274]: I1029 04:39:06.242070 1274 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Oct 29 04:39:06.242112 update_engine[1274]: I1029 04:39:06.242112 1274 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Oct 29 04:39:06.311963 update_engine[1274]: E1029 04:39:06.311903 1274 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Oct 29 04:39:06.311963 update_engine[1274]: I1029 04:39:06.311949 1274 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Oct 29 04:39:06.311963 update_engine[1274]: I1029 04:39:06.311954 1274 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Oct 29 04:39:06.311963 update_engine[1274]: I1029 04:39:06.311956 1274 update_attempter.cc:302] Processing Done. Oct 29 04:39:06.312286 update_engine[1274]: E1029 04:39:06.312003 1274 update_attempter.cc:615] Update failed. Oct 29 04:39:06.312286 update_engine[1274]: I1029 04:39:06.312008 1274 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Oct 29 04:39:06.312286 update_engine[1274]: I1029 04:39:06.312012 1274 payload_state.cc:276] Incrementing the URL failure count Oct 29 04:39:06.312286 update_engine[1274]: I1029 04:39:06.312014 1274 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 29 04:39:06.312286 update_engine[1274]: I1029 04:39:06.312197 1274 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 29 04:39:06.312286 update_engine[1274]: I1029 04:39:06.312219 1274 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.2:34567/v1/update Oct 29 04:39:06.312286 update_engine[1274]: I1029 04:39:06.312224 1274 omaha_request_action.cc:269] Request: Oct 29 04:39:06.312286 update_engine[1274]: Oct 29 04:39:06.312286 update_engine[1274]: Oct 29 04:39:06.312286 update_engine[1274]: Oct 29 04:39:06.312286 update_engine[1274]: Oct 29 04:39:06.312286 update_engine[1274]: Oct 29 04:39:06.312286 update_engine[1274]: Oct 29 04:39:06.312286 update_engine[1274]: I1029 04:39:06.312228 1274 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 29 04:39:06.312286 update_engine[1274]: I1029 04:39:06.312285 1274 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 29 04:39:06.312582 update_engine[1274]: I1029 04:39:06.312409 1274 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 29 04:39:06.434516 update_engine[1274]: I1029 04:39:06.434465 1274 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 29 04:39:06.434628 update_engine[1274]: I1029 04:39:06.434578 1274 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Oct 29 04:39:06.434628 update_engine[1274]: I1029 04:39:06.434587 1274 omaha_request_action.cc:619] Omaha request response: Oct 29 04:39:06.434628 update_engine[1274]: Oct 29 04:39:06.434628 update_engine[1274]: Oct 29 04:39:06.434628 update_engine[1274]: Oct 29 04:39:06.434628 update_engine[1274]: Oct 29 04:39:06.434628 update_engine[1274]: Oct 29 04:39:06.434628 update_engine[1274]: Oct 29 04:39:06.434628 update_engine[1274]: E1029 04:39:06.434602 1274 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Oct 29 04:39:06.434628 update_engine[1274]: I1029 04:39:06.434605 1274 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 29 04:39:06.434628 update_engine[1274]: I1029 04:39:06.434608 1274 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 29 04:39:06.434628 update_engine[1274]: I1029 04:39:06.434612 1274 update_attempter.cc:302] Processing Done. Oct 29 04:39:06.434628 update_engine[1274]: I1029 04:39:06.434615 1274 update_attempter.cc:306] Error event sent. Oct 29 04:39:14.418246 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56754). Oct 29 04:39:14.461018 sshd[1308]: Accepted publickey for core from 10.0.0.1 port 56754 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:14.462505 sshd[1308]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:14.464418 kernel: audit: type=1006 audit(1761712754.461:353): pid=1308 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=26 res=1 Oct 29 04:39:14.465499 systemd-logind[920]: New session 26 of user core. Oct 29 04:39:14.480564 systemd[1]: Started Session 26 of User core. Oct 29 04:39:14.536485 sshd[1308]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:14.547009 systemd[1]: sshd@25-10.0.0.3:22-10.0.0.1:56754.service: Deactivated successfully. Oct 29 04:39:14.547579 systemd[1]: session-26.scope: Deactivated successfully. Oct 29 04:39:14.548128 systemd-logind[920]: Session 26 logged out. Waiting for processes to exit. Oct 29 04:39:14.549115 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56766). Oct 29 04:39:14.549756 systemd-logind[920]: Removed session 26. Oct 29 04:39:14.590035 sshd[1315]: Accepted publickey for core from 10.0.0.1 port 56766 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:14.591152 sshd[1315]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:14.593423 kernel: audit: type=1006 audit(1761712754.590:354): pid=1315 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=27 res=1 Oct 29 04:39:14.594247 systemd-logind[920]: New session 27 of user core. Oct 29 04:39:14.605554 systemd[1]: Started Session 27 of User core. Oct 29 04:39:14.659660 sshd[1315]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:14.675535 systemd[1]: sshd@26-10.0.0.3:22-10.0.0.1:56766.service: Deactivated successfully. Oct 29 04:39:14.676056 systemd[1]: session-27.scope: Deactivated successfully. Oct 29 04:39:14.676683 systemd-logind[920]: Session 27 logged out. Waiting for processes to exit. Oct 29 04:39:14.677703 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56782). Oct 29 04:39:14.678539 systemd-logind[920]: Removed session 27. Oct 29 04:39:14.717623 sshd[1323]: Accepted publickey for core from 10.0.0.1 port 56782 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:14.718559 sshd[1323]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:14.721043 systemd-logind[920]: New session 28 of user core. Oct 29 04:39:14.721415 kernel: audit: type=1006 audit(1761712754.716:355): pid=1323 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=28 res=1 Oct 29 04:39:14.729548 systemd[1]: Started Session 28 of User core. Oct 29 04:39:14.777750 sshd[1323]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:14.792192 systemd[1]: sshd@27-10.0.0.3:22-10.0.0.1:56782.service: Deactivated successfully. Oct 29 04:39:14.792760 systemd[1]: session-28.scope: Deactivated successfully. Oct 29 04:39:14.793360 systemd-logind[920]: Session 28 logged out. Waiting for processes to exit. Oct 29 04:39:14.794358 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56788). Oct 29 04:39:14.794984 systemd-logind[920]: Removed session 28. Oct 29 04:39:14.834997 sshd[1329]: Accepted publickey for core from 10.0.0.1 port 56788 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:14.836309 sshd[1329]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:14.838407 kernel: audit: type=1006 audit(1761712754.834:356): pid=1329 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=29 res=1 Oct 29 04:39:14.840333 systemd-logind[920]: New session 29 of user core. Oct 29 04:39:14.850537 systemd[1]: Started Session 29 of User core. Oct 29 04:39:14.897875 sshd[1329]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:14.907464 systemd[1]: sshd@28-10.0.0.3:22-10.0.0.1:56788.service: Deactivated successfully. Oct 29 04:39:14.908015 systemd[1]: session-29.scope: Deactivated successfully. Oct 29 04:39:14.908649 systemd-logind[920]: Session 29 logged out. Waiting for processes to exit. Oct 29 04:39:14.909706 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56800). Oct 29 04:39:14.910562 systemd-logind[920]: Removed session 29. Oct 29 04:39:14.949834 sshd[1335]: Accepted publickey for core from 10.0.0.1 port 56800 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:14.951272 sshd[1335]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:14.953404 kernel: audit: type=1006 audit(1761712754.949:357): pid=1335 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=30 res=1 Oct 29 04:39:14.955474 systemd-logind[920]: New session 30 of user core. Oct 29 04:39:14.961538 systemd[1]: Started Session 30 of User core. Oct 29 04:39:15.012931 sudo[1339]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Oct 29 04:39:15.013136 sudo[1339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 04:39:15.019273 systemd[1]: sshd.socket: Deactivated successfully. Oct 29 04:39:15.019649 systemd[1]: Closed OpenSSH Server Socket. Oct 29 04:39:15.020622 sudo[1339]: pam_unix(sudo:session): session closed for user root Oct 29 04:39:15.024417 sudo[1338]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Oct 29 04:39:15.024628 sudo[1338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 04:39:15.046511 systemd[1]: Stopping Session 1 of User core... Oct 29 04:39:15.047971 sshd[1335]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:15.048140 systemd[1]: Stopping Session 30 of User core... Oct 29 04:39:15.048167 sshd[956]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:15.049073 sudo[1338]: pam_unix(sudo:session): session closed for user root Oct 29 04:39:15.050102 systemd[1]: Removed slice Slice /system/addon-config. Oct 29 04:39:15.051287 systemd[1]: Removed slice Slice /system/addon-run. Oct 29 04:39:15.052644 systemd[1]: Removed slice Slice /system/modprobe. Oct 29 04:39:15.053805 systemd[1]: Removed slice Slice /system/system-cloudinit. Oct 29 04:39:15.054973 systemd[1]: Stopped target Multi-User System. Oct 29 04:39:15.055904 systemd[1]: Stopped target Login Prompts. Oct 29 04:39:15.056753 systemd[1]: Stopped target Containers. Oct 29 04:39:15.057645 systemd[1]: Stopped target Host and Network Name Lookups. Oct 29 04:39:15.058673 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 29 04:39:15.059713 systemd[1]: Stopped target Timer Units. Oct 29 04:39:15.060509 systemd[1]: logrotate.timer: Deactivated successfully. Oct 29 04:39:15.060551 systemd[1]: Stopped Daily Log Rotation. Oct 29 04:39:15.061323 systemd[1]: mdadm.timer: Deactivated successfully. Oct 29 04:39:15.061362 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Oct 29 04:39:15.062729 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 29 04:39:15.062770 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Oct 29 04:39:15.063794 systemd[1]: Stopped target System Time Set. Oct 29 04:39:15.064689 systemd[1]: Stopped target Load user-provided cloud configs. Oct 29 04:39:15.065750 systemd[1]: Stopped target Load system-provided cloud configs. Oct 29 04:39:15.066895 systemd[1]: systemd-coredump.socket: Deactivated successfully. Oct 29 04:39:15.066987 systemd[1]: Closed Process Core Dump Socket. Oct 29 04:39:15.068665 systemd[1]: Unmounting Boot partition... Oct 29 04:39:15.069728 systemd[1]: Stopping Getty on tty1... Oct 29 04:39:15.070926 systemd[1]: Stopping Serial Getty on ttyAMA0... Oct 29 04:39:15.073247 systemd[1]: Stopping OpenSSH per-connection server daemon... Oct 29 04:39:15.074288 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:56800)... Oct 29 04:39:15.075457 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Oct 29 04:39:15.075611 systemd[1]: Stopped Commit a transient machine-id on disk. Oct 29 04:39:15.076761 systemd[1]: Stopped target First Boot Complete. Oct 29 04:39:15.078875 systemd[1]: Stopping Load/Save Random Seed... Oct 29 04:39:15.079927 systemd[1]: Stopping Update Engine... Oct 29 04:39:15.083239 systemd[1]: getty@tty1.service: Deactivated successfully. Oct 29 04:39:15.083465 systemd[1]: Stopped Getty on tty1. Oct 29 04:39:15.084782 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Oct 29 04:39:15.084992 systemd[1]: Stopped Serial Getty on ttyAMA0. Oct 29 04:39:15.086520 systemd[1]: sshd@0-10.0.0.3:22-10.0.0.1:40894.service: Deactivated successfully. Oct 29 04:39:15.086716 systemd[1]: Stopped OpenSSH per-connection server daemon. Oct 29 04:39:15.088161 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Oct 29 04:39:15.088238 systemd[1]: update-engine.service: Failed with result 'exit-code'. Oct 29 04:39:15.088509 systemd[1]: Stopped Update Engine. Oct 29 04:39:15.089320 systemd[1]: update-engine.service: Consumed 2.439s CPU time. Oct 29 04:39:15.089614 systemd[1]: sshd@29-10.0.0.3:22-10.0.0.1:56800.service: Deactivated successfully. Oct 29 04:39:15.089823 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:56800). Oct 29 04:39:15.100949 systemd[1]: boot.mount: Deactivated successfully. Oct 29 04:39:15.101232 systemd[1]: Unmounted Boot partition. Oct 29 04:39:15.102417 systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 29 04:39:15.102638 systemd[1]: Stopped Load/Save Random Seed. Oct 29 04:39:15.103672 systemd[1]: session-1.scope: Deactivated successfully. Oct 29 04:39:15.103922 systemd[1]: Stopped Session 1 of User core. Oct 29 04:39:15.104929 systemd[1]: session-30.scope: Deactivated successfully. Oct 29 04:39:15.105150 systemd[1]: Stopped Session 30 of User core. Oct 29 04:39:15.106570 systemd-logind[920]: Session 30 logged out. Waiting for processes to exit. Oct 29 04:39:15.107476 systemd-logind[920]: Session 1 logged out. Waiting for processes to exit. Oct 29 04:39:15.107719 systemd[1]: Removed slice Slice /system/getty. Oct 29 04:39:15.108898 systemd[1]: Removed slice Slice /system/serial-getty. Oct 29 04:39:15.110573 systemd[1]: Removed slice Slice /system/sshd. Oct 29 04:39:15.111446 systemd[1]: sshd-keygen.service: Deactivated successfully. Oct 29 04:39:15.111546 systemd[1]: Stopped Generate sshd host keys. Oct 29 04:39:15.112434 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Oct 29 04:39:15.112553 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Oct 29 04:39:15.113938 systemd[1]: Stopping User Login Management... Oct 29 04:39:15.114807 systemd[1]: Stopping User Manager for UID 500... Oct 29 04:39:15.115686 systemd[959]: Stopped target Main User Target. Oct 29 04:39:15.115706 systemd[959]: Stopped target Basic System. Oct 29 04:39:15.115716 systemd[959]: Stopped target Paths. Oct 29 04:39:15.115724 systemd[959]: Stopped target Sockets. Oct 29 04:39:15.115740 systemd[959]: Reached target Shutdown. Oct 29 04:39:15.115747 systemd[959]: Stopped target Timers. Oct 29 04:39:15.115862 systemd[959]: Finished Exit the Session. Oct 29 04:39:15.115885 systemd[959]: Reached target Exit the Session. Oct 29 04:39:15.117420 systemd-logind[920]: Removed session 30. Oct 29 04:39:15.118064 systemd[1]: user@500.service: Deactivated successfully. Oct 29 04:39:15.118239 systemd[1]: Stopped User Manager for UID 500. Oct 29 04:39:15.120504 systemd[1]: Stopping User Runtime Directory /run/user/500... Oct 29 04:39:15.125735 systemd[1]: run-user-500.mount: Deactivated successfully. Oct 29 04:39:15.125799 systemd[1]: Unmounted /run/user/500. Oct 29 04:39:15.126982 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Oct 29 04:39:15.127200 systemd[1]: Stopped User Runtime Directory /run/user/500. Oct 29 04:39:15.128600 systemd[1]: systemd-logind.service: Deactivated successfully. Oct 29 04:39:15.128841 systemd[1]: Stopped User Login Management. Oct 29 04:39:15.130323 systemd[1]: Removed slice User Slice of UID 500. Oct 29 04:39:15.132022 systemd[1]: Stopping D-Bus System Message Bus... Oct 29 04:39:15.142000 audit: BPF prog-id=50 op=UNLOAD Oct 29 04:39:15.143237 systemd[1]: Stopping Permit User Sessions... Oct 29 04:39:15.144416 kernel: audit: type=1334 audit(1761712755.142:358): prog-id=50 op=UNLOAD Oct 29 04:39:15.144766 systemd[1]: dbus.service: Deactivated successfully. Oct 29 04:39:15.145013 systemd[1]: Stopped D-Bus System Message Bus. Oct 29 04:39:15.147628 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Oct 29 04:39:15.147844 systemd[1]: Stopped Permit User Sessions. Oct 29 04:39:15.148768 systemd[1]: Stopped target Basic System. Oct 29 04:39:15.149539 systemd[1]: Stopped target Network. Oct 29 04:39:15.150227 systemd[1]: Stopped target Path Units. Oct 29 04:39:15.150992 systemd[1]: motdgen.path: Deactivated successfully. Oct 29 04:39:15.155433 systemd[1]: Stopped Watch for update engine configuration changes. Oct 29 04:39:15.156479 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Oct 29 04:39:15.161444 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 04:39:15.162611 systemd[1]: Stopped target Remote File Systems. Oct 29 04:39:15.163431 systemd[1]: Stopped target Slice Units. Oct 29 04:39:15.164479 systemd[1]: Removed slice User and Session Slice. Oct 29 04:39:15.165312 systemd[1]: Stopped target Socket Units. Oct 29 04:39:15.166227 systemd[1]: dbus.socket: Deactivated successfully. Oct 29 04:39:15.166281 systemd[1]: Closed D-Bus System Message Bus Socket. Oct 29 04:39:15.167324 systemd[1]: docker.socket: Deactivated successfully. Oct 29 04:39:15.167568 systemd[1]: Closed Docker Socket for the API. Oct 29 04:39:15.168379 systemd[1]: Stopped target System Initialization. Oct 29 04:39:15.169236 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Oct 29 04:39:15.169369 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Oct 29 04:39:15.170476 systemd[1]: Stopped target Local Verity Protected Volumes. Oct 29 04:39:15.172206 systemd[1]: Stopping Load Security Auditing Rules... Oct 29 04:39:15.173419 systemd[1]: Stopping Network Name Resolution... Oct 29 04:39:15.174402 systemd[1]: Stopping Network Time Synchronization... Oct 29 04:39:15.174678 auditctl[1357]: No rules Oct 29 04:39:15.175185 systemd[1]: systemd-update-done.service: Deactivated successfully. Oct 29 04:39:15.175257 systemd[1]: Stopped Update is Completed. Oct 29 04:39:15.176199 systemd[1]: ldconfig.service: Deactivated successfully. Oct 29 04:39:15.176255 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Oct 29 04:39:15.177183 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Oct 29 04:39:15.177238 systemd[1]: Stopped Rebuild Hardware Database. Oct 29 04:39:15.178128 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Oct 29 04:39:15.178187 systemd[1]: Stopped Rebuild Journal Catalog. Oct 29 04:39:15.179818 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Oct 29 04:39:15.182334 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 29 04:39:15.182642 systemd[1]: Stopped Network Name Resolution. Oct 29 04:39:15.183746 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Oct 29 04:39:15.183977 systemd[1]: Stopped Network Time Synchronization. Oct 29 04:39:15.185212 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 04:39:15.185420 systemd[1]: Stopped Load Security Auditing Rules. Oct 29 04:39:15.187649 systemd[1]: Stopping Network Configuration... Oct 29 04:39:15.188669 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Oct 29 04:39:15.188878 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Oct 29 04:39:15.190432 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 29 04:39:15.190524 systemd[1]: Stopped Create Volatile Files and Directories. Oct 29 04:39:15.191528 systemd[1]: Stopped target Local File Systems. Oct 29 04:39:15.192000 audit: BPF prog-id=60 op=UNLOAD Oct 29 04:39:15.193313 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Oct 29 04:39:15.192000 audit: BPF prog-id=53 op=UNLOAD Oct 29 04:39:15.195439 kernel: audit: type=1334 audit(1761712755.192:359): prog-id=60 op=UNLOAD Oct 29 04:39:15.195494 kernel: audit: type=1334 audit(1761712755.192:360): prog-id=53 op=UNLOAD Oct 29 04:39:15.194456 systemd-networkd[843]: eth0: DHCPv6 lease lost Oct 29 04:39:15.195814 systemd[1]: Unmounting External Media Directory... Oct 29 04:39:15.197505 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Oct 29 04:39:15.199193 systemd[1]: Unmounting /run/torcx/unpack... Oct 29 04:39:15.200503 systemd[1]: Unmounting Temporary Directory /tmp... Oct 29 04:39:15.201791 systemd[1]: Unmounting /usr/share/oem... Oct 29 04:39:15.203328 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Oct 29 04:39:15.206067 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 29 04:39:15.206302 systemd[1]: Stopped Network Configuration. Oct 29 04:39:15.207288 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 29 04:39:15.207526 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Oct 29 04:39:15.208764 systemd[1]: media.mount: Deactivated successfully. Oct 29 04:39:15.209048 systemd[1]: Unmounted External Media Directory. Oct 29 04:39:15.210027 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Oct 29 04:39:15.210241 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Oct 29 04:39:15.219579 systemd[1]: tmp.mount: Deactivated successfully. Oct 29 04:39:15.219820 systemd[1]: Unmounted Temporary Directory /tmp. Oct 29 04:39:15.220820 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Oct 29 04:39:15.221043 systemd[1]: Unmounted /run/torcx/unpack. Oct 29 04:39:15.222051 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 29 04:39:15.222288 systemd[1]: Unmounted /usr/share/oem. Oct 29 04:39:15.223195 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 29 04:39:15.223429 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Oct 29 04:39:15.224000 audit: BPF prog-id=61 op=UNLOAD Oct 29 04:39:15.224785 systemd[1]: Stopped target Swaps. Oct 29 04:39:15.226277 systemd[1]: Reached target Unmount All Filesystems. Oct 29 04:39:15.226408 kernel: audit: type=1334 audit(1761712755.224:361): prog-id=61 op=UNLOAD Oct 29 04:39:15.227328 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 29 04:39:15.227386 systemd[1]: Closed Network Service Netlink Socket. Oct 29 04:39:15.228259 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Oct 29 04:39:15.228326 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Oct 29 04:39:15.229675 systemd[1]: Removed slice Slice /system/systemd-fsck. Oct 29 04:39:15.230803 systemd[1]: Stopped target Preparation for Local File Systems. Oct 29 04:39:15.231876 systemd[1]: lvm2-activation.service: Deactivated successfully. Oct 29 04:39:15.231942 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 29 04:39:15.232922 systemd[1]: Stopped target Local Encrypted Volumes. Oct 29 04:39:15.233912 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 04:39:15.233954 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 29 04:39:15.235102 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Oct 29 04:39:15.239452 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Oct 29 04:39:15.240584 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Oct 29 04:39:15.240655 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 29 04:39:15.241787 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 04:39:15.241844 systemd[1]: Stopped Apply Kernel Variables. Oct 29 04:39:15.242690 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 04:39:15.242750 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 29 04:39:15.243767 systemd[1]: systemd-sysusers.service: Deactivated successfully. Oct 29 04:39:15.243825 systemd[1]: Stopped Create System Users. Oct 29 04:39:15.244621 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Oct 29 04:39:15.244676 systemd[1]: Stopped Remount Root and Kernel File Systems. Oct 29 04:39:15.245637 systemd[1]: Reached target System Shutdown. Oct 29 04:39:15.246427 systemd[1]: Reached target Late Shutdown Services. Oct 29 04:39:15.247333 systemd[1]: systemd-reboot.service: Deactivated successfully. Oct 29 04:39:15.247372 systemd[1]: Finished System Reboot. Oct 29 04:39:15.248110 systemd[1]: Reached target System Reboot. Oct 29 04:39:15.248995 systemd[1]: Shutting down. Oct 29 04:39:15.250000 audit: BPF prog-id=52 op=UNLOAD Oct 29 04:39:15.250000 audit: BPF prog-id=51 op=UNLOAD Oct 29 04:39:15.252409 kernel: audit: type=1334 audit(1761712755.250:362): prog-id=52 op=UNLOAD Oct 29 04:39:15.251000 audit: BPF prog-id=56 op=UNLOAD Oct 29 04:39:15.251000 audit: BPF prog-id=55 op=UNLOAD Oct 29 04:39:15.251000 audit: BPF prog-id=54 op=UNLOAD Oct 29 04:39:15.252000 audit: BPF prog-id=59 op=UNLOAD Oct 29 04:39:15.252000 audit: BPF prog-id=58 op=UNLOAD Oct 29 04:39:15.252000 audit: BPF prog-id=57 op=UNLOAD Oct 29 04:39:15.287425 systemd-shutdown[1]: Syncing filesystems and block devices. Oct 29 04:39:15.294918 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Oct 29 04:39:15.294979 systemd-journald[809]: Journal stopped -- Reboot -- Oct 29 04:39:23.685445 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 29 04:39:23.685463 kernel: Linux version 5.10.107-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP PREEMPT Tue Mar 22 19:39:09 -00 2022 Oct 29 04:39:23.685471 kernel: efi: EFI v2.70 by EDK II Oct 29 04:39:23.685477 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 29 04:39:23.685481 kernel: efi: seeding entropy pool Oct 29 04:39:23.685486 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 29 04:39:23.685492 kernel: ACPI: Early table checksum verification disabled Oct 29 04:39:23.685497 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 29 04:39:23.685502 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 29 04:39:23.685508 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:39:23.685514 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:39:23.685519 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:39:23.685524 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:39:23.685529 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:39:23.685535 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:39:23.685541 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:39:23.685548 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:39:23.685553 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 04:39:23.685558 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 29 04:39:23.685563 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 29 04:39:23.685569 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 04:39:23.685574 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 29 04:39:23.685579 kernel: Zone ranges: Oct 29 04:39:23.685584 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 04:39:23.685589 kernel: DMA32 empty Oct 29 04:39:23.685594 kernel: Normal empty Oct 29 04:39:23.685599 kernel: Movable zone start for each node Oct 29 04:39:23.685605 kernel: Early memory node ranges Oct 29 04:39:23.685610 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 29 04:39:23.685616 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 29 04:39:23.685621 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 29 04:39:23.685626 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 29 04:39:23.685631 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 29 04:39:23.685636 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 29 04:39:23.685642 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 29 04:39:23.685647 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 29 04:39:23.685652 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 29 04:39:23.685658 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 29 04:39:23.685663 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 29 04:39:23.685668 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 04:39:23.685677 kernel: On node 0 totalpages: 643072 Oct 29 04:39:23.685682 kernel: DMA zone: 10048 pages used for memmap Oct 29 04:39:23.685688 kernel: DMA zone: 0 pages reserved Oct 29 04:39:23.685693 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 29 04:39:23.685699 kernel: psci: probing for conduit method from ACPI. Oct 29 04:39:23.685704 kernel: psci: PSCIv1.1 detected in firmware. Oct 29 04:39:23.685709 kernel: psci: Using standard PSCI v0.2 function IDs Oct 29 04:39:23.685715 kernel: psci: Trusted OS migration not required Oct 29 04:39:23.685722 kernel: psci: SMC Calling Convention v1.1 Oct 29 04:39:23.685727 kernel: ACPI: SRAT not present Oct 29 04:39:23.685733 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 29 04:39:23.685747 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 29 04:39:23.685755 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 29 04:39:23.685760 kernel: Detected PIPT I-cache on CPU0 Oct 29 04:39:23.685766 kernel: CPU features: detected: GIC system register CPU interface Oct 29 04:39:23.685771 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 29 04:39:23.685777 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 29 04:39:23.685782 kernel: CPU features: detected: Hardware dirty bit management Oct 29 04:39:23.685788 kernel: CPU features: detected: Spectre-v4 Oct 29 04:39:23.685794 kernel: CPU features: detected: ARM erratum 1418040 Oct 29 04:39:23.685800 kernel: CPU features: detected: Spectre-BHB Oct 29 04:39:23.685805 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 29 04:39:23.685811 kernel: Policy zone: DMA Oct 29 04:39:23.685817 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 29 04:39:23.685823 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 29 04:39:23.685829 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 29 04:39:23.685834 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 29 04:39:23.685840 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 29 04:39:23.685846 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 29 04:39:23.685851 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 29 04:39:23.685858 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 29 04:39:23.685864 kernel: rcu: RCU event tracing is enabled. Oct 29 04:39:23.685869 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 29 04:39:23.685875 kernel: Trampoline variant of Tasks RCU enabled. Oct 29 04:39:23.685881 kernel: Tracing variant of Tasks RCU enabled. Oct 29 04:39:23.685886 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 29 04:39:23.685892 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 29 04:39:23.685898 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 29 04:39:23.685903 kernel: GICv3: 256 SPIs implemented Oct 29 04:39:23.685908 kernel: GICv3: 0 Extended SPIs implemented Oct 29 04:39:23.685914 kernel: GICv3: Distributor has no Range Selector support Oct 29 04:39:23.685919 kernel: GICv3: 16 PPIs implemented Oct 29 04:39:23.685926 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 29 04:39:23.685931 kernel: ACPI: SRAT not present Oct 29 04:39:23.685936 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 29 04:39:23.685942 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 29 04:39:23.685948 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 29 04:39:23.685953 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 29 04:39:23.685959 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 29 04:39:23.685964 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 04:39:23.685970 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 29 04:39:23.685975 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 29 04:39:23.685981 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 29 04:39:23.685987 kernel: arm-pv: using stolen time PV Oct 29 04:39:23.685994 kernel: Console: colour dummy device 80x25 Oct 29 04:39:23.685999 kernel: ACPI: Core revision 20200925 Oct 29 04:39:23.686005 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 29 04:39:23.686011 kernel: pid_max: default: 32768 minimum: 301 Oct 29 04:39:23.686016 kernel: LSM: Security Framework initializing Oct 29 04:39:23.686022 kernel: SELinux: Initializing. Oct 29 04:39:23.686028 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 04:39:23.686034 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 04:39:23.686039 kernel: rcu: Hierarchical SRCU implementation. Oct 29 04:39:23.686046 kernel: Platform MSI: ITS@0x8080000 domain created Oct 29 04:39:23.686052 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 29 04:39:23.686057 kernel: Remapping and enabling EFI services. Oct 29 04:39:23.686063 kernel: smp: Bringing up secondary CPUs ... Oct 29 04:39:23.686069 kernel: Detected PIPT I-cache on CPU1 Oct 29 04:39:23.686074 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 29 04:39:23.686080 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 29 04:39:23.686086 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 04:39:23.686092 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 29 04:39:23.686097 kernel: Detected PIPT I-cache on CPU2 Oct 29 04:39:23.686104 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 29 04:39:23.686110 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 29 04:39:23.686116 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 04:39:23.686129 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 29 04:39:23.686135 kernel: Detected PIPT I-cache on CPU3 Oct 29 04:39:23.686146 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 29 04:39:23.686152 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 29 04:39:23.686159 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 04:39:23.686165 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 29 04:39:23.686171 kernel: smp: Brought up 1 node, 4 CPUs Oct 29 04:39:23.686177 kernel: SMP: Total of 4 processors activated. Oct 29 04:39:23.686183 kernel: CPU features: detected: Privileged Access Never Oct 29 04:39:23.686189 kernel: CPU features: detected: LSE atomic instructions Oct 29 04:39:23.686195 kernel: CPU features: detected: User Access Override Oct 29 04:39:23.686202 kernel: CPU features: detected: 32-bit EL0 Support Oct 29 04:39:23.686208 kernel: CPU features: detected: Common not Private translations Oct 29 04:39:23.686213 kernel: CPU features: detected: RAS Extension Support Oct 29 04:39:23.686236 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 29 04:39:23.686243 kernel: CPU features: detected: CRC32 instructions Oct 29 04:39:23.686250 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 29 04:39:23.686255 kernel: CPU: All CPU(s) started at EL1 Oct 29 04:39:23.686262 kernel: alternatives: patching kernel code Oct 29 04:39:23.686269 kernel: devtmpfs: initialized Oct 29 04:39:23.686275 kernel: KASLR enabled Oct 29 04:39:23.686281 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 29 04:39:23.686287 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 29 04:39:23.686293 kernel: pinctrl core: initialized pinctrl subsystem Oct 29 04:39:23.686300 kernel: SMBIOS 3.0.0 present. Oct 29 04:39:23.686306 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 29 04:39:23.686312 kernel: NET: Registered protocol family 16 Oct 29 04:39:23.686318 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 29 04:39:23.686324 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 29 04:39:23.686332 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 29 04:39:23.686338 kernel: audit: initializing netlink subsys (disabled) Oct 29 04:39:23.686344 kernel: audit: type=2000 audit(0.033:1): state=initialized audit_enabled=0 res=1 Oct 29 04:39:23.686350 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 29 04:39:23.686356 kernel: cpuidle: using governor menu Oct 29 04:39:23.686362 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 29 04:39:23.686369 kernel: ASID allocator initialised with 32768 entries Oct 29 04:39:23.686374 kernel: ACPI: bus type PCI registered Oct 29 04:39:23.686380 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 29 04:39:23.686387 kernel: Serial: AMBA PL011 UART driver Oct 29 04:39:23.686393 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 29 04:39:23.686400 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 29 04:39:23.686406 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 29 04:39:23.686412 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 29 04:39:23.686418 kernel: cryptd: max_cpu_qlen set to 1000 Oct 29 04:39:23.686424 kernel: ACPI: Added _OSI(Module Device) Oct 29 04:39:23.686430 kernel: ACPI: Added _OSI(Processor Device) Oct 29 04:39:23.686436 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 29 04:39:23.686443 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 29 04:39:23.686449 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 29 04:39:23.686455 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 29 04:39:23.686461 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 29 04:39:23.686467 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 29 04:39:23.686473 kernel: ACPI: Interpreter enabled Oct 29 04:39:23.686479 kernel: ACPI: Using GIC for interrupt routing Oct 29 04:39:23.686485 kernel: ACPI: MCFG table detected, 1 entries Oct 29 04:39:23.686491 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 29 04:39:23.686497 kernel: printk: console [ttyAMA0] enabled Oct 29 04:39:23.686505 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 29 04:39:23.686610 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 29 04:39:23.686669 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 29 04:39:23.686724 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 29 04:39:23.686780 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 29 04:39:23.686836 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 29 04:39:23.686844 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 29 04:39:23.686852 kernel: PCI host bridge to bus 0000:00 Oct 29 04:39:23.686932 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 29 04:39:23.687003 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 29 04:39:23.687067 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 29 04:39:23.687117 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 29 04:39:23.687241 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 29 04:39:23.687317 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 29 04:39:23.687378 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 29 04:39:23.687436 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 29 04:39:23.687492 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 04:39:23.687548 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 04:39:23.687604 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 29 04:39:23.687660 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 29 04:39:23.687711 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 29 04:39:23.687761 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 29 04:39:23.687811 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 29 04:39:23.687819 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 29 04:39:23.687826 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 29 04:39:23.687832 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 29 04:39:23.687838 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 29 04:39:23.687844 kernel: vgaarb: loaded Oct 29 04:39:23.687850 kernel: Registered efivars operations Oct 29 04:39:23.687856 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 29 04:39:23.687864 kernel: VFS: Disk quotas dquot_6.6.0 Oct 29 04:39:23.687870 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 29 04:39:23.687877 kernel: pnp: PnP ACPI init Oct 29 04:39:23.687942 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 29 04:39:23.687993 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 29 04:39:23.688001 kernel: pnp: PnP ACPI: found 1 devices Oct 29 04:39:23.688007 kernel: NET: Registered protocol family 2 Oct 29 04:39:23.688014 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 29 04:39:23.688021 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 29 04:39:23.688028 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 29 04:39:23.688034 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 29 04:39:23.688040 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 29 04:39:23.688046 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 04:39:23.688052 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 04:39:23.688058 kernel: NET: Registered protocol family 1 Oct 29 04:39:23.688065 kernel: PCI: CLS 0 bytes, default 64 Oct 29 04:39:23.688071 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 29 04:39:23.688078 kernel: kvm [1]: HYP mode not available Oct 29 04:39:23.688084 kernel: Initialise system trusted keyrings Oct 29 04:39:23.688090 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 29 04:39:23.688096 kernel: Key type asymmetric registered Oct 29 04:39:23.688102 kernel: Asymmetric key parser 'x509' registered Oct 29 04:39:23.688108 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 29 04:39:23.688114 kernel: io scheduler mq-deadline registered Oct 29 04:39:23.688128 kernel: io scheduler kyber registered Oct 29 04:39:23.688134 kernel: io scheduler bfq registered Oct 29 04:39:23.688142 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 29 04:39:23.688148 kernel: ACPI: Power Button [PWRB] Oct 29 04:39:23.688154 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 29 04:39:23.688212 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 29 04:39:23.688233 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 29 04:39:23.688240 kernel: msm_serial: driver initialized Oct 29 04:39:23.688246 kernel: random: fast init done Oct 29 04:39:23.688252 kernel: random: crng init done Oct 29 04:39:23.688258 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 29 04:39:23.688264 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 29 04:39:23.688272 kernel: thunder_xcv, ver 1.0 Oct 29 04:39:23.688278 kernel: thunder_bgx, ver 1.0 Oct 29 04:39:23.688284 kernel: nicpf, ver 1.0 Oct 29 04:39:23.688290 kernel: nicvf, ver 1.0 Oct 29 04:39:23.688355 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 29 04:39:23.688408 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-29T04:39:23 UTC (1761712763) Oct 29 04:39:23.688416 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 29 04:39:23.688422 kernel: NET: Registered protocol family 10 Oct 29 04:39:23.688430 kernel: Segment Routing with IPv6 Oct 29 04:39:23.688436 kernel: NET: Registered protocol family 17 Oct 29 04:39:23.688442 kernel: Key type dns_resolver registered Oct 29 04:39:23.688448 kernel: registered taskstats version 1 Oct 29 04:39:23.688454 kernel: Loading compiled-in X.509 certificates Oct 29 04:39:23.688460 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 29 04:39:23.688467 kernel: Key type ._fscrypt registered Oct 29 04:39:23.688473 kernel: Key type .fscrypt registered Oct 29 04:39:23.688479 kernel: Key type fscrypt-provisioning registered Oct 29 04:39:23.688486 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 29 04:39:23.688492 kernel: ima: Allocated hash algorithm: sha1 Oct 29 04:39:23.688513 kernel: ima: No architecture policies found Oct 29 04:39:23.688521 kernel: Freeing unused kernel memory: 34240K Oct 29 04:39:23.688527 kernel: Run /init as init process Oct 29 04:39:23.688533 kernel: with arguments: Oct 29 04:39:23.688539 kernel: /init Oct 29 04:39:23.688545 kernel: with environment: Oct 29 04:39:23.688550 kernel: HOME=/ Oct 29 04:39:23.688556 kernel: TERM=linux Oct 29 04:39:23.688564 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 29 04:39:23.688572 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 29 04:39:23.688580 systemd[1]: Detected virtualization kvm. Oct 29 04:39:23.688587 systemd[1]: Detected architecture arm64. Oct 29 04:39:23.688593 systemd[1]: Running in initial RAM disk. Oct 29 04:39:23.688600 systemd[1]: No hostname configured, using default hostname. Oct 29 04:39:23.688606 systemd[1]: Hostname set to . Oct 29 04:39:23.688614 systemd[1]: Initializing machine ID from VM UUID. Oct 29 04:39:23.688621 systemd[1]: Queued start job for default target Initrd Default Target. Oct 29 04:39:23.688627 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 04:39:23.688634 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 04:39:23.688640 systemd[1]: Reached target Path Units. Oct 29 04:39:23.688647 systemd[1]: Reached target Slice Units. Oct 29 04:39:23.688653 systemd[1]: Reached target Swaps. Oct 29 04:39:23.688660 systemd[1]: Reached target Timer Units. Oct 29 04:39:23.688668 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 29 04:39:23.688675 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 29 04:39:23.688681 systemd[1]: Listening on Journal Audit Socket. Oct 29 04:39:23.688688 systemd[1]: Listening on Journal Socket (/dev/log). Oct 29 04:39:23.688694 systemd[1]: Listening on Journal Socket. Oct 29 04:39:23.688701 systemd[1]: Listening on udev Control Socket. Oct 29 04:39:23.688707 systemd[1]: Listening on udev Kernel Socket. Oct 29 04:39:23.688714 systemd[1]: Reached target Socket Units. Oct 29 04:39:23.688720 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 29 04:39:23.688728 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 04:39:23.688735 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 29 04:39:23.688742 systemd[1]: Starting Journal Service... Oct 29 04:39:23.688758 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 04:39:23.688764 systemd[1]: Starting Apply Kernel Variables... Oct 29 04:39:23.688771 systemd[1]: Starting Setup Virtual Console... Oct 29 04:39:23.688777 kernel: SCSI subsystem initialized Oct 29 04:39:23.688783 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 29 04:39:23.688790 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 04:39:23.688796 kernel: Loading iSCSI transport class v2.0-870. Oct 29 04:39:23.688804 systemd[1]: Finished Apply Kernel Variables. Oct 29 04:39:23.688811 systemd[1]: Finished Setup Virtual Console. Oct 29 04:39:23.688820 systemd-journald[270]: Journal started Oct 29 04:39:23.688856 systemd-journald[270]: Runtime Journal (/run/log/journal/24c2f1393fab4f32a2d1741936dad9bf) is 6.0M, max 48.5M, 42.4M free. Oct 29 04:39:23.691483 systemd[1]: Started Journal Service. Oct 29 04:39:23.692392 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 29 04:39:23.693903 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 04:39:23.697231 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 04:39:23.707518 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 29 04:39:23.709305 systemd[1]: Starting dracut cmdline hook... Oct 29 04:39:23.718481 dracut-cmdline[290]: dracut-dracut-053 Oct 29 04:39:23.720909 dracut-cmdline[290]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 29 04:39:23.782245 kernel: iscsi: registered transport (tcp) Oct 29 04:39:23.794249 kernel: iscsi: registered transport (qla4xxx) Oct 29 04:39:23.794269 kernel: QLogic iSCSI HBA Driver Oct 29 04:39:23.804705 systemd[1]: Finished dracut cmdline hook. Oct 29 04:39:23.806458 systemd[1]: Starting dracut pre-udev hook... Oct 29 04:39:23.807858 systemd[1]: Starting Open-iSCSI... Oct 29 04:39:23.810353 iscsid[376]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 29 04:39:23.810353 iscsid[376]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 29 04:39:23.810353 iscsid[376]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 29 04:39:23.810353 iscsid[376]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 29 04:39:23.820784 iscsid[376]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 29 04:39:23.820784 iscsid[376]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 29 04:39:23.824744 kernel: device-mapper: uevent: version 1.0.3 Oct 29 04:39:23.824767 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 29 04:39:23.813984 systemd[1]: Started Open-iSCSI. Oct 29 04:39:23.858267 kernel: raid6: neonx8 gen() 13795 MB/s Oct 29 04:39:23.875252 kernel: raid6: neonx8 xor() 10750 MB/s Oct 29 04:39:23.892253 kernel: raid6: neonx4 gen() 13586 MB/s Oct 29 04:39:23.909248 kernel: raid6: neonx4 xor() 11556 MB/s Oct 29 04:39:23.926243 kernel: raid6: neonx2 gen() 13018 MB/s Oct 29 04:39:23.943259 kernel: raid6: neonx2 xor() 9883 MB/s Oct 29 04:39:23.960243 kernel: raid6: neonx1 gen() 10842 MB/s Oct 29 04:39:23.977250 kernel: raid6: neonx1 xor() 8841 MB/s Oct 29 04:39:23.994245 kernel: raid6: int64x8 gen() 6808 MB/s Oct 29 04:39:24.011245 kernel: raid6: int64x8 xor() 3798 MB/s Oct 29 04:39:24.028246 kernel: raid6: int64x4 gen() 6909 MB/s Oct 29 04:39:24.045245 kernel: raid6: int64x4 xor() 3836 MB/s Oct 29 04:39:24.062249 kernel: raid6: int64x2 gen() 6188 MB/s Oct 29 04:39:24.079245 kernel: raid6: int64x2 xor() 3314 MB/s Oct 29 04:39:24.096251 kernel: raid6: int64x1 gen() 5015 MB/s Oct 29 04:39:24.113399 kernel: raid6: int64x1 xor() 2599 MB/s Oct 29 04:39:24.113410 kernel: raid6: using algorithm neonx8 gen() 13795 MB/s Oct 29 04:39:24.113418 kernel: raid6: .... xor() 10750 MB/s, rmw enabled Oct 29 04:39:24.114556 kernel: raid6: using neon recovery algorithm Oct 29 04:39:24.124248 kernel: xor: measuring software checksum speed Oct 29 04:39:24.125243 kernel: 8regs : 17156 MB/sec Oct 29 04:39:24.126249 kernel: 32regs : 20765 MB/sec Oct 29 04:39:24.127637 kernel: arm64_neon : 29173 MB/sec Oct 29 04:39:24.127648 kernel: xor: using function: arm64_neon (29173 MB/sec) Oct 29 04:39:24.173250 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 29 04:39:24.183664 systemd[1]: Finished dracut pre-udev hook. Oct 29 04:39:24.184000 audit: BPF prog-id=6 op=LOAD Oct 29 04:39:24.185000 audit: BPF prog-id=7 op=LOAD Oct 29 04:39:24.186625 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 04:39:24.188980 kernel: audit: type=1334 audit(1761712764.184:2): prog-id=6 op=LOAD Oct 29 04:39:24.189004 kernel: audit: type=1334 audit(1761712764.185:3): prog-id=7 op=LOAD Oct 29 04:39:24.189013 kernel: audit: type=1334 audit(1761712764.185:4): prog-id=8 op=LOAD Oct 29 04:39:24.185000 audit: BPF prog-id=8 op=LOAD Oct 29 04:39:24.199290 systemd-udevd[409]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 04:39:24.200973 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 04:39:24.202861 systemd[1]: Starting dracut pre-trigger hook... Oct 29 04:39:24.214321 dracut-pre-trigger[416]: rd.md=0: removing MD RAID activation Oct 29 04:39:24.238620 systemd[1]: Finished dracut pre-trigger hook. Oct 29 04:39:24.240166 systemd[1]: Starting Coldplug All udev Devices... Oct 29 04:39:24.250292 systemd-udevd[409]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 04:39:24.274341 systemd[1]: Finished Coldplug All udev Devices. Oct 29 04:39:24.276001 systemd[1]: Starting dracut initqueue hook... Oct 29 04:39:24.296199 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 29 04:39:24.296353 kernel: vda: detected capacity change from 0 to 4756340736 Oct 29 04:39:24.306792 systemd-udevd[454]: Using default interface naming scheme 'v249'. Oct 29 04:39:24.315258 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 04:39:24.342045 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 29 04:39:24.343973 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 29 04:39:24.351244 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (468) Oct 29 04:39:24.355164 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 29 04:39:24.365732 systemd[1]: Finished dracut initqueue hook. Oct 29 04:39:24.366704 systemd[1]: Reached target Initrd Root Device. Oct 29 04:39:24.368073 systemd[1]: Reached target Preparation for Remote File Systems. Oct 29 04:39:24.372257 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 04:39:24.374095 systemd[1]: Reached target Remote File Systems. Oct 29 04:39:24.376505 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 29 04:39:24.378276 systemd[1]: Starting dracut pre-mount hook... Oct 29 04:39:24.379659 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 29 04:39:24.379784 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 29 04:39:24.380972 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 04:39:24.383412 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 29 04:39:24.387876 systemd[1]: Finished dracut pre-mount hook. Oct 29 04:39:24.389546 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 29 04:39:24.396277 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 29 04:39:24.399052 systemd-fsck[503]: ROOT: clean, 640/553792 files, 39961/553472 blocks Oct 29 04:39:24.416262 systemd[1]: Found device /dev/mapper/usr. Oct 29 04:39:24.417820 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 29 04:39:24.419023 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 29 04:39:24.428256 systemd-fsck[512]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 29 04:39:24.428256 systemd-fsck[512]: You must have r/w access to the filesystem or be root Oct 29 04:39:24.432090 systemd-fsck[510]: fsck failed with exit status 8. Oct 29 04:39:24.432099 systemd-fsck[510]: Ignoring error. Oct 29 04:39:24.432813 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 29 04:39:24.434925 systemd[1]: Mounting /sysusr/usr... Oct 29 04:39:24.450385 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 29 04:39:24.452290 systemd[1]: Mounting /sysroot... Oct 29 04:39:24.478244 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 29 04:39:24.478683 systemd[1]: Mounted /sysusr/usr. Oct 29 04:39:24.479475 systemd[1]: Reached target Local File Systems. Oct 29 04:39:24.480743 systemd[1]: Reached target System Initialization. Oct 29 04:39:24.485081 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 29 04:39:24.485105 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 29 04:39:24.484355 systemd[1]: Reached target Basic System. Oct 29 04:39:24.486460 systemd[1]: Mounted /sysroot. Oct 29 04:39:24.487217 systemd[1]: Reached target Initrd Root File System. Oct 29 04:39:24.489875 systemd[1]: Mounting /sysroot/usr... Oct 29 04:39:24.491266 systemd[1]: Starting Reload Configuration from the Real Root... Oct 29 04:39:24.492902 systemd[1]: Mounted /sysroot/usr. Oct 29 04:39:24.495751 systemd[1]: Reloading. Oct 29 04:39:24.497000 audit: BPF prog-id=6 op=UNLOAD Oct 29 04:39:24.499242 kernel: audit: type=1334 audit(1761712764.497:5): prog-id=6 op=UNLOAD Oct 29 04:39:24.506000 audit: BPF prog-id=3 op=UNLOAD Oct 29 04:39:24.508257 kernel: audit: type=1334 audit(1761712764.506:6): prog-id=3 op=UNLOAD Oct 29 04:39:24.588000 audit: BPF prog-id=9 op=LOAD Oct 29 04:39:24.589000 audit: BPF prog-id=10 op=LOAD Oct 29 04:39:24.589000 audit: BPF prog-id=11 op=LOAD Oct 29 04:39:24.591042 kernel: audit: type=1334 audit(1761712764.588:7): prog-id=9 op=LOAD Oct 29 04:39:24.591067 kernel: audit: type=1334 audit(1761712764.589:8): prog-id=10 op=LOAD Oct 29 04:39:24.591076 kernel: audit: type=1334 audit(1761712764.589:9): prog-id=11 op=LOAD Oct 29 04:39:24.591093 kernel: audit: type=1334 audit(1761712764.589:10): prog-id=7 op=UNLOAD Oct 29 04:39:24.589000 audit: BPF prog-id=7 op=UNLOAD Oct 29 04:39:24.589000 audit: BPF prog-id=8 op=UNLOAD Oct 29 04:39:24.590000 audit: BPF prog-id=12 op=LOAD Oct 29 04:39:24.591000 audit: BPF prog-id=13 op=LOAD Oct 29 04:39:24.591000 audit: BPF prog-id=14 op=LOAD Oct 29 04:39:24.591000 audit: BPF prog-id=4 op=UNLOAD Oct 29 04:39:24.591000 audit: BPF prog-id=5 op=UNLOAD Oct 29 04:39:24.607273 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 29 04:39:24.607417 systemd[1]: Finished Reload Configuration from the Real Root. Oct 29 04:39:24.609300 systemd[1]: Reached target Initrd File Systems. Oct 29 04:39:24.610932 systemd[1]: Reached target Initrd Default Target. Oct 29 04:39:24.612480 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 29 04:39:24.613249 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 29 04:39:24.623973 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 29 04:39:24.625705 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 29 04:39:24.631833 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 29 04:39:24.632818 systemd[1]: Stopped target Timer Units. Oct 29 04:39:24.634259 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 29 04:39:24.634385 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 29 04:39:24.635818 systemd[1]: Stopped target Initrd Default Target. Oct 29 04:39:24.637290 systemd[1]: Stopped target Basic System. Oct 29 04:39:24.638878 systemd[1]: Stopped target Initrd Root Device. Oct 29 04:39:24.640370 systemd[1]: Stopped target Path Units. Oct 29 04:39:24.641631 systemd[1]: Stopped target Remote File Systems. Oct 29 04:39:24.643132 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 29 04:39:24.644714 systemd[1]: Stopped target Slice Units. Oct 29 04:39:24.646074 systemd[1]: Stopped target Socket Units. Oct 29 04:39:24.647600 systemd[1]: Stopped target System Initialization. Oct 29 04:39:24.649007 systemd[1]: Stopped target Local File Systems. Oct 29 04:39:24.650325 systemd[1]: Stopped target Preparation for Local File Systems. Oct 29 04:39:24.652033 systemd[1]: Stopped target Swaps. Oct 29 04:39:24.653317 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 29 04:39:24.653441 systemd[1]: Stopped dracut pre-mount hook. Oct 29 04:39:24.655042 systemd[1]: Stopped target Local Encrypted Volumes. Oct 29 04:39:24.656314 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 04:39:24.660254 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 29 04:39:24.661399 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 29 04:39:24.661517 systemd[1]: Stopped dracut initqueue hook. Oct 29 04:39:24.666761 iscsid[376]: iscsid shutting down. Oct 29 04:39:24.663267 systemd[1]: Stopping Open-iSCSI... Oct 29 04:39:24.666067 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 04:39:24.666213 systemd[1]: Stopped Apply Kernel Variables. Oct 29 04:39:24.667602 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 29 04:39:24.667697 systemd[1]: Stopped Coldplug All udev Devices. Oct 29 04:39:24.668934 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 29 04:39:24.669026 systemd[1]: Stopped dracut pre-trigger hook. Oct 29 04:39:24.670323 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 29 04:39:24.674518 systemd[1]: iscsid.service: Deactivated successfully. Oct 29 04:39:24.674639 systemd[1]: Stopped Open-iSCSI. Oct 29 04:39:24.676268 systemd[1]: iscsid.socket: Deactivated successfully. Oct 29 04:39:24.676335 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 29 04:39:24.678012 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 29 04:39:24.688000 audit: BPF prog-id=9 op=UNLOAD Oct 29 04:39:24.680170 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 29 04:39:24.680301 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 29 04:39:24.681603 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 29 04:39:24.681726 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 29 04:39:24.683621 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 29 04:39:24.683733 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 29 04:39:24.685766 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 29 04:39:24.685817 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 29 04:39:24.687215 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 29 04:39:24.687272 systemd[1]: Closed udev Control Socket. Oct 29 04:39:24.688783 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 29 04:39:24.688818 systemd[1]: Closed udev Kernel Socket. Oct 29 04:39:24.690168 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 29 04:39:24.690215 systemd[1]: Stopped dracut pre-udev hook. Oct 29 04:39:24.691749 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 29 04:39:24.691791 systemd[1]: Stopped dracut cmdline hook. Oct 29 04:39:24.693113 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 29 04:39:24.693166 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 29 04:39:24.695656 systemd[1]: Starting Cleanup udev Database... Oct 29 04:39:24.696626 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 29 04:39:24.698327 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 04:39:24.698393 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 29 04:39:24.700127 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 29 04:39:24.718000 audit: BPF prog-id=12 op=UNLOAD Oct 29 04:39:24.700178 systemd[1]: Stopped Create List of Static Device Nodes. Oct 29 04:39:24.701853 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 04:39:24.701899 systemd[1]: Stopped Setup Virtual Console. Oct 29 04:39:24.703520 systemd[1]: rngd.service: Deactivated successfully. Oct 29 04:39:24.703642 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 29 04:39:24.705342 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 29 04:39:24.705453 systemd[1]: Finished Cleanup udev Database. Oct 29 04:39:24.706630 systemd[1]: Reached target Switch Root. Oct 29 04:39:24.708738 systemd[1]: Starting Switch Root... Oct 29 04:39:24.716427 systemd[1]: Switching root. Oct 29 04:39:24.736545 systemd-journald[270]: Journal stopped Oct 29 04:39:26.829764 systemd-journald[270]: Received SIGTERM from PID 1 (systemd). Oct 29 04:39:26.829860 kernel: SELinux: policy capability network_peer_controls=1 Oct 29 04:39:26.829880 kernel: SELinux: policy capability open_perms=1 Oct 29 04:39:26.829897 kernel: SELinux: policy capability extended_socket_class=1 Oct 29 04:39:26.829906 kernel: SELinux: policy capability always_check_network=0 Oct 29 04:39:26.829915 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 29 04:39:26.829928 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 29 04:39:26.829937 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 29 04:39:26.829947 systemd[1]: Successfully loaded SELinux policy in 34.074ms. Oct 29 04:39:26.829959 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.430ms. Oct 29 04:39:26.829970 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 29 04:39:26.829981 systemd[1]: Detected virtualization kvm. Oct 29 04:39:26.829991 systemd[1]: Detected architecture arm64. Oct 29 04:39:26.830008 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 29 04:39:26.830020 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 29 04:39:26.830030 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 29 04:39:26.830040 systemd[1]: Stopped Switch Root. Oct 29 04:39:26.830049 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 29 04:39:26.830059 systemd[1]: Created slice Slice /system/addon-config. Oct 29 04:39:26.830068 systemd[1]: Created slice Slice /system/addon-run. Oct 29 04:39:26.830079 systemd[1]: Created slice Slice /system/getty. Oct 29 04:39:26.830089 systemd[1]: Created slice Slice /system/modprobe. Oct 29 04:39:26.830098 systemd[1]: Created slice Slice /system/serial-getty. Oct 29 04:39:26.830114 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 29 04:39:26.830126 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 29 04:39:26.830158 systemd[1]: Created slice User and Session Slice. Oct 29 04:39:26.830170 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 04:39:26.830180 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 29 04:39:26.830190 systemd[1]: Set up automount Boot partition Automount Point. Oct 29 04:39:26.830201 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 29 04:39:26.830211 systemd[1]: Stopped target Switch Root. Oct 29 04:39:26.830240 systemd[1]: Stopped target Initrd File Systems. Oct 29 04:39:26.830250 systemd[1]: Stopped target Initrd Root File System. Oct 29 04:39:26.830260 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 04:39:26.830273 systemd[1]: Reached target Remote File Systems. Oct 29 04:39:26.830283 systemd[1]: Reached target Slice Units. Oct 29 04:39:26.830294 systemd[1]: Reached target Swaps. Oct 29 04:39:26.830304 systemd[1]: Reached target Verify torcx succeeded. Oct 29 04:39:26.830314 systemd[1]: Reached target Local Verity Protected Volumes. Oct 29 04:39:26.830325 systemd[1]: Listening on Process Core Dump Socket. Oct 29 04:39:26.830335 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 29 04:39:26.830345 systemd[1]: Listening on Network Service Netlink Socket. Oct 29 04:39:26.830354 systemd[1]: Listening on udev Control Socket. Oct 29 04:39:26.830364 systemd[1]: Listening on udev Kernel Socket. Oct 29 04:39:26.830374 systemd[1]: Mounting Huge Pages File System... Oct 29 04:39:26.830383 systemd[1]: Mounting POSIX Message Queue File System... Oct 29 04:39:26.830393 systemd[1]: Mounting External Media Directory... Oct 29 04:39:26.830406 systemd[1]: Mounting Kernel Debug File System... Oct 29 04:39:26.830462 systemd[1]: Mounting Kernel Trace File System... Oct 29 04:39:26.830476 systemd[1]: Mounting Temporary Directory /tmp... Oct 29 04:39:26.830486 systemd[1]: Starting Create missing system files... Oct 29 04:39:26.830496 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 04:39:26.830506 systemd[1]: Starting Load Kernel Module configfs... Oct 29 04:39:26.830516 systemd[1]: Starting Load Kernel Module drm... Oct 29 04:39:26.830525 systemd[1]: Starting Load Kernel Module fuse... Oct 29 04:39:26.830541 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 29 04:39:26.830551 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 29 04:39:26.830562 systemd[1]: Stopped File System Check on Root Device. Oct 29 04:39:26.830574 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 29 04:39:26.830583 systemd[1]: Stopped systemd-fsck-usr.service. Oct 29 04:39:26.830593 kernel: fuse: init (API version 7.32) Oct 29 04:39:26.830602 systemd[1]: Stopped Journal Service. Oct 29 04:39:26.830612 systemd[1]: Starting Journal Service... Oct 29 04:39:26.830621 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 04:39:26.830631 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 29 04:39:26.830641 systemd[1]: Starting Apply Kernel Variables... Oct 29 04:39:26.830651 systemd[1]: Starting Coldplug All udev Devices... Oct 29 04:39:26.830660 systemd[1]: verity-setup.service: Deactivated successfully. Oct 29 04:39:26.830672 systemd[1]: Stopped verity-setup.service. Oct 29 04:39:26.830684 systemd-journald[692]: Journal started Oct 29 04:39:26.830730 systemd-journald[692]: Runtime Journal (/run/log/journal/24c2f1393fab4f32a2d1741936dad9bf) is 6.0M, max 48.5M, 42.4M free. Oct 29 04:39:24.811000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 29 04:39:24.845000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 29 04:39:24.845000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 29 04:39:24.845000 audit: BPF prog-id=15 op=LOAD Oct 29 04:39:24.845000 audit: BPF prog-id=15 op=UNLOAD Oct 29 04:39:24.845000 audit: BPF prog-id=16 op=LOAD Oct 29 04:39:24.845000 audit: BPF prog-id=16 op=UNLOAD Oct 29 04:39:24.880000 audit[643]: AVC avc: denied { associate } for pid=643 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 29 04:39:26.700000 audit: BPF prog-id=17 op=LOAD Oct 29 04:39:26.700000 audit: BPF prog-id=18 op=LOAD Oct 29 04:39:26.700000 audit: BPF prog-id=19 op=LOAD Oct 29 04:39:26.700000 audit: BPF prog-id=13 op=UNLOAD Oct 29 04:39:26.700000 audit: BPF prog-id=14 op=UNLOAD Oct 29 04:39:26.700000 audit: BPF prog-id=20 op=LOAD Oct 29 04:39:26.700000 audit: BPF prog-id=17 op=UNLOAD Oct 29 04:39:26.700000 audit: BPF prog-id=21 op=LOAD Oct 29 04:39:26.700000 audit: BPF prog-id=22 op=LOAD Oct 29 04:39:26.700000 audit: BPF prog-id=18 op=UNLOAD Oct 29 04:39:26.700000 audit: BPF prog-id=19 op=UNLOAD Oct 29 04:39:26.701000 audit: BPF prog-id=23 op=LOAD Oct 29 04:39:26.701000 audit: BPF prog-id=20 op=UNLOAD Oct 29 04:39:26.701000 audit: BPF prog-id=24 op=LOAD Oct 29 04:39:26.701000 audit: BPF prog-id=25 op=LOAD Oct 29 04:39:26.701000 audit: BPF prog-id=21 op=UNLOAD Oct 29 04:39:26.701000 audit: BPF prog-id=22 op=UNLOAD Oct 29 04:39:26.715000 audit: BPF prog-id=23 op=UNLOAD Oct 29 04:39:26.815000 audit: BPF prog-id=26 op=LOAD Oct 29 04:39:26.815000 audit: BPF prog-id=27 op=LOAD Oct 29 04:39:26.815000 audit: BPF prog-id=28 op=LOAD Oct 29 04:39:26.815000 audit: BPF prog-id=24 op=UNLOAD Oct 29 04:39:26.815000 audit: BPF prog-id=25 op=UNLOAD Oct 29 04:39:26.828000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 29 04:39:26.691162 systemd[1]: Queued start job for default target Multi-User System. Oct 29 04:39:24.878835 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 29 04:39:26.702074 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 29 04:39:24.879143 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 04:39:24.879164 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 04:39:24.879502 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 29 04:39:24.879515 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 29 04:39:24.879560 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 29 04:39:24.879574 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 29 04:39:24.879952 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 29 04:39:24.879993 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 04:39:24.880009 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 04:39:24.880443 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 29 04:39:24.880477 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 29 04:39:24.880498 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 29 04:39:24.880514 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 29 04:39:24.880638 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 29 04:39:24.880656 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:24Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 29 04:39:26.594065 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:26Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 04:39:26.594394 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:26Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 04:39:26.594508 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:26Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 04:39:26.594650 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:26Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 04:39:26.594748 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:26Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 29 04:39:26.594810 /usr/lib/systemd/system-generators/torcx-generator[643]: time="2025-10-29T04:39:26Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 29 04:39:26.833760 systemd[1]: Started Journal Service. Oct 29 04:39:26.834309 systemd[1]: Mounted Huge Pages File System. Oct 29 04:39:26.835271 systemd[1]: Mounted POSIX Message Queue File System. Oct 29 04:39:26.836289 systemd[1]: Mounted External Media Directory. Oct 29 04:39:26.837252 systemd[1]: Mounted Kernel Debug File System. Oct 29 04:39:26.838238 systemd[1]: Mounted Kernel Trace File System. Oct 29 04:39:26.839165 systemd[1]: Mounted Temporary Directory /tmp. Oct 29 04:39:26.840444 systemd[1]: Finished Create missing system files. Oct 29 04:39:26.841748 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 04:39:26.842986 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 29 04:39:26.843197 systemd[1]: Finished Load Kernel Module configfs. Oct 29 04:39:26.844321 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 04:39:26.844507 systemd[1]: Finished Load Kernel Module drm. Oct 29 04:39:26.845643 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 29 04:39:26.845840 systemd[1]: Finished Load Kernel Module fuse. Oct 29 04:39:26.847052 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 29 04:39:26.848467 systemd[1]: Finished Apply Kernel Variables. Oct 29 04:39:26.864726 systemd[1]: Mounting FUSE Control File System... Oct 29 04:39:26.866732 systemd[1]: Mounting Kernel Configuration File System... Oct 29 04:39:26.867799 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 29 04:39:26.868946 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 29 04:39:26.870089 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 29 04:39:26.871280 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 29 04:39:26.872565 systemd[1]: Starting Load/Save Random Seed... Oct 29 04:39:26.873599 systemd[1]: Condition check resulted in Create System Users being skipped. Oct 29 04:39:26.875315 systemd-journald[692]: Time spent on flushing to /var/log/journal/24c2f1393fab4f32a2d1741936dad9bf is 11.984ms for 667 entries. Oct 29 04:39:26.875315 systemd-journald[692]: System Journal (/var/log/journal/24c2f1393fab4f32a2d1741936dad9bf) is 8.0M, max 203.0M, 195.0M free. Oct 29 04:39:26.899000 audit: BPF prog-id=29 op=LOAD Oct 29 04:39:26.899000 audit: BPF prog-id=30 op=LOAD Oct 29 04:39:26.899000 audit: BPF prog-id=31 op=LOAD Oct 29 04:39:26.899000 audit: BPF prog-id=10 op=UNLOAD Oct 29 04:39:26.899000 audit: BPF prog-id=11 op=UNLOAD Oct 29 04:39:26.874785 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 04:39:26.878194 systemd[1]: Mounted FUSE Control File System. Oct 29 04:39:26.879364 systemd[1]: Mounted Kernel Configuration File System. Oct 29 04:39:26.880994 systemd[1]: Finished Load/Save Random Seed. Oct 29 04:39:26.882340 systemd[1]: Condition check resulted in First Boot Complete being skipped. Oct 29 04:39:26.883820 systemd[1]: Finished Coldplug All udev Devices. Oct 29 04:39:26.894309 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 29 04:39:26.897489 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 04:39:26.900185 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 04:39:26.904236 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 29 04:39:26.924966 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 04:39:26.933000 audit: BPF prog-id=32 op=LOAD Oct 29 04:39:26.934621 systemd[1]: Starting Network Configuration... Oct 29 04:39:26.959948 systemd-udevd[718]: Using default interface naming scheme 'v249'. Oct 29 04:39:26.968358 systemd-networkd[724]: lo: Link UP Oct 29 04:39:26.968369 systemd-networkd[724]: lo: Gained carrier Oct 29 04:39:26.968752 systemd-networkd[724]: Enumeration completed Oct 29 04:39:26.968860 systemd[1]: Started Network Configuration. Oct 29 04:39:26.970297 systemd-networkd[724]: eth0: Link UP Oct 29 04:39:26.975420 systemd-networkd[724]: eth0: Gained carrier Oct 29 04:39:26.982408 systemd-networkd[724]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Oct 29 04:39:26.984318 udevadm[704]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 29 04:39:27.009377 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 29 04:39:27.018293 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 04:39:27.026812 lvm[732]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 04:39:27.050337 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 04:39:27.051504 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 04:39:27.068249 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 04:39:27.071801 lvm[734]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 04:39:27.101407 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 04:39:27.102546 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 04:39:27.103644 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 29 04:39:27.103670 systemd[1]: Reached target Containers. Oct 29 04:39:27.114490 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 29 04:39:27.127896 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 29 04:39:27.143239 systemd[1]: Mounting /usr/share/oem... Oct 29 04:39:27.149820 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 29 04:39:27.149950 kernel: BTRFS info (device vda6): has skinny extents Oct 29 04:39:27.152359 systemd[1]: Mounted /usr/share/oem. Oct 29 04:39:27.153215 systemd[1]: Reached target Local File Systems. Oct 29 04:39:27.154169 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Oct 29 04:39:27.154547 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 29 04:39:27.154586 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 29 04:39:27.154617 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 29 04:39:27.155733 systemd[1]: Starting Create Volatile Files and Directories... Oct 29 04:39:27.161965 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 29 04:39:27.163031 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 29 04:39:27.196409 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 04:39:27.196423 systemd-tmpfiles[757]: Skipping /boot Oct 29 04:39:27.202429 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 04:39:27.202446 systemd-tmpfiles[757]: Skipping /boot Oct 29 04:39:27.232447 systemd[1]: Finished Create Volatile Files and Directories. Oct 29 04:39:27.243444 systemd[1]: Starting Load Security Auditing Rules... Oct 29 04:39:27.245444 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 29 04:39:27.246575 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Oct 29 04:39:27.247000 audit: BPF prog-id=33 op=LOAD Oct 29 04:39:27.248217 systemd[1]: Starting Network Name Resolution... Oct 29 04:39:27.249000 audit: BPF prog-id=34 op=LOAD Oct 29 04:39:27.251167 systemd[1]: Starting Network Time Synchronization... Oct 29 04:39:27.252164 systemd[1]: Condition check resulted in Update is Completed being skipped. Oct 29 04:39:27.253590 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 29 04:39:27.255136 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 29 04:39:27.256547 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 29 04:39:27.266630 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 29 04:39:27.273000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 29 04:39:27.273921 augenrules[778]: No rules Oct 29 04:39:27.281637 systemd[1]: Finished Load Security Auditing Rules. Oct 29 04:39:26.492700 systemd-timesyncd[767]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 29 04:39:26.496711 systemd-resolved[761]: Positive Trust Anchors: Oct 29 04:39:26.496720 systemd-resolved[761]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 04:39:26.496747 systemd-resolved[761]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 29 04:39:26.498945 systemd-resolved[761]: Defaulting to hostname 'linux'. Oct 29 04:39:26.501821 systemd[1]: Started Network Time Synchronization. Oct 29 04:39:26.503084 systemd[1]: Started Network Name Resolution. Oct 29 04:39:26.504099 systemd[1]: Reached target Network. Oct 29 04:39:26.504914 systemd[1]: Reached target Host and Network Name Lookups. Oct 29 04:39:26.506031 systemd[1]: Reached target System Initialization. Oct 29 04:39:26.507171 systemd[1]: Started Watch for update engine configuration changes. Oct 29 04:39:26.510848 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 04:39:26.512032 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 29 04:39:26.513120 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 29 04:39:26.513155 systemd[1]: Reached target Path Units. Oct 29 04:39:26.513946 systemd[1]: Reached target System Time Set. Oct 29 04:39:26.515164 systemd[1]: Started Daily Log Rotation. Oct 29 04:39:26.516070 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 29 04:39:26.517204 systemd[1]: Reached target Timer Units. Oct 29 04:39:26.518400 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 29 04:39:26.532366 systemd[1]: Starting Docker Socket for the API... Oct 29 04:39:26.536042 systemd[1]: Listening on OpenSSH Server Socket. Oct 29 04:39:26.537495 systemd[1]: Listening on Docker Socket for the API. Oct 29 04:39:26.538558 systemd[1]: Reached target Socket Units. Oct 29 04:39:26.539415 systemd[1]: Reached target Basic System. Oct 29 04:39:26.540291 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 29 04:39:26.540326 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 29 04:39:26.541491 systemd[1]: Started D-Bus System Message Bus. Oct 29 04:39:26.545599 systemd[1]: Starting Extend Filesystems... Oct 29 04:39:26.546553 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 29 04:39:26.547756 systemd[1]: Starting Generate /run/flatcar/motd... Oct 29 04:39:26.549773 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 29 04:39:26.552338 systemd[1]: Starting Generate sshd host keys... Oct 29 04:39:26.553293 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 29 04:39:26.553336 systemd[1]: Reached target Load system-provided cloud configs. Oct 29 04:39:26.556149 extend-filesystems[789]: Found vda Oct 29 04:39:26.557236 extend-filesystems[789]: Found vda1 Oct 29 04:39:26.557236 extend-filesystems[789]: Found vda2 Oct 29 04:39:26.557236 extend-filesystems[789]: Found vda3 Oct 29 04:39:26.557236 extend-filesystems[789]: Found usr Oct 29 04:39:26.557236 extend-filesystems[789]: Found vda4 Oct 29 04:39:26.557236 extend-filesystems[789]: Found vda6 Oct 29 04:39:26.557236 extend-filesystems[789]: Found vda7 Oct 29 04:39:26.557236 extend-filesystems[789]: Found vda9 Oct 29 04:39:26.557236 extend-filesystems[789]: Checking size of /dev/vda9 Oct 29 04:39:26.556595 systemd[1]: Starting User Login Management... Oct 29 04:39:26.594548 extend-filesystems[789]: Old size kept for /dev/vda9 Oct 29 04:39:26.558531 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 29 04:39:26.559310 systemd[1]: Starting Update Engine... Oct 29 04:39:26.562210 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 29 04:39:26.562243 systemd[1]: Reached target Load user-provided cloud configs. Oct 29 04:39:26.564237 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 29 04:39:26.564492 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 29 04:39:26.568605 systemd[1]: motdgen.service: Deactivated successfully. Oct 29 04:39:26.568869 systemd[1]: Finished Generate /run/flatcar/motd. Oct 29 04:39:26.582934 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 29 04:39:26.583182 systemd[1]: Finished Extend Filesystems. Oct 29 04:39:26.608113 systemd-logind[799]: Watching system buttons on /dev/input/event0 (Power Button) Oct 29 04:39:26.608349 systemd-logind[799]: New seat seat0. Oct 29 04:39:26.611973 systemd[1]: Started User Login Management. Oct 29 04:39:26.613422 systemd[1]: Finished Generate sshd host keys. Oct 29 04:39:26.617560 update_engine[802]: I1029 04:39:26.617277 802 main.cc:89] Flatcar Update Engine starting Oct 29 04:39:26.625839 update_engine[802]: I1029 04:39:26.618160 802 payload_state.cc:360] Current Response Signature = Oct 29 04:39:26.625839 update_engine[802]: NumURLs = 1 Oct 29 04:39:26.625839 update_engine[802]: Url0 = http://10.0.0.2:34567/packages/update.gz Oct 29 04:39:26.625839 update_engine[802]: Payload Size = 439474134 Oct 29 04:39:26.625839 update_engine[802]: Payload Sha256 Hash = 1W6DmQyJ99PNHJd8mThbRqG9pYJ4dSvmM+KW8Iw2VW0= Oct 29 04:39:26.625839 update_engine[802]: Is Delta Payload = 0 Oct 29 04:39:26.625839 update_engine[802]: Max Failure Count Per Url = 10 Oct 29 04:39:26.625839 update_engine[802]: Disable Payload Backoff = 1 Oct 29 04:39:26.625839 update_engine[802]: I1029 04:39:26.618345 802 payload_state.cc:381] Payload Attempt Number = 0 Oct 29 04:39:26.625839 update_engine[802]: I1029 04:39:26.618481 802 payload_state.cc:404] Current URL Index = 0 Oct 29 04:39:26.625839 update_engine[802]: I1029 04:39:26.618643 802 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 29 04:39:26.625839 update_engine[802]: I1029 04:39:26.618780 802 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 29 04:39:26.625839 update_engine[802]: I1029 04:39:26.620929 802 update_check_scheduler.cc:74] Next update check in 7m57s Oct 29 04:39:26.626806 systemd[1]: Starting Generate /run/issue... Oct 29 04:39:26.627792 systemd[1]: Started Update Engine. Oct 29 04:39:26.631314 systemd[1]: issuegen.service: Deactivated successfully. Oct 29 04:39:26.631574 systemd[1]: Finished Generate /run/issue. Oct 29 04:39:26.633922 systemd[1]: Starting Permit User Sessions... Oct 29 04:39:26.638793 systemd[1]: Finished Permit User Sessions. Oct 29 04:39:26.641271 systemd[1]: Started Getty on tty1. Oct 29 04:39:26.643454 systemd[1]: Started Serial Getty on ttyAMA0. Oct 29 04:39:26.644584 systemd[1]: Reached target Login Prompts. Oct 29 04:39:26.645603 systemd[1]: Reached target Multi-User System. Oct 29 04:39:26.647843 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 29 04:39:26.653305 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 29 04:39:26.653561 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 29 04:39:26.654774 systemd[1]: Startup finished in 716ms (kernel) + 1.214s (initrd) + 2.677s (userspace) = 4.608s. Oct 29 04:39:27.162765 systemd[1]: Created slice Slice /system/sshd. Oct 29 04:39:27.163843 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53814). Oct 29 04:39:27.207977 sshd[829]: Accepted publickey for core from 10.0.0.1 port 53814 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:27.211793 sshd[829]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:27.229157 systemd[1]: Created slice User Slice of UID 500. Oct 29 04:39:27.230244 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 29 04:39:27.231878 systemd-logind[799]: New session 1 of user core. Oct 29 04:39:27.236494 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 29 04:39:27.237790 systemd[1]: Starting User Manager for UID 500... Oct 29 04:39:27.240661 systemd[832]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:27.292670 systemd[832]: Queued start job for default target Main User Target. Oct 29 04:39:27.292832 systemd[832]: Reached target Paths. Oct 29 04:39:27.292846 systemd[832]: Reached target Sockets. Oct 29 04:39:27.292857 systemd[832]: Reached target Timers. Oct 29 04:39:27.292867 systemd[832]: Reached target Basic System. Oct 29 04:39:27.292905 systemd[832]: Reached target Main User Target. Oct 29 04:39:27.292917 systemd[832]: Startup finished in 48ms. Oct 29 04:39:27.293433 systemd[1]: Started User Manager for UID 500. Oct 29 04:39:27.309012 systemd[1]: Started Session 1 of User core. Oct 29 04:39:27.369084 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53822). Oct 29 04:39:27.409144 sshd[841]: Accepted publickey for core from 10.0.0.1 port 53822 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:27.410539 sshd[841]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:27.413945 systemd-logind[799]: New session 2 of user core. Oct 29 04:39:27.429878 systemd[1]: Started Session 2 of User core. Oct 29 04:39:27.482742 sshd[841]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:27.491312 systemd[1]: sshd@1-10.0.0.3:22-10.0.0.1:53822.service: Deactivated successfully. Oct 29 04:39:27.491947 systemd[1]: session-2.scope: Deactivated successfully. Oct 29 04:39:27.492603 systemd-logind[799]: Session 2 logged out. Waiting for processes to exit. Oct 29 04:39:27.493710 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53824). Oct 29 04:39:27.494395 systemd-logind[799]: Removed session 2. Oct 29 04:39:27.535033 sshd[847]: Accepted publickey for core from 10.0.0.1 port 53824 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:27.536157 sshd[847]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:27.539094 systemd-logind[799]: New session 3 of user core. Oct 29 04:39:27.545879 systemd[1]: Started Session 3 of User core. Oct 29 04:39:27.593782 sshd[847]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:27.606410 systemd[1]: sshd@2-10.0.0.3:22-10.0.0.1:53824.service: Deactivated successfully. Oct 29 04:39:27.607022 systemd[1]: session-3.scope: Deactivated successfully. Oct 29 04:39:27.607612 systemd-logind[799]: Session 3 logged out. Waiting for processes to exit. Oct 29 04:39:27.608709 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53830). Oct 29 04:39:27.609366 systemd-logind[799]: Removed session 3. Oct 29 04:39:27.648996 sshd[853]: Accepted publickey for core from 10.0.0.1 port 53830 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:27.649964 sshd[853]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:27.652609 systemd-logind[799]: New session 4 of user core. Oct 29 04:39:27.668828 systemd[1]: Started Session 4 of User core. Oct 29 04:39:27.720025 sshd[853]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:27.728290 systemd[1]: sshd@3-10.0.0.3:22-10.0.0.1:53830.service: Deactivated successfully. Oct 29 04:39:27.728922 systemd[1]: session-4.scope: Deactivated successfully. Oct 29 04:39:27.729537 systemd-logind[799]: Session 4 logged out. Waiting for processes to exit. Oct 29 04:39:27.730594 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53836). Oct 29 04:39:27.731287 systemd-logind[799]: Removed session 4. Oct 29 04:39:27.771220 sshd[859]: Accepted publickey for core from 10.0.0.1 port 53836 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:27.772206 sshd[859]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:27.775231 systemd-logind[799]: New session 5 of user core. Oct 29 04:39:27.784828 systemd[1]: Started Session 5 of User core. Oct 29 04:39:27.842319 sudo[862]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 29 04:39:27.842551 sudo[862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 04:39:27.854996 sudo[862]: pam_unix(sudo:session): session closed for user root Oct 29 04:39:27.856833 sshd[859]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:27.868665 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53840). Oct 29 04:39:27.870007 dbus-daemon[788]: [system] Reloaded configuration Oct 29 04:39:27.872981 systemd[1]: sshd@4-10.0.0.3:22-10.0.0.1:53836.service: Deactivated successfully. Oct 29 04:39:27.873783 systemd[1]: session-5.scope: Deactivated successfully. Oct 29 04:39:27.874541 systemd-logind[799]: Session 5 logged out. Waiting for processes to exit. Oct 29 04:39:27.875239 systemd-logind[799]: Removed session 5. Oct 29 04:39:27.909332 sshd[865]: Accepted publickey for core from 10.0.0.1 port 53840 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:27.910811 sshd[865]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:27.913630 systemd-logind[799]: New session 6 of user core. Oct 29 04:39:27.919854 systemd[1]: Started Session 6 of User core. Oct 29 04:39:27.971183 sudo[870]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 29 04:39:27.971400 sudo[870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 04:39:27.974251 sudo[870]: pam_unix(sudo:session): session closed for user root Oct 29 04:39:27.978253 sudo[869]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 29 04:39:27.978472 sudo[869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 04:39:27.998693 systemd[1]: Stopping Load Security Auditing Rules... Oct 29 04:39:27.999000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 29 04:39:27.999888 auditctl[873]: No rules Oct 29 04:39:28.000892 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 04:39:28.001241 systemd[1]: Stopped Load Security Auditing Rules. Oct 29 04:39:28.002351 kernel: kauditd_printk_skb: 49 callbacks suppressed Oct 29 04:39:28.002399 kernel: audit: type=1305 audit(1761712767.999:60): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 29 04:39:28.003092 systemd[1]: Starting Load Security Auditing Rules... Oct 29 04:39:28.018474 augenrules[890]: No rules Oct 29 04:39:28.019201 systemd[1]: Finished Load Security Auditing Rules. Oct 29 04:39:28.020120 sudo[869]: pam_unix(sudo:session): session closed for user root Oct 29 04:39:28.021259 sshd[865]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:28.034424 systemd[1]: sshd@5-10.0.0.3:22-10.0.0.1:53840.service: Deactivated successfully. Oct 29 04:39:28.035043 systemd[1]: session-6.scope: Deactivated successfully. Oct 29 04:39:28.035609 systemd-logind[799]: Session 6 logged out. Waiting for processes to exit. Oct 29 04:39:28.036711 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53846). Oct 29 04:39:28.037321 systemd-logind[799]: Removed session 6. Oct 29 04:39:28.077722 sshd[896]: Accepted publickey for core from 10.0.0.1 port 53846 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:28.079072 sshd[896]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:28.081864 kernel: audit: type=1006 audit(1761712768.078:61): pid=896 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=7 res=1 Oct 29 04:39:28.082078 systemd-logind[799]: New session 7 of user core. Oct 29 04:39:28.090856 systemd[1]: Started Session 7 of User core. Oct 29 04:39:28.094076 systemd-networkd[724]: eth0: Gained IPv6LL Oct 29 04:39:28.139659 sshd[896]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:28.148468 systemd[1]: sshd@6-10.0.0.3:22-10.0.0.1:53846.service: Deactivated successfully. Oct 29 04:39:28.149094 systemd[1]: session-7.scope: Deactivated successfully. Oct 29 04:39:28.149708 systemd-logind[799]: Session 7 logged out. Waiting for processes to exit. Oct 29 04:39:28.150854 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53862). Oct 29 04:39:28.151578 systemd-logind[799]: Removed session 7. Oct 29 04:39:28.192117 sshd[902]: Accepted publickey for core from 10.0.0.1 port 53862 ssh2: RSA SHA256:sr+5Mm6tQgMVqPGtSknwMSt64oUS6sHSaN8Y/Pn+uY4 Oct 29 04:39:28.195649 kernel: audit: type=1006 audit(1761712768.192:62): pid=902 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=8 res=1 Oct 29 04:39:28.195902 sshd[902]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 04:39:28.198555 systemd-logind[799]: New session 8 of user core. Oct 29 04:39:28.209807 systemd[1]: Started Session 8 of User core. Oct 29 04:39:28.258218 sshd[902]: pam_unix(sshd:session): session closed for user core Oct 29 04:39:28.260300 systemd[1]: sshd@7-10.0.0.3:22-10.0.0.1:53862.service: Deactivated successfully. Oct 29 04:39:28.260970 systemd[1]: session-8.scope: Deactivated successfully. Oct 29 04:39:28.261541 systemd-logind[799]: Session 8 logged out. Waiting for processes to exit. Oct 29 04:39:28.262179 systemd-logind[799]: Removed session 8.