Oct 27 15:47:27.677614 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 27 15:47:27.677631 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 27 15:47:27.677639 kernel: efi: EFI v2.70 by EDK II Oct 27 15:47:27.677644 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 27 15:47:27.677649 kernel: efi: seeding entropy pool Oct 27 15:47:27.677654 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 27 15:47:27.677661 kernel: ACPI: Early table checksum verification disabled Oct 27 15:47:27.677666 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 27 15:47:27.677671 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 27 15:47:27.677676 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:27.677682 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:27.677687 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:27.677692 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:27.677697 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:27.677704 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:27.677709 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:27.677716 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:27.677721 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:27.677727 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 27 15:47:27.677732 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 27 15:47:27.677737 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 15:47:27.677743 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 27 15:47:27.677748 kernel: Zone ranges: Oct 27 15:47:27.677753 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 15:47:27.677759 kernel: DMA32 empty Oct 27 15:47:27.677764 kernel: Normal empty Oct 27 15:47:27.677769 kernel: Movable zone start for each node Oct 27 15:47:27.677775 kernel: Early memory node ranges Oct 27 15:47:27.677781 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 27 15:47:27.677786 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 27 15:47:27.677791 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 27 15:47:27.677797 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 27 15:47:27.677802 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 27 15:47:27.677807 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 27 15:47:27.677813 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 27 15:47:27.677818 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 27 15:47:27.677834 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 27 15:47:27.677839 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 27 15:47:27.677844 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 27 15:47:27.677850 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 15:47:27.677860 kernel: On node 0 totalpages: 643072 Oct 27 15:47:27.677866 kernel: DMA zone: 10048 pages used for memmap Oct 27 15:47:27.677871 kernel: DMA zone: 0 pages reserved Oct 27 15:47:27.677877 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 27 15:47:27.677888 kernel: psci: probing for conduit method from ACPI. Oct 27 15:47:27.677894 kernel: psci: PSCIv1.1 detected in firmware. Oct 27 15:47:27.677900 kernel: psci: Using standard PSCI v0.2 function IDs Oct 27 15:47:27.677906 kernel: psci: Trusted OS migration not required Oct 27 15:47:27.677913 kernel: psci: SMC Calling Convention v1.1 Oct 27 15:47:27.677918 kernel: ACPI: SRAT not present Oct 27 15:47:27.677924 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 27 15:47:27.677930 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 27 15:47:27.677936 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 27 15:47:27.677941 kernel: Detected PIPT I-cache on CPU0 Oct 27 15:47:27.677947 kernel: CPU features: detected: GIC system register CPU interface Oct 27 15:47:27.677952 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 27 15:47:27.677958 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 27 15:47:27.677964 kernel: CPU features: detected: Hardware dirty bit management Oct 27 15:47:27.677969 kernel: CPU features: detected: Spectre-v4 Oct 27 15:47:27.677976 kernel: CPU features: detected: ARM erratum 1418040 Oct 27 15:47:27.677982 kernel: CPU features: detected: Spectre-BHB Oct 27 15:47:27.677988 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 27 15:47:27.677993 kernel: Policy zone: DMA Oct 27 15:47:27.678000 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 27 15:47:27.678006 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 27 15:47:27.678012 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 27 15:47:27.678017 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 27 15:47:27.678023 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 27 15:47:27.678029 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 27 15:47:27.678035 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 27 15:47:27.678041 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 27 15:47:27.678047 kernel: rcu: RCU event tracing is enabled. Oct 27 15:47:27.678053 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 27 15:47:27.678059 kernel: Trampoline variant of Tasks RCU enabled. Oct 27 15:47:27.678065 kernel: Tracing variant of Tasks RCU enabled. Oct 27 15:47:27.678071 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 27 15:47:27.678077 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 27 15:47:27.678082 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 27 15:47:27.678088 kernel: GICv3: 256 SPIs implemented Oct 27 15:47:27.678093 kernel: GICv3: 0 Extended SPIs implemented Oct 27 15:47:27.678099 kernel: GICv3: Distributor has no Range Selector support Oct 27 15:47:27.678105 kernel: GICv3: 16 PPIs implemented Oct 27 15:47:27.678112 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 27 15:47:27.678117 kernel: ACPI: SRAT not present Oct 27 15:47:27.678123 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 27 15:47:27.678128 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 27 15:47:27.678135 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 27 15:47:27.678140 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 27 15:47:27.678146 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 27 15:47:27.678152 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:47:27.678158 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 27 15:47:27.678164 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 27 15:47:27.678170 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 27 15:47:27.678176 kernel: arm-pv: using stolen time PV Oct 27 15:47:27.678184 kernel: Console: colour dummy device 80x25 Oct 27 15:47:27.678190 kernel: ACPI: Core revision 20200925 Oct 27 15:47:27.678197 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 27 15:47:27.678202 kernel: pid_max: default: 32768 minimum: 301 Oct 27 15:47:27.678209 kernel: LSM: Security Framework initializing Oct 27 15:47:27.678214 kernel: SELinux: Initializing. Oct 27 15:47:27.678220 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 15:47:27.678227 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 15:47:27.678233 kernel: rcu: Hierarchical SRCU implementation. Oct 27 15:47:27.678241 kernel: Platform MSI: ITS@0x8080000 domain created Oct 27 15:47:27.678247 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 27 15:47:27.678253 kernel: Remapping and enabling EFI services. Oct 27 15:47:27.678259 kernel: smp: Bringing up secondary CPUs ... Oct 27 15:47:27.678265 kernel: Detected PIPT I-cache on CPU1 Oct 27 15:47:27.678272 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 27 15:47:27.678278 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 27 15:47:27.678285 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:47:27.678291 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 27 15:47:27.678297 kernel: Detected PIPT I-cache on CPU2 Oct 27 15:47:27.678305 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 27 15:47:27.678311 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 27 15:47:27.678318 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:47:27.678324 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 27 15:47:27.678330 kernel: Detected PIPT I-cache on CPU3 Oct 27 15:47:27.678340 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 27 15:47:27.678347 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 27 15:47:27.678354 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:47:27.678360 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 27 15:47:27.678367 kernel: smp: Brought up 1 node, 4 CPUs Oct 27 15:47:27.678373 kernel: SMP: Total of 4 processors activated. Oct 27 15:47:27.678379 kernel: CPU features: detected: Privileged Access Never Oct 27 15:47:27.678386 kernel: CPU features: detected: LSE atomic instructions Oct 27 15:47:27.678392 kernel: CPU features: detected: User Access Override Oct 27 15:47:27.678411 kernel: CPU features: detected: 32-bit EL0 Support Oct 27 15:47:27.678418 kernel: CPU features: detected: Common not Private translations Oct 27 15:47:27.678424 kernel: CPU features: detected: RAS Extension Support Oct 27 15:47:27.678432 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 27 15:47:27.678439 kernel: CPU features: detected: CRC32 instructions Oct 27 15:47:27.678446 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 27 15:47:27.678452 kernel: CPU: All CPU(s) started at EL1 Oct 27 15:47:27.678458 kernel: alternatives: patching kernel code Oct 27 15:47:27.678465 kernel: devtmpfs: initialized Oct 27 15:47:27.678471 kernel: KASLR enabled Oct 27 15:47:27.678478 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 27 15:47:27.678484 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 27 15:47:27.678491 kernel: pinctrl core: initialized pinctrl subsystem Oct 27 15:47:27.678497 kernel: SMBIOS 3.0.0 present. Oct 27 15:47:27.678503 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 27 15:47:27.678509 kernel: NET: Registered protocol family 16 Oct 27 15:47:27.678515 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 27 15:47:27.678522 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 27 15:47:27.678530 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 27 15:47:27.678536 kernel: audit: initializing netlink subsys (disabled) Oct 27 15:47:27.678542 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Oct 27 15:47:27.678549 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 27 15:47:27.678555 kernel: cpuidle: using governor menu Oct 27 15:47:27.678561 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 27 15:47:27.678567 kernel: ASID allocator initialised with 32768 entries Oct 27 15:47:27.678574 kernel: ACPI: bus type PCI registered Oct 27 15:47:27.678580 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 27 15:47:27.678587 kernel: Serial: AMBA PL011 UART driver Oct 27 15:47:27.678593 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 27 15:47:27.678600 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 27 15:47:27.678606 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 27 15:47:27.678612 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 27 15:47:27.678619 kernel: cryptd: max_cpu_qlen set to 1000 Oct 27 15:47:27.678625 kernel: ACPI: Added _OSI(Module Device) Oct 27 15:47:27.678632 kernel: ACPI: Added _OSI(Processor Device) Oct 27 15:47:27.678638 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 27 15:47:27.678646 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 27 15:47:27.678652 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 27 15:47:27.678658 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 27 15:47:27.678665 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 27 15:47:27.678671 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 27 15:47:27.678677 kernel: ACPI: Interpreter enabled Oct 27 15:47:27.678683 kernel: ACPI: Using GIC for interrupt routing Oct 27 15:47:27.678689 kernel: ACPI: MCFG table detected, 1 entries Oct 27 15:47:27.678696 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 27 15:47:27.678702 kernel: printk: console [ttyAMA0] enabled Oct 27 15:47:27.678710 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 27 15:47:27.678815 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 27 15:47:27.678893 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 27 15:47:27.678952 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 27 15:47:27.679007 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 27 15:47:27.679061 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 27 15:47:27.679069 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 27 15:47:27.679078 kernel: PCI host bridge to bus 0000:00 Oct 27 15:47:27.679138 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 27 15:47:27.679188 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 27 15:47:27.679239 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 27 15:47:27.679288 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 27 15:47:27.679356 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 27 15:47:27.679420 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 27 15:47:27.679478 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 27 15:47:27.679533 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 27 15:47:27.679588 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 27 15:47:27.679644 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 27 15:47:27.679700 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 27 15:47:27.679756 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 27 15:47:27.679806 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 27 15:47:27.679866 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 27 15:47:27.679921 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 27 15:47:27.679930 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 27 15:47:27.679936 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 27 15:47:27.679942 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 27 15:47:27.679949 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 27 15:47:27.679955 kernel: vgaarb: loaded Oct 27 15:47:27.679961 kernel: Registered efivars operations Oct 27 15:47:27.679968 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 27 15:47:27.679976 kernel: VFS: Disk quotas dquot_6.6.0 Oct 27 15:47:27.679982 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 27 15:47:27.679988 kernel: pnp: PnP ACPI init Oct 27 15:47:27.680053 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 27 15:47:27.680104 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 27 15:47:27.680112 kernel: pnp: PnP ACPI: found 1 devices Oct 27 15:47:27.680118 kernel: NET: Registered protocol family 2 Oct 27 15:47:27.680125 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 27 15:47:27.680133 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 27 15:47:27.680140 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 27 15:47:27.680146 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 27 15:47:27.680152 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 27 15:47:27.680158 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 15:47:27.680165 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 15:47:27.680171 kernel: NET: Registered protocol family 1 Oct 27 15:47:27.680177 kernel: PCI: CLS 0 bytes, default 64 Oct 27 15:47:27.680183 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 27 15:47:27.680191 kernel: kvm [1]: HYP mode not available Oct 27 15:47:27.680197 kernel: Initialise system trusted keyrings Oct 27 15:47:27.680203 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 27 15:47:27.680210 kernel: Key type asymmetric registered Oct 27 15:47:27.680216 kernel: Asymmetric key parser 'x509' registered Oct 27 15:47:27.680222 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 27 15:47:27.680228 kernel: io scheduler mq-deadline registered Oct 27 15:47:27.680235 kernel: io scheduler kyber registered Oct 27 15:47:27.680241 kernel: io scheduler bfq registered Oct 27 15:47:27.680248 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 27 15:47:27.680255 kernel: ACPI: Power Button [PWRB] Oct 27 15:47:27.680261 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 27 15:47:27.680317 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 27 15:47:27.680325 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 27 15:47:27.680331 kernel: msm_serial: driver initialized Oct 27 15:47:27.680337 kernel: random: fast init done Oct 27 15:47:27.680343 kernel: random: crng init done Oct 27 15:47:27.680350 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 27 15:47:27.680356 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 27 15:47:27.680364 kernel: thunder_xcv, ver 1.0 Oct 27 15:47:27.680370 kernel: thunder_bgx, ver 1.0 Oct 27 15:47:27.680376 kernel: nicpf, ver 1.0 Oct 27 15:47:27.680382 kernel: nicvf, ver 1.0 Oct 27 15:47:27.680443 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 27 15:47:27.680500 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-27T15:47:27 UTC (1761580047) Oct 27 15:47:27.680508 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 27 15:47:27.680515 kernel: NET: Registered protocol family 10 Oct 27 15:47:27.680523 kernel: Segment Routing with IPv6 Oct 27 15:47:27.680529 kernel: NET: Registered protocol family 17 Oct 27 15:47:27.680535 kernel: Key type dns_resolver registered Oct 27 15:47:27.680542 kernel: registered taskstats version 1 Oct 27 15:47:27.680548 kernel: Loading compiled-in X.509 certificates Oct 27 15:47:27.680555 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 27 15:47:27.680561 kernel: Key type ._fscrypt registered Oct 27 15:47:27.680567 kernel: Key type .fscrypt registered Oct 27 15:47:27.680573 kernel: Key type fscrypt-provisioning registered Oct 27 15:47:27.680582 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 27 15:47:27.680588 kernel: ima: Allocated hash algorithm: sha1 Oct 27 15:47:27.680595 kernel: ima: No architecture policies found Oct 27 15:47:27.680601 kernel: Freeing unused kernel memory: 34240K Oct 27 15:47:27.680607 kernel: Run /init as init process Oct 27 15:47:27.680614 kernel: with arguments: Oct 27 15:47:27.680620 kernel: /init Oct 27 15:47:27.680626 kernel: with environment: Oct 27 15:47:27.680632 kernel: HOME=/ Oct 27 15:47:27.680638 kernel: TERM=linux Oct 27 15:47:27.680645 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 27 15:47:27.680653 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 27 15:47:27.680662 systemd[1]: Detected virtualization kvm. Oct 27 15:47:27.680669 systemd[1]: Detected architecture arm64. Oct 27 15:47:27.680675 systemd[1]: Running in initial RAM disk. Oct 27 15:47:27.680682 systemd[1]: No hostname configured, using default hostname. Oct 27 15:47:27.680688 systemd[1]: Hostname set to . Oct 27 15:47:27.680697 systemd[1]: Initializing machine ID from VM UUID. Oct 27 15:47:27.680704 systemd[1]: Queued start job for default target Initrd Default Target. Oct 27 15:47:27.680710 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 27 15:47:27.680717 systemd[1]: Reached target Local Encrypted Volumes. Oct 27 15:47:27.680723 systemd[1]: Reached target Path Units. Oct 27 15:47:27.680730 systemd[1]: Reached target Slice Units. Oct 27 15:47:27.680736 systemd[1]: Reached target Swaps. Oct 27 15:47:27.680743 systemd[1]: Reached target Timer Units. Oct 27 15:47:27.680751 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 27 15:47:27.680758 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 27 15:47:27.680764 systemd[1]: Listening on Journal Audit Socket. Oct 27 15:47:27.680771 systemd[1]: Listening on Journal Socket (/dev/log). Oct 27 15:47:27.680777 systemd[1]: Listening on Journal Socket. Oct 27 15:47:27.680784 systemd[1]: Listening on Network Service Netlink Socket. Oct 27 15:47:27.680790 systemd[1]: Listening on udev Control Socket. Oct 27 15:47:27.680797 systemd[1]: Listening on udev Kernel Socket. Oct 27 15:47:27.680804 systemd[1]: Reached target Socket Units. Oct 27 15:47:27.680811 systemd[1]: Starting Create List of Static Device Nodes... Oct 27 15:47:27.680818 systemd[1]: Finished Network Cleanup. Oct 27 15:47:27.680833 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 27 15:47:27.680840 systemd[1]: Starting Journal Service... Oct 27 15:47:27.680846 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 27 15:47:27.680853 systemd[1]: Starting Apply Kernel Variables... Oct 27 15:47:27.680859 systemd[1]: Starting Setup Virtual Console... Oct 27 15:47:27.680866 systemd[1]: Finished Create List of Static Device Nodes. Oct 27 15:47:27.680873 systemd[1]: Finished Apply Kernel Variables. Oct 27 15:47:27.680884 systemd[1]: Finished Setup Virtual Console. Oct 27 15:47:27.680892 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 27 15:47:27.680899 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 27 15:47:27.680912 systemd-journald[278]: Journal started Oct 27 15:47:27.680952 systemd-journald[278]: Runtime Journal (/run/log/journal/c2bbe02a57c64a59b4c44d8497bb70b1) is 6.0M, max 48.5M, 42.4M free. Oct 27 15:47:27.683341 systemd[1]: Started Journal Service. Oct 27 15:47:27.683914 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 27 15:47:27.693913 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 27 15:47:27.695503 systemd[1]: Starting dracut cmdline hook... Oct 27 15:47:27.704245 dracut-cmdline[295]: dracut-dracut-053 Oct 27 15:47:27.706522 dracut-cmdline[295]: 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 27 15:47:27.764898 kernel: SCSI subsystem initialized Oct 27 15:47:27.767834 kernel: Loading iSCSI transport class v2.0-870. Oct 27 15:47:27.773851 kernel: iscsi: registered transport (tcp) Oct 27 15:47:27.784857 kernel: iscsi: registered transport (qla4xxx) Oct 27 15:47:27.784870 kernel: QLogic iSCSI HBA Driver Oct 27 15:47:27.794694 systemd[1]: Finished dracut cmdline hook. Oct 27 15:47:27.796262 systemd[1]: Starting dracut pre-udev hook... Oct 27 15:47:27.809544 kernel: device-mapper: uevent: version 1.0.3 Oct 27 15:47:27.809577 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 27 15:47:27.844849 kernel: raid6: neonx8 gen() 13757 MB/s Oct 27 15:47:27.861841 kernel: raid6: neonx8 xor() 10765 MB/s Oct 27 15:47:27.878837 kernel: raid6: neonx4 gen() 13683 MB/s Oct 27 15:47:27.895838 kernel: raid6: neonx4 xor() 11600 MB/s Oct 27 15:47:27.912850 kernel: raid6: neonx2 gen() 13023 MB/s Oct 27 15:47:27.929837 kernel: raid6: neonx2 xor() 9889 MB/s Oct 27 15:47:27.946839 kernel: raid6: neonx1 gen() 10996 MB/s Oct 27 15:47:27.963838 kernel: raid6: neonx1 xor() 8854 MB/s Oct 27 15:47:27.980837 kernel: raid6: int64x8 gen() 6812 MB/s Oct 27 15:47:27.997835 kernel: raid6: int64x8 xor() 3794 MB/s Oct 27 15:47:28.014840 kernel: raid6: int64x4 gen() 6909 MB/s Oct 27 15:47:28.031849 kernel: raid6: int64x4 xor() 3837 MB/s Oct 27 15:47:28.048843 kernel: raid6: int64x2 gen() 6172 MB/s Oct 27 15:47:28.065841 kernel: raid6: int64x2 xor() 3324 MB/s Oct 27 15:47:28.082849 kernel: raid6: int64x1 gen() 5008 MB/s Oct 27 15:47:28.100406 kernel: raid6: int64x1 xor() 2594 MB/s Oct 27 15:47:28.100427 kernel: raid6: using algorithm neonx8 gen() 13757 MB/s Oct 27 15:47:28.100443 kernel: raid6: .... xor() 10765 MB/s, rmw enabled Oct 27 15:47:28.100459 kernel: raid6: using neon recovery algorithm Oct 27 15:47:28.110849 kernel: xor: measuring software checksum speed Oct 27 15:47:28.111840 kernel: 8regs : 17184 MB/sec Oct 27 15:47:28.113170 kernel: 32regs : 20749 MB/sec Oct 27 15:47:28.113191 kernel: arm64_neon : 29049 MB/sec Oct 27 15:47:28.113205 kernel: xor: using function: arm64_neon (29049 MB/sec) Oct 27 15:47:28.157849 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 27 15:47:28.167867 systemd[1]: Finished dracut pre-udev hook. Oct 27 15:47:28.168000 audit: BPF prog-id=6 op=LOAD Oct 27 15:47:28.168000 audit: BPF prog-id=7 op=LOAD Oct 27 15:47:28.170285 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 27 15:47:28.172305 kernel: audit: type=1334 audit(1761580048.168:2): prog-id=6 op=LOAD Oct 27 15:47:28.172321 kernel: audit: type=1334 audit(1761580048.168:3): prog-id=7 op=LOAD Oct 27 15:47:28.172330 kernel: audit: type=1334 audit(1761580048.169:4): prog-id=8 op=LOAD Oct 27 15:47:28.169000 audit: BPF prog-id=8 op=LOAD Oct 27 15:47:28.182430 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 27 15:47:28.184190 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 27 15:47:28.185768 systemd[1]: Starting dracut pre-trigger hook... Oct 27 15:47:28.187000 audit: BPF prog-id=9 op=LOAD Oct 27 15:47:28.187877 systemd[1]: Starting Network Configuration... Oct 27 15:47:28.189118 kernel: audit: type=1334 audit(1761580048.187:5): prog-id=9 op=LOAD Oct 27 15:47:28.199635 dracut-pre-trigger[422]: rd.md=0: removing MD RAID activation Oct 27 15:47:28.205187 systemd-networkd[428]: lo: Link UP Oct 27 15:47:28.205194 systemd-networkd[428]: lo: Gained carrier Oct 27 15:47:28.207000 audit: BPF prog-id=10 op=LOAD Oct 27 15:47:28.205486 systemd-networkd[428]: Enumeration completed Oct 27 15:47:28.205729 systemd[1]: Started Network Configuration. Oct 27 15:47:28.210080 kernel: audit: type=1334 audit(1761580048.207:6): prog-id=10 op=LOAD Oct 27 15:47:28.207525 systemd[1]: Starting Network Name Resolution... Oct 27 15:47:28.225170 systemd[1]: Finished dracut pre-trigger hook. Oct 27 15:47:28.226673 systemd[1]: Starting Coldplug All udev Devices... Oct 27 15:47:28.235801 systemd-resolved[442]: Positive Trust Anchors: Oct 27 15:47:28.235820 systemd-resolved[442]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 27 15:47:28.235861 systemd-resolved[442]: 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 27 15:47:28.236154 systemd-resolved[442]: Defaulting to hostname 'linux'. Oct 27 15:47:28.236222 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 27 15:47:28.236761 systemd[1]: Started Network Name Resolution. Oct 27 15:47:28.238149 systemd[1]: Reached target Network. Oct 27 15:47:28.238803 systemd[1]: Reached target Host and Network Name Lookups. Oct 27 15:47:28.242312 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 27 15:47:28.249935 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 27 15:47:28.251689 systemd[1]: Starting Open-iSCSI... Oct 27 15:47:28.256279 iscsid[482]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 27 15:47:28.256279 iscsid[482]: 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 27 15:47:28.256279 iscsid[482]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 27 15:47:28.256279 iscsid[482]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 27 15:47:28.256279 iscsid[482]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 27 15:47:28.256279 iscsid[482]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 27 15:47:28.262404 systemd[1]: Started Open-iSCSI. Oct 27 15:47:28.265008 systemd[1]: Finished Coldplug All udev Devices. Oct 27 15:47:28.266401 systemd[1]: Starting dracut initqueue hook... Oct 27 15:47:28.298058 systemd-udevd[462]: Using default interface naming scheme 'v249'. Oct 27 15:47:28.300981 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 27 15:47:28.301102 kernel: vda: detected capacity change from 0 to 4756340736 Oct 27 15:47:28.305118 systemd-networkd[428]: eth0: Link UP Oct 27 15:47:28.311837 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 15:47:28.330068 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 27 15:47:28.332505 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (471) Oct 27 15:47:28.334578 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 27 15:47:28.340342 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 27 15:47:28.341346 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 27 15:47:28.343284 systemd[1]: Finished dracut initqueue hook. Oct 27 15:47:28.346402 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 27 15:47:28.348945 systemd[1]: Reached target Initrd Root Device. Oct 27 15:47:28.349657 systemd[1]: Reached target Preparation for Remote File Systems. Oct 27 15:47:28.351162 systemd[1]: Reached target Remote Encrypted Volumes. Oct 27 15:47:28.352548 systemd[1]: Reached target Remote File Systems. Oct 27 15:47:28.354622 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 27 15:47:28.356181 systemd[1]: Starting dracut pre-mount hook... Oct 27 15:47:28.363197 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 15:47:28.366075 systemd[1]: Finished dracut pre-mount hook. Oct 27 15:47:29.326757 systemd-networkd[428]: eth0: Gained carrier Oct 27 15:47:29.327900 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 27 15:47:29.340890 systemd-networkd[428]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Oct 27 15:47:29.369837 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 15:47:29.369882 disk-uuid[504]: The operation has completed successfully. Oct 27 15:47:29.393637 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 27 15:47:29.393765 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 27 15:47:29.396282 systemd[1]: Starting Ignition (setup)... Oct 27 15:47:29.404163 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 27 15:47:29.404205 kernel: BTRFS info (device vda6): has skinny extents Oct 27 15:47:29.410258 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 27 15:47:29.415208 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 27 15:47:29.415332 systemd[1]: Finished Ignition (setup). Oct 27 15:47:29.417325 systemd[1]: Starting Ignition (disks)... Oct 27 15:47:29.426607 ignition[541]: Ignition v0.36.1-15-gde4e6cc9 Oct 27 15:47:29.426626 ignition[541]: Stage: disks Oct 27 15:47:29.426636 ignition[541]: reading system config file "/usr/lib/ignition/base.ign" Oct 27 15:47:29.426647 ignition[541]: no config at "/usr/lib/ignition/base.ign" Oct 27 15:47:29.426693 ignition[541]: parsed url from cmdline: "" Oct 27 15:47:29.426696 ignition[541]: no config URL provided Oct 27 15:47:29.426701 ignition[541]: reading system config file "/usr/lib/ignition/user.ign" Oct 27 15:47:29.426708 ignition[541]: no config at "/usr/lib/ignition/user.ign" Oct 27 15:47:29.426728 ignition[541]: op(1): [started] loading QEMU firmware config module Oct 27 15:47:29.426732 ignition[541]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 27 15:47:29.427810 ignition[541]: op(1): [finished] loading QEMU firmware config module Oct 27 15:47:29.441100 ignition[541]: parsing config with SHA512: 63e8cbcdbae58fa5bad64bf520c0cc9f6f48c8996096a954c62d0e617a0c638d056c731e98f61ff665472a0e9b73a695aef069fd085036cfb8a4e6691793afec Oct 27 15:47:29.443040 ignition[541]: disks: disks passed Oct 27 15:47:29.443049 ignition[541]: Ignition finished successfully Oct 27 15:47:29.444050 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 27 15:47:29.444203 systemd[1]: Finished Ignition (disks). Oct 27 15:47:29.445355 systemd[1]: Reached target Preparation for Local File Systems. Oct 27 15:47:29.447483 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 27 15:47:29.449572 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 27 15:47:29.458744 systemd-fsck[554]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 27 15:47:29.461847 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 27 15:47:29.462740 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 27 15:47:29.464873 systemd[1]: Mounting /sysroot... Oct 27 15:47:29.485854 systemd[1]: Found device /dev/mapper/usr. Oct 27 15:47:29.487198 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 27 15:47:29.488175 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 27 15:47:29.499104 systemd-fsck[569]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 27 15:47:29.499104 systemd-fsck[569]: You must have r/w access to the filesystem or be root Oct 27 15:47:29.503993 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 27 15:47:29.504034 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 27 15:47:29.499611 systemd-fsck[567]: fsck failed with exit status 8. Oct 27 15:47:29.499614 systemd-fsck[567]: Ignoring error. Oct 27 15:47:29.500313 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 27 15:47:29.503637 systemd[1]: Mounted /sysroot. Oct 27 15:47:29.504641 systemd[1]: Reached target Initrd Root File System. Oct 27 15:47:29.506851 systemd[1]: Mounting /sysusr/usr... Oct 27 15:47:29.514859 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 27 15:47:29.515357 systemd[1]: Mounted /sysusr/usr. Oct 27 15:47:29.516184 systemd[1]: Reached target Local File Systems. Oct 27 15:47:29.517136 systemd[1]: Reached target System Initialization. Oct 27 15:47:29.518409 systemd[1]: Reached target Basic System. Oct 27 15:47:29.520641 systemd[1]: Mounting /sysroot/usr... Oct 27 15:47:29.522616 systemd[1]: Mounted /sysroot/usr. Oct 27 15:47:29.523953 systemd[1]: Starting Root filesystem setup... Oct 27 15:47:29.548710 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 27 15:47:29.548866 systemd[1]: Finished Root filesystem setup. Oct 27 15:47:29.551152 systemd[1]: Starting Ignition (files)... Oct 27 15:47:29.552386 systemd[1]: Starting /sysroot/boot... Oct 27 15:47:29.554522 ignition[585]: Ignition v0.36.1-15-gde4e6cc9 Oct 27 15:47:29.554539 ignition[585]: Stage: files Oct 27 15:47:29.554549 ignition[585]: reading system config file "/usr/lib/ignition/base.ign" Oct 27 15:47:29.554559 ignition[585]: no config at "/usr/lib/ignition/base.ign" Oct 27 15:47:29.555332 ignition[585]: files: compiled without relabeling support, skipping Oct 27 15:47:29.568433 systemd[1]: Finished /sysroot/boot. Oct 27 15:47:29.572412 ignition[585]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 27 15:47:29.572427 ignition[585]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 27 15:47:29.574110 ignition[585]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 27 15:47:29.574126 ignition[585]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 27 15:47:29.575305 ignition[585]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 27 15:47:29.576674 systemd[1]: ignition-files.service: Deactivated successfully. Oct 27 15:47:29.575326 ignition[585]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 27 15:47:29.576787 systemd[1]: Finished Ignition (files). Oct 27 15:47:29.575614 ignition[585]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 27 15:47:29.578370 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 27 15:47:29.575621 ignition[585]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 27 15:47:29.579044 systemd[1]: Starting Ignition (record completion)... Oct 27 15:47:29.575705 ignition[585]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 27 15:47:29.587000 audit: BPF prog-id=9 op=UNLOAD Oct 27 15:47:29.580300 systemd[1]: Starting Reload Configuration from the Real Root... Oct 27 15:47:29.591056 kernel: audit: type=1334 audit(1761580049.587:7): prog-id=9 op=UNLOAD Oct 27 15:47:29.576087 ignition[585]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 27 15:47:29.598748 kernel: audit: type=1334 audit(1761580049.590:8): prog-id=3 op=UNLOAD Oct 27 15:47:29.598768 kernel: audit: type=1334 audit(1761580049.590:9): prog-id=6 op=UNLOAD Oct 27 15:47:29.598789 kernel: audit: type=1334 audit(1761580049.590:10): prog-id=10 op=UNLOAD Oct 27 15:47:29.590000 audit: BPF prog-id=3 op=UNLOAD Oct 27 15:47:29.590000 audit: BPF prog-id=6 op=UNLOAD Oct 27 15:47:29.590000 audit: BPF prog-id=10 op=UNLOAD Oct 27 15:47:29.582130 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 27 15:47:29.576093 ignition[585]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 27 15:47:29.582240 systemd[1]: Finished Ignition (record completion). Oct 27 15:47:29.576098 ignition[585]: files: files passed Oct 27 15:47:29.585079 systemd[1]: Reloading. Oct 27 15:47:29.576102 ignition[585]: Ignition finished successfully Oct 27 15:47:29.675000 audit: BPF prog-id=11 op=LOAD Oct 27 15:47:29.675000 audit: BPF prog-id=12 op=LOAD Oct 27 15:47:29.675000 audit: BPF prog-id=13 op=LOAD Oct 27 15:47:29.676000 audit: BPF prog-id=14 op=LOAD Oct 27 15:47:29.676000 audit: BPF prog-id=4 op=UNLOAD Oct 27 15:47:29.676000 audit: BPF prog-id=5 op=UNLOAD Oct 27 15:47:29.676000 audit: BPF prog-id=15 op=LOAD Oct 27 15:47:29.676000 audit: BPF prog-id=16 op=LOAD Oct 27 15:47:29.676000 audit: BPF prog-id=17 op=LOAD Oct 27 15:47:29.676000 audit: BPF prog-id=7 op=UNLOAD Oct 27 15:47:29.676000 audit: BPF prog-id=8 op=UNLOAD Oct 27 15:47:29.676000 audit: BPF prog-id=18 op=LOAD Oct 27 15:47:29.695116 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 27 15:47:29.695239 systemd[1]: Finished Reload Configuration from the Real Root. Oct 27 15:47:29.696934 systemd[1]: Reached target Initrd File Systems. Oct 27 15:47:29.698454 systemd[1]: Reached target Initrd Default Target. Oct 27 15:47:29.699855 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 27 15:47:29.700548 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 27 15:47:29.710777 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 27 15:47:29.712352 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 27 15:47:29.718511 systemd[1]: Stopped target Host and Network Name Lookups. Oct 27 15:47:29.719497 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 27 15:47:29.721061 systemd[1]: Stopped target Timer Units. Oct 27 15:47:29.722304 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 27 15:47:29.722417 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 27 15:47:29.723689 systemd[1]: Stopped target Initrd Default Target. Oct 27 15:47:29.725091 systemd[1]: Stopped target Basic System. Oct 27 15:47:29.726401 systemd[1]: Stopped target Initrd Root Device. Oct 27 15:47:29.727630 systemd[1]: Stopped target Path Units. Oct 27 15:47:29.728745 systemd[1]: Stopped target Remote File Systems. Oct 27 15:47:29.730078 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 27 15:47:29.731406 systemd[1]: Stopped target Slice Units. Oct 27 15:47:29.732565 systemd[1]: Stopped target Socket Units. Oct 27 15:47:29.733929 systemd[1]: Stopped target System Initialization. Oct 27 15:47:29.735250 systemd[1]: Stopped target Local File Systems. Oct 27 15:47:29.736478 systemd[1]: Stopped target Preparation for Local File Systems. Oct 27 15:47:29.737888 systemd[1]: Stopped target Swaps. Oct 27 15:47:29.739103 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 27 15:47:29.739210 systemd[1]: Stopped dracut pre-mount hook. Oct 27 15:47:29.740554 systemd[1]: Stopped target Local Encrypted Volumes. Oct 27 15:47:29.741611 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 27 15:47:29.744864 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 27 15:47:29.745867 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 27 15:47:29.745983 systemd[1]: Stopped dracut initqueue hook. Oct 27 15:47:29.749265 iscsid[482]: iscsid shutting down. Oct 27 15:47:29.747649 systemd[1]: Stopping Open-iSCSI... Oct 27 15:47:29.749348 systemd[1]: Stopping /sysroot/boot... Oct 27 15:47:29.749929 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 27 15:47:29.750058 systemd[1]: Stopped Coldplug All udev Devices. Oct 27 15:47:29.751144 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 27 15:47:29.751226 systemd[1]: Stopped dracut pre-trigger hook. Oct 27 15:47:29.753240 systemd[1]: iscsid.service: Deactivated successfully. Oct 27 15:47:29.753349 systemd[1]: Stopped Open-iSCSI. Oct 27 15:47:29.754913 systemd[1]: iscsid.socket: Deactivated successfully. Oct 27 15:47:29.754983 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 27 15:47:29.756513 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 27 15:47:29.759047 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 27 15:47:29.759152 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 27 15:47:29.760398 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 27 15:47:29.760504 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 27 15:47:29.761746 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 27 15:47:29.761928 systemd[1]: Stopped /sysroot/boot. Oct 27 15:47:29.763267 systemd[1]: Stopped target Network. Oct 27 15:47:29.764459 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 27 15:47:29.764520 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 27 15:47:29.765785 systemd[1]: Stopping Network Name Resolution... Oct 27 15:47:29.770427 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 27 15:47:29.770539 systemd[1]: Stopped Network Name Resolution. Oct 27 15:47:29.771698 systemd[1]: Stopping Network Configuration... Oct 27 15:47:29.776944 systemd-networkd[428]: eth0: DHCP lease lost Oct 27 15:47:29.780000 audit: BPF prog-id=18 op=UNLOAD Oct 27 15:47:29.783879 systemd-networkd[428]: eth0: DHCPv6 lease lost Oct 27 15:47:29.785543 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 27 15:47:29.785664 systemd[1]: Stopped Network Configuration. Oct 27 15:47:29.787255 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 27 15:47:29.787287 systemd[1]: Closed Network Service Netlink Socket. Oct 27 15:47:29.789010 systemd[1]: Stopping Network Cleanup... Oct 27 15:47:29.789613 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 27 15:47:29.789678 systemd[1]: Stopped Apply Kernel Variables. Oct 27 15:47:29.794000 audit: BPF prog-id=11 op=UNLOAD Oct 27 15:47:29.790986 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 27 15:47:29.796737 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 27 15:47:29.796911 systemd[1]: Stopped Network Cleanup. Oct 27 15:47:29.799940 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 27 15:47:29.800066 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 27 15:47:29.801792 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 27 15:47:29.801848 systemd[1]: Closed udev Control Socket. Oct 27 15:47:29.803212 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 27 15:47:29.803244 systemd[1]: Closed udev Kernel Socket. Oct 27 15:47:29.806000 audit: BPF prog-id=15 op=UNLOAD Oct 27 15:47:29.804510 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 27 15:47:29.804548 systemd[1]: Stopped dracut pre-udev hook. Oct 27 15:47:29.805700 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 27 15:47:29.805742 systemd[1]: Stopped dracut cmdline hook. Oct 27 15:47:29.807179 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 27 15:47:29.807221 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 27 15:47:29.809211 systemd[1]: Starting Cleanup udev Database... Oct 27 15:47:29.809965 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 27 15:47:29.811150 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 27 15:47:29.811201 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 27 15:47:29.812576 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 27 15:47:29.812617 systemd[1]: Stopped Create List of Static Device Nodes. Oct 27 15:47:29.813845 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 27 15:47:29.813894 systemd[1]: Stopped Setup Virtual Console. Oct 27 15:47:29.815505 systemd[1]: rngd.service: Deactivated successfully. Oct 27 15:47:29.815616 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 27 15:47:29.816943 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 27 15:47:29.825000 audit: BPF prog-id=12 op=UNLOAD Oct 27 15:47:29.817046 systemd[1]: Finished Cleanup udev Database. Oct 27 15:47:29.818113 systemd[1]: Reached target Switch Root. Oct 27 15:47:29.819937 systemd[1]: Starting Switch Root... Oct 27 15:47:29.824724 systemd[1]: Switching root. Oct 27 15:47:29.842238 systemd-journald[278]: Journal stopped Oct 27 15:47:31.995772 systemd-journald[278]: Received SIGTERM from PID 1 (systemd). Oct 27 15:47:31.995854 kernel: SELinux: policy capability network_peer_controls=1 Oct 27 15:47:31.995938 kernel: SELinux: policy capability open_perms=1 Oct 27 15:47:31.995958 kernel: SELinux: policy capability extended_socket_class=1 Oct 27 15:47:31.995968 kernel: SELinux: policy capability always_check_network=0 Oct 27 15:47:31.995978 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 27 15:47:31.995988 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 27 15:47:31.995998 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 27 15:47:31.996009 systemd[1]: Successfully loaded SELinux policy in 30.139ms. Oct 27 15:47:31.996026 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.390ms. Oct 27 15:47:31.996039 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 27 15:47:31.996052 systemd[1]: Detected virtualization kvm. Oct 27 15:47:31.996063 systemd[1]: Detected architecture arm64. Oct 27 15:47:31.996074 systemd[1]: Detected first boot. Oct 27 15:47:31.996084 systemd[1]: Initializing machine ID from VM UUID. Oct 27 15:47:31.996095 systemd[1]: Populated /etc with preset unit settings. Oct 27 15:47:31.996108 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 27 15:47:31.996120 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 27 15:47:31.996131 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 27 15:47:31.996142 systemd[1]: Stopped Switch Root. Oct 27 15:47:31.996152 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 27 15:47:31.996162 systemd[1]: Created slice Slice /system/addon-config. Oct 27 15:47:31.996172 systemd[1]: Created slice Slice /system/addon-run. Oct 27 15:47:31.996182 systemd[1]: Created slice Slice /system/getty. Oct 27 15:47:31.996192 systemd[1]: Created slice Slice /system/modprobe. Oct 27 15:47:31.996202 systemd[1]: Created slice Slice /system/serial-getty. Oct 27 15:47:31.996211 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 27 15:47:31.996223 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 27 15:47:31.996232 systemd[1]: Created slice User and Session Slice. Oct 27 15:47:31.996243 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 27 15:47:31.996253 kernel: kauditd_printk_skb: 35 callbacks suppressed Oct 27 15:47:31.996263 kernel: audit: type=1334 audit(1761580051.897:46): prog-id=24 op=UNLOAD Oct 27 15:47:31.996287 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 27 15:47:31.996297 systemd[1]: Set up automount Boot partition Automount Point. Oct 27 15:47:31.996307 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 27 15:47:31.996318 systemd[1]: Stopped target Switch Root. Oct 27 15:47:31.996330 systemd[1]: Stopped target Initrd File Systems. Oct 27 15:47:31.996341 systemd[1]: Stopped target Initrd Root File System. Oct 27 15:47:31.996352 systemd[1]: Reached target Remote Encrypted Volumes. Oct 27 15:47:31.996361 systemd[1]: Reached target Remote File Systems. Oct 27 15:47:31.996372 systemd[1]: Reached target Slice Units. Oct 27 15:47:31.996381 systemd[1]: Reached target Swaps. Oct 27 15:47:31.996391 systemd[1]: Reached target Verify torcx succeeded. Oct 27 15:47:31.996401 systemd[1]: Reached target Local Verity Protected Volumes. Oct 27 15:47:31.996412 systemd[1]: Listening on Process Core Dump Socket. Oct 27 15:47:31.996422 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 27 15:47:31.996434 systemd[1]: Listening on Network Service Netlink Socket. Oct 27 15:47:31.996444 systemd[1]: Listening on udev Control Socket. Oct 27 15:47:31.996455 systemd[1]: Listening on udev Kernel Socket. Oct 27 15:47:31.996466 systemd[1]: Mounting Huge Pages File System... Oct 27 15:47:31.996477 systemd[1]: Mounting POSIX Message Queue File System... Oct 27 15:47:31.996488 systemd[1]: Mounting External Media Directory... Oct 27 15:47:31.996498 systemd[1]: Mounting Kernel Debug File System... Oct 27 15:47:31.996508 systemd[1]: Mounting Kernel Trace File System... Oct 27 15:47:31.996518 systemd[1]: Mounting Temporary Directory /tmp... Oct 27 15:47:31.996529 systemd[1]: Starting Create missing system files... Oct 27 15:47:31.996541 systemd[1]: Starting Create List of Static Device Nodes... Oct 27 15:47:31.996551 systemd[1]: Starting Load Kernel Module configfs... Oct 27 15:47:31.996562 systemd[1]: Starting Load Kernel Module drm... Oct 27 15:47:31.996573 systemd[1]: Starting Load Kernel Module fuse... Oct 27 15:47:31.996583 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 27 15:47:31.996594 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 27 15:47:31.996606 systemd[1]: Stopped File System Check on Root Device. Oct 27 15:47:31.996616 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 27 15:47:31.996628 systemd[1]: Stopped systemd-fsck-usr.service. Oct 27 15:47:31.996640 systemd[1]: Stopped Journal Service. Oct 27 15:47:31.996650 kernel: fuse: init (API version 7.32) Oct 27 15:47:31.996659 kernel: audit: type=1334 audit(1761580051.978:47): prog-id=27 op=LOAD Oct 27 15:47:31.996669 kernel: audit: type=1334 audit(1761580051.979:48): prog-id=28 op=LOAD Oct 27 15:47:31.996678 kernel: audit: type=1334 audit(1761580051.980:49): prog-id=29 op=LOAD Oct 27 15:47:31.996688 systemd[1]: Starting Journal Service... Oct 27 15:47:31.996699 kernel: audit: type=1334 audit(1761580051.980:50): prog-id=25 op=UNLOAD Oct 27 15:47:31.996708 kernel: audit: type=1334 audit(1761580051.980:51): prog-id=26 op=UNLOAD Oct 27 15:47:31.996718 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 27 15:47:31.996728 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 27 15:47:31.996739 systemd[1]: Starting Apply Kernel Variables... Oct 27 15:47:31.996748 systemd[1]: Starting Coldplug All udev Devices... Oct 27 15:47:31.996759 systemd[1]: verity-setup.service: Deactivated successfully. Oct 27 15:47:31.996769 kernel: audit: type=1305 audit(1761580051.994:52): op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 27 15:47:31.996779 systemd[1]: Stopped verity-setup.service. Oct 27 15:47:31.996791 systemd-journald[801]: Journal started Oct 27 15:47:31.996854 systemd-journald[801]: Runtime Journal (/run/log/journal/c2bbe02a57c64a59b4c44d8497bb70b1) is 6.0M, max 48.5M, 42.4M free. Oct 27 15:47:29.904000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 27 15:47:29.933000 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 27 15:47:29.933000 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 27 15:47:29.933000 audit: BPF prog-id=19 op=LOAD Oct 27 15:47:29.934000 audit: BPF prog-id=19 op=UNLOAD Oct 27 15:47:29.934000 audit: BPF prog-id=20 op=LOAD Oct 27 15:47:29.934000 audit: BPF prog-id=20 op=UNLOAD Oct 27 15:47:29.965000 audit[750]: AVC avc: denied { associate } for pid=750 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 27 15:47:31.868000 audit: BPF prog-id=21 op=LOAD Oct 27 15:47:31.868000 audit: BPF prog-id=22 op=LOAD Oct 27 15:47:31.868000 audit: BPF prog-id=23 op=LOAD Oct 27 15:47:31.868000 audit: BPF prog-id=13 op=UNLOAD Oct 27 15:47:31.868000 audit: BPF prog-id=14 op=UNLOAD Oct 27 15:47:31.877000 audit: BPF prog-id=24 op=LOAD Oct 27 15:47:31.877000 audit: BPF prog-id=21 op=UNLOAD Oct 27 15:47:31.877000 audit: BPF prog-id=25 op=LOAD Oct 27 15:47:31.877000 audit: BPF prog-id=26 op=LOAD Oct 27 15:47:31.877000 audit: BPF prog-id=22 op=UNLOAD Oct 27 15:47:31.877000 audit: BPF prog-id=23 op=UNLOAD Oct 27 15:47:31.897000 audit: BPF prog-id=24 op=UNLOAD Oct 27 15:47:31.978000 audit: BPF prog-id=27 op=LOAD Oct 27 15:47:31.979000 audit: BPF prog-id=28 op=LOAD Oct 27 15:47:31.980000 audit: BPF prog-id=29 op=LOAD Oct 27 15:47:31.980000 audit: BPF prog-id=25 op=UNLOAD Oct 27 15:47:31.980000 audit: BPF prog-id=26 op=UNLOAD Oct 27 15:47:31.994000 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 27 15:47:31.868244 systemd[1]: Queued start job for default target Multi-User System. Oct 27 15:47:29.964603 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" 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 27 15:47:31.878665 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 27 15:47:29.964862 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 27 15:47:29.964891 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 27 15:47:29.965009 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 27 15:47:29.965021 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 27 15:47:29.965053 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 27 15:47:29.965066 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 27 15:47:29.965254 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 27 15:47:29.965292 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 27 15:47:29.965307 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 27 15:47:29.965691 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" 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 27 15:47:29.965722 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" 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 27 15:47:29.965743 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" 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 27 15:47:29.965759 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 27 15:47:29.965777 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" 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 27 15:47:29.965791 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:29Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 27 15:47:31.687606 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:31Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 27 15:47:31.687910 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:31Z" 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 27 15:47:31.688012 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:31Z" 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 27 15:47:31.688132 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:31Z" 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 27 15:47:31.688183 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:31Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 27 15:47:31.688238 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-27T15:47:31Z" 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 27 15:47:32.000847 systemd[1]: Started Journal Service. Oct 27 15:47:32.001154 systemd[1]: Mounted Huge Pages File System. Oct 27 15:47:32.001962 systemd[1]: Mounted POSIX Message Queue File System. Oct 27 15:47:32.002894 systemd[1]: Mounted External Media Directory. Oct 27 15:47:32.003691 systemd[1]: Mounted Kernel Debug File System. Oct 27 15:47:32.004566 systemd[1]: Mounted Kernel Trace File System. Oct 27 15:47:32.005444 systemd[1]: Mounted Temporary Directory /tmp. Oct 27 15:47:32.006536 systemd[1]: Finished Create missing system files. Oct 27 15:47:32.007706 systemd[1]: Finished Create List of Static Device Nodes. Oct 27 15:47:32.008878 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 27 15:47:32.009068 systemd[1]: Finished Load Kernel Module configfs. Oct 27 15:47:32.010069 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 27 15:47:32.010245 systemd[1]: Finished Load Kernel Module drm. Oct 27 15:47:32.011175 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 27 15:47:32.011352 systemd[1]: Finished Load Kernel Module fuse. Oct 27 15:47:32.012404 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 27 15:47:32.026265 systemd[1]: Finished Apply Kernel Variables. Oct 27 15:47:32.029205 systemd[1]: Mounting FUSE Control File System... Oct 27 15:47:32.031011 systemd[1]: Mounting Kernel Configuration File System... Oct 27 15:47:32.031785 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 27 15:47:32.033390 systemd[1]: Starting Rebuild Hardware Database... Oct 27 15:47:32.035390 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 27 15:47:32.036367 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 27 15:47:32.038692 systemd-journald[801]: Time spent on flushing to /var/log/journal/c2bbe02a57c64a59b4c44d8497bb70b1 is 8.059ms for 773 entries. Oct 27 15:47:32.038692 systemd-journald[801]: System Journal (/var/log/journal/c2bbe02a57c64a59b4c44d8497bb70b1) is 8.0M, max 203.0M, 195.0M free. Oct 27 15:47:32.037362 systemd[1]: Starting Load/Save Random Seed... Oct 27 15:47:32.039206 systemd[1]: Starting Create System Users... Oct 27 15:47:32.043737 systemd[1]: Mounted FUSE Control File System. Oct 27 15:47:32.044658 systemd[1]: Mounted Kernel Configuration File System. Oct 27 15:47:32.049205 systemd[1]: Finished Load/Save Random Seed. Oct 27 15:47:32.050282 systemd[1]: Finished Coldplug All udev Devices. Oct 27 15:47:32.051290 systemd[1]: Reached target First Boot Complete. Oct 27 15:47:32.058759 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 27 15:47:32.060167 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 27 15:47:32.062063 systemd-sysusers[813]: Creating group sgx with gid 999. Oct 27 15:47:32.063146 systemd-sysusers[813]: Creating group systemd-oom with gid 998. Oct 27 15:47:32.063781 systemd-sysusers[813]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 27 15:47:32.064722 systemd-sysusers[813]: Creating group systemd-timesync with gid 997. Oct 27 15:47:32.065407 systemd-sysusers[813]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 27 15:47:32.066253 systemd-sysusers[813]: Creating group systemd-coredump with gid 996. Oct 27 15:47:32.066952 systemd-sysusers[813]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 27 15:47:32.076389 systemd[1]: Finished Create System Users. Oct 27 15:47:32.078166 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 27 15:47:32.091785 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 27 15:47:32.283593 systemd[1]: Finished Rebuild Hardware Database. Oct 27 15:47:32.290000 audit: BPF prog-id=30 op=LOAD Oct 27 15:47:32.292000 audit: BPF prog-id=31 op=LOAD Oct 27 15:47:32.293194 kernel: audit: type=1334 audit(1761580052.290:53): prog-id=30 op=LOAD Oct 27 15:47:32.293250 kernel: audit: type=1334 audit(1761580052.292:54): prog-id=31 op=LOAD Oct 27 15:47:32.293268 kernel: audit: type=1334 audit(1761580052.292:55): prog-id=32 op=LOAD Oct 27 15:47:32.292000 audit: BPF prog-id=32 op=LOAD Oct 27 15:47:32.293785 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 27 15:47:32.292000 audit: BPF prog-id=16 op=UNLOAD Oct 27 15:47:32.292000 audit: BPF prog-id=17 op=UNLOAD Oct 27 15:47:32.317813 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 27 15:47:32.324000 audit: BPF prog-id=33 op=LOAD Oct 27 15:47:32.326055 systemd[1]: Starting Network Configuration... Oct 27 15:47:32.353097 systemd-networkd[838]: lo: Link UP Oct 27 15:47:32.353106 systemd-networkd[838]: lo: Gained carrier Oct 27 15:47:32.353396 systemd-networkd[838]: Enumeration completed Oct 27 15:47:32.353485 systemd[1]: Started Network Configuration. Oct 27 15:47:32.359787 systemd-udevd[827]: Using default interface naming scheme 'v249'. Oct 27 15:47:32.387790 udevadm[817]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 27 15:47:32.392487 systemd-networkd[838]: eth0: Link UP Oct 27 15:47:32.398924 systemd-networkd[838]: eth0: Gained carrier Oct 27 15:47:32.408959 systemd-networkd[838]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Oct 27 15:47:32.410048 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 27 15:47:32.421811 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 27 15:47:32.430043 lvm[846]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 27 15:47:32.457802 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 27 15:47:32.458762 systemd[1]: Reached target Local Encrypted Volumes. Oct 27 15:47:32.471845 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 27 15:47:32.475174 lvm[847]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 27 15:47:32.502763 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 27 15:47:32.503772 systemd[1]: Reached target Preparation for Local File Systems. Oct 27 15:47:32.504768 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 27 15:47:32.504794 systemd[1]: Reached target Containers. Oct 27 15:47:32.515095 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 27 15:47:32.526890 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 27 15:47:32.528930 systemd[1]: Mounting /usr/share/oem... Oct 27 15:47:32.535077 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 27 15:47:32.535122 kernel: BTRFS info (device vda6): has skinny extents Oct 27 15:47:32.537401 systemd[1]: Mounted /usr/share/oem. Oct 27 15:47:32.538150 systemd[1]: Reached target Local File Systems. Oct 27 15:47:32.551680 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 27 15:47:32.552914 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 27 15:47:32.552962 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 27 15:47:32.553962 systemd[1]: Starting Commit a transient machine-id on disk... Oct 27 15:47:32.556031 systemd[1]: Starting Create Volatile Files and Directories... Oct 27 15:47:32.561715 systemd-tmpfiles[872]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 27 15:47:32.562753 systemd-tmpfiles[872]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 27 15:47:32.573077 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 27 15:47:32.573687 systemd[1]: Finished Commit a transient machine-id on disk. Oct 27 15:47:32.591387 systemd-tmpfiles[872]: Detected autofs mount point /boot during canonicalization of /boot. Oct 27 15:47:32.591529 systemd-tmpfiles[872]: Skipping /boot Oct 27 15:47:32.596381 systemd-tmpfiles[872]: Detected autofs mount point /boot during canonicalization of /boot. Oct 27 15:47:32.596491 systemd-tmpfiles[872]: Skipping /boot Oct 27 15:47:32.625371 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 27 15:47:32.629707 systemd[1]: Finished Create Volatile Files and Directories. Oct 27 15:47:32.642795 systemd[1]: Starting Load Security Auditing Rules... Oct 27 15:47:32.644553 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 27 15:47:32.646510 systemd[1]: Starting Rebuild Journal Catalog... Oct 27 15:47:32.647000 audit: BPF prog-id=34 op=LOAD Oct 27 15:47:32.648726 systemd[1]: Starting Network Name Resolution... Oct 27 15:47:32.650000 audit: BPF prog-id=35 op=LOAD Oct 27 15:47:32.652195 systemd[1]: Starting Network Time Synchronization... Oct 27 15:47:32.654159 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 27 15:47:32.655613 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 27 15:47:32.658349 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 27 15:47:32.660303 systemd[1]: Finished Rebuild Journal Catalog. Oct 27 15:47:32.662439 systemd[1]: Starting Update is Completed... Oct 27 15:47:32.665208 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 27 15:47:32.667903 systemd[1]: Finished Update is Completed. Oct 27 15:47:32.672000 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 27 15:47:32.673040 augenrules[894]: No rules Oct 27 15:47:32.673873 systemd[1]: Finished Load Security Auditing Rules. Oct 27 15:47:32.693363 systemd[1]: Started Network Time Synchronization. Oct 27 15:47:32.694032 systemd-timesyncd[883]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 27 15:47:32.694503 systemd[1]: Reached target System Initialization. Oct 27 15:47:32.695383 systemd[1]: Started Watch for update engine configuration changes. Oct 27 15:47:32.696490 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 27 15:47:32.697380 systemd-resolved[880]: Positive Trust Anchors: Oct 27 15:47:32.697388 systemd-resolved[880]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 27 15:47:32.697416 systemd-resolved[880]: 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 27 15:47:32.697579 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 27 15:47:32.698484 systemd-resolved[880]: Defaulting to hostname 'linux'. Oct 27 15:47:32.698622 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 27 15:47:32.698647 systemd[1]: Reached target Path Units. Oct 27 15:47:32.699321 systemd[1]: Reached target System Time Set. Oct 27 15:47:32.700166 systemd[1]: Started Daily Log Rotation. Oct 27 15:47:32.701000 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 27 15:47:32.702011 systemd[1]: Reached target Timer Units. Oct 27 15:47:32.702998 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 27 15:47:32.716574 systemd[1]: Starting Docker Socket for the API... Oct 27 15:47:32.719699 systemd[1]: Listening on OpenSSH Server Socket. Oct 27 15:47:32.720919 systemd[1]: Started Network Name Resolution. Oct 27 15:47:32.721717 systemd[1]: Listening on Docker Socket for the API. Oct 27 15:47:32.722576 systemd[1]: Reached target Network. Oct 27 15:47:32.723226 systemd[1]: Reached target Host and Network Name Lookups. Oct 27 15:47:32.724031 systemd[1]: Reached target Socket Units. Oct 27 15:47:32.724659 systemd[1]: Reached target Basic System. Oct 27 15:47:32.725408 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 27 15:47:32.725436 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 27 15:47:32.726429 systemd[1]: Started D-Bus System Message Bus. Oct 27 15:47:32.729480 systemd[1]: Starting Extend Filesystems... Oct 27 15:47:32.730279 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 27 15:47:32.731273 systemd[1]: Starting Generate /run/flatcar/motd... Oct 27 15:47:32.733032 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 27 15:47:32.735513 systemd[1]: Starting Generate sshd host keys... Oct 27 15:47:32.736418 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 27 15:47:32.736445 systemd[1]: Reached target Load system-provided cloud configs. Oct 27 15:47:32.739417 extend-filesystems[903]: Found vda Oct 27 15:47:32.739417 extend-filesystems[903]: Found vda1 Oct 27 15:47:32.739417 extend-filesystems[903]: Found vda2 Oct 27 15:47:32.750057 extend-filesystems[903]: Found vda3 Oct 27 15:47:32.750057 extend-filesystems[903]: Found usr Oct 27 15:47:32.750057 extend-filesystems[903]: Found vda4 Oct 27 15:47:32.750057 extend-filesystems[903]: Found vda6 Oct 27 15:47:32.750057 extend-filesystems[903]: Found vda7 Oct 27 15:47:32.750057 extend-filesystems[903]: Found vda9 Oct 27 15:47:32.750057 extend-filesystems[903]: Checking size of /dev/vda9 Oct 27 15:47:32.739454 systemd[1]: Starting User Login Management... Oct 27 15:47:32.740640 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 27 15:47:32.742928 systemd[1]: Starting Update Engine... Oct 27 15:47:32.743673 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 27 15:47:32.743700 systemd[1]: Reached target Load user-provided cloud configs. Oct 27 15:47:32.745215 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 27 15:47:32.745431 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 27 15:47:32.747533 systemd[1]: motdgen.service: Deactivated successfully. Oct 27 15:47:32.747765 systemd[1]: Finished Generate /run/flatcar/motd. Oct 27 15:47:32.770764 extend-filesystems[903]: Old size kept for /dev/vda9 Oct 27 15:47:32.769552 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 27 15:47:32.769770 systemd[1]: Finished Extend Filesystems. Oct 27 15:47:32.776678 systemd-logind[913]: Watching system buttons on /dev/input/event0 (Power Button) Oct 27 15:47:32.776893 systemd-logind[913]: New seat seat0. Oct 27 15:47:32.780168 systemd[1]: Started User Login Management. Oct 27 15:47:32.781258 update_engine[919]: I1027 15:47:32.780882 919 main.cc:89] Flatcar Update Engine starting Oct 27 15:47:32.795376 update_engine[919]: I1027 15:47:32.783468 919 update_check_scheduler.cc:74] Next update check in 9m58s Oct 27 15:47:32.783258 systemd[1]: Started Update Engine. Oct 27 15:47:32.795158 systemd[1]: Started Cluster reboot manager. Oct 27 15:47:32.821521 locksmithd[926]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 27 15:47:33.349841 systemd[1]: Created slice Slice /system/sshd. Oct 27 15:47:33.486122 systemd-networkd[838]: eth0: Gained IPv6LL Oct 27 15:47:33.686716 sshd_keygen[921]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 27 15:47:33.704708 systemd[1]: Finished Generate sshd host keys. Oct 27 15:47:33.716677 systemd[1]: Starting Generate /run/issue... Oct 27 15:47:33.718318 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:32972). Oct 27 15:47:33.721225 systemd[1]: issuegen.service: Deactivated successfully. Oct 27 15:47:33.721439 systemd[1]: Finished Generate /run/issue. Oct 27 15:47:33.723317 systemd[1]: Starting Permit User Sessions... Oct 27 15:47:33.727585 systemd[1]: Finished Permit User Sessions. Oct 27 15:47:33.729538 systemd[1]: Started Getty on tty1. Oct 27 15:47:33.731625 systemd[1]: Started Serial Getty on ttyAMA0. Oct 27 15:47:33.732598 systemd[1]: Reached target Login Prompts. Oct 27 15:47:33.733408 systemd[1]: Reached target Multi-User System. Oct 27 15:47:33.735208 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 27 15:47:33.740998 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 27 15:47:33.741218 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 27 15:47:33.742335 systemd[1]: Startup finished in 692ms (kernel) + 2.317s (initrd) + 3.869s (userspace) = 6.879s. Oct 27 15:47:33.765395 sshd[941]: Accepted publickey for core from 10.0.0.1 port 32972 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:47:33.767095 sshd[941]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:33.782324 systemd[1]: Created slice User Slice of UID 500. Oct 27 15:47:33.783297 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 27 15:47:33.784830 systemd-logind[913]: New session 1 of user core. Oct 27 15:47:33.789428 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 27 15:47:33.790558 systemd[1]: Starting User Manager for UID 500... Oct 27 15:47:33.792865 systemd[951]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:33.838880 systemd[951]: Queued start job for default target Main User Target. Oct 27 15:47:33.839066 systemd[951]: Reached target Paths. Oct 27 15:47:33.839080 systemd[951]: Reached target Sockets. Oct 27 15:47:33.839090 systemd[951]: Reached target Timers. Oct 27 15:47:33.839100 systemd[951]: Reached target Basic System. Oct 27 15:47:33.839143 systemd[951]: Reached target Main User Target. Oct 27 15:47:33.839155 systemd[951]: Startup finished in 42ms. Oct 27 15:47:33.839377 systemd[1]: Started User Manager for UID 500. Oct 27 15:47:33.850018 systemd[1]: Started Session 1 of User core. Oct 27 15:47:33.937430 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39734). Oct 27 15:47:33.981816 sshd[960]: Accepted publickey for core from 10.0.0.1 port 39734 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:47:33.982899 sshd[960]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:33.985603 systemd-logind[913]: New session 2 of user core. Oct 27 15:47:34.001008 systemd[1]: Started Session 2 of User core. Oct 27 15:47:34.052403 sshd[960]: pam_unix(sshd:session): session closed for user core Oct 27 15:47:34.065459 systemd[1]: sshd@1-10.0.0.3:22-10.0.0.1:39734.service: Deactivated successfully. Oct 27 15:47:34.066077 systemd[1]: session-2.scope: Deactivated successfully. Oct 27 15:47:34.066626 systemd-logind[913]: Session 2 logged out. Waiting for processes to exit. Oct 27 15:47:34.067616 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39748). Oct 27 15:47:34.068191 systemd-logind[913]: Removed session 2. Oct 27 15:47:34.108924 sshd[966]: Accepted publickey for core from 10.0.0.1 port 39748 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:47:34.109893 sshd[966]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:34.112640 systemd-logind[913]: New session 3 of user core. Oct 27 15:47:34.124983 systemd[1]: Started Session 3 of User core. Oct 27 15:47:34.171649 sshd[966]: pam_unix(sshd:session): session closed for user core Oct 27 15:47:34.183692 systemd[1]: sshd@2-10.0.0.3:22-10.0.0.1:39748.service: Deactivated successfully. Oct 27 15:47:34.184240 systemd[1]: session-3.scope: Deactivated successfully. Oct 27 15:47:34.184749 systemd-logind[913]: Session 3 logged out. Waiting for processes to exit. Oct 27 15:47:34.185721 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39756). Oct 27 15:47:34.186394 systemd-logind[913]: Removed session 3. Oct 27 15:47:34.227349 sshd[972]: Accepted publickey for core from 10.0.0.1 port 39756 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:47:34.228192 sshd[972]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:34.230689 systemd-logind[913]: New session 4 of user core. Oct 27 15:47:34.241969 systemd[1]: Started Session 4 of User core. Oct 27 15:47:34.292522 sshd[972]: pam_unix(sshd:session): session closed for user core Oct 27 15:47:34.301692 systemd[1]: sshd@3-10.0.0.3:22-10.0.0.1:39756.service: Deactivated successfully. Oct 27 15:47:34.302246 systemd[1]: session-4.scope: Deactivated successfully. Oct 27 15:47:34.302843 systemd-logind[913]: Session 4 logged out. Waiting for processes to exit. Oct 27 15:47:34.303860 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39770). Oct 27 15:47:34.304423 systemd-logind[913]: Removed session 4. Oct 27 15:47:34.344945 sshd[978]: Accepted publickey for core from 10.0.0.1 port 39770 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:47:34.346183 sshd[978]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:34.348670 systemd-logind[913]: New session 5 of user core. Oct 27 15:47:34.358994 systemd[1]: Started Session 5 of User core. Oct 27 15:47:34.412676 sudo[981]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 27 15:47:34.412897 sudo[981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:47:34.422320 sudo[981]: pam_unix(sudo:session): session closed for user root Oct 27 15:47:34.424253 sshd[978]: pam_unix(sshd:session): session closed for user core Oct 27 15:47:34.434472 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39776). Oct 27 15:47:34.436518 dbus-daemon[902]: [system] Reloaded configuration Oct 27 15:47:34.438630 systemd[1]: sshd@4-10.0.0.3:22-10.0.0.1:39770.service: Deactivated successfully. Oct 27 15:47:34.439198 systemd[1]: session-5.scope: Deactivated successfully. Oct 27 15:47:34.439774 systemd-logind[913]: Session 5 logged out. Waiting for processes to exit. Oct 27 15:47:34.440375 systemd-logind[913]: Removed session 5. Oct 27 15:47:34.477553 sshd[984]: Accepted publickey for core from 10.0.0.1 port 39776 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:47:34.478784 sshd[984]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:34.481953 systemd-logind[913]: New session 6 of user core. Oct 27 15:47:34.490383 systemd[1]: Started Session 6 of User core. Oct 27 15:47:34.541217 sudo[989]: 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 27 15:47:34.541415 sudo[989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:47:34.543772 sudo[989]: pam_unix(sudo:session): session closed for user root Oct 27 15:47:34.547237 sudo[988]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 27 15:47:34.547439 sudo[988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:47:34.563525 systemd[1]: Stopping Load Security Auditing Rules... Oct 27 15:47:34.563000 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 27 15:47:34.565038 auditctl[992]: No rules Oct 27 15:47:34.565318 systemd[1]: audit-rules.service: Deactivated successfully. Oct 27 15:47:34.565564 systemd[1]: Stopped Load Security Auditing Rules. Oct 27 15:47:34.566945 systemd[1]: Starting Load Security Auditing Rules... Oct 27 15:47:34.581257 augenrules[1009]: No rules Oct 27 15:47:34.582154 systemd[1]: Finished Load Security Auditing Rules. Oct 27 15:47:34.583191 sudo[988]: pam_unix(sudo:session): session closed for user root Oct 27 15:47:34.584425 sshd[984]: pam_unix(sshd:session): session closed for user core Oct 27 15:47:34.600581 systemd[1]: sshd@5-10.0.0.3:22-10.0.0.1:39776.service: Deactivated successfully. Oct 27 15:47:34.601141 systemd[1]: session-6.scope: Deactivated successfully. Oct 27 15:47:34.601699 systemd-logind[913]: Session 6 logged out. Waiting for processes to exit. Oct 27 15:47:34.602707 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39788). Oct 27 15:47:34.603341 systemd-logind[913]: Removed session 6. Oct 27 15:47:34.644083 sshd[1016]: Accepted publickey for core from 10.0.0.1 port 39788 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:47:34.645450 sshd[1016]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:34.647966 systemd-logind[913]: New session 7 of user core. Oct 27 15:47:34.663973 systemd[1]: Started Session 7 of User core. Oct 27 15:47:34.715185 sudo[1019]: 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 27 15:47:37.574942 update_engine[1267]: Oct 27 15:47:37.574942 update_engine[1267]: Oct 27 15:47:37.574942 update_engine[1267]: Oct 27 15:47:37.574942 update_engine[1267]: Oct 27 15:47:37.574942 update_engine[1267]: Oct 27 15:47:37.574942 update_engine[1267]: Oct 27 15:47:37.574942 update_engine[1267]: Oct 27 15:47:37.574942 update_engine[1267]: Oct 27 15:47:37.574942 update_engine[1267]: I1027 15:47:37.574882 1267 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 27 15:47:37.579810 update_engine[1267]: I1027 15:47:37.579789 1267 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 27 15:47:37.580111 update_engine[1267]: I1027 15:47:37.580089 1267 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 27 15:47:37.581626 update_engine[1267]: I1027 15:47:37.581605 1267 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 27 15:47:37.582887 update_engine[1267]: I1027 15:47:37.582676 1267 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Oct 27 15:47:37.582887 update_engine[1267]: I1027 15:47:37.582696 1267 omaha_request_action.cc:619] Omaha request response: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.582887 update_engine[1267]: Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589301 1267 omaha_request_action.cc:447] Omaha Response manifest version = Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589339 1267 omaha_request_action.cc:470] Found 1 url(s) Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589352 1267 omaha_request_action.cc:506] Processing first of 1 package(s) Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589356 1267 omaha_request_action.cc:513] Omaha Response package name = update.gz Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589359 1267 omaha_request_action.cc:529] Url0: http://10.0.0.2:34567/packages/update.gz Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589370 1267 omaha_request_action.cc:541] Payload size = 435420691 bytes Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589391 1267 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589406 1267 payload_state.cc:51] Resetting all persisted state as this is a new response Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589409 1267 payload_state.cc:360] Current Response Signature = Oct 27 15:47:37.590525 update_engine[1267]: NumURLs = 1 Oct 27 15:47:37.590525 update_engine[1267]: Url0 = http://10.0.0.2:34567/packages/update.gz Oct 27 15:47:37.590525 update_engine[1267]: Payload Size = 435420691 Oct 27 15:47:37.590525 update_engine[1267]: Payload Sha256 Hash = nLXXFElmOY/MZeJVU0GqyZXmGgOOWnMNMVrLO0s03g0= Oct 27 15:47:37.590525 update_engine[1267]: Is Delta Payload = 0 Oct 27 15:47:37.590525 update_engine[1267]: Max Failure Count Per Url = 10 Oct 27 15:47:37.590525 update_engine[1267]: Disable Payload Backoff = 1 Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589521 1267 payload_state.cc:381] Payload Attempt Number = 0 Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589555 1267 payload_state.cc:404] Current URL Index = 0 Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589584 1267 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589613 1267 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589617 1267 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589653 1267 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589672 1267 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589676 1267 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Oct 27 15:47:37.590525 update_engine[1267]: I1027 15:47:37.589764 1267 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Oct 27 15:47:37.591104 update_engine[1267]: E1027 15:47:37.589776 1267 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Oct 27 15:47:37.591104 update_engine[1267]: I1027 15:47:37.590332 1267 omaha_response_handler_action.cc:85] Using this install plan: Oct 27 15:47:37.591104 update_engine[1267]: I1027 15:47:37.590343 1267 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.2:34567/packages/update.gz, payload size: 435420691, payload hash: nLXXFElmOY/MZeJVU0GqyZXmGgOOWnMNMVrLO0s03g0=, 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 27 15:47:37.591104 update_engine[1267]: I1027 15:47:37.590443 1267 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Oct 27 15:47:37.593178 update_engine[1267]: I1027 15:47:37.593083 1267 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Oct 27 15:47:37.611995 sshd[1287]: Accepted publickey for core from 10.0.0.1 port 39948 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:47:37.614840 sshd[1287]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:37.619997 systemd-logind[913]: New session 25 of user core. Oct 27 15:47:37.625964 systemd[1]: Started Session 25 of User core. Oct 27 15:47:37.691608 sshd[1287]: pam_unix(sshd:session): session closed for user core Oct 27 15:47:37.695891 systemd-logind[913]: Session 25 logged out. Waiting for processes to exit. Oct 27 15:47:37.695962 systemd[1]: sshd@24-10.0.0.3:22-10.0.0.1:39948.service: Deactivated successfully. Oct 27 15:47:37.696575 systemd[1]: session-25.scope: Deactivated successfully. Oct 27 15:47:37.697171 systemd-logind[913]: Removed session 25. Oct 27 15:47:39.447398 update_engine[1267]: I1027 15:47:39.447362 1267 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Oct 27 15:47:39.447807 update_engine[1267]: I1027 15:47:39.447791 1267 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Oct 27 15:47:39.513638 update_engine[1267]: E1027 15:47:39.513598 1267 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Oct 27 15:47:39.513794 update_engine[1267]: I1027 15:47:39.513784 1267 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Oct 27 15:47:39.513867 update_engine[1267]: I1027 15:47:39.513856 1267 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Oct 27 15:47:39.513920 update_engine[1267]: I1027 15:47:39.513911 1267 update_attempter.cc:302] Processing Done. Oct 27 15:47:39.514007 update_engine[1267]: E1027 15:47:39.513996 1267 update_attempter.cc:615] Update failed. Oct 27 15:47:39.514056 update_engine[1267]: I1027 15:47:39.514045 1267 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Oct 27 15:47:39.514100 update_engine[1267]: I1027 15:47:39.514091 1267 payload_state.cc:276] Incrementing the URL failure count Oct 27 15:47:39.514146 update_engine[1267]: I1027 15:47:39.514137 1267 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 27 15:47:39.514482 update_engine[1267]: I1027 15:47:39.514467 1267 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 27 15:47:39.514588 update_engine[1267]: I1027 15:47:39.514575 1267 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.2:34567/v1/update Oct 27 15:47:39.514645 update_engine[1267]: I1027 15:47:39.514635 1267 omaha_request_action.cc:269] Request: Oct 27 15:47:39.514645 update_engine[1267]: Oct 27 15:47:39.514645 update_engine[1267]: Oct 27 15:47:39.514645 update_engine[1267]: Oct 27 15:47:39.514645 update_engine[1267]: Oct 27 15:47:39.514645 update_engine[1267]: Oct 27 15:47:39.514645 update_engine[1267]: Oct 27 15:47:39.514789 update_engine[1267]: I1027 15:47:39.514779 1267 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 27 15:47:39.514926 update_engine[1267]: I1027 15:47:39.514915 1267 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 27 15:47:39.515104 update_engine[1267]: I1027 15:47:39.515087 1267 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 27 15:47:39.704110 update_engine[1267]: I1027 15:47:39.703981 1267 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 27 15:47:39.704110 update_engine[1267]: I1027 15:47:39.704062 1267 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Oct 27 15:47:39.704110 update_engine[1267]: I1027 15:47:39.704069 1267 omaha_request_action.cc:619] Omaha request response: Oct 27 15:47:39.704110 update_engine[1267]: Oct 27 15:47:39.704110 update_engine[1267]: Oct 27 15:47:39.704110 update_engine[1267]: Oct 27 15:47:39.704110 update_engine[1267]: Oct 27 15:47:39.704110 update_engine[1267]: Oct 27 15:47:39.704110 update_engine[1267]: Oct 27 15:47:39.704110 update_engine[1267]: E1027 15:47:39.704084 1267 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Oct 27 15:47:39.704110 update_engine[1267]: I1027 15:47:39.704088 1267 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 27 15:47:39.704110 update_engine[1267]: I1027 15:47:39.704092 1267 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 27 15:47:39.704110 update_engine[1267]: I1027 15:47:39.704095 1267 update_attempter.cc:302] Processing Done. Oct 27 15:47:39.704110 update_engine[1267]: I1027 15:47:39.704100 1267 update_attempter.cc:306] Error event sent. Oct 27 15:47:47.656570 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36854). Oct 27 15:47:47.700220 sshd[1303]: Accepted publickey for core from 10.0.0.1 port 36854 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:47:47.701840 sshd[1303]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:47.702067 kernel: kauditd_printk_skb: 132 callbacks suppressed Oct 27 15:47:47.702107 kernel: audit: type=1006 audit(1761580067.699:359): pid=1303 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 27 15:47:47.704706 systemd-logind[913]: New session 26 of user core. Oct 27 15:47:47.713000 systemd[1]: Started Session 26 of User core. Oct 27 15:47:47.768941 sshd[1303]: pam_unix(sshd:session): session closed for user core Oct 27 15:47:47.776916 systemd[1]: sshd@25-10.0.0.3:22-10.0.0.1:36854.service: Deactivated successfully. Oct 27 15:47:47.777550 systemd[1]: session-26.scope: Deactivated successfully. Oct 27 15:47:47.778206 systemd-logind[913]: Session 26 logged out. Waiting for processes to exit. Oct 27 15:47:47.779291 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36858). Oct 27 15:47:47.780007 systemd-logind[913]: Removed session 26. Oct 27 15:47:47.820370 sshd[1310]: Accepted publickey for core from 10.0.0.1 port 36858 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:47:47.821405 sshd[1310]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:47.823844 kernel: audit: type=1006 audit(1761580067.820:360): pid=1310 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 27 15:47:47.825207 systemd-logind[913]: New session 27 of user core. Oct 27 15:47:47.836006 systemd[1]: Started Session 27 of User core. Oct 27 15:47:47.891026 sshd[1310]: pam_unix(sshd:session): session closed for user core Oct 27 15:47:47.906993 systemd[1]: sshd@26-10.0.0.3:22-10.0.0.1:36858.service: Deactivated successfully. Oct 27 15:47:47.908063 systemd[1]: session-27.scope: Deactivated successfully. Oct 27 15:47:47.908698 systemd-logind[913]: Session 27 logged out. Waiting for processes to exit. Oct 27 15:47:47.909844 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36868). Oct 27 15:47:47.910547 systemd-logind[913]: Removed session 27. Oct 27 15:47:47.951878 sshd[1318]: Accepted publickey for core from 10.0.0.1 port 36868 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:47:47.953165 sshd[1318]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:47.954836 kernel: audit: type=1006 audit(1761580067.952:361): pid=1318 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 27 15:47:47.956919 systemd-logind[913]: New session 28 of user core. Oct 27 15:47:47.972011 systemd[1]: Started Session 28 of User core. Oct 27 15:47:48.021514 sshd[1318]: pam_unix(sshd:session): session closed for user core Oct 27 15:47:48.036992 systemd[1]: sshd@27-10.0.0.3:22-10.0.0.1:36868.service: Deactivated successfully. Oct 27 15:47:48.037800 systemd[1]: session-28.scope: Deactivated successfully. Oct 27 15:47:48.038551 systemd-logind[913]: Session 28 logged out. Waiting for processes to exit. Oct 27 15:47:48.039962 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36880). Oct 27 15:47:48.040723 systemd-logind[913]: Removed session 28. Oct 27 15:47:48.081223 sshd[1324]: Accepted publickey for core from 10.0.0.1 port 36880 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:47:48.082222 sshd[1324]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:48.083847 kernel: audit: type=1006 audit(1761580068.081:362): pid=1324 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 27 15:47:48.086048 systemd-logind[913]: New session 29 of user core. Oct 27 15:47:48.093106 systemd[1]: Started Session 29 of User core. Oct 27 15:47:48.141335 sshd[1324]: pam_unix(sshd:session): session closed for user core Oct 27 15:47:48.157632 systemd[1]: sshd@28-10.0.0.3:22-10.0.0.1:36880.service: Deactivated successfully. Oct 27 15:47:48.158231 systemd[1]: session-29.scope: Deactivated successfully. Oct 27 15:47:48.158866 systemd-logind[913]: Session 29 logged out. Waiting for processes to exit. Oct 27 15:47:48.159987 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36892). Oct 27 15:47:48.160655 systemd-logind[913]: Removed session 29. Oct 27 15:47:48.202021 sshd[1330]: Accepted publickey for core from 10.0.0.1 port 36892 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:47:48.203542 sshd[1330]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:47:48.205844 kernel: audit: type=1006 audit(1761580068.201:363): pid=1330 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 27 15:47:48.207583 systemd-logind[913]: New session 30 of user core. Oct 27 15:47:48.221019 systemd[1]: Started Session 30 of User core. Oct 27 15:47:48.272663 sudo[1334]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Oct 27 15:47:48.272892 sudo[1334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:47:48.278816 systemd[1]: sshd.socket: Deactivated successfully. Oct 27 15:47:48.279197 systemd[1]: Closed OpenSSH Server Socket. Oct 27 15:47:48.280174 sudo[1334]: pam_unix(sudo:session): session closed for user root Oct 27 15:47:48.284145 sudo[1333]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Oct 27 15:47:48.284350 sudo[1333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:47:48.301071 systemd[1]: Stopping Session 1 of User core... Oct 27 15:47:48.301479 sshd[941]: pam_unix(sshd:session): session closed for user core Oct 27 15:47:48.302915 sshd[1330]: pam_unix(sshd:session): session closed for user core Oct 27 15:47:48.303102 systemd[1]: Stopping Session 30 of User core... Oct 27 15:47:48.303371 sudo[1333]: pam_unix(sudo:session): session closed for user root Oct 27 15:47:48.304458 systemd[1]: Removed slice Slice /system/addon-config. Oct 27 15:47:48.305768 systemd[1]: Removed slice Slice /system/addon-run. Oct 27 15:47:48.307060 systemd[1]: Removed slice Slice /system/modprobe. Oct 27 15:47:48.308257 systemd[1]: Removed slice Slice /system/system-cloudinit. Oct 27 15:47:48.309358 systemd[1]: Stopped target Multi-User System. Oct 27 15:47:48.310289 systemd[1]: Stopped target Login Prompts. Oct 27 15:47:48.311237 systemd[1]: Stopped target Containers. Oct 27 15:47:48.312056 systemd[1]: Stopped target Host and Network Name Lookups. Oct 27 15:47:48.313043 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 27 15:47:48.314003 systemd[1]: Stopped target Timer Units. Oct 27 15:47:48.314803 systemd[1]: logrotate.timer: Deactivated successfully. Oct 27 15:47:48.315092 systemd[1]: Stopped Daily Log Rotation. Oct 27 15:47:48.315912 systemd[1]: mdadm.timer: Deactivated successfully. Oct 27 15:47:48.316056 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Oct 27 15:47:48.317166 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 27 15:47:48.317296 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Oct 27 15:47:48.318348 systemd[1]: Stopped target System Time Set. Oct 27 15:47:48.319448 systemd[1]: Stopped target Load user-provided cloud configs. Oct 27 15:47:48.320574 systemd[1]: Stopped target Load system-provided cloud configs. Oct 27 15:47:48.321727 systemd[1]: systemd-coredump.socket: Deactivated successfully. Oct 27 15:47:48.321960 systemd[1]: Closed Process Core Dump Socket. Oct 27 15:47:48.323657 systemd[1]: Unmounting Boot partition... Oct 27 15:47:48.324912 systemd[1]: Stopping Getty on tty1... Oct 27 15:47:48.325791 systemd[1]: Stopping Serial Getty on ttyAMA0... Oct 27 15:47:48.328125 systemd[1]: Stopping OpenSSH per-connection server daemon... Oct 27 15:47:48.329025 sshd[1330]: pam_systemd(sshd:session): Failed to release session: Interrupted system call Oct 27 15:47:48.329086 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:36892)... Oct 27 15:47:48.330058 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Oct 27 15:47:48.330200 systemd[1]: Stopped Commit a transient machine-id on disk. Oct 27 15:47:48.331199 systemd[1]: Stopped target First Boot Complete. Oct 27 15:47:48.333120 systemd[1]: Stopping Load/Save Random Seed... Oct 27 15:47:48.334015 systemd[1]: Stopping Update Engine... Oct 27 15:47:48.337279 systemd[1]: sshd@0-10.0.0.3:22-10.0.0.1:32972.service: Deactivated successfully. Oct 27 15:47:48.338366 systemd[1]: Stopped OpenSSH per-connection server daemon. Oct 27 15:47:48.340318 systemd[1]: getty@tty1.service: Deactivated successfully. Oct 27 15:47:48.340551 systemd[1]: Stopped Getty on tty1. Oct 27 15:47:48.341739 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Oct 27 15:47:48.341963 systemd[1]: Stopped Serial Getty on ttyAMA0. Oct 27 15:47:48.343273 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Oct 27 15:47:48.343345 systemd[1]: update-engine.service: Failed with result 'exit-code'. Oct 27 15:47:48.343599 systemd[1]: Stopped Update Engine. Oct 27 15:47:48.344367 systemd[1]: update-engine.service: Consumed 2.374s CPU time. Oct 27 15:47:48.344635 systemd[1]: sshd@29-10.0.0.3:22-10.0.0.1:36892.service: Deactivated successfully. Oct 27 15:47:48.344833 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:36892). Oct 27 15:47:48.352585 systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 27 15:47:48.352854 systemd[1]: Stopped Load/Save Random Seed. Oct 27 15:47:48.353844 systemd[1]: session-30.scope: Deactivated successfully. Oct 27 15:47:48.354072 systemd[1]: Stopped Session 30 of User core. Oct 27 15:47:48.355150 systemd[1]: session-1.scope: Deactivated successfully. Oct 27 15:47:48.355383 systemd[1]: Stopped Session 1 of User core. Oct 27 15:47:48.356573 systemd-logind[913]: Session 1 logged out. Waiting for processes to exit. Oct 27 15:47:48.356643 systemd[1]: boot.mount: Deactivated successfully. Oct 27 15:47:48.356941 systemd[1]: Unmounted Boot partition. Oct 27 15:47:48.358862 systemd-logind[913]: Session 30 logged out. Waiting for processes to exit. Oct 27 15:47:48.359106 systemd[1]: Removed slice Slice /system/getty. Oct 27 15:47:48.360214 systemd[1]: Removed slice Slice /system/serial-getty. Oct 27 15:47:48.361762 systemd[1]: Removed slice Slice /system/sshd. Oct 27 15:47:48.362631 systemd[1]: sshd-keygen.service: Deactivated successfully. Oct 27 15:47:48.362739 systemd[1]: Stopped Generate sshd host keys. Oct 27 15:47:48.363658 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Oct 27 15:47:48.363781 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Oct 27 15:47:48.365288 systemd[1]: Stopping User Login Management... Oct 27 15:47:48.366054 systemd[1]: Stopping User Manager for UID 500... Oct 27 15:47:48.366972 systemd[951]: Stopped target Main User Target. Oct 27 15:47:48.366991 systemd[951]: Stopped target Basic System. Oct 27 15:47:48.367000 systemd[951]: Stopped target Paths. Oct 27 15:47:48.367008 systemd[951]: Stopped target Sockets. Oct 27 15:47:48.367026 systemd[951]: Reached target Shutdown. Oct 27 15:47:48.367032 systemd[951]: Stopped target Timers. Oct 27 15:47:48.367145 systemd[951]: Finished Exit the Session. Oct 27 15:47:48.367170 systemd[951]: Reached target Exit the Session. Oct 27 15:47:48.368575 systemd-logind[913]: Removed session 30. Oct 27 15:47:48.369401 systemd[1]: user@500.service: Deactivated successfully. Oct 27 15:47:48.369672 systemd[1]: Stopped User Manager for UID 500. Oct 27 15:47:48.371913 systemd[1]: Stopping User Runtime Directory /run/user/500... Oct 27 15:47:48.377278 systemd[1]: run-user-500.mount: Deactivated successfully. Oct 27 15:47:48.377453 systemd[1]: Unmounted /run/user/500. Oct 27 15:47:48.378668 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Oct 27 15:47:48.378996 systemd[1]: Stopped User Runtime Directory /run/user/500. Oct 27 15:47:48.380625 systemd[1]: Removed slice User Slice of UID 500. Oct 27 15:47:48.383177 systemd[1]: Stopping D-Bus System Message Bus... Oct 27 15:47:48.384680 systemd[1]: Stopping Permit User Sessions... Oct 27 15:47:48.386062 systemd[1]: dbus.service: Deactivated successfully. Oct 27 15:47:48.386307 systemd[1]: Stopped D-Bus System Message Bus. Oct 27 15:47:48.387700 systemd[1]: systemd-logind.service: Deactivated successfully. Oct 27 15:47:48.387970 systemd[1]: Stopped User Login Management. Oct 27 15:47:48.389793 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Oct 27 15:47:48.390027 systemd[1]: Stopped Permit User Sessions. Oct 27 15:47:48.390791 systemd[1]: Stopped target Basic System. Oct 27 15:47:48.391586 systemd[1]: Stopped target Network. Oct 27 15:47:48.392254 systemd[1]: Stopped target Path Units. Oct 27 15:47:48.393032 systemd[1]: motdgen.path: Deactivated successfully. Oct 27 15:47:48.394000 audit: BPF prog-id=57 op=UNLOAD Oct 27 15:47:48.395844 kernel: audit: type=1334 audit(1761580068.394:364): prog-id=57 op=UNLOAD Oct 27 15:47:48.396892 systemd[1]: Stopped Watch for update engine configuration changes. Oct 27 15:47:48.397912 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Oct 27 15:47:48.400888 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 27 15:47:48.401909 systemd[1]: Stopped target Remote File Systems. Oct 27 15:47:48.402619 systemd[1]: Stopped target Slice Units. Oct 27 15:47:48.403656 systemd[1]: Removed slice User and Session Slice. Oct 27 15:47:48.404515 systemd[1]: Stopped target Socket Units. Oct 27 15:47:48.405251 systemd[1]: dbus.socket: Deactivated successfully. Oct 27 15:47:48.405309 systemd[1]: Closed D-Bus System Message Bus Socket. Oct 27 15:47:48.406254 systemd[1]: docker.socket: Deactivated successfully. Oct 27 15:47:48.406519 systemd[1]: Closed Docker Socket for the API. Oct 27 15:47:48.407364 systemd[1]: Stopped target System Initialization. Oct 27 15:47:48.408252 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Oct 27 15:47:48.408409 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Oct 27 15:47:48.409463 systemd[1]: Stopped target Local Verity Protected Volumes. Oct 27 15:47:48.421845 systemd[1]: Stopping Load Security Auditing Rules... Oct 27 15:47:48.423811 systemd[1]: Stopping Network Name Resolution... Oct 27 15:47:48.423935 auditctl[1350]: No rules Oct 27 15:47:48.424554 systemd[1]: Stopping Network Time Synchronization... Oct 27 15:47:48.425261 systemd[1]: systemd-update-done.service: Deactivated successfully. Oct 27 15:47:48.425340 systemd[1]: Stopped Update is Completed. Oct 27 15:47:48.426056 systemd[1]: ldconfig.service: Deactivated successfully. Oct 27 15:47:48.426108 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Oct 27 15:47:48.426939 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Oct 27 15:47:48.426995 systemd[1]: Stopped Rebuild Hardware Database. Oct 27 15:47:48.427737 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Oct 27 15:47:48.427792 systemd[1]: Stopped Rebuild Journal Catalog. Oct 27 15:47:48.429500 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Oct 27 15:47:48.432166 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 27 15:47:48.432444 systemd[1]: Stopped Network Name Resolution. Oct 27 15:47:48.433496 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Oct 27 15:47:48.433733 systemd[1]: Stopped Network Time Synchronization. Oct 27 15:47:48.434943 systemd[1]: audit-rules.service: Deactivated successfully. Oct 27 15:47:48.435163 systemd[1]: Stopped Load Security Auditing Rules. Oct 27 15:47:48.437417 systemd[1]: Stopping Network Configuration... Oct 27 15:47:48.446877 systemd-networkd[838]: eth0: DHCPv6 lease lost Oct 27 15:47:48.451000 audit: BPF prog-id=64 op=UNLOAD Oct 27 15:47:48.452464 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 27 15:47:48.451000 audit: BPF prog-id=56 op=UNLOAD Oct 27 15:47:48.452886 systemd[1]: Stopped Network Configuration. Oct 27 15:47:48.453527 kernel: audit: type=1334 audit(1761580068.451:365): prog-id=64 op=UNLOAD Oct 27 15:47:48.453561 kernel: audit: type=1334 audit(1761580068.451:366): prog-id=56 op=UNLOAD Oct 27 15:47:48.454609 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Oct 27 15:47:48.454872 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Oct 27 15:47:48.456556 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 27 15:47:48.456644 systemd[1]: Closed Network Service Netlink Socket. Oct 27 15:47:48.457558 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 27 15:47:48.457618 systemd[1]: Stopped Apply Kernel Variables. Oct 27 15:47:48.458375 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 27 15:47:48.458427 systemd[1]: Stopped Create Volatile Files and Directories. Oct 27 15:47:48.459301 systemd[1]: Stopped target Local File Systems. Oct 27 15:47:48.460000 audit: BPF prog-id=63 op=UNLOAD Oct 27 15:47:48.461191 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Oct 27 15:47:48.461847 kernel: audit: type=1334 audit(1761580068.460:367): prog-id=63 op=UNLOAD Oct 27 15:47:48.463313 systemd[1]: Unmounting External Media Directory... Oct 27 15:47:48.464975 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Oct 27 15:47:48.466733 systemd[1]: Unmounting /run/torcx/unpack... Oct 27 15:47:48.468254 systemd[1]: Unmounting Temporary Directory /tmp... Oct 27 15:47:48.469627 systemd[1]: Unmounting /usr/share/oem... Oct 27 15:47:48.471222 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Oct 27 15:47:48.474154 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 27 15:47:48.474436 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Oct 27 15:47:48.475642 systemd[1]: media.mount: Deactivated successfully. Oct 27 15:47:48.475931 systemd[1]: Unmounted External Media Directory. Oct 27 15:47:48.476777 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Oct 27 15:47:48.477041 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Oct 27 15:47:48.478229 systemd[1]: tmp.mount: Deactivated successfully. Oct 27 15:47:48.478488 systemd[1]: Unmounted Temporary Directory /tmp. Oct 27 15:47:48.479398 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 27 15:47:48.479655 systemd[1]: Unmounted /usr/share/oem. Oct 27 15:47:48.480510 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 27 15:47:48.480755 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Oct 27 15:47:48.482095 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Oct 27 15:47:48.482175 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Oct 27 15:47:48.483339 systemd[1]: Removed slice Slice /system/systemd-fsck. Oct 27 15:47:48.489286 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Oct 27 15:47:48.489531 systemd[1]: Unmounted /run/torcx/unpack. Oct 27 15:47:48.490729 systemd[1]: Stopped target Preparation for Local File Systems. Oct 27 15:47:48.491751 systemd[1]: Stopped target Swaps. Oct 27 15:47:48.492431 systemd[1]: Reached target Unmount All Filesystems. Oct 27 15:47:48.493217 systemd[1]: lvm2-activation.service: Deactivated successfully. Oct 27 15:47:48.493290 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 27 15:47:48.494358 systemd[1]: Stopped target Local Encrypted Volumes. Oct 27 15:47:48.495114 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 27 15:47:48.495321 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 27 15:47:48.496572 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Oct 27 15:47:48.501898 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Oct 27 15:47:48.502935 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Oct 27 15:47:48.503015 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 27 15:47:48.503796 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 27 15:47:48.503872 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 27 15:47:48.504651 systemd[1]: systemd-sysusers.service: Deactivated successfully. Oct 27 15:47:48.504706 systemd[1]: Stopped Create System Users. Oct 27 15:47:48.505458 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Oct 27 15:47:48.505509 systemd[1]: Stopped Remount Root and Kernel File Systems. Oct 27 15:47:48.506399 systemd[1]: Reached target System Shutdown. Oct 27 15:47:48.507096 systemd[1]: Reached target Late Shutdown Services. Oct 27 15:47:48.507888 systemd[1]: systemd-reboot.service: Deactivated successfully. Oct 27 15:47:48.507927 systemd[1]: Finished System Reboot. Oct 27 15:47:48.508541 systemd[1]: Reached target System Reboot. Oct 27 15:47:48.509244 systemd[1]: Shutting down. Oct 27 15:47:48.511000 audit: BPF prog-id=55 op=UNLOAD Oct 27 15:47:48.512876 kernel: audit: type=1334 audit(1761580068.511:368): prog-id=55 op=UNLOAD Oct 27 15:47:48.511000 audit: BPF prog-id=54 op=UNLOAD Oct 27 15:47:48.511000 audit: BPF prog-id=53 op=UNLOAD Oct 27 15:47:48.512000 audit: BPF prog-id=59 op=UNLOAD Oct 27 15:47:48.512000 audit: BPF prog-id=58 op=UNLOAD Oct 27 15:47:48.513000 audit: BPF prog-id=62 op=UNLOAD Oct 27 15:47:48.513000 audit: BPF prog-id=61 op=UNLOAD Oct 27 15:47:48.513000 audit: BPF prog-id=60 op=UNLOAD Oct 27 15:47:48.545794 systemd-shutdown[1]: Syncing filesystems and block devices. Oct 27 15:47:48.551851 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Oct 27 15:47:48.553571 systemd-journald[801]: Journal stopped -- Reboot -- Oct 27 15:47:56.669944 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 27 15:47:56.669960 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 27 15:47:56.669968 kernel: efi: EFI v2.70 by EDK II Oct 27 15:47:56.669973 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 27 15:47:56.669978 kernel: efi: seeding entropy pool Oct 27 15:47:56.669983 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 27 15:47:56.669990 kernel: ACPI: Early table checksum verification disabled Oct 27 15:47:56.669995 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 27 15:47:56.670000 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 27 15:47:56.670005 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:56.670011 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:56.670016 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:56.670021 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:56.670026 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:56.670032 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:56.670038 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:56.670044 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:56.670050 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:47:56.670055 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 27 15:47:56.670060 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 27 15:47:56.670066 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 15:47:56.670071 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 27 15:47:56.670076 kernel: Zone ranges: Oct 27 15:47:56.670081 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 15:47:56.670087 kernel: DMA32 empty Oct 27 15:47:56.670092 kernel: Normal empty Oct 27 15:47:56.670121 kernel: Movable zone start for each node Oct 27 15:47:56.670129 kernel: Early memory node ranges Oct 27 15:47:56.670135 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 27 15:47:56.670140 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 27 15:47:56.670145 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 27 15:47:56.670151 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 27 15:47:56.670156 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 27 15:47:56.670161 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 27 15:47:56.670166 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 27 15:47:56.670172 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 27 15:47:56.670177 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 27 15:47:56.670182 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 27 15:47:56.670187 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 27 15:47:56.670193 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 15:47:56.670201 kernel: On node 0 totalpages: 643072 Oct 27 15:47:56.670207 kernel: DMA zone: 10048 pages used for memmap Oct 27 15:47:56.670213 kernel: DMA zone: 0 pages reserved Oct 27 15:47:56.670218 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 27 15:47:56.670223 kernel: psci: probing for conduit method from ACPI. Oct 27 15:47:56.670229 kernel: psci: PSCIv1.1 detected in firmware. Oct 27 15:47:56.670234 kernel: psci: Using standard PSCI v0.2 function IDs Oct 27 15:47:56.670240 kernel: psci: Trusted OS migration not required Oct 27 15:47:56.670247 kernel: psci: SMC Calling Convention v1.1 Oct 27 15:47:56.670252 kernel: ACPI: SRAT not present Oct 27 15:47:56.670258 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 27 15:47:56.670263 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 27 15:47:56.670269 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 27 15:47:56.670280 kernel: Detected PIPT I-cache on CPU0 Oct 27 15:47:56.670286 kernel: CPU features: detected: GIC system register CPU interface Oct 27 15:47:56.670291 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 27 15:47:56.670297 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 27 15:47:56.670302 kernel: CPU features: detected: Hardware dirty bit management Oct 27 15:47:56.670308 kernel: CPU features: detected: Spectre-v4 Oct 27 15:47:56.670315 kernel: CPU features: detected: ARM erratum 1418040 Oct 27 15:47:56.670321 kernel: CPU features: detected: Spectre-BHB Oct 27 15:47:56.670326 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 27 15:47:56.670332 kernel: Policy zone: DMA Oct 27 15:47:56.670338 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 27 15:47:56.670344 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 27 15:47:56.670350 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 27 15:47:56.670356 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 27 15:47:56.670362 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 27 15:47:56.670368 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 27 15:47:56.670373 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 27 15:47:56.670380 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 27 15:47:56.670390 kernel: rcu: RCU event tracing is enabled. Oct 27 15:47:56.670396 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 27 15:47:56.670402 kernel: Trampoline variant of Tasks RCU enabled. Oct 27 15:47:56.670407 kernel: Tracing variant of Tasks RCU enabled. Oct 27 15:47:56.670413 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 27 15:47:56.670419 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 27 15:47:56.670425 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 27 15:47:56.670431 kernel: GICv3: 256 SPIs implemented Oct 27 15:47:56.670436 kernel: GICv3: 0 Extended SPIs implemented Oct 27 15:47:56.670442 kernel: GICv3: Distributor has no Range Selector support Oct 27 15:47:56.670447 kernel: GICv3: 16 PPIs implemented Oct 27 15:47:56.670454 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 27 15:47:56.670460 kernel: ACPI: SRAT not present Oct 27 15:47:56.670465 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 27 15:47:56.670471 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 27 15:47:56.670477 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 27 15:47:56.670483 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 27 15:47:56.670489 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 27 15:47:56.670494 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:47:56.670500 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 27 15:47:56.670506 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 27 15:47:56.670512 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 27 15:47:56.670518 kernel: arm-pv: using stolen time PV Oct 27 15:47:56.670525 kernel: Console: colour dummy device 80x25 Oct 27 15:47:56.670531 kernel: ACPI: Core revision 20200925 Oct 27 15:47:56.670540 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 27 15:47:56.670546 kernel: pid_max: default: 32768 minimum: 301 Oct 27 15:47:56.670552 kernel: LSM: Security Framework initializing Oct 27 15:47:56.670558 kernel: SELinux: Initializing. Oct 27 15:47:56.670564 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 15:47:56.670570 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 15:47:56.670576 kernel: rcu: Hierarchical SRCU implementation. Oct 27 15:47:56.670583 kernel: Platform MSI: ITS@0x8080000 domain created Oct 27 15:47:56.670589 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 27 15:47:56.670594 kernel: Remapping and enabling EFI services. Oct 27 15:47:56.670600 kernel: smp: Bringing up secondary CPUs ... Oct 27 15:47:56.670606 kernel: Detected PIPT I-cache on CPU1 Oct 27 15:47:56.670612 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 27 15:47:56.670617 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 27 15:47:56.670624 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:47:56.670629 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 27 15:47:56.670635 kernel: Detected PIPT I-cache on CPU2 Oct 27 15:47:56.670642 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 27 15:47:56.670648 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 27 15:47:56.670654 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:47:56.670660 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 27 15:47:56.670666 kernel: Detected PIPT I-cache on CPU3 Oct 27 15:47:56.670675 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 27 15:47:56.670681 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 27 15:47:56.670688 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:47:56.670701 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 27 15:47:56.670708 kernel: smp: Brought up 1 node, 4 CPUs Oct 27 15:47:56.670714 kernel: SMP: Total of 4 processors activated. Oct 27 15:47:56.670720 kernel: CPU features: detected: Privileged Access Never Oct 27 15:47:56.670730 kernel: CPU features: detected: LSE atomic instructions Oct 27 15:47:56.670736 kernel: CPU features: detected: User Access Override Oct 27 15:47:56.670742 kernel: CPU features: detected: 32-bit EL0 Support Oct 27 15:47:56.670748 kernel: CPU features: detected: Common not Private translations Oct 27 15:47:56.670754 kernel: CPU features: detected: RAS Extension Support Oct 27 15:47:56.670762 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 27 15:47:56.670768 kernel: CPU features: detected: CRC32 instructions Oct 27 15:47:56.670774 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 27 15:47:56.670780 kernel: CPU: All CPU(s) started at EL1 Oct 27 15:47:56.670786 kernel: alternatives: patching kernel code Oct 27 15:47:56.670793 kernel: devtmpfs: initialized Oct 27 15:47:56.670799 kernel: KASLR enabled Oct 27 15:47:56.670805 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 27 15:47:56.670812 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 27 15:47:56.670818 kernel: pinctrl core: initialized pinctrl subsystem Oct 27 15:47:56.670824 kernel: SMBIOS 3.0.0 present. Oct 27 15:47:56.670830 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 27 15:47:56.670836 kernel: NET: Registered protocol family 16 Oct 27 15:47:56.670842 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 27 15:47:56.670848 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 27 15:47:56.670855 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 27 15:47:56.670861 kernel: audit: initializing netlink subsys (disabled) Oct 27 15:47:56.670868 kernel: audit: type=2000 audit(0.029:1): state=initialized audit_enabled=0 res=1 Oct 27 15:47:56.670874 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 27 15:47:56.670880 kernel: cpuidle: using governor menu Oct 27 15:47:56.670886 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 27 15:47:56.670892 kernel: ASID allocator initialised with 32768 entries Oct 27 15:47:56.670898 kernel: ACPI: bus type PCI registered Oct 27 15:47:56.670904 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 27 15:47:56.670911 kernel: Serial: AMBA PL011 UART driver Oct 27 15:47:56.670917 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 27 15:47:56.670923 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 27 15:47:56.670930 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 27 15:47:56.670936 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 27 15:47:56.670942 kernel: cryptd: max_cpu_qlen set to 1000 Oct 27 15:47:56.670948 kernel: ACPI: Added _OSI(Module Device) Oct 27 15:47:56.670954 kernel: ACPI: Added _OSI(Processor Device) Oct 27 15:47:56.670960 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 27 15:47:56.670967 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 27 15:47:56.670973 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 27 15:47:56.670979 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 27 15:47:56.670985 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 27 15:47:56.670991 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 27 15:47:56.670997 kernel: ACPI: Interpreter enabled Oct 27 15:47:56.671003 kernel: ACPI: Using GIC for interrupt routing Oct 27 15:47:56.671009 kernel: ACPI: MCFG table detected, 1 entries Oct 27 15:47:56.671015 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 27 15:47:56.671021 kernel: printk: console [ttyAMA0] enabled Oct 27 15:47:56.671028 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 27 15:47:56.671141 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 27 15:47:56.671198 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 27 15:47:56.671250 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 27 15:47:56.671301 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 27 15:47:56.671352 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 27 15:47:56.671359 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 27 15:47:56.671368 kernel: PCI host bridge to bus 0000:00 Oct 27 15:47:56.671426 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 27 15:47:56.671480 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 27 15:47:56.671530 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 27 15:47:56.671582 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 27 15:47:56.671646 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 27 15:47:56.671714 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 27 15:47:56.671779 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 27 15:47:56.671843 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 27 15:47:56.671902 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 27 15:47:56.671963 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 27 15:47:56.672029 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 27 15:47:56.672089 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 27 15:47:56.672150 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 27 15:47:56.672200 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 27 15:47:56.672248 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 27 15:47:56.672257 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 27 15:47:56.672263 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 27 15:47:56.672269 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 27 15:47:56.672275 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 27 15:47:56.672282 kernel: vgaarb: loaded Oct 27 15:47:56.672288 kernel: Registered efivars operations Oct 27 15:47:56.672294 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 27 15:47:56.672302 kernel: VFS: Disk quotas dquot_6.6.0 Oct 27 15:47:56.672308 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 27 15:47:56.672314 kernel: pnp: PnP ACPI init Oct 27 15:47:56.672376 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 27 15:47:56.672426 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 27 15:47:56.672434 kernel: pnp: PnP ACPI: found 1 devices Oct 27 15:47:56.672440 kernel: NET: Registered protocol family 2 Oct 27 15:47:56.672447 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 27 15:47:56.672454 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 27 15:47:56.672461 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 27 15:47:56.672467 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 27 15:47:56.672473 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 27 15:47:56.672479 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 15:47:56.672486 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 15:47:56.672492 kernel: NET: Registered protocol family 1 Oct 27 15:47:56.672498 kernel: PCI: CLS 0 bytes, default 64 Oct 27 15:47:56.672505 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 27 15:47:56.672512 kernel: kvm [1]: HYP mode not available Oct 27 15:47:56.672518 kernel: Initialise system trusted keyrings Oct 27 15:47:56.672524 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 27 15:47:56.672530 kernel: Key type asymmetric registered Oct 27 15:47:56.672540 kernel: Asymmetric key parser 'x509' registered Oct 27 15:47:56.672550 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 27 15:47:56.672557 kernel: io scheduler mq-deadline registered Oct 27 15:47:56.672563 kernel: io scheduler kyber registered Oct 27 15:47:56.672569 kernel: io scheduler bfq registered Oct 27 15:47:56.672576 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 27 15:47:56.672582 kernel: ACPI: Power Button [PWRB] Oct 27 15:47:56.672589 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 27 15:47:56.672643 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 27 15:47:56.672652 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 27 15:47:56.672658 kernel: msm_serial: driver initialized Oct 27 15:47:56.672668 kernel: random: fast init done Oct 27 15:47:56.672674 kernel: random: crng init done Oct 27 15:47:56.672680 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 27 15:47:56.672687 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 27 15:47:56.672700 kernel: thunder_xcv, ver 1.0 Oct 27 15:47:56.672706 kernel: thunder_bgx, ver 1.0 Oct 27 15:47:56.672712 kernel: nicpf, ver 1.0 Oct 27 15:47:56.672718 kernel: nicvf, ver 1.0 Oct 27 15:47:56.672785 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 27 15:47:56.672837 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-27T15:47:56 UTC (1761580076) Oct 27 15:47:56.672845 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 27 15:47:56.672851 kernel: NET: Registered protocol family 10 Oct 27 15:47:56.672859 kernel: Segment Routing with IPv6 Oct 27 15:47:56.672865 kernel: NET: Registered protocol family 17 Oct 27 15:47:56.672871 kernel: Key type dns_resolver registered Oct 27 15:47:56.672878 kernel: registered taskstats version 1 Oct 27 15:47:56.672886 kernel: Loading compiled-in X.509 certificates Oct 27 15:47:56.672894 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 27 15:47:56.672903 kernel: Key type ._fscrypt registered Oct 27 15:47:56.672909 kernel: Key type .fscrypt registered Oct 27 15:47:56.672915 kernel: Key type fscrypt-provisioning registered Oct 27 15:47:56.672922 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 27 15:47:56.672929 kernel: ima: Allocated hash algorithm: sha1 Oct 27 15:47:56.672935 kernel: ima: No architecture policies found Oct 27 15:47:56.672941 kernel: Freeing unused kernel memory: 34240K Oct 27 15:47:56.672947 kernel: Run /init as init process Oct 27 15:47:56.672953 kernel: with arguments: Oct 27 15:47:56.672959 kernel: /init Oct 27 15:47:56.672965 kernel: with environment: Oct 27 15:47:56.672971 kernel: HOME=/ Oct 27 15:47:56.672977 kernel: TERM=linux Oct 27 15:47:56.672984 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 27 15:47:56.672992 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 27 15:47:56.673000 systemd[1]: Detected virtualization kvm. Oct 27 15:47:56.673006 systemd[1]: Detected architecture arm64. Oct 27 15:47:56.673013 systemd[1]: Running in initial RAM disk. Oct 27 15:47:56.673019 systemd[1]: No hostname configured, using default hostname. Oct 27 15:47:56.673025 systemd[1]: Hostname set to . Oct 27 15:47:56.673033 systemd[1]: Initializing machine ID from VM UUID. Oct 27 15:47:56.673040 systemd[1]: Queued start job for default target Initrd Default Target. Oct 27 15:47:56.673046 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 27 15:47:56.673053 systemd[1]: Reached target Local Encrypted Volumes. Oct 27 15:47:56.673059 systemd[1]: Reached target Path Units. Oct 27 15:47:56.673066 systemd[1]: Reached target Slice Units. Oct 27 15:47:56.673072 systemd[1]: Reached target Swaps. Oct 27 15:47:56.673078 systemd[1]: Reached target Timer Units. Oct 27 15:47:56.673086 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 27 15:47:56.673093 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 27 15:47:56.673141 systemd[1]: Listening on Journal Audit Socket. Oct 27 15:47:56.673148 systemd[1]: Listening on Journal Socket (/dev/log). Oct 27 15:47:56.673157 systemd[1]: Listening on Journal Socket. Oct 27 15:47:56.673172 systemd[1]: Listening on udev Control Socket. Oct 27 15:47:56.673179 systemd[1]: Listening on udev Kernel Socket. Oct 27 15:47:56.673185 systemd[1]: Reached target Socket Units. Oct 27 15:47:56.673192 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 27 15:47:56.673200 systemd[1]: Starting Create List of Static Device Nodes... Oct 27 15:47:56.673207 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 27 15:47:56.673213 systemd[1]: Starting Journal Service... Oct 27 15:47:56.673220 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 27 15:47:56.673226 systemd[1]: Starting Apply Kernel Variables... Oct 27 15:47:56.673233 systemd[1]: Starting Setup Virtual Console... Oct 27 15:47:56.673239 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 27 15:47:56.673246 systemd[1]: Finished Create List of Static Device Nodes. Oct 27 15:47:56.673252 kernel: SCSI subsystem initialized Oct 27 15:47:56.673258 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 27 15:47:56.673266 kernel: Loading iSCSI transport class v2.0-870. Oct 27 15:47:56.673273 systemd[1]: Finished Setup Virtual Console. Oct 27 15:47:56.673282 systemd-journald[270]: Journal started Oct 27 15:47:56.673874 systemd-journald[270]: Runtime Journal (/run/log/journal/c2bbe02a57c64a59b4c44d8497bb70b1) is 6.0M, max 48.5M, 42.4M free. Oct 27 15:47:56.674119 systemd[1]: Started Journal Service. Oct 27 15:47:56.675529 systemd[1]: Finished Apply Kernel Variables. Oct 27 15:47:56.676874 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 27 15:47:56.677947 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 27 15:47:56.688405 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 27 15:47:56.689942 systemd[1]: Starting dracut cmdline hook... Oct 27 15:47:56.698520 dracut-cmdline[292]: dracut-dracut-053 Oct 27 15:47:56.700817 dracut-cmdline[292]: 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 27 15:47:56.757127 kernel: iscsi: registered transport (tcp) Oct 27 15:47:56.768115 kernel: iscsi: registered transport (qla4xxx) Oct 27 15:47:56.768138 kernel: QLogic iSCSI HBA Driver Oct 27 15:47:56.777969 systemd[1]: Finished dracut cmdline hook. Oct 27 15:47:56.779500 systemd[1]: Starting dracut pre-udev hook... Oct 27 15:47:56.780644 systemd[1]: Starting Open-iSCSI... Oct 27 15:47:56.782975 iscsid[378]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 27 15:47:56.782975 iscsid[378]: 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 27 15:47:56.782975 iscsid[378]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 27 15:47:56.782975 iscsid[378]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 27 15:47:56.782975 iscsid[378]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 27 15:47:56.782975 iscsid[378]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 27 15:47:56.786436 systemd[1]: Started Open-iSCSI. Oct 27 15:47:56.795392 kernel: device-mapper: uevent: version 1.0.3 Oct 27 15:47:56.795412 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 27 15:47:56.829124 kernel: raid6: neonx8 gen() 13725 MB/s Oct 27 15:47:56.846118 kernel: raid6: neonx8 xor() 10702 MB/s Oct 27 15:47:56.863111 kernel: raid6: neonx4 gen() 13549 MB/s Oct 27 15:47:56.880112 kernel: raid6: neonx4 xor() 11516 MB/s Oct 27 15:47:56.897110 kernel: raid6: neonx2 gen() 12953 MB/s Oct 27 15:47:56.914110 kernel: raid6: neonx2 xor() 9840 MB/s Oct 27 15:47:56.931109 kernel: raid6: neonx1 gen() 10863 MB/s Oct 27 15:47:56.948111 kernel: raid6: neonx1 xor() 8786 MB/s Oct 27 15:47:56.965113 kernel: raid6: int64x8 gen() 6788 MB/s Oct 27 15:47:56.982116 kernel: raid6: int64x8 xor() 3774 MB/s Oct 27 15:47:56.999113 kernel: raid6: int64x4 gen() 6877 MB/s Oct 27 15:47:57.016113 kernel: raid6: int64x4 xor() 3826 MB/s Oct 27 15:47:57.033112 kernel: raid6: int64x2 gen() 6162 MB/s Oct 27 15:47:57.050113 kernel: raid6: int64x2 xor() 3303 MB/s Oct 27 15:47:57.067114 kernel: raid6: int64x1 gen() 4993 MB/s Oct 27 15:47:57.084685 kernel: raid6: int64x1 xor() 2584 MB/s Oct 27 15:47:57.084714 kernel: raid6: using algorithm neonx8 gen() 13725 MB/s Oct 27 15:47:57.084732 kernel: raid6: .... xor() 10702 MB/s, rmw enabled Oct 27 15:47:57.084748 kernel: raid6: using neon recovery algorithm Oct 27 15:47:57.095523 kernel: xor: measuring software checksum speed Oct 27 15:47:57.095546 kernel: 8regs : 17152 MB/sec Oct 27 15:47:57.096488 kernel: 32regs : 20755 MB/sec Oct 27 15:47:57.097303 kernel: arm64_neon : 28643 MB/sec Oct 27 15:47:57.097316 kernel: xor: using function: arm64_neon (28643 MB/sec) Oct 27 15:47:57.142120 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 27 15:47:57.151981 systemd[1]: Finished dracut pre-udev hook. Oct 27 15:47:57.153000 audit: BPF prog-id=6 op=LOAD Oct 27 15:47:57.153000 audit: BPF prog-id=7 op=LOAD Oct 27 15:47:57.154795 kernel: audit: type=1334 audit(1761580077.153:2): prog-id=6 op=LOAD Oct 27 15:47:57.154814 kernel: audit: type=1334 audit(1761580077.153:3): prog-id=7 op=LOAD Oct 27 15:47:57.154823 kernel: audit: type=1334 audit(1761580077.154:4): prog-id=8 op=LOAD Oct 27 15:47:57.154000 audit: BPF prog-id=8 op=LOAD Oct 27 15:47:57.155165 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 27 15:47:57.166971 systemd-udevd[410]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 27 15:47:57.168688 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 27 15:47:57.171231 systemd[1]: Starting dracut pre-trigger hook... Oct 27 15:47:57.182376 dracut-pre-trigger[420]: rd.md=0: removing MD RAID activation Oct 27 15:47:57.203991 systemd[1]: Finished dracut pre-trigger hook. Oct 27 15:47:57.205387 systemd[1]: Starting Coldplug All udev Devices... Oct 27 15:47:57.214804 systemd-udevd[410]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 27 15:47:57.234177 systemd[1]: Finished Coldplug All udev Devices. Oct 27 15:47:57.235571 systemd[1]: Starting dracut initqueue hook... Oct 27 15:47:57.256164 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 27 15:47:57.256292 kernel: vda: detected capacity change from 0 to 4756340736 Oct 27 15:47:57.258733 systemd-udevd[467]: Using default interface naming scheme 'v249'. Oct 27 15:47:57.266129 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 15:47:57.287951 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 27 15:47:57.290013 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (458) Oct 27 15:47:57.288979 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 27 15:47:57.295922 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 27 15:47:57.300066 systemd[1]: Reached target Initrd Root Device. Oct 27 15:47:57.301511 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 27 15:47:57.302648 systemd[1]: Finished dracut initqueue hook. Oct 27 15:47:57.304060 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 27 15:47:57.304183 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 27 15:47:57.305344 systemd[1]: Reached target Preparation for Local File Systems. Oct 27 15:47:57.306790 systemd[1]: Reached target Preparation for Remote File Systems. Oct 27 15:47:57.308366 systemd[1]: Reached target Remote Encrypted Volumes. Oct 27 15:47:57.309801 systemd[1]: Reached target Remote File Systems. Oct 27 15:47:57.311899 systemd[1]: Starting dracut pre-mount hook... Oct 27 15:47:57.313752 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 27 15:47:57.320193 systemd[1]: Finished dracut pre-mount hook. Oct 27 15:47:57.321638 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 27 15:47:57.326236 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 27 15:47:57.332810 systemd-fsck[505]: ROOT: clean, 640/553792 files, 39961/553472 blocks Oct 27 15:47:57.336164 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 27 15:47:57.338068 systemd[1]: Mounting /sysroot... Oct 27 15:47:57.350153 systemd[1]: Found device /dev/mapper/usr. Oct 27 15:47:57.352261 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 27 15:47:57.353284 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 27 15:47:57.362323 systemd-fsck[516]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 27 15:47:57.362323 systemd-fsck[516]: You must have r/w access to the filesystem or be root Oct 27 15:47:57.365701 systemd-fsck[514]: fsck failed with exit status 8. Oct 27 15:47:57.365708 systemd-fsck[514]: Ignoring error. Oct 27 15:47:57.366369 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 27 15:47:57.368666 systemd[1]: Mounting /sysusr/usr... Oct 27 15:47:57.379679 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 27 15:47:57.379716 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 27 15:47:57.380161 systemd[1]: Mounted /sysroot. Oct 27 15:47:57.380786 systemd[1]: Reached target Initrd Root File System. Oct 27 15:47:57.382436 systemd[1]: Starting Reload Configuration from the Real Root... Oct 27 15:47:57.386782 systemd[1]: Reloading. Oct 27 15:47:57.388000 audit: BPF prog-id=3 op=UNLOAD Oct 27 15:47:57.389116 kernel: audit: type=1334 audit(1761580077.388:5): prog-id=3 op=UNLOAD Oct 27 15:47:57.389000 audit: BPF prog-id=6 op=UNLOAD Oct 27 15:47:57.391121 kernel: audit: type=1334 audit(1761580077.389:6): prog-id=6 op=UNLOAD Oct 27 15:47:57.394125 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 27 15:47:57.465000 audit: BPF prog-id=9 op=LOAD Oct 27 15:47:57.465000 audit: BPF prog-id=10 op=LOAD Oct 27 15:47:57.466843 kernel: audit: type=1334 audit(1761580077.465:7): prog-id=9 op=LOAD Oct 27 15:47:57.466867 kernel: audit: type=1334 audit(1761580077.465:8): prog-id=10 op=LOAD Oct 27 15:47:57.466876 kernel: audit: type=1334 audit(1761580077.466:9): prog-id=11 op=LOAD Oct 27 15:47:57.466000 audit: BPF prog-id=11 op=LOAD Oct 27 15:47:57.467585 kernel: audit: type=1334 audit(1761580077.466:10): prog-id=4 op=UNLOAD Oct 27 15:47:57.466000 audit: BPF prog-id=4 op=UNLOAD Oct 27 15:47:57.466000 audit: BPF prog-id=5 op=UNLOAD Oct 27 15:47:57.467000 audit: BPF prog-id=12 op=LOAD Oct 27 15:47:57.468000 audit: BPF prog-id=13 op=LOAD Oct 27 15:47:57.468000 audit: BPF prog-id=14 op=LOAD Oct 27 15:47:57.468000 audit: BPF prog-id=7 op=UNLOAD Oct 27 15:47:57.468000 audit: BPF prog-id=8 op=UNLOAD Oct 27 15:47:57.469727 systemd[1]: Mounted /sysusr/usr. Oct 27 15:47:57.471785 systemd[1]: Reached target Local File Systems. Oct 27 15:47:57.472556 systemd[1]: Reached target System Initialization. Oct 27 15:47:57.473794 systemd[1]: Reached target Basic System. Oct 27 15:47:57.476185 systemd[1]: Mounting /sysroot/usr... Oct 27 15:47:57.478610 systemd[1]: Mounted /sysroot/usr. Oct 27 15:47:57.487414 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 27 15:47:57.487534 systemd[1]: Finished Reload Configuration from the Real Root. Oct 27 15:47:57.489190 systemd[1]: Reached target Initrd File Systems. Oct 27 15:47:57.490611 systemd[1]: Reached target Initrd Default Target. Oct 27 15:47:57.491876 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 27 15:47:57.492559 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 27 15:47:57.502581 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 27 15:47:57.504088 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 27 15:47:57.509909 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 27 15:47:57.510835 systemd[1]: Stopped target Timer Units. Oct 27 15:47:57.512165 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 27 15:47:57.512281 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 27 15:47:57.513497 systemd[1]: Stopped target Initrd Default Target. Oct 27 15:47:57.514961 systemd[1]: Stopped target Basic System. Oct 27 15:47:57.516204 systemd[1]: Stopped target Initrd Root Device. Oct 27 15:47:57.517543 systemd[1]: Stopped target Path Units. Oct 27 15:47:57.518699 systemd[1]: Stopped target Remote File Systems. Oct 27 15:47:57.519901 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 27 15:47:57.521260 systemd[1]: Stopped target Slice Units. Oct 27 15:47:57.522451 systemd[1]: Stopped target Socket Units. Oct 27 15:47:57.523804 systemd[1]: Stopped target System Initialization. Oct 27 15:47:57.524977 systemd[1]: Stopped target Local File Systems. Oct 27 15:47:57.526195 systemd[1]: Stopped target Preparation for Local File Systems. Oct 27 15:47:57.527626 systemd[1]: Stopped target Swaps. Oct 27 15:47:57.528750 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 27 15:47:57.528861 systemd[1]: Stopped dracut pre-mount hook. Oct 27 15:47:57.530230 systemd[1]: Stopped target Local Encrypted Volumes. Oct 27 15:47:57.531428 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 27 15:47:57.535164 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 27 15:47:57.536157 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 27 15:47:57.536264 systemd[1]: Stopped dracut initqueue hook. Oct 27 15:47:57.541017 iscsid[378]: iscsid shutting down. Oct 27 15:47:57.537838 systemd[1]: Stopping Open-iSCSI... Oct 27 15:47:57.540487 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 27 15:47:57.540603 systemd[1]: Stopped Apply Kernel Variables. Oct 27 15:47:57.541765 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 27 15:47:57.541849 systemd[1]: Stopped Coldplug All udev Devices. Oct 27 15:47:57.542869 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 27 15:47:57.542949 systemd[1]: Stopped dracut pre-trigger hook. Oct 27 15:47:57.544114 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 27 15:47:57.548398 systemd[1]: iscsid.service: Deactivated successfully. Oct 27 15:47:57.548505 systemd[1]: Stopped Open-iSCSI. Oct 27 15:47:57.549738 systemd[1]: iscsid.socket: Deactivated successfully. Oct 27 15:47:57.549783 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 27 15:47:57.550701 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 27 15:47:57.552963 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 27 15:47:57.553082 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 27 15:47:57.554226 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 27 15:47:57.554319 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 27 15:47:57.556444 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 27 15:47:57.567000 audit: BPF prog-id=12 op=UNLOAD Oct 27 15:47:57.556495 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 27 15:47:57.557341 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 27 15:47:57.557460 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 27 15:47:57.558797 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 27 15:47:57.558831 systemd[1]: Closed udev Control Socket. Oct 27 15:47:57.559868 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 27 15:47:57.559899 systemd[1]: Closed udev Kernel Socket. Oct 27 15:47:57.560983 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 27 15:47:57.561023 systemd[1]: Stopped dracut pre-udev hook. Oct 27 15:47:57.562361 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 27 15:47:57.562398 systemd[1]: Stopped dracut cmdline hook. Oct 27 15:47:57.563650 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 27 15:47:57.563687 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 27 15:47:57.582000 audit: BPF prog-id=9 op=UNLOAD Oct 27 15:47:57.565680 systemd[1]: Starting Cleanup udev Database... Oct 27 15:47:57.566535 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 27 15:47:57.567906 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 27 15:47:57.567960 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 27 15:47:57.569361 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 27 15:47:57.569398 systemd[1]: Stopped Create List of Static Device Nodes. Oct 27 15:47:57.570695 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 27 15:47:57.570732 systemd[1]: Stopped Setup Virtual Console. Oct 27 15:47:57.572230 systemd[1]: rngd.service: Deactivated successfully. Oct 27 15:47:57.572337 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 27 15:47:57.573933 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 27 15:47:57.574032 systemd[1]: Finished Cleanup udev Database. Oct 27 15:47:57.575404 systemd[1]: Reached target Switch Root. Oct 27 15:47:57.577026 systemd[1]: Starting Switch Root... Oct 27 15:47:57.582662 systemd[1]: Switching root. Oct 27 15:47:57.597409 systemd-journald[270]: Journal stopped Oct 27 15:47:59.620494 systemd-journald[270]: Received SIGTERM from PID 1 (systemd). Oct 27 15:47:59.620539 kernel: SELinux: policy capability network_peer_controls=1 Oct 27 15:47:59.620551 kernel: SELinux: policy capability open_perms=1 Oct 27 15:47:59.620563 kernel: SELinux: policy capability extended_socket_class=1 Oct 27 15:47:59.620574 kernel: SELinux: policy capability always_check_network=0 Oct 27 15:47:59.620583 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 27 15:47:59.620592 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 27 15:47:59.620601 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 27 15:47:59.620611 systemd[1]: Successfully loaded SELinux policy in 30.574ms. Oct 27 15:47:59.620624 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.265ms. Oct 27 15:47:59.620635 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 27 15:47:59.620645 systemd[1]: Detected virtualization kvm. Oct 27 15:47:59.620655 systemd[1]: Detected architecture arm64. Oct 27 15:47:59.620667 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 27 15:47:59.620677 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 27 15:47:59.620697 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 27 15:47:59.620709 systemd[1]: Stopped Switch Root. Oct 27 15:47:59.620718 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 27 15:47:59.620728 systemd[1]: Created slice Slice /system/addon-config. Oct 27 15:47:59.620738 systemd[1]: Created slice Slice /system/addon-run. Oct 27 15:47:59.620749 systemd[1]: Created slice Slice /system/getty. Oct 27 15:47:59.620759 systemd[1]: Created slice Slice /system/modprobe. Oct 27 15:47:59.620769 systemd[1]: Created slice Slice /system/serial-getty. Oct 27 15:47:59.620778 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 27 15:47:59.620788 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 27 15:47:59.620798 systemd[1]: Created slice User and Session Slice. Oct 27 15:47:59.620807 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 27 15:47:59.620817 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 27 15:47:59.620827 systemd[1]: Set up automount Boot partition Automount Point. Oct 27 15:47:59.620841 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 27 15:47:59.620853 systemd[1]: Stopped target Switch Root. Oct 27 15:47:59.620862 systemd[1]: Stopped target Initrd File Systems. Oct 27 15:47:59.620872 systemd[1]: Stopped target Initrd Root File System. Oct 27 15:47:59.620881 systemd[1]: Reached target Remote Encrypted Volumes. Oct 27 15:47:59.620891 systemd[1]: Reached target Remote File Systems. Oct 27 15:47:59.620900 systemd[1]: Reached target Slice Units. Oct 27 15:47:59.620910 systemd[1]: Reached target Swaps. Oct 27 15:47:59.620920 systemd[1]: Reached target Verify torcx succeeded. Oct 27 15:47:59.620929 systemd[1]: Reached target Local Verity Protected Volumes. Oct 27 15:47:59.620939 systemd[1]: Listening on Process Core Dump Socket. Oct 27 15:47:59.620949 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 27 15:47:59.620961 systemd[1]: Listening on Network Service Netlink Socket. Oct 27 15:47:59.620973 systemd[1]: Listening on udev Control Socket. Oct 27 15:47:59.620983 systemd[1]: Listening on udev Kernel Socket. Oct 27 15:47:59.620996 systemd[1]: Mounting Huge Pages File System... Oct 27 15:47:59.621006 systemd[1]: Mounting POSIX Message Queue File System... Oct 27 15:47:59.621016 systemd[1]: Mounting External Media Directory... Oct 27 15:47:59.621026 systemd[1]: Mounting Kernel Debug File System... Oct 27 15:47:59.621036 systemd[1]: Mounting Kernel Trace File System... Oct 27 15:47:59.621047 systemd[1]: Mounting Temporary Directory /tmp... Oct 27 15:47:59.621057 systemd[1]: Starting Create missing system files... Oct 27 15:47:59.621066 systemd[1]: Starting Create List of Static Device Nodes... Oct 27 15:47:59.621076 systemd[1]: Starting Load Kernel Module configfs... Oct 27 15:47:59.621085 systemd[1]: Starting Load Kernel Module drm... Oct 27 15:47:59.621095 systemd[1]: Starting Load Kernel Module fuse... Oct 27 15:47:59.621113 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 27 15:47:59.621122 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 27 15:47:59.621132 systemd[1]: Stopped File System Check on Root Device. Oct 27 15:47:59.621142 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 27 15:47:59.621152 systemd[1]: Stopped systemd-fsck-usr.service. Oct 27 15:47:59.621161 systemd[1]: Stopped Journal Service. Oct 27 15:47:59.621170 kernel: fuse: init (API version 7.32) Oct 27 15:47:59.621179 systemd[1]: Starting Journal Service... Oct 27 15:47:59.621189 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 27 15:47:59.621199 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 27 15:47:59.621208 systemd[1]: Starting Apply Kernel Variables... Oct 27 15:47:59.621218 systemd[1]: Starting Coldplug All udev Devices... Oct 27 15:47:59.621228 systemd[1]: verity-setup.service: Deactivated successfully. Oct 27 15:47:59.621240 systemd[1]: Stopped verity-setup.service. Oct 27 15:47:59.621250 systemd-journald[693]: Journal started Oct 27 15:47:59.621287 systemd-journald[693]: Runtime Journal (/run/log/journal/c2bbe02a57c64a59b4c44d8497bb70b1) is 6.0M, max 48.5M, 42.4M free. Oct 27 15:47:57.670000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 27 15:47:57.695000 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 27 15:47:57.695000 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 27 15:47:57.695000 audit: BPF prog-id=15 op=LOAD Oct 27 15:47:57.695000 audit: BPF prog-id=15 op=UNLOAD Oct 27 15:47:57.695000 audit: BPF prog-id=16 op=LOAD Oct 27 15:47:57.695000 audit: BPF prog-id=16 op=UNLOAD Oct 27 15:47:57.732000 audit[645]: AVC avc: denied { associate } for pid=645 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 27 15:47:59.517000 audit: BPF prog-id=17 op=LOAD Oct 27 15:47:59.518000 audit: BPF prog-id=18 op=LOAD Oct 27 15:47:59.518000 audit: BPF prog-id=19 op=LOAD Oct 27 15:47:59.518000 audit: BPF prog-id=10 op=UNLOAD Oct 27 15:47:59.518000 audit: BPF prog-id=11 op=UNLOAD Oct 27 15:47:59.526000 audit: BPF prog-id=17 op=UNLOAD Oct 27 15:47:59.608000 audit: BPF prog-id=20 op=LOAD Oct 27 15:47:59.608000 audit: BPF prog-id=21 op=LOAD Oct 27 15:47:59.608000 audit: BPF prog-id=22 op=LOAD Oct 27 15:47:59.608000 audit: BPF prog-id=18 op=UNLOAD Oct 27 15:47:59.608000 audit: BPF prog-id=19 op=UNLOAD Oct 27 15:47:59.619000 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 27 15:47:59.509074 systemd[1]: Queued start job for default target Multi-User System. Oct 27 15:47:57.730087 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 27 15:47:59.518905 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 27 15:47:57.730395 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 27 15:47:57.730415 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 27 15:47:57.730739 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 27 15:47:57.730753 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 27 15:47:57.730789 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 27 15:47:57.730802 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 27 15:47:57.731179 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 27 15:47:57.731219 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 27 15:47:57.731235 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 27 15:47:57.731592 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 27 15:47:57.731623 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 27 15:47:57.731643 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 27 15:47:57.731659 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 27 15:47:57.731769 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 27 15:47:57.731787 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:57Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 27 15:47:59.424867 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:59Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 27 15:47:59.425146 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:59Z" 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 27 15:47:59.425242 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:59Z" 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 27 15:47:59.425366 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:59Z" 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 27 15:47:59.425466 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:59Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 27 15:47:59.425529 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-27T15:47:59Z" 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 27 15:47:59.623651 systemd[1]: Started Journal Service. Oct 27 15:47:59.624091 systemd[1]: Mounted Huge Pages File System. Oct 27 15:47:59.624900 systemd[1]: Mounted POSIX Message Queue File System. Oct 27 15:47:59.625813 systemd[1]: Mounted External Media Directory. Oct 27 15:47:59.626660 systemd[1]: Mounted Kernel Debug File System. Oct 27 15:47:59.627483 systemd[1]: Mounted Kernel Trace File System. Oct 27 15:47:59.628301 systemd[1]: Mounted Temporary Directory /tmp. Oct 27 15:47:59.629359 systemd[1]: Finished Create missing system files. Oct 27 15:47:59.630430 systemd[1]: Finished Create List of Static Device Nodes. Oct 27 15:47:59.631491 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 27 15:47:59.631659 systemd[1]: Finished Load Kernel Module configfs. Oct 27 15:47:59.632607 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 27 15:47:59.632790 systemd[1]: Finished Load Kernel Module drm. Oct 27 15:47:59.633714 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 27 15:47:59.633885 systemd[1]: Finished Load Kernel Module fuse. Oct 27 15:47:59.634930 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 27 15:47:59.643508 systemd[1]: Finished Apply Kernel Variables. Oct 27 15:47:59.646172 systemd[1]: Mounting FUSE Control File System... Oct 27 15:47:59.647796 systemd[1]: Mounting Kernel Configuration File System... Oct 27 15:47:59.648599 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 27 15:47:59.649587 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 27 15:47:59.650496 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 27 15:47:59.651409 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 27 15:47:59.652354 systemd[1]: Starting Load/Save Random Seed... Oct 27 15:47:59.653212 systemd[1]: Condition check resulted in Create System Users being skipped. Oct 27 15:47:59.654122 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 27 15:47:59.656955 systemd-journald[693]: Time spent on flushing to /var/log/journal/c2bbe02a57c64a59b4c44d8497bb70b1 is 12.536ms for 656 entries. Oct 27 15:47:59.656955 systemd-journald[693]: System Journal (/var/log/journal/c2bbe02a57c64a59b4c44d8497bb70b1) is 8.0M, max 203.0M, 195.0M free. Oct 27 15:47:59.679000 audit: BPF prog-id=23 op=LOAD Oct 27 15:47:59.679000 audit: BPF prog-id=24 op=LOAD Oct 27 15:47:59.679000 audit: BPF prog-id=25 op=LOAD Oct 27 15:47:59.679000 audit: BPF prog-id=13 op=UNLOAD Oct 27 15:47:59.679000 audit: BPF prog-id=14 op=UNLOAD Oct 27 15:47:59.655719 systemd[1]: Mounted FUSE Control File System. Oct 27 15:47:59.656602 systemd[1]: Mounted Kernel Configuration File System. Oct 27 15:47:59.663336 systemd[1]: Finished Load/Save Random Seed. Oct 27 15:47:59.664819 systemd[1]: Finished Coldplug All udev Devices. Oct 27 15:47:59.666022 systemd[1]: Condition check resulted in First Boot Complete being skipped. Oct 27 15:47:59.676064 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 27 15:47:59.677536 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 27 15:47:59.679765 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 27 15:47:59.681679 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 27 15:47:59.704773 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 27 15:47:59.712000 audit: BPF prog-id=26 op=LOAD Oct 27 15:47:59.713226 systemd[1]: Starting Network Configuration... Oct 27 15:47:59.736463 systemd-udevd[713]: Using default interface naming scheme 'v249'. Oct 27 15:47:59.744696 systemd-networkd[725]: lo: Link UP Oct 27 15:47:59.744705 systemd-networkd[725]: lo: Gained carrier Oct 27 15:47:59.745266 systemd-networkd[725]: Enumeration completed Oct 27 15:47:59.745367 systemd[1]: Started Network Configuration. Oct 27 15:47:59.747233 systemd-networkd[725]: eth0: Link UP Oct 27 15:47:59.753162 systemd-networkd[725]: eth0: Gained carrier Oct 27 15:47:59.759268 systemd-networkd[725]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Oct 27 15:47:59.764502 udevadm[705]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 27 15:47:59.790242 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 27 15:47:59.798026 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 27 15:47:59.806916 lvm[733]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 27 15:47:59.833166 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 27 15:47:59.834108 systemd[1]: Reached target Local Encrypted Volumes. Oct 27 15:47:59.844090 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 27 15:47:59.847474 lvm[734]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 27 15:47:59.875175 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 27 15:47:59.876117 systemd[1]: Reached target Preparation for Local File Systems. Oct 27 15:47:59.876985 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 27 15:47:59.877009 systemd[1]: Reached target Containers. Oct 27 15:47:59.889356 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 27 15:47:59.901490 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 27 15:47:59.903612 systemd[1]: Mounting /usr/share/oem... Oct 27 15:47:59.909689 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 27 15:47:59.909816 kernel: BTRFS info (device vda6): has skinny extents Oct 27 15:47:59.911842 systemd[1]: Mounted /usr/share/oem. Oct 27 15:47:59.912590 systemd[1]: Reached target Local File Systems. Oct 27 15:47:59.913383 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Oct 27 15:47:59.913753 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 27 15:47:59.913788 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 27 15:47:59.913818 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 27 15:47:59.914756 systemd[1]: Starting Create Volatile Files and Directories... Oct 27 15:47:59.920705 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 27 15:47:59.921715 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 27 15:47:59.952031 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Oct 27 15:47:59.952045 systemd-tmpfiles[757]: Skipping /boot Oct 27 15:47:59.958063 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Oct 27 15:47:59.958080 systemd-tmpfiles[757]: Skipping /boot Oct 27 15:47:59.990396 systemd[1]: Finished Create Volatile Files and Directories. Oct 27 15:48:00.002126 systemd[1]: Starting Load Security Auditing Rules... Oct 27 15:48:00.003845 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 27 15:48:00.004885 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Oct 27 15:48:00.005000 audit: BPF prog-id=27 op=LOAD Oct 27 15:48:00.006376 systemd[1]: Starting Network Name Resolution... Oct 27 15:48:00.007000 audit: BPF prog-id=28 op=LOAD Oct 27 15:48:00.008461 systemd[1]: Starting Network Time Synchronization... Oct 27 15:48:00.009328 systemd[1]: Condition check resulted in Update is Completed being skipped. Oct 27 15:48:00.010354 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 27 15:48:00.011686 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 27 15:48:00.012898 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 27 15:48:00.016158 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 27 15:48:00.026000 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 27 15:48:00.027321 augenrules[779]: No rules Oct 27 15:48:00.028223 systemd[1]: Finished Load Security Auditing Rules. Oct 27 15:47:59.419333 systemd-timesyncd[767]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 27 15:47:59.419718 systemd-resolved[761]: Positive Trust Anchors: Oct 27 15:47:59.419724 systemd-resolved[761]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 27 15:47:59.419752 systemd-resolved[761]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 27 15:47:59.420503 systemd-resolved[761]: Defaulting to hostname 'linux'. Oct 27 15:47:59.425045 systemd[1]: Started Network Time Synchronization. Oct 27 15:47:59.426117 systemd[1]: Started Network Name Resolution. Oct 27 15:47:59.426940 systemd[1]: Reached target Network. Oct 27 15:47:59.427671 systemd[1]: Reached target Host and Network Name Lookups. Oct 27 15:47:59.428734 systemd[1]: Reached target System Initialization. Oct 27 15:47:59.431425 systemd[1]: Started Watch for update engine configuration changes. Oct 27 15:47:59.432494 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 27 15:47:59.433510 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 27 15:47:59.434450 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 27 15:47:59.434483 systemd[1]: Reached target Path Units. Oct 27 15:47:59.435155 systemd[1]: Reached target System Time Set. Oct 27 15:47:59.436106 systemd[1]: Started Daily Log Rotation. Oct 27 15:47:59.437040 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 27 15:47:59.437971 systemd[1]: Reached target Timer Units. Oct 27 15:47:59.439022 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 27 15:47:59.450559 systemd[1]: Starting Docker Socket for the API... Oct 27 15:47:59.453746 systemd[1]: Listening on OpenSSH Server Socket. Oct 27 15:47:59.455018 systemd[1]: Listening on Docker Socket for the API. Oct 27 15:47:59.455819 systemd[1]: Reached target Socket Units. Oct 27 15:47:59.456543 systemd[1]: Reached target Basic System. Oct 27 15:47:59.457247 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 27 15:47:59.457277 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 27 15:47:59.458293 systemd[1]: Started D-Bus System Message Bus. Oct 27 15:47:59.461803 systemd[1]: Starting Extend Filesystems... Oct 27 15:47:59.462590 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 27 15:47:59.463623 systemd[1]: Starting Generate /run/flatcar/motd... Oct 27 15:47:59.465425 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 27 15:47:59.467613 systemd[1]: Starting Generate sshd host keys... Oct 27 15:47:59.469025 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 27 15:47:59.469064 systemd[1]: Reached target Load system-provided cloud configs. Oct 27 15:47:59.471538 extend-filesystems[789]: Found vda Oct 27 15:47:59.472993 extend-filesystems[789]: Found vda1 Oct 27 15:47:59.472993 extend-filesystems[789]: Found vda2 Oct 27 15:47:59.472993 extend-filesystems[789]: Found vda3 Oct 27 15:47:59.472993 extend-filesystems[789]: Found usr Oct 27 15:47:59.472993 extend-filesystems[789]: Found vda4 Oct 27 15:47:59.472993 extend-filesystems[789]: Found vda6 Oct 27 15:47:59.472993 extend-filesystems[789]: Found vda7 Oct 27 15:47:59.472993 extend-filesystems[789]: Found vda9 Oct 27 15:47:59.472993 extend-filesystems[789]: Checking size of /dev/vda9 Oct 27 15:47:59.471783 systemd[1]: Starting User Login Management... Oct 27 15:47:59.474062 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 27 15:47:59.474679 systemd[1]: Starting Update Engine... Oct 27 15:47:59.475304 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 27 15:47:59.475333 systemd[1]: Reached target Load user-provided cloud configs. Oct 27 15:47:59.477346 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 27 15:47:59.477616 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 27 15:47:59.480376 systemd[1]: motdgen.service: Deactivated successfully. Oct 27 15:47:59.480619 systemd[1]: Finished Generate /run/flatcar/motd. Oct 27 15:47:59.491431 extend-filesystems[789]: Old size kept for /dev/vda9 Oct 27 15:47:59.491286 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 27 15:47:59.491517 systemd[1]: Finished Extend Filesystems. Oct 27 15:47:59.515965 systemd-logind[799]: Watching system buttons on /dev/input/event0 (Power Button) Oct 27 15:47:59.516374 systemd-logind[799]: New seat seat0. Oct 27 15:47:59.518190 systemd[1]: Started User Login Management. Oct 27 15:47:59.522638 update_engine[805]: I1027 15:47:59.522377 805 main.cc:89] Flatcar Update Engine starting Oct 27 15:47:59.523238 update_engine[805]: I1027 15:47:59.523195 805 payload_state.cc:360] Current Response Signature = Oct 27 15:47:59.523238 update_engine[805]: NumURLs = 1 Oct 27 15:47:59.523238 update_engine[805]: Url0 = http://10.0.0.2:34567/packages/update.gz Oct 27 15:47:59.523238 update_engine[805]: Payload Size = 435420691 Oct 27 15:47:59.523238 update_engine[805]: Payload Sha256 Hash = nLXXFElmOY/MZeJVU0GqyZXmGgOOWnMNMVrLO0s03g0= Oct 27 15:47:59.523238 update_engine[805]: Is Delta Payload = 0 Oct 27 15:47:59.523238 update_engine[805]: Max Failure Count Per Url = 10 Oct 27 15:47:59.523238 update_engine[805]: Disable Payload Backoff = 1 Oct 27 15:47:59.523399 update_engine[805]: I1027 15:47:59.523384 805 payload_state.cc:381] Payload Attempt Number = 0 Oct 27 15:47:59.523567 update_engine[805]: I1027 15:47:59.523541 805 payload_state.cc:404] Current URL Index = 0 Oct 27 15:47:59.523719 update_engine[805]: I1027 15:47:59.523707 805 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 27 15:47:59.523929 update_engine[805]: I1027 15:47:59.523857 805 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 27 15:47:59.525511 systemd[1]: Started Update Engine. Oct 27 15:47:59.525928 update_engine[805]: I1027 15:47:59.525694 805 update_check_scheduler.cc:74] Next update check in 10m48s Oct 27 15:47:59.532370 systemd[1]: Finished Generate sshd host keys. Oct 27 15:47:59.541956 systemd[1]: Starting Generate /run/issue... Oct 27 15:47:59.546502 systemd[1]: issuegen.service: Deactivated successfully. Oct 27 15:47:59.546725 systemd[1]: Finished Generate /run/issue. Oct 27 15:47:59.548749 systemd[1]: Starting Permit User Sessions... Oct 27 15:47:59.553151 systemd[1]: Finished Permit User Sessions. Oct 27 15:47:59.555132 systemd[1]: Started Getty on tty1. Oct 27 15:47:59.556912 systemd[1]: Started Serial Getty on ttyAMA0. Oct 27 15:47:59.557781 systemd[1]: Reached target Login Prompts. Oct 27 15:47:59.558612 systemd[1]: Reached target Multi-User System. Oct 27 15:47:59.560548 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 27 15:47:59.565511 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 27 15:47:59.565721 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 27 15:47:59.566661 systemd[1]: Startup finished in 697ms (kernel) + 1.082s (initrd) + 2.553s (userspace) = 4.332s. Oct 27 15:48:00.642203 systemd-networkd[725]: eth0: Gained IPv6LL Oct 27 15:48:09.356780 systemd[1]: Created slice Slice /system/sshd. Oct 27 15:48:09.357769 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40600). Oct 27 15:48:09.402768 sshd[831]: Accepted publickey for core from 10.0.0.1 port 40600 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:48:09.404790 sshd[831]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:48:09.422311 systemd[1]: Created slice User Slice of UID 500. Oct 27 15:48:09.423222 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 27 15:48:09.424566 systemd-logind[799]: New session 1 of user core. Oct 27 15:48:09.428921 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 27 15:48:09.429997 systemd[1]: Starting User Manager for UID 500... Oct 27 15:48:09.432172 systemd[834]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:48:09.477618 systemd[834]: Queued start job for default target Main User Target. Oct 27 15:48:09.477762 systemd[834]: Reached target Paths. Oct 27 15:48:09.477780 systemd[834]: Reached target Sockets. Oct 27 15:48:09.477790 systemd[834]: Reached target Timers. Oct 27 15:48:09.477800 systemd[834]: Reached target Basic System. Oct 27 15:48:09.477914 systemd[1]: Started User Manager for UID 500. Oct 27 15:48:09.478531 systemd[834]: Reached target Main User Target. Oct 27 15:48:09.478562 systemd[834]: Startup finished in 42ms. Oct 27 15:48:09.485028 systemd[1]: Started Session 1 of User core. Oct 27 15:48:09.544360 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40616). Oct 27 15:48:09.585461 sshd[843]: Accepted publickey for core from 10.0.0.1 port 40616 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:48:09.586771 sshd[843]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:48:09.589290 systemd-logind[799]: New session 2 of user core. Oct 27 15:48:09.599019 systemd[1]: Started Session 2 of User core. Oct 27 15:48:09.652256 sshd[843]: pam_unix(sshd:session): session closed for user core Oct 27 15:48:09.661455 systemd[1]: sshd@1-10.0.0.3:22-10.0.0.1:40616.service: Deactivated successfully. Oct 27 15:48:09.662053 systemd[1]: session-2.scope: Deactivated successfully. Oct 27 15:48:09.662659 systemd-logind[799]: Session 2 logged out. Waiting for processes to exit. Oct 27 15:48:09.663666 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40620). Oct 27 15:48:09.664349 systemd-logind[799]: Removed session 2. Oct 27 15:48:09.705187 sshd[849]: Accepted publickey for core from 10.0.0.1 port 40620 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:48:09.706237 sshd[849]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:48:09.709009 systemd-logind[799]: New session 3 of user core. Oct 27 15:48:09.718031 systemd[1]: Started Session 3 of User core. Oct 27 15:48:09.766456 sshd[849]: pam_unix(sshd:session): session closed for user core Oct 27 15:48:09.774781 systemd[1]: sshd@2-10.0.0.3:22-10.0.0.1:40620.service: Deactivated successfully. Oct 27 15:48:09.775345 systemd[1]: session-3.scope: Deactivated successfully. Oct 27 15:48:09.775965 systemd-logind[799]: Session 3 logged out. Waiting for processes to exit. Oct 27 15:48:09.776925 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40636). Oct 27 15:48:09.777750 systemd-logind[799]: Removed session 3. Oct 27 15:48:09.818042 sshd[855]: Accepted publickey for core from 10.0.0.1 port 40636 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:48:09.819021 sshd[855]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:48:09.821640 systemd-logind[799]: New session 4 of user core. Oct 27 15:48:09.832109 systemd[1]: Started Session 4 of User core. Oct 27 15:48:09.884239 sshd[855]: pam_unix(sshd:session): session closed for user core Oct 27 15:48:09.896388 systemd[1]: sshd@3-10.0.0.3:22-10.0.0.1:40636.service: Deactivated successfully. Oct 27 15:48:09.897036 systemd[1]: session-4.scope: Deactivated successfully. Oct 27 15:48:09.897697 systemd-logind[799]: Session 4 logged out. Waiting for processes to exit. Oct 27 15:48:09.898784 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40638). Oct 27 15:48:09.899490 systemd-logind[799]: Removed session 4. Oct 27 15:48:09.939832 sshd[861]: Accepted publickey for core from 10.0.0.1 port 40638 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:48:09.941632 sshd[861]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:48:09.944157 systemd-logind[799]: New session 5 of user core. Oct 27 15:48:09.954012 systemd[1]: Started Session 5 of User core. Oct 27 15:48:10.009509 sudo[864]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 27 15:48:10.009967 sudo[864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:48:10.018371 sudo[864]: pam_unix(sudo:session): session closed for user root Oct 27 15:48:10.020108 sshd[861]: pam_unix(sshd:session): session closed for user core Oct 27 15:48:10.042611 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40646). Oct 27 15:48:10.044651 dbus-daemon[788]: [system] Reloaded configuration Oct 27 15:48:10.047194 systemd[1]: sshd@4-10.0.0.3:22-10.0.0.1:40638.service: Deactivated successfully. Oct 27 15:48:10.047765 systemd[1]: session-5.scope: Deactivated successfully. Oct 27 15:48:10.048394 systemd-logind[799]: Session 5 logged out. Waiting for processes to exit. Oct 27 15:48:10.049146 systemd-logind[799]: Removed session 5. Oct 27 15:48:10.084261 sshd[867]: Accepted publickey for core from 10.0.0.1 port 40646 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:48:10.085374 sshd[867]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:48:10.087756 systemd-logind[799]: New session 6 of user core. Oct 27 15:48:10.103022 systemd[1]: Started Session 6 of User core. Oct 27 15:48:10.153638 sudo[872]: 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 27 15:48:10.153835 sudo[872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:48:10.156097 sudo[872]: pam_unix(sudo:session): session closed for user root Oct 27 15:48:10.159413 sudo[871]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 27 15:48:10.159618 sudo[871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:48:10.174761 systemd[1]: Stopping Load Security Auditing Rules... Oct 27 15:48:10.174000 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 27 15:48:10.175892 auditctl[875]: No rules Oct 27 15:48:10.176128 systemd[1]: audit-rules.service: Deactivated successfully. Oct 27 15:48:10.176350 systemd[1]: Stopped Load Security Auditing Rules. Oct 27 15:48:10.176454 kernel: kauditd_printk_skb: 37 callbacks suppressed Oct 27 15:48:10.176481 kernel: audit: type=1305 audit(1761580090.174:48): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 27 15:48:10.177565 systemd[1]: Starting Load Security Auditing Rules... Oct 27 15:48:10.191105 augenrules[892]: No rules Oct 27 15:48:10.191914 systemd[1]: Finished Load Security Auditing Rules. Oct 27 15:48:10.192911 sudo[871]: pam_unix(sudo:session): session closed for user root Oct 27 15:48:10.194058 sshd[867]: pam_unix(sshd:session): session closed for user core Oct 27 15:48:10.201459 systemd[1]: sshd@5-10.0.0.3:22-10.0.0.1:40646.service: Deactivated successfully. Oct 27 15:48:10.201973 systemd[1]: session-6.scope: Deactivated successfully. Oct 27 15:48:10.202515 systemd-logind[799]: Session 6 logged out. Waiting for processes to exit. Oct 27 15:48:10.203390 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40654). Oct 27 15:48:10.203968 systemd-logind[799]: Removed session 6. Oct 27 15:48:10.244586 sshd[898]: Accepted publickey for core from 10.0.0.1 port 40654 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:48:10.245476 sshd[898]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:48:10.247918 kernel: audit: type=1006 audit(1761580090.244:49): pid=898 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 27 15:48:10.248582 systemd-logind[799]: New session 7 of user core. Oct 27 15:48:10.263033 systemd[1]: Started Session 7 of User core. Oct 27 15:48:10.311726 sshd[898]: pam_unix(sshd:session): session closed for user core Oct 27 15:48:10.324343 systemd[1]: sshd@6-10.0.0.3:22-10.0.0.1:40654.service: Deactivated successfully. Oct 27 15:48:10.324844 systemd[1]: session-7.scope: Deactivated successfully. Oct 27 15:48:10.325409 systemd-logind[799]: Session 7 logged out. Waiting for processes to exit. Oct 27 15:48:10.326281 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40668). Oct 27 15:48:10.326959 systemd-logind[799]: Removed session 7. Oct 27 15:48:10.367353 sshd[904]: Accepted publickey for core from 10.0.0.1 port 40668 ssh2: RSA SHA256:e81tlCDaefVTwCz+6UGXYsJ6lgfhJ2NKCK7MgYKT6gg Oct 27 15:48:10.368277 sshd[904]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:48:10.369894 kernel: audit: type=1006 audit(1761580090.367:50): pid=904 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 27 15:48:10.371950 systemd-logind[799]: New session 8 of user core. Oct 27 15:48:10.380030 systemd[1]: Started Session 8 of User core. Oct 27 15:48:10.428663 sshd[904]: pam_unix(sshd:session): session closed for user core Oct 27 15:48:10.430438 systemd[1]: sshd@7-10.0.0.3:22-10.0.0.1:40668.service: Deactivated successfully. Oct 27 15:48:10.431102 systemd[1]: session-8.scope: Deactivated successfully. Oct 27 15:48:10.431722 systemd-logind[799]: Session 8 logged out. Waiting for processes to exit. Oct 27 15:48:10.432345 systemd-logind[799]: Removed session 8.