Oct 29 19:51:31.688204 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 29 19:51:31.688220 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 19:51:31.688229 kernel: efi: EFI v2.70 by EDK II Oct 29 19:51:31.688234 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 29 19:51:31.688239 kernel: efi: seeding entropy pool Oct 29 19:51:31.688244 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 29 19:51:31.688250 kernel: ACPI: Early table checksum verification disabled Oct 29 19:51:31.688255 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 29 19:51:31.688259 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 29 19:51:31.688264 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:51:31.688271 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:51:31.688276 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:51:31.688281 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:51:31.688286 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:51:31.688292 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:51:31.688297 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:51:31.688304 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:51:31.688309 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:51:31.688315 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 29 19:51:31.688320 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 29 19:51:31.688325 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 19:51:31.688330 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 29 19:51:31.688336 kernel: Zone ranges: Oct 29 19:51:31.688341 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 19:51:31.688346 kernel: DMA32 empty Oct 29 19:51:31.688351 kernel: Normal empty Oct 29 19:51:31.688356 kernel: Movable zone start for each node Oct 29 19:51:31.688363 kernel: Early memory node ranges Oct 29 19:51:31.688368 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 29 19:51:31.688373 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 29 19:51:31.688378 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 29 19:51:31.688383 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 29 19:51:31.688389 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 29 19:51:31.688394 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 29 19:51:31.688399 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 29 19:51:31.688404 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 29 19:51:31.688409 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 29 19:51:31.688414 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 29 19:51:31.688419 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 29 19:51:31.688425 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 19:51:31.688434 kernel: On node 0 totalpages: 643072 Oct 29 19:51:31.688439 kernel: DMA zone: 10048 pages used for memmap Oct 29 19:51:31.688445 kernel: DMA zone: 0 pages reserved Oct 29 19:51:31.688451 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 29 19:51:31.688456 kernel: psci: probing for conduit method from ACPI. Oct 29 19:51:31.688462 kernel: psci: PSCIv1.1 detected in firmware. Oct 29 19:51:31.688467 kernel: psci: Using standard PSCI v0.2 function IDs Oct 29 19:51:31.688473 kernel: psci: Trusted OS migration not required Oct 29 19:51:31.688479 kernel: psci: SMC Calling Convention v1.1 Oct 29 19:51:31.688485 kernel: ACPI: SRAT not present Oct 29 19:51:31.688490 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 29 19:51:31.688496 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 29 19:51:31.688502 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 29 19:51:31.688507 kernel: Detected PIPT I-cache on CPU0 Oct 29 19:51:31.688513 kernel: CPU features: detected: GIC system register CPU interface Oct 29 19:51:31.688518 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 29 19:51:31.688524 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 29 19:51:31.688529 kernel: CPU features: detected: Hardware dirty bit management Oct 29 19:51:31.688535 kernel: CPU features: detected: Spectre-v4 Oct 29 19:51:31.688542 kernel: CPU features: detected: ARM erratum 1418040 Oct 29 19:51:31.688547 kernel: CPU features: detected: Spectre-BHB Oct 29 19:51:31.688553 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 29 19:51:31.688558 kernel: Policy zone: DMA Oct 29 19:51:31.688565 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 19:51:31.688571 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 29 19:51:31.688577 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 29 19:51:31.688582 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 29 19:51:31.688588 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 29 19:51:31.688594 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 29 19:51:31.688599 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 29 19:51:31.688606 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 29 19:51:31.688612 kernel: rcu: RCU event tracing is enabled. Oct 29 19:51:31.688618 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 29 19:51:31.688623 kernel: Trampoline variant of Tasks RCU enabled. Oct 29 19:51:31.688629 kernel: Tracing variant of Tasks RCU enabled. Oct 29 19:51:31.688635 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 29 19:51:31.688640 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 29 19:51:31.688646 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 29 19:51:31.688651 kernel: GICv3: 256 SPIs implemented Oct 29 19:51:31.688657 kernel: GICv3: 0 Extended SPIs implemented Oct 29 19:51:31.688662 kernel: GICv3: Distributor has no Range Selector support Oct 29 19:51:31.688668 kernel: GICv3: 16 PPIs implemented Oct 29 19:51:31.688675 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 29 19:51:31.688680 kernel: ACPI: SRAT not present Oct 29 19:51:31.688685 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 29 19:51:31.688691 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 29 19:51:31.688697 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 29 19:51:31.688702 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 29 19:51:31.688708 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 29 19:51:31.688714 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 19:51:31.688719 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 29 19:51:31.688725 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 29 19:51:31.688731 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 29 19:51:31.688737 kernel: arm-pv: using stolen time PV Oct 29 19:51:31.688744 kernel: Console: colour dummy device 80x25 Oct 29 19:51:31.688749 kernel: ACPI: Core revision 20200925 Oct 29 19:51:31.688755 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 29 19:51:31.688761 kernel: pid_max: default: 32768 minimum: 301 Oct 29 19:51:31.688767 kernel: LSM: Security Framework initializing Oct 29 19:51:31.688773 kernel: SELinux: Initializing. Oct 29 19:51:31.688778 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 19:51:31.688784 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 19:51:31.688790 kernel: rcu: Hierarchical SRCU implementation. Oct 29 19:51:31.688797 kernel: Platform MSI: ITS@0x8080000 domain created Oct 29 19:51:31.688803 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 29 19:51:31.688808 kernel: Remapping and enabling EFI services. Oct 29 19:51:31.688814 kernel: smp: Bringing up secondary CPUs ... Oct 29 19:51:31.688819 kernel: Detected PIPT I-cache on CPU1 Oct 29 19:51:31.688825 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 29 19:51:31.688831 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 29 19:51:31.688837 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 19:51:31.688843 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 29 19:51:31.688849 kernel: Detected PIPT I-cache on CPU2 Oct 29 19:51:31.688856 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 29 19:51:31.688862 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 29 19:51:31.688867 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 19:51:31.688873 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 29 19:51:31.688879 kernel: Detected PIPT I-cache on CPU3 Oct 29 19:51:31.688889 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 29 19:51:31.688895 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 29 19:51:31.688902 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 19:51:31.688908 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 29 19:51:31.688914 kernel: smp: Brought up 1 node, 4 CPUs Oct 29 19:51:31.688920 kernel: SMP: Total of 4 processors activated. Oct 29 19:51:31.688926 kernel: CPU features: detected: Privileged Access Never Oct 29 19:51:31.688932 kernel: CPU features: detected: LSE atomic instructions Oct 29 19:51:31.688938 kernel: CPU features: detected: User Access Override Oct 29 19:51:31.688944 kernel: CPU features: detected: 32-bit EL0 Support Oct 29 19:51:31.688951 kernel: CPU features: detected: Common not Private translations Oct 29 19:51:31.688967 kernel: CPU features: detected: RAS Extension Support Oct 29 19:51:31.688989 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 29 19:51:31.688997 kernel: CPU features: detected: CRC32 instructions Oct 29 19:51:31.689003 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 29 19:51:31.689009 kernel: CPU: All CPU(s) started at EL1 Oct 29 19:51:31.689015 kernel: alternatives: patching kernel code Oct 29 19:51:31.689022 kernel: devtmpfs: initialized Oct 29 19:51:31.689028 kernel: KASLR enabled Oct 29 19:51:31.689035 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 29 19:51:31.689041 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 29 19:51:31.689047 kernel: pinctrl core: initialized pinctrl subsystem Oct 29 19:51:31.689053 kernel: SMBIOS 3.0.0 present. Oct 29 19:51:31.689059 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 29 19:51:31.689065 kernel: NET: Registered protocol family 16 Oct 29 19:51:31.689071 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 29 19:51:31.689077 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 29 19:51:31.689085 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 29 19:51:31.689091 kernel: audit: initializing netlink subsys (disabled) Oct 29 19:51:31.689098 kernel: audit: type=2000 audit(0.033:1): state=initialized audit_enabled=0 res=1 Oct 29 19:51:31.689104 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 29 19:51:31.689115 kernel: cpuidle: using governor menu Oct 29 19:51:31.689122 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 29 19:51:31.689128 kernel: ASID allocator initialised with 32768 entries Oct 29 19:51:31.689134 kernel: ACPI: bus type PCI registered Oct 29 19:51:31.689140 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 29 19:51:31.689147 kernel: Serial: AMBA PL011 UART driver Oct 29 19:51:31.689154 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 29 19:51:31.689161 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 29 19:51:31.689167 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 29 19:51:31.689173 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 29 19:51:31.689179 kernel: cryptd: max_cpu_qlen set to 1000 Oct 29 19:51:31.689185 kernel: ACPI: Added _OSI(Module Device) Oct 29 19:51:31.689191 kernel: ACPI: Added _OSI(Processor Device) Oct 29 19:51:31.689197 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 29 19:51:31.689205 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 29 19:51:31.689211 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 29 19:51:31.689217 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 29 19:51:31.689223 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 29 19:51:31.689229 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 29 19:51:31.689236 kernel: ACPI: Interpreter enabled Oct 29 19:51:31.689242 kernel: ACPI: Using GIC for interrupt routing Oct 29 19:51:31.689248 kernel: ACPI: MCFG table detected, 1 entries Oct 29 19:51:31.689254 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 29 19:51:31.689260 kernel: printk: console [ttyAMA0] enabled Oct 29 19:51:31.689267 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 29 19:51:31.689378 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 29 19:51:31.689437 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 29 19:51:31.689492 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 29 19:51:31.689546 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 29 19:51:31.689598 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 29 19:51:31.689606 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 29 19:51:31.689615 kernel: PCI host bridge to bus 0000:00 Oct 29 19:51:31.689678 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 29 19:51:31.689728 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 29 19:51:31.689777 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 29 19:51:31.689825 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 29 19:51:31.689892 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 29 19:51:31.689964 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 29 19:51:31.690025 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 29 19:51:31.690083 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 29 19:51:31.690147 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 19:51:31.690205 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 19:51:31.690261 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 29 19:51:31.690317 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 29 19:51:31.690367 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 29 19:51:31.690418 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 29 19:51:31.690467 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 29 19:51:31.690475 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 29 19:51:31.690481 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 29 19:51:31.690487 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 29 19:51:31.690494 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 29 19:51:31.690500 kernel: vgaarb: loaded Oct 29 19:51:31.690506 kernel: Registered efivars operations Oct 29 19:51:31.690512 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 29 19:51:31.690519 kernel: VFS: Disk quotas dquot_6.6.0 Oct 29 19:51:31.690526 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 29 19:51:31.690532 kernel: pnp: PnP ACPI init Oct 29 19:51:31.690596 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 29 19:51:31.690647 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 29 19:51:31.690655 kernel: pnp: PnP ACPI: found 1 devices Oct 29 19:51:31.690662 kernel: NET: Registered protocol family 2 Oct 29 19:51:31.690668 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 29 19:51:31.690675 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 29 19:51:31.690682 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 29 19:51:31.690688 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 29 19:51:31.690694 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 29 19:51:31.690700 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 19:51:31.690707 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 19:51:31.690713 kernel: NET: Registered protocol family 1 Oct 29 19:51:31.690719 kernel: PCI: CLS 0 bytes, default 64 Oct 29 19:51:31.690725 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 29 19:51:31.690732 kernel: kvm [1]: HYP mode not available Oct 29 19:51:31.690738 kernel: Initialise system trusted keyrings Oct 29 19:51:31.690744 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 29 19:51:31.690750 kernel: Key type asymmetric registered Oct 29 19:51:31.690756 kernel: Asymmetric key parser 'x509' registered Oct 29 19:51:31.690762 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 29 19:51:31.690769 kernel: io scheduler mq-deadline registered Oct 29 19:51:31.690775 kernel: io scheduler kyber registered Oct 29 19:51:31.690781 kernel: io scheduler bfq registered Oct 29 19:51:31.690788 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 29 19:51:31.690794 kernel: ACPI: Power Button [PWRB] Oct 29 19:51:31.690800 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 29 19:51:31.690855 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 29 19:51:31.690863 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 29 19:51:31.690869 kernel: msm_serial: driver initialized Oct 29 19:51:31.690875 kernel: random: fast init done Oct 29 19:51:31.690881 kernel: random: crng init done Oct 29 19:51:31.690887 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 29 19:51:31.690894 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 29 19:51:31.690901 kernel: thunder_xcv, ver 1.0 Oct 29 19:51:31.690907 kernel: thunder_bgx, ver 1.0 Oct 29 19:51:31.690913 kernel: nicpf, ver 1.0 Oct 29 19:51:31.690919 kernel: nicvf, ver 1.0 Oct 29 19:51:31.691004 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 29 19:51:31.691056 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-29T19:51:31 UTC (1761767491) Oct 29 19:51:31.691065 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 29 19:51:31.691071 kernel: NET: Registered protocol family 10 Oct 29 19:51:31.691079 kernel: Segment Routing with IPv6 Oct 29 19:51:31.691085 kernel: NET: Registered protocol family 17 Oct 29 19:51:31.691091 kernel: Key type dns_resolver registered Oct 29 19:51:31.691096 kernel: registered taskstats version 1 Oct 29 19:51:31.691103 kernel: Loading compiled-in X.509 certificates Oct 29 19:51:31.691115 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 29 19:51:31.691121 kernel: Key type ._fscrypt registered Oct 29 19:51:31.691127 kernel: Key type .fscrypt registered Oct 29 19:51:31.691133 kernel: Key type fscrypt-provisioning registered Oct 29 19:51:31.691141 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 29 19:51:31.691147 kernel: ima: Allocated hash algorithm: sha1 Oct 29 19:51:31.691154 kernel: ima: No architecture policies found Oct 29 19:51:31.691160 kernel: Freeing unused kernel memory: 34240K Oct 29 19:51:31.691166 kernel: Run /init as init process Oct 29 19:51:31.691172 kernel: with arguments: Oct 29 19:51:31.691177 kernel: /init Oct 29 19:51:31.691183 kernel: with environment: Oct 29 19:51:31.691190 kernel: HOME=/ Oct 29 19:51:31.691195 kernel: TERM=linux Oct 29 19:51:31.691202 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 29 19:51:31.691210 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 19:51:31.691219 systemd[1]: Detected virtualization kvm. Oct 29 19:51:31.691225 systemd[1]: Detected architecture arm64. Oct 29 19:51:31.691232 systemd[1]: Running in initial RAM disk. Oct 29 19:51:31.691238 systemd[1]: No hostname configured, using default hostname. Oct 29 19:51:31.691245 systemd[1]: Hostname set to . Oct 29 19:51:31.691253 systemd[1]: Initializing machine ID from VM UUID. Oct 29 19:51:31.691260 systemd[1]: Queued start job for default target Initrd Default Target. Oct 29 19:51:31.691266 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 19:51:31.691273 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 19:51:31.691279 systemd[1]: Reached target Path Units. Oct 29 19:51:31.691285 systemd[1]: Reached target Slice Units. Oct 29 19:51:31.691292 systemd[1]: Reached target Swaps. Oct 29 19:51:31.691298 systemd[1]: Reached target Timer Units. Oct 29 19:51:31.691306 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 29 19:51:31.691312 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 29 19:51:31.691319 systemd[1]: Listening on Journal Audit Socket. Oct 29 19:51:31.691325 systemd[1]: Listening on Journal Socket (/dev/log). Oct 29 19:51:31.691332 systemd[1]: Listening on Journal Socket. Oct 29 19:51:31.691338 systemd[1]: Listening on Network Service Netlink Socket. Oct 29 19:51:31.691345 systemd[1]: Listening on udev Control Socket. Oct 29 19:51:31.691351 systemd[1]: Listening on udev Kernel Socket. Oct 29 19:51:31.691358 systemd[1]: Reached target Socket Units. Oct 29 19:51:31.691366 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 19:51:31.691372 systemd[1]: Finished Network Cleanup. Oct 29 19:51:31.691379 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 29 19:51:31.691385 systemd[1]: Starting Journal Service... Oct 29 19:51:31.691391 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 19:51:31.691398 systemd[1]: Starting Apply Kernel Variables... Oct 29 19:51:31.691404 systemd[1]: Starting Setup Virtual Console... Oct 29 19:51:31.691411 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 19:51:31.691417 systemd[1]: Finished Apply Kernel Variables. Oct 29 19:51:31.691425 systemd[1]: Finished Setup Virtual Console. Oct 29 19:51:31.691431 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 29 19:51:31.691440 systemd-journald[278]: Journal started Oct 29 19:51:31.691479 systemd-journald[278]: Runtime Journal (/run/log/journal/2c84dc9bbd9a46a29f3a296e04c5ff21) is 6.0M, max 48.5M, 42.4M free. Oct 29 19:51:31.695532 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 19:51:31.695577 systemd[1]: Started Journal Service. Oct 29 19:51:31.696265 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 19:51:31.702889 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 29 19:51:31.706090 systemd[1]: Starting dracut cmdline hook... Oct 29 19:51:31.715366 dracut-cmdline[296]: dracut-dracut-053 Oct 29 19:51:31.717862 dracut-cmdline[296]: 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 19:51:31.777994 kernel: SCSI subsystem initialized Oct 29 19:51:31.781971 kernel: Loading iSCSI transport class v2.0-870. Oct 29 19:51:31.787978 kernel: iscsi: registered transport (tcp) Oct 29 19:51:31.799346 kernel: iscsi: registered transport (qla4xxx) Oct 29 19:51:31.799366 kernel: QLogic iSCSI HBA Driver Oct 29 19:51:31.809788 systemd[1]: Finished dracut cmdline hook. Oct 29 19:51:31.811607 systemd[1]: Starting dracut pre-udev hook... Oct 29 19:51:31.825705 kernel: device-mapper: uevent: version 1.0.3 Oct 29 19:51:31.825757 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 29 19:51:31.862006 kernel: raid6: neonx8 gen() 13800 MB/s Oct 29 19:51:31.878995 kernel: raid6: neonx8 xor() 10746 MB/s Oct 29 19:51:31.896009 kernel: raid6: neonx4 gen() 13627 MB/s Oct 29 19:51:31.912994 kernel: raid6: neonx4 xor() 11571 MB/s Oct 29 19:51:31.929983 kernel: raid6: neonx2 gen() 12949 MB/s Oct 29 19:51:31.946988 kernel: raid6: neonx2 xor() 9881 MB/s Oct 29 19:51:31.963984 kernel: raid6: neonx1 gen() 10976 MB/s Oct 29 19:51:31.980977 kernel: raid6: neonx1 xor() 8834 MB/s Oct 29 19:51:31.997995 kernel: raid6: int64x8 gen() 6833 MB/s Oct 29 19:51:32.014984 kernel: raid6: int64x8 xor() 3796 MB/s Oct 29 19:51:32.031991 kernel: raid6: int64x4 gen() 6874 MB/s Oct 29 19:51:32.048987 kernel: raid6: int64x4 xor() 3831 MB/s Oct 29 19:51:32.065989 kernel: raid6: int64x2 gen() 6190 MB/s Oct 29 19:51:32.082996 kernel: raid6: int64x2 xor() 3313 MB/s Oct 29 19:51:32.100019 kernel: raid6: int64x1 gen() 5008 MB/s Oct 29 19:51:32.117164 kernel: raid6: int64x1 xor() 2597 MB/s Oct 29 19:51:32.117181 kernel: raid6: using algorithm neonx8 gen() 13800 MB/s Oct 29 19:51:32.117190 kernel: raid6: .... xor() 10746 MB/s, rmw enabled Oct 29 19:51:32.118349 kernel: raid6: using neon recovery algorithm Oct 29 19:51:32.127983 kernel: xor: measuring software checksum speed Oct 29 19:51:32.128979 kernel: 8regs : 17159 MB/sec Oct 29 19:51:32.129978 kernel: 32regs : 20760 MB/sec Oct 29 19:51:32.131480 kernel: arm64_neon : 29173 MB/sec Oct 29 19:51:32.131491 kernel: xor: using function: arm64_neon (29173 MB/sec) Oct 29 19:51:32.176984 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 29 19:51:32.187930 systemd[1]: Finished dracut pre-udev hook. Oct 29 19:51:32.188000 audit: BPF prog-id=6 op=LOAD Oct 29 19:51:32.189000 audit: BPF prog-id=7 op=LOAD Oct 29 19:51:32.189000 audit: BPF prog-id=8 op=LOAD Oct 29 19:51:32.190599 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 19:51:32.192822 kernel: audit: type=1334 audit(1761767492.188:2): prog-id=6 op=LOAD Oct 29 19:51:32.192850 kernel: audit: type=1334 audit(1761767492.189:3): prog-id=7 op=LOAD Oct 29 19:51:32.192859 kernel: audit: type=1334 audit(1761767492.189:4): prog-id=8 op=LOAD Oct 29 19:51:32.203307 systemd-udevd[418]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 19:51:32.205011 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 19:51:32.206753 systemd[1]: Starting dracut pre-trigger hook... Oct 29 19:51:32.211252 kernel: audit: type=1334 audit(1761767492.209:5): prog-id=9 op=LOAD Oct 29 19:51:32.209000 audit: BPF prog-id=9 op=LOAD Oct 29 19:51:32.211126 systemd[1]: Starting Network Configuration... Oct 29 19:51:32.219485 dracut-pre-trigger[424]: rd.md=0: removing MD RAID activation Oct 29 19:51:32.227536 systemd-networkd[436]: lo: Link UP Oct 29 19:51:32.227543 systemd-networkd[436]: lo: Gained carrier Oct 29 19:51:32.228000 audit: BPF prog-id=10 op=LOAD Oct 29 19:51:32.227830 systemd-networkd[436]: Enumeration completed Oct 29 19:51:32.231266 kernel: audit: type=1334 audit(1761767492.228:6): prog-id=10 op=LOAD Oct 29 19:51:32.227929 systemd[1]: Started Network Configuration. Oct 29 19:51:32.230063 systemd[1]: Starting Network Name Resolution... Oct 29 19:51:32.246040 systemd[1]: Finished dracut pre-trigger hook. Oct 29 19:51:32.247660 systemd[1]: Starting Coldplug All udev Devices... Oct 29 19:51:32.257977 systemd-udevd[418]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 19:51:32.261055 systemd-resolved[451]: Positive Trust Anchors: Oct 29 19:51:32.261071 systemd-resolved[451]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 19:51:32.261101 systemd-resolved[451]: 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 19:51:32.261387 systemd-resolved[451]: Defaulting to hostname 'linux'. Oct 29 19:51:32.267129 systemd[1]: Started Network Name Resolution. Oct 29 19:51:32.270450 systemd[1]: Reached target Network. Oct 29 19:51:32.271117 systemd[1]: Reached target Host and Network Name Lookups. Oct 29 19:51:32.273457 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 29 19:51:32.279111 systemd[1]: Finished Coldplug All udev Devices. Oct 29 19:51:32.280530 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 29 19:51:32.282173 systemd[1]: Starting Open-iSCSI... Oct 29 19:51:32.284639 iscsid[489]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 29 19:51:32.284639 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 19:51:32.284639 iscsid[489]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 29 19:51:32.284639 iscsid[489]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 29 19:51:32.284639 iscsid[489]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 29 19:51:32.284639 iscsid[489]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 29 19:51:32.288065 systemd[1]: Started Open-iSCSI. Oct 29 19:51:32.292670 systemd[1]: Starting dracut initqueue hook... Oct 29 19:51:32.316606 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 29 19:51:32.316729 kernel: vda: detected capacity change from 0 to 4756340736 Oct 29 19:51:32.324985 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 19:51:32.330045 systemd-udevd[482]: Using default interface naming scheme 'v249'. Oct 29 19:51:32.343969 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 29 19:51:32.347114 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (475) Oct 29 19:51:32.350083 systemd-networkd[436]: eth0: Link UP Oct 29 19:51:32.352056 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 29 19:51:32.354492 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 29 19:51:32.355566 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 29 19:51:32.359949 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 29 19:51:32.361093 systemd[1]: Finished dracut initqueue hook. Oct 29 19:51:32.362458 systemd[1]: Reached target Initrd Root Device. Oct 29 19:51:32.363755 systemd[1]: Reached target Preparation for Remote File Systems. Oct 29 19:51:32.365377 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 19:51:32.366974 systemd[1]: Reached target Remote File Systems. Oct 29 19:51:32.369186 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 29 19:51:32.371167 systemd[1]: Starting dracut pre-mount hook... Oct 29 19:51:32.379310 systemd[1]: Finished dracut pre-mount hook. Oct 29 19:51:32.392978 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 19:51:33.353040 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 29 19:51:33.353021 systemd-networkd[436]: eth0: Gained carrier Oct 29 19:51:33.363035 systemd-networkd[436]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 29 19:51:33.400995 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 19:51:33.401517 disk-uuid[513]: The operation has completed successfully. Oct 29 19:51:33.428033 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 29 19:51:33.428160 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 29 19:51:33.430275 systemd[1]: Starting Ignition (setup)... Oct 29 19:51:33.441977 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 29 19:51:33.442005 kernel: BTRFS info (device vda6): has skinny extents Oct 29 19:51:33.447896 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 29 19:51:33.453469 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 29 19:51:33.453590 systemd[1]: Finished Ignition (setup). Oct 29 19:51:33.455853 systemd[1]: Starting Ignition (disks)... Oct 29 19:51:33.465178 ignition[545]: Ignition v0.36.1-15-gde4e6cc9 Oct 29 19:51:33.465195 ignition[545]: Stage: disks Oct 29 19:51:33.465205 ignition[545]: reading system config file "/usr/lib/ignition/base.ign" Oct 29 19:51:33.465216 ignition[545]: no config at "/usr/lib/ignition/base.ign" Oct 29 19:51:33.465260 ignition[545]: parsed url from cmdline: "" Oct 29 19:51:33.465263 ignition[545]: no config URL provided Oct 29 19:51:33.465268 ignition[545]: reading system config file "/usr/lib/ignition/user.ign" Oct 29 19:51:33.465275 ignition[545]: no config at "/usr/lib/ignition/user.ign" Oct 29 19:51:33.465295 ignition[545]: op(1): [started] loading QEMU firmware config module Oct 29 19:51:33.465299 ignition[545]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 29 19:51:33.466291 ignition[545]: op(1): [finished] loading QEMU firmware config module Oct 29 19:51:33.480551 ignition[545]: parsing config with SHA512: dd1a46caf8eaf1c85b779b3360809504b4a60c446f401a5886368963ef21aba506e3af5ec2b6956e9964a3d4c56da11cd741e0873af765c2062c6af2f3e819fc Oct 29 19:51:33.482678 ignition[545]: disks: disks passed Oct 29 19:51:33.482690 ignition[545]: Ignition finished successfully Oct 29 19:51:33.483279 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 29 19:51:33.483393 systemd[1]: Finished Ignition (disks). Oct 29 19:51:33.484865 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 19:51:33.487210 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 29 19:51:33.489548 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 29 19:51:33.497459 systemd-fsck[558]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 29 19:51:33.502051 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 29 19:51:33.502515 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 29 19:51:33.504409 systemd[1]: Mounting /sysroot... Oct 29 19:51:33.521878 systemd[1]: Found device /dev/mapper/usr. Oct 29 19:51:33.523522 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 29 19:51:33.524565 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 29 19:51:33.533515 systemd-fsck[572]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 29 19:51:33.533515 systemd-fsck[572]: You must have r/w access to the filesystem or be root Oct 29 19:51:33.537979 systemd-fsck[570]: fsck failed with exit status 8. Oct 29 19:51:33.537987 systemd-fsck[570]: Ignoring error. Oct 29 19:51:33.539224 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 29 19:51:33.541445 systemd[1]: Mounting /sysusr/usr... Oct 29 19:51:33.546789 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 29 19:51:33.546821 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 29 19:51:33.547288 systemd[1]: Mounted /sysroot. Oct 29 19:51:33.548136 systemd[1]: Reached target Initrd Root File System. Oct 29 19:51:33.551724 systemd[1]: Mounted /sysusr/usr. Oct 29 19:51:33.553013 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 29 19:51:33.552547 systemd[1]: Reached target Local File Systems. Oct 29 19:51:33.553753 systemd[1]: Reached target System Initialization. Oct 29 19:51:33.555205 systemd[1]: Reached target Basic System. Oct 29 19:51:33.557751 systemd[1]: Mounting /sysroot/usr... Oct 29 19:51:33.559762 systemd[1]: Mounted /sysroot/usr. Oct 29 19:51:33.561124 systemd[1]: Starting Root filesystem setup... Oct 29 19:51:33.586565 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 29 19:51:33.586702 systemd[1]: Finished Root filesystem setup. Oct 29 19:51:33.589292 systemd[1]: Starting Ignition (files)... Oct 29 19:51:33.590828 systemd[1]: Starting /sysroot/boot... Oct 29 19:51:33.593636 ignition[588]: Ignition v0.36.1-15-gde4e6cc9 Oct 29 19:51:33.593655 ignition[588]: Stage: files Oct 29 19:51:33.593665 ignition[588]: reading system config file "/usr/lib/ignition/base.ign" Oct 29 19:51:33.593676 ignition[588]: no config at "/usr/lib/ignition/base.ign" Oct 29 19:51:33.594231 ignition[588]: files: compiled without relabeling support, skipping Oct 29 19:51:33.609705 systemd[1]: Finished /sysroot/boot. Oct 29 19:51:33.612644 ignition[588]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 29 19:51:33.612661 ignition[588]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 29 19:51:33.614569 ignition[588]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 29 19:51:33.614580 ignition[588]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 29 19:51:33.615901 ignition[588]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 29 19:51:33.615926 ignition[588]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 29 19:51:33.616225 ignition[588]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 29 19:51:33.619233 systemd[1]: ignition-files.service: Deactivated successfully. Oct 29 19:51:33.616232 ignition[588]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 29 19:51:33.619355 systemd[1]: Finished Ignition (files). Oct 29 19:51:33.616323 ignition[588]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 29 19:51:33.621292 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 29 19:51:33.616717 ignition[588]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 29 19:51:33.634397 kernel: audit: type=1334 audit(1761767493.630:7): prog-id=6 op=UNLOAD Oct 29 19:51:33.634420 kernel: audit: type=1334 audit(1761767493.632:8): prog-id=10 op=UNLOAD Oct 29 19:51:33.630000 audit: BPF prog-id=6 op=UNLOAD Oct 29 19:51:33.636215 kernel: audit: type=1334 audit(1761767493.634:9): prog-id=3 op=UNLOAD Oct 29 19:51:33.636253 kernel: audit: type=1334 audit(1761767493.634:10): prog-id=9 op=UNLOAD Oct 29 19:51:33.632000 audit: BPF prog-id=10 op=UNLOAD Oct 29 19:51:33.634000 audit: BPF prog-id=3 op=UNLOAD Oct 29 19:51:33.634000 audit: BPF prog-id=9 op=UNLOAD Oct 29 19:51:33.622163 systemd[1]: Starting Ignition (record completion)... Oct 29 19:51:33.616723 ignition[588]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 29 19:51:33.624015 systemd[1]: Starting Reload Configuration from the Real Root... Oct 29 19:51:33.616728 ignition[588]: files: files passed Oct 29 19:51:33.628705 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 29 19:51:33.616732 ignition[588]: Ignition finished successfully Oct 29 19:51:33.628841 systemd[1]: Finished Ignition (record completion). Oct 29 19:51:33.630053 systemd[1]: Reloading. Oct 29 19:51:33.727000 audit: BPF prog-id=11 op=LOAD Oct 29 19:51:33.727000 audit: BPF prog-id=12 op=LOAD Oct 29 19:51:33.727000 audit: BPF prog-id=13 op=LOAD Oct 29 19:51:33.727000 audit: BPF prog-id=7 op=UNLOAD Oct 29 19:51:33.727000 audit: BPF prog-id=8 op=UNLOAD Oct 29 19:51:33.727000 audit: BPF prog-id=14 op=LOAD Oct 29 19:51:33.728000 audit: BPF prog-id=15 op=LOAD Oct 29 19:51:33.728000 audit: BPF prog-id=16 op=LOAD Oct 29 19:51:33.728000 audit: BPF prog-id=17 op=LOAD Oct 29 19:51:33.728000 audit: BPF prog-id=4 op=UNLOAD Oct 29 19:51:33.728000 audit: BPF prog-id=5 op=UNLOAD Oct 29 19:51:33.728000 audit: BPF prog-id=18 op=LOAD Oct 29 19:51:33.744907 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 29 19:51:33.745059 systemd[1]: Finished Reload Configuration from the Real Root. Oct 29 19:51:33.747007 systemd[1]: Reached target Initrd File Systems. Oct 29 19:51:33.748473 systemd[1]: Reached target Initrd Default Target. Oct 29 19:51:33.749947 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 29 19:51:33.750740 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 29 19:51:33.761582 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 29 19:51:33.763228 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 29 19:51:33.769651 systemd[1]: Stopped target Host and Network Name Lookups. Oct 29 19:51:33.770785 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 29 19:51:33.772363 systemd[1]: Stopped target Timer Units. Oct 29 19:51:33.773593 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 29 19:51:33.773720 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 29 19:51:33.775168 systemd[1]: Stopped target Initrd Default Target. Oct 29 19:51:33.776722 systemd[1]: Stopped target Basic System. Oct 29 19:51:33.778159 systemd[1]: Stopped target Initrd Root Device. Oct 29 19:51:33.779502 systemd[1]: Stopped target Path Units. Oct 29 19:51:33.780639 systemd[1]: Stopped target Remote File Systems. Oct 29 19:51:33.782136 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 29 19:51:33.783641 systemd[1]: Stopped target Slice Units. Oct 29 19:51:33.785006 systemd[1]: Stopped target Socket Units. Oct 29 19:51:33.786594 systemd[1]: Stopped target System Initialization. Oct 29 19:51:33.787944 systemd[1]: Stopped target Local File Systems. Oct 29 19:51:33.789267 systemd[1]: Stopped target Preparation for Local File Systems. Oct 29 19:51:33.790713 systemd[1]: Stopped target Swaps. Oct 29 19:51:33.792031 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 29 19:51:33.792156 systemd[1]: Stopped dracut pre-mount hook. Oct 29 19:51:33.793615 systemd[1]: Stopped target Local Encrypted Volumes. Oct 29 19:51:33.794890 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 19:51:33.798986 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 29 19:51:33.800106 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 29 19:51:33.800220 systemd[1]: Stopped dracut initqueue hook. Oct 29 19:51:33.805448 iscsid[489]: iscsid shutting down. Oct 29 19:51:33.801820 systemd[1]: Stopping Open-iSCSI... Oct 29 19:51:33.805521 systemd[1]: Stopping /sysroot/boot... Oct 29 19:51:33.806131 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 29 19:51:33.806250 systemd[1]: Stopped Coldplug All udev Devices. Oct 29 19:51:33.807472 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 29 19:51:33.807562 systemd[1]: Stopped dracut pre-trigger hook. Oct 29 19:51:33.809739 systemd[1]: iscsid.service: Deactivated successfully. Oct 29 19:51:33.809874 systemd[1]: Stopped Open-iSCSI. Oct 29 19:51:33.811638 systemd[1]: iscsid.socket: Deactivated successfully. Oct 29 19:51:33.811700 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 29 19:51:33.813432 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 29 19:51:33.814910 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 29 19:51:33.815056 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 29 19:51:33.816526 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 29 19:51:33.816644 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 29 19:51:33.818110 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 29 19:51:33.818237 systemd[1]: Stopped /sysroot/boot. Oct 29 19:51:33.819722 systemd[1]: Stopped target Network. Oct 29 19:51:33.820523 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 29 19:51:33.820593 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 29 19:51:33.821966 systemd[1]: Stopping Network Name Resolution... Oct 29 19:51:33.826277 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 29 19:51:33.833000 audit: BPF prog-id=14 op=UNLOAD Oct 29 19:51:33.826397 systemd[1]: Stopped Network Name Resolution. Oct 29 19:51:33.828044 systemd[1]: Stopping Network Configuration... Oct 29 19:51:33.828715 systemd-networkd[436]: eth0: DHCP lease lost Oct 29 19:51:33.834991 systemd-networkd[436]: eth0: DHCPv6 lease lost Oct 29 19:51:33.835949 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 29 19:51:33.836086 systemd[1]: Stopped Network Configuration. Oct 29 19:51:33.837387 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 29 19:51:33.842000 audit: BPF prog-id=18 op=UNLOAD Oct 29 19:51:33.837416 systemd[1]: Closed Network Service Netlink Socket. Oct 29 19:51:33.839441 systemd[1]: Stopping Network Cleanup... Oct 29 19:51:33.840329 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 19:51:33.840396 systemd[1]: Stopped Apply Kernel Variables. Oct 29 19:51:33.841814 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 29 19:51:33.847861 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 29 19:51:33.848060 systemd[1]: Stopped Network Cleanup. Oct 29 19:51:33.848904 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 29 19:51:33.849074 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 29 19:51:33.850982 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 29 19:51:33.851045 systemd[1]: Closed udev Control Socket. Oct 29 19:51:33.852341 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 29 19:51:33.852380 systemd[1]: Closed udev Kernel Socket. Oct 29 19:51:33.859000 audit: BPF prog-id=11 op=UNLOAD Oct 29 19:51:33.853558 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 29 19:51:33.853607 systemd[1]: Stopped dracut pre-udev hook. Oct 29 19:51:33.854995 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 29 19:51:33.855038 systemd[1]: Stopped dracut cmdline hook. Oct 29 19:51:33.856615 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 29 19:51:33.856658 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 29 19:51:33.859122 systemd[1]: Starting Cleanup udev Database... Oct 29 19:51:33.860780 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 29 19:51:33.862135 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 19:51:33.862194 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 29 19:51:33.863994 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 29 19:51:33.864045 systemd[1]: Stopped Create List of Static Device Nodes. Oct 29 19:51:33.865516 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 19:51:33.865565 systemd[1]: Stopped Setup Virtual Console. Oct 29 19:51:33.867245 systemd[1]: rngd.service: Deactivated successfully. Oct 29 19:51:33.878000 audit: BPF prog-id=15 op=UNLOAD Oct 29 19:51:33.867362 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 29 19:51:33.868733 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 29 19:51:33.868842 systemd[1]: Finished Cleanup udev Database. Oct 29 19:51:33.870318 systemd[1]: Reached target Switch Root. Oct 29 19:51:33.872454 systemd[1]: Starting Switch Root... Oct 29 19:51:33.877864 systemd[1]: Switching root. Oct 29 19:51:33.893446 systemd-journald[278]: Journal stopped Oct 29 19:51:36.111930 systemd-journald[278]: Received SIGTERM from PID 1 (systemd). Oct 29 19:51:36.112020 kernel: SELinux: policy capability network_peer_controls=1 Oct 29 19:51:36.112033 kernel: SELinux: policy capability open_perms=1 Oct 29 19:51:36.112043 kernel: SELinux: policy capability extended_socket_class=1 Oct 29 19:51:36.112052 kernel: SELinux: policy capability always_check_network=0 Oct 29 19:51:36.112063 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 29 19:51:36.112082 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 29 19:51:36.112093 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 29 19:51:36.112106 systemd[1]: Successfully loaded SELinux policy in 36.451ms. Oct 29 19:51:36.112122 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.790ms. Oct 29 19:51:36.112134 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 19:51:36.112144 systemd[1]: Detected virtualization kvm. Oct 29 19:51:36.112154 systemd[1]: Detected architecture arm64. Oct 29 19:51:36.112164 systemd[1]: Detected first boot. Oct 29 19:51:36.112174 systemd[1]: Initializing machine ID from VM UUID. Oct 29 19:51:36.112184 systemd[1]: Populated /etc with preset unit settings. Oct 29 19:51:36.112196 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 19:51:36.112207 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 29 19:51:36.112218 kernel: kauditd_printk_skb: 24 callbacks suppressed Oct 29 19:51:36.112227 kernel: audit: type=1334 audit(1761767495.973:35): prog-id=21 op=LOAD Oct 29 19:51:36.112236 kernel: audit: type=1334 audit(1761767495.974:36): prog-id=22 op=LOAD Oct 29 19:51:36.112245 kernel: audit: type=1334 audit(1761767495.975:37): prog-id=23 op=LOAD Oct 29 19:51:36.112253 kernel: audit: type=1334 audit(1761767495.975:38): prog-id=16 op=UNLOAD Oct 29 19:51:36.112263 kernel: audit: type=1334 audit(1761767495.975:39): prog-id=17 op=UNLOAD Oct 29 19:51:36.112272 kernel: audit: type=1334 audit(1761767495.987:40): prog-id=24 op=LOAD Oct 29 19:51:36.112282 kernel: audit: type=1334 audit(1761767495.987:41): prog-id=21 op=UNLOAD Oct 29 19:51:36.112291 kernel: audit: type=1334 audit(1761767495.988:42): prog-id=25 op=LOAD Oct 29 19:51:36.112300 kernel: audit: type=1334 audit(1761767495.988:43): prog-id=26 op=LOAD Oct 29 19:51:36.112310 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 29 19:51:36.112320 kernel: audit: type=1334 audit(1761767495.988:44): prog-id=22 op=UNLOAD Oct 29 19:51:36.112333 systemd[1]: Stopped Switch Root. Oct 29 19:51:36.112343 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 29 19:51:36.112354 systemd[1]: Created slice Slice /system/addon-config. Oct 29 19:51:36.112365 systemd[1]: Created slice Slice /system/addon-run. Oct 29 19:51:36.112374 systemd[1]: Created slice Slice /system/getty. Oct 29 19:51:36.112384 systemd[1]: Created slice Slice /system/modprobe. Oct 29 19:51:36.112395 systemd[1]: Created slice Slice /system/serial-getty. Oct 29 19:51:36.112405 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 29 19:51:36.112415 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 29 19:51:36.112428 systemd[1]: Created slice User and Session Slice. Oct 29 19:51:36.112438 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 19:51:36.112449 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 29 19:51:36.112460 systemd[1]: Set up automount Boot partition Automount Point. Oct 29 19:51:36.112470 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 29 19:51:36.112480 systemd[1]: Stopped target Switch Root. Oct 29 19:51:36.112504 systemd[1]: Stopped target Initrd File Systems. Oct 29 19:51:36.112514 systemd[1]: Stopped target Initrd Root File System. Oct 29 19:51:36.112524 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 19:51:36.112535 systemd[1]: Reached target Remote File Systems. Oct 29 19:51:36.112545 systemd[1]: Reached target Slice Units. Oct 29 19:51:36.112554 systemd[1]: Reached target Swaps. Oct 29 19:51:36.112564 systemd[1]: Reached target Verify torcx succeeded. Oct 29 19:51:36.112576 systemd[1]: Reached target Local Verity Protected Volumes. Oct 29 19:51:36.112679 systemd[1]: Listening on Process Core Dump Socket. Oct 29 19:51:36.112693 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 29 19:51:36.112704 systemd[1]: Listening on Network Service Netlink Socket. Oct 29 19:51:36.112713 systemd[1]: Listening on udev Control Socket. Oct 29 19:51:36.112724 systemd[1]: Listening on udev Kernel Socket. Oct 29 19:51:36.112735 systemd[1]: Mounting Huge Pages File System... Oct 29 19:51:36.112745 systemd[1]: Mounting POSIX Message Queue File System... Oct 29 19:51:36.112755 systemd[1]: Mounting External Media Directory... Oct 29 19:51:36.112767 systemd[1]: Mounting Kernel Debug File System... Oct 29 19:51:36.112791 systemd[1]: Mounting Kernel Trace File System... Oct 29 19:51:36.112801 systemd[1]: Mounting Temporary Directory /tmp... Oct 29 19:51:36.112811 systemd[1]: Starting Create missing system files... Oct 29 19:51:36.112821 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 19:51:36.112831 systemd[1]: Starting Load Kernel Module configfs... Oct 29 19:51:36.112853 systemd[1]: Starting Load Kernel Module drm... Oct 29 19:51:36.112864 systemd[1]: Starting Load Kernel Module fuse... Oct 29 19:51:36.112874 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 29 19:51:36.112999 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 29 19:51:36.113018 systemd[1]: Stopped File System Check on Root Device. Oct 29 19:51:36.113028 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 29 19:51:36.113038 systemd[1]: Stopped systemd-fsck-usr.service. Oct 29 19:51:36.113049 kernel: fuse: init (API version 7.32) Oct 29 19:51:36.113058 systemd[1]: Stopped Journal Service. Oct 29 19:51:36.113069 systemd[1]: Starting Journal Service... Oct 29 19:51:36.113085 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 19:51:36.113095 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 29 19:51:36.113106 systemd[1]: Starting Apply Kernel Variables... Oct 29 19:51:36.113117 systemd[1]: Starting Coldplug All udev Devices... Oct 29 19:51:36.113127 systemd[1]: verity-setup.service: Deactivated successfully. Oct 29 19:51:36.113138 systemd[1]: Stopped verity-setup.service. Oct 29 19:51:36.113150 systemd-journald[805]: Journal started Oct 29 19:51:36.113191 systemd-journald[805]: Runtime Journal (/run/log/journal/2c84dc9bbd9a46a29f3a296e04c5ff21) is 6.0M, max 48.5M, 42.4M free. Oct 29 19:51:33.965000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 29 19:51:34.000000 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 19:51:34.000000 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 19:51:34.000000 audit: BPF prog-id=19 op=LOAD Oct 29 19:51:34.000000 audit: BPF prog-id=19 op=UNLOAD Oct 29 19:51:34.000000 audit: BPF prog-id=20 op=LOAD Oct 29 19:51:34.000000 audit: BPF prog-id=20 op=UNLOAD Oct 29 19:51:34.035000 audit[754]: AVC avc: denied { associate } for pid=754 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 19:51:35.973000 audit: BPF prog-id=21 op=LOAD Oct 29 19:51:35.974000 audit: BPF prog-id=22 op=LOAD Oct 29 19:51:35.975000 audit: BPF prog-id=23 op=LOAD Oct 29 19:51:35.975000 audit: BPF prog-id=16 op=UNLOAD Oct 29 19:51:35.975000 audit: BPF prog-id=17 op=UNLOAD Oct 29 19:51:35.987000 audit: BPF prog-id=24 op=LOAD Oct 29 19:51:35.987000 audit: BPF prog-id=21 op=UNLOAD Oct 29 19:51:35.988000 audit: BPF prog-id=25 op=LOAD Oct 29 19:51:35.988000 audit: BPF prog-id=26 op=LOAD Oct 29 19:51:35.988000 audit: BPF prog-id=22 op=UNLOAD Oct 29 19:51:35.988000 audit: BPF prog-id=23 op=UNLOAD Oct 29 19:51:36.002000 audit: BPF prog-id=24 op=UNLOAD Oct 29 19:51:36.095000 audit: BPF prog-id=27 op=LOAD Oct 29 19:51:36.095000 audit: BPF prog-id=28 op=LOAD Oct 29 19:51:36.095000 audit: BPF prog-id=29 op=LOAD Oct 29 19:51:36.095000 audit: BPF prog-id=25 op=UNLOAD Oct 29 19:51:36.095000 audit: BPF prog-id=26 op=UNLOAD Oct 29 19:51:36.109000 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 19:51:35.973900 systemd[1]: Queued start job for default target Multi-User System. Oct 29 19:51:34.034815 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" 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 19:51:35.990467 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 29 19:51:34.035143 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 19:51:34.035164 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 19:51:34.035300 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 29 19:51:34.035313 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 29 19:51:34.035347 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 29 19:51:34.035360 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 29 19:51:34.035570 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 29 19:51:34.035606 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 19:51:34.035621 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 19:51:34.036019 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" 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 19:51:34.036053 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" 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 19:51:34.036073 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" 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 19:51:34.036098 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" 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 19:51:34.036121 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" 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 19:51:34.036135 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:34Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 29 19:51:36.114973 systemd[1]: Mounted Huge Pages File System. Oct 29 19:51:35.789071 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:35Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 19:51:35.789382 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:35Z" 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 19:51:35.789484 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:35Z" 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 19:51:35.789617 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:35Z" 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 19:51:35.789675 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:35Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 29 19:51:35.789731 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-29T19:51:35Z" 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 19:51:36.116467 systemd[1]: Started Journal Service. Oct 29 19:51:36.117030 systemd[1]: Mounted POSIX Message Queue File System. Oct 29 19:51:36.118150 systemd[1]: Mounted External Media Directory. Oct 29 19:51:36.119123 systemd[1]: Mounted Kernel Debug File System. Oct 29 19:51:36.120044 systemd[1]: Mounted Kernel Trace File System. Oct 29 19:51:36.121054 systemd[1]: Mounted Temporary Directory /tmp. Oct 29 19:51:36.122315 systemd[1]: Finished Create missing system files. Oct 29 19:51:36.123733 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 19:51:36.125031 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 29 19:51:36.125240 systemd[1]: Finished Load Kernel Module configfs. Oct 29 19:51:36.126394 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 19:51:36.126588 systemd[1]: Finished Load Kernel Module drm. Oct 29 19:51:36.127735 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 29 19:51:36.127928 systemd[1]: Finished Load Kernel Module fuse. Oct 29 19:51:36.140492 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 29 19:51:36.141819 systemd[1]: Finished Apply Kernel Variables. Oct 29 19:51:36.145053 systemd[1]: Mounting FUSE Control File System... Oct 29 19:51:36.147038 systemd[1]: Mounting Kernel Configuration File System... Oct 29 19:51:36.147852 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 29 19:51:36.149777 systemd[1]: Starting Rebuild Hardware Database... Oct 29 19:51:36.151829 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 29 19:51:36.152932 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 29 19:51:36.156118 systemd-journald[805]: Time spent on flushing to /var/log/journal/2c84dc9bbd9a46a29f3a296e04c5ff21 is 8.702ms for 776 entries. Oct 29 19:51:36.156118 systemd-journald[805]: System Journal (/var/log/journal/2c84dc9bbd9a46a29f3a296e04c5ff21) is 8.0M, max 203.0M, 195.0M free. Oct 29 19:51:36.154293 systemd[1]: Starting Load/Save Random Seed... Oct 29 19:51:36.156410 systemd[1]: Starting Create System Users... Oct 29 19:51:36.160894 systemd[1]: Mounted FUSE Control File System. Oct 29 19:51:36.162932 systemd[1]: Mounted Kernel Configuration File System. Oct 29 19:51:36.165405 systemd[1]: Finished Load/Save Random Seed. Oct 29 19:51:36.166664 systemd[1]: Finished Coldplug All udev Devices. Oct 29 19:51:36.167921 systemd[1]: Reached target First Boot Complete. Oct 29 19:51:36.176088 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 29 19:51:36.177996 systemd-sysusers[817]: Creating group sgx with gid 999. Oct 29 19:51:36.178837 systemd-sysusers[817]: Creating group systemd-oom with gid 998. Oct 29 19:51:36.179365 systemd-sysusers[817]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 29 19:51:36.180596 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 29 19:51:36.182177 systemd-sysusers[817]: Creating group systemd-timesync with gid 997. Oct 29 19:51:36.182689 systemd-sysusers[817]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 29 19:51:36.183320 systemd-sysusers[817]: Creating group systemd-coredump with gid 996. Oct 29 19:51:36.183758 systemd-sysusers[817]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 29 19:51:36.192681 systemd[1]: Finished Create System Users. Oct 29 19:51:36.194772 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 19:51:36.208486 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 19:51:36.425611 systemd[1]: Finished Rebuild Hardware Database. Oct 29 19:51:36.434000 audit: BPF prog-id=30 op=LOAD Oct 29 19:51:36.434000 audit: BPF prog-id=31 op=LOAD Oct 29 19:51:36.434000 audit: BPF prog-id=32 op=LOAD Oct 29 19:51:36.434000 audit: BPF prog-id=12 op=UNLOAD Oct 29 19:51:36.434000 audit: BPF prog-id=13 op=UNLOAD Oct 29 19:51:36.436601 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 19:51:36.460207 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 19:51:36.469000 audit: BPF prog-id=33 op=LOAD Oct 29 19:51:36.471326 systemd[1]: Starting Network Configuration... Oct 29 19:51:36.499819 systemd-networkd[842]: lo: Link UP Oct 29 19:51:36.499828 systemd-networkd[842]: lo: Gained carrier Oct 29 19:51:36.500237 systemd-networkd[842]: Enumeration completed Oct 29 19:51:36.500343 systemd[1]: Started Network Configuration. Oct 29 19:51:36.503407 systemd-udevd[835]: Using default interface naming scheme 'v249'. Oct 29 19:51:36.527607 udevadm[820]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 29 19:51:36.539306 systemd-networkd[842]: eth0: Link UP Oct 29 19:51:36.546045 systemd-networkd[842]: eth0: Gained carrier Oct 29 19:51:36.559107 systemd-networkd[842]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 29 19:51:36.559338 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 29 19:51:36.569138 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 19:51:36.577866 lvm[850]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 19:51:36.603031 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 19:51:36.604183 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 19:51:36.615079 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 19:51:36.618647 lvm[851]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 19:51:36.650065 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 19:51:36.651102 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 19:51:36.652199 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 29 19:51:36.652225 systemd[1]: Reached target Containers. Oct 29 19:51:36.670246 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 29 19:51:36.683606 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 29 19:51:36.703976 systemd[1]: Mounting /usr/share/oem... Oct 29 19:51:36.710477 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 29 19:51:36.710637 kernel: BTRFS info (device vda6): has skinny extents Oct 29 19:51:36.712832 systemd[1]: Mounted /usr/share/oem. Oct 29 19:51:36.713743 systemd[1]: Reached target Local File Systems. Oct 29 19:51:36.715832 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 29 19:51:36.717276 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 29 19:51:36.717415 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 29 19:51:36.718937 systemd[1]: Starting Commit a transient machine-id on disk... Oct 29 19:51:36.721271 systemd[1]: Starting Create Volatile Files and Directories... Oct 29 19:51:36.728895 systemd-tmpfiles[876]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 29 19:51:36.730350 systemd-tmpfiles[876]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 29 19:51:36.739337 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 29 19:51:36.740183 systemd[1]: Finished Commit a transient machine-id on disk. Oct 29 19:51:36.762056 systemd-tmpfiles[876]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 19:51:36.762212 systemd-tmpfiles[876]: Skipping /boot Oct 29 19:51:36.767929 systemd-tmpfiles[876]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 19:51:36.768103 systemd-tmpfiles[876]: Skipping /boot Oct 29 19:51:36.798039 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 29 19:51:36.809440 systemd[1]: Finished Create Volatile Files and Directories. Oct 29 19:51:36.811739 systemd[1]: Starting Load Security Auditing Rules... Oct 29 19:51:36.813739 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 29 19:51:36.816106 systemd[1]: Starting Rebuild Journal Catalog... Oct 29 19:51:36.816000 audit: BPF prog-id=34 op=LOAD Oct 29 19:51:36.818651 systemd[1]: Starting Network Name Resolution... Oct 29 19:51:36.819000 audit: BPF prog-id=35 op=LOAD Oct 29 19:51:36.821488 systemd[1]: Starting Network Time Synchronization... Oct 29 19:51:36.823599 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 29 19:51:36.825399 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 29 19:51:36.826857 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 29 19:51:36.828224 systemd[1]: Finished Rebuild Journal Catalog. Oct 29 19:51:36.838980 systemd[1]: Starting Update is Completed... Oct 29 19:51:36.841234 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 29 19:51:36.842000 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 19:51:36.843883 augenrules[900]: No rules Oct 29 19:51:36.844915 systemd[1]: Finished Load Security Auditing Rules. Oct 29 19:51:36.846292 systemd[1]: Finished Update is Completed. Oct 29 19:51:36.862254 systemd-timesyncd[887]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 29 19:51:36.863218 systemd[1]: Started Network Time Synchronization. Oct 29 19:51:36.864349 systemd[1]: Reached target System Initialization. Oct 29 19:51:36.865278 systemd[1]: Started Watch for update engine configuration changes. Oct 29 19:51:36.866398 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 19:51:36.867743 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 29 19:51:36.868841 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 29 19:51:36.868880 systemd[1]: Reached target Path Units. Oct 29 19:51:36.868882 systemd-resolved[883]: Positive Trust Anchors: Oct 29 19:51:36.868890 systemd-resolved[883]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 19:51:36.868918 systemd-resolved[883]: 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 19:51:36.869875 systemd[1]: Reached target System Time Set. Oct 29 19:51:36.869989 systemd-resolved[883]: Defaulting to hostname 'linux'. Oct 29 19:51:36.870888 systemd[1]: Started Daily Log Rotation. Oct 29 19:51:36.871921 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 29 19:51:36.873012 systemd[1]: Reached target Timer Units. Oct 29 19:51:36.874212 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 29 19:51:36.884753 systemd[1]: Starting Docker Socket for the API... Oct 29 19:51:36.888152 systemd[1]: Listening on OpenSSH Server Socket. Oct 29 19:51:36.889475 systemd[1]: Started Network Name Resolution. Oct 29 19:51:36.890520 systemd[1]: Listening on Docker Socket for the API. Oct 29 19:51:36.891509 systemd[1]: Reached target Network. Oct 29 19:51:36.892288 systemd[1]: Reached target Host and Network Name Lookups. Oct 29 19:51:36.893216 systemd[1]: Reached target Socket Units. Oct 29 19:51:36.893946 systemd[1]: Reached target Basic System. Oct 29 19:51:36.894819 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 29 19:51:36.894849 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 29 19:51:36.896130 systemd[1]: Started D-Bus System Message Bus. Oct 29 19:51:36.899476 systemd[1]: Starting Extend Filesystems... Oct 29 19:51:36.900375 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 29 19:51:36.901466 systemd[1]: Starting Generate /run/flatcar/motd... Oct 29 19:51:36.903415 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 29 19:51:36.905466 systemd[1]: Starting Generate sshd host keys... Oct 29 19:51:36.906762 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 29 19:51:36.906799 systemd[1]: Reached target Load system-provided cloud configs. Oct 29 19:51:36.909061 extend-filesystems[910]: Found vda Oct 29 19:51:36.910110 extend-filesystems[910]: Found vda1 Oct 29 19:51:36.910110 extend-filesystems[910]: Found vda2 Oct 29 19:51:36.910110 extend-filesystems[910]: Found vda3 Oct 29 19:51:36.910110 extend-filesystems[910]: Found usr Oct 29 19:51:36.910110 extend-filesystems[910]: Found vda4 Oct 29 19:51:36.910110 extend-filesystems[910]: Found vda6 Oct 29 19:51:36.910110 extend-filesystems[910]: Found vda7 Oct 29 19:51:36.910110 extend-filesystems[910]: Found vda9 Oct 29 19:51:36.910110 extend-filesystems[910]: Checking size of /dev/vda9 Oct 29 19:51:36.910637 systemd[1]: Starting User Login Management... Oct 29 19:51:36.912037 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 29 19:51:36.914351 systemd[1]: Starting Update Engine... Oct 29 19:51:36.915447 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 29 19:51:36.915480 systemd[1]: Reached target Load user-provided cloud configs. Oct 29 19:51:36.917563 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 29 19:51:36.917808 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 29 19:51:36.925907 systemd[1]: motdgen.service: Deactivated successfully. Oct 29 19:51:36.926233 systemd[1]: Finished Generate /run/flatcar/motd. Oct 29 19:51:36.940650 extend-filesystems[910]: Old size kept for /dev/vda9 Oct 29 19:51:36.941059 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 29 19:51:36.941304 systemd[1]: Finished Extend Filesystems. Oct 29 19:51:36.952333 update_engine[926]: I1029 19:51:36.951912 926 main.cc:89] Flatcar Update Engine starting Oct 29 19:51:36.961132 update_engine[926]: I1029 19:51:36.954777 926 update_check_scheduler.cc:74] Next update check in 11m53s Oct 29 19:51:36.954511 systemd[1]: Started Update Engine. Oct 29 19:51:36.962289 systemd-logind[921]: Watching system buttons on /dev/input/event0 (Power Button) Oct 29 19:51:36.962631 systemd[1]: Started Cluster reboot manager. Oct 29 19:51:36.962874 systemd-logind[921]: New seat seat0. Oct 29 19:51:36.965735 systemd[1]: Started User Login Management. Oct 29 19:51:36.991029 locksmithd[933]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 29 19:51:37.263640 sshd_keygen[927]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 29 19:51:37.283129 systemd[1]: Finished Generate sshd host keys. Oct 29 19:51:37.298167 systemd[1]: Starting Generate /run/issue... Oct 29 19:51:37.302499 systemd[1]: issuegen.service: Deactivated successfully. Oct 29 19:51:37.302749 systemd[1]: Finished Generate /run/issue. Oct 29 19:51:37.305113 systemd[1]: Starting Permit User Sessions... Oct 29 19:51:37.309942 systemd[1]: Finished Permit User Sessions. Oct 29 19:51:37.312321 systemd[1]: Started Getty on tty1. Oct 29 19:51:37.314336 systemd[1]: Started Serial Getty on ttyAMA0. Oct 29 19:51:37.315312 systemd[1]: Reached target Login Prompts. Oct 29 19:51:37.316206 systemd[1]: Reached target Multi-User System. Oct 29 19:51:37.318303 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 29 19:51:37.323583 systemd[1]: Created slice Slice /system/sshd. Oct 29 19:51:37.325655 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:44540). Oct 29 19:51:37.328472 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 29 19:51:37.328716 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 29 19:51:37.329985 systemd[1]: Startup finished in 731ms (kernel) + 2.370s (initrd) + 3.402s (userspace) = 6.505s. Oct 29 19:51:37.368438 sshd[955]: Accepted publickey for core from 10.0.0.1 port 44540 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:51:37.370156 sshd[955]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:37.386531 systemd[1]: Created slice User Slice of UID 500. Oct 29 19:51:37.387852 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 29 19:51:37.389555 systemd-logind[921]: New session 1 of user core. Oct 29 19:51:37.394218 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 29 19:51:37.395919 systemd[1]: Starting User Manager for UID 500... Oct 29 19:51:37.398093 systemd[958]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:37.443116 systemd[958]: Queued start job for default target Main User Target. Oct 29 19:51:37.443273 systemd[958]: Reached target Paths. Oct 29 19:51:37.443286 systemd[958]: Reached target Sockets. Oct 29 19:51:37.443296 systemd[958]: Reached target Timers. Oct 29 19:51:37.443306 systemd[958]: Reached target Basic System. Oct 29 19:51:37.443347 systemd[958]: Reached target Main User Target. Oct 29 19:51:37.443359 systemd[958]: Startup finished in 41ms. Oct 29 19:51:37.443554 systemd[1]: Started User Manager for UID 500. Oct 29 19:51:37.452154 systemd[1]: Started Session 1 of User core. Oct 29 19:51:37.514566 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:44546). Oct 29 19:51:37.554620 sshd[967]: Accepted publickey for core from 10.0.0.1 port 44546 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:51:37.555873 sshd[967]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:37.558337 systemd-logind[921]: New session 2 of user core. Oct 29 19:51:37.571093 systemd[1]: Started Session 2 of User core. Oct 29 19:51:37.620271 sshd[967]: pam_unix(sshd:session): session closed for user core Oct 29 19:51:37.628435 systemd[1]: sshd@1-10.0.0.5:22-10.0.0.1:44546.service: Deactivated successfully. Oct 29 19:51:37.629025 systemd[1]: session-2.scope: Deactivated successfully. Oct 29 19:51:37.629510 systemd-logind[921]: Session 2 logged out. Waiting for processes to exit. Oct 29 19:51:37.630407 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:44560). Oct 29 19:51:37.630895 systemd-logind[921]: Removed session 2. Oct 29 19:51:37.668063 sshd[973]: Accepted publickey for core from 10.0.0.1 port 44560 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:51:37.669084 sshd[973]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:37.671754 systemd-logind[921]: New session 3 of user core. Oct 29 19:51:37.678110 systemd[1]: Started Session 3 of User core. Oct 29 19:51:37.723213 sshd[973]: pam_unix(sshd:session): session closed for user core Oct 29 19:51:37.732892 systemd[1]: sshd@2-10.0.0.5:22-10.0.0.1:44560.service: Deactivated successfully. Oct 29 19:51:37.733452 systemd[1]: session-3.scope: Deactivated successfully. Oct 29 19:51:37.733997 systemd-logind[921]: Session 3 logged out. Waiting for processes to exit. Oct 29 19:51:37.734977 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:44570). Oct 29 19:51:37.735656 systemd-logind[921]: Removed session 3. Oct 29 19:51:37.773098 sshd[979]: Accepted publickey for core from 10.0.0.1 port 44570 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:51:37.774142 sshd[979]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:37.776633 systemd-logind[921]: New session 4 of user core. Oct 29 19:51:37.787101 systemd[1]: Started Session 4 of User core. Oct 29 19:51:37.835631 sshd[979]: pam_unix(sshd:session): session closed for user core Oct 29 19:51:37.845499 systemd[1]: sshd@3-10.0.0.5:22-10.0.0.1:44570.service: Deactivated successfully. Oct 29 19:51:37.846060 systemd[1]: session-4.scope: Deactivated successfully. Oct 29 19:51:37.846597 systemd-logind[921]: Session 4 logged out. Waiting for processes to exit. Oct 29 19:51:37.847482 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:44586). Oct 29 19:51:37.848013 systemd-logind[921]: Removed session 4. Oct 29 19:51:37.886009 sshd[985]: Accepted publickey for core from 10.0.0.1 port 44586 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:51:37.887255 sshd[985]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:37.889623 systemd-logind[921]: New session 5 of user core. Oct 29 19:51:37.901099 systemd[1]: Started Session 5 of User core. Oct 29 19:51:37.954455 sudo[988]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 29 19:51:37.954660 sudo[988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 19:51:37.965437 sudo[988]: pam_unix(sudo:session): session closed for user root Oct 29 19:51:37.967658 sshd[985]: pam_unix(sshd:session): session closed for user core Oct 29 19:51:37.981629 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:44592). Oct 29 19:51:37.982765 dbus-daemon[909]: [system] Reloaded configuration Oct 29 19:51:37.985252 systemd[1]: sshd@4-10.0.0.5:22-10.0.0.1:44586.service: Deactivated successfully. Oct 29 19:51:37.985938 systemd[1]: session-5.scope: Deactivated successfully. Oct 29 19:51:37.986644 systemd-logind[921]: Session 5 logged out. Waiting for processes to exit. Oct 29 19:51:37.987334 systemd-logind[921]: Removed session 5. Oct 29 19:51:38.020498 sshd[991]: Accepted publickey for core from 10.0.0.1 port 44592 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:51:38.021549 sshd[991]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:38.024103 systemd-logind[921]: New session 6 of user core. Oct 29 19:51:38.037100 systemd[1]: Started Session 6 of User core. Oct 29 19:51:38.086787 sudo[996]: 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 19:51:38.087006 sudo[996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 19:51:38.089028 sudo[996]: pam_unix(sudo:session): session closed for user root Oct 29 19:51:38.092471 sudo[995]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 29 19:51:38.092672 sudo[995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 19:51:38.103808 systemd[1]: Stopping Load Security Auditing Rules... Oct 29 19:51:38.104000 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 19:51:38.105063 auditctl[999]: No rules Oct 29 19:51:38.105317 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 19:51:38.105554 systemd[1]: Stopped Load Security Auditing Rules. Oct 29 19:51:38.106792 systemd[1]: Starting Load Security Auditing Rules... Oct 29 19:51:38.120606 augenrules[1016]: No rules Oct 29 19:51:38.121277 systemd[1]: Finished Load Security Auditing Rules. Oct 29 19:51:38.122034 sudo[995]: pam_unix(sudo:session): session closed for user root Oct 29 19:51:38.123141 sshd[991]: pam_unix(sshd:session): session closed for user core Oct 29 19:51:38.135474 systemd[1]: sshd@5-10.0.0.5:22-10.0.0.1:44592.service: Deactivated successfully. Oct 29 19:51:38.136003 systemd[1]: session-6.scope: Deactivated successfully. Oct 29 19:51:38.136565 systemd-logind[921]: Session 6 logged out. Waiting for processes to exit. Oct 29 19:51:38.137434 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:44596). Oct 29 19:51:38.138172 systemd-logind[921]: Removed session 6. Oct 29 19:51:38.175075 sshd[1022]: Accepted publickey for core from 10.0.0.1 port 44596 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:51:38.175895 sshd[1022]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:38.178244 systemd-logind[921]: New session 7 of user core. Oct 29 19:51:38.195112 systemd[1]: Started Session 7 of User core. Oct 29 19:51:38.216145 systemd-networkd[842]: eth0: Gained IPv6LL Oct 29 19:51:38.242899 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 19:51:41.002535 update_engine[1275]: Oct 29 19:51:41.002535 update_engine[1275]: Oct 29 19:51:41.002535 update_engine[1275]: Oct 29 19:51:41.002535 update_engine[1275]: Oct 29 19:51:41.002535 update_engine[1275]: Oct 29 19:51:41.002535 update_engine[1275]: Oct 29 19:51:41.002535 update_engine[1275]: Oct 29 19:51:41.002535 update_engine[1275]: Oct 29 19:51:41.002535 update_engine[1275]: I1029 19:51:41.002508 1275 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 29 19:51:41.005161 update_engine[1275]: I1029 19:51:41.005134 1275 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 29 19:51:41.005323 systemd-logind[921]: Removed session 24. Oct 29 19:51:41.005711 update_engine[1275]: I1029 19:51:41.005343 1275 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 29 19:51:41.006899 update_engine[1275]: I1029 19:51:41.006860 1275 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 29 19:51:41.006991 update_engine[1275]: I1029 19:51:41.006944 1275 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Oct 29 19:51:41.006991 update_engine[1275]: I1029 19:51:41.006960 1275 omaha_request_action.cc:619] Omaha request response: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.006991 update_engine[1275]: Oct 29 19:51:41.012036 update_engine[1275]: I1029 19:51:41.012005 1275 omaha_request_action.cc:447] Omaha Response manifest version = Oct 29 19:51:41.012036 update_engine[1275]: I1029 19:51:41.012038 1275 omaha_request_action.cc:470] Found 1 url(s) Oct 29 19:51:41.012167 update_engine[1275]: I1029 19:51:41.012051 1275 omaha_request_action.cc:506] Processing first of 1 package(s) Oct 29 19:51:41.012167 update_engine[1275]: I1029 19:51:41.012054 1275 omaha_request_action.cc:513] Omaha Response package name = update.gz Oct 29 19:51:41.012167 update_engine[1275]: I1029 19:51:41.012058 1275 omaha_request_action.cc:529] Url0: http://10.0.0.4:34567/packages/update.gz Oct 29 19:51:41.012167 update_engine[1275]: I1029 19:51:41.012065 1275 omaha_request_action.cc:541] Payload size = 439328019 bytes Oct 29 19:51:41.012167 update_engine[1275]: I1029 19:51:41.012074 1275 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Oct 29 19:51:41.012167 update_engine[1275]: I1029 19:51:41.012085 1275 payload_state.cc:51] Resetting all persisted state as this is a new response Oct 29 19:51:41.012167 update_engine[1275]: I1029 19:51:41.012089 1275 payload_state.cc:360] Current Response Signature = Oct 29 19:51:41.012167 update_engine[1275]: NumURLs = 1 Oct 29 19:51:41.012167 update_engine[1275]: Url0 = http://10.0.0.4:34567/packages/update.gz Oct 29 19:51:41.012167 update_engine[1275]: Payload Size = 439328019 Oct 29 19:51:41.012167 update_engine[1275]: Payload Sha256 Hash = JprWN+czonR3c0MyWAyU2g4RVVt99zqV4E+IEkT3wn8= Oct 29 19:51:41.012167 update_engine[1275]: Is Delta Payload = 0 Oct 29 19:51:41.012167 update_engine[1275]: Max Failure Count Per Url = 10 Oct 29 19:51:41.012167 update_engine[1275]: Disable Payload Backoff = 1 Oct 29 19:51:41.012387 update_engine[1275]: I1029 19:51:41.012176 1275 payload_state.cc:381] Payload Attempt Number = 0 Oct 29 19:51:41.012387 update_engine[1275]: I1029 19:51:41.012210 1275 payload_state.cc:404] Current URL Index = 0 Oct 29 19:51:41.012387 update_engine[1275]: I1029 19:51:41.012238 1275 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Oct 29 19:51:41.012387 update_engine[1275]: I1029 19:51:41.012264 1275 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Oct 29 19:51:41.012387 update_engine[1275]: I1029 19:51:41.012269 1275 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 29 19:51:41.012387 update_engine[1275]: I1029 19:51:41.012300 1275 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Oct 29 19:51:41.012387 update_engine[1275]: I1029 19:51:41.012316 1275 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 29 19:51:41.012387 update_engine[1275]: I1029 19:51:41.012320 1275 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Oct 29 19:51:41.012387 update_engine[1275]: I1029 19:51:41.012334 1275 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Oct 29 19:51:41.012387 update_engine[1275]: E1029 19:51:41.012338 1275 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Oct 29 19:51:41.012994 update_engine[1275]: I1029 19:51:41.012969 1275 omaha_response_handler_action.cc:85] Using this install plan: Oct 29 19:51:41.012994 update_engine[1275]: I1029 19:51:41.012987 1275 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.4:34567/packages/update.gz, payload size: 439328019, payload hash: JprWN+czonR3c0MyWAyU2g4RVVt99zqV4E+IEkT3wn8=, 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 19:51:41.013073 update_engine[1275]: I1029 19:51:41.013063 1275 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Oct 29 19:51:41.014490 update_engine[1275]: I1029 19:51:41.014460 1275 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Oct 29 19:51:41.042870 sshd[1296]: Accepted publickey for core from 10.0.0.1 port 52426 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:51:41.047616 kernel: kauditd_printk_skb: 313 callbacks suppressed Oct 29 19:51:41.047685 kernel: audit: type=1006 audit(1761767501.042:358): pid=1296 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 19:51:41.044007 sshd[1296]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:41.050842 systemd-logind[921]: New session 25 of user core. Oct 29 19:51:41.055134 systemd[1]: Started Session 25 of User core. Oct 29 19:51:41.115699 sshd[1296]: pam_unix(sshd:session): session closed for user core Oct 29 19:51:41.118944 systemd[1]: sshd@24-10.0.0.5:22-10.0.0.1:52426.service: Deactivated successfully. Oct 29 19:51:41.119581 systemd[1]: session-25.scope: Deactivated successfully. Oct 29 19:51:41.120110 systemd-logind[921]: Session 25 logged out. Waiting for processes to exit. Oct 29 19:51:41.120980 systemd-logind[921]: Removed session 25. Oct 29 19:51:42.702909 update_engine[1275]: I1029 19:51:42.702859 1275 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Oct 29 19:51:42.702909 update_engine[1275]: I1029 19:51:42.702899 1275 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Oct 29 19:51:42.757767 update_engine[1275]: E1029 19:51:42.757716 1275 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Oct 29 19:51:42.757767 update_engine[1275]: I1029 19:51:42.757759 1275 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Oct 29 19:51:42.757767 update_engine[1275]: I1029 19:51:42.757764 1275 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Oct 29 19:51:42.757767 update_engine[1275]: I1029 19:51:42.757769 1275 update_attempter.cc:302] Processing Done. Oct 29 19:51:42.758010 update_engine[1275]: E1029 19:51:42.757814 1275 update_attempter.cc:615] Update failed. Oct 29 19:51:42.758010 update_engine[1275]: I1029 19:51:42.757818 1275 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Oct 29 19:51:42.758010 update_engine[1275]: I1029 19:51:42.757822 1275 payload_state.cc:276] Incrementing the URL failure count Oct 29 19:51:42.758010 update_engine[1275]: I1029 19:51:42.757825 1275 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 29 19:51:42.758091 update_engine[1275]: I1029 19:51:42.758013 1275 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 29 19:51:42.758091 update_engine[1275]: I1029 19:51:42.758034 1275 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.4:34567/v1/update Oct 29 19:51:42.758091 update_engine[1275]: I1029 19:51:42.758038 1275 omaha_request_action.cc:269] Request: Oct 29 19:51:42.758091 update_engine[1275]: Oct 29 19:51:42.758091 update_engine[1275]: Oct 29 19:51:42.758091 update_engine[1275]: Oct 29 19:51:42.758091 update_engine[1275]: Oct 29 19:51:42.758091 update_engine[1275]: Oct 29 19:51:42.758091 update_engine[1275]: Oct 29 19:51:42.758091 update_engine[1275]: I1029 19:51:42.758042 1275 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 29 19:51:42.758287 update_engine[1275]: I1029 19:51:42.758097 1275 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 29 19:51:42.758287 update_engine[1275]: I1029 19:51:42.758201 1275 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 29 19:51:42.878113 update_engine[1275]: I1029 19:51:42.878068 1275 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 29 19:51:42.878221 update_engine[1275]: I1029 19:51:42.878144 1275 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Oct 29 19:51:42.878221 update_engine[1275]: I1029 19:51:42.878151 1275 omaha_request_action.cc:619] Omaha request response: Oct 29 19:51:42.878221 update_engine[1275]: Oct 29 19:51:42.878221 update_engine[1275]: Oct 29 19:51:42.878221 update_engine[1275]: Oct 29 19:51:42.878221 update_engine[1275]: Oct 29 19:51:42.878221 update_engine[1275]: Oct 29 19:51:42.878221 update_engine[1275]: Oct 29 19:51:42.878221 update_engine[1275]: E1029 19:51:42.878165 1275 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Oct 29 19:51:42.878221 update_engine[1275]: I1029 19:51:42.878168 1275 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 29 19:51:42.878221 update_engine[1275]: I1029 19:51:42.878171 1275 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 29 19:51:42.878221 update_engine[1275]: I1029 19:51:42.878175 1275 update_attempter.cc:302] Processing Done. Oct 29 19:51:42.878221 update_engine[1275]: I1029 19:51:42.878180 1275 update_attempter.cc:306] Error event sent. Oct 29 19:51:50.960302 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39560). Oct 29 19:51:51.001560 sshd[1311]: Accepted publickey for core from 10.0.0.1 port 39560 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:51:51.003233 sshd[1311]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:51.005982 kernel: audit: type=1006 audit(1761767511.001:359): pid=1311 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 19:51:51.007785 systemd-logind[921]: New session 26 of user core. Oct 29 19:51:51.020119 systemd[1]: Started Session 26 of User core. Oct 29 19:51:51.075377 sshd[1311]: pam_unix(sshd:session): session closed for user core Oct 29 19:51:51.087691 systemd[1]: sshd@25-10.0.0.5:22-10.0.0.1:39560.service: Deactivated successfully. Oct 29 19:51:51.088297 systemd[1]: session-26.scope: Deactivated successfully. Oct 29 19:51:51.088911 systemd-logind[921]: Session 26 logged out. Waiting for processes to exit. Oct 29 19:51:51.089929 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39564). Oct 29 19:51:51.090613 systemd-logind[921]: Removed session 26. Oct 29 19:51:51.127934 sshd[1318]: Accepted publickey for core from 10.0.0.1 port 39564 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:51:51.129276 sshd[1318]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:51.131974 kernel: audit: type=1006 audit(1761767511.128:360): pid=1318 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 19:51:51.134138 systemd-logind[921]: New session 27 of user core. Oct 29 19:51:51.142118 systemd[1]: Started Session 27 of User core. Oct 29 19:51:51.196718 sshd[1318]: pam_unix(sshd:session): session closed for user core Oct 29 19:51:51.206735 systemd[1]: sshd@26-10.0.0.5:22-10.0.0.1:39564.service: Deactivated successfully. Oct 29 19:51:51.207336 systemd[1]: session-27.scope: Deactivated successfully. Oct 29 19:51:51.207918 systemd-logind[921]: Session 27 logged out. Waiting for processes to exit. Oct 29 19:51:51.209051 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39568). Oct 29 19:51:51.210308 systemd-logind[921]: Removed session 27. Oct 29 19:51:51.247651 sshd[1326]: Accepted publickey for core from 10.0.0.1 port 39568 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:51:51.248634 sshd[1326]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:51.250973 kernel: audit: type=1006 audit(1761767511.247:361): pid=1326 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 19:51:51.252250 systemd-logind[921]: New session 28 of user core. Oct 29 19:51:51.258133 systemd[1]: Started Session 28 of User core. Oct 29 19:51:51.306530 sshd[1326]: pam_unix(sshd:session): session closed for user core Oct 29 19:51:51.318719 systemd[1]: sshd@27-10.0.0.5:22-10.0.0.1:39568.service: Deactivated successfully. Oct 29 19:51:51.319299 systemd[1]: session-28.scope: Deactivated successfully. Oct 29 19:51:51.319842 systemd-logind[921]: Session 28 logged out. Waiting for processes to exit. Oct 29 19:51:51.320847 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39572). Oct 29 19:51:51.321496 systemd-logind[921]: Removed session 28. Oct 29 19:51:51.359641 sshd[1332]: Accepted publickey for core from 10.0.0.1 port 39572 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:51:51.361333 sshd[1332]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:51.362972 kernel: audit: type=1006 audit(1761767511.359:362): pid=1332 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 19:51:51.364417 systemd-logind[921]: New session 29 of user core. Oct 29 19:51:51.372134 systemd[1]: Started Session 29 of User core. Oct 29 19:51:51.420595 sshd[1332]: pam_unix(sshd:session): session closed for user core Oct 29 19:51:51.432660 systemd[1]: sshd@28-10.0.0.5:22-10.0.0.1:39572.service: Deactivated successfully. Oct 29 19:51:51.433210 systemd[1]: session-29.scope: Deactivated successfully. Oct 29 19:51:51.433800 systemd-logind[921]: Session 29 logged out. Waiting for processes to exit. Oct 29 19:51:51.434773 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39584). Oct 29 19:51:51.435426 systemd-logind[921]: Removed session 29. Oct 29 19:51:51.476120 sshd[1338]: Accepted publickey for core from 10.0.0.1 port 39584 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:51:51.477736 sshd[1338]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:51:51.481073 kernel: audit: type=1006 audit(1761767511.476:363): pid=1338 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 19:51:51.482949 systemd-logind[921]: New session 30 of user core. Oct 29 19:51:51.499106 systemd[1]: Started Session 30 of User core. Oct 29 19:51:51.549491 sudo[1342]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Oct 29 19:51:51.549696 sudo[1342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 19:51:51.555346 systemd[1]: sshd.socket: Deactivated successfully. Oct 29 19:51:51.555750 systemd[1]: Closed OpenSSH Server Socket. Oct 29 19:51:51.556914 sudo[1342]: pam_unix(sudo:session): session closed for user root Oct 29 19:51:51.560403 sudo[1341]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Oct 29 19:51:51.560590 sudo[1341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 19:51:51.579624 sshd[955]: pam_unix(sshd:session): session closed for user core Oct 29 19:51:51.579720 systemd[1]: Stopping Session 1 of User core... Oct 29 19:51:51.580948 systemd[1]: Stopping Session 30 of User core... Oct 29 19:51:51.581245 sudo[1341]: pam_unix(sudo:session): session closed for user root Oct 29 19:51:51.582331 systemd[1]: Removed slice Slice /system/addon-config. Oct 29 19:51:51.583219 sshd[1338]: pam_unix(sshd:session): session closed for user core Oct 29 19:51:51.583619 systemd[1]: Removed slice Slice /system/addon-run. Oct 29 19:51:51.584792 systemd[1]: Removed slice Slice /system/modprobe. Oct 29 19:51:51.586163 systemd[1]: Removed slice Slice /system/system-cloudinit. Oct 29 19:51:51.587297 systemd[1]: Stopped target Multi-User System. Oct 29 19:51:51.588148 systemd[1]: Stopped target Login Prompts. Oct 29 19:51:51.588910 systemd[1]: Stopped target Containers. Oct 29 19:51:51.589824 systemd[1]: Stopped target Host and Network Name Lookups. Oct 29 19:51:51.590802 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 29 19:51:51.591739 systemd[1]: Stopped target Timer Units. Oct 29 19:51:51.592590 systemd[1]: logrotate.timer: Deactivated successfully. Oct 29 19:51:51.592629 systemd[1]: Stopped Daily Log Rotation. Oct 29 19:51:51.593397 systemd[1]: mdadm.timer: Deactivated successfully. Oct 29 19:51:51.593433 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Oct 29 19:51:51.594548 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 29 19:51:51.594590 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Oct 29 19:51:51.595553 systemd[1]: Stopped target System Time Set. Oct 29 19:51:51.596520 systemd[1]: Stopped target Load user-provided cloud configs. Oct 29 19:51:51.597561 systemd[1]: Stopped target Load system-provided cloud configs. Oct 29 19:51:51.598590 systemd[1]: systemd-coredump.socket: Deactivated successfully. Oct 29 19:51:51.598678 systemd[1]: Closed Process Core Dump Socket. Oct 29 19:51:51.600239 systemd[1]: Unmounting Boot partition... Oct 29 19:51:51.601239 systemd[1]: Stopping Getty on tty1... Oct 29 19:51:51.602017 systemd[1]: Stopping Serial Getty on ttyAMA0... Oct 29 19:51:51.602907 systemd[1]: Stopping OpenSSH per-connection server daemon... Oct 29 19:51:51.604025 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:39584)... Oct 29 19:51:51.604819 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Oct 29 19:51:51.604937 systemd[1]: Stopped Commit a transient machine-id on disk. Oct 29 19:51:51.605998 systemd[1]: Stopped target First Boot Complete. Oct 29 19:51:51.607765 systemd[1]: Stopping Load/Save Random Seed... Oct 29 19:51:51.608817 systemd[1]: Stopping Update Engine... Oct 29 19:51:51.609391 sshd[1338]: pam_systemd(sshd:session): Failed to release session: Interrupted system call Oct 29 19:51:51.612000 systemd[1]: getty@tty1.service: Deactivated successfully. Oct 29 19:51:51.612187 systemd[1]: Stopped Getty on tty1. Oct 29 19:51:51.613291 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Oct 29 19:51:51.613468 systemd[1]: Stopped Serial Getty on ttyAMA0. Oct 29 19:51:51.614744 systemd[1]: sshd@0-10.0.0.5:22-10.0.0.1:44540.service: Deactivated successfully. Oct 29 19:51:51.614937 systemd[1]: Stopped OpenSSH per-connection server daemon. Oct 29 19:51:51.616303 systemd[1]: sshd@29-10.0.0.5:22-10.0.0.1:39584.service: Deactivated successfully. Oct 29 19:51:51.616482 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:39584). Oct 29 19:51:51.628262 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Oct 29 19:51:51.628337 systemd[1]: update-engine.service: Failed with result 'exit-code'. Oct 29 19:51:51.628632 systemd[1]: Stopped Update Engine. Oct 29 19:51:51.629538 systemd[1]: update-engine.service: Consumed 2.368s CPU time. Oct 29 19:51:51.630081 systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 29 19:51:51.630288 systemd[1]: Stopped Load/Save Random Seed. Oct 29 19:51:51.631472 systemd[1]: boot.mount: Deactivated successfully. Oct 29 19:51:51.631708 systemd[1]: Unmounted Boot partition. Oct 29 19:51:51.632944 systemd[1]: session-30.scope: Deactivated successfully. Oct 29 19:51:51.633150 systemd[1]: Stopped Session 30 of User core. Oct 29 19:51:51.634241 systemd[1]: session-1.scope: Deactivated successfully. Oct 29 19:51:51.634470 systemd[1]: Stopped Session 1 of User core. Oct 29 19:51:51.635811 systemd-logind[921]: Session 1 logged out. Waiting for processes to exit. Oct 29 19:51:51.637026 systemd-logind[921]: Session 30 logged out. Waiting for processes to exit. Oct 29 19:51:51.639356 systemd[1]: Removed slice Slice /system/getty. Oct 29 19:51:51.640794 systemd[1]: Removed slice Slice /system/serial-getty. Oct 29 19:51:51.642087 systemd[1]: Removed slice Slice /system/sshd. Oct 29 19:51:51.642990 systemd[1]: sshd-keygen.service: Deactivated successfully. Oct 29 19:51:51.643089 systemd[1]: Stopped Generate sshd host keys. Oct 29 19:51:51.644178 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Oct 29 19:51:51.644345 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Oct 29 19:51:51.645828 systemd[1]: Stopping User Login Management... Oct 29 19:51:51.646680 systemd[1]: Stopping User Manager for UID 500... Oct 29 19:51:51.647524 systemd[958]: Stopped target Main User Target. Oct 29 19:51:51.647537 systemd[958]: Stopped target Basic System. Oct 29 19:51:51.647545 systemd[958]: Stopped target Paths. Oct 29 19:51:51.647553 systemd[958]: Stopped target Sockets. Oct 29 19:51:51.647569 systemd[958]: Reached target Shutdown. Oct 29 19:51:51.647575 systemd[958]: Stopped target Timers. Oct 29 19:51:51.647687 systemd[958]: Finished Exit the Session. Oct 29 19:51:51.647712 systemd[958]: Reached target Exit the Session. Oct 29 19:51:51.649105 systemd-logind[921]: Removed session 30. Oct 29 19:51:51.650048 systemd[1]: user@500.service: Deactivated successfully. Oct 29 19:51:51.650218 systemd[1]: Stopped User Manager for UID 500. Oct 29 19:51:51.652346 systemd[1]: Stopping User Runtime Directory /run/user/500... Oct 29 19:51:51.658118 systemd[1]: run-user-500.mount: Deactivated successfully. Oct 29 19:51:51.658181 systemd[1]: Unmounted /run/user/500. Oct 29 19:51:51.659282 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Oct 29 19:51:51.659489 systemd[1]: Stopped User Runtime Directory /run/user/500. Oct 29 19:51:51.661200 systemd[1]: Removed slice User Slice of UID 500. Oct 29 19:51:51.663578 systemd[1]: Stopping D-Bus System Message Bus... Oct 29 19:51:51.673798 systemd[1]: Stopping Permit User Sessions... Oct 29 19:51:51.675112 systemd[1]: dbus.service: Deactivated successfully. Oct 29 19:51:51.675352 systemd[1]: Stopped D-Bus System Message Bus. Oct 29 19:51:51.676323 systemd[1]: systemd-logind.service: Deactivated successfully. Oct 29 19:51:51.676570 systemd[1]: Stopped User Login Management. Oct 29 19:51:51.678306 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Oct 29 19:51:51.678519 systemd[1]: Stopped Permit User Sessions. Oct 29 19:51:51.679412 systemd[1]: Stopped target Basic System. Oct 29 19:51:51.680153 systemd[1]: Stopped target Network. Oct 29 19:51:51.680819 systemd[1]: Stopped target Path Units. Oct 29 19:51:51.680000 audit: BPF prog-id=56 op=UNLOAD Oct 29 19:51:51.682604 systemd[1]: motdgen.path: Deactivated successfully. Oct 29 19:51:51.682978 kernel: audit: type=1334 audit(1761767511.680:364): prog-id=56 op=UNLOAD Oct 29 19:51:51.684994 systemd[1]: Stopped Watch for update engine configuration changes. Oct 29 19:51:51.685933 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Oct 29 19:51:51.690013 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 19:51:51.691181 systemd[1]: Stopped target Remote File Systems. Oct 29 19:51:51.691962 systemd[1]: Stopped target Slice Units. Oct 29 19:51:51.692906 systemd[1]: Removed slice User and Session Slice. Oct 29 19:51:51.693771 systemd[1]: Stopped target Socket Units. Oct 29 19:51:51.694509 systemd[1]: dbus.socket: Deactivated successfully. Oct 29 19:51:51.694557 systemd[1]: Closed D-Bus System Message Bus Socket. Oct 29 19:51:51.695533 systemd[1]: docker.socket: Deactivated successfully. Oct 29 19:51:51.695752 systemd[1]: Closed Docker Socket for the API. Oct 29 19:51:51.696638 systemd[1]: Stopped target System Initialization. Oct 29 19:51:51.697510 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Oct 29 19:51:51.697631 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Oct 29 19:51:51.698786 systemd[1]: Stopped target Local Verity Protected Volumes. Oct 29 19:51:51.700537 systemd[1]: Stopping Load Security Auditing Rules... Oct 29 19:51:51.701570 systemd[1]: Stopping Network Name Resolution... Oct 29 19:51:51.702631 systemd[1]: Stopping Network Time Synchronization... Oct 29 19:51:51.703314 systemd[1]: systemd-update-done.service: Deactivated successfully. Oct 29 19:51:51.703478 auditctl[1355]: No rules Oct 29 19:51:51.703376 systemd[1]: Stopped Update is Completed. Oct 29 19:51:51.704223 systemd[1]: ldconfig.service: Deactivated successfully. Oct 29 19:51:51.704281 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Oct 29 19:51:51.705115 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Oct 29 19:51:51.705172 systemd[1]: Stopped Rebuild Hardware Database. Oct 29 19:51:51.706032 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Oct 29 19:51:51.706094 systemd[1]: Stopped Rebuild Journal Catalog. Oct 29 19:51:51.707730 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Oct 29 19:51:51.710356 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 29 19:51:51.710587 systemd[1]: Stopped Network Name Resolution. Oct 29 19:51:51.711681 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Oct 29 19:51:51.711914 systemd[1]: Stopped Network Time Synchronization. Oct 29 19:51:51.713220 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 19:51:51.713422 systemd[1]: Stopped Load Security Auditing Rules. Oct 29 19:51:51.713000 audit: BPF prog-id=63 op=UNLOAD Oct 29 19:51:51.715976 kernel: audit: type=1334 audit(1761767511.713:365): prog-id=63 op=UNLOAD Oct 29 19:51:51.716769 systemd[1]: Stopping Network Configuration... Oct 29 19:51:51.716000 audit: BPF prog-id=59 op=UNLOAD Oct 29 19:51:51.718685 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Oct 29 19:51:51.718898 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Oct 29 19:51:51.718966 kernel: audit: type=1334 audit(1761767511.716:366): prog-id=59 op=UNLOAD Oct 29 19:51:51.720513 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 29 19:51:51.720612 systemd[1]: Stopped Create Volatile Files and Directories. Oct 29 19:51:51.721659 systemd[1]: Stopped target Local File Systems. Oct 29 19:51:51.725699 systemd-networkd[842]: eth0: DHCPv6 lease lost Oct 29 19:51:51.726321 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Oct 29 19:51:51.728937 systemd[1]: Unmounting External Media Directory... Oct 29 19:51:51.746050 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Oct 29 19:51:51.747903 systemd[1]: Unmounting /run/torcx/unpack... Oct 29 19:51:51.749153 systemd[1]: Unmounting Temporary Directory /tmp... Oct 29 19:51:51.750386 systemd[1]: Unmounting /usr/share/oem... Oct 29 19:51:51.751916 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Oct 29 19:51:51.754714 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 29 19:51:51.755091 systemd[1]: Stopped Network Configuration. Oct 29 19:51:51.756016 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 29 19:51:51.756249 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Oct 29 19:51:51.757568 systemd[1]: media.mount: Deactivated successfully. Oct 29 19:51:51.757822 systemd[1]: Unmounted External Media Directory. Oct 29 19:51:51.758744 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Oct 29 19:51:51.759051 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Oct 29 19:51:51.760211 systemd[1]: tmp.mount: Deactivated successfully. Oct 29 19:51:51.760463 systemd[1]: Unmounted Temporary Directory /tmp. Oct 29 19:51:51.767193 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 29 19:51:51.767459 systemd[1]: Unmounted /usr/share/oem. Oct 29 19:51:51.768390 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 29 19:51:51.768618 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Oct 29 19:51:51.770103 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Oct 29 19:51:51.770323 systemd[1]: Unmounted /run/torcx/unpack. Oct 29 19:51:51.770000 audit: BPF prog-id=64 op=UNLOAD Oct 29 19:51:51.771385 systemd[1]: Stopped target Swaps. Oct 29 19:51:51.772814 systemd[1]: Reached target Unmount All Filesystems. Oct 29 19:51:51.772970 kernel: audit: type=1334 audit(1761767511.770:367): prog-id=64 op=UNLOAD Oct 29 19:51:51.773729 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 29 19:51:51.773786 systemd[1]: Closed Network Service Netlink Socket. Oct 29 19:51:51.774675 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Oct 29 19:51:51.774746 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Oct 29 19:51:51.776032 systemd[1]: Removed slice Slice /system/systemd-fsck. Oct 29 19:51:51.776914 systemd[1]: Stopped target Preparation for Local File Systems. Oct 29 19:51:51.777900 systemd[1]: lvm2-activation.service: Deactivated successfully. Oct 29 19:51:51.778164 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 29 19:51:51.779098 systemd[1]: Stopped target Local Encrypted Volumes. Oct 29 19:51:51.779906 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 19:51:51.779979 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 29 19:51:51.780937 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Oct 29 19:51:51.785004 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Oct 29 19:51:51.786124 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Oct 29 19:51:51.786193 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 29 19:51:51.787143 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 19:51:51.787197 systemd[1]: Stopped Apply Kernel Variables. Oct 29 19:51:51.787872 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 19:51:51.787923 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 29 19:51:51.788866 systemd[1]: systemd-sysusers.service: Deactivated successfully. Oct 29 19:51:51.788920 systemd[1]: Stopped Create System Users. Oct 29 19:51:51.789876 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Oct 29 19:51:51.789927 systemd[1]: Stopped Remount Root and Kernel File Systems. Oct 29 19:51:51.791055 systemd[1]: Reached target System Shutdown. Oct 29 19:51:51.791850 systemd[1]: Reached target Late Shutdown Services. Oct 29 19:51:51.792858 systemd[1]: systemd-reboot.service: Deactivated successfully. Oct 29 19:51:51.792892 systemd[1]: Finished System Reboot. Oct 29 19:51:51.793634 systemd[1]: Reached target System Reboot. Oct 29 19:51:51.794381 systemd[1]: Shutting down. Oct 29 19:51:51.795000 audit: BPF prog-id=55 op=UNLOAD Oct 29 19:51:51.795000 audit: BPF prog-id=54 op=UNLOAD Oct 29 19:51:51.795000 audit: BPF prog-id=53 op=UNLOAD Oct 29 19:51:51.797976 kernel: audit: type=1334 audit(1761767511.795:368): prog-id=55 op=UNLOAD Oct 29 19:51:51.797000 audit: BPF prog-id=58 op=UNLOAD Oct 29 19:51:51.797000 audit: BPF prog-id=57 op=UNLOAD Oct 29 19:51:51.799000 audit: BPF prog-id=62 op=UNLOAD Oct 29 19:51:51.799000 audit: BPF prog-id=61 op=UNLOAD Oct 29 19:51:51.799000 audit: BPF prog-id=60 op=UNLOAD Oct 29 19:51:51.835969 systemd-shutdown[1]: Syncing filesystems and block devices. Oct 29 19:51:51.842185 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Oct 29 19:51:51.842261 systemd-journald[805]: Journal stopped -- Reboot -- Oct 29 19:52:00.677694 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 29 19:52:00.677711 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 19:52:00.677719 kernel: efi: EFI v2.70 by EDK II Oct 29 19:52:00.677724 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 29 19:52:00.677729 kernel: efi: seeding entropy pool Oct 29 19:52:00.677734 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 29 19:52:00.677741 kernel: ACPI: Early table checksum verification disabled Oct 29 19:52:00.677746 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 29 19:52:00.677751 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 29 19:52:00.677756 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:52:00.677762 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:52:00.677767 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:52:00.677772 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:52:00.677778 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:52:00.677784 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:52:00.677789 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:52:00.677795 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:52:00.677801 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 19:52:00.677806 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 29 19:52:00.677811 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 29 19:52:00.677817 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 19:52:00.677822 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 29 19:52:00.677827 kernel: Zone ranges: Oct 29 19:52:00.677832 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 19:52:00.677838 kernel: DMA32 empty Oct 29 19:52:00.677843 kernel: Normal empty Oct 29 19:52:00.677848 kernel: Movable zone start for each node Oct 29 19:52:00.677854 kernel: Early memory node ranges Oct 29 19:52:00.677859 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 29 19:52:00.677865 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 29 19:52:00.677870 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 29 19:52:00.677875 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 29 19:52:00.677880 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 29 19:52:00.677885 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 29 19:52:00.677890 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 29 19:52:00.677896 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 29 19:52:00.677901 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 29 19:52:00.677906 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 29 19:52:00.677921 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 29 19:52:00.677927 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 19:52:00.677936 kernel: On node 0 totalpages: 643072 Oct 29 19:52:00.677942 kernel: DMA zone: 10048 pages used for memmap Oct 29 19:52:00.677948 kernel: DMA zone: 0 pages reserved Oct 29 19:52:00.677953 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 29 19:52:00.677959 kernel: psci: probing for conduit method from ACPI. Oct 29 19:52:00.677965 kernel: psci: PSCIv1.1 detected in firmware. Oct 29 19:52:00.677970 kernel: psci: Using standard PSCI v0.2 function IDs Oct 29 19:52:00.677976 kernel: psci: Trusted OS migration not required Oct 29 19:52:00.677982 kernel: psci: SMC Calling Convention v1.1 Oct 29 19:52:00.677988 kernel: ACPI: SRAT not present Oct 29 19:52:00.677994 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 29 19:52:00.677999 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 29 19:52:00.678005 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 29 19:52:00.678011 kernel: Detected PIPT I-cache on CPU0 Oct 29 19:52:00.678016 kernel: CPU features: detected: GIC system register CPU interface Oct 29 19:52:00.678022 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 29 19:52:00.678028 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 29 19:52:00.678033 kernel: CPU features: detected: Hardware dirty bit management Oct 29 19:52:00.678039 kernel: CPU features: detected: Spectre-v4 Oct 29 19:52:00.678045 kernel: CPU features: detected: ARM erratum 1418040 Oct 29 19:52:00.678051 kernel: CPU features: detected: Spectre-BHB Oct 29 19:52:00.678056 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 29 19:52:00.678062 kernel: Policy zone: DMA Oct 29 19:52:00.678068 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 19:52:00.678074 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 29 19:52:00.678080 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 29 19:52:00.678085 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 29 19:52:00.678091 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 29 19:52:00.678097 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 29 19:52:00.678103 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 29 19:52:00.678110 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 29 19:52:00.678116 kernel: rcu: RCU event tracing is enabled. Oct 29 19:52:00.678122 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 29 19:52:00.678127 kernel: Trampoline variant of Tasks RCU enabled. Oct 29 19:52:00.678134 kernel: Tracing variant of Tasks RCU enabled. Oct 29 19:52:00.678139 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 29 19:52:00.678145 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 29 19:52:00.678151 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 29 19:52:00.678156 kernel: GICv3: 256 SPIs implemented Oct 29 19:52:00.678162 kernel: GICv3: 0 Extended SPIs implemented Oct 29 19:52:00.678167 kernel: GICv3: Distributor has no Range Selector support Oct 29 19:52:00.678173 kernel: GICv3: 16 PPIs implemented Oct 29 19:52:00.678180 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 29 19:52:00.678185 kernel: ACPI: SRAT not present Oct 29 19:52:00.678190 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 29 19:52:00.678196 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 29 19:52:00.678202 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 29 19:52:00.678208 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 29 19:52:00.678214 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 29 19:52:00.678219 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 19:52:00.678225 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 29 19:52:00.678231 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 29 19:52:00.678237 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 29 19:52:00.678243 kernel: arm-pv: using stolen time PV Oct 29 19:52:00.678250 kernel: Console: colour dummy device 80x25 Oct 29 19:52:00.678256 kernel: ACPI: Core revision 20200925 Oct 29 19:52:00.678262 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 29 19:52:00.678269 kernel: pid_max: default: 32768 minimum: 301 Oct 29 19:52:00.678275 kernel: LSM: Security Framework initializing Oct 29 19:52:00.678297 kernel: SELinux: Initializing. Oct 29 19:52:00.678309 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 19:52:00.678315 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 19:52:00.678321 kernel: rcu: Hierarchical SRCU implementation. Oct 29 19:52:00.678329 kernel: Platform MSI: ITS@0x8080000 domain created Oct 29 19:52:00.678335 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 29 19:52:00.678341 kernel: Remapping and enabling EFI services. Oct 29 19:52:00.678346 kernel: smp: Bringing up secondary CPUs ... Oct 29 19:52:00.678352 kernel: Detected PIPT I-cache on CPU1 Oct 29 19:52:00.678358 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 29 19:52:00.678364 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 29 19:52:00.678369 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 19:52:00.678375 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 29 19:52:00.678393 kernel: Detected PIPT I-cache on CPU2 Oct 29 19:52:00.678400 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 29 19:52:00.678406 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 29 19:52:00.678414 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 19:52:00.678425 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 29 19:52:00.678433 kernel: Detected PIPT I-cache on CPU3 Oct 29 19:52:00.678443 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 29 19:52:00.678449 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 29 19:52:00.678456 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 19:52:00.678462 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 29 19:52:00.678469 kernel: smp: Brought up 1 node, 4 CPUs Oct 29 19:52:00.678475 kernel: SMP: Total of 4 processors activated. Oct 29 19:52:00.678481 kernel: CPU features: detected: Privileged Access Never Oct 29 19:52:00.678487 kernel: CPU features: detected: LSE atomic instructions Oct 29 19:52:00.678493 kernel: CPU features: detected: User Access Override Oct 29 19:52:00.678499 kernel: CPU features: detected: 32-bit EL0 Support Oct 29 19:52:00.678505 kernel: CPU features: detected: Common not Private translations Oct 29 19:52:00.678512 kernel: CPU features: detected: RAS Extension Support Oct 29 19:52:00.678519 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 29 19:52:00.678526 kernel: CPU features: detected: CRC32 instructions Oct 29 19:52:00.678532 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 29 19:52:00.678538 kernel: CPU: All CPU(s) started at EL1 Oct 29 19:52:00.678544 kernel: alternatives: patching kernel code Oct 29 19:52:00.678551 kernel: devtmpfs: initialized Oct 29 19:52:00.678557 kernel: KASLR enabled Oct 29 19:52:00.678563 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 29 19:52:00.678569 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 29 19:52:00.678576 kernel: pinctrl core: initialized pinctrl subsystem Oct 29 19:52:00.678582 kernel: SMBIOS 3.0.0 present. Oct 29 19:52:00.678588 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 29 19:52:00.678594 kernel: NET: Registered protocol family 16 Oct 29 19:52:00.678600 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 29 19:52:00.678606 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 29 19:52:00.678613 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 29 19:52:00.678620 kernel: audit: initializing netlink subsys (disabled) Oct 29 19:52:00.678626 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Oct 29 19:52:00.678632 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 29 19:52:00.678638 kernel: cpuidle: using governor menu Oct 29 19:52:00.678644 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 29 19:52:00.678678 kernel: ASID allocator initialised with 32768 entries Oct 29 19:52:00.678685 kernel: ACPI: bus type PCI registered Oct 29 19:52:00.678691 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 29 19:52:00.678700 kernel: Serial: AMBA PL011 UART driver Oct 29 19:52:00.678706 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 29 19:52:00.678712 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 29 19:52:00.678718 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 29 19:52:00.678725 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 29 19:52:00.678731 kernel: cryptd: max_cpu_qlen set to 1000 Oct 29 19:52:00.678737 kernel: ACPI: Added _OSI(Module Device) Oct 29 19:52:00.678743 kernel: ACPI: Added _OSI(Processor Device) Oct 29 19:52:00.678749 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 29 19:52:00.678756 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 29 19:52:00.678762 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 29 19:52:00.678769 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 29 19:52:00.678775 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 29 19:52:00.678781 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 29 19:52:00.678787 kernel: ACPI: Interpreter enabled Oct 29 19:52:00.678793 kernel: ACPI: Using GIC for interrupt routing Oct 29 19:52:00.678800 kernel: ACPI: MCFG table detected, 1 entries Oct 29 19:52:00.678806 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 29 19:52:00.678812 kernel: printk: console [ttyAMA0] enabled Oct 29 19:52:00.678820 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 29 19:52:00.678936 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 29 19:52:00.678995 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 29 19:52:00.679047 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 29 19:52:00.679100 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 29 19:52:00.679155 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 29 19:52:00.679163 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 29 19:52:00.679172 kernel: PCI host bridge to bus 0000:00 Oct 29 19:52:00.679232 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 29 19:52:00.679284 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 29 19:52:00.679332 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 29 19:52:00.679382 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 29 19:52:00.679450 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 29 19:52:00.679513 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 29 19:52:00.679574 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 29 19:52:00.679631 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 29 19:52:00.679699 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 19:52:00.679759 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 19:52:00.679827 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 29 19:52:00.679882 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 29 19:52:00.679940 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 29 19:52:00.679993 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 29 19:52:00.680042 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 29 19:52:00.680051 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 29 19:52:00.680057 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 29 19:52:00.680063 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 29 19:52:00.680070 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 29 19:52:00.680076 kernel: vgaarb: loaded Oct 29 19:52:00.680082 kernel: Registered efivars operations Oct 29 19:52:00.680089 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 29 19:52:00.680096 kernel: VFS: Disk quotas dquot_6.6.0 Oct 29 19:52:00.680103 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 29 19:52:00.680109 kernel: pnp: PnP ACPI init Oct 29 19:52:00.680171 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 29 19:52:00.680223 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 29 19:52:00.680231 kernel: pnp: PnP ACPI: found 1 devices Oct 29 19:52:00.680237 kernel: NET: Registered protocol family 2 Oct 29 19:52:00.680244 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 29 19:52:00.680252 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 29 19:52:00.680259 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 29 19:52:00.680265 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 29 19:52:00.680272 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 29 19:52:00.680278 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 19:52:00.680284 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 19:52:00.680290 kernel: NET: Registered protocol family 1 Oct 29 19:52:00.680296 kernel: PCI: CLS 0 bytes, default 64 Oct 29 19:52:00.680302 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 29 19:52:00.680310 kernel: kvm [1]: HYP mode not available Oct 29 19:52:00.680316 kernel: Initialise system trusted keyrings Oct 29 19:52:00.680323 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 29 19:52:00.680329 kernel: Key type asymmetric registered Oct 29 19:52:00.680335 kernel: Asymmetric key parser 'x509' registered Oct 29 19:52:00.680341 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 29 19:52:00.680347 kernel: io scheduler mq-deadline registered Oct 29 19:52:00.680353 kernel: io scheduler kyber registered Oct 29 19:52:00.680360 kernel: io scheduler bfq registered Oct 29 19:52:00.680367 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 29 19:52:00.680373 kernel: ACPI: Power Button [PWRB] Oct 29 19:52:00.680380 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 29 19:52:00.680434 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 29 19:52:00.680442 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 29 19:52:00.680449 kernel: msm_serial: driver initialized Oct 29 19:52:00.680455 kernel: random: fast init done Oct 29 19:52:00.680462 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 29 19:52:00.680468 kernel: random: crng init done Oct 29 19:52:00.680474 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 29 19:52:00.680482 kernel: thunder_xcv, ver 1.0 Oct 29 19:52:00.680488 kernel: thunder_bgx, ver 1.0 Oct 29 19:52:00.680494 kernel: nicpf, ver 1.0 Oct 29 19:52:00.680500 kernel: nicvf, ver 1.0 Oct 29 19:52:00.680563 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 29 19:52:00.680616 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-29T19:52:00 UTC (1761767520) Oct 29 19:52:00.680625 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 29 19:52:00.680631 kernel: NET: Registered protocol family 10 Oct 29 19:52:00.680639 kernel: Segment Routing with IPv6 Oct 29 19:52:00.680666 kernel: NET: Registered protocol family 17 Oct 29 19:52:00.680673 kernel: Key type dns_resolver registered Oct 29 19:52:00.680679 kernel: registered taskstats version 1 Oct 29 19:52:00.680685 kernel: Loading compiled-in X.509 certificates Oct 29 19:52:00.680691 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 29 19:52:00.680697 kernel: Key type ._fscrypt registered Oct 29 19:52:00.680703 kernel: Key type .fscrypt registered Oct 29 19:52:00.680710 kernel: Key type fscrypt-provisioning registered Oct 29 19:52:00.680717 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 29 19:52:00.680723 kernel: ima: Allocated hash algorithm: sha1 Oct 29 19:52:00.680729 kernel: ima: No architecture policies found Oct 29 19:52:00.680735 kernel: Freeing unused kernel memory: 34240K Oct 29 19:52:00.680742 kernel: Run /init as init process Oct 29 19:52:00.680748 kernel: with arguments: Oct 29 19:52:00.680754 kernel: /init Oct 29 19:52:00.680760 kernel: with environment: Oct 29 19:52:00.680765 kernel: HOME=/ Oct 29 19:52:00.680771 kernel: TERM=linux Oct 29 19:52:00.680778 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 29 19:52:00.680786 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 19:52:00.680795 systemd[1]: Detected virtualization kvm. Oct 29 19:52:00.680801 systemd[1]: Detected architecture arm64. Oct 29 19:52:00.680808 systemd[1]: Running in initial RAM disk. Oct 29 19:52:00.680814 systemd[1]: No hostname configured, using default hostname. Oct 29 19:52:00.680821 systemd[1]: Hostname set to . Oct 29 19:52:00.680829 systemd[1]: Initializing machine ID from VM UUID. Oct 29 19:52:00.680836 systemd[1]: Queued start job for default target Initrd Default Target. Oct 29 19:52:00.680842 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 19:52:00.680849 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 19:52:00.680855 systemd[1]: Reached target Path Units. Oct 29 19:52:00.680862 systemd[1]: Reached target Slice Units. Oct 29 19:52:00.680868 systemd[1]: Reached target Swaps. Oct 29 19:52:00.680875 systemd[1]: Reached target Timer Units. Oct 29 19:52:00.680883 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 29 19:52:00.680889 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 29 19:52:00.680896 systemd[1]: Listening on Journal Audit Socket. Oct 29 19:52:00.680902 systemd[1]: Listening on Journal Socket (/dev/log). Oct 29 19:52:00.680914 systemd[1]: Listening on Journal Socket. Oct 29 19:52:00.680921 systemd[1]: Listening on udev Control Socket. Oct 29 19:52:00.680928 systemd[1]: Listening on udev Kernel Socket. Oct 29 19:52:00.680935 systemd[1]: Reached target Socket Units. Oct 29 19:52:00.680941 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 29 19:52:00.680950 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 19:52:00.680956 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 29 19:52:00.680963 systemd[1]: Starting Journal Service... Oct 29 19:52:00.680970 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 19:52:00.680976 systemd[1]: Starting Apply Kernel Variables... Oct 29 19:52:00.680983 systemd[1]: Starting Setup Virtual Console... Oct 29 19:52:00.680989 kernel: SCSI subsystem initialized Oct 29 19:52:00.680996 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 29 19:52:00.681002 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 19:52:00.681009 kernel: Loading iSCSI transport class v2.0-870. Oct 29 19:52:00.681016 systemd[1]: Finished Apply Kernel Variables. Oct 29 19:52:00.681023 systemd[1]: Finished Setup Virtual Console. Oct 29 19:52:00.681032 systemd-journald[270]: Journal started Oct 29 19:52:00.681072 systemd-journald[270]: Runtime Journal (/run/log/journal/2c84dc9bbd9a46a29f3a296e04c5ff21) is 6.0M, max 48.5M, 42.4M free. Oct 29 19:52:00.682691 systemd[1]: Started Journal Service. Oct 29 19:52:00.683418 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 29 19:52:00.684987 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 19:52:00.688422 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 19:52:00.697375 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 29 19:52:00.699138 systemd[1]: Starting dracut cmdline hook... Oct 29 19:52:00.707711 dracut-cmdline[290]: dracut-dracut-053 Oct 29 19:52:00.709928 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 19:52:00.766691 kernel: iscsi: registered transport (tcp) Oct 29 19:52:00.777897 kernel: iscsi: registered transport (qla4xxx) Oct 29 19:52:00.777930 kernel: QLogic iSCSI HBA Driver Oct 29 19:52:00.787903 systemd[1]: Finished dracut cmdline hook. Oct 29 19:52:00.789516 systemd[1]: Starting dracut pre-udev hook... Oct 29 19:52:00.790790 systemd[1]: Starting Open-iSCSI... Oct 29 19:52:00.793142 iscsid[376]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 29 19:52:00.793142 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 19:52:00.793142 iscsid[376]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 29 19:52:00.793142 iscsid[376]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 29 19:52:00.793142 iscsid[376]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 29 19:52:00.804448 iscsid[376]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 29 19:52:00.806878 kernel: device-mapper: uevent: version 1.0.3 Oct 29 19:52:00.806899 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 29 19:52:00.796867 systemd[1]: Started Open-iSCSI. Oct 29 19:52:00.839678 kernel: raid6: neonx8 gen() 13691 MB/s Oct 29 19:52:00.856670 kernel: raid6: neonx8 xor() 10751 MB/s Oct 29 19:52:00.873661 kernel: raid6: neonx4 gen() 13539 MB/s Oct 29 19:52:00.890675 kernel: raid6: neonx4 xor() 11494 MB/s Oct 29 19:52:00.907674 kernel: raid6: neonx2 gen() 12975 MB/s Oct 29 19:52:00.924670 kernel: raid6: neonx2 xor() 9849 MB/s Oct 29 19:52:00.941664 kernel: raid6: neonx1 gen() 10949 MB/s Oct 29 19:52:00.958670 kernel: raid6: neonx1 xor() 8784 MB/s Oct 29 19:52:00.975668 kernel: raid6: int64x8 gen() 6796 MB/s Oct 29 19:52:00.992680 kernel: raid6: int64x8 xor() 3708 MB/s Oct 29 19:52:01.009673 kernel: raid6: int64x4 gen() 6827 MB/s Oct 29 19:52:01.026668 kernel: raid6: int64x4 xor() 3824 MB/s Oct 29 19:52:01.043668 kernel: raid6: int64x2 gen() 6174 MB/s Oct 29 19:52:01.060670 kernel: raid6: int64x2 xor() 3265 MB/s Oct 29 19:52:01.077681 kernel: raid6: int64x1 gen() 4984 MB/s Oct 29 19:52:01.094735 kernel: raid6: int64x1 xor() 2588 MB/s Oct 29 19:52:01.094756 kernel: raid6: using algorithm neonx8 gen() 13691 MB/s Oct 29 19:52:01.094772 kernel: raid6: .... xor() 10751 MB/s, rmw enabled Oct 29 19:52:01.095832 kernel: raid6: using neon recovery algorithm Oct 29 19:52:01.105666 kernel: xor: measuring software checksum speed Oct 29 19:52:01.106679 kernel: 8regs : 17163 MB/sec Oct 29 19:52:01.107669 kernel: 32regs : 20765 MB/sec Oct 29 19:52:01.108851 kernel: arm64_neon : 29153 MB/sec Oct 29 19:52:01.108861 kernel: xor: using function: arm64_neon (29153 MB/sec) Oct 29 19:52:01.153671 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 29 19:52:01.163564 systemd[1]: Finished dracut pre-udev hook. Oct 29 19:52:01.164000 audit: BPF prog-id=6 op=LOAD Oct 29 19:52:01.166000 audit: BPF prog-id=7 op=LOAD Oct 29 19:52:01.166937 kernel: audit: type=1334 audit(1761767521.164:2): prog-id=6 op=LOAD Oct 29 19:52:01.166963 kernel: audit: type=1334 audit(1761767521.166:3): prog-id=7 op=LOAD Oct 29 19:52:01.166972 kernel: audit: type=1334 audit(1761767521.166:4): prog-id=8 op=LOAD Oct 29 19:52:01.166000 audit: BPF prog-id=8 op=LOAD Oct 29 19:52:01.167285 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 19:52:01.179277 systemd-udevd[409]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 19:52:01.182185 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 19:52:01.185731 systemd[1]: Starting dracut pre-trigger hook... Oct 29 19:52:01.196319 dracut-pre-trigger[420]: rd.md=0: removing MD RAID activation Oct 29 19:52:01.218393 systemd[1]: Finished dracut pre-trigger hook. Oct 29 19:52:01.219857 systemd[1]: Starting Coldplug All udev Devices... Oct 29 19:52:01.229122 systemd-udevd[409]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 19:52:01.248844 systemd[1]: Finished Coldplug All udev Devices. Oct 29 19:52:01.250482 systemd[1]: Starting dracut initqueue hook... Oct 29 19:52:01.277717 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 29 19:52:01.277866 kernel: vda: detected capacity change from 0 to 4756340736 Oct 29 19:52:01.288499 systemd-udevd[451]: Using default interface naming scheme 'v249'. Oct 29 19:52:01.290684 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 19:52:01.306971 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 29 19:52:01.308510 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 29 19:52:01.311331 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (459) Oct 29 19:52:01.312264 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 29 19:52:01.321600 systemd[1]: Finished dracut initqueue hook. Oct 29 19:52:01.322499 systemd[1]: Reached target Initrd Root Device. Oct 29 19:52:01.323826 systemd[1]: Reached target Preparation for Remote File Systems. Oct 29 19:52:01.325393 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 19:52:01.327035 systemd[1]: Reached target Remote File Systems. Oct 29 19:52:01.329134 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 29 19:52:01.330708 systemd[1]: Starting dracut pre-mount hook... Oct 29 19:52:01.332507 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 29 19:52:01.332611 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 29 19:52:01.333750 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 19:52:01.335924 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 29 19:52:01.339031 systemd[1]: Finished dracut pre-mount hook. Oct 29 19:52:01.341054 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 29 19:52:01.349929 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 29 19:52:01.351320 systemd-fsck[500]: ROOT: clean, 640/553792 files, 39961/553472 blocks Oct 29 19:52:01.353989 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 29 19:52:01.355579 systemd[1]: Mounting /sysroot... Oct 29 19:52:01.373246 systemd[1]: Found device /dev/mapper/usr. Oct 29 19:52:01.374730 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 29 19:52:01.380864 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 29 19:52:01.385994 systemd-fsck[514]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 29 19:52:01.385994 systemd-fsck[514]: You must have r/w access to the filesystem or be root Oct 29 19:52:01.386879 systemd-fsck[509]: fsck failed with exit status 8. Oct 29 19:52:01.386883 systemd-fsck[509]: Ignoring error. Oct 29 19:52:01.387498 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 29 19:52:01.393315 systemd[1]: Mounting /sysusr/usr... Oct 29 19:52:01.398435 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 29 19:52:01.398467 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 29 19:52:01.398477 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 29 19:52:01.399138 systemd[1]: Mounted /sysroot. Oct 29 19:52:01.400466 systemd[1]: Mounted /sysusr/usr. Oct 29 19:52:01.401384 systemd[1]: Reached target Initrd Root File System. Oct 29 19:52:01.402523 systemd[1]: Reached target Local File Systems. Oct 29 19:52:01.403972 systemd[1]: Reached target System Initialization. Oct 29 19:52:01.405496 systemd[1]: Reached target Basic System. Oct 29 19:52:01.408914 systemd[1]: Mounting /sysroot/usr... Oct 29 19:52:01.410130 systemd[1]: Starting Reload Configuration from the Real Root... Oct 29 19:52:01.411632 systemd[1]: Mounted /sysroot/usr. Oct 29 19:52:01.414298 systemd[1]: Reloading. Oct 29 19:52:01.414000 audit: BPF prog-id=3 op=UNLOAD Oct 29 19:52:01.416673 kernel: audit: type=1334 audit(1761767521.414:5): prog-id=3 op=UNLOAD Oct 29 19:52:01.416000 audit: BPF prog-id=6 op=UNLOAD Oct 29 19:52:01.418653 kernel: audit: type=1334 audit(1761767521.416:6): prog-id=6 op=UNLOAD Oct 29 19:52:01.495000 audit: BPF prog-id=9 op=LOAD Oct 29 19:52:01.497000 audit: BPF prog-id=10 op=LOAD Oct 29 19:52:01.498454 kernel: audit: type=1334 audit(1761767521.495:7): prog-id=9 op=LOAD Oct 29 19:52:01.498476 kernel: audit: type=1334 audit(1761767521.497:8): prog-id=10 op=LOAD Oct 29 19:52:01.497000 audit: BPF prog-id=11 op=LOAD Oct 29 19:52:01.499490 kernel: audit: type=1334 audit(1761767521.497:9): prog-id=11 op=LOAD Oct 29 19:52:01.499000 audit: BPF prog-id=4 op=UNLOAD Oct 29 19:52:01.500446 kernel: audit: type=1334 audit(1761767521.499:10): prog-id=4 op=UNLOAD Oct 29 19:52:01.500000 audit: BPF prog-id=5 op=UNLOAD Oct 29 19:52:01.500000 audit: BPF prog-id=12 op=LOAD Oct 29 19:52:01.500000 audit: BPF prog-id=13 op=LOAD Oct 29 19:52:01.500000 audit: BPF prog-id=14 op=LOAD Oct 29 19:52:01.500000 audit: BPF prog-id=7 op=UNLOAD Oct 29 19:52:01.500000 audit: BPF prog-id=8 op=UNLOAD Oct 29 19:52:01.515068 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 29 19:52:01.515205 systemd[1]: Finished Reload Configuration from the Real Root. Oct 29 19:52:01.517000 systemd[1]: Reached target Initrd File Systems. Oct 29 19:52:01.518474 systemd[1]: Reached target Initrd Default Target. Oct 29 19:52:01.519950 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 29 19:52:01.520587 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 29 19:52:01.530546 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 29 19:52:01.532248 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 29 19:52:01.537750 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 29 19:52:01.538744 systemd[1]: Stopped target Timer Units. Oct 29 19:52:01.540324 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 29 19:52:01.540429 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 29 19:52:01.541989 systemd[1]: Stopped target Initrd Default Target. Oct 29 19:52:01.543535 systemd[1]: Stopped target Basic System. Oct 29 19:52:01.545002 systemd[1]: Stopped target Initrd Root Device. Oct 29 19:52:01.546507 systemd[1]: Stopped target Path Units. Oct 29 19:52:01.547705 systemd[1]: Stopped target Remote File Systems. Oct 29 19:52:01.549220 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 29 19:52:01.550612 systemd[1]: Stopped target Slice Units. Oct 29 19:52:01.551929 systemd[1]: Stopped target Socket Units. Oct 29 19:52:01.553362 systemd[1]: Stopped target System Initialization. Oct 29 19:52:01.554668 systemd[1]: Stopped target Local File Systems. Oct 29 19:52:01.556073 systemd[1]: Stopped target Preparation for Local File Systems. Oct 29 19:52:01.557722 systemd[1]: Stopped target Swaps. Oct 29 19:52:01.558995 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 29 19:52:01.559101 systemd[1]: Stopped dracut pre-mount hook. Oct 29 19:52:01.560641 systemd[1]: Stopped target Local Encrypted Volumes. Oct 29 19:52:01.561947 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 19:52:01.565711 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 29 19:52:01.566942 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 29 19:52:01.567044 systemd[1]: Stopped dracut initqueue hook. Oct 29 19:52:01.572043 iscsid[376]: iscsid shutting down. Oct 29 19:52:01.568857 systemd[1]: Stopping Open-iSCSI... Oct 29 19:52:01.571390 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 19:52:01.571609 systemd[1]: Stopped Apply Kernel Variables. Oct 29 19:52:01.572841 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 29 19:52:01.572939 systemd[1]: Stopped Coldplug All udev Devices. Oct 29 19:52:01.574117 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 29 19:52:01.574212 systemd[1]: Stopped dracut pre-trigger hook. Oct 29 19:52:01.575404 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 29 19:52:01.579493 systemd[1]: iscsid.service: Deactivated successfully. Oct 29 19:52:01.579611 systemd[1]: Stopped Open-iSCSI. Oct 29 19:52:01.581617 systemd[1]: iscsid.socket: Deactivated successfully. Oct 29 19:52:01.581799 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 29 19:52:01.582959 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 29 19:52:01.585212 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 29 19:52:01.585330 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 29 19:52:01.586955 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 29 19:52:01.587052 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 29 19:52:01.588687 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 29 19:52:01.601000 audit: BPF prog-id=12 op=UNLOAD Oct 29 19:52:01.588792 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 29 19:52:01.590813 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 29 19:52:01.590863 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 29 19:52:01.592379 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 29 19:52:01.592419 systemd[1]: Closed udev Control Socket. Oct 29 19:52:01.593679 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 29 19:52:01.593710 systemd[1]: Closed udev Kernel Socket. Oct 29 19:52:01.595017 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 29 19:52:01.595058 systemd[1]: Stopped dracut pre-udev hook. Oct 29 19:52:01.596454 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 29 19:52:01.596495 systemd[1]: Stopped dracut cmdline hook. Oct 29 19:52:01.597958 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 29 19:52:01.597997 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 29 19:52:01.600266 systemd[1]: Starting Cleanup udev Database... Oct 29 19:52:01.601195 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 29 19:52:01.602842 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 19:52:01.621000 audit: BPF prog-id=9 op=UNLOAD Oct 29 19:52:01.602900 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 29 19:52:01.604661 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 29 19:52:01.604705 systemd[1]: Stopped Create List of Static Device Nodes. Oct 29 19:52:01.606476 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 19:52:01.606518 systemd[1]: Stopped Setup Virtual Console. Oct 29 19:52:01.608214 systemd[1]: rngd.service: Deactivated successfully. Oct 29 19:52:01.608321 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 29 19:52:01.610114 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 29 19:52:01.610210 systemd[1]: Finished Cleanup udev Database. Oct 29 19:52:01.611574 systemd[1]: Reached target Switch Root. Oct 29 19:52:01.613784 systemd[1]: Starting Switch Root... Oct 29 19:52:01.621563 systemd[1]: Switching root. Oct 29 19:52:01.636882 systemd-journald[270]: Journal stopped Oct 29 19:52:03.700730 systemd-journald[270]: Received SIGTERM from PID 1 (systemd). Oct 29 19:52:03.700774 kernel: SELinux: policy capability network_peer_controls=1 Oct 29 19:52:03.700786 kernel: SELinux: policy capability open_perms=1 Oct 29 19:52:03.700798 kernel: SELinux: policy capability extended_socket_class=1 Oct 29 19:52:03.700809 kernel: SELinux: policy capability always_check_network=0 Oct 29 19:52:03.700823 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 29 19:52:03.700832 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 29 19:52:03.700841 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 29 19:52:03.700850 systemd[1]: Successfully loaded SELinux policy in 31.824ms. Oct 29 19:52:03.700865 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.160ms. Oct 29 19:52:03.700876 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 19:52:03.700894 systemd[1]: Detected virtualization kvm. Oct 29 19:52:03.700905 systemd[1]: Detected architecture arm64. Oct 29 19:52:03.700916 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 19:52:03.700928 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 29 19:52:03.700939 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 29 19:52:03.700948 systemd[1]: Stopped Switch Root. Oct 29 19:52:03.700957 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 29 19:52:03.700967 systemd[1]: Created slice Slice /system/addon-config. Oct 29 19:52:03.700977 systemd[1]: Created slice Slice /system/addon-run. Oct 29 19:52:03.700987 systemd[1]: Created slice Slice /system/getty. Oct 29 19:52:03.701010 systemd[1]: Created slice Slice /system/modprobe. Oct 29 19:52:03.701020 systemd[1]: Created slice Slice /system/serial-getty. Oct 29 19:52:03.701030 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 29 19:52:03.701039 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 29 19:52:03.701049 systemd[1]: Created slice User and Session Slice. Oct 29 19:52:03.701059 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 19:52:03.701069 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 29 19:52:03.701080 systemd[1]: Set up automount Boot partition Automount Point. Oct 29 19:52:03.701102 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 29 19:52:03.701117 systemd[1]: Stopped target Switch Root. Oct 29 19:52:03.701128 systemd[1]: Stopped target Initrd File Systems. Oct 29 19:52:03.701137 systemd[1]: Stopped target Initrd Root File System. Oct 29 19:52:03.701148 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 19:52:03.701158 systemd[1]: Reached target Remote File Systems. Oct 29 19:52:03.701168 systemd[1]: Reached target Slice Units. Oct 29 19:52:03.701178 systemd[1]: Reached target Swaps. Oct 29 19:52:03.701187 systemd[1]: Reached target Verify torcx succeeded. Oct 29 19:52:03.701197 systemd[1]: Reached target Local Verity Protected Volumes. Oct 29 19:52:03.701207 systemd[1]: Listening on Process Core Dump Socket. Oct 29 19:52:03.701219 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 29 19:52:03.701228 systemd[1]: Listening on Network Service Netlink Socket. Oct 29 19:52:03.701240 systemd[1]: Listening on udev Control Socket. Oct 29 19:52:03.701249 systemd[1]: Listening on udev Kernel Socket. Oct 29 19:52:03.701259 systemd[1]: Mounting Huge Pages File System... Oct 29 19:52:03.701268 systemd[1]: Mounting POSIX Message Queue File System... Oct 29 19:52:03.701278 systemd[1]: Mounting External Media Directory... Oct 29 19:52:03.701289 systemd[1]: Mounting Kernel Debug File System... Oct 29 19:52:03.701299 systemd[1]: Mounting Kernel Trace File System... Oct 29 19:52:03.701310 systemd[1]: Mounting Temporary Directory /tmp... Oct 29 19:52:03.701326 systemd[1]: Starting Create missing system files... Oct 29 19:52:03.701336 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 19:52:03.701346 systemd[1]: Starting Load Kernel Module configfs... Oct 29 19:52:03.701355 systemd[1]: Starting Load Kernel Module drm... Oct 29 19:52:03.701365 systemd[1]: Starting Load Kernel Module fuse... Oct 29 19:52:03.701375 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 29 19:52:03.701384 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 29 19:52:03.701394 systemd[1]: Stopped File System Check on Root Device. Oct 29 19:52:03.701405 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 29 19:52:03.701415 systemd[1]: Stopped systemd-fsck-usr.service. Oct 29 19:52:03.701424 kernel: fuse: init (API version 7.32) Oct 29 19:52:03.701433 systemd[1]: Stopped Journal Service. Oct 29 19:52:03.701443 systemd[1]: Starting Journal Service... Oct 29 19:52:03.701454 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 19:52:03.701465 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 29 19:52:03.701475 systemd[1]: Starting Apply Kernel Variables... Oct 29 19:52:03.701484 systemd[1]: Starting Coldplug All udev Devices... Oct 29 19:52:03.701494 systemd[1]: verity-setup.service: Deactivated successfully. Oct 29 19:52:03.701505 systemd[1]: Stopped verity-setup.service. Oct 29 19:52:03.701517 systemd-journald[690]: Journal started Oct 29 19:52:03.701554 systemd-journald[690]: Runtime Journal (/run/log/journal/2c84dc9bbd9a46a29f3a296e04c5ff21) is 6.0M, max 48.5M, 42.4M free. Oct 29 19:52:01.708000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 29 19:52:01.735000 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 19:52:01.735000 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 19:52:01.735000 audit: BPF prog-id=15 op=LOAD Oct 29 19:52:01.735000 audit: BPF prog-id=15 op=UNLOAD Oct 29 19:52:01.735000 audit: BPF prog-id=16 op=LOAD Oct 29 19:52:01.735000 audit: BPF prog-id=16 op=UNLOAD Oct 29 19:52:01.773000 audit[642]: AVC avc: denied { associate } for pid=642 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 19:52:03.576000 audit: BPF prog-id=17 op=LOAD Oct 29 19:52:03.576000 audit: BPF prog-id=18 op=LOAD Oct 29 19:52:03.576000 audit: BPF prog-id=19 op=LOAD Oct 29 19:52:03.576000 audit: BPF prog-id=10 op=UNLOAD Oct 29 19:52:03.576000 audit: BPF prog-id=11 op=UNLOAD Oct 29 19:52:03.585000 audit: BPF prog-id=17 op=UNLOAD Oct 29 19:52:03.687000 audit: BPF prog-id=20 op=LOAD Oct 29 19:52:03.687000 audit: BPF prog-id=21 op=LOAD Oct 29 19:52:03.687000 audit: BPF prog-id=22 op=LOAD Oct 29 19:52:03.687000 audit: BPF prog-id=18 op=UNLOAD Oct 29 19:52:03.687000 audit: BPF prog-id=19 op=UNLOAD Oct 29 19:52:03.699000 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 19:52:03.566990 systemd[1]: Queued start job for default target Multi-User System. Oct 29 19:52:01.772102 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" 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 19:52:03.577679 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 29 19:52:01.772384 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 19:52:01.772403 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 19:52:01.772735 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 29 19:52:01.772747 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 29 19:52:01.772783 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 29 19:52:01.772797 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 29 19:52:01.773366 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 29 19:52:01.773407 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 19:52:01.773425 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 19:52:01.773824 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" 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 19:52:01.773855 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" 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 19:52:01.773876 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" 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 19:52:01.773891 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" 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 19:52:01.774033 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" 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 19:52:01.774050 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:01Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 29 19:52:03.477920 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:03Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 19:52:03.478186 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:03Z" 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 19:52:03.478288 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:03Z" 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 19:52:03.478414 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:03Z" 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 19:52:03.478507 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:03Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 29 19:52:03.478563 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-29T19:52:03Z" 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 19:52:03.705681 systemd[1]: Started Journal Service. Oct 29 19:52:03.706181 systemd[1]: Mounted Huge Pages File System. Oct 29 19:52:03.707136 systemd[1]: Mounted POSIX Message Queue File System. Oct 29 19:52:03.708121 systemd[1]: Mounted External Media Directory. Oct 29 19:52:03.709097 systemd[1]: Mounted Kernel Debug File System. Oct 29 19:52:03.710117 systemd[1]: Mounted Kernel Trace File System. Oct 29 19:52:03.711099 systemd[1]: Mounted Temporary Directory /tmp. Oct 29 19:52:03.712292 systemd[1]: Finished Create missing system files. Oct 29 19:52:03.713618 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 19:52:03.714974 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 29 19:52:03.715165 systemd[1]: Finished Load Kernel Module configfs. Oct 29 19:52:03.728984 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 19:52:03.729190 systemd[1]: Finished Load Kernel Module drm. Oct 29 19:52:03.730184 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 29 19:52:03.730364 systemd[1]: Finished Load Kernel Module fuse. Oct 29 19:52:03.731644 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 29 19:52:03.732979 systemd[1]: Finished Apply Kernel Variables. Oct 29 19:52:03.736032 systemd[1]: Mounting FUSE Control File System... Oct 29 19:52:03.738009 systemd[1]: Mounting Kernel Configuration File System... Oct 29 19:52:03.739212 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 29 19:52:03.740262 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 29 19:52:03.741254 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 29 19:52:03.742397 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 29 19:52:03.743392 systemd[1]: Starting Load/Save Random Seed... Oct 29 19:52:03.746683 systemd-journald[690]: Time spent on flushing to /var/log/journal/2c84dc9bbd9a46a29f3a296e04c5ff21 is 11.332ms for 654 entries. Oct 29 19:52:03.746683 systemd-journald[690]: System Journal (/var/log/journal/2c84dc9bbd9a46a29f3a296e04c5ff21) is 8.0M, max 203.0M, 195.0M free. Oct 29 19:52:03.767000 audit: BPF prog-id=23 op=LOAD Oct 29 19:52:03.767000 audit: BPF prog-id=24 op=LOAD Oct 29 19:52:03.767000 audit: BPF prog-id=25 op=LOAD Oct 29 19:52:03.767000 audit: BPF prog-id=13 op=UNLOAD Oct 29 19:52:03.767000 audit: BPF prog-id=14 op=UNLOAD Oct 29 19:52:03.744328 systemd[1]: Condition check resulted in Create System Users being skipped. Oct 29 19:52:03.745348 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 19:52:03.747173 systemd[1]: Mounted FUSE Control File System. Oct 29 19:52:03.749349 systemd[1]: Mounted Kernel Configuration File System. Oct 29 19:52:03.751775 systemd[1]: Finished Load/Save Random Seed. Oct 29 19:52:03.753321 systemd[1]: Condition check resulted in First Boot Complete being skipped. Oct 29 19:52:03.756351 systemd[1]: Finished Coldplug All udev Devices. Oct 29 19:52:03.764699 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 29 19:52:03.766614 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 19:52:03.769203 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 19:52:03.772704 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 29 19:52:03.801802 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 19:52:03.803000 audit: BPF prog-id=26 op=LOAD Oct 29 19:52:03.805026 systemd[1]: Starting Network Configuration... Oct 29 19:52:03.833995 systemd-networkd[721]: lo: Link UP Oct 29 19:52:03.834003 systemd-networkd[721]: lo: Gained carrier Oct 29 19:52:03.834333 systemd-networkd[721]: Enumeration completed Oct 29 19:52:03.834424 systemd[1]: Started Network Configuration. Oct 29 19:52:03.842825 systemd-udevd[719]: Using default interface naming scheme 'v249'. Oct 29 19:52:03.861473 udevadm[703]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 29 19:52:03.876185 systemd-networkd[721]: eth0: Link UP Oct 29 19:52:03.884755 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 29 19:52:03.886705 systemd-networkd[721]: eth0: Gained carrier Oct 29 19:52:03.893546 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 19:52:03.897761 systemd-networkd[721]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 29 19:52:03.901898 lvm[732]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 19:52:03.925920 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 19:52:03.927063 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 19:52:03.939753 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 19:52:03.943170 lvm[733]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 19:52:03.973746 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 19:52:03.974642 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 19:52:03.975600 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 29 19:52:03.975628 systemd[1]: Reached target Containers. Oct 29 19:52:03.985965 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 29 19:52:03.998774 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 29 19:52:04.008505 systemd[1]: Mounting /usr/share/oem... Oct 29 19:52:04.015102 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 29 19:52:04.015230 kernel: BTRFS info (device vda6): has skinny extents Oct 29 19:52:04.017416 systemd[1]: Mounted /usr/share/oem. Oct 29 19:52:04.018278 systemd[1]: Reached target Local File Systems. Oct 29 19:52:04.019227 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Oct 29 19:52:04.019608 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 29 19:52:04.019658 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 29 19:52:04.019688 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 29 19:52:04.020619 systemd[1]: Starting Create Volatile Files and Directories... Oct 29 19:52:04.026584 systemd-tmpfiles[756]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 29 19:52:04.027607 systemd-tmpfiles[756]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 29 19:52:04.054902 systemd-tmpfiles[756]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 19:52:04.054911 systemd-tmpfiles[756]: Skipping /boot Oct 29 19:52:04.060508 systemd-tmpfiles[756]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 19:52:04.060526 systemd-tmpfiles[756]: Skipping /boot Oct 29 19:52:04.091513 systemd[1]: Finished Create Volatile Files and Directories. Oct 29 19:52:04.103681 systemd[1]: Starting Load Security Auditing Rules... Oct 29 19:52:04.105447 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 29 19:52:04.106493 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Oct 29 19:52:04.106000 audit: BPF prog-id=27 op=LOAD Oct 29 19:52:04.107918 systemd[1]: Starting Network Name Resolution... Oct 29 19:52:04.108000 audit: BPF prog-id=28 op=LOAD Oct 29 19:52:04.110092 systemd[1]: Starting Network Time Synchronization... Oct 29 19:52:04.111046 systemd[1]: Condition check resulted in Update is Completed being skipped. Oct 29 19:52:04.111972 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 29 19:52:04.113409 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 29 19:52:04.114737 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 29 19:52:04.124041 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 29 19:52:04.129000 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 19:52:04.130667 augenrules[777]: No rules Oct 29 19:52:04.141149 systemd[1]: Finished Load Security Auditing Rules. Oct 29 19:52:04.145093 systemd[1]: Started Network Time Synchronization. Oct 29 19:52:02.806584 systemd-timesyncd[766]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 29 19:52:02.806763 systemd[1]: Reached target System Initialization. Oct 29 19:52:02.808042 systemd[1]: Started Watch for update engine configuration changes. Oct 29 19:52:02.809664 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 19:52:02.809780 systemd-resolved[760]: Positive Trust Anchors: Oct 29 19:52:02.809787 systemd-resolved[760]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 19:52:02.809815 systemd-resolved[760]: 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 19:52:02.812370 systemd-resolved[760]: Defaulting to hostname 'linux'. Oct 29 19:52:02.812519 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 29 19:52:02.813612 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 29 19:52:02.813644 systemd[1]: Reached target Path Units. Oct 29 19:52:02.814404 systemd[1]: Reached target System Time Set. Oct 29 19:52:02.815939 systemd[1]: Started Daily Log Rotation. Oct 29 19:52:02.817063 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 29 19:52:02.818266 systemd[1]: Reached target Timer Units. Oct 29 19:52:02.819299 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 29 19:52:02.821901 systemd[1]: Starting Docker Socket for the API... Oct 29 19:52:02.824990 systemd[1]: Listening on OpenSSH Server Socket. Oct 29 19:52:02.826348 systemd[1]: Started Network Name Resolution. Oct 29 19:52:02.827251 systemd[1]: Listening on Docker Socket for the API. Oct 29 19:52:02.828174 systemd[1]: Reached target Network. Oct 29 19:52:02.828911 systemd[1]: Reached target Host and Network Name Lookups. Oct 29 19:52:02.829931 systemd[1]: Reached target Socket Units. Oct 29 19:52:02.830776 systemd[1]: Reached target Basic System. Oct 29 19:52:02.831657 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 29 19:52:02.831690 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 29 19:52:02.832646 systemd[1]: Started D-Bus System Message Bus. Oct 29 19:52:02.835826 systemd[1]: Starting Extend Filesystems... Oct 29 19:52:02.836720 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 29 19:52:02.837786 systemd[1]: Starting Generate /run/flatcar/motd... Oct 29 19:52:02.839575 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 29 19:52:02.841688 systemd[1]: Starting Generate sshd host keys... Oct 29 19:52:02.842605 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 29 19:52:02.842633 systemd[1]: Reached target Load system-provided cloud configs. Oct 29 19:52:02.845688 systemd[1]: Starting User Login Management... Oct 29 19:52:02.847618 extend-filesystems[787]: Found vda Oct 29 19:52:02.847618 extend-filesystems[787]: Found vda1 Oct 29 19:52:02.847618 extend-filesystems[787]: Found vda2 Oct 29 19:52:02.847618 extend-filesystems[787]: Found vda3 Oct 29 19:52:02.847618 extend-filesystems[787]: Found usr Oct 29 19:52:02.847618 extend-filesystems[787]: Found vda4 Oct 29 19:52:02.847618 extend-filesystems[787]: Found vda6 Oct 29 19:52:02.847618 extend-filesystems[787]: Found vda7 Oct 29 19:52:02.847618 extend-filesystems[787]: Found vda9 Oct 29 19:52:02.847618 extend-filesystems[787]: Checking size of /dev/vda9 Oct 29 19:52:02.847047 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 29 19:52:02.848029 systemd[1]: Starting Update Engine... Oct 29 19:52:02.852652 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 29 19:52:02.852692 systemd[1]: Reached target Load user-provided cloud configs. Oct 29 19:52:02.855122 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 29 19:52:02.855416 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 29 19:52:02.861157 systemd[1]: motdgen.service: Deactivated successfully. Oct 29 19:52:02.861411 systemd[1]: Finished Generate /run/flatcar/motd. Oct 29 19:52:02.879775 extend-filesystems[787]: Old size kept for /dev/vda9 Oct 29 19:52:02.881031 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 29 19:52:02.881266 systemd[1]: Finished Extend Filesystems. Oct 29 19:52:02.893364 update_engine[800]: I1029 19:52:02.893033 800 main.cc:89] Flatcar Update Engine starting Oct 29 19:52:02.894376 update_engine[800]: I1029 19:52:02.893959 800 payload_state.cc:360] Current Response Signature = Oct 29 19:52:02.894376 update_engine[800]: NumURLs = 1 Oct 29 19:52:02.894376 update_engine[800]: Url0 = http://10.0.0.4:34567/packages/update.gz Oct 29 19:52:02.894376 update_engine[800]: Payload Size = 439328019 Oct 29 19:52:02.894376 update_engine[800]: Payload Sha256 Hash = JprWN+czonR3c0MyWAyU2g4RVVt99zqV4E+IEkT3wn8= Oct 29 19:52:02.894376 update_engine[800]: Is Delta Payload = 0 Oct 29 19:52:02.894376 update_engine[800]: Max Failure Count Per Url = 10 Oct 29 19:52:02.894376 update_engine[800]: Disable Payload Backoff = 1 Oct 29 19:52:02.894376 update_engine[800]: I1029 19:52:02.894186 800 payload_state.cc:381] Payload Attempt Number = 0 Oct 29 19:52:02.894581 update_engine[800]: I1029 19:52:02.894491 800 payload_state.cc:404] Current URL Index = 0 Oct 29 19:52:02.895186 update_engine[800]: I1029 19:52:02.895166 800 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 29 19:52:02.895474 update_engine[800]: I1029 19:52:02.895426 800 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 29 19:52:02.896671 systemd-logind[795]: Watching system buttons on /dev/input/event0 (Power Button) Oct 29 19:52:02.896896 systemd-logind[795]: New seat seat0. Oct 29 19:52:02.897459 systemd[1]: Started Update Engine. Oct 29 19:52:02.898692 update_engine[800]: I1029 19:52:02.898663 800 update_check_scheduler.cc:74] Next update check in 7m40s Oct 29 19:52:02.900419 systemd[1]: Started User Login Management. Oct 29 19:52:02.914626 systemd[1]: Finished Generate sshd host keys. Oct 29 19:52:02.926307 systemd[1]: Starting Generate /run/issue... Oct 29 19:52:02.930777 systemd[1]: issuegen.service: Deactivated successfully. Oct 29 19:52:02.931010 systemd[1]: Finished Generate /run/issue. Oct 29 19:52:02.933239 systemd[1]: Starting Permit User Sessions... Oct 29 19:52:02.937599 systemd[1]: Finished Permit User Sessions. Oct 29 19:52:02.939600 systemd[1]: Started Getty on tty1. Oct 29 19:52:02.941427 systemd[1]: Started Serial Getty on ttyAMA0. Oct 29 19:52:02.942402 systemd[1]: Reached target Login Prompts. Oct 29 19:52:02.943275 systemd[1]: Reached target Multi-User System. Oct 29 19:52:02.945247 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 29 19:52:02.950086 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 29 19:52:02.950320 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 29 19:52:02.951335 systemd[1]: Startup finished in 712ms (kernel) + 1.118s (initrd) + 2.615s (userspace) = 4.446s. Oct 29 19:52:04.296778 systemd-networkd[721]: eth0: Gained IPv6LL Oct 29 19:52:12.622140 systemd[1]: Created slice Slice /system/sshd. Oct 29 19:52:12.623547 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57706). Oct 29 19:52:12.665660 sshd[826]: Accepted publickey for core from 10.0.0.1 port 57706 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:52:12.668048 sshd[826]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:52:12.684696 systemd[1]: Created slice User Slice of UID 500. Oct 29 19:52:12.685893 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 29 19:52:12.687399 systemd-logind[795]: New session 1 of user core. Oct 29 19:52:12.691622 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 29 19:52:12.692823 systemd[1]: Starting User Manager for UID 500... Oct 29 19:52:12.694803 systemd[829]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:52:12.740256 systemd[829]: Queued start job for default target Main User Target. Oct 29 19:52:12.740411 systemd[829]: Reached target Paths. Oct 29 19:52:12.740425 systemd[829]: Reached target Sockets. Oct 29 19:52:12.740436 systemd[829]: Reached target Timers. Oct 29 19:52:12.740445 systemd[829]: Reached target Basic System. Oct 29 19:52:12.740489 systemd[829]: Reached target Main User Target. Oct 29 19:52:12.740503 systemd[829]: Startup finished in 42ms. Oct 29 19:52:12.740602 systemd[1]: Started User Manager for UID 500. Oct 29 19:52:12.747406 systemd[1]: Started Session 1 of User core. Oct 29 19:52:12.805849 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57710). Oct 29 19:52:12.844378 sshd[838]: Accepted publickey for core from 10.0.0.1 port 57710 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:52:12.845645 sshd[838]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:52:12.848570 systemd-logind[795]: New session 2 of user core. Oct 29 19:52:12.857450 systemd[1]: Started Session 2 of User core. Oct 29 19:52:12.910175 sshd[838]: pam_unix(sshd:session): session closed for user core Oct 29 19:52:12.931776 systemd[1]: sshd@1-10.0.0.5:22-10.0.0.1:57710.service: Deactivated successfully. Oct 29 19:52:12.932364 systemd[1]: session-2.scope: Deactivated successfully. Oct 29 19:52:12.932921 systemd-logind[795]: Session 2 logged out. Waiting for processes to exit. Oct 29 19:52:12.933898 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57722). Oct 29 19:52:12.934581 systemd-logind[795]: Removed session 2. Oct 29 19:52:12.972547 sshd[844]: Accepted publickey for core from 10.0.0.1 port 57722 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:52:12.973445 sshd[844]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:52:12.975906 systemd-logind[795]: New session 3 of user core. Oct 29 19:52:12.984352 systemd[1]: Started Session 3 of User core. Oct 29 19:52:13.034311 sshd[844]: pam_unix(sshd:session): session closed for user core Oct 29 19:52:13.050826 systemd[1]: sshd@2-10.0.0.5:22-10.0.0.1:57722.service: Deactivated successfully. Oct 29 19:52:13.051392 systemd[1]: session-3.scope: Deactivated successfully. Oct 29 19:52:13.051962 systemd-logind[795]: Session 3 logged out. Waiting for processes to exit. Oct 29 19:52:13.052877 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57728). Oct 29 19:52:13.053511 systemd-logind[795]: Removed session 3. Oct 29 19:52:13.091447 sshd[851]: Accepted publickey for core from 10.0.0.1 port 57728 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:52:13.092440 sshd[851]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:52:13.095423 systemd-logind[795]: New session 4 of user core. Oct 29 19:52:13.111365 systemd[1]: Started Session 4 of User core. Oct 29 19:52:13.163525 sshd[851]: pam_unix(sshd:session): session closed for user core Oct 29 19:52:13.172791 systemd[1]: sshd@3-10.0.0.5:22-10.0.0.1:57728.service: Deactivated successfully. Oct 29 19:52:13.173371 systemd[1]: session-4.scope: Deactivated successfully. Oct 29 19:52:13.173917 systemd-logind[795]: Session 4 logged out. Waiting for processes to exit. Oct 29 19:52:13.174907 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57738). Oct 29 19:52:13.175467 systemd-logind[795]: Removed session 4. Oct 29 19:52:13.213403 sshd[857]: Accepted publickey for core from 10.0.0.1 port 57738 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:52:13.214652 sshd[857]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:52:13.217249 systemd-logind[795]: New session 5 of user core. Oct 29 19:52:13.234367 systemd[1]: Started Session 5 of User core. Oct 29 19:52:13.291777 sudo[861]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 29 19:52:13.291991 sudo[861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 19:52:13.302718 sudo[861]: pam_unix(sudo:session): session closed for user root Oct 29 19:52:13.305119 sshd[857]: pam_unix(sshd:session): session closed for user core Oct 29 19:52:13.316895 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57748). Oct 29 19:52:13.319015 dbus-daemon[786]: [system] Reloaded configuration Oct 29 19:52:13.321741 systemd[1]: sshd@4-10.0.0.5:22-10.0.0.1:57738.service: Deactivated successfully. Oct 29 19:52:13.322365 systemd[1]: session-5.scope: Deactivated successfully. Oct 29 19:52:13.322933 systemd-logind[795]: Session 5 logged out. Waiting for processes to exit. Oct 29 19:52:13.323518 systemd-logind[795]: Removed session 5. Oct 29 19:52:13.356890 sshd[864]: Accepted publickey for core from 10.0.0.1 port 57748 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:52:13.358090 sshd[864]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:52:13.360471 systemd-logind[795]: New session 6 of user core. Oct 29 19:52:13.366345 systemd[1]: Started Session 6 of User core. Oct 29 19:52:13.415680 sudo[869]: 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 19:52:13.415902 sudo[869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 19:52:13.419000 sudo[869]: pam_unix(sudo:session): session closed for user root Oct 29 19:52:13.422631 sudo[868]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 29 19:52:13.422829 sudo[868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 19:52:13.440597 systemd[1]: Stopping Load Security Auditing Rules... Oct 29 19:52:13.440000 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 19:52:13.441922 auditctl[872]: No rules Oct 29 19:52:13.442174 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 19:52:13.442414 systemd[1]: Stopped Load Security Auditing Rules. Oct 29 19:52:13.442557 kernel: kauditd_printk_skb: 37 callbacks suppressed Oct 29 19:52:13.442580 kernel: audit: type=1305 audit(1761767533.440:48): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 29 19:52:13.443659 systemd[1]: Starting Load Security Auditing Rules... Oct 29 19:52:13.457791 augenrules[889]: No rules Oct 29 19:52:13.458625 systemd[1]: Finished Load Security Auditing Rules. Oct 29 19:52:13.459387 sudo[868]: pam_unix(sudo:session): session closed for user root Oct 29 19:52:13.460510 sshd[864]: pam_unix(sshd:session): session closed for user core Oct 29 19:52:13.475880 systemd[1]: sshd@5-10.0.0.5:22-10.0.0.1:57748.service: Deactivated successfully. Oct 29 19:52:13.476439 systemd[1]: session-6.scope: Deactivated successfully. Oct 29 19:52:13.477068 systemd-logind[795]: Session 6 logged out. Waiting for processes to exit. Oct 29 19:52:13.478021 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57750). Oct 29 19:52:13.478694 systemd-logind[795]: Removed session 6. Oct 29 19:52:13.516247 sshd[896]: Accepted publickey for core from 10.0.0.1 port 57750 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:52:13.517379 sshd[896]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:52:13.520227 kernel: audit: type=1006 audit(1761767533.515:49): 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 19:52:13.521318 systemd-logind[795]: New session 7 of user core. Oct 29 19:52:13.532347 systemd[1]: Started Session 7 of User core. Oct 29 19:52:13.582804 sshd[896]: pam_unix(sshd:session): session closed for user core Oct 29 19:52:13.594896 systemd[1]: sshd@6-10.0.0.5:22-10.0.0.1:57750.service: Deactivated successfully. Oct 29 19:52:13.595601 systemd-logind[795]: Session 7 logged out. Waiting for processes to exit. Oct 29 19:52:13.595659 systemd[1]: session-7.scope: Deactivated successfully. Oct 29 19:52:13.596887 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57752). Oct 29 19:52:13.597578 systemd-logind[795]: Removed session 7. Oct 29 19:52:13.635787 sshd[902]: Accepted publickey for core from 10.0.0.1 port 57752 ssh2: RSA SHA256:TVir4qWi6igJDsB5yD9HAw6jDTbopdqedYMEDjGTgPI Oct 29 19:52:13.636919 sshd[902]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 19:52:13.639221 kernel: audit: type=1006 audit(1761767533.635:50): 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 19:52:13.640389 systemd-logind[795]: New session 8 of user core. Oct 29 19:52:13.655405 systemd[1]: Started Session 8 of User core. Oct 29 19:52:13.706362 sshd[902]: pam_unix(sshd:session): session closed for user core Oct 29 19:52:13.708324 systemd[1]: sshd@7-10.0.0.5:22-10.0.0.1:57752.service: Deactivated successfully. Oct 29 19:52:13.708890 systemd[1]: session-8.scope: Deactivated successfully. Oct 29 19:52:13.709479 systemd-logind[795]: Session 8 logged out. Waiting for processes to exit. Oct 29 19:52:13.710118 systemd-logind[795]: Removed session 8.