Oct 29 05:10:39.671848 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 29 05:10:39.671865 kernel: Linux version 5.10.107-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP PREEMPT Tue Mar 22 19:39:09 -00 2022 Oct 29 05:10:39.671873 kernel: efi: EFI v2.70 by EDK II Oct 29 05:10:39.671878 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 29 05:10:39.671883 kernel: efi: seeding entropy pool Oct 29 05:10:39.671889 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 29 05:10:39.671895 kernel: ACPI: Early table checksum verification disabled Oct 29 05:10:39.671900 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 29 05:10:39.671905 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 29 05:10:39.671910 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:10:39.671917 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:10:39.671922 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:10:39.671927 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:10:39.671932 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:10:39.671938 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:10:39.671944 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:10:39.671950 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:10:39.671956 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:10:39.671961 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 29 05:10:39.671966 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 29 05:10:39.671972 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 05:10:39.671977 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 29 05:10:39.671982 kernel: Zone ranges: Oct 29 05:10:39.671987 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 05:10:39.671992 kernel: DMA32 empty Oct 29 05:10:39.671997 kernel: Normal empty Oct 29 05:10:39.672003 kernel: Movable zone start for each node Oct 29 05:10:39.672009 kernel: Early memory node ranges Oct 29 05:10:39.672014 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 29 05:10:39.672019 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 29 05:10:39.672025 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 29 05:10:39.672030 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 29 05:10:39.672036 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 29 05:10:39.672041 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 29 05:10:39.672046 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 29 05:10:39.672051 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 29 05:10:39.672057 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 29 05:10:39.672062 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 29 05:10:39.672067 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 29 05:10:39.672073 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 05:10:39.672081 kernel: On node 0 totalpages: 643072 Oct 29 05:10:39.672087 kernel: DMA zone: 10048 pages used for memmap Oct 29 05:10:39.672092 kernel: DMA zone: 0 pages reserved Oct 29 05:10:39.672098 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 29 05:10:39.672103 kernel: psci: probing for conduit method from ACPI. Oct 29 05:10:39.672109 kernel: psci: PSCIv1.1 detected in firmware. Oct 29 05:10:39.672114 kernel: psci: Using standard PSCI v0.2 function IDs Oct 29 05:10:39.672120 kernel: psci: Trusted OS migration not required Oct 29 05:10:39.672127 kernel: psci: SMC Calling Convention v1.1 Oct 29 05:10:39.672132 kernel: ACPI: SRAT not present Oct 29 05:10:39.672138 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 29 05:10:39.672144 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 29 05:10:39.672149 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 29 05:10:39.672155 kernel: Detected PIPT I-cache on CPU0 Oct 29 05:10:39.672160 kernel: CPU features: detected: GIC system register CPU interface Oct 29 05:10:39.672166 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 29 05:10:39.672172 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 29 05:10:39.672177 kernel: CPU features: detected: Hardware dirty bit management Oct 29 05:10:39.672183 kernel: CPU features: detected: Spectre-v4 Oct 29 05:10:39.672189 kernel: CPU features: detected: ARM erratum 1418040 Oct 29 05:10:39.672195 kernel: CPU features: detected: Spectre-BHB Oct 29 05:10:39.672200 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 29 05:10:39.672206 kernel: Policy zone: DMA Oct 29 05:10:39.672213 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 29 05:10:39.672219 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 29 05:10:39.672224 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 29 05:10:39.672230 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 29 05:10:39.672244 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 29 05:10:39.672259 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 29 05:10:39.672264 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 29 05:10:39.672272 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 29 05:10:39.672278 kernel: rcu: RCU event tracing is enabled. Oct 29 05:10:39.672284 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 29 05:10:39.672290 kernel: Trampoline variant of Tasks RCU enabled. Oct 29 05:10:39.672295 kernel: Tracing variant of Tasks RCU enabled. Oct 29 05:10:39.672301 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 29 05:10:39.672307 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 29 05:10:39.672312 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 29 05:10:39.672318 kernel: GICv3: 256 SPIs implemented Oct 29 05:10:39.672323 kernel: GICv3: 0 Extended SPIs implemented Oct 29 05:10:39.672329 kernel: GICv3: Distributor has no Range Selector support Oct 29 05:10:39.672334 kernel: GICv3: 16 PPIs implemented Oct 29 05:10:39.672346 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 29 05:10:39.672352 kernel: ACPI: SRAT not present Oct 29 05:10:39.672357 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 29 05:10:39.672363 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 29 05:10:39.672369 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 29 05:10:39.672374 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 29 05:10:39.672380 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 29 05:10:39.672385 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 05:10:39.672391 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 29 05:10:39.672397 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 29 05:10:39.672403 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 29 05:10:39.672409 kernel: arm-pv: using stolen time PV Oct 29 05:10:39.672416 kernel: Console: colour dummy device 80x25 Oct 29 05:10:39.672421 kernel: ACPI: Core revision 20200925 Oct 29 05:10:39.672427 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 29 05:10:39.672433 kernel: pid_max: default: 32768 minimum: 301 Oct 29 05:10:39.672439 kernel: LSM: Security Framework initializing Oct 29 05:10:39.672445 kernel: SELinux: Initializing. Oct 29 05:10:39.672451 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 05:10:39.672457 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 05:10:39.672463 kernel: rcu: Hierarchical SRCU implementation. Oct 29 05:10:39.672470 kernel: Platform MSI: ITS@0x8080000 domain created Oct 29 05:10:39.672475 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 29 05:10:39.672481 kernel: Remapping and enabling EFI services. Oct 29 05:10:39.672487 kernel: smp: Bringing up secondary CPUs ... Oct 29 05:10:39.672493 kernel: Detected PIPT I-cache on CPU1 Oct 29 05:10:39.672499 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 29 05:10:39.672505 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 29 05:10:39.672511 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 05:10:39.672517 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 29 05:10:39.672523 kernel: Detected PIPT I-cache on CPU2 Oct 29 05:10:39.672530 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 29 05:10:39.672536 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 29 05:10:39.672542 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 05:10:39.672548 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 29 05:10:39.672554 kernel: Detected PIPT I-cache on CPU3 Oct 29 05:10:39.672564 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 29 05:10:39.672571 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 29 05:10:39.672578 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 05:10:39.672584 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 29 05:10:39.672590 kernel: smp: Brought up 1 node, 4 CPUs Oct 29 05:10:39.672596 kernel: SMP: Total of 4 processors activated. Oct 29 05:10:39.672602 kernel: CPU features: detected: Privileged Access Never Oct 29 05:10:39.672609 kernel: CPU features: detected: LSE atomic instructions Oct 29 05:10:39.672615 kernel: CPU features: detected: User Access Override Oct 29 05:10:39.672621 kernel: CPU features: detected: 32-bit EL0 Support Oct 29 05:10:39.672627 kernel: CPU features: detected: Common not Private translations Oct 29 05:10:39.672633 kernel: CPU features: detected: RAS Extension Support Oct 29 05:10:39.672640 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 29 05:10:39.672647 kernel: CPU features: detected: CRC32 instructions Oct 29 05:10:39.672653 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 29 05:10:39.672659 kernel: CPU: All CPU(s) started at EL1 Oct 29 05:10:39.672665 kernel: alternatives: patching kernel code Oct 29 05:10:39.672672 kernel: devtmpfs: initialized Oct 29 05:10:39.672678 kernel: KASLR enabled Oct 29 05:10:39.672684 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 29 05:10:39.672690 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 29 05:10:39.672697 kernel: pinctrl core: initialized pinctrl subsystem Oct 29 05:10:39.672703 kernel: SMBIOS 3.0.0 present. Oct 29 05:10:39.672709 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 29 05:10:39.672715 kernel: NET: Registered protocol family 16 Oct 29 05:10:39.672721 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 29 05:10:39.672727 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 29 05:10:39.672734 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 29 05:10:39.672741 kernel: audit: initializing netlink subsys (disabled) Oct 29 05:10:39.672747 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Oct 29 05:10:39.672753 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 29 05:10:39.672759 kernel: cpuidle: using governor menu Oct 29 05:10:39.672765 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 29 05:10:39.672771 kernel: ASID allocator initialised with 32768 entries Oct 29 05:10:39.672777 kernel: ACPI: bus type PCI registered Oct 29 05:10:39.672783 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 29 05:10:39.672790 kernel: Serial: AMBA PL011 UART driver Oct 29 05:10:39.672796 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 29 05:10:39.672803 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 29 05:10:39.672809 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 29 05:10:39.672815 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 29 05:10:39.672821 kernel: cryptd: max_cpu_qlen set to 1000 Oct 29 05:10:39.672827 kernel: ACPI: Added _OSI(Module Device) Oct 29 05:10:39.672833 kernel: ACPI: Added _OSI(Processor Device) Oct 29 05:10:39.672839 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 29 05:10:39.672846 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 29 05:10:39.672852 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 29 05:10:39.672858 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 29 05:10:39.672864 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 29 05:10:39.672871 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 29 05:10:39.672877 kernel: ACPI: Interpreter enabled Oct 29 05:10:39.672883 kernel: ACPI: Using GIC for interrupt routing Oct 29 05:10:39.672889 kernel: ACPI: MCFG table detected, 1 entries Oct 29 05:10:39.672895 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 29 05:10:39.672901 kernel: printk: console [ttyAMA0] enabled Oct 29 05:10:39.672908 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 29 05:10:39.673006 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 29 05:10:39.673062 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 29 05:10:39.673113 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 29 05:10:39.673164 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 29 05:10:39.673215 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 29 05:10:39.673223 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 29 05:10:39.673231 kernel: PCI host bridge to bus 0000:00 Oct 29 05:10:39.673309 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 29 05:10:39.673367 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 29 05:10:39.673415 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 29 05:10:39.673462 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 29 05:10:39.673527 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 29 05:10:39.673588 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 29 05:10:39.673645 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 29 05:10:39.673699 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 29 05:10:39.673753 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 05:10:39.673806 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 05:10:39.673860 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 29 05:10:39.673914 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 29 05:10:39.673962 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 29 05:10:39.674010 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 29 05:10:39.674057 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 29 05:10:39.674065 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 29 05:10:39.674072 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 29 05:10:39.674078 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 29 05:10:39.674084 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 29 05:10:39.674091 kernel: vgaarb: loaded Oct 29 05:10:39.674097 kernel: Registered efivars operations Oct 29 05:10:39.674103 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 29 05:10:39.674110 kernel: VFS: Disk quotas dquot_6.6.0 Oct 29 05:10:39.674117 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 29 05:10:39.674123 kernel: pnp: PnP ACPI init Oct 29 05:10:39.674183 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 29 05:10:39.674232 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 29 05:10:39.674250 kernel: pnp: PnP ACPI: found 1 devices Oct 29 05:10:39.674265 kernel: NET: Registered protocol family 2 Oct 29 05:10:39.674272 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 29 05:10:39.674281 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 29 05:10:39.674287 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 29 05:10:39.674294 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 29 05:10:39.674300 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 29 05:10:39.674306 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 05:10:39.674312 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 05:10:39.674318 kernel: NET: Registered protocol family 1 Oct 29 05:10:39.674324 kernel: PCI: CLS 0 bytes, default 64 Oct 29 05:10:39.674331 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 29 05:10:39.674343 kernel: kvm [1]: HYP mode not available Oct 29 05:10:39.674350 kernel: Initialise system trusted keyrings Oct 29 05:10:39.674356 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 29 05:10:39.674362 kernel: Key type asymmetric registered Oct 29 05:10:39.674368 kernel: Asymmetric key parser 'x509' registered Oct 29 05:10:39.674374 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 29 05:10:39.674380 kernel: io scheduler mq-deadline registered Oct 29 05:10:39.674386 kernel: io scheduler kyber registered Oct 29 05:10:39.674393 kernel: io scheduler bfq registered Oct 29 05:10:39.674401 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 29 05:10:39.674407 kernel: ACPI: Power Button [PWRB] Oct 29 05:10:39.674413 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 29 05:10:39.674482 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 29 05:10:39.674490 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 29 05:10:39.674496 kernel: msm_serial: driver initialized Oct 29 05:10:39.674503 kernel: random: fast init done Oct 29 05:10:39.674509 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 29 05:10:39.674515 kernel: random: crng init done Oct 29 05:10:39.674521 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 29 05:10:39.674529 kernel: thunder_xcv, ver 1.0 Oct 29 05:10:39.674534 kernel: thunder_bgx, ver 1.0 Oct 29 05:10:39.674540 kernel: nicpf, ver 1.0 Oct 29 05:10:39.674546 kernel: nicvf, ver 1.0 Oct 29 05:10:39.674607 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 29 05:10:39.674658 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-29T05:10:39 UTC (1761714639) Oct 29 05:10:39.674667 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 29 05:10:39.674673 kernel: NET: Registered protocol family 10 Oct 29 05:10:39.674680 kernel: Segment Routing with IPv6 Oct 29 05:10:39.674687 kernel: NET: Registered protocol family 17 Oct 29 05:10:39.674693 kernel: Key type dns_resolver registered Oct 29 05:10:39.674699 kernel: registered taskstats version 1 Oct 29 05:10:39.674705 kernel: Loading compiled-in X.509 certificates Oct 29 05:10:39.674712 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 29 05:10:39.674718 kernel: Key type ._fscrypt registered Oct 29 05:10:39.674724 kernel: Key type .fscrypt registered Oct 29 05:10:39.674730 kernel: Key type fscrypt-provisioning registered Oct 29 05:10:39.674737 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 29 05:10:39.674743 kernel: ima: Allocated hash algorithm: sha1 Oct 29 05:10:39.674750 kernel: ima: No architecture policies found Oct 29 05:10:39.674756 kernel: Freeing unused kernel memory: 34240K Oct 29 05:10:39.674762 kernel: Run /init as init process Oct 29 05:10:39.674768 kernel: with arguments: Oct 29 05:10:39.674774 kernel: /init Oct 29 05:10:39.674780 kernel: with environment: Oct 29 05:10:39.674786 kernel: HOME=/ Oct 29 05:10:39.674792 kernel: TERM=linux Oct 29 05:10:39.674798 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 29 05:10:39.674807 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 29 05:10:39.674815 systemd[1]: Detected virtualization kvm. Oct 29 05:10:39.674822 systemd[1]: Detected architecture arm64. Oct 29 05:10:39.674829 systemd[1]: Running in initial RAM disk. Oct 29 05:10:39.674835 systemd[1]: No hostname configured, using default hostname. Oct 29 05:10:39.674841 systemd[1]: Hostname set to . Oct 29 05:10:39.674849 systemd[1]: Initializing machine ID from VM UUID. Oct 29 05:10:39.674856 systemd[1]: Queued start job for default target Initrd Default Target. Oct 29 05:10:39.674863 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 05:10:39.674870 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 05:10:39.674876 systemd[1]: Reached target Path Units. Oct 29 05:10:39.674882 systemd[1]: Reached target Slice Units. Oct 29 05:10:39.674889 systemd[1]: Reached target Swaps. Oct 29 05:10:39.674895 systemd[1]: Reached target Timer Units. Oct 29 05:10:39.674904 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 29 05:10:39.674910 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 29 05:10:39.674917 systemd[1]: Listening on Journal Audit Socket. Oct 29 05:10:39.674924 systemd[1]: Listening on Journal Socket (/dev/log). Oct 29 05:10:39.674930 systemd[1]: Listening on Journal Socket. Oct 29 05:10:39.674937 systemd[1]: Listening on Network Service Netlink Socket. Oct 29 05:10:39.674944 systemd[1]: Listening on udev Control Socket. Oct 29 05:10:39.674950 systemd[1]: Listening on udev Kernel Socket. Oct 29 05:10:39.674957 systemd[1]: Reached target Socket Units. Oct 29 05:10:39.674964 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 05:10:39.674971 systemd[1]: Finished Network Cleanup. Oct 29 05:10:39.674978 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 29 05:10:39.674984 systemd[1]: Starting Journal Service... Oct 29 05:10:39.674991 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 05:10:39.674997 systemd[1]: Starting Apply Kernel Variables... Oct 29 05:10:39.675004 systemd[1]: Starting Setup Virtual Console... Oct 29 05:10:39.675010 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 05:10:39.675017 systemd[1]: Finished Apply Kernel Variables. Oct 29 05:10:39.675025 systemd[1]: Finished Setup Virtual Console. Oct 29 05:10:39.675031 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 29 05:10:39.675038 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 05:10:39.675046 systemd-journald[279]: Journal started Oct 29 05:10:39.675078 systemd-journald[279]: Runtime Journal (/run/log/journal/59a3743c4545412ca2554a2733fd8526) is 6.0M, max 48.5M, 42.4M free. Oct 29 05:10:39.677445 systemd[1]: Started Journal Service. Oct 29 05:10:39.677803 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 05:10:39.689047 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 29 05:10:39.690724 systemd[1]: Starting dracut cmdline hook... Oct 29 05:10:39.699028 dracut-cmdline[298]: dracut-dracut-053 Oct 29 05:10:39.701164 dracut-cmdline[298]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 29 05:10:39.757262 kernel: SCSI subsystem initialized Oct 29 05:10:39.760255 kernel: Loading iSCSI transport class v2.0-870. Oct 29 05:10:39.766271 kernel: iscsi: registered transport (tcp) Oct 29 05:10:39.777262 kernel: iscsi: registered transport (qla4xxx) Oct 29 05:10:39.777276 kernel: QLogic iSCSI HBA Driver Oct 29 05:10:39.786716 systemd[1]: Finished dracut cmdline hook. Oct 29 05:10:39.788050 systemd[1]: Starting dracut pre-udev hook... Oct 29 05:10:39.801079 kernel: device-mapper: uevent: version 1.0.3 Oct 29 05:10:39.801128 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 29 05:10:39.835255 kernel: raid6: neonx8 gen() 13779 MB/s Oct 29 05:10:39.852251 kernel: raid6: neonx8 xor() 10757 MB/s Oct 29 05:10:39.869251 kernel: raid6: neonx4 gen() 13555 MB/s Oct 29 05:10:39.886254 kernel: raid6: neonx4 xor() 11536 MB/s Oct 29 05:10:39.903253 kernel: raid6: neonx2 gen() 13003 MB/s Oct 29 05:10:39.920263 kernel: raid6: neonx2 xor() 9886 MB/s Oct 29 05:10:39.937250 kernel: raid6: neonx1 gen() 10655 MB/s Oct 29 05:10:39.954262 kernel: raid6: neonx1 xor() 8869 MB/s Oct 29 05:10:39.971258 kernel: raid6: int64x8 gen() 6788 MB/s Oct 29 05:10:39.988253 kernel: raid6: int64x8 xor() 3780 MB/s Oct 29 05:10:40.005253 kernel: raid6: int64x4 gen() 6908 MB/s Oct 29 05:10:40.022260 kernel: raid6: int64x4 xor() 3832 MB/s Oct 29 05:10:40.039261 kernel: raid6: int64x2 gen() 6177 MB/s Oct 29 05:10:40.056252 kernel: raid6: int64x2 xor() 3306 MB/s Oct 29 05:10:40.073259 kernel: raid6: int64x1 gen() 5005 MB/s Oct 29 05:10:40.090799 kernel: raid6: int64x1 xor() 2594 MB/s Oct 29 05:10:40.090810 kernel: raid6: using algorithm neonx8 gen() 13779 MB/s Oct 29 05:10:40.090818 kernel: raid6: .... xor() 10757 MB/s, rmw enabled Oct 29 05:10:40.090826 kernel: raid6: using neon recovery algorithm Oct 29 05:10:40.101470 kernel: xor: measuring software checksum speed Oct 29 05:10:40.101499 kernel: 8regs : 17174 MB/sec Oct 29 05:10:40.102400 kernel: 32regs : 20770 MB/sec Oct 29 05:10:40.103783 kernel: arm64_neon : 29132 MB/sec Oct 29 05:10:40.103794 kernel: xor: using function: arm64_neon (29132 MB/sec) Oct 29 05:10:40.148272 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 29 05:10:40.157739 systemd[1]: Finished dracut pre-udev hook. Oct 29 05:10:40.158000 audit: BPF prog-id=6 op=LOAD Oct 29 05:10:40.158000 audit: BPF prog-id=7 op=LOAD Oct 29 05:10:40.160165 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 05:10:40.162155 kernel: audit: type=1334 audit(1761714640.158:2): prog-id=6 op=LOAD Oct 29 05:10:40.162171 kernel: audit: type=1334 audit(1761714640.158:3): prog-id=7 op=LOAD Oct 29 05:10:40.162180 kernel: audit: type=1334 audit(1761714640.159:4): prog-id=8 op=LOAD Oct 29 05:10:40.159000 audit: BPF prog-id=8 op=LOAD Oct 29 05:10:40.172326 systemd-udevd[417]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 05:10:40.174062 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 05:10:40.178077 systemd[1]: Starting dracut pre-trigger hook... Oct 29 05:10:40.179000 audit: BPF prog-id=9 op=LOAD Oct 29 05:10:40.179777 systemd[1]: Starting Network Configuration... Oct 29 05:10:40.181411 kernel: audit: type=1334 audit(1761714640.179:5): prog-id=9 op=LOAD Oct 29 05:10:40.188610 dracut-pre-trigger[428]: rd.md=0: removing MD RAID activation Oct 29 05:10:40.196797 systemd-networkd[429]: lo: Link UP Oct 29 05:10:40.196806 systemd-networkd[429]: lo: Gained carrier Oct 29 05:10:40.198000 audit: BPF prog-id=10 op=LOAD Oct 29 05:10:40.197065 systemd-networkd[429]: Enumeration completed Oct 29 05:10:40.200622 kernel: audit: type=1334 audit(1761714640.198:6): prog-id=10 op=LOAD Oct 29 05:10:40.197145 systemd[1]: Started Network Configuration. Oct 29 05:10:40.199062 systemd[1]: Starting Network Name Resolution... Oct 29 05:10:40.218544 systemd[1]: Finished dracut pre-trigger hook. Oct 29 05:10:40.219912 systemd[1]: Starting Coldplug All udev Devices... Oct 29 05:10:40.228156 systemd-resolved[445]: Positive Trust Anchors: Oct 29 05:10:40.228174 systemd-resolved[445]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 05:10:40.228203 systemd-resolved[445]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 29 05:10:40.228498 systemd-resolved[445]: Defaulting to hostname 'linux'. Oct 29 05:10:40.229008 systemd-udevd[417]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 05:10:40.229062 systemd[1]: Started Network Name Resolution. Oct 29 05:10:40.230360 systemd[1]: Reached target Network. Oct 29 05:10:40.231057 systemd[1]: Reached target Host and Network Name Lookups. Oct 29 05:10:40.239926 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 29 05:10:40.248597 systemd[1]: Finished Coldplug All udev Devices. Oct 29 05:10:40.255835 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 29 05:10:40.257292 systemd[1]: Starting Open-iSCSI... Oct 29 05:10:40.259588 iscsid[482]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 29 05:10:40.259588 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 29 05:10:40.259588 iscsid[482]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 29 05:10:40.259588 iscsid[482]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 29 05:10:40.259588 iscsid[482]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 29 05:10:40.270412 iscsid[482]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 29 05:10:40.263048 systemd[1]: Started Open-iSCSI. Oct 29 05:10:40.266682 systemd[1]: Starting dracut initqueue hook... Oct 29 05:10:40.295433 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 29 05:10:40.295541 kernel: vda: detected capacity change from 0 to 4756340736 Oct 29 05:10:40.304869 systemd-udevd[464]: Using default interface naming scheme 'v249'. Oct 29 05:10:40.305961 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 05:10:40.310098 systemd-networkd[429]: eth0: Link UP Oct 29 05:10:40.323197 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 29 05:10:40.324497 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (478) Oct 29 05:10:40.330475 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 29 05:10:40.333745 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 29 05:10:40.336546 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 29 05:10:40.338075 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 29 05:10:40.339279 systemd[1]: Finished dracut initqueue hook. Oct 29 05:10:40.340785 systemd[1]: Reached target Initrd Root Device. Oct 29 05:10:40.341962 systemd[1]: Reached target Preparation for Remote File Systems. Oct 29 05:10:40.343337 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 05:10:40.344763 systemd[1]: Reached target Remote File Systems. Oct 29 05:10:40.346627 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 29 05:10:40.348056 systemd[1]: Starting dracut pre-mount hook... Oct 29 05:10:40.356675 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 05:10:40.356048 systemd[1]: Finished dracut pre-mount hook. Oct 29 05:10:41.340220 systemd-networkd[429]: eth0: Gained carrier Oct 29 05:10:41.341293 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 29 05:10:41.347347 systemd-networkd[429]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 29 05:10:41.363282 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 05:10:41.363316 disk-uuid[504]: The operation has completed successfully. Oct 29 05:10:41.384580 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 29 05:10:41.384715 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 29 05:10:41.388144 systemd[1]: Starting Ignition (setup)... Oct 29 05:10:41.395700 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 29 05:10:41.395771 kernel: BTRFS info (device vda6): has skinny extents Oct 29 05:10:41.401365 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 29 05:10:41.405887 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 29 05:10:41.405999 systemd[1]: Finished Ignition (setup). Oct 29 05:10:41.407374 systemd[1]: Starting Ignition (disks)... Oct 29 05:10:41.416998 ignition[539]: Ignition v0.36.1-15-gde4e6cc9 Oct 29 05:10:41.417016 ignition[539]: Stage: disks Oct 29 05:10:41.417026 ignition[539]: reading system config file "/usr/lib/ignition/base.ign" Oct 29 05:10:41.417035 ignition[539]: no config at "/usr/lib/ignition/base.ign" Oct 29 05:10:41.417075 ignition[539]: parsed url from cmdline: "" Oct 29 05:10:41.417078 ignition[539]: no config URL provided Oct 29 05:10:41.417083 ignition[539]: reading system config file "/usr/lib/ignition/user.ign" Oct 29 05:10:41.417089 ignition[539]: no config at "/usr/lib/ignition/user.ign" Oct 29 05:10:41.417107 ignition[539]: op(1): [started] loading QEMU firmware config module Oct 29 05:10:41.417112 ignition[539]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 29 05:10:41.418073 ignition[539]: op(1): [finished] loading QEMU firmware config module Oct 29 05:10:41.431266 ignition[539]: parsing config with SHA512: f78ca86163beec276c6693b08ca5d56001c6e0242e9903503017d11f2357b352a1b7b600a28fd4762d36b74c2d1018a11a5a150b46cf66673523f64447809b4c Oct 29 05:10:41.433063 ignition[539]: disks: disks passed Oct 29 05:10:41.433071 ignition[539]: Ignition finished successfully Oct 29 05:10:41.433900 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 29 05:10:41.434013 systemd[1]: Finished Ignition (disks). Oct 29 05:10:41.435589 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 05:10:41.437818 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 29 05:10:41.440625 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 29 05:10:41.447808 systemd-fsck[552]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 29 05:10:41.452142 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 29 05:10:41.451622 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 29 05:10:41.453186 systemd[1]: Mounting /sysroot... Oct 29 05:10:41.468992 systemd[1]: Found device /dev/mapper/usr. Oct 29 05:10:41.470350 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 29 05:10:41.471348 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 29 05:10:41.481952 systemd-fsck[568]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 29 05:10:41.481952 systemd-fsck[568]: You must have r/w access to the filesystem or be root Oct 29 05:10:41.482300 systemd-fsck[566]: fsck failed with exit status 8. Oct 29 05:10:41.482303 systemd-fsck[566]: Ignoring error. Oct 29 05:10:41.482999 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 29 05:10:41.484900 systemd[1]: Mounting /sysusr/usr... Oct 29 05:10:41.497018 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 29 05:10:41.497048 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 29 05:10:41.497501 systemd[1]: Mounted /sysroot. Oct 29 05:10:41.498128 systemd[1]: Reached target Initrd Root File System. Oct 29 05:10:41.511252 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 29 05:10:41.511272 systemd[1]: Mounted /sysusr/usr. Oct 29 05:10:41.511906 systemd[1]: Reached target Local File Systems. Oct 29 05:10:41.513049 systemd[1]: Reached target System Initialization. Oct 29 05:10:41.514318 systemd[1]: Reached target Basic System. Oct 29 05:10:41.516495 systemd[1]: Mounting /sysroot/usr... Oct 29 05:10:41.518398 systemd[1]: Mounted /sysroot/usr. Oct 29 05:10:41.519613 systemd[1]: Starting Root filesystem setup... Oct 29 05:10:41.543158 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 29 05:10:41.543283 systemd[1]: Finished Root filesystem setup. Oct 29 05:10:41.544618 systemd[1]: Starting Ignition (files)... Oct 29 05:10:41.546186 systemd[1]: Starting /sysroot/boot... Oct 29 05:10:41.547050 ignition[584]: Ignition v0.36.1-15-gde4e6cc9 Oct 29 05:10:41.547056 ignition[584]: Stage: files Oct 29 05:10:41.547065 ignition[584]: reading system config file "/usr/lib/ignition/base.ign" Oct 29 05:10:41.547074 ignition[584]: no config at "/usr/lib/ignition/base.ign" Oct 29 05:10:41.547579 ignition[584]: files: compiled without relabeling support, skipping Oct 29 05:10:41.564870 ignition[584]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 29 05:10:41.564881 ignition[584]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 29 05:10:41.566580 systemd[1]: Finished /sysroot/boot. Oct 29 05:10:41.567320 ignition[584]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 29 05:10:41.567333 ignition[584]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 29 05:10:41.569373 ignition[584]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 29 05:10:41.569393 ignition[584]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 29 05:10:41.570175 ignition[584]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 29 05:10:41.570182 ignition[584]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 29 05:10:41.573274 systemd[1]: ignition-files.service: Deactivated successfully. Oct 29 05:10:41.570285 ignition[584]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 29 05:10:41.573381 systemd[1]: Finished Ignition (files). Oct 29 05:10:41.572665 ignition[584]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 29 05:10:41.574688 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 29 05:10:41.585583 kernel: audit: type=1334 audit(1761714641.582:7): prog-id=6 op=UNLOAD Oct 29 05:10:41.585604 kernel: audit: type=1334 audit(1761714641.583:8): prog-id=10 op=UNLOAD Oct 29 05:10:41.585619 kernel: audit: type=1334 audit(1761714641.583:9): prog-id=9 op=UNLOAD Oct 29 05:10:41.585628 kernel: audit: type=1334 audit(1761714641.585:10): prog-id=3 op=UNLOAD Oct 29 05:10:41.582000 audit: BPF prog-id=6 op=UNLOAD Oct 29 05:10:41.583000 audit: BPF prog-id=10 op=UNLOAD Oct 29 05:10:41.583000 audit: BPF prog-id=9 op=UNLOAD Oct 29 05:10:41.585000 audit: BPF prog-id=3 op=UNLOAD Oct 29 05:10:41.572671 ignition[584]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 29 05:10:41.575224 systemd[1]: Starting Ignition (record completion)... Oct 29 05:10:41.572675 ignition[584]: files: files passed Oct 29 05:10:41.576696 systemd[1]: Starting Reload Configuration from the Real Root... Oct 29 05:10:41.572679 ignition[584]: Ignition finished successfully Oct 29 05:10:41.578139 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 29 05:10:41.578252 systemd[1]: Finished Ignition (record completion). Oct 29 05:10:41.581379 systemd[1]: Reloading. Oct 29 05:10:41.668000 audit: BPF prog-id=11 op=LOAD Oct 29 05:10:41.668000 audit: BPF prog-id=12 op=LOAD Oct 29 05:10:41.668000 audit: BPF prog-id=13 op=LOAD Oct 29 05:10:41.668000 audit: BPF prog-id=7 op=UNLOAD Oct 29 05:10:41.668000 audit: BPF prog-id=8 op=UNLOAD Oct 29 05:10:41.668000 audit: BPF prog-id=14 op=LOAD Oct 29 05:10:41.669000 audit: BPF prog-id=15 op=LOAD Oct 29 05:10:41.669000 audit: BPF prog-id=16 op=LOAD Oct 29 05:10:41.669000 audit: BPF prog-id=17 op=LOAD Oct 29 05:10:41.669000 audit: BPF prog-id=18 op=LOAD Oct 29 05:10:41.669000 audit: BPF prog-id=4 op=UNLOAD Oct 29 05:10:41.669000 audit: BPF prog-id=5 op=UNLOAD Oct 29 05:10:41.685595 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 29 05:10:41.685701 systemd[1]: Finished Reload Configuration from the Real Root. Oct 29 05:10:41.687316 systemd[1]: Reached target Initrd File Systems. Oct 29 05:10:41.688741 systemd[1]: Reached target Initrd Default Target. Oct 29 05:10:41.690002 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 29 05:10:41.690624 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 29 05:10:41.700595 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 29 05:10:41.701963 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 29 05:10:41.707701 systemd[1]: Stopped target Host and Network Name Lookups. Oct 29 05:10:41.708607 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 29 05:10:41.710049 systemd[1]: Stopped target Timer Units. Oct 29 05:10:41.711288 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 29 05:10:41.711404 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 29 05:10:41.712627 systemd[1]: Stopped target Initrd Default Target. Oct 29 05:10:41.714007 systemd[1]: Stopped target Basic System. Oct 29 05:10:41.715286 systemd[1]: Stopped target Initrd Root Device. Oct 29 05:10:41.716587 systemd[1]: Stopped target Path Units. Oct 29 05:10:41.717693 systemd[1]: Stopped target Remote File Systems. Oct 29 05:10:41.718987 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 29 05:10:41.720282 systemd[1]: Stopped target Slice Units. Oct 29 05:10:41.721500 systemd[1]: Stopped target Socket Units. Oct 29 05:10:41.722837 systemd[1]: Stopped target System Initialization. Oct 29 05:10:41.724011 systemd[1]: Stopped target Local File Systems. Oct 29 05:10:41.725160 systemd[1]: Stopped target Preparation for Local File Systems. Oct 29 05:10:41.726599 systemd[1]: Stopped target Swaps. Oct 29 05:10:41.727733 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 29 05:10:41.727825 systemd[1]: Stopped dracut pre-mount hook. Oct 29 05:10:41.729112 systemd[1]: Stopped target Local Encrypted Volumes. Oct 29 05:10:41.730194 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 05:10:41.736655 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 29 05:10:41.738345 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 29 05:10:41.738462 systemd[1]: Stopped dracut initqueue hook. Oct 29 05:10:41.741958 iscsid[482]: iscsid shutting down. Oct 29 05:10:41.740107 systemd[1]: Stopping Open-iSCSI... Oct 29 05:10:41.741985 systemd[1]: Stopping /sysroot/boot... Oct 29 05:10:41.742576 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 29 05:10:41.742691 systemd[1]: Stopped Coldplug All udev Devices. Oct 29 05:10:41.743831 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 29 05:10:41.743903 systemd[1]: Stopped dracut pre-trigger hook. Oct 29 05:10:41.745872 systemd[1]: iscsid.service: Deactivated successfully. Oct 29 05:10:41.745983 systemd[1]: Stopped Open-iSCSI. Oct 29 05:10:41.747459 systemd[1]: iscsid.socket: Deactivated successfully. Oct 29 05:10:41.747518 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 29 05:10:41.748616 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 29 05:10:41.750690 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 29 05:10:41.750793 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 29 05:10:41.752113 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 29 05:10:41.752215 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 29 05:10:41.754270 systemd[1]: Stopped target Network. Oct 29 05:10:41.755451 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 29 05:10:41.755504 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 29 05:10:41.756722 systemd[1]: Stopping Network Name Resolution... Oct 29 05:10:41.758093 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 29 05:10:41.758187 systemd[1]: Stopped /sysroot/boot. Oct 29 05:10:41.762408 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 29 05:10:41.762512 systemd[1]: Stopped Network Name Resolution. Oct 29 05:10:41.763869 systemd[1]: Stopping Network Configuration... Oct 29 05:10:41.767794 systemd-networkd[429]: eth0: DHCP lease lost Oct 29 05:10:41.769000 audit: BPF prog-id=14 op=UNLOAD Oct 29 05:10:41.771273 systemd-networkd[429]: eth0: DHCPv6 lease lost Oct 29 05:10:41.772139 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 29 05:10:41.772258 systemd[1]: Stopped Network Configuration. Oct 29 05:10:41.773893 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 29 05:10:41.773920 systemd[1]: Closed Network Service Netlink Socket. Oct 29 05:10:41.775652 systemd[1]: Stopping Network Cleanup... Oct 29 05:10:41.776000 audit: BPF prog-id=15 op=UNLOAD Oct 29 05:10:41.776497 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 05:10:41.776553 systemd[1]: Stopped Apply Kernel Variables. Oct 29 05:10:41.780157 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 29 05:10:41.783146 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 29 05:10:41.783264 systemd[1]: Stopped Network Cleanup. Oct 29 05:10:41.785356 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 29 05:10:41.785462 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 29 05:10:41.787182 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 29 05:10:41.787218 systemd[1]: Closed udev Control Socket. Oct 29 05:10:41.788204 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 29 05:10:41.793000 audit: BPF prog-id=11 op=UNLOAD Oct 29 05:10:41.788231 systemd[1]: Closed udev Kernel Socket. Oct 29 05:10:41.789680 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 29 05:10:41.789716 systemd[1]: Stopped dracut pre-udev hook. Oct 29 05:10:41.791052 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 29 05:10:41.791086 systemd[1]: Stopped dracut cmdline hook. Oct 29 05:10:41.792446 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 29 05:10:41.792480 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 29 05:10:41.794399 systemd[1]: Starting Cleanup udev Database... Oct 29 05:10:41.795259 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 29 05:10:41.796564 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 05:10:41.796618 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 29 05:10:41.798011 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 29 05:10:41.798046 systemd[1]: Stopped Create List of Static Device Nodes. Oct 29 05:10:41.799314 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 05:10:41.799360 systemd[1]: Stopped Setup Virtual Console. Oct 29 05:10:41.810000 audit: BPF prog-id=16 op=UNLOAD Oct 29 05:10:41.800981 systemd[1]: rngd.service: Deactivated successfully. Oct 29 05:10:41.801081 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 29 05:10:41.802161 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 29 05:10:41.802298 systemd[1]: Finished Cleanup udev Database. Oct 29 05:10:41.803526 systemd[1]: Reached target Switch Root. Oct 29 05:10:41.805366 systemd[1]: Starting Switch Root... Oct 29 05:10:41.809964 systemd[1]: Switching root. Oct 29 05:10:41.824428 systemd-journald[279]: Journal stopped Oct 29 05:10:43.942735 systemd-journald[279]: Received SIGTERM from PID 1 (systemd). Oct 29 05:10:43.942796 kernel: SELinux: policy capability network_peer_controls=1 Oct 29 05:10:43.942856 kernel: SELinux: policy capability open_perms=1 Oct 29 05:10:43.942869 kernel: SELinux: policy capability extended_socket_class=1 Oct 29 05:10:43.942878 kernel: SELinux: policy capability always_check_network=0 Oct 29 05:10:43.942887 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 29 05:10:43.942896 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 29 05:10:43.942905 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 29 05:10:43.942915 systemd[1]: Successfully loaded SELinux policy in 31.161ms. Oct 29 05:10:43.942930 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.110ms. Oct 29 05:10:43.942941 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 29 05:10:43.942952 systemd[1]: Detected virtualization kvm. Oct 29 05:10:43.942963 systemd[1]: Detected architecture arm64. Oct 29 05:10:43.942972 systemd[1]: Detected first boot. Oct 29 05:10:43.942981 systemd[1]: Initializing machine ID from VM UUID. Oct 29 05:10:43.942991 systemd[1]: Populated /etc with preset unit settings. Oct 29 05:10:43.943002 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 29 05:10:43.943012 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 29 05:10:43.943022 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 29 05:10:43.943033 systemd[1]: Stopped Switch Root. Oct 29 05:10:43.943043 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 29 05:10:43.943053 systemd[1]: Created slice Slice /system/addon-config. Oct 29 05:10:43.943062 systemd[1]: Created slice Slice /system/addon-run. Oct 29 05:10:43.943071 systemd[1]: Created slice Slice /system/getty. Oct 29 05:10:43.943123 systemd[1]: Created slice Slice /system/modprobe. Oct 29 05:10:43.943136 systemd[1]: Created slice Slice /system/serial-getty. Oct 29 05:10:43.943146 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 29 05:10:43.943157 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 29 05:10:43.943169 systemd[1]: Created slice User and Session Slice. Oct 29 05:10:43.943179 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 05:10:43.943188 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 29 05:10:43.943198 systemd[1]: Set up automount Boot partition Automount Point. Oct 29 05:10:43.943245 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 29 05:10:43.943258 systemd[1]: Stopped target Switch Root. Oct 29 05:10:43.943267 systemd[1]: Stopped target Initrd File Systems. Oct 29 05:10:43.943278 systemd[1]: Stopped target Initrd Root File System. Oct 29 05:10:43.943287 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 05:10:43.943297 systemd[1]: Reached target Remote File Systems. Oct 29 05:10:43.943309 systemd[1]: Reached target Slice Units. Oct 29 05:10:43.943326 systemd[1]: Reached target Swaps. Oct 29 05:10:43.943337 systemd[1]: Reached target Verify torcx succeeded. Oct 29 05:10:43.943347 systemd[1]: Reached target Local Verity Protected Volumes. Oct 29 05:10:43.943357 systemd[1]: Listening on Process Core Dump Socket. Oct 29 05:10:43.943366 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 29 05:10:43.943376 systemd[1]: Listening on Network Service Netlink Socket. Oct 29 05:10:43.943385 systemd[1]: Listening on udev Control Socket. Oct 29 05:10:43.943394 systemd[1]: Listening on udev Kernel Socket. Oct 29 05:10:43.943405 systemd[1]: Mounting Huge Pages File System... Oct 29 05:10:43.943416 systemd[1]: Mounting POSIX Message Queue File System... Oct 29 05:10:43.943426 systemd[1]: Mounting External Media Directory... Oct 29 05:10:43.943435 systemd[1]: Mounting Kernel Debug File System... Oct 29 05:10:43.943445 systemd[1]: Mounting Kernel Trace File System... Oct 29 05:10:43.943454 systemd[1]: Mounting Temporary Directory /tmp... Oct 29 05:10:43.943463 systemd[1]: Starting Create missing system files... Oct 29 05:10:43.943473 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 05:10:43.943483 systemd[1]: Starting Load Kernel Module configfs... Oct 29 05:10:43.943492 systemd[1]: Starting Load Kernel Module drm... Oct 29 05:10:43.943502 systemd[1]: Starting Load Kernel Module fuse... Oct 29 05:10:43.943512 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 29 05:10:43.943522 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 29 05:10:43.943532 systemd[1]: Stopped File System Check on Root Device. Oct 29 05:10:43.943541 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 29 05:10:43.943551 systemd[1]: Stopped systemd-fsck-usr.service. Oct 29 05:10:43.943560 systemd[1]: Stopped Journal Service. Oct 29 05:10:43.943569 kernel: fuse: init (API version 7.32) Oct 29 05:10:43.943578 kernel: kauditd_printk_skb: 36 callbacks suppressed Oct 29 05:10:43.943588 kernel: audit: type=1334 audit(1761714643.925:47): prog-id=27 op=LOAD Oct 29 05:10:43.943597 kernel: audit: type=1334 audit(1761714643.926:48): prog-id=28 op=LOAD Oct 29 05:10:43.943606 systemd[1]: Starting Journal Service... Oct 29 05:10:43.943616 kernel: audit: type=1334 audit(1761714643.927:49): prog-id=29 op=LOAD Oct 29 05:10:43.943625 kernel: audit: type=1334 audit(1761714643.927:50): prog-id=25 op=UNLOAD Oct 29 05:10:43.943633 kernel: audit: type=1334 audit(1761714643.927:51): prog-id=26 op=UNLOAD Oct 29 05:10:43.943642 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 05:10:43.943652 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 29 05:10:43.943661 systemd[1]: Starting Apply Kernel Variables... Oct 29 05:10:43.943671 systemd[1]: Starting Coldplug All udev Devices... Oct 29 05:10:43.943681 systemd[1]: verity-setup.service: Deactivated successfully. Oct 29 05:10:43.943691 systemd[1]: Stopped verity-setup.service. Oct 29 05:10:43.943701 kernel: audit: type=1305 audit(1761714643.941:52): op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 29 05:10:43.943711 systemd-journald[800]: Journal started Oct 29 05:10:43.943751 systemd-journald[800]: Runtime Journal (/run/log/journal/59a3743c4545412ca2554a2733fd8526) is 6.0M, max 48.5M, 42.4M free. Oct 29 05:10:41.885000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 29 05:10:41.914000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 29 05:10:41.914000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 29 05:10:41.914000 audit: BPF prog-id=19 op=LOAD Oct 29 05:10:41.914000 audit: BPF prog-id=19 op=UNLOAD Oct 29 05:10:41.914000 audit: BPF prog-id=20 op=LOAD Oct 29 05:10:41.914000 audit: BPF prog-id=20 op=UNLOAD Oct 29 05:10:41.950000 audit[749]: AVC avc: denied { associate } for pid=749 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 29 05:10:43.810000 audit: BPF prog-id=21 op=LOAD Oct 29 05:10:43.810000 audit: BPF prog-id=22 op=LOAD Oct 29 05:10:43.810000 audit: BPF prog-id=23 op=LOAD Oct 29 05:10:43.810000 audit: BPF prog-id=17 op=UNLOAD Oct 29 05:10:43.810000 audit: BPF prog-id=18 op=UNLOAD Oct 29 05:10:43.827000 audit: BPF prog-id=24 op=LOAD Oct 29 05:10:43.827000 audit: BPF prog-id=21 op=UNLOAD Oct 29 05:10:43.827000 audit: BPF prog-id=25 op=LOAD Oct 29 05:10:43.827000 audit: BPF prog-id=26 op=LOAD Oct 29 05:10:43.827000 audit: BPF prog-id=22 op=UNLOAD Oct 29 05:10:43.827000 audit: BPF prog-id=23 op=UNLOAD Oct 29 05:10:43.839000 audit: BPF prog-id=24 op=UNLOAD Oct 29 05:10:43.925000 audit: BPF prog-id=27 op=LOAD Oct 29 05:10:43.926000 audit: BPF prog-id=28 op=LOAD Oct 29 05:10:43.927000 audit: BPF prog-id=29 op=LOAD Oct 29 05:10:43.927000 audit: BPF prog-id=25 op=UNLOAD Oct 29 05:10:43.927000 audit: BPF prog-id=26 op=UNLOAD Oct 29 05:10:43.941000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 29 05:10:43.809969 systemd[1]: Queued start job for default target Multi-User System. Oct 29 05:10:41.948801 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 29 05:10:43.829018 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 29 05:10:41.949248 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 05:10:41.949270 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 05:10:41.949403 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 29 05:10:41.949415 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 29 05:10:41.949446 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 29 05:10:41.949459 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 29 05:10:41.949642 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 29 05:10:41.949676 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 05:10:41.949691 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 05:10:41.950056 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 29 05:10:41.950087 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 29 05:10:41.950110 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 29 05:10:41.950126 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 29 05:10:41.950144 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 29 05:10:41.950158 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:41Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 29 05:10:43.645881 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:43Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 05:10:43.646158 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:43Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 05:10:43.646275 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:43Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 05:10:43.646416 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:43Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 05:10:43.646472 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:43Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 29 05:10:43.646530 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-29T05:10:43Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 29 05:10:43.947270 systemd[1]: Started Journal Service. Oct 29 05:10:43.947623 systemd[1]: Mounted Huge Pages File System. Oct 29 05:10:43.948402 systemd[1]: Mounted POSIX Message Queue File System. Oct 29 05:10:43.949230 systemd[1]: Mounted External Media Directory. Oct 29 05:10:43.950020 systemd[1]: Mounted Kernel Debug File System. Oct 29 05:10:43.950863 systemd[1]: Mounted Kernel Trace File System. Oct 29 05:10:43.951682 systemd[1]: Mounted Temporary Directory /tmp. Oct 29 05:10:43.952692 systemd[1]: Finished Create missing system files. Oct 29 05:10:43.953723 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 05:10:43.954783 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 29 05:10:43.954945 systemd[1]: Finished Load Kernel Module configfs. Oct 29 05:10:43.955895 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 05:10:43.956063 systemd[1]: Finished Load Kernel Module drm. Oct 29 05:10:43.957032 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 29 05:10:43.957198 systemd[1]: Finished Load Kernel Module fuse. Oct 29 05:10:43.958250 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 29 05:10:43.959348 systemd[1]: Finished Apply Kernel Variables. Oct 29 05:10:43.968218 systemd[1]: Mounting FUSE Control File System... Oct 29 05:10:43.969803 systemd[1]: Mounting Kernel Configuration File System... Oct 29 05:10:43.970629 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 29 05:10:43.971898 systemd[1]: Starting Rebuild Hardware Database... Oct 29 05:10:43.973672 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 29 05:10:43.974616 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 29 05:10:43.977362 systemd-journald[800]: Time spent on flushing to /var/log/journal/59a3743c4545412ca2554a2733fd8526 is 9.696ms for 772 entries. Oct 29 05:10:43.977362 systemd-journald[800]: System Journal (/var/log/journal/59a3743c4545412ca2554a2733fd8526) is 8.0M, max 203.0M, 195.0M free. Oct 29 05:10:43.975552 systemd[1]: Starting Load/Save Random Seed... Oct 29 05:10:43.977254 systemd[1]: Starting Create System Users... Oct 29 05:10:43.980713 systemd[1]: Mounted FUSE Control File System. Oct 29 05:10:43.981729 systemd[1]: Mounted Kernel Configuration File System. Oct 29 05:10:43.984145 systemd[1]: Finished Load/Save Random Seed. Oct 29 05:10:43.987293 systemd[1]: Reached target First Boot Complete. Oct 29 05:10:43.989974 systemd-sysusers[812]: Creating group sgx with gid 999. Oct 29 05:10:43.996576 systemd[1]: Finished Coldplug All udev Devices. Oct 29 05:10:43.998259 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 29 05:10:43.999690 systemd-sysusers[812]: Creating group systemd-oom with gid 998. Oct 29 05:10:44.000118 systemd-sysusers[812]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 29 05:10:44.000999 systemd-sysusers[812]: Creating group systemd-timesync with gid 997. Oct 29 05:10:44.001462 systemd-sysusers[812]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 29 05:10:44.002690 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 29 05:10:44.003740 systemd-sysusers[812]: Creating group systemd-coredump with gid 996. Oct 29 05:10:44.004454 systemd-sysusers[812]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 29 05:10:44.012592 systemd[1]: Finished Create System Users. Oct 29 05:10:44.014282 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 05:10:44.026778 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 05:10:44.204842 systemd[1]: Finished Rebuild Hardware Database. Oct 29 05:10:44.217000 audit: BPF prog-id=30 op=LOAD Oct 29 05:10:44.218000 audit: BPF prog-id=31 op=LOAD Oct 29 05:10:44.219838 kernel: audit: type=1334 audit(1761714644.217:53): prog-id=30 op=LOAD Oct 29 05:10:44.219878 kernel: audit: type=1334 audit(1761714644.218:54): prog-id=31 op=LOAD Oct 29 05:10:44.219896 kernel: audit: type=1334 audit(1761714644.219:55): prog-id=32 op=LOAD Oct 29 05:10:44.219000 audit: BPF prog-id=32 op=LOAD Oct 29 05:10:44.220626 kernel: audit: type=1334 audit(1761714644.219:56): prog-id=12 op=UNLOAD Oct 29 05:10:44.219000 audit: BPF prog-id=12 op=UNLOAD Oct 29 05:10:44.219000 audit: BPF prog-id=13 op=UNLOAD Oct 29 05:10:44.220416 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 05:10:44.244585 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 05:10:44.249000 audit: BPF prog-id=33 op=LOAD Oct 29 05:10:44.250502 systemd[1]: Starting Network Configuration... Oct 29 05:10:44.276949 systemd-udevd[824]: Using default interface naming scheme 'v249'. Oct 29 05:10:44.277167 systemd-networkd[833]: lo: Link UP Oct 29 05:10:44.277175 systemd-networkd[833]: lo: Gained carrier Oct 29 05:10:44.277484 systemd-networkd[833]: Enumeration completed Oct 29 05:10:44.277578 systemd[1]: Started Network Configuration. Oct 29 05:10:44.288910 systemd-networkd[833]: eth0: Link UP Oct 29 05:10:44.296335 systemd-networkd[833]: eth0: Gained carrier Oct 29 05:10:44.305436 systemd-networkd[833]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 29 05:10:44.308196 udevadm[815]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 29 05:10:44.348062 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 29 05:10:44.362571 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 05:10:44.372318 lvm[845]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 05:10:44.396894 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 05:10:44.398266 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 05:10:44.419070 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 05:10:44.422358 lvm[846]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 05:10:44.459464 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 05:10:44.460765 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 05:10:44.461915 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 29 05:10:44.461943 systemd[1]: Reached target Containers. Oct 29 05:10:44.479528 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 29 05:10:44.491518 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 29 05:10:44.493399 systemd[1]: Mounting /usr/share/oem... Oct 29 05:10:44.499260 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 29 05:10:44.499321 kernel: BTRFS info (device vda6): has skinny extents Oct 29 05:10:44.501153 systemd[1]: Mounted /usr/share/oem. Oct 29 05:10:44.501939 systemd[1]: Reached target Local File Systems. Oct 29 05:10:44.521891 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 29 05:10:44.523357 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 29 05:10:44.523475 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 29 05:10:44.524502 systemd[1]: Starting Commit a transient machine-id on disk... Oct 29 05:10:44.526511 systemd[1]: Starting Create Volatile Files and Directories... Oct 29 05:10:44.533317 systemd-tmpfiles[871]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 29 05:10:44.534395 systemd-tmpfiles[871]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 29 05:10:44.541058 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 29 05:10:44.541780 systemd[1]: Finished Commit a transient machine-id on disk. Oct 29 05:10:44.569185 systemd-tmpfiles[871]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 05:10:44.569195 systemd-tmpfiles[871]: Skipping /boot Oct 29 05:10:44.575987 systemd-tmpfiles[871]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 05:10:44.576001 systemd-tmpfiles[871]: Skipping /boot Oct 29 05:10:44.593615 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 29 05:10:44.611786 systemd[1]: Finished Create Volatile Files and Directories. Oct 29 05:10:44.613854 systemd[1]: Starting Load Security Auditing Rules... Oct 29 05:10:44.615842 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 29 05:10:44.618302 systemd[1]: Starting Rebuild Journal Catalog... Oct 29 05:10:44.619000 audit: BPF prog-id=34 op=LOAD Oct 29 05:10:44.620854 systemd[1]: Starting Network Name Resolution... Oct 29 05:10:44.623000 audit: BPF prog-id=35 op=LOAD Oct 29 05:10:44.624790 systemd[1]: Starting Network Time Synchronization... Oct 29 05:10:44.626856 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 29 05:10:44.628455 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 29 05:10:44.629789 systemd[1]: Finished Rebuild Journal Catalog. Oct 29 05:10:44.633041 systemd[1]: Starting Update is Completed... Oct 29 05:10:44.633873 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 29 05:10:44.635277 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 29 05:10:44.637536 systemd[1]: Finished Update is Completed. Oct 29 05:10:44.648000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 29 05:10:44.648391 augenrules[893]: No rules Oct 29 05:10:44.649184 systemd[1]: Finished Load Security Auditing Rules. Oct 29 05:10:44.661582 systemd-resolved[879]: Positive Trust Anchors: Oct 29 05:10:44.661593 systemd-resolved[879]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 05:10:44.661620 systemd-resolved[879]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 29 05:10:44.661929 systemd[1]: Started Network Time Synchronization. Oct 29 05:10:44.662426 systemd-resolved[879]: Defaulting to hostname 'linux'. Oct 29 05:10:44.662673 systemd-timesyncd[882]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 29 05:10:44.663129 systemd[1]: Reached target System Initialization. Oct 29 05:10:44.664059 systemd[1]: Started Watch for update engine configuration changes. Oct 29 05:10:44.665048 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 05:10:44.666066 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 29 05:10:44.666950 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 29 05:10:44.666978 systemd[1]: Reached target Path Units. Oct 29 05:10:44.667640 systemd[1]: Reached target System Time Set. Oct 29 05:10:44.668439 systemd[1]: Started Daily Log Rotation. Oct 29 05:10:44.669161 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 29 05:10:44.670204 systemd[1]: Reached target Timer Units. Oct 29 05:10:44.671220 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 29 05:10:44.672823 systemd[1]: Starting Docker Socket for the API... Oct 29 05:10:44.675685 systemd[1]: Listening on OpenSSH Server Socket. Oct 29 05:10:44.676777 systemd[1]: Started Network Name Resolution. Oct 29 05:10:44.677628 systemd[1]: Listening on Docker Socket for the API. Oct 29 05:10:44.678460 systemd[1]: Reached target Network. Oct 29 05:10:44.679059 systemd[1]: Reached target Host and Network Name Lookups. Oct 29 05:10:44.679917 systemd[1]: Reached target Socket Units. Oct 29 05:10:44.680592 systemd[1]: Reached target Basic System. Oct 29 05:10:44.681295 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 29 05:10:44.681330 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 29 05:10:44.682267 systemd[1]: Started D-Bus System Message Bus. Oct 29 05:10:44.685659 systemd[1]: Starting Extend Filesystems... Oct 29 05:10:44.686471 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 29 05:10:44.687458 systemd[1]: Starting Generate /run/flatcar/motd... Oct 29 05:10:44.689192 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 29 05:10:44.691181 systemd[1]: Starting Generate sshd host keys... Oct 29 05:10:44.694353 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 29 05:10:44.694384 systemd[1]: Reached target Load system-provided cloud configs. Oct 29 05:10:44.695715 extend-filesystems[902]: Found vda Oct 29 05:10:44.696575 extend-filesystems[902]: Found vda1 Oct 29 05:10:44.697138 systemd[1]: Starting User Login Management... Oct 29 05:10:44.697395 extend-filesystems[902]: Found vda2 Oct 29 05:10:44.698136 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 29 05:10:44.698477 extend-filesystems[902]: Found vda3 Oct 29 05:10:44.698741 systemd[1]: Starting Update Engine... Oct 29 05:10:44.699331 extend-filesystems[902]: Found usr Oct 29 05:10:44.700270 extend-filesystems[902]: Found vda4 Oct 29 05:10:44.699736 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 29 05:10:44.699763 systemd[1]: Reached target Load user-provided cloud configs. Oct 29 05:10:44.701787 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 29 05:10:44.702007 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 29 05:10:44.702336 extend-filesystems[902]: Found vda6 Oct 29 05:10:44.702336 extend-filesystems[902]: Found vda7 Oct 29 05:10:44.702336 extend-filesystems[902]: Found vda9 Oct 29 05:10:44.702336 extend-filesystems[902]: Checking size of /dev/vda9 Oct 29 05:10:44.706586 systemd[1]: motdgen.service: Deactivated successfully. Oct 29 05:10:44.706864 systemd[1]: Finished Generate /run/flatcar/motd. Oct 29 05:10:44.726658 extend-filesystems[902]: Old size kept for /dev/vda9 Oct 29 05:10:44.727946 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 29 05:10:44.728168 systemd[1]: Finished Extend Filesystems. Oct 29 05:10:44.730203 update_engine[916]: I1029 05:10:44.729914 916 main.cc:89] Flatcar Update Engine starting Oct 29 05:10:44.732078 systemd[1]: Started Update Engine. Oct 29 05:10:44.732963 update_engine[916]: I1029 05:10:44.732934 916 update_check_scheduler.cc:74] Next update check in 10m14s Oct 29 05:10:44.742510 systemd[1]: Started Cluster reboot manager. Oct 29 05:10:44.744085 systemd-logind[915]: Watching system buttons on /dev/input/event0 (Power Button) Oct 29 05:10:44.744586 systemd-logind[915]: New seat seat0. Oct 29 05:10:44.747570 systemd[1]: Started User Login Management. Oct 29 05:10:44.770738 locksmithd[926]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 29 05:10:45.627443 systemd-networkd[833]: eth0: Gained IPv6LL Oct 29 05:10:45.809488 sshd_keygen[920]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 29 05:10:45.827640 systemd[1]: Finished Generate sshd host keys. Oct 29 05:10:45.841330 systemd[1]: Starting Generate /run/issue... Oct 29 05:10:45.845463 systemd[1]: issuegen.service: Deactivated successfully. Oct 29 05:10:45.845671 systemd[1]: Finished Generate /run/issue. Oct 29 05:10:45.847542 systemd[1]: Starting Permit User Sessions... Oct 29 05:10:45.852040 systemd[1]: Finished Permit User Sessions. Oct 29 05:10:45.853940 systemd[1]: Started Getty on tty1. Oct 29 05:10:45.855662 systemd[1]: Started Serial Getty on ttyAMA0. Oct 29 05:10:45.856533 systemd[1]: Reached target Login Prompts. Oct 29 05:10:45.857356 systemd[1]: Reached target Multi-User System. Oct 29 05:10:45.859085 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 29 05:10:45.863680 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 29 05:10:45.863886 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 29 05:10:45.864874 systemd[1]: Startup finished in 681ms (kernel) + 2.296s (initrd) + 4.011s (userspace) = 6.988s. Oct 29 05:10:51.499803 systemd[1]: Created slice Slice /system/sshd. Oct 29 05:10:51.501005 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47172). Oct 29 05:10:51.541595 sshd[948]: Accepted publickey for core from 10.0.0.1 port 47172 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:10:51.543661 sshd[948]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:10:51.562277 systemd[1]: Created slice User Slice of UID 500. Oct 29 05:10:51.563181 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 29 05:10:51.564897 systemd-logind[915]: New session 1 of user core. Oct 29 05:10:51.569593 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 29 05:10:51.571203 systemd[1]: Starting User Manager for UID 500... Oct 29 05:10:51.573636 systemd[951]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:10:51.619521 systemd[951]: Queued start job for default target Main User Target. Oct 29 05:10:51.619656 systemd[951]: Reached target Paths. Oct 29 05:10:51.619670 systemd[951]: Reached target Sockets. Oct 29 05:10:51.619681 systemd[951]: Reached target Timers. Oct 29 05:10:51.619691 systemd[951]: Reached target Basic System. Oct 29 05:10:51.619730 systemd[951]: Reached target Main User Target. Oct 29 05:10:51.619742 systemd[951]: Startup finished in 42ms. Oct 29 05:10:51.619917 systemd[1]: Started User Manager for UID 500. Oct 29 05:10:51.636754 systemd[1]: Started Session 1 of User core. Oct 29 05:10:51.702925 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47186). Oct 29 05:10:51.740689 sshd[960]: Accepted publickey for core from 10.0.0.1 port 47186 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:10:51.742270 sshd[960]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:10:51.745636 systemd-logind[915]: New session 2 of user core. Oct 29 05:10:51.758425 systemd[1]: Started Session 2 of User core. Oct 29 05:10:51.812231 sshd[960]: pam_unix(sshd:session): session closed for user core Oct 29 05:10:51.831125 systemd[1]: sshd@1-10.0.0.5:22-10.0.0.1:47186.service: Deactivated successfully. Oct 29 05:10:51.831874 systemd[1]: session-2.scope: Deactivated successfully. Oct 29 05:10:51.832466 systemd-logind[915]: Session 2 logged out. Waiting for processes to exit. Oct 29 05:10:51.834523 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47188). Oct 29 05:10:51.835113 systemd-logind[915]: Removed session 2. Oct 29 05:10:51.873768 sshd[966]: Accepted publickey for core from 10.0.0.1 port 47188 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:10:51.874994 sshd[966]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:10:51.878087 systemd-logind[915]: New session 3 of user core. Oct 29 05:10:51.892911 systemd[1]: Started Session 3 of User core. Oct 29 05:10:51.945024 sshd[966]: pam_unix(sshd:session): session closed for user core Oct 29 05:10:51.963488 systemd[1]: sshd@2-10.0.0.5:22-10.0.0.1:47188.service: Deactivated successfully. Oct 29 05:10:51.964296 systemd[1]: session-3.scope: Deactivated successfully. Oct 29 05:10:51.964856 systemd-logind[915]: Session 3 logged out. Waiting for processes to exit. Oct 29 05:10:51.965768 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47190). Oct 29 05:10:51.966729 systemd-logind[915]: Removed session 3. Oct 29 05:10:52.005046 sshd[972]: Accepted publickey for core from 10.0.0.1 port 47190 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:10:52.006165 sshd[972]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:10:52.008576 systemd-logind[915]: New session 4 of user core. Oct 29 05:10:52.022745 systemd[1]: Started Session 4 of User core. Oct 29 05:10:52.076088 sshd[972]: pam_unix(sshd:session): session closed for user core Oct 29 05:10:52.084039 systemd[1]: sshd@3-10.0.0.5:22-10.0.0.1:47190.service: Deactivated successfully. Oct 29 05:10:52.084683 systemd[1]: session-4.scope: Deactivated successfully. Oct 29 05:10:52.085186 systemd-logind[915]: Session 4 logged out. Waiting for processes to exit. Oct 29 05:10:52.086173 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47206). Oct 29 05:10:52.086890 systemd-logind[915]: Removed session 4. Oct 29 05:10:52.126869 sshd[978]: Accepted publickey for core from 10.0.0.1 port 47206 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:10:52.128335 sshd[978]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:10:52.131085 systemd-logind[915]: New session 5 of user core. Oct 29 05:10:52.138015 systemd[1]: Started Session 5 of User core. Oct 29 05:10:52.194473 sudo[981]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 29 05:10:52.194686 sudo[981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 05:10:52.218471 sudo[981]: pam_unix(sudo:session): session closed for user root Oct 29 05:10:52.220805 sshd[978]: pam_unix(sshd:session): session closed for user core Oct 29 05:10:52.237953 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47220). Oct 29 05:10:52.240778 dbus-daemon[901]: [system] Reloaded configuration Oct 29 05:10:52.242957 systemd[1]: sshd@4-10.0.0.5:22-10.0.0.1:47206.service: Deactivated successfully. Oct 29 05:10:52.243557 systemd[1]: session-5.scope: Deactivated successfully. Oct 29 05:10:52.244095 systemd-logind[915]: Session 5 logged out. Waiting for processes to exit. Oct 29 05:10:52.244700 systemd-logind[915]: Removed session 5. Oct 29 05:10:52.277151 sshd[984]: Accepted publickey for core from 10.0.0.1 port 47220 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:10:52.279346 sshd[984]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:10:52.281784 systemd-logind[915]: New session 6 of user core. Oct 29 05:10:52.296439 systemd[1]: Started Session 6 of User core. Oct 29 05:10:52.347985 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 29 05:10:52.348187 sudo[989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 05:10:52.350354 sudo[989]: pam_unix(sudo:session): session closed for user root Oct 29 05:10:52.353828 sudo[988]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 29 05:10:52.354030 sudo[988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 05:10:52.374770 systemd[1]: Stopping Load Security Auditing Rules... Oct 29 05:10:52.374000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 29 05:10:52.376372 auditctl[992]: No rules Oct 29 05:10:52.376701 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 05:10:52.376959 systemd[1]: Stopped Load Security Auditing Rules. Oct 29 05:10:52.378186 systemd[1]: Starting Load Security Auditing Rules... Oct 29 05:10:52.378501 kernel: kauditd_printk_skb: 5 callbacks suppressed Oct 29 05:10:52.378532 kernel: audit: type=1305 audit(1761714652.374:62): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 29 05:10:52.392564 augenrules[1009]: No rules Oct 29 05:10:52.393208 systemd[1]: Finished Load Security Auditing Rules. Oct 29 05:10:52.394851 sudo[988]: pam_unix(sudo:session): session closed for user root Oct 29 05:10:52.395990 sshd[984]: pam_unix(sshd:session): session closed for user core Oct 29 05:10:52.407847 systemd[1]: sshd@5-10.0.0.5:22-10.0.0.1:47220.service: Deactivated successfully. Oct 29 05:10:52.408446 systemd[1]: session-6.scope: Deactivated successfully. Oct 29 05:10:52.408996 systemd-logind[915]: Session 6 logged out. Waiting for processes to exit. Oct 29 05:10:52.409910 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47236). Oct 29 05:10:52.410555 systemd-logind[915]: Removed session 6. Oct 29 05:10:52.446733 sshd[1015]: Accepted publickey for core from 10.0.0.1 port 47236 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:10:52.448561 sshd[1015]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:10:52.450266 kernel: audit: type=1006 audit(1761714652.446:63): pid=1015 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=7 res=1 Oct 29 05:10:52.451177 systemd-logind[915]: New session 7 of user core. Oct 29 05:10:52.459455 systemd[1]: Started Session 7 of User core. Oct 29 05:10:52.512020 sudo[1018]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/bash -c cat >/etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem </etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem < Oct 29 05:10:55.482211 update_engine[1264]: Oct 29 05:10:55.482211 update_engine[1264]: Oct 29 05:10:55.482211 update_engine[1264]: Oct 29 05:10:55.482211 update_engine[1264]: Oct 29 05:10:55.482211 update_engine[1264]: Oct 29 05:10:55.482211 update_engine[1264]: Oct 29 05:10:55.482211 update_engine[1264]: Oct 29 05:10:55.482211 update_engine[1264]: Oct 29 05:10:55.482211 update_engine[1264]: I1029 05:10:55.482200 1264 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 29 05:10:55.482613 sshd[1278]: Accepted publickey for core from 10.0.0.1 port 47392 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:10:55.483959 sshd[1278]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:10:55.485788 update_engine[1264]: I1029 05:10:55.485740 1264 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 29 05:10:55.486103 update_engine[1264]: I1029 05:10:55.486045 1264 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 29 05:10:55.488054 systemd-logind[915]: New session 25 of user core. Oct 29 05:10:55.489444 update_engine[1264]: I1029 05:10:55.489375 1264 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 29 05:10:55.489512 update_engine[1264]: I1029 05:10:55.489496 1264 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Oct 29 05:10:55.489512 update_engine[1264]: I1029 05:10:55.489506 1264 omaha_request_action.cc:619] Omaha request response: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.489512 update_engine[1264]: Oct 29 05:10:55.491933 update_engine[1264]: I1029 05:10:55.491889 1264 omaha_request_action.cc:447] Omaha Response manifest version = Oct 29 05:10:55.491933 update_engine[1264]: I1029 05:10:55.491926 1264 omaha_request_action.cc:470] Found 1 url(s) Oct 29 05:10:55.491933 update_engine[1264]: I1029 05:10:55.491940 1264 omaha_request_action.cc:506] Processing first of 1 package(s) Oct 29 05:10:55.491933 update_engine[1264]: I1029 05:10:55.491943 1264 omaha_request_action.cc:513] Omaha Response package name = update.gz Oct 29 05:10:55.492072 update_engine[1264]: I1029 05:10:55.491947 1264 omaha_request_action.cc:529] Url0: http://10.0.0.4:34567/packages/update.gz Oct 29 05:10:55.492072 update_engine[1264]: I1029 05:10:55.491955 1264 omaha_request_action.cc:541] Payload size = 439474134 bytes Oct 29 05:10:55.492072 update_engine[1264]: I1029 05:10:55.491966 1264 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Oct 29 05:10:55.492072 update_engine[1264]: I1029 05:10:55.491977 1264 payload_state.cc:51] Resetting all persisted state as this is a new response Oct 29 05:10:55.492072 update_engine[1264]: I1029 05:10:55.491981 1264 payload_state.cc:360] Current Response Signature = Oct 29 05:10:55.492072 update_engine[1264]: NumURLs = 1 Oct 29 05:10:55.492072 update_engine[1264]: Url0 = http://10.0.0.4:34567/packages/update.gz Oct 29 05:10:55.492072 update_engine[1264]: Payload Size = 439474134 Oct 29 05:10:55.492072 update_engine[1264]: Payload Sha256 Hash = 1W6DmQyJ99PNHJd8mThbRqG9pYJ4dSvmM+KW8Iw2VW0= Oct 29 05:10:55.492072 update_engine[1264]: Is Delta Payload = 0 Oct 29 05:10:55.492072 update_engine[1264]: Max Failure Count Per Url = 10 Oct 29 05:10:55.492072 update_engine[1264]: Disable Payload Backoff = 1 Oct 29 05:10:55.492072 update_engine[1264]: I1029 05:10:55.492062 1264 payload_state.cc:381] Payload Attempt Number = 0 Oct 29 05:10:55.492301 update_engine[1264]: I1029 05:10:55.492095 1264 payload_state.cc:404] Current URL Index = 0 Oct 29 05:10:55.492301 update_engine[1264]: I1029 05:10:55.492125 1264 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Oct 29 05:10:55.492301 update_engine[1264]: I1029 05:10:55.492151 1264 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Oct 29 05:10:55.492434 update_engine[1264]: I1029 05:10:55.492396 1264 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 29 05:10:55.492486 update_engine[1264]: I1029 05:10:55.492470 1264 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Oct 29 05:10:55.492513 update_engine[1264]: I1029 05:10:55.492496 1264 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 29 05:10:55.492513 update_engine[1264]: I1029 05:10:55.492501 1264 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Oct 29 05:10:55.492554 update_engine[1264]: I1029 05:10:55.492517 1264 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Oct 29 05:10:55.492554 update_engine[1264]: E1029 05:10:55.492522 1264 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Oct 29 05:10:55.493036 update_engine[1264]: I1029 05:10:55.492995 1264 omaha_response_handler_action.cc:85] Using this install plan: Oct 29 05:10:55.493036 update_engine[1264]: I1029 05:10:55.493014 1264 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.4:34567/packages/update.gz, payload size: 439474134, payload hash: 1W6DmQyJ99PNHJd8mThbRqG9pYJ4dSvmM+KW8Iw2VW0=, partition_path: /dev/vda4, kernel_path: /boot/flatcar/vmlinuz-b, pcr_policy_path: /var/lib/update_engine/pcrs-b.zip, old_partition_path: /dev/vda3, old_kernel_path: /boot/flatcar/vmlinuz-a Oct 29 05:10:55.493124 update_engine[1264]: I1029 05:10:55.493093 1264 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Oct 29 05:10:55.494144 update_engine[1264]: I1029 05:10:55.494097 1264 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Oct 29 05:10:55.497470 systemd[1]: Started Session 25 of User core. Oct 29 05:10:55.559552 sshd[1278]: pam_unix(sshd:session): session closed for user core Oct 29 05:10:55.562723 systemd[1]: sshd@24-10.0.0.5:22-10.0.0.1:47392.service: Deactivated successfully. Oct 29 05:10:55.563384 systemd[1]: session-25.scope: Deactivated successfully. Oct 29 05:10:55.563945 systemd-logind[915]: Session 25 logged out. Waiting for processes to exit. Oct 29 05:10:55.564585 systemd-logind[915]: Removed session 25. Oct 29 05:10:57.389429 update_engine[1264]: I1029 05:10:57.387017 1264 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Oct 29 05:10:57.389429 update_engine[1264]: I1029 05:10:57.387065 1264 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Oct 29 05:10:57.448359 update_engine[1264]: E1029 05:10:57.448318 1264 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Oct 29 05:10:57.448517 update_engine[1264]: I1029 05:10:57.448506 1264 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Oct 29 05:10:57.448563 update_engine[1264]: I1029 05:10:57.448554 1264 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Oct 29 05:10:57.448615 update_engine[1264]: I1029 05:10:57.448606 1264 update_attempter.cc:302] Processing Done. Oct 29 05:10:57.448708 update_engine[1264]: E1029 05:10:57.448697 1264 update_attempter.cc:615] Update failed. Oct 29 05:10:57.448758 update_engine[1264]: I1029 05:10:57.448748 1264 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Oct 29 05:10:57.448801 update_engine[1264]: I1029 05:10:57.448792 1264 payload_state.cc:276] Incrementing the URL failure count Oct 29 05:10:57.448844 update_engine[1264]: I1029 05:10:57.448835 1264 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 29 05:10:57.449107 update_engine[1264]: I1029 05:10:57.449057 1264 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 29 05:10:57.449194 update_engine[1264]: I1029 05:10:57.449182 1264 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.4:34567/v1/update Oct 29 05:10:57.449269 update_engine[1264]: I1029 05:10:57.449257 1264 omaha_request_action.cc:269] Request: Oct 29 05:10:57.449269 update_engine[1264]: Oct 29 05:10:57.449269 update_engine[1264]: Oct 29 05:10:57.449269 update_engine[1264]: Oct 29 05:10:57.449269 update_engine[1264]: Oct 29 05:10:57.449269 update_engine[1264]: Oct 29 05:10:57.449269 update_engine[1264]: Oct 29 05:10:57.449413 update_engine[1264]: I1029 05:10:57.449404 1264 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 29 05:10:57.449538 update_engine[1264]: I1029 05:10:57.449527 1264 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 29 05:10:57.449709 update_engine[1264]: I1029 05:10:57.449693 1264 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 29 05:10:57.634028 update_engine[1264]: I1029 05:10:57.633977 1264 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 29 05:10:57.634147 update_engine[1264]: I1029 05:10:57.634070 1264 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Oct 29 05:10:57.634147 update_engine[1264]: I1029 05:10:57.634078 1264 omaha_request_action.cc:619] Omaha request response: Oct 29 05:10:57.634147 update_engine[1264]: Oct 29 05:10:57.634147 update_engine[1264]: Oct 29 05:10:57.634147 update_engine[1264]: Oct 29 05:10:57.634147 update_engine[1264]: Oct 29 05:10:57.634147 update_engine[1264]: Oct 29 05:10:57.634147 update_engine[1264]: Oct 29 05:10:57.634147 update_engine[1264]: E1029 05:10:57.634092 1264 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Oct 29 05:10:57.634147 update_engine[1264]: I1029 05:10:57.634096 1264 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 29 05:10:57.634147 update_engine[1264]: I1029 05:10:57.634099 1264 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 29 05:10:57.634147 update_engine[1264]: I1029 05:10:57.634102 1264 update_attempter.cc:302] Processing Done. Oct 29 05:10:57.634147 update_engine[1264]: I1029 05:10:57.634106 1264 update_attempter.cc:306] Error event sent. Oct 29 05:11:05.586772 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43236). Oct 29 05:11:05.626973 sshd[1305]: Accepted publickey for core from 10.0.0.1 port 43236 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:11:05.628391 sshd[1305]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:05.629074 kernel: kauditd_printk_skb: 287 callbacks suppressed Oct 29 05:11:05.629118 kernel: audit: type=1006 audit(1761714665.627:359): pid=1305 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=26 res=1 Oct 29 05:11:05.632135 systemd-logind[915]: New session 26 of user core. Oct 29 05:11:05.641608 systemd[1]: Started Session 26 of User core. Oct 29 05:11:05.698649 sshd[1305]: pam_unix(sshd:session): session closed for user core Oct 29 05:11:05.717192 systemd[1]: sshd@25-10.0.0.5:22-10.0.0.1:43236.service: Deactivated successfully. Oct 29 05:11:05.717837 systemd[1]: session-26.scope: Deactivated successfully. Oct 29 05:11:05.719003 systemd-logind[915]: Session 26 logged out. Waiting for processes to exit. Oct 29 05:11:05.720347 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43242). Oct 29 05:11:05.720871 systemd-logind[915]: Removed session 26. Oct 29 05:11:05.760002 sshd[1312]: Accepted publickey for core from 10.0.0.1 port 43242 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:11:05.761580 sshd[1312]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:05.764294 kernel: audit: type=1006 audit(1761714665.760:360): pid=1312 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=27 res=1 Oct 29 05:11:05.765036 systemd-logind[915]: New session 27 of user core. Oct 29 05:11:05.779613 systemd[1]: Started Session 27 of User core. Oct 29 05:11:05.835888 sshd[1312]: pam_unix(sshd:session): session closed for user core Oct 29 05:11:05.848216 systemd[1]: sshd@26-10.0.0.5:22-10.0.0.1:43242.service: Deactivated successfully. Oct 29 05:11:05.848740 systemd[1]: session-27.scope: Deactivated successfully. Oct 29 05:11:05.849291 systemd-logind[915]: Session 27 logged out. Waiting for processes to exit. Oct 29 05:11:05.850163 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43248). Oct 29 05:11:05.850839 systemd-logind[915]: Removed session 27. Oct 29 05:11:05.889678 sshd[1320]: Accepted publickey for core from 10.0.0.1 port 43248 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:11:05.891067 sshd[1320]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:05.893266 kernel: audit: type=1006 audit(1761714665.889:361): pid=1320 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=28 res=1 Oct 29 05:11:05.893825 systemd-logind[915]: New session 28 of user core. Oct 29 05:11:05.907583 systemd[1]: Started Session 28 of User core. Oct 29 05:11:05.956767 sshd[1320]: pam_unix(sshd:session): session closed for user core Oct 29 05:11:05.965039 systemd[1]: sshd@27-10.0.0.5:22-10.0.0.1:43248.service: Deactivated successfully. Oct 29 05:11:05.965568 systemd[1]: session-28.scope: Deactivated successfully. Oct 29 05:11:05.966073 systemd-logind[915]: Session 28 logged out. Waiting for processes to exit. Oct 29 05:11:05.967050 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43254). Oct 29 05:11:05.967781 systemd-logind[915]: Removed session 28. Oct 29 05:11:06.004190 sshd[1326]: Accepted publickey for core from 10.0.0.1 port 43254 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:11:06.006610 sshd[1326]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:06.007310 kernel: audit: type=1006 audit(1761714666.003:362): pid=1326 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=29 res=1 Oct 29 05:11:06.009058 systemd-logind[915]: New session 29 of user core. Oct 29 05:11:06.022531 systemd[1]: Started Session 29 of User core. Oct 29 05:11:06.072845 sshd[1326]: pam_unix(sshd:session): session closed for user core Oct 29 05:11:06.082708 systemd[1]: sshd@28-10.0.0.5:22-10.0.0.1:43254.service: Deactivated successfully. Oct 29 05:11:06.083173 systemd[1]: session-29.scope: Deactivated successfully. Oct 29 05:11:06.083764 systemd-logind[915]: Session 29 logged out. Waiting for processes to exit. Oct 29 05:11:06.084674 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43260). Oct 29 05:11:06.085356 systemd-logind[915]: Removed session 29. Oct 29 05:11:06.120791 sshd[1332]: Accepted publickey for core from 10.0.0.1 port 43260 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:11:06.122094 sshd[1332]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:06.124264 kernel: audit: type=1006 audit(1761714666.120:363): pid=1332 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=30 res=1 Oct 29 05:11:06.124769 systemd-logind[915]: New session 30 of user core. Oct 29 05:11:06.137354 systemd[1]: Started Session 30 of User core. Oct 29 05:11:06.191086 sudo[1336]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Oct 29 05:11:06.191309 sudo[1336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 05:11:06.196584 systemd[1]: sshd.socket: Deactivated successfully. Oct 29 05:11:06.196901 systemd[1]: Closed OpenSSH Server Socket. Oct 29 05:11:06.197778 sudo[1336]: pam_unix(sudo:session): session closed for user root Oct 29 05:11:06.201091 sudo[1335]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Oct 29 05:11:06.201307 sudo[1335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 05:11:06.214774 systemd[1]: Stopping Session 1 of User core... Oct 29 05:11:06.215776 sshd[948]: pam_unix(sshd:session): session closed for user core Oct 29 05:11:06.216018 sshd[1332]: pam_unix(sshd:session): session closed for user core Oct 29 05:11:06.216035 systemd[1]: Stopping Session 30 of User core... Oct 29 05:11:06.216766 sudo[1335]: pam_unix(sudo:session): session closed for user root Oct 29 05:11:06.219054 systemd[1]: Removed slice Slice /system/addon-config. Oct 29 05:11:06.220220 systemd[1]: Removed slice Slice /system/addon-run. Oct 29 05:11:06.221381 systemd[1]: Removed slice Slice /system/modprobe. Oct 29 05:11:06.222449 systemd[1]: Removed slice Slice /system/system-cloudinit. Oct 29 05:11:06.223365 systemd[1]: Stopped target Multi-User System. Oct 29 05:11:06.224100 systemd[1]: Stopped target Login Prompts. Oct 29 05:11:06.224923 systemd[1]: Stopped target Containers. Oct 29 05:11:06.225607 systemd[1]: Stopped target Host and Network Name Lookups. Oct 29 05:11:06.226542 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 29 05:11:06.227357 systemd[1]: Stopped target Timer Units. Oct 29 05:11:06.227992 systemd[1]: logrotate.timer: Deactivated successfully. Oct 29 05:11:06.228043 systemd[1]: Stopped Daily Log Rotation. Oct 29 05:11:06.228867 systemd[1]: mdadm.timer: Deactivated successfully. Oct 29 05:11:06.228906 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Oct 29 05:11:06.229887 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 29 05:11:06.229926 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Oct 29 05:11:06.230816 systemd[1]: Stopped target System Time Set. Oct 29 05:11:06.231533 systemd[1]: Stopped target Load user-provided cloud configs. Oct 29 05:11:06.232386 systemd[1]: Stopped target Load system-provided cloud configs. Oct 29 05:11:06.233409 systemd[1]: systemd-coredump.socket: Deactivated successfully. Oct 29 05:11:06.233519 systemd[1]: Closed Process Core Dump Socket. Oct 29 05:11:06.234911 systemd[1]: Unmounting Boot partition... Oct 29 05:11:06.235863 systemd[1]: Stopping Getty on tty1... Oct 29 05:11:06.236561 systemd[1]: Stopping Serial Getty on ttyAMA0... Oct 29 05:11:06.237465 systemd[1]: Stopping OpenSSH per-connection server daemon... Oct 29 05:11:06.238431 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:43260)... Oct 29 05:11:06.239277 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Oct 29 05:11:06.239404 systemd[1]: Stopped Commit a transient machine-id on disk. Oct 29 05:11:06.240327 systemd[1]: Stopped target First Boot Complete. Oct 29 05:11:06.242029 systemd[1]: Stopping Load/Save Random Seed... Oct 29 05:11:06.242981 systemd[1]: Stopping Update Engine... Oct 29 05:11:06.246105 systemd[1]: getty@tty1.service: Deactivated successfully. Oct 29 05:11:06.246321 systemd[1]: Stopped Getty on tty1. Oct 29 05:11:06.247368 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Oct 29 05:11:06.247552 systemd[1]: Stopped Serial Getty on ttyAMA0. Oct 29 05:11:06.248708 systemd[1]: sshd@0-10.0.0.5:22-10.0.0.1:47172.service: Deactivated successfully. Oct 29 05:11:06.248887 systemd[1]: Stopped OpenSSH per-connection server daemon. Oct 29 05:11:06.250212 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:11:06.250294 systemd[1]: update-engine.service: Failed with result 'exit-code'. Oct 29 05:11:06.250528 systemd[1]: Stopped Update Engine. Oct 29 05:11:06.251189 systemd[1]: update-engine.service: Consumed 2.401s CPU time. Oct 29 05:11:06.251434 systemd[1]: sshd@29-10.0.0.5:22-10.0.0.1:43260.service: Deactivated successfully. Oct 29 05:11:06.251609 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:43260). Oct 29 05:11:06.256952 systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 29 05:11:06.257160 systemd[1]: Stopped Load/Save Random Seed. Oct 29 05:11:06.258147 systemd[1]: session-30.scope: Deactivated successfully. Oct 29 05:11:06.258379 systemd[1]: Stopped Session 30 of User core. Oct 29 05:11:06.259291 systemd[1]: session-1.scope: Deactivated successfully. Oct 29 05:11:06.259514 systemd[1]: Stopped Session 1 of User core. Oct 29 05:11:06.260639 systemd-logind[915]: Session 1 logged out. Waiting for processes to exit. Oct 29 05:11:06.261586 systemd-logind[915]: Session 30 logged out. Waiting for processes to exit. Oct 29 05:11:06.261830 systemd[1]: Removed slice Slice /system/getty. Oct 29 05:11:06.262889 systemd[1]: Removed slice Slice /system/serial-getty. Oct 29 05:11:06.264285 systemd[1]: Removed slice Slice /system/sshd. Oct 29 05:11:06.265039 systemd[1]: sshd-keygen.service: Deactivated successfully. Oct 29 05:11:06.265139 systemd[1]: Stopped Generate sshd host keys. Oct 29 05:11:06.265942 systemd[1]: sshd-keygen.service: Consumed 1.093s CPU time. Oct 29 05:11:06.266104 systemd[1]: Stopping User Login Management... Oct 29 05:11:06.266850 systemd[1]: Stopping User Manager for UID 500... Oct 29 05:11:06.267645 systemd[951]: Stopped target Main User Target. Oct 29 05:11:06.267661 systemd[951]: Stopped target Basic System. Oct 29 05:11:06.267670 systemd[951]: Stopped target Paths. Oct 29 05:11:06.267678 systemd[951]: Stopped target Sockets. Oct 29 05:11:06.267693 systemd[951]: Reached target Shutdown. Oct 29 05:11:06.267711 systemd[951]: Stopped target Timers. Oct 29 05:11:06.267815 systemd[951]: Finished Exit the Session. Oct 29 05:11:06.267838 systemd[951]: Reached target Exit the Session. Oct 29 05:11:06.268978 systemd-logind[915]: Removed session 30. Oct 29 05:11:06.270563 systemd[1]: user@500.service: Deactivated successfully. Oct 29 05:11:06.270822 systemd[1]: Stopped User Manager for UID 500. Oct 29 05:11:06.271891 systemd[1]: boot.mount: Deactivated successfully. Oct 29 05:11:06.272133 systemd[1]: Unmounted Boot partition. Oct 29 05:11:06.273188 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Oct 29 05:11:06.273265 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Oct 29 05:11:06.274890 systemd[1]: Stopping User Runtime Directory /run/user/500... Oct 29 05:11:06.279813 systemd[1]: run-user-500.mount: Deactivated successfully. Oct 29 05:11:06.279877 systemd[1]: Unmounted /run/user/500. Oct 29 05:11:06.280943 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Oct 29 05:11:06.281137 systemd[1]: Stopped User Runtime Directory /run/user/500. Oct 29 05:11:06.282461 systemd[1]: systemd-logind.service: Deactivated successfully. Oct 29 05:11:06.282691 systemd[1]: Stopped User Login Management. Oct 29 05:11:06.283797 systemd[1]: Removed slice User Slice of UID 500. Oct 29 05:11:06.285379 systemd[1]: Stopping D-Bus System Message Bus... Oct 29 05:11:06.286908 systemd[1]: Stopping Permit User Sessions... Oct 29 05:11:06.286000 audit: BPF prog-id=62 op=UNLOAD Oct 29 05:11:06.289270 kernel: audit: type=1334 audit(1761714666.286:364): prog-id=62 op=UNLOAD Oct 29 05:11:06.290557 systemd[1]: dbus.service: Deactivated successfully. Oct 29 05:11:06.290930 systemd[1]: Stopped D-Bus System Message Bus. Oct 29 05:11:06.291954 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Oct 29 05:11:06.292270 systemd[1]: Stopped Permit User Sessions. Oct 29 05:11:06.293097 systemd[1]: Stopped target Basic System. Oct 29 05:11:06.293910 systemd[1]: Stopped target Network. Oct 29 05:11:06.294764 systemd[1]: Stopped target Path Units. Oct 29 05:11:06.295614 systemd[1]: motdgen.path: Deactivated successfully. Oct 29 05:11:06.301427 systemd[1]: Stopped Watch for update engine configuration changes. Oct 29 05:11:06.302465 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Oct 29 05:11:06.306458 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 05:11:06.307559 systemd[1]: Stopped target Remote File Systems. Oct 29 05:11:06.308389 systemd[1]: Stopped target Slice Units. Oct 29 05:11:06.309383 systemd[1]: Removed slice User and Session Slice. Oct 29 05:11:06.310196 systemd[1]: Stopped target Socket Units. Oct 29 05:11:06.310975 systemd[1]: dbus.socket: Deactivated successfully. Oct 29 05:11:06.311090 systemd[1]: Closed D-Bus System Message Bus Socket. Oct 29 05:11:06.312065 systemd[1]: docker.socket: Deactivated successfully. Oct 29 05:11:06.312396 systemd[1]: Closed Docker Socket for the API. Oct 29 05:11:06.313172 systemd[1]: Stopped target System Initialization. Oct 29 05:11:06.313994 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Oct 29 05:11:06.314172 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Oct 29 05:11:06.315164 systemd[1]: Stopped target Local Verity Protected Volumes. Oct 29 05:11:06.330573 systemd[1]: Stopping Load Security Auditing Rules... Oct 29 05:11:06.331436 systemd[1]: Stopping Network Name Resolution... Oct 29 05:11:06.332400 systemd[1]: Stopping Network Time Synchronization... Oct 29 05:11:06.333129 systemd[1]: systemd-update-done.service: Deactivated successfully. Oct 29 05:11:06.333203 systemd[1]: Stopped Update is Completed. Oct 29 05:11:06.333261 auditctl[1351]: No rules Oct 29 05:11:06.333958 systemd[1]: ldconfig.service: Deactivated successfully. Oct 29 05:11:06.334012 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Oct 29 05:11:06.334794 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Oct 29 05:11:06.334874 systemd[1]: Stopped Rebuild Hardware Database. Oct 29 05:11:06.335614 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Oct 29 05:11:06.335662 systemd[1]: Stopped Rebuild Journal Catalog. Oct 29 05:11:06.337096 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Oct 29 05:11:06.339451 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 29 05:11:06.339689 systemd[1]: Stopped Network Name Resolution. Oct 29 05:11:06.340644 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Oct 29 05:11:06.340877 systemd[1]: Stopped Network Time Synchronization. Oct 29 05:11:06.341955 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 05:11:06.342149 systemd[1]: Stopped Load Security Auditing Rules. Oct 29 05:11:06.344484 systemd[1]: Stopping Network Configuration... Oct 29 05:11:06.345288 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Oct 29 05:11:06.345488 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Oct 29 05:11:06.346877 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 29 05:11:06.346969 systemd[1]: Stopped Create Volatile Files and Directories. Oct 29 05:11:06.347978 systemd[1]: Stopped target Local File Systems. Oct 29 05:11:06.352298 systemd-networkd[833]: eth0: DHCPv6 lease lost Oct 29 05:11:06.356026 kernel: audit: type=1334 audit(1761714666.353:365): prog-id=53 op=UNLOAD Oct 29 05:11:06.356048 kernel: audit: type=1334 audit(1761714666.353:366): prog-id=54 op=UNLOAD Oct 29 05:11:06.353000 audit: BPF prog-id=53 op=UNLOAD Oct 29 05:11:06.353000 audit: BPF prog-id=54 op=UNLOAD Oct 29 05:11:06.355943 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Oct 29 05:11:06.357285 systemd[1]: Unmounting External Media Directory... Oct 29 05:11:06.358774 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Oct 29 05:11:06.360374 systemd[1]: Unmounting /run/torcx/unpack... Oct 29 05:11:06.361522 systemd[1]: Unmounting Temporary Directory /tmp... Oct 29 05:11:06.362712 systemd[1]: Unmounting /usr/share/oem... Oct 29 05:11:06.364111 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Oct 29 05:11:06.366499 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 29 05:11:06.366738 systemd[1]: Stopped Network Configuration. Oct 29 05:11:06.367615 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 29 05:11:06.367847 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Oct 29 05:11:06.368974 systemd[1]: media.mount: Deactivated successfully. Oct 29 05:11:06.369213 systemd[1]: Unmounted External Media Directory. Oct 29 05:11:06.370116 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Oct 29 05:11:06.370353 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Oct 29 05:11:06.371434 systemd[1]: tmp.mount: Deactivated successfully. Oct 29 05:11:06.371676 systemd[1]: Unmounted Temporary Directory /tmp. Oct 29 05:11:06.379462 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 29 05:11:06.379709 systemd[1]: Unmounted /usr/share/oem. Oct 29 05:11:06.380510 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 29 05:11:06.380731 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Oct 29 05:11:06.381944 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Oct 29 05:11:06.382159 systemd[1]: Unmounted /run/torcx/unpack. Oct 29 05:11:06.381000 audit: BPF prog-id=55 op=UNLOAD Oct 29 05:11:06.383160 systemd[1]: Stopped target Swaps. Oct 29 05:11:06.384261 kernel: audit: type=1334 audit(1761714666.381:367): prog-id=55 op=UNLOAD Oct 29 05:11:06.384589 systemd[1]: Reached target Unmount All Filesystems. Oct 29 05:11:06.385359 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 29 05:11:06.385417 systemd[1]: Closed Network Service Netlink Socket. Oct 29 05:11:06.386157 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Oct 29 05:11:06.386216 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Oct 29 05:11:06.387414 systemd[1]: Removed slice Slice /system/systemd-fsck. Oct 29 05:11:06.388166 systemd[1]: Stopped target Preparation for Local File Systems. Oct 29 05:11:06.389091 systemd[1]: lvm2-activation.service: Deactivated successfully. Oct 29 05:11:06.389156 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 29 05:11:06.390026 systemd[1]: Stopped target Local Encrypted Volumes. Oct 29 05:11:06.390808 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 05:11:06.390877 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 29 05:11:06.392129 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Oct 29 05:11:06.398933 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Oct 29 05:11:06.400147 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Oct 29 05:11:06.400213 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 29 05:11:06.401021 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 05:11:06.401069 systemd[1]: Stopped Apply Kernel Variables. Oct 29 05:11:06.401807 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 05:11:06.401857 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 29 05:11:06.402700 systemd[1]: systemd-sysusers.service: Deactivated successfully. Oct 29 05:11:06.402749 systemd[1]: Stopped Create System Users. Oct 29 05:11:06.403440 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Oct 29 05:11:06.403487 systemd[1]: Stopped Remount Root and Kernel File Systems. Oct 29 05:11:06.404312 systemd[1]: Reached target System Shutdown. Oct 29 05:11:06.404975 systemd[1]: Reached target Late Shutdown Services. Oct 29 05:11:06.405785 systemd[1]: systemd-reboot.service: Deactivated successfully. Oct 29 05:11:06.405827 systemd[1]: Finished System Reboot. Oct 29 05:11:06.406593 systemd[1]: Reached target System Reboot. Oct 29 05:11:06.407251 systemd[1]: Shutting down. Oct 29 05:11:06.408000 audit: BPF prog-id=64 op=UNLOAD Oct 29 05:11:06.408000 audit: BPF prog-id=63 op=UNLOAD Oct 29 05:11:06.411254 kernel: audit: type=1334 audit(1761714666.408:368): prog-id=64 op=UNLOAD Oct 29 05:11:06.410000 audit: BPF prog-id=58 op=UNLOAD Oct 29 05:11:06.410000 audit: BPF prog-id=57 op=UNLOAD Oct 29 05:11:06.410000 audit: BPF prog-id=56 op=UNLOAD Oct 29 05:11:06.410000 audit: BPF prog-id=61 op=UNLOAD Oct 29 05:11:06.410000 audit: BPF prog-id=60 op=UNLOAD Oct 29 05:11:06.410000 audit: BPF prog-id=59 op=UNLOAD Oct 29 05:11:06.464259 systemd-shutdown[1]: Syncing filesystems and block devices. Oct 29 05:11:06.471859 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Oct 29 05:11:06.471936 systemd-journald[800]: Journal stopped -- Reboot -- Oct 29 05:11:13.668328 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 29 05:11:13.668344 kernel: Linux version 5.10.107-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP PREEMPT Tue Mar 22 19:39:09 -00 2022 Oct 29 05:11:13.668352 kernel: efi: EFI v2.70 by EDK II Oct 29 05:11:13.668357 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82d018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 29 05:11:13.668362 kernel: efi: seeding entropy pool Oct 29 05:11:13.668367 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 29 05:11:13.668373 kernel: ACPI: Early table checksum verification disabled Oct 29 05:11:13.668377 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 29 05:11:13.668382 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 29 05:11:13.668387 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:11:13.668393 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:11:13.668399 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:11:13.668404 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:11:13.668409 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:11:13.668420 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:11:13.668425 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:11:13.668432 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:11:13.668437 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 05:11:13.668442 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 29 05:11:13.668447 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 29 05:11:13.668453 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 05:11:13.668458 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 29 05:11:13.668463 kernel: Zone ranges: Oct 29 05:11:13.668468 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 05:11:13.668473 kernel: DMA32 empty Oct 29 05:11:13.668478 kernel: Normal empty Oct 29 05:11:13.668483 kernel: Movable zone start for each node Oct 29 05:11:13.668489 kernel: Early memory node ranges Oct 29 05:11:13.668495 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 29 05:11:13.668500 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 29 05:11:13.668505 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 29 05:11:13.668510 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 29 05:11:13.668515 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 29 05:11:13.668520 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 29 05:11:13.668525 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 29 05:11:13.668530 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 29 05:11:13.668535 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 29 05:11:13.668541 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 29 05:11:13.668546 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 29 05:11:13.668551 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 05:11:13.668559 kernel: On node 0 totalpages: 643072 Oct 29 05:11:13.668565 kernel: DMA zone: 10048 pages used for memmap Oct 29 05:11:13.668570 kernel: DMA zone: 0 pages reserved Oct 29 05:11:13.668575 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 29 05:11:13.668581 kernel: psci: probing for conduit method from ACPI. Oct 29 05:11:13.668586 kernel: psci: PSCIv1.1 detected in firmware. Oct 29 05:11:13.668591 kernel: psci: Using standard PSCI v0.2 function IDs Oct 29 05:11:13.668597 kernel: psci: Trusted OS migration not required Oct 29 05:11:13.668603 kernel: psci: SMC Calling Convention v1.1 Oct 29 05:11:13.668609 kernel: ACPI: SRAT not present Oct 29 05:11:13.668614 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 29 05:11:13.668620 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 29 05:11:13.668625 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 29 05:11:13.668630 kernel: Detected PIPT I-cache on CPU0 Oct 29 05:11:13.668636 kernel: CPU features: detected: GIC system register CPU interface Oct 29 05:11:13.668641 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 29 05:11:13.668646 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 29 05:11:13.668652 kernel: CPU features: detected: Hardware dirty bit management Oct 29 05:11:13.668657 kernel: CPU features: detected: Spectre-v4 Oct 29 05:11:13.668664 kernel: CPU features: detected: ARM erratum 1418040 Oct 29 05:11:13.668669 kernel: CPU features: detected: Spectre-BHB Oct 29 05:11:13.668674 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 29 05:11:13.668680 kernel: Policy zone: DMA Oct 29 05:11:13.668686 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 29 05:11:13.668692 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 29 05:11:13.668698 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 29 05:11:13.668703 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 29 05:11:13.668709 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 29 05:11:13.668714 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 29 05:11:13.668720 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 29 05:11:13.668726 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 29 05:11:13.668732 kernel: rcu: RCU event tracing is enabled. Oct 29 05:11:13.668738 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 29 05:11:13.668743 kernel: Trampoline variant of Tasks RCU enabled. Oct 29 05:11:13.668749 kernel: Tracing variant of Tasks RCU enabled. Oct 29 05:11:13.668754 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 29 05:11:13.668760 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 29 05:11:13.668765 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 29 05:11:13.668771 kernel: GICv3: 256 SPIs implemented Oct 29 05:11:13.668776 kernel: GICv3: 0 Extended SPIs implemented Oct 29 05:11:13.668782 kernel: GICv3: Distributor has no Range Selector support Oct 29 05:11:13.668787 kernel: GICv3: 16 PPIs implemented Oct 29 05:11:13.668794 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 29 05:11:13.668805 kernel: ACPI: SRAT not present Oct 29 05:11:13.668810 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 29 05:11:13.668816 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 29 05:11:13.668822 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 29 05:11:13.668827 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 29 05:11:13.668833 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 29 05:11:13.668839 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 05:11:13.668845 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 29 05:11:13.668851 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 29 05:11:13.668856 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 29 05:11:13.668863 kernel: arm-pv: using stolen time PV Oct 29 05:11:13.668870 kernel: Console: colour dummy device 80x25 Oct 29 05:11:13.668876 kernel: ACPI: Core revision 20200925 Oct 29 05:11:13.668882 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 29 05:11:13.668888 kernel: pid_max: default: 32768 minimum: 301 Oct 29 05:11:13.668894 kernel: LSM: Security Framework initializing Oct 29 05:11:13.668899 kernel: SELinux: Initializing. Oct 29 05:11:13.668908 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 05:11:13.668914 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 05:11:13.668920 kernel: rcu: Hierarchical SRCU implementation. Oct 29 05:11:13.668927 kernel: Platform MSI: ITS@0x8080000 domain created Oct 29 05:11:13.668933 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 29 05:11:13.668939 kernel: Remapping and enabling EFI services. Oct 29 05:11:13.668945 kernel: smp: Bringing up secondary CPUs ... Oct 29 05:11:13.668953 kernel: Detected PIPT I-cache on CPU1 Oct 29 05:11:13.668959 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 29 05:11:13.668965 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 29 05:11:13.668972 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 05:11:13.668978 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 29 05:11:13.668984 kernel: Detected PIPT I-cache on CPU2 Oct 29 05:11:13.668991 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 29 05:11:13.668997 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 29 05:11:13.669002 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 05:11:13.669008 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 29 05:11:13.669014 kernel: Detected PIPT I-cache on CPU3 Oct 29 05:11:13.669024 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 29 05:11:13.669030 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 29 05:11:13.669037 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 05:11:13.669043 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 29 05:11:13.669049 kernel: smp: Brought up 1 node, 4 CPUs Oct 29 05:11:13.669055 kernel: SMP: Total of 4 processors activated. Oct 29 05:11:13.669061 kernel: CPU features: detected: Privileged Access Never Oct 29 05:11:13.669067 kernel: CPU features: detected: LSE atomic instructions Oct 29 05:11:13.669072 kernel: CPU features: detected: User Access Override Oct 29 05:11:13.669078 kernel: CPU features: detected: 32-bit EL0 Support Oct 29 05:11:13.669085 kernel: CPU features: detected: Common not Private translations Oct 29 05:11:13.669090 kernel: CPU features: detected: RAS Extension Support Oct 29 05:11:13.669099 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 29 05:11:13.669105 kernel: CPU features: detected: CRC32 instructions Oct 29 05:11:13.669111 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 29 05:11:13.669117 kernel: CPU: All CPU(s) started at EL1 Oct 29 05:11:13.669123 kernel: alternatives: patching kernel code Oct 29 05:11:13.669130 kernel: devtmpfs: initialized Oct 29 05:11:13.669136 kernel: KASLR enabled Oct 29 05:11:13.669142 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 29 05:11:13.669148 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 29 05:11:13.669154 kernel: pinctrl core: initialized pinctrl subsystem Oct 29 05:11:13.669160 kernel: SMBIOS 3.0.0 present. Oct 29 05:11:13.669173 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 29 05:11:13.669179 kernel: NET: Registered protocol family 16 Oct 29 05:11:13.669185 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 29 05:11:13.669191 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 29 05:11:13.669199 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 29 05:11:13.669205 kernel: audit: initializing netlink subsys (disabled) Oct 29 05:11:13.669211 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Oct 29 05:11:13.669217 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 29 05:11:13.669223 kernel: cpuidle: using governor menu Oct 29 05:11:13.669229 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 29 05:11:13.669235 kernel: ASID allocator initialised with 32768 entries Oct 29 05:11:13.669240 kernel: ACPI: bus type PCI registered Oct 29 05:11:13.669246 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 29 05:11:13.669253 kernel: Serial: AMBA PL011 UART driver Oct 29 05:11:13.669260 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 29 05:11:13.669266 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 29 05:11:13.669272 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 29 05:11:13.669278 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 29 05:11:13.669284 kernel: cryptd: max_cpu_qlen set to 1000 Oct 29 05:11:13.669290 kernel: ACPI: Added _OSI(Module Device) Oct 29 05:11:13.669296 kernel: ACPI: Added _OSI(Processor Device) Oct 29 05:11:13.669302 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 29 05:11:13.669308 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 29 05:11:13.669315 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 29 05:11:13.669321 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 29 05:11:13.669327 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 29 05:11:13.669333 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 29 05:11:13.669339 kernel: ACPI: Interpreter enabled Oct 29 05:11:13.669345 kernel: ACPI: Using GIC for interrupt routing Oct 29 05:11:13.669350 kernel: ACPI: MCFG table detected, 1 entries Oct 29 05:11:13.669356 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 29 05:11:13.669362 kernel: printk: console [ttyAMA0] enabled Oct 29 05:11:13.669369 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 29 05:11:13.669474 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 29 05:11:13.669531 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 29 05:11:13.669583 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 29 05:11:13.669634 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 29 05:11:13.669689 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 29 05:11:13.669697 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 29 05:11:13.669706 kernel: PCI host bridge to bus 0000:00 Oct 29 05:11:13.669766 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 29 05:11:13.669830 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 29 05:11:13.669885 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 29 05:11:13.669944 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 29 05:11:13.670012 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 29 05:11:13.670074 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 29 05:11:13.670133 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 29 05:11:13.670198 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 29 05:11:13.670253 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 05:11:13.670307 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 05:11:13.670360 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 29 05:11:13.670414 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 29 05:11:13.670463 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 29 05:11:13.670512 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 29 05:11:13.670560 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 29 05:11:13.670568 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 29 05:11:13.670574 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 29 05:11:13.670580 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 29 05:11:13.670586 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 29 05:11:13.670592 kernel: vgaarb: loaded Oct 29 05:11:13.670598 kernel: Registered efivars operations Oct 29 05:11:13.670604 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 29 05:11:13.670612 kernel: VFS: Disk quotas dquot_6.6.0 Oct 29 05:11:13.670618 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 29 05:11:13.670624 kernel: pnp: PnP ACPI init Oct 29 05:11:13.670685 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 29 05:11:13.670734 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 29 05:11:13.670742 kernel: pnp: PnP ACPI: found 1 devices Oct 29 05:11:13.670748 kernel: NET: Registered protocol family 2 Oct 29 05:11:13.670754 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 29 05:11:13.670762 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 29 05:11:13.670768 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 29 05:11:13.670774 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 29 05:11:13.670780 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 29 05:11:13.670786 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 05:11:13.670792 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 05:11:13.670805 kernel: NET: Registered protocol family 1 Oct 29 05:11:13.670811 kernel: PCI: CLS 0 bytes, default 64 Oct 29 05:11:13.670818 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 29 05:11:13.670825 kernel: kvm [1]: HYP mode not available Oct 29 05:11:13.670831 kernel: Initialise system trusted keyrings Oct 29 05:11:13.670837 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 29 05:11:13.670843 kernel: Key type asymmetric registered Oct 29 05:11:13.670849 kernel: Asymmetric key parser 'x509' registered Oct 29 05:11:13.670855 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 29 05:11:13.670861 kernel: io scheduler mq-deadline registered Oct 29 05:11:13.670867 kernel: io scheduler kyber registered Oct 29 05:11:13.670873 kernel: io scheduler bfq registered Oct 29 05:11:13.670879 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 29 05:11:13.670886 kernel: ACPI: Power Button [PWRB] Oct 29 05:11:13.670892 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 29 05:11:13.670950 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 29 05:11:13.670958 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 29 05:11:13.670964 kernel: msm_serial: driver initialized Oct 29 05:11:13.670970 kernel: random: fast init done Oct 29 05:11:13.670976 kernel: random: crng init done Oct 29 05:11:13.670982 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 29 05:11:13.670988 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 29 05:11:13.670995 kernel: thunder_xcv, ver 1.0 Oct 29 05:11:13.671001 kernel: thunder_bgx, ver 1.0 Oct 29 05:11:13.671007 kernel: nicpf, ver 1.0 Oct 29 05:11:13.671013 kernel: nicvf, ver 1.0 Oct 29 05:11:13.671074 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 29 05:11:13.671124 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-29T05:11:13 UTC (1761714673) Oct 29 05:11:13.671133 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 29 05:11:13.671139 kernel: NET: Registered protocol family 10 Oct 29 05:11:13.671146 kernel: Segment Routing with IPv6 Oct 29 05:11:13.671152 kernel: NET: Registered protocol family 17 Oct 29 05:11:13.671158 kernel: Key type dns_resolver registered Oct 29 05:11:13.671185 kernel: registered taskstats version 1 Oct 29 05:11:13.671191 kernel: Loading compiled-in X.509 certificates Oct 29 05:11:13.671197 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 29 05:11:13.671203 kernel: Key type ._fscrypt registered Oct 29 05:11:13.671209 kernel: Key type .fscrypt registered Oct 29 05:11:13.671215 kernel: Key type fscrypt-provisioning registered Oct 29 05:11:13.671223 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 29 05:11:13.671229 kernel: ima: Allocated hash algorithm: sha1 Oct 29 05:11:13.671235 kernel: ima: No architecture policies found Oct 29 05:11:13.671241 kernel: Freeing unused kernel memory: 34240K Oct 29 05:11:13.671247 kernel: Run /init as init process Oct 29 05:11:13.671253 kernel: with arguments: Oct 29 05:11:13.671259 kernel: /init Oct 29 05:11:13.671265 kernel: with environment: Oct 29 05:11:13.671271 kernel: HOME=/ Oct 29 05:11:13.671276 kernel: TERM=linux Oct 29 05:11:13.671283 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 29 05:11:13.671291 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 29 05:11:13.671299 systemd[1]: Detected virtualization kvm. Oct 29 05:11:13.671306 systemd[1]: Detected architecture arm64. Oct 29 05:11:13.671313 systemd[1]: Running in initial RAM disk. Oct 29 05:11:13.671319 systemd[1]: No hostname configured, using default hostname. Oct 29 05:11:13.671325 systemd[1]: Hostname set to . Oct 29 05:11:13.671333 systemd[1]: Initializing machine ID from VM UUID. Oct 29 05:11:13.671339 systemd[1]: Queued start job for default target Initrd Default Target. Oct 29 05:11:13.671346 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 05:11:13.671352 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 05:11:13.671358 systemd[1]: Reached target Path Units. Oct 29 05:11:13.671365 systemd[1]: Reached target Slice Units. Oct 29 05:11:13.671371 systemd[1]: Reached target Swaps. Oct 29 05:11:13.671377 systemd[1]: Reached target Timer Units. Oct 29 05:11:13.671385 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 29 05:11:13.671392 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 29 05:11:13.671398 systemd[1]: Listening on Journal Audit Socket. Oct 29 05:11:13.671404 systemd[1]: Listening on Journal Socket (/dev/log). Oct 29 05:11:13.671411 systemd[1]: Listening on Journal Socket. Oct 29 05:11:13.671417 systemd[1]: Listening on udev Control Socket. Oct 29 05:11:13.671423 systemd[1]: Listening on udev Kernel Socket. Oct 29 05:11:13.671430 systemd[1]: Reached target Socket Units. Oct 29 05:11:13.671436 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 29 05:11:13.671443 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 05:11:13.671450 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 29 05:11:13.671456 systemd[1]: Starting Journal Service... Oct 29 05:11:13.671463 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 05:11:13.671469 systemd[1]: Starting Apply Kernel Variables... Oct 29 05:11:13.671475 systemd[1]: Starting Setup Virtual Console... Oct 29 05:11:13.671482 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 29 05:11:13.671488 kernel: SCSI subsystem initialized Oct 29 05:11:13.671494 kernel: Loading iSCSI transport class v2.0-870. Oct 29 05:11:13.671500 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 05:11:13.671507 systemd[1]: Finished Apply Kernel Variables. Oct 29 05:11:13.671516 systemd-journald[270]: Journal started Oct 29 05:11:13.671553 systemd-journald[270]: Runtime Journal (/run/log/journal/59a3743c4545412ca2554a2733fd8526) is 6.0M, max 48.5M, 42.4M free. Oct 29 05:11:13.673737 systemd[1]: Started Journal Service. Oct 29 05:11:13.674089 systemd[1]: Finished Setup Virtual Console. Oct 29 05:11:13.675392 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 29 05:11:13.676733 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 05:11:13.679737 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 05:11:13.690984 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 29 05:11:13.692521 systemd[1]: Starting dracut cmdline hook... Oct 29 05:11:13.700809 dracut-cmdline[290]: dracut-dracut-053 Oct 29 05:11:13.702948 dracut-cmdline[290]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 29 05:11:13.757197 kernel: iscsi: registered transport (tcp) Oct 29 05:11:13.767510 kernel: iscsi: registered transport (qla4xxx) Oct 29 05:11:13.767525 kernel: QLogic iSCSI HBA Driver Oct 29 05:11:13.776745 systemd[1]: Finished dracut cmdline hook. Oct 29 05:11:13.778039 systemd[1]: Starting dracut pre-udev hook... Oct 29 05:11:13.779195 systemd[1]: Starting Open-iSCSI... Oct 29 05:11:13.781364 iscsid[376]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 29 05:11:13.781364 iscsid[376]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 29 05:11:13.781364 iscsid[376]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 29 05:11:13.781364 iscsid[376]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 29 05:11:13.781364 iscsid[376]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 29 05:11:13.790549 iscsid[376]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 29 05:11:13.792211 kernel: device-mapper: uevent: version 1.0.3 Oct 29 05:11:13.792228 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 29 05:11:13.784947 systemd[1]: Started Open-iSCSI. Oct 29 05:11:13.827182 kernel: raid6: neonx8 gen() 13725 MB/s Oct 29 05:11:13.844178 kernel: raid6: neonx8 xor() 10848 MB/s Oct 29 05:11:13.861177 kernel: raid6: neonx4 gen() 13549 MB/s Oct 29 05:11:13.878176 kernel: raid6: neonx4 xor() 11392 MB/s Oct 29 05:11:13.895181 kernel: raid6: neonx2 gen() 12985 MB/s Oct 29 05:11:13.912176 kernel: raid6: neonx2 xor() 10203 MB/s Oct 29 05:11:13.929176 kernel: raid6: neonx1 gen() 10782 MB/s Oct 29 05:11:13.946178 kernel: raid6: neonx1 xor() 8920 MB/s Oct 29 05:11:13.963176 kernel: raid6: int64x8 gen() 6817 MB/s Oct 29 05:11:13.980177 kernel: raid6: int64x8 xor() 3787 MB/s Oct 29 05:11:13.997176 kernel: raid6: int64x4 gen() 6893 MB/s Oct 29 05:11:14.014176 kernel: raid6: int64x4 xor() 3826 MB/s Oct 29 05:11:14.031177 kernel: raid6: int64x2 gen() 6164 MB/s Oct 29 05:11:14.048176 kernel: raid6: int64x2 xor() 3306 MB/s Oct 29 05:11:14.065191 kernel: raid6: int64x1 gen() 4990 MB/s Oct 29 05:11:14.082736 kernel: raid6: int64x1 xor() 2572 MB/s Oct 29 05:11:14.082756 kernel: raid6: using algorithm neonx8 gen() 13725 MB/s Oct 29 05:11:14.082771 kernel: raid6: .... xor() 10848 MB/s, rmw enabled Oct 29 05:11:14.082786 kernel: raid6: using neon recovery algorithm Oct 29 05:11:14.093227 kernel: xor: measuring software checksum speed Oct 29 05:11:14.093242 kernel: 8regs : 17152 MB/sec Oct 29 05:11:14.094180 kernel: 32regs : 20765 MB/sec Oct 29 05:11:14.095509 kernel: arm64_neon : 28977 MB/sec Oct 29 05:11:14.095523 kernel: xor: using function: arm64_neon (28977 MB/sec) Oct 29 05:11:14.139182 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 29 05:11:14.148539 systemd[1]: Finished dracut pre-udev hook. Oct 29 05:11:14.149000 audit: BPF prog-id=6 op=LOAD Oct 29 05:11:14.150000 audit: BPF prog-id=7 op=LOAD Oct 29 05:11:14.151217 kernel: audit: type=1334 audit(1761714674.149:2): prog-id=6 op=LOAD Oct 29 05:11:14.151236 kernel: audit: type=1334 audit(1761714674.150:3): prog-id=7 op=LOAD Oct 29 05:11:14.151245 kernel: audit: type=1334 audit(1761714674.151:4): prog-id=8 op=LOAD Oct 29 05:11:14.151000 audit: BPF prog-id=8 op=LOAD Oct 29 05:11:14.151538 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 05:11:14.162781 systemd-udevd[408]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 05:11:14.164453 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 05:11:14.165951 systemd[1]: Starting dracut pre-trigger hook... Oct 29 05:11:14.177415 dracut-pre-trigger[415]: rd.md=0: removing MD RAID activation Oct 29 05:11:14.199947 systemd[1]: Finished dracut pre-trigger hook. Oct 29 05:11:14.201243 systemd[1]: Starting Coldplug All udev Devices... Oct 29 05:11:14.210269 systemd-udevd[408]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 05:11:14.228843 systemd[1]: Finished Coldplug All udev Devices. Oct 29 05:11:14.230209 systemd[1]: Starting dracut initqueue hook... Oct 29 05:11:14.243209 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 29 05:11:14.243343 kernel: vda: detected capacity change from 0 to 4756340736 Oct 29 05:11:14.254466 systemd-udevd[466]: Using default interface naming scheme 'v249'. Oct 29 05:11:14.257193 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 05:11:14.274190 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (463) Oct 29 05:11:14.279089 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 29 05:11:14.281273 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 29 05:11:14.282194 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 29 05:11:14.287249 systemd[1]: Reached target Initrd Root Device. Oct 29 05:11:14.288571 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 29 05:11:14.290555 systemd[1]: Finished dracut initqueue hook. Oct 29 05:11:14.291312 systemd[1]: Reached target Preparation for Remote File Systems. Oct 29 05:11:14.292737 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 05:11:14.294233 systemd[1]: Reached target Remote File Systems. Oct 29 05:11:14.296096 systemd[1]: Starting dracut pre-mount hook... Oct 29 05:11:14.296942 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 29 05:11:14.297050 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 29 05:11:14.298227 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 05:11:14.300260 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 29 05:11:14.303714 systemd[1]: Finished dracut pre-mount hook. Oct 29 05:11:14.305144 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 29 05:11:14.312544 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 29 05:11:14.313953 systemd-fsck[504]: ROOT: clean, 640/553792 files, 39961/553472 blocks Oct 29 05:11:14.317318 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 29 05:11:14.318820 systemd[1]: Mounting /sysroot... Oct 29 05:11:14.333180 systemd[1]: Found device /dev/mapper/usr. Oct 29 05:11:14.334690 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 29 05:11:14.336824 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 29 05:11:14.344281 systemd-fsck[518]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 29 05:11:14.344281 systemd-fsck[518]: You must have r/w access to the filesystem or be root Oct 29 05:11:14.346424 systemd-fsck[515]: fsck failed with exit status 8. Oct 29 05:11:14.346430 systemd-fsck[515]: Ignoring error. Oct 29 05:11:14.347018 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 29 05:11:14.348681 systemd[1]: Mounting /sysusr/usr... Oct 29 05:11:14.356490 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 29 05:11:14.356528 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 29 05:11:14.356293 systemd[1]: Mounted /sysroot. Oct 29 05:11:14.356906 systemd[1]: Reached target Initrd Root File System. Oct 29 05:11:14.358511 systemd[1]: Starting Reload Configuration from the Real Root... Oct 29 05:11:14.363188 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 29 05:11:14.363349 systemd[1]: Mounted /sysusr/usr. Oct 29 05:11:14.364003 systemd[1]: Reached target Local File Systems. Oct 29 05:11:14.365057 systemd[1]: Reached target System Initialization. Oct 29 05:11:14.366400 systemd[1]: Reached target Basic System. Oct 29 05:11:14.368501 systemd[1]: Mounting /sysroot/usr... Oct 29 05:11:14.369113 systemd[1]: Reloading. Oct 29 05:11:14.374000 audit: BPF prog-id=6 op=UNLOAD Oct 29 05:11:14.375182 kernel: audit: type=1334 audit(1761714674.374:5): prog-id=6 op=UNLOAD Oct 29 05:11:14.376000 audit: BPF prog-id=3 op=UNLOAD Oct 29 05:11:14.381203 kernel: audit: type=1334 audit(1761714674.376:6): prog-id=3 op=UNLOAD Oct 29 05:11:14.448000 audit: BPF prog-id=9 op=LOAD Oct 29 05:11:14.448000 audit: BPF prog-id=10 op=LOAD Oct 29 05:11:14.449879 kernel: audit: type=1334 audit(1761714674.448:7): prog-id=9 op=LOAD Oct 29 05:11:14.449898 kernel: audit: type=1334 audit(1761714674.448:8): prog-id=10 op=LOAD Oct 29 05:11:14.449908 kernel: audit: type=1334 audit(1761714674.449:9): prog-id=11 op=LOAD Oct 29 05:11:14.449000 audit: BPF prog-id=11 op=LOAD Oct 29 05:11:14.450607 kernel: audit: type=1334 audit(1761714674.449:10): prog-id=7 op=UNLOAD Oct 29 05:11:14.449000 audit: BPF prog-id=7 op=UNLOAD Oct 29 05:11:14.449000 audit: BPF prog-id=8 op=UNLOAD Oct 29 05:11:14.450000 audit: BPF prog-id=12 op=LOAD Oct 29 05:11:14.451000 audit: BPF prog-id=13 op=LOAD Oct 29 05:11:14.451000 audit: BPF prog-id=14 op=LOAD Oct 29 05:11:14.451000 audit: BPF prog-id=4 op=UNLOAD Oct 29 05:11:14.451000 audit: BPF prog-id=5 op=UNLOAD Oct 29 05:11:14.452464 systemd[1]: Mounted /sysroot/usr. Oct 29 05:11:14.464772 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 29 05:11:14.464891 systemd[1]: Finished Reload Configuration from the Real Root. Oct 29 05:11:14.466531 systemd[1]: Reached target Initrd File Systems. Oct 29 05:11:14.467988 systemd[1]: Reached target Initrd Default Target. Oct 29 05:11:14.469327 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 29 05:11:14.469942 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 29 05:11:14.479687 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 29 05:11:14.481117 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 29 05:11:14.486477 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 29 05:11:14.487335 systemd[1]: Stopped target Timer Units. Oct 29 05:11:14.488533 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 29 05:11:14.488644 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 29 05:11:14.489949 systemd[1]: Stopped target Initrd Default Target. Oct 29 05:11:14.491319 systemd[1]: Stopped target Basic System. Oct 29 05:11:14.492552 systemd[1]: Stopped target Initrd Root Device. Oct 29 05:11:14.493829 systemd[1]: Stopped target Path Units. Oct 29 05:11:14.494929 systemd[1]: Stopped target Remote File Systems. Oct 29 05:11:14.496159 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 29 05:11:14.497533 systemd[1]: Stopped target Slice Units. Oct 29 05:11:14.498715 systemd[1]: Stopped target Socket Units. Oct 29 05:11:14.500025 systemd[1]: Stopped target System Initialization. Oct 29 05:11:14.501222 systemd[1]: Stopped target Local File Systems. Oct 29 05:11:14.502493 systemd[1]: Stopped target Preparation for Local File Systems. Oct 29 05:11:14.503921 systemd[1]: Stopped target Swaps. Oct 29 05:11:14.505010 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 29 05:11:14.505113 systemd[1]: Stopped dracut pre-mount hook. Oct 29 05:11:14.506475 systemd[1]: Stopped target Local Encrypted Volumes. Oct 29 05:11:14.507565 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 05:11:14.511818 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 29 05:11:14.512951 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 29 05:11:14.513055 systemd[1]: Stopped dracut initqueue hook. Oct 29 05:11:14.515780 iscsid[376]: iscsid shutting down. Oct 29 05:11:14.515314 systemd[1]: Stopping Open-iSCSI... Oct 29 05:11:14.516231 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 05:11:14.516348 systemd[1]: Stopped Apply Kernel Variables. Oct 29 05:11:14.517381 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 29 05:11:14.517469 systemd[1]: Stopped Coldplug All udev Devices. Oct 29 05:11:14.518505 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 29 05:11:14.518594 systemd[1]: Stopped dracut pre-trigger hook. Oct 29 05:11:14.519994 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 29 05:11:14.525190 systemd[1]: iscsid.service: Deactivated successfully. Oct 29 05:11:14.525294 systemd[1]: Stopped Open-iSCSI. Oct 29 05:11:14.526471 systemd[1]: iscsid.socket: Deactivated successfully. Oct 29 05:11:14.526516 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 29 05:11:14.527450 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 29 05:11:14.530430 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 29 05:11:14.530536 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 29 05:11:14.531511 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 29 05:11:14.531613 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 29 05:11:14.533587 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 29 05:11:14.533703 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 29 05:11:14.535248 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 29 05:11:14.535279 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 29 05:11:14.536503 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 29 05:11:14.536540 systemd[1]: Closed udev Control Socket. Oct 29 05:11:14.537788 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 29 05:11:14.548000 audit: BPF prog-id=9 op=UNLOAD Oct 29 05:11:14.537831 systemd[1]: Closed udev Kernel Socket. Oct 29 05:11:14.539101 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 29 05:11:14.539141 systemd[1]: Stopped dracut pre-udev hook. Oct 29 05:11:14.540472 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 29 05:11:14.540509 systemd[1]: Stopped dracut cmdline hook. Oct 29 05:11:14.541824 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 29 05:11:14.541861 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 29 05:11:14.543992 systemd[1]: Starting Cleanup udev Database... Oct 29 05:11:14.544873 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 29 05:11:14.545931 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 05:11:14.545984 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 29 05:11:14.547508 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 29 05:11:14.547551 systemd[1]: Stopped Create List of Static Device Nodes. Oct 29 05:11:14.562000 audit: BPF prog-id=12 op=UNLOAD Oct 29 05:11:14.548889 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 05:11:14.548933 systemd[1]: Stopped Setup Virtual Console. Oct 29 05:11:14.550567 systemd[1]: rngd.service: Deactivated successfully. Oct 29 05:11:14.550670 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 29 05:11:14.551875 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 29 05:11:14.551974 systemd[1]: Finished Cleanup udev Database. Oct 29 05:11:14.553194 systemd[1]: Reached target Switch Root. Oct 29 05:11:14.554860 systemd[1]: Starting Switch Root... Oct 29 05:11:14.560773 systemd[1]: Switching root. Oct 29 05:11:14.575676 systemd-journald[270]: Journal stopped Oct 29 05:11:16.622631 systemd-journald[270]: Received SIGTERM from PID 1 (systemd). Oct 29 05:11:16.622679 kernel: SELinux: policy capability network_peer_controls=1 Oct 29 05:11:16.622692 kernel: SELinux: policy capability open_perms=1 Oct 29 05:11:16.622701 kernel: SELinux: policy capability extended_socket_class=1 Oct 29 05:11:16.622713 kernel: SELinux: policy capability always_check_network=0 Oct 29 05:11:16.622723 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 29 05:11:16.622731 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 29 05:11:16.622740 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 29 05:11:16.622749 systemd[1]: Successfully loaded SELinux policy in 32.010ms. Oct 29 05:11:16.622764 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.024ms. Oct 29 05:11:16.622774 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 29 05:11:16.622795 systemd[1]: Detected virtualization kvm. Oct 29 05:11:16.622809 systemd[1]: Detected architecture arm64. Oct 29 05:11:16.622820 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 29 05:11:16.622832 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 29 05:11:16.622843 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 29 05:11:16.622852 systemd[1]: Stopped Switch Root. Oct 29 05:11:16.622862 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 29 05:11:16.622872 systemd[1]: Created slice Slice /system/addon-config. Oct 29 05:11:16.622883 systemd[1]: Created slice Slice /system/addon-run. Oct 29 05:11:16.622892 systemd[1]: Created slice Slice /system/getty. Oct 29 05:11:16.622901 systemd[1]: Created slice Slice /system/modprobe. Oct 29 05:11:16.622911 systemd[1]: Created slice Slice /system/serial-getty. Oct 29 05:11:16.622921 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 29 05:11:16.622931 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 29 05:11:16.622940 systemd[1]: Created slice User and Session Slice. Oct 29 05:11:16.622950 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 05:11:16.622959 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 29 05:11:16.622969 systemd[1]: Set up automount Boot partition Automount Point. Oct 29 05:11:16.622979 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 29 05:11:16.622989 systemd[1]: Stopped target Switch Root. Oct 29 05:11:16.622999 systemd[1]: Stopped target Initrd File Systems. Oct 29 05:11:16.623009 systemd[1]: Stopped target Initrd Root File System. Oct 29 05:11:16.623019 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 05:11:16.623029 systemd[1]: Reached target Remote File Systems. Oct 29 05:11:16.623040 systemd[1]: Reached target Slice Units. Oct 29 05:11:16.623049 systemd[1]: Reached target Swaps. Oct 29 05:11:16.623059 systemd[1]: Reached target Verify torcx succeeded. Oct 29 05:11:16.623069 systemd[1]: Reached target Local Verity Protected Volumes. Oct 29 05:11:16.623079 systemd[1]: Listening on Process Core Dump Socket. Oct 29 05:11:16.623088 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 29 05:11:16.623098 systemd[1]: Listening on Network Service Netlink Socket. Oct 29 05:11:16.623107 systemd[1]: Listening on udev Control Socket. Oct 29 05:11:16.623117 systemd[1]: Listening on udev Kernel Socket. Oct 29 05:11:16.623127 systemd[1]: Mounting Huge Pages File System... Oct 29 05:11:16.623137 systemd[1]: Mounting POSIX Message Queue File System... Oct 29 05:11:16.623146 systemd[1]: Mounting External Media Directory... Oct 29 05:11:16.623156 systemd[1]: Mounting Kernel Debug File System... Oct 29 05:11:16.623189 systemd[1]: Mounting Kernel Trace File System... Oct 29 05:11:16.623201 systemd[1]: Mounting Temporary Directory /tmp... Oct 29 05:11:16.623210 systemd[1]: Starting Create missing system files... Oct 29 05:11:16.623220 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 05:11:16.623230 systemd[1]: Starting Load Kernel Module configfs... Oct 29 05:11:16.623240 systemd[1]: Starting Load Kernel Module drm... Oct 29 05:11:16.623249 systemd[1]: Starting Load Kernel Module fuse... Oct 29 05:11:16.623263 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 29 05:11:16.623276 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 29 05:11:16.623288 systemd[1]: Stopped File System Check on Root Device. Oct 29 05:11:16.623298 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 29 05:11:16.623308 systemd[1]: Stopped systemd-fsck-usr.service. Oct 29 05:11:16.623318 systemd[1]: Stopped Journal Service. Oct 29 05:11:16.623327 kernel: fuse: init (API version 7.32) Oct 29 05:11:16.623336 systemd[1]: Starting Journal Service... Oct 29 05:11:16.623346 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 05:11:16.623355 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 29 05:11:16.623365 systemd[1]: Starting Apply Kernel Variables... Oct 29 05:11:16.623375 systemd[1]: Starting Coldplug All udev Devices... Oct 29 05:11:16.623386 systemd[1]: verity-setup.service: Deactivated successfully. Oct 29 05:11:16.623396 systemd[1]: Stopped verity-setup.service. Oct 29 05:11:16.623407 systemd-journald[694]: Journal started Oct 29 05:11:16.623444 systemd-journald[694]: Runtime Journal (/run/log/journal/59a3743c4545412ca2554a2733fd8526) is 6.0M, max 48.5M, 42.4M free. Oct 29 05:11:14.645000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 29 05:11:14.670000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 29 05:11:14.670000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 29 05:11:14.670000 audit: BPF prog-id=15 op=LOAD Oct 29 05:11:14.670000 audit: BPF prog-id=15 op=UNLOAD Oct 29 05:11:14.670000 audit: BPF prog-id=16 op=LOAD Oct 29 05:11:14.670000 audit: BPF prog-id=16 op=UNLOAD Oct 29 05:11:14.705000 audit[646]: AVC avc: denied { associate } for pid=646 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 29 05:11:16.495000 audit: BPF prog-id=17 op=LOAD Oct 29 05:11:16.495000 audit: BPF prog-id=18 op=LOAD Oct 29 05:11:16.495000 audit: BPF prog-id=19 op=LOAD Oct 29 05:11:16.495000 audit: BPF prog-id=13 op=UNLOAD Oct 29 05:11:16.495000 audit: BPF prog-id=14 op=UNLOAD Oct 29 05:11:16.514000 audit: BPF prog-id=20 op=LOAD Oct 29 05:11:16.514000 audit: BPF prog-id=17 op=UNLOAD Oct 29 05:11:16.514000 audit: BPF prog-id=21 op=LOAD Oct 29 05:11:16.514000 audit: BPF prog-id=22 op=LOAD Oct 29 05:11:16.514000 audit: BPF prog-id=18 op=UNLOAD Oct 29 05:11:16.514000 audit: BPF prog-id=19 op=UNLOAD Oct 29 05:11:16.515000 audit: BPF prog-id=23 op=LOAD Oct 29 05:11:16.515000 audit: BPF prog-id=20 op=UNLOAD Oct 29 05:11:16.515000 audit: BPF prog-id=24 op=LOAD Oct 29 05:11:16.515000 audit: BPF prog-id=25 op=LOAD Oct 29 05:11:16.515000 audit: BPF prog-id=21 op=UNLOAD Oct 29 05:11:16.515000 audit: BPF prog-id=22 op=UNLOAD Oct 29 05:11:16.524000 audit: BPF prog-id=23 op=UNLOAD Oct 29 05:11:16.611000 audit: BPF prog-id=26 op=LOAD Oct 29 05:11:16.611000 audit: BPF prog-id=27 op=LOAD Oct 29 05:11:16.611000 audit: BPF prog-id=28 op=LOAD Oct 29 05:11:16.611000 audit: BPF prog-id=24 op=UNLOAD Oct 29 05:11:16.611000 audit: BPF prog-id=25 op=UNLOAD Oct 29 05:11:16.621000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 29 05:11:16.494420 systemd[1]: Queued start job for default target Multi-User System. Oct 29 05:11:14.703700 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 29 05:11:16.516016 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 29 05:11:14.703935 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 05:11:14.703954 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 05:11:14.704208 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 29 05:11:14.704219 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 29 05:11:14.704249 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 29 05:11:14.704262 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 29 05:11:14.704536 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 29 05:11:14.704569 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 05:11:14.704587 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 05:11:14.704927 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 29 05:11:14.704957 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 29 05:11:14.704976 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 29 05:11:14.704990 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 29 05:11:14.705088 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 29 05:11:14.705102 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:14Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 29 05:11:16.408603 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:16Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 05:11:16.408935 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:16Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 05:11:16.409041 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:16Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 05:11:16.409161 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:16Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 05:11:16.409274 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:16Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 29 05:11:16.409332 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-29T05:11:16Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 29 05:11:16.626214 systemd[1]: Started Journal Service. Oct 29 05:11:16.627193 systemd[1]: Mounted Huge Pages File System. Oct 29 05:11:16.627996 systemd[1]: Mounted POSIX Message Queue File System. Oct 29 05:11:16.628927 systemd[1]: Mounted External Media Directory. Oct 29 05:11:16.629773 systemd[1]: Mounted Kernel Debug File System. Oct 29 05:11:16.630615 systemd[1]: Mounted Kernel Trace File System. Oct 29 05:11:16.631420 systemd[1]: Mounted Temporary Directory /tmp. Oct 29 05:11:16.632431 systemd[1]: Finished Create missing system files. Oct 29 05:11:16.633500 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 05:11:16.634555 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 29 05:11:16.634724 systemd[1]: Finished Load Kernel Module configfs. Oct 29 05:11:16.635709 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 05:11:16.635888 systemd[1]: Finished Load Kernel Module drm. Oct 29 05:11:16.636857 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 29 05:11:16.637023 systemd[1]: Finished Load Kernel Module fuse. Oct 29 05:11:16.638068 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 29 05:11:16.649275 systemd[1]: Finished Apply Kernel Variables. Oct 29 05:11:16.652468 systemd[1]: Mounting FUSE Control File System... Oct 29 05:11:16.654191 systemd[1]: Mounting Kernel Configuration File System... Oct 29 05:11:16.654954 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 29 05:11:16.655761 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 29 05:11:16.656812 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 29 05:11:16.657777 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 29 05:11:16.658687 systemd[1]: Starting Load/Save Random Seed... Oct 29 05:11:16.659537 systemd[1]: Condition check resulted in Create System Users being skipped. Oct 29 05:11:16.660870 systemd-journald[694]: Time spent on flushing to /var/log/journal/59a3743c4545412ca2554a2733fd8526 is 12.928ms for 667 entries. Oct 29 05:11:16.660870 systemd-journald[694]: System Journal (/var/log/journal/59a3743c4545412ca2554a2733fd8526) is 8.0M, max 203.0M, 195.0M free. Oct 29 05:11:16.660415 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 05:11:16.662937 systemd[1]: Mounted FUSE Control File System. Oct 29 05:11:16.664296 systemd[1]: Mounted Kernel Configuration File System. Oct 29 05:11:16.669805 systemd[1]: Finished Coldplug All udev Devices. Oct 29 05:11:16.671124 systemd[1]: Finished Load/Save Random Seed. Oct 29 05:11:16.686000 audit: BPF prog-id=29 op=LOAD Oct 29 05:11:16.672302 systemd[1]: Condition check resulted in First Boot Complete being skipped. Oct 29 05:11:16.687000 audit: BPF prog-id=30 op=LOAD Oct 29 05:11:16.687000 audit: BPF prog-id=31 op=LOAD Oct 29 05:11:16.687000 audit: BPF prog-id=10 op=UNLOAD Oct 29 05:11:16.687000 audit: BPF prog-id=11 op=UNLOAD Oct 29 05:11:16.684063 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 29 05:11:16.685357 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 05:11:16.687797 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 05:11:16.689138 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 29 05:11:16.711016 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 05:11:16.719278 systemd[1]: Starting Network Configuration... Oct 29 05:11:16.718000 audit: BPF prog-id=32 op=LOAD Oct 29 05:11:16.744015 systemd-udevd[714]: Using default interface naming scheme 'v249'. Oct 29 05:11:16.747692 systemd-networkd[724]: lo: Link UP Oct 29 05:11:16.747699 systemd-networkd[724]: lo: Gained carrier Oct 29 05:11:16.748035 systemd-networkd[724]: Enumeration completed Oct 29 05:11:16.748125 systemd[1]: Started Network Configuration. Oct 29 05:11:16.749230 systemd-networkd[724]: eth0: Link UP Oct 29 05:11:16.755254 systemd-networkd[724]: eth0: Gained carrier Oct 29 05:11:16.762326 systemd-networkd[724]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 29 05:11:16.765695 udevadm[706]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 29 05:11:16.796421 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 29 05:11:16.813671 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 05:11:16.822670 lvm[734]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 05:11:16.848968 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 05:11:16.850369 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 05:11:16.864129 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 05:11:16.868041 lvm[735]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 05:11:16.909813 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 05:11:16.910806 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 05:11:16.911771 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 29 05:11:16.911800 systemd[1]: Reached target Containers. Oct 29 05:11:16.931362 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 29 05:11:16.944105 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 29 05:11:16.965529 systemd[1]: Mounting /usr/share/oem... Oct 29 05:11:16.973180 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 29 05:11:16.973280 kernel: BTRFS info (device vda6): has skinny extents Oct 29 05:11:16.975418 systemd[1]: Mounted /usr/share/oem. Oct 29 05:11:16.976101 systemd[1]: Reached target Local File Systems. Oct 29 05:11:16.976955 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Oct 29 05:11:16.977265 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 29 05:11:16.977300 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 29 05:11:16.977327 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 29 05:11:17.001515 systemd[1]: Starting Create Volatile Files and Directories... Oct 29 05:11:17.007401 systemd-tmpfiles[758]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 29 05:11:17.008408 systemd-tmpfiles[758]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 29 05:11:17.038943 systemd-tmpfiles[758]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 05:11:17.038955 systemd-tmpfiles[758]: Skipping /boot Oct 29 05:11:17.044649 systemd-tmpfiles[758]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 05:11:17.044668 systemd-tmpfiles[758]: Skipping /boot Oct 29 05:11:17.072797 systemd[1]: Finished Create Volatile Files and Directories. Oct 29 05:11:17.086831 systemd[1]: Starting Load Security Auditing Rules... Oct 29 05:11:17.088981 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 29 05:11:17.090013 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Oct 29 05:11:17.090000 audit: BPF prog-id=33 op=LOAD Oct 29 05:11:17.091403 systemd[1]: Starting Network Name Resolution... Oct 29 05:11:17.092000 audit: BPF prog-id=34 op=LOAD Oct 29 05:11:17.093705 systemd[1]: Starting Network Time Synchronization... Oct 29 05:11:17.095541 systemd[1]: Condition check resulted in Update is Completed being skipped. Oct 29 05:11:17.096488 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 29 05:11:17.097749 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 29 05:11:17.098895 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 29 05:11:17.105460 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 29 05:11:17.114000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 29 05:11:17.114711 augenrules[779]: No rules Oct 29 05:11:17.116558 systemd[1]: Finished Load Security Auditing Rules. Oct 29 05:11:17.130623 systemd-resolved[762]: Positive Trust Anchors: Oct 29 05:11:17.130638 systemd-resolved[762]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 05:11:17.130668 systemd-resolved[762]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 29 05:11:17.131477 systemd-timesyncd[768]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 29 05:11:17.131518 systemd-resolved[762]: Defaulting to hostname 'linux'. Oct 29 05:11:17.132281 systemd[1]: Started Network Time Synchronization. Oct 29 05:11:17.133211 systemd[1]: Reached target System Initialization. Oct 29 05:11:17.134011 systemd[1]: Started Watch for update engine configuration changes. Oct 29 05:11:17.135000 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 05:11:17.136011 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 29 05:11:17.136885 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 29 05:11:17.136915 systemd[1]: Reached target Path Units. Oct 29 05:11:17.137559 systemd[1]: Reached target System Time Set. Oct 29 05:11:17.138410 systemd[1]: Started Daily Log Rotation. Oct 29 05:11:17.139124 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 29 05:11:17.140134 systemd[1]: Reached target Timer Units. Oct 29 05:11:17.141156 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 29 05:11:17.164275 systemd[1]: Starting Docker Socket for the API... Oct 29 05:11:17.168197 systemd[1]: Listening on OpenSSH Server Socket. Oct 29 05:11:17.169421 systemd[1]: Started Network Name Resolution. Oct 29 05:11:17.170276 systemd[1]: Listening on Docker Socket for the API. Oct 29 05:11:17.171095 systemd[1]: Reached target Network. Oct 29 05:11:17.171806 systemd[1]: Reached target Host and Network Name Lookups. Oct 29 05:11:17.172631 systemd[1]: Reached target Socket Units. Oct 29 05:11:17.173279 systemd[1]: Reached target Basic System. Oct 29 05:11:17.173940 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 29 05:11:17.173966 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 29 05:11:17.174862 systemd[1]: Started D-Bus System Message Bus. Oct 29 05:11:17.177919 systemd[1]: Starting Extend Filesystems... Oct 29 05:11:17.178706 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 29 05:11:17.179632 systemd[1]: Starting Generate /run/flatcar/motd... Oct 29 05:11:17.181394 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 29 05:11:17.184336 systemd[1]: Starting Generate sshd host keys... Oct 29 05:11:17.185028 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 29 05:11:17.185053 systemd[1]: Reached target Load system-provided cloud configs. Oct 29 05:11:17.187659 systemd[1]: Starting User Login Management... Oct 29 05:11:17.188632 extend-filesystems[788]: Found vda Oct 29 05:11:17.188677 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 29 05:11:17.189483 systemd[1]: Starting Update Engine... Oct 29 05:11:17.190283 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 29 05:11:17.190316 systemd[1]: Reached target Load user-provided cloud configs. Oct 29 05:11:17.190720 extend-filesystems[788]: Found vda1 Oct 29 05:11:17.191702 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 29 05:11:17.192485 extend-filesystems[788]: Found vda2 Oct 29 05:11:17.192485 extend-filesystems[788]: Found vda3 Oct 29 05:11:17.192485 extend-filesystems[788]: Found usr Oct 29 05:11:17.192485 extend-filesystems[788]: Found vda4 Oct 29 05:11:17.192485 extend-filesystems[788]: Found vda6 Oct 29 05:11:17.192485 extend-filesystems[788]: Found vda7 Oct 29 05:11:17.192485 extend-filesystems[788]: Found vda9 Oct 29 05:11:17.192485 extend-filesystems[788]: Checking size of /dev/vda9 Oct 29 05:11:17.192014 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 29 05:11:17.193571 systemd[1]: motdgen.service: Deactivated successfully. Oct 29 05:11:17.193773 systemd[1]: Finished Generate /run/flatcar/motd. Oct 29 05:11:17.203773 extend-filesystems[788]: Old size kept for /dev/vda9 Oct 29 05:11:17.204036 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 29 05:11:17.204298 systemd[1]: Finished Extend Filesystems. Oct 29 05:11:17.219596 systemd-logind[801]: Watching system buttons on /dev/input/event0 (Power Button) Oct 29 05:11:17.219801 systemd-logind[801]: New seat seat0. Oct 29 05:11:17.221859 systemd[1]: Started User Login Management. Oct 29 05:11:17.238516 systemd[1]: Finished Generate sshd host keys. Oct 29 05:11:17.244407 update_engine[802]: I1029 05:11:17.244130 802 main.cc:89] Flatcar Update Engine starting Oct 29 05:11:17.246430 update_engine[802]: I1029 05:11:17.244911 802 payload_state.cc:360] Current Response Signature = Oct 29 05:11:17.246430 update_engine[802]: NumURLs = 1 Oct 29 05:11:17.246430 update_engine[802]: Url0 = http://10.0.0.4:34567/packages/update.gz Oct 29 05:11:17.246430 update_engine[802]: Payload Size = 439474134 Oct 29 05:11:17.246430 update_engine[802]: Payload Sha256 Hash = 1W6DmQyJ99PNHJd8mThbRqG9pYJ4dSvmM+KW8Iw2VW0= Oct 29 05:11:17.246430 update_engine[802]: Is Delta Payload = 0 Oct 29 05:11:17.246430 update_engine[802]: Max Failure Count Per Url = 10 Oct 29 05:11:17.246430 update_engine[802]: Disable Payload Backoff = 1 Oct 29 05:11:17.246430 update_engine[802]: I1029 05:11:17.245093 802 payload_state.cc:381] Payload Attempt Number = 0 Oct 29 05:11:17.246430 update_engine[802]: I1029 05:11:17.245263 802 payload_state.cc:404] Current URL Index = 0 Oct 29 05:11:17.246430 update_engine[802]: I1029 05:11:17.245397 802 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 29 05:11:17.246430 update_engine[802]: I1029 05:11:17.245522 802 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 29 05:11:17.247116 systemd[1]: Starting Generate /run/issue... Oct 29 05:11:17.247938 systemd[1]: Started Update Engine. Oct 29 05:11:17.248079 update_engine[802]: I1029 05:11:17.248037 802 update_check_scheduler.cc:74] Next update check in 10m42s Oct 29 05:11:17.251447 systemd[1]: issuegen.service: Deactivated successfully. Oct 29 05:11:17.251663 systemd[1]: Finished Generate /run/issue. Oct 29 05:11:17.253466 systemd[1]: Starting Permit User Sessions... Oct 29 05:11:17.257713 systemd[1]: Finished Permit User Sessions. Oct 29 05:11:17.259523 systemd[1]: Started Getty on tty1. Oct 29 05:11:17.261304 systemd[1]: Started Serial Getty on ttyAMA0. Oct 29 05:11:17.262133 systemd[1]: Reached target Login Prompts. Oct 29 05:11:17.262947 systemd[1]: Reached target Multi-User System. Oct 29 05:11:17.264826 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 29 05:11:17.269280 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 29 05:11:17.269481 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 29 05:11:17.270344 systemd[1]: Startup finished in 690ms (kernel) + 1.053s (initrd) + 2.658s (userspace) = 4.402s. Oct 29 05:11:17.956744 systemd-networkd[724]: eth0: Gained IPv6LL Oct 29 05:11:27.227372 systemd[1]: Created slice Slice /system/sshd. Oct 29 05:11:27.228562 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49644). Oct 29 05:11:27.269960 sshd[829]: Accepted publickey for core from 10.0.0.1 port 49644 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:11:27.271924 sshd[829]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:27.299792 systemd[1]: Created slice User Slice of UID 500. Oct 29 05:11:27.300873 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 29 05:11:27.302557 systemd-logind[801]: New session 1 of user core. Oct 29 05:11:27.306694 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 29 05:11:27.307784 systemd[1]: Starting User Manager for UID 500... Oct 29 05:11:27.309994 systemd[832]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:27.358033 systemd[832]: Queued start job for default target Main User Target. Oct 29 05:11:27.358171 systemd[832]: Reached target Paths. Oct 29 05:11:27.358199 systemd[832]: Reached target Sockets. Oct 29 05:11:27.358210 systemd[832]: Reached target Timers. Oct 29 05:11:27.358220 systemd[832]: Reached target Basic System. Oct 29 05:11:27.358260 systemd[832]: Reached target Main User Target. Oct 29 05:11:27.358273 systemd[832]: Startup finished in 44ms. Oct 29 05:11:27.358336 systemd[1]: Started User Manager for UID 500. Oct 29 05:11:27.370953 systemd[1]: Started Session 1 of User core. Oct 29 05:11:27.442634 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49652). Oct 29 05:11:27.484373 sshd[841]: Accepted publickey for core from 10.0.0.1 port 49652 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:11:27.485613 sshd[841]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:27.488090 systemd-logind[801]: New session 2 of user core. Oct 29 05:11:27.505341 systemd[1]: Started Session 2 of User core. Oct 29 05:11:27.558875 sshd[841]: pam_unix(sshd:session): session closed for user core Oct 29 05:11:27.571153 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49656). Oct 29 05:11:27.571598 systemd[1]: sshd@1-10.0.0.5:22-10.0.0.1:49652.service: Deactivated successfully. Oct 29 05:11:27.572213 systemd[1]: session-2.scope: Deactivated successfully. Oct 29 05:11:27.572779 systemd-logind[801]: Session 2 logged out. Waiting for processes to exit. Oct 29 05:11:27.573520 systemd-logind[801]: Removed session 2. Oct 29 05:11:27.610044 sshd[846]: Accepted publickey for core from 10.0.0.1 port 49656 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:11:27.611460 sshd[846]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:27.614242 systemd-logind[801]: New session 3 of user core. Oct 29 05:11:27.641594 systemd[1]: Started Session 3 of User core. Oct 29 05:11:27.691898 sshd[846]: pam_unix(sshd:session): session closed for user core Oct 29 05:11:27.713291 systemd[1]: sshd@2-10.0.0.5:22-10.0.0.1:49656.service: Deactivated successfully. Oct 29 05:11:27.713797 systemd[1]: session-3.scope: Deactivated successfully. Oct 29 05:11:27.714305 systemd-logind[801]: Session 3 logged out. Waiting for processes to exit. Oct 29 05:11:27.715210 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49658). Oct 29 05:11:27.715818 systemd-logind[801]: Removed session 3. Oct 29 05:11:27.751620 sshd[854]: Accepted publickey for core from 10.0.0.1 port 49658 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:11:27.752775 sshd[854]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:27.755066 systemd-logind[801]: New session 4 of user core. Oct 29 05:11:27.781800 systemd[1]: Started Session 4 of User core. Oct 29 05:11:27.836706 sshd[854]: pam_unix(sshd:session): session closed for user core Oct 29 05:11:27.847701 systemd[1]: sshd@3-10.0.0.5:22-10.0.0.1:49658.service: Deactivated successfully. Oct 29 05:11:27.848229 systemd[1]: session-4.scope: Deactivated successfully. Oct 29 05:11:27.848788 systemd-logind[801]: Session 4 logged out. Waiting for processes to exit. Oct 29 05:11:27.849738 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49670). Oct 29 05:11:27.850371 systemd-logind[801]: Removed session 4. Oct 29 05:11:27.887471 sshd[860]: Accepted publickey for core from 10.0.0.1 port 49670 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:11:27.888986 sshd[860]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:27.892283 systemd-logind[801]: New session 5 of user core. Oct 29 05:11:27.906790 systemd[1]: Started Session 5 of User core. Oct 29 05:11:27.968840 sudo[863]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 29 05:11:27.969041 sudo[863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 05:11:27.979679 sudo[863]: pam_unix(sudo:session): session closed for user root Oct 29 05:11:27.981332 sshd[860]: pam_unix(sshd:session): session closed for user core Oct 29 05:11:28.012039 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49684). Oct 29 05:11:28.014949 dbus-daemon[787]: [system] Reloaded configuration Oct 29 05:11:28.017488 systemd[1]: sshd@4-10.0.0.5:22-10.0.0.1:49670.service: Deactivated successfully. Oct 29 05:11:28.018164 systemd[1]: session-5.scope: Deactivated successfully. Oct 29 05:11:28.018923 systemd-logind[801]: Session 5 logged out. Waiting for processes to exit. Oct 29 05:11:28.019735 systemd-logind[801]: Removed session 5. Oct 29 05:11:28.047732 sshd[866]: Accepted publickey for core from 10.0.0.1 port 49684 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:11:28.048799 sshd[866]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:28.051422 systemd-logind[801]: New session 6 of user core. Oct 29 05:11:28.063532 systemd[1]: Started Session 6 of User core. Oct 29 05:11:28.116858 sudo[871]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 29 05:11:28.117074 sudo[871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 05:11:28.119154 sudo[871]: pam_unix(sudo:session): session closed for user root Oct 29 05:11:28.122582 sudo[870]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 29 05:11:28.123015 sudo[870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 05:11:28.140017 systemd[1]: Stopping Load Security Auditing Rules... Oct 29 05:11:28.139000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 29 05:11:28.141288 auditctl[874]: No rules Oct 29 05:11:28.141471 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 05:11:28.141704 systemd[1]: Stopped Load Security Auditing Rules. Oct 29 05:11:28.141780 kernel: kauditd_printk_skb: 49 callbacks suppressed Oct 29 05:11:28.141803 kernel: audit: type=1305 audit(1761714688.139:60): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 29 05:11:28.142942 systemd[1]: Starting Load Security Auditing Rules... Oct 29 05:11:28.157237 augenrules[891]: No rules Oct 29 05:11:28.158129 systemd[1]: Finished Load Security Auditing Rules. Oct 29 05:11:28.158885 sudo[870]: pam_unix(sudo:session): session closed for user root Oct 29 05:11:28.159880 sshd[866]: pam_unix(sshd:session): session closed for user core Oct 29 05:11:28.177476 systemd[1]: sshd@5-10.0.0.5:22-10.0.0.1:49684.service: Deactivated successfully. Oct 29 05:11:28.178038 systemd[1]: session-6.scope: Deactivated successfully. Oct 29 05:11:28.178641 systemd-logind[801]: Session 6 logged out. Waiting for processes to exit. Oct 29 05:11:28.179528 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49694). Oct 29 05:11:28.180170 systemd-logind[801]: Removed session 6. Oct 29 05:11:28.217424 sshd[898]: Accepted publickey for core from 10.0.0.1 port 49694 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:11:28.221198 kernel: audit: type=1006 audit(1761714688.218:61): 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 29 05:11:28.221267 sshd[898]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:28.224103 systemd-logind[801]: New session 7 of user core. Oct 29 05:11:28.237986 systemd[1]: Started Session 7 of User core. Oct 29 05:11:28.288134 sshd[898]: pam_unix(sshd:session): session closed for user core Oct 29 05:11:28.306601 systemd[1]: sshd@6-10.0.0.5:22-10.0.0.1:49694.service: Deactivated successfully. Oct 29 05:11:28.307214 systemd[1]: session-7.scope: Deactivated successfully. Oct 29 05:11:28.307808 systemd-logind[801]: Session 7 logged out. Waiting for processes to exit. Oct 29 05:11:28.308681 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49696). Oct 29 05:11:28.309422 systemd-logind[801]: Removed session 7. Oct 29 05:11:28.346095 sshd[904]: Accepted publickey for core from 10.0.0.1 port 49696 ssh2: RSA SHA256:MllBGNyi0r3O961Vf47Z53omnILo0sYR6LRnXpfOuS4 Oct 29 05:11:28.347494 sshd[904]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 05:11:28.349215 kernel: audit: type=1006 audit(1761714688.345:62): 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 29 05:11:28.349864 systemd-logind[801]: New session 8 of user core. Oct 29 05:11:28.370623 systemd[1]: Started Session 8 of User core. Oct 29 05:11:28.422348 sshd[904]: pam_unix(sshd:session): session closed for user core Oct 29 05:11:28.424456 systemd[1]: sshd@7-10.0.0.5:22-10.0.0.1:49696.service: Deactivated successfully. Oct 29 05:11:28.425035 systemd[1]: session-8.scope: Deactivated successfully. Oct 29 05:11:28.425581 systemd-logind[801]: Session 8 logged out. Waiting for processes to exit. Oct 29 05:11:28.426254 systemd-logind[801]: Removed session 8.