Oct 31 14:02:31.684266 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 31 14:02:31.684283 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 31 14:02:31.684291 kernel: efi: EFI v2.70 by EDK II Oct 31 14:02:31.684296 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 31 14:02:31.684301 kernel: efi: seeding entropy pool Oct 31 14:02:31.684306 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 31 14:02:31.684312 kernel: ACPI: Early table checksum verification disabled Oct 31 14:02:31.684317 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 31 14:02:31.684322 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 31 14:02:31.684327 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:02:31.684334 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:02:31.684339 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:02:31.684344 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:02:31.684349 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:02:31.684355 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:02:31.684360 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:02:31.684366 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:02:31.684372 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:02:31.684377 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 31 14:02:31.684382 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 31 14:02:31.684388 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 14:02:31.684393 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 31 14:02:31.684398 kernel: Zone ranges: Oct 31 14:02:31.684403 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 14:02:31.684408 kernel: DMA32 empty Oct 31 14:02:31.684413 kernel: Normal empty Oct 31 14:02:31.684418 kernel: Movable zone start for each node Oct 31 14:02:31.684424 kernel: Early memory node ranges Oct 31 14:02:31.684429 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 31 14:02:31.684435 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 31 14:02:31.684440 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 31 14:02:31.684445 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 31 14:02:31.684450 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 31 14:02:31.684455 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 31 14:02:31.684461 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 31 14:02:31.684466 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 31 14:02:31.684471 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 31 14:02:31.684476 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 31 14:02:31.684481 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 31 14:02:31.684487 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 14:02:31.684495 kernel: On node 0 totalpages: 643072 Oct 31 14:02:31.684500 kernel: DMA zone: 10048 pages used for memmap Oct 31 14:02:31.684506 kernel: DMA zone: 0 pages reserved Oct 31 14:02:31.684511 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 31 14:02:31.684516 kernel: psci: probing for conduit method from ACPI. Oct 31 14:02:31.684522 kernel: psci: PSCIv1.1 detected in firmware. Oct 31 14:02:31.684527 kernel: psci: Using standard PSCI v0.2 function IDs Oct 31 14:02:31.684533 kernel: psci: Trusted OS migration not required Oct 31 14:02:31.684539 kernel: psci: SMC Calling Convention v1.1 Oct 31 14:02:31.684545 kernel: ACPI: SRAT not present Oct 31 14:02:31.684550 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 31 14:02:31.684556 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 31 14:02:31.684561 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 31 14:02:31.684567 kernel: Detected PIPT I-cache on CPU0 Oct 31 14:02:31.684572 kernel: CPU features: detected: GIC system register CPU interface Oct 31 14:02:31.684578 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 31 14:02:31.684584 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 31 14:02:31.684601 kernel: CPU features: detected: Hardware dirty bit management Oct 31 14:02:31.684607 kernel: CPU features: detected: Spectre-v4 Oct 31 14:02:31.684614 kernel: CPU features: detected: ARM erratum 1418040 Oct 31 14:02:31.684619 kernel: CPU features: detected: Spectre-BHB Oct 31 14:02:31.684625 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 31 14:02:31.684630 kernel: Policy zone: DMA Oct 31 14:02:31.684637 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 31 14:02:31.684643 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 31 14:02:31.684649 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 31 14:02:31.684654 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 31 14:02:31.684660 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 31 14:02:31.684666 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 31 14:02:31.684672 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 31 14:02:31.684678 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 31 14:02:31.684685 kernel: rcu: RCU event tracing is enabled. Oct 31 14:02:31.684691 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 31 14:02:31.684696 kernel: Trampoline variant of Tasks RCU enabled. Oct 31 14:02:31.684702 kernel: Tracing variant of Tasks RCU enabled. Oct 31 14:02:31.684707 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 31 14:02:31.684713 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 31 14:02:31.684719 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 31 14:02:31.684724 kernel: GICv3: 256 SPIs implemented Oct 31 14:02:31.684730 kernel: GICv3: 0 Extended SPIs implemented Oct 31 14:02:31.684735 kernel: GICv3: Distributor has no Range Selector support Oct 31 14:02:31.684741 kernel: GICv3: 16 PPIs implemented Oct 31 14:02:31.684747 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 31 14:02:31.684753 kernel: ACPI: SRAT not present Oct 31 14:02:31.684758 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 31 14:02:31.684764 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 31 14:02:31.684769 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 31 14:02:31.684775 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 31 14:02:31.684781 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 31 14:02:31.684787 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 14:02:31.684792 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 31 14:02:31.684798 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 31 14:02:31.684804 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 31 14:02:31.684810 kernel: arm-pv: using stolen time PV Oct 31 14:02:31.684817 kernel: Console: colour dummy device 80x25 Oct 31 14:02:31.684823 kernel: ACPI: Core revision 20200925 Oct 31 14:02:31.684829 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 31 14:02:31.684834 kernel: pid_max: default: 32768 minimum: 301 Oct 31 14:02:31.684840 kernel: LSM: Security Framework initializing Oct 31 14:02:31.684846 kernel: SELinux: Initializing. Oct 31 14:02:31.684852 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 14:02:31.684858 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 14:02:31.684864 kernel: rcu: Hierarchical SRCU implementation. Oct 31 14:02:31.684870 kernel: Platform MSI: ITS@0x8080000 domain created Oct 31 14:02:31.684876 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 31 14:02:31.684881 kernel: Remapping and enabling EFI services. Oct 31 14:02:31.684887 kernel: smp: Bringing up secondary CPUs ... Oct 31 14:02:31.684893 kernel: Detected PIPT I-cache on CPU1 Oct 31 14:02:31.684899 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 31 14:02:31.684904 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 31 14:02:31.684916 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 14:02:31.684922 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 31 14:02:31.684928 kernel: Detected PIPT I-cache on CPU2 Oct 31 14:02:31.684935 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 31 14:02:31.684941 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 31 14:02:31.684947 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 14:02:31.684953 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 31 14:02:31.684959 kernel: Detected PIPT I-cache on CPU3 Oct 31 14:02:31.684968 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 31 14:02:31.684974 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 31 14:02:31.684981 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 14:02:31.684987 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 31 14:02:31.684993 kernel: smp: Brought up 1 node, 4 CPUs Oct 31 14:02:31.684999 kernel: SMP: Total of 4 processors activated. Oct 31 14:02:31.685005 kernel: CPU features: detected: Privileged Access Never Oct 31 14:02:31.685010 kernel: CPU features: detected: LSE atomic instructions Oct 31 14:02:31.685016 kernel: CPU features: detected: User Access Override Oct 31 14:02:31.685022 kernel: CPU features: detected: 32-bit EL0 Support Oct 31 14:02:31.685028 kernel: CPU features: detected: Common not Private translations Oct 31 14:02:31.685034 kernel: CPU features: detected: RAS Extension Support Oct 31 14:02:31.685041 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 31 14:02:31.685048 kernel: CPU features: detected: CRC32 instructions Oct 31 14:02:31.685054 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 31 14:02:31.685059 kernel: CPU: All CPU(s) started at EL1 Oct 31 14:02:31.685065 kernel: alternatives: patching kernel code Oct 31 14:02:31.685072 kernel: devtmpfs: initialized Oct 31 14:02:31.685078 kernel: KASLR enabled Oct 31 14:02:31.685084 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 31 14:02:31.685091 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 31 14:02:31.685097 kernel: pinctrl core: initialized pinctrl subsystem Oct 31 14:02:31.685102 kernel: SMBIOS 3.0.0 present. Oct 31 14:02:31.685108 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 31 14:02:31.685114 kernel: NET: Registered protocol family 16 Oct 31 14:02:31.685121 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 31 14:02:31.685127 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 31 14:02:31.685134 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 31 14:02:31.685140 kernel: audit: initializing netlink subsys (disabled) Oct 31 14:02:31.685146 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Oct 31 14:02:31.685152 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 31 14:02:31.685158 kernel: cpuidle: using governor menu Oct 31 14:02:31.685164 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 31 14:02:31.685170 kernel: ASID allocator initialised with 32768 entries Oct 31 14:02:31.685176 kernel: ACPI: bus type PCI registered Oct 31 14:02:31.685182 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 31 14:02:31.685189 kernel: Serial: AMBA PL011 UART driver Oct 31 14:02:31.685195 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 31 14:02:31.685201 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 31 14:02:31.685207 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 31 14:02:31.685213 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 31 14:02:31.685219 kernel: cryptd: max_cpu_qlen set to 1000 Oct 31 14:02:31.685226 kernel: ACPI: Added _OSI(Module Device) Oct 31 14:02:31.685232 kernel: ACPI: Added _OSI(Processor Device) Oct 31 14:02:31.685238 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 31 14:02:31.685245 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 31 14:02:31.685251 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 31 14:02:31.685257 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 31 14:02:31.685263 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 31 14:02:31.685270 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 31 14:02:31.685276 kernel: ACPI: Interpreter enabled Oct 31 14:02:31.685282 kernel: ACPI: Using GIC for interrupt routing Oct 31 14:02:31.685288 kernel: ACPI: MCFG table detected, 1 entries Oct 31 14:02:31.685295 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 31 14:02:31.685301 kernel: printk: console [ttyAMA0] enabled Oct 31 14:02:31.685308 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 31 14:02:31.685407 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 31 14:02:31.685467 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 31 14:02:31.685521 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 31 14:02:31.685574 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 31 14:02:31.685637 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 31 14:02:31.685646 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 31 14:02:31.685655 kernel: PCI host bridge to bus 0000:00 Oct 31 14:02:31.685714 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 31 14:02:31.685763 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 31 14:02:31.685813 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 31 14:02:31.685860 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 31 14:02:31.685932 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 31 14:02:31.685995 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 31 14:02:31.686054 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 31 14:02:31.686110 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 31 14:02:31.686165 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 31 14:02:31.686221 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 31 14:02:31.686276 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 31 14:02:31.686332 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 31 14:02:31.686382 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 31 14:02:31.686433 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 31 14:02:31.686482 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 31 14:02:31.686490 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 31 14:02:31.686496 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 31 14:02:31.686502 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 31 14:02:31.686508 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 31 14:02:31.686514 kernel: vgaarb: loaded Oct 31 14:02:31.686520 kernel: Registered efivars operations Oct 31 14:02:31.686526 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 31 14:02:31.686534 kernel: VFS: Disk quotas dquot_6.6.0 Oct 31 14:02:31.686540 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 31 14:02:31.686546 kernel: pnp: PnP ACPI init Oct 31 14:02:31.686622 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 31 14:02:31.686674 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 31 14:02:31.686682 kernel: pnp: PnP ACPI: found 1 devices Oct 31 14:02:31.686688 kernel: NET: Registered protocol family 2 Oct 31 14:02:31.686694 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 31 14:02:31.686702 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 31 14:02:31.686709 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 31 14:02:31.686715 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 31 14:02:31.686721 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 31 14:02:31.686727 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 14:02:31.686733 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 14:02:31.686739 kernel: NET: Registered protocol family 1 Oct 31 14:02:31.686745 kernel: PCI: CLS 0 bytes, default 64 Oct 31 14:02:31.686751 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 31 14:02:31.686758 kernel: kvm [1]: HYP mode not available Oct 31 14:02:31.686764 kernel: Initialise system trusted keyrings Oct 31 14:02:31.686770 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 31 14:02:31.686776 kernel: Key type asymmetric registered Oct 31 14:02:31.686782 kernel: Asymmetric key parser 'x509' registered Oct 31 14:02:31.686788 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 31 14:02:31.686794 kernel: io scheduler mq-deadline registered Oct 31 14:02:31.686800 kernel: io scheduler kyber registered Oct 31 14:02:31.686806 kernel: io scheduler bfq registered Oct 31 14:02:31.686813 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 31 14:02:31.686819 kernel: ACPI: Power Button [PWRB] Oct 31 14:02:31.686826 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 31 14:02:31.686878 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 31 14:02:31.686887 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 31 14:02:31.686893 kernel: msm_serial: driver initialized Oct 31 14:02:31.686899 kernel: random: fast init done Oct 31 14:02:31.686905 kernel: random: crng init done Oct 31 14:02:31.686917 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 31 14:02:31.686924 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 31 14:02:31.686932 kernel: thunder_xcv, ver 1.0 Oct 31 14:02:31.686938 kernel: thunder_bgx, ver 1.0 Oct 31 14:02:31.686944 kernel: nicpf, ver 1.0 Oct 31 14:02:31.686950 kernel: nicvf, ver 1.0 Oct 31 14:02:31.687012 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 31 14:02:31.687064 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-31T14:02:31 UTC (1761919351) Oct 31 14:02:31.687073 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 31 14:02:31.687079 kernel: NET: Registered protocol family 10 Oct 31 14:02:31.687086 kernel: Segment Routing with IPv6 Oct 31 14:02:31.687092 kernel: NET: Registered protocol family 17 Oct 31 14:02:31.687098 kernel: Key type dns_resolver registered Oct 31 14:02:31.687104 kernel: registered taskstats version 1 Oct 31 14:02:31.687110 kernel: Loading compiled-in X.509 certificates Oct 31 14:02:31.687116 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 31 14:02:31.687123 kernel: Key type ._fscrypt registered Oct 31 14:02:31.687129 kernel: Key type .fscrypt registered Oct 31 14:02:31.687135 kernel: Key type fscrypt-provisioning registered Oct 31 14:02:31.687142 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 31 14:02:31.687148 kernel: ima: Allocated hash algorithm: sha1 Oct 31 14:02:31.687154 kernel: ima: No architecture policies found Oct 31 14:02:31.687160 kernel: Freeing unused kernel memory: 34240K Oct 31 14:02:31.687166 kernel: Run /init as init process Oct 31 14:02:31.687172 kernel: with arguments: Oct 31 14:02:31.687178 kernel: /init Oct 31 14:02:31.687183 kernel: with environment: Oct 31 14:02:31.687189 kernel: HOME=/ Oct 31 14:02:31.687195 kernel: TERM=linux Oct 31 14:02:31.687202 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 31 14:02:31.687210 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 31 14:02:31.687218 systemd[1]: Detected virtualization kvm. Oct 31 14:02:31.687225 systemd[1]: Detected architecture arm64. Oct 31 14:02:31.687231 systemd[1]: Running in initial RAM disk. Oct 31 14:02:31.687237 systemd[1]: No hostname configured, using default hostname. Oct 31 14:02:31.687244 systemd[1]: Hostname set to . Oct 31 14:02:31.687251 systemd[1]: Initializing machine ID from VM UUID. Oct 31 14:02:31.687258 systemd[1]: Queued start job for default target Initrd Default Target. Oct 31 14:02:31.687265 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 31 14:02:31.687271 systemd[1]: Reached target Local Encrypted Volumes. Oct 31 14:02:31.687278 systemd[1]: Reached target Path Units. Oct 31 14:02:31.687284 systemd[1]: Reached target Slice Units. Oct 31 14:02:31.687290 systemd[1]: Reached target Swaps. Oct 31 14:02:31.687297 systemd[1]: Reached target Timer Units. Oct 31 14:02:31.687305 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 31 14:02:31.687311 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 31 14:02:31.687318 systemd[1]: Listening on Journal Audit Socket. Oct 31 14:02:31.687324 systemd[1]: Listening on Journal Socket (/dev/log). Oct 31 14:02:31.687331 systemd[1]: Listening on Journal Socket. Oct 31 14:02:31.687337 systemd[1]: Listening on Network Service Netlink Socket. Oct 31 14:02:31.687344 systemd[1]: Listening on udev Control Socket. Oct 31 14:02:31.687350 systemd[1]: Listening on udev Kernel Socket. Oct 31 14:02:31.687357 systemd[1]: Reached target Socket Units. Oct 31 14:02:31.687364 systemd[1]: Starting Create List of Static Device Nodes... Oct 31 14:02:31.687370 systemd[1]: Finished Network Cleanup. Oct 31 14:02:31.687377 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 31 14:02:31.687383 systemd[1]: Starting Journal Service... Oct 31 14:02:31.687390 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 31 14:02:31.687396 systemd[1]: Starting Apply Kernel Variables... Oct 31 14:02:31.687403 systemd[1]: Starting Setup Virtual Console... Oct 31 14:02:31.687409 systemd[1]: Finished Create List of Static Device Nodes. Oct 31 14:02:31.687416 systemd[1]: Finished Apply Kernel Variables. Oct 31 14:02:31.687422 systemd[1]: Finished Setup Virtual Console. Oct 31 14:02:31.687430 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 31 14:02:31.687438 systemd-journald[278]: Journal started Oct 31 14:02:31.687470 systemd-journald[278]: Runtime Journal (/run/log/journal/d8d9977182e744b4adbf916ed31da156) is 6.0M, max 48.5M, 42.4M free. Oct 31 14:02:31.690073 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 31 14:02:31.690095 systemd[1]: Started Journal Service. Oct 31 14:02:31.691118 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 31 14:02:31.700012 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 31 14:02:31.701679 systemd[1]: Starting dracut cmdline hook... Oct 31 14:02:31.709954 dracut-cmdline[295]: dracut-dracut-053 Oct 31 14:02:31.712271 dracut-cmdline[295]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 31 14:02:31.768611 kernel: SCSI subsystem initialized Oct 31 14:02:31.771605 kernel: Loading iSCSI transport class v2.0-870. Oct 31 14:02:31.777606 kernel: iscsi: registered transport (tcp) Oct 31 14:02:31.789614 kernel: iscsi: registered transport (qla4xxx) Oct 31 14:02:31.789627 kernel: QLogic iSCSI HBA Driver Oct 31 14:02:31.798884 systemd[1]: Finished dracut cmdline hook. Oct 31 14:02:31.800299 systemd[1]: Starting dracut pre-udev hook... Oct 31 14:02:31.813272 kernel: device-mapper: uevent: version 1.0.3 Oct 31 14:02:31.813296 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 31 14:02:31.847611 kernel: raid6: neonx8 gen() 13677 MB/s Oct 31 14:02:31.864614 kernel: raid6: neonx8 xor() 10717 MB/s Oct 31 14:02:31.881603 kernel: raid6: neonx4 gen() 13573 MB/s Oct 31 14:02:31.898605 kernel: raid6: neonx4 xor() 11542 MB/s Oct 31 14:02:31.915612 kernel: raid6: neonx2 gen() 12915 MB/s Oct 31 14:02:31.932610 kernel: raid6: neonx2 xor() 9864 MB/s Oct 31 14:02:31.949608 kernel: raid6: neonx1 gen() 10873 MB/s Oct 31 14:02:31.966605 kernel: raid6: neonx1 xor() 8817 MB/s Oct 31 14:02:31.983613 kernel: raid6: int64x8 gen() 6785 MB/s Oct 31 14:02:32.000615 kernel: raid6: int64x8 xor() 3786 MB/s Oct 31 14:02:32.017626 kernel: raid6: int64x4 gen() 6906 MB/s Oct 31 14:02:32.034608 kernel: raid6: int64x4 xor() 3835 MB/s Oct 31 14:02:32.051625 kernel: raid6: int64x2 gen() 6190 MB/s Oct 31 14:02:32.068621 kernel: raid6: int64x2 xor() 3312 MB/s Oct 31 14:02:32.085624 kernel: raid6: int64x1 gen() 5014 MB/s Oct 31 14:02:32.102684 kernel: raid6: int64x1 xor() 2599 MB/s Oct 31 14:02:32.102710 kernel: raid6: using algorithm neonx8 gen() 13677 MB/s Oct 31 14:02:32.102726 kernel: raid6: .... xor() 10717 MB/s, rmw enabled Oct 31 14:02:32.103756 kernel: raid6: using neon recovery algorithm Oct 31 14:02:32.114021 kernel: xor: measuring software checksum speed Oct 31 14:02:32.114056 kernel: 8regs : 17148 MB/sec Oct 31 14:02:32.115010 kernel: 32regs : 20755 MB/sec Oct 31 14:02:32.115864 kernel: arm64_neon : 28743 MB/sec Oct 31 14:02:32.115884 kernel: xor: using function: arm64_neon (28743 MB/sec) Oct 31 14:02:32.160619 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 31 14:02:32.170238 systemd[1]: Finished dracut pre-udev hook. Oct 31 14:02:32.173948 kernel: audit: type=1334 audit(1761919352.170:2): prog-id=6 op=LOAD Oct 31 14:02:32.173987 kernel: audit: type=1334 audit(1761919352.171:3): prog-id=7 op=LOAD Oct 31 14:02:32.173997 kernel: audit: type=1334 audit(1761919352.171:4): prog-id=8 op=LOAD Oct 31 14:02:32.170000 audit: BPF prog-id=6 op=LOAD Oct 31 14:02:32.171000 audit: BPF prog-id=7 op=LOAD Oct 31 14:02:32.171000 audit: BPF prog-id=8 op=LOAD Oct 31 14:02:32.172027 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 31 14:02:32.185065 systemd-udevd[416]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 31 14:02:32.186751 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 31 14:02:32.188353 systemd[1]: Starting dracut pre-trigger hook... Oct 31 14:02:32.189000 audit: BPF prog-id=9 op=LOAD Oct 31 14:02:32.193174 systemd[1]: Starting Network Configuration... Oct 31 14:02:32.193975 kernel: audit: type=1334 audit(1761919352.189:5): prog-id=9 op=LOAD Oct 31 14:02:32.199095 dracut-pre-trigger[422]: rd.md=0: removing MD RAID activation Oct 31 14:02:32.211437 systemd-networkd[434]: lo: Link UP Oct 31 14:02:32.211444 systemd-networkd[434]: lo: Gained carrier Oct 31 14:02:32.212000 audit: BPF prog-id=10 op=LOAD Oct 31 14:02:32.211746 systemd-networkd[434]: Enumeration completed Oct 31 14:02:32.215298 kernel: audit: type=1334 audit(1761919352.212:6): prog-id=10 op=LOAD Oct 31 14:02:32.211811 systemd[1]: Started Network Configuration. Oct 31 14:02:32.213867 systemd[1]: Starting Network Name Resolution... Oct 31 14:02:32.237008 systemd[1]: Finished dracut pre-trigger hook. Oct 31 14:02:32.238381 systemd[1]: Starting Coldplug All udev Devices... Oct 31 14:02:32.242558 systemd-resolved[459]: Positive Trust Anchors: Oct 31 14:02:32.242576 systemd-resolved[459]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 14:02:32.242621 systemd-resolved[459]: 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 31 14:02:32.242891 systemd-resolved[459]: Defaulting to hostname 'linux'. Oct 31 14:02:32.243480 systemd[1]: Started Network Name Resolution. Oct 31 14:02:32.245462 systemd[1]: Reached target Network. Oct 31 14:02:32.251798 systemd-udevd[416]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 31 14:02:32.252302 systemd[1]: Reached target Host and Network Name Lookups. Oct 31 14:02:32.254965 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 31 14:02:32.262748 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 31 14:02:32.264542 systemd[1]: Starting Open-iSCSI... Oct 31 14:02:32.267559 iscsid[490]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 31 14:02:32.267559 iscsid[490]: 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 31 14:02:32.267559 iscsid[490]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 31 14:02:32.267559 iscsid[490]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 31 14:02:32.267559 iscsid[490]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 31 14:02:32.278663 iscsid[490]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 31 14:02:32.271065 systemd[1]: Started Open-iSCSI. Oct 31 14:02:32.274934 systemd[1]: Finished Coldplug All udev Devices. Oct 31 14:02:32.276958 systemd[1]: Starting dracut initqueue hook... Oct 31 14:02:32.307047 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 31 14:02:32.307161 kernel: vda: detected capacity change from 0 to 4756340736 Oct 31 14:02:32.316278 systemd-udevd[469]: Using default interface naming scheme 'v249'. Oct 31 14:02:32.319666 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 14:02:32.321494 systemd-networkd[434]: eth0: Link UP Oct 31 14:02:32.346529 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 31 14:02:32.347740 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 31 14:02:32.352785 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 31 14:02:32.355097 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (485) Oct 31 14:02:32.359088 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 31 14:02:32.361803 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 31 14:02:32.362647 systemd[1]: Reached target Initrd Root Device. Oct 31 14:02:32.365238 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 31 14:02:32.366448 systemd[1]: Finished dracut initqueue hook. Oct 31 14:02:32.367896 systemd[1]: Reached target Preparation for Remote File Systems. Oct 31 14:02:32.369709 systemd[1]: Reached target Remote Encrypted Volumes. Oct 31 14:02:32.371558 systemd[1]: Reached target Remote File Systems. Oct 31 14:02:32.374489 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 14:02:32.374409 systemd[1]: Starting dracut pre-mount hook... Oct 31 14:02:32.386157 systemd[1]: Finished dracut pre-mount hook. Oct 31 14:02:33.323689 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 31 14:02:33.323806 systemd-networkd[434]: eth0: Gained carrier Oct 31 14:02:33.338966 systemd-networkd[434]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Oct 31 14:02:33.381170 disk-uuid[509]: The operation has completed successfully. Oct 31 14:02:33.382657 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 14:02:33.401070 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 31 14:02:33.401183 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 31 14:02:33.402937 systemd[1]: Starting Ignition (setup)... Oct 31 14:02:33.410606 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 31 14:02:33.410633 kernel: BTRFS info (device vda6): has skinny extents Oct 31 14:02:33.416153 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 31 14:02:33.422120 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 31 14:02:33.422231 systemd[1]: Finished Ignition (setup). Oct 31 14:02:33.424335 systemd[1]: Starting Ignition (disks)... Oct 31 14:02:33.433942 ignition[547]: Ignition v0.36.1-15-gde4e6cc9 Oct 31 14:02:33.433957 ignition[547]: Stage: disks Oct 31 14:02:33.433966 ignition[547]: reading system config file "/usr/lib/ignition/base.ign" Oct 31 14:02:33.433976 ignition[547]: no config at "/usr/lib/ignition/base.ign" Oct 31 14:02:33.434017 ignition[547]: parsed url from cmdline: "" Oct 31 14:02:33.434021 ignition[547]: no config URL provided Oct 31 14:02:33.434025 ignition[547]: reading system config file "/usr/lib/ignition/user.ign" Oct 31 14:02:33.434032 ignition[547]: no config at "/usr/lib/ignition/user.ign" Oct 31 14:02:33.434050 ignition[547]: op(1): [started] loading QEMU firmware config module Oct 31 14:02:33.434054 ignition[547]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 31 14:02:33.435017 ignition[547]: op(1): [finished] loading QEMU firmware config module Oct 31 14:02:33.448701 ignition[547]: parsing config with SHA512: e9d73172bbfb410641286fb3bf117db0c7b12d7c8c9cd3adb0c1c7182e45a56049dd54c73a49e81c5ae214e20adffb80280973a652f19e7f4237224f160a5221 Oct 31 14:02:33.450646 ignition[547]: disks: disks passed Oct 31 14:02:33.450654 ignition[547]: Ignition finished successfully Oct 31 14:02:33.451239 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 31 14:02:33.451345 systemd[1]: Finished Ignition (disks). Oct 31 14:02:33.452726 systemd[1]: Reached target Preparation for Local File Systems. Oct 31 14:02:33.454927 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 31 14:02:33.456400 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 31 14:02:33.464601 systemd-fsck[560]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 31 14:02:33.470156 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 31 14:02:33.467700 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 31 14:02:33.469371 systemd[1]: Mounting /sysroot... Oct 31 14:02:33.486126 systemd[1]: Found device /dev/mapper/usr. Oct 31 14:02:33.487488 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 31 14:02:33.488561 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 31 14:02:33.496807 systemd-fsck[577]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 31 14:02:33.496807 systemd-fsck[577]: You must have r/w access to the filesystem or be root Oct 31 14:02:33.507823 systemd-fsck[573]: fsck failed with exit status 8. Oct 31 14:02:33.512662 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 31 14:02:33.512684 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 31 14:02:33.507829 systemd-fsck[573]: Ignoring error. Oct 31 14:02:33.509129 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 31 14:02:33.513921 systemd[1]: Mounted /sysroot. Oct 31 14:02:33.514700 systemd[1]: Reached target Initrd Root File System. Oct 31 14:02:33.516814 systemd[1]: Mounting /sysusr/usr... Oct 31 14:02:33.524303 systemd[1]: Mounted /sysusr/usr. Oct 31 14:02:33.525498 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 31 14:02:33.525038 systemd[1]: Reached target Local File Systems. Oct 31 14:02:33.526250 systemd[1]: Reached target System Initialization. Oct 31 14:02:33.527644 systemd[1]: Reached target Basic System. Oct 31 14:02:33.530249 systemd[1]: Mounting /sysroot/usr... Oct 31 14:02:33.532115 systemd[1]: Mounted /sysroot/usr. Oct 31 14:02:33.533383 systemd[1]: Starting Root filesystem setup... Oct 31 14:02:33.555855 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 31 14:02:33.555979 systemd[1]: Finished Root filesystem setup. Oct 31 14:02:33.558185 systemd[1]: Starting Ignition (files)... Oct 31 14:02:33.559380 systemd[1]: Starting /sysroot/boot... Oct 31 14:02:33.561531 ignition[594]: Ignition v0.36.1-15-gde4e6cc9 Oct 31 14:02:33.561548 ignition[594]: Stage: files Oct 31 14:02:33.561557 ignition[594]: reading system config file "/usr/lib/ignition/base.ign" Oct 31 14:02:33.561566 ignition[594]: no config at "/usr/lib/ignition/base.ign" Oct 31 14:02:33.562073 ignition[594]: files: compiled without relabeling support, skipping Oct 31 14:02:33.575240 systemd[1]: Finished /sysroot/boot. Oct 31 14:02:33.579554 ignition[594]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 31 14:02:33.579565 ignition[594]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 31 14:02:33.581188 ignition[594]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 31 14:02:33.581199 ignition[594]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 31 14:02:33.584193 ignition[594]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 31 14:02:33.584220 ignition[594]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 31 14:02:33.585497 ignition[594]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 31 14:02:33.585503 ignition[594]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 31 14:02:33.585606 ignition[594]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 31 14:02:33.589312 systemd[1]: ignition-files.service: Deactivated successfully. Oct 31 14:02:33.588756 ignition[594]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 31 14:02:33.589409 systemd[1]: Finished Ignition (files). Oct 31 14:02:33.588766 ignition[594]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 31 14:02:33.590465 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 31 14:02:33.588771 ignition[594]: files: files passed Oct 31 14:02:33.591069 systemd[1]: Starting Ignition (record completion)... Oct 31 14:02:33.603099 kernel: audit: type=1334 audit(1761919353.600:7): prog-id=3 op=UNLOAD Oct 31 14:02:33.603119 kernel: audit: type=1334 audit(1761919353.600:8): prog-id=6 op=UNLOAD Oct 31 14:02:33.600000 audit: BPF prog-id=3 op=UNLOAD Oct 31 14:02:33.600000 audit: BPF prog-id=6 op=UNLOAD Oct 31 14:02:33.588774 ignition[594]: Ignition finished successfully Oct 31 14:02:33.593220 systemd[1]: Starting Reload Configuration from the Real Root... Oct 31 14:02:33.594603 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 31 14:02:33.594710 systemd[1]: Finished Ignition (record completion). Oct 31 14:02:33.598153 systemd[1]: Reloading. Oct 31 14:02:33.606000 audit: BPF prog-id=10 op=UNLOAD Oct 31 14:02:33.606000 audit: BPF prog-id=9 op=UNLOAD Oct 31 14:02:33.610665 kernel: audit: type=1334 audit(1761919353.606:9): prog-id=10 op=UNLOAD Oct 31 14:02:33.610697 kernel: audit: type=1334 audit(1761919353.606:10): prog-id=9 op=UNLOAD Oct 31 14:02:33.682000 audit: BPF prog-id=11 op=LOAD Oct 31 14:02:33.682000 audit: BPF prog-id=12 op=LOAD Oct 31 14:02:33.682000 audit: BPF prog-id=13 op=LOAD Oct 31 14:02:33.682000 audit: BPF prog-id=4 op=UNLOAD Oct 31 14:02:33.682000 audit: BPF prog-id=5 op=UNLOAD Oct 31 14:02:33.682000 audit: BPF prog-id=14 op=LOAD Oct 31 14:02:33.682000 audit: BPF prog-id=15 op=LOAD Oct 31 14:02:33.682000 audit: BPF prog-id=16 op=LOAD Oct 31 14:02:33.682000 audit: BPF prog-id=7 op=UNLOAD Oct 31 14:02:33.682000 audit: BPF prog-id=8 op=UNLOAD Oct 31 14:02:33.683000 audit: BPF prog-id=17 op=LOAD Oct 31 14:02:33.683000 audit: BPF prog-id=18 op=LOAD Oct 31 14:02:33.697226 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 31 14:02:33.697331 systemd[1]: Finished Reload Configuration from the Real Root. Oct 31 14:02:33.699232 systemd[1]: Reached target Initrd File Systems. Oct 31 14:02:33.700625 systemd[1]: Reached target Initrd Default Target. Oct 31 14:02:33.702114 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 31 14:02:33.702724 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 31 14:02:33.712443 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 31 14:02:33.713984 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 31 14:02:33.719575 systemd[1]: Stopped target Host and Network Name Lookups. Oct 31 14:02:33.720527 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 31 14:02:33.722163 systemd[1]: Stopped target Timer Units. Oct 31 14:02:33.723523 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 31 14:02:33.723641 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 31 14:02:33.724984 systemd[1]: Stopped target Initrd Default Target. Oct 31 14:02:33.726421 systemd[1]: Stopped target Basic System. Oct 31 14:02:33.727828 systemd[1]: Stopped target Initrd Root Device. Oct 31 14:02:33.729229 systemd[1]: Stopped target Path Units. Oct 31 14:02:33.730457 systemd[1]: Stopped target Remote File Systems. Oct 31 14:02:33.731861 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 31 14:02:33.733308 systemd[1]: Stopped target Slice Units. Oct 31 14:02:33.734583 systemd[1]: Stopped target Socket Units. Oct 31 14:02:33.736129 systemd[1]: Stopped target System Initialization. Oct 31 14:02:33.737570 systemd[1]: Stopped target Local File Systems. Oct 31 14:02:33.738911 systemd[1]: Stopped target Preparation for Local File Systems. Oct 31 14:02:33.740498 systemd[1]: Stopped target Swaps. Oct 31 14:02:33.741740 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 31 14:02:33.741839 systemd[1]: Stopped dracut pre-mount hook. Oct 31 14:02:33.743281 systemd[1]: Stopped target Local Encrypted Volumes. Oct 31 14:02:33.744464 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 31 14:02:33.747622 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 31 14:02:33.748840 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 31 14:02:33.748943 systemd[1]: Stopped dracut initqueue hook. Oct 31 14:02:33.751954 iscsid[490]: iscsid shutting down. Oct 31 14:02:33.752299 systemd[1]: Stopping Open-iSCSI... Oct 31 14:02:33.753742 systemd[1]: Stopping /sysroot/boot... Oct 31 14:02:33.754345 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 31 14:02:33.754467 systemd[1]: Stopped Coldplug All udev Devices. Oct 31 14:02:33.755730 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 31 14:02:33.755808 systemd[1]: Stopped dracut pre-trigger hook. Oct 31 14:02:33.758284 systemd[1]: iscsid.service: Deactivated successfully. Oct 31 14:02:33.758384 systemd[1]: Stopped Open-iSCSI. Oct 31 14:02:33.760151 systemd[1]: iscsid.socket: Deactivated successfully. Oct 31 14:02:33.760192 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 31 14:02:33.761370 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 31 14:02:33.764327 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 31 14:02:33.764423 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 31 14:02:33.765633 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 31 14:02:33.765731 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 31 14:02:33.767085 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 31 14:02:33.767179 systemd[1]: Stopped /sysroot/boot. Oct 31 14:02:33.768824 systemd[1]: Stopped target Network. Oct 31 14:02:33.769538 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 31 14:02:33.769614 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 31 14:02:33.771190 systemd[1]: Stopping Network Name Resolution... Oct 31 14:02:33.776612 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 31 14:02:33.776723 systemd[1]: Stopped Network Name Resolution. Oct 31 14:02:33.778306 systemd[1]: Stopping Network Configuration... Oct 31 14:02:33.782691 systemd-networkd[434]: eth0: DHCP lease lost Oct 31 14:02:33.783000 audit: BPF prog-id=17 op=UNLOAD Oct 31 14:02:33.790096 systemd-networkd[434]: eth0: DHCPv6 lease lost Oct 31 14:02:33.791043 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 31 14:02:33.791153 systemd[1]: Stopped Network Configuration. Oct 31 14:02:33.792796 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 31 14:02:33.792824 systemd[1]: Closed Network Service Netlink Socket. Oct 31 14:02:33.794526 systemd[1]: Stopping Network Cleanup... Oct 31 14:02:33.795369 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 31 14:02:33.795433 systemd[1]: Stopped Apply Kernel Variables. Oct 31 14:02:33.798856 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 31 14:02:33.802452 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 31 14:02:33.802565 systemd[1]: Stopped Network Cleanup. Oct 31 14:02:33.803000 audit: BPF prog-id=18 op=UNLOAD Oct 31 14:02:33.804905 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 31 14:02:33.805027 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 31 14:02:33.806866 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 31 14:02:33.806911 systemd[1]: Closed udev Control Socket. Oct 31 14:02:33.808380 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 31 14:02:33.810000 audit: BPF prog-id=14 op=UNLOAD Oct 31 14:02:33.808412 systemd[1]: Closed udev Kernel Socket. Oct 31 14:02:33.809926 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 31 14:02:33.809967 systemd[1]: Stopped dracut pre-udev hook. Oct 31 14:02:33.811265 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 31 14:02:33.811305 systemd[1]: Stopped dracut cmdline hook. Oct 31 14:02:33.812879 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 31 14:02:33.812929 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 31 14:02:33.814954 systemd[1]: Starting Cleanup udev Database... Oct 31 14:02:33.816038 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 31 14:02:33.817296 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 31 14:02:33.817352 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 31 14:02:33.818985 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 31 14:02:33.819027 systemd[1]: Stopped Create List of Static Device Nodes. Oct 31 14:02:33.820459 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 14:02:33.820502 systemd[1]: Stopped Setup Virtual Console. Oct 31 14:02:33.822254 systemd[1]: rngd.service: Deactivated successfully. Oct 31 14:02:33.822356 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 31 14:02:33.823876 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 31 14:02:33.833000 audit: BPF prog-id=11 op=UNLOAD Oct 31 14:02:33.823989 systemd[1]: Finished Cleanup udev Database. Oct 31 14:02:33.825143 systemd[1]: Reached target Switch Root. Oct 31 14:02:33.827033 systemd[1]: Starting Switch Root... Oct 31 14:02:33.831620 systemd[1]: Switching root. Oct 31 14:02:33.841990 systemd-journald[278]: Journal stopped Oct 31 14:02:35.953212 systemd-journald[278]: Received SIGTERM from PID 1 (systemd). Oct 31 14:02:35.953261 kernel: SELinux: policy capability network_peer_controls=1 Oct 31 14:02:35.953273 kernel: SELinux: policy capability open_perms=1 Oct 31 14:02:35.953283 kernel: SELinux: policy capability extended_socket_class=1 Oct 31 14:02:35.953293 kernel: SELinux: policy capability always_check_network=0 Oct 31 14:02:35.953302 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 31 14:02:35.953312 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 31 14:02:35.953321 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 31 14:02:35.953331 systemd[1]: Successfully loaded SELinux policy in 29.180ms. Oct 31 14:02:35.953348 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.224ms. Oct 31 14:02:35.953359 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 31 14:02:35.953370 systemd[1]: Detected virtualization kvm. Oct 31 14:02:35.953379 systemd[1]: Detected architecture arm64. Oct 31 14:02:35.953389 systemd[1]: Detected first boot. Oct 31 14:02:35.953398 systemd[1]: Initializing machine ID from VM UUID. Oct 31 14:02:35.953408 systemd[1]: Populated /etc with preset unit settings. Oct 31 14:02:35.953419 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 31 14:02:35.953431 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 31 14:02:35.953441 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 31 14:02:35.953451 systemd[1]: Stopped Switch Root. Oct 31 14:02:35.953460 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 31 14:02:35.953470 systemd[1]: Created slice Slice /system/addon-config. Oct 31 14:02:35.953479 systemd[1]: Created slice Slice /system/addon-run. Oct 31 14:02:35.953489 systemd[1]: Created slice Slice /system/getty. Oct 31 14:02:35.953499 systemd[1]: Created slice Slice /system/modprobe. Oct 31 14:02:35.953509 systemd[1]: Created slice Slice /system/serial-getty. Oct 31 14:02:35.953519 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 31 14:02:35.953530 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 31 14:02:35.953540 systemd[1]: Created slice User and Session Slice. Oct 31 14:02:35.953549 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 31 14:02:35.953559 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 31 14:02:35.953569 systemd[1]: Set up automount Boot partition Automount Point. Oct 31 14:02:35.953578 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 31 14:02:35.953600 systemd[1]: Stopped target Switch Root. Oct 31 14:02:35.953611 systemd[1]: Stopped target Initrd File Systems. Oct 31 14:02:35.953621 systemd[1]: Stopped target Initrd Root File System. Oct 31 14:02:35.953630 systemd[1]: Reached target Remote Encrypted Volumes. Oct 31 14:02:35.953640 systemd[1]: Reached target Remote File Systems. Oct 31 14:02:35.953650 systemd[1]: Reached target Slice Units. Oct 31 14:02:35.953659 systemd[1]: Reached target Swaps. Oct 31 14:02:35.953669 systemd[1]: Reached target Verify torcx succeeded. Oct 31 14:02:35.953678 systemd[1]: Reached target Local Verity Protected Volumes. Oct 31 14:02:35.953688 systemd[1]: Listening on Process Core Dump Socket. Oct 31 14:02:35.953698 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 31 14:02:35.953709 systemd[1]: Listening on Network Service Netlink Socket. Oct 31 14:02:35.953720 systemd[1]: Listening on udev Control Socket. Oct 31 14:02:35.953729 systemd[1]: Listening on udev Kernel Socket. Oct 31 14:02:35.953739 systemd[1]: Mounting Huge Pages File System... Oct 31 14:02:35.953748 systemd[1]: Mounting POSIX Message Queue File System... Oct 31 14:02:35.953758 systemd[1]: Mounting External Media Directory... Oct 31 14:02:35.953767 systemd[1]: Mounting Kernel Debug File System... Oct 31 14:02:35.953777 systemd[1]: Mounting Kernel Trace File System... Oct 31 14:02:35.953786 systemd[1]: Mounting Temporary Directory /tmp... Oct 31 14:02:35.953796 systemd[1]: Starting Create missing system files... Oct 31 14:02:35.953806 systemd[1]: Starting Create List of Static Device Nodes... Oct 31 14:02:35.953816 systemd[1]: Starting Load Kernel Module configfs... Oct 31 14:02:35.953825 systemd[1]: Starting Load Kernel Module drm... Oct 31 14:02:35.953835 systemd[1]: Starting Load Kernel Module fuse... Oct 31 14:02:35.953845 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 31 14:02:35.953854 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 31 14:02:35.953863 systemd[1]: Stopped File System Check on Root Device. Oct 31 14:02:35.953873 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 31 14:02:35.953883 systemd[1]: Stopped systemd-fsck-usr.service. Oct 31 14:02:35.953899 kernel: fuse: init (API version 7.32) Oct 31 14:02:35.953908 systemd[1]: Stopped Journal Service. Oct 31 14:02:35.953918 kernel: kauditd_printk_skb: 30 callbacks suppressed Oct 31 14:02:35.953926 kernel: audit: type=1334 audit(1761919355.937:41): prog-id=24 op=LOAD Oct 31 14:02:35.953935 kernel: audit: type=1334 audit(1761919355.938:42): prog-id=25 op=LOAD Oct 31 14:02:35.953944 systemd[1]: Starting Journal Service... Oct 31 14:02:35.953954 kernel: audit: type=1334 audit(1761919355.938:43): prog-id=26 op=LOAD Oct 31 14:02:35.953962 kernel: audit: type=1334 audit(1761919355.938:44): prog-id=22 op=UNLOAD Oct 31 14:02:35.953972 kernel: audit: type=1334 audit(1761919355.938:45): prog-id=23 op=UNLOAD Oct 31 14:02:35.953981 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 31 14:02:35.953991 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 31 14:02:35.954001 systemd[1]: Starting Apply Kernel Variables... Oct 31 14:02:35.954011 systemd[1]: Starting Coldplug All udev Devices... Oct 31 14:02:35.954020 kernel: audit: type=1305 audit(1761919355.951:46): op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 31 14:02:35.954030 systemd-journald[810]: Journal started Oct 31 14:02:35.954066 systemd-journald[810]: Runtime Journal (/run/log/journal/d8d9977182e744b4adbf916ed31da156) is 6.0M, max 48.5M, 42.4M free. Oct 31 14:02:33.901000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 31 14:02:33.930000 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 31 14:02:33.930000 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 31 14:02:33.930000 audit: BPF prog-id=19 op=LOAD Oct 31 14:02:33.930000 audit: BPF prog-id=19 op=UNLOAD Oct 31 14:02:33.930000 audit: BPF prog-id=20 op=LOAD Oct 31 14:02:33.930000 audit: BPF prog-id=20 op=UNLOAD Oct 31 14:02:33.962000 audit[760]: AVC avc: denied { associate } for pid=760 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 31 14:02:35.839000 audit: BPF prog-id=21 op=LOAD Oct 31 14:02:35.839000 audit: BPF prog-id=22 op=LOAD Oct 31 14:02:35.839000 audit: BPF prog-id=23 op=LOAD Oct 31 14:02:35.839000 audit: BPF prog-id=12 op=UNLOAD Oct 31 14:02:35.839000 audit: BPF prog-id=13 op=UNLOAD Oct 31 14:02:35.852000 audit: BPF prog-id=21 op=UNLOAD Oct 31 14:02:35.937000 audit: BPF prog-id=24 op=LOAD Oct 31 14:02:35.938000 audit: BPF prog-id=25 op=LOAD Oct 31 14:02:35.938000 audit: BPF prog-id=26 op=LOAD Oct 31 14:02:35.938000 audit: BPF prog-id=22 op=UNLOAD Oct 31 14:02:35.938000 audit: BPF prog-id=23 op=UNLOAD Oct 31 14:02:35.951000 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 31 14:02:35.829875 systemd[1]: Queued start job for default target Multi-User System. Oct 31 14:02:33.961396 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" 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 31 14:02:35.954650 systemd[1]: verity-setup.service: Deactivated successfully. Oct 31 14:02:35.840604 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 31 14:02:33.961670 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 31 14:02:33.961689 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 31 14:02:33.961810 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 31 14:02:33.961821 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 31 14:02:33.961851 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 31 14:02:33.961863 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 31 14:02:33.962062 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 31 14:02:33.962096 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 31 14:02:33.962110 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 31 14:02:33.962469 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" 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 31 14:02:33.962499 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" 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 31 14:02:33.962519 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" 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 31 14:02:33.962534 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 31 14:02:33.962551 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" 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 31 14:02:33.962565 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:33Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 31 14:02:35.657508 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:35Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 14:02:35.657796 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:35Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 14:02:35.657905 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:35Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 14:02:35.658035 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:35Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 14:02:35.658094 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:35Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 31 14:02:35.658263 /usr/lib/systemd/system-generators/torcx-generator[760]: time="2025-10-31T14:02:35Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 31 14:02:35.956295 systemd[1]: Stopped verity-setup.service. Oct 31 14:02:35.959608 systemd[1]: Started Journal Service. Oct 31 14:02:35.960046 systemd[1]: Mounted Huge Pages File System. Oct 31 14:02:35.960932 systemd[1]: Mounted POSIX Message Queue File System. Oct 31 14:02:35.961879 systemd[1]: Mounted External Media Directory. Oct 31 14:02:35.962755 systemd[1]: Mounted Kernel Debug File System. Oct 31 14:02:35.963644 systemd[1]: Mounted Kernel Trace File System. Oct 31 14:02:35.964492 systemd[1]: Mounted Temporary Directory /tmp. Oct 31 14:02:35.965677 systemd[1]: Finished Create missing system files. Oct 31 14:02:35.966806 systemd[1]: Finished Create List of Static Device Nodes. Oct 31 14:02:35.967952 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 31 14:02:35.968122 systemd[1]: Finished Load Kernel Module configfs. Oct 31 14:02:35.969159 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 14:02:35.969335 systemd[1]: Finished Load Kernel Module drm. Oct 31 14:02:35.981149 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 31 14:02:35.981396 systemd[1]: Finished Load Kernel Module fuse. Oct 31 14:02:35.982847 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 31 14:02:35.984132 systemd[1]: Finished Apply Kernel Variables. Oct 31 14:02:35.986828 systemd[1]: Mounting FUSE Control File System... Oct 31 14:02:35.988463 systemd[1]: Mounting Kernel Configuration File System... Oct 31 14:02:35.989336 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 31 14:02:35.990820 systemd[1]: Starting Rebuild Hardware Database... Oct 31 14:02:35.992458 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 31 14:02:35.993496 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 31 14:02:35.995707 systemd-journald[810]: Time spent on flushing to /var/log/journal/d8d9977182e744b4adbf916ed31da156 is 8.049ms for 766 entries. Oct 31 14:02:35.995707 systemd-journald[810]: System Journal (/var/log/journal/d8d9977182e744b4adbf916ed31da156) is 8.0M, max 203.0M, 195.0M free. Oct 31 14:02:35.994407 systemd[1]: Starting Load/Save Random Seed... Oct 31 14:02:35.996512 systemd[1]: Starting Create System Users... Oct 31 14:02:36.000704 systemd[1]: Mounted FUSE Control File System. Oct 31 14:02:36.002229 systemd[1]: Mounted Kernel Configuration File System. Oct 31 14:02:36.003683 systemd[1]: Finished Load/Save Random Seed. Oct 31 14:02:36.005852 systemd[1]: Reached target First Boot Complete. Oct 31 14:02:36.007487 systemd-sysusers[822]: Creating group sgx with gid 999. Oct 31 14:02:36.008267 systemd-sysusers[822]: Creating group systemd-oom with gid 998. Oct 31 14:02:36.008761 systemd-sysusers[822]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 31 14:02:36.009360 systemd-sysusers[822]: Creating group systemd-timesync with gid 997. Oct 31 14:02:36.009872 systemd-sysusers[822]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 31 14:02:36.010294 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 31 14:02:36.011647 systemd[1]: Finished Coldplug All udev Devices. Oct 31 14:02:36.019342 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 31 14:02:36.021036 systemd-sysusers[822]: Creating group systemd-coredump with gid 996. Oct 31 14:02:36.021699 systemd-sysusers[822]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 31 14:02:36.030903 systemd[1]: Finished Create System Users. Oct 31 14:02:36.044437 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 31 14:02:36.056451 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 31 14:02:36.247239 systemd[1]: Finished Rebuild Hardware Database. Oct 31 14:02:36.258000 audit: BPF prog-id=27 op=LOAD Oct 31 14:02:36.260000 audit: BPF prog-id=28 op=LOAD Oct 31 14:02:36.260921 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 31 14:02:36.261146 kernel: audit: type=1334 audit(1761919356.258:47): prog-id=27 op=LOAD Oct 31 14:02:36.261184 kernel: audit: type=1334 audit(1761919356.260:48): prog-id=28 op=LOAD Oct 31 14:02:36.261197 kernel: audit: type=1334 audit(1761919356.260:49): prog-id=29 op=LOAD Oct 31 14:02:36.261220 kernel: audit: type=1334 audit(1761919356.260:50): prog-id=15 op=UNLOAD Oct 31 14:02:36.260000 audit: BPF prog-id=29 op=LOAD Oct 31 14:02:36.260000 audit: BPF prog-id=15 op=UNLOAD Oct 31 14:02:36.260000 audit: BPF prog-id=16 op=UNLOAD Oct 31 14:02:36.283865 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 31 14:02:36.292000 audit: BPF prog-id=30 op=LOAD Oct 31 14:02:36.293692 systemd[1]: Starting Network Configuration... Oct 31 14:02:36.319954 systemd-udevd[842]: Using default interface naming scheme 'v249'. Oct 31 14:02:36.320172 systemd-networkd[847]: lo: Link UP Oct 31 14:02:36.320176 systemd-networkd[847]: lo: Gained carrier Oct 31 14:02:36.320447 systemd-networkd[847]: Enumeration completed Oct 31 14:02:36.320526 systemd[1]: Started Network Configuration. Oct 31 14:02:36.339138 udevadm[826]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 31 14:02:36.343069 systemd-networkd[847]: eth0: Link UP Oct 31 14:02:36.346640 systemd-networkd[847]: eth0: Gained carrier Oct 31 14:02:36.350709 systemd-networkd[847]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Oct 31 14:02:36.370028 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 31 14:02:36.387584 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 31 14:02:36.395809 lvm[855]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 14:02:36.427738 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 31 14:02:36.429173 systemd[1]: Reached target Local Encrypted Volumes. Oct 31 14:02:36.442111 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 31 14:02:36.445419 lvm[856]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 14:02:36.469219 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 31 14:02:36.470353 systemd[1]: Reached target Preparation for Local File Systems. Oct 31 14:02:36.471514 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 31 14:02:36.471539 systemd[1]: Reached target Containers. Oct 31 14:02:36.487165 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 31 14:02:36.501263 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 31 14:02:36.512463 systemd[1]: Mounting /usr/share/oem... Oct 31 14:02:36.518603 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 31 14:02:36.518650 kernel: BTRFS info (device vda6): has skinny extents Oct 31 14:02:36.520846 systemd[1]: Mounted /usr/share/oem. Oct 31 14:02:36.521618 systemd[1]: Reached target Local File Systems. Oct 31 14:02:36.523312 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 31 14:02:36.524716 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 31 14:02:36.524758 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 31 14:02:36.525653 systemd[1]: Starting Commit a transient machine-id on disk... Oct 31 14:02:36.527529 systemd[1]: Starting Create Volatile Files and Directories... Oct 31 14:02:36.533180 systemd-tmpfiles[881]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 31 14:02:36.534236 systemd-tmpfiles[881]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 31 14:02:36.543737 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 31 14:02:36.544279 systemd[1]: Finished Commit a transient machine-id on disk. Oct 31 14:02:36.561672 systemd-tmpfiles[881]: Detected autofs mount point /boot during canonicalization of /boot. Oct 31 14:02:36.561681 systemd-tmpfiles[881]: Skipping /boot Oct 31 14:02:36.566349 systemd-tmpfiles[881]: Detected autofs mount point /boot during canonicalization of /boot. Oct 31 14:02:36.566364 systemd-tmpfiles[881]: Skipping /boot Oct 31 14:02:36.598765 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 31 14:02:36.600143 systemd[1]: Finished Create Volatile Files and Directories. Oct 31 14:02:36.618053 systemd[1]: Starting Load Security Auditing Rules... Oct 31 14:02:36.620276 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 31 14:02:36.622257 systemd[1]: Starting Rebuild Journal Catalog... Oct 31 14:02:36.623000 audit: BPF prog-id=31 op=LOAD Oct 31 14:02:36.624731 systemd[1]: Starting Network Name Resolution... Oct 31 14:02:36.625000 audit: BPF prog-id=32 op=LOAD Oct 31 14:02:36.627048 systemd[1]: Starting Network Time Synchronization... Oct 31 14:02:36.629551 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 31 14:02:36.631052 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 31 14:02:36.632333 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 31 14:02:36.634704 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 31 14:02:36.636037 systemd[1]: Finished Rebuild Journal Catalog. Oct 31 14:02:36.637935 systemd[1]: Starting Update is Completed... Oct 31 14:02:36.642343 systemd[1]: Finished Update is Completed. Oct 31 14:02:36.647000 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 31 14:02:36.648115 augenrules[903]: No rules Oct 31 14:02:36.648759 systemd[1]: Finished Load Security Auditing Rules. Oct 31 14:02:36.235763 systemd-timesyncd[892]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 31 14:02:36.237376 systemd[1]: Started Network Time Synchronization. Oct 31 14:02:36.238478 systemd[1]: Reached target System Initialization. Oct 31 14:02:36.238747 systemd-resolved[891]: Positive Trust Anchors: Oct 31 14:02:36.238754 systemd-resolved[891]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 14:02:36.238780 systemd-resolved[891]: 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 31 14:02:36.239581 systemd[1]: Started Watch for update engine configuration changes. Oct 31 14:02:36.239763 systemd-resolved[891]: Defaulting to hostname 'linux'. Oct 31 14:02:36.240748 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 31 14:02:36.242785 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 31 14:02:36.243777 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 31 14:02:36.243812 systemd[1]: Reached target Path Units. Oct 31 14:02:36.244670 systemd[1]: Reached target System Time Set. Oct 31 14:02:36.245643 systemd[1]: Started Daily Log Rotation. Oct 31 14:02:36.246476 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 31 14:02:36.247562 systemd[1]: Reached target Timer Units. Oct 31 14:02:36.248582 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 31 14:02:36.262843 systemd[1]: Starting Docker Socket for the API... Oct 31 14:02:36.265774 systemd[1]: Listening on OpenSSH Server Socket. Oct 31 14:02:36.267001 systemd[1]: Started Network Name Resolution. Oct 31 14:02:36.267945 systemd[1]: Listening on Docker Socket for the API. Oct 31 14:02:36.268887 systemd[1]: Reached target Network. Oct 31 14:02:36.269621 systemd[1]: Reached target Host and Network Name Lookups. Oct 31 14:02:36.270548 systemd[1]: Reached target Socket Units. Oct 31 14:02:36.271280 systemd[1]: Reached target Basic System. Oct 31 14:02:36.272062 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 31 14:02:36.272093 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 31 14:02:36.273048 systemd[1]: Started D-Bus System Message Bus. Oct 31 14:02:36.276174 systemd[1]: Starting Extend Filesystems... Oct 31 14:02:36.277048 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 31 14:02:36.277957 systemd[1]: Starting Generate /run/flatcar/motd... Oct 31 14:02:36.279764 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 31 14:02:36.282453 systemd[1]: Starting Generate sshd host keys... Oct 31 14:02:36.283332 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 31 14:02:36.283365 systemd[1]: Reached target Load system-provided cloud configs. Oct 31 14:02:36.286605 extend-filesystems[913]: Found vda Oct 31 14:02:36.286605 extend-filesystems[913]: Found vda1 Oct 31 14:02:36.286605 extend-filesystems[913]: Found vda2 Oct 31 14:02:36.286605 extend-filesystems[913]: Found vda3 Oct 31 14:02:36.286605 extend-filesystems[913]: Found usr Oct 31 14:02:36.286605 extend-filesystems[913]: Found vda4 Oct 31 14:02:36.286605 extend-filesystems[913]: Found vda6 Oct 31 14:02:36.286605 extend-filesystems[913]: Found vda7 Oct 31 14:02:36.286605 extend-filesystems[913]: Found vda9 Oct 31 14:02:36.286605 extend-filesystems[913]: Checking size of /dev/vda9 Oct 31 14:02:36.286852 systemd[1]: Starting User Login Management... Oct 31 14:02:36.287930 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 31 14:02:36.288692 systemd[1]: Starting Update Engine... Oct 31 14:02:36.289860 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 31 14:02:36.289892 systemd[1]: Reached target Load user-provided cloud configs. Oct 31 14:02:36.291961 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 31 14:02:36.292172 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 31 14:02:36.294528 systemd[1]: motdgen.service: Deactivated successfully. Oct 31 14:02:36.294738 systemd[1]: Finished Generate /run/flatcar/motd. Oct 31 14:02:36.302736 extend-filesystems[913]: Old size kept for /dev/vda9 Oct 31 14:02:36.305660 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 31 14:02:36.305890 systemd[1]: Finished Extend Filesystems. Oct 31 14:02:36.321546 update_engine[929]: I1031 14:02:36.320667 929 main.cc:89] Flatcar Update Engine starting Oct 31 14:02:36.323165 systemd[1]: Started Update Engine. Oct 31 14:02:36.323460 update_engine[929]: I1031 14:02:36.323431 929 update_check_scheduler.cc:74] Next update check in 3m28s Oct 31 14:02:36.325374 systemd[1]: Started Cluster reboot manager. Oct 31 14:02:36.328646 systemd-logind[926]: Watching system buttons on /dev/input/event0 (Power Button) Oct 31 14:02:36.328830 systemd-logind[926]: New seat seat0. Oct 31 14:02:36.332937 systemd[1]: Started User Login Management. Oct 31 14:02:36.355296 locksmithd[938]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 31 14:02:36.964614 sshd_keygen[930]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 31 14:02:36.982128 systemd[1]: Finished Generate sshd host keys. Oct 31 14:02:36.993256 systemd[1]: Starting Generate /run/issue... Oct 31 14:02:36.997320 systemd[1]: issuegen.service: Deactivated successfully. Oct 31 14:02:36.997532 systemd[1]: Finished Generate /run/issue. Oct 31 14:02:36.999480 systemd[1]: Starting Permit User Sessions... Oct 31 14:02:37.003634 systemd[1]: Finished Permit User Sessions. Oct 31 14:02:37.005526 systemd[1]: Started Getty on tty1. Oct 31 14:02:37.007345 systemd[1]: Started Serial Getty on ttyAMA0. Oct 31 14:02:37.008318 systemd[1]: Reached target Login Prompts. Oct 31 14:02:37.009194 systemd[1]: Reached target Multi-User System. Oct 31 14:02:37.011050 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 31 14:02:37.015774 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 31 14:02:37.015980 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 31 14:02:37.017059 systemd[1]: Startup finished in 702ms (kernel) + 2.309s (initrd) + 3.579s (userspace) = 6.591s. Oct 31 14:02:37.368779 systemd-networkd[847]: eth0: Gained IPv6LL Oct 31 14:02:43.022553 systemd[1]: Created slice Slice /system/sshd. Oct 31 14:02:43.023627 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58240). Oct 31 14:02:43.068163 sshd[959]: Accepted publickey for core from 10.0.0.1 port 58240 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:02:43.069995 sshd[959]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:43.089531 systemd[1]: Created slice User Slice of UID 500. Oct 31 14:02:43.090835 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 31 14:02:43.092987 systemd-logind[926]: New session 1 of user core. Oct 31 14:02:43.096984 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 31 14:02:43.098160 systemd[1]: Starting User Manager for UID 500... Oct 31 14:02:43.100152 systemd[962]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:43.145277 systemd[962]: Queued start job for default target Main User Target. Oct 31 14:02:43.145411 systemd[962]: Reached target Paths. Oct 31 14:02:43.145425 systemd[962]: Reached target Sockets. Oct 31 14:02:43.145436 systemd[962]: Reached target Timers. Oct 31 14:02:43.145445 systemd[962]: Reached target Basic System. Oct 31 14:02:43.145493 systemd[962]: Reached target Main User Target. Oct 31 14:02:43.145505 systemd[962]: Startup finished in 41ms. Oct 31 14:02:43.145706 systemd[1]: Started User Manager for UID 500. Oct 31 14:02:43.152386 systemd[1]: Started Session 1 of User core. Oct 31 14:02:43.210504 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58242). Oct 31 14:02:43.252999 sshd[971]: Accepted publickey for core from 10.0.0.1 port 58242 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:02:43.254298 sshd[971]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:43.258793 systemd-logind[926]: New session 2 of user core. Oct 31 14:02:43.269860 systemd[1]: Started Session 2 of User core. Oct 31 14:02:43.322962 sshd[971]: pam_unix(sshd:session): session closed for user core Oct 31 14:02:43.336719 systemd[1]: sshd@1-10.0.0.3:22-10.0.0.1:58242.service: Deactivated successfully. Oct 31 14:02:43.337333 systemd[1]: session-2.scope: Deactivated successfully. Oct 31 14:02:43.337940 systemd-logind[926]: Session 2 logged out. Waiting for processes to exit. Oct 31 14:02:43.338774 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58246). Oct 31 14:02:43.339364 systemd-logind[926]: Removed session 2. Oct 31 14:02:43.381730 sshd[977]: Accepted publickey for core from 10.0.0.1 port 58246 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:02:43.383045 sshd[977]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:43.385818 systemd-logind[926]: New session 3 of user core. Oct 31 14:02:43.400745 systemd[1]: Started Session 3 of User core. Oct 31 14:02:43.449450 sshd[977]: pam_unix(sshd:session): session closed for user core Oct 31 14:02:43.458022 systemd[1]: sshd@2-10.0.0.3:22-10.0.0.1:58246.service: Deactivated successfully. Oct 31 14:02:43.458603 systemd[1]: session-3.scope: Deactivated successfully. Oct 31 14:02:43.459165 systemd-logind[926]: Session 3 logged out. Waiting for processes to exit. Oct 31 14:02:43.460034 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58248). Oct 31 14:02:43.460660 systemd-logind[926]: Removed session 3. Oct 31 14:02:43.503393 sshd[983]: Accepted publickey for core from 10.0.0.1 port 58248 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:02:43.504954 sshd[983]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:43.507662 systemd-logind[926]: New session 4 of user core. Oct 31 14:02:43.522558 systemd[1]: Started Session 4 of User core. Oct 31 14:02:43.573916 sshd[983]: pam_unix(sshd:session): session closed for user core Oct 31 14:02:43.583992 systemd[1]: sshd@3-10.0.0.3:22-10.0.0.1:58248.service: Deactivated successfully. Oct 31 14:02:43.584584 systemd[1]: session-4.scope: Deactivated successfully. Oct 31 14:02:43.585090 systemd-logind[926]: Session 4 logged out. Waiting for processes to exit. Oct 31 14:02:43.585992 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58250). Oct 31 14:02:43.586534 systemd-logind[926]: Removed session 4. Oct 31 14:02:43.627323 sshd[989]: Accepted publickey for core from 10.0.0.1 port 58250 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:02:43.628456 sshd[989]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:43.630857 systemd-logind[926]: New session 5 of user core. Oct 31 14:02:43.643759 systemd[1]: Started Session 5 of User core. Oct 31 14:02:43.699735 sudo[992]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 31 14:02:43.699933 sudo[992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 14:02:43.722328 sudo[992]: pam_unix(sudo:session): session closed for user root Oct 31 14:02:43.724611 sshd[989]: pam_unix(sshd:session): session closed for user core Oct 31 14:02:43.736763 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58258). Oct 31 14:02:43.738113 dbus-daemon[912]: [system] Reloaded configuration Oct 31 14:02:43.740383 systemd[1]: sshd@4-10.0.0.3:22-10.0.0.1:58250.service: Deactivated successfully. Oct 31 14:02:43.740954 systemd[1]: session-5.scope: Deactivated successfully. Oct 31 14:02:43.741549 systemd-logind[926]: Session 5 logged out. Waiting for processes to exit. Oct 31 14:02:43.742255 systemd-logind[926]: Removed session 5. Oct 31 14:02:43.778437 sshd[995]: Accepted publickey for core from 10.0.0.1 port 58258 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:02:43.779568 sshd[995]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:43.781913 systemd-logind[926]: New session 6 of user core. Oct 31 14:02:43.791564 systemd[1]: Started Session 6 of User core. Oct 31 14:02:43.842596 sudo[1000]: 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 31 14:02:43.842793 sudo[1000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 14:02:43.845076 sudo[1000]: pam_unix(sudo:session): session closed for user root Oct 31 14:02:43.848413 sudo[999]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 31 14:02:43.848613 sudo[999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 14:02:43.873009 systemd[1]: Stopping Load Security Auditing Rules... Oct 31 14:02:43.873000 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 31 14:02:43.874574 auditctl[1003]: No rules Oct 31 14:02:43.874985 systemd[1]: audit-rules.service: Deactivated successfully. Oct 31 14:02:43.875246 systemd[1]: Stopped Load Security Auditing Rules. Oct 31 14:02:43.877139 kernel: kauditd_printk_skb: 5 callbacks suppressed Oct 31 14:02:43.877189 kernel: audit: type=1305 audit(1761919363.873:56): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 31 14:02:43.876520 systemd[1]: Starting Load Security Auditing Rules... Oct 31 14:02:43.890372 augenrules[1020]: No rules Oct 31 14:02:43.891198 systemd[1]: Finished Load Security Auditing Rules. Oct 31 14:02:43.892162 sudo[999]: pam_unix(sudo:session): session closed for user root Oct 31 14:02:43.893278 sshd[995]: pam_unix(sshd:session): session closed for user core Oct 31 14:02:43.908119 systemd[1]: sshd@5-10.0.0.3:22-10.0.0.1:58258.service: Deactivated successfully. Oct 31 14:02:43.908627 systemd[1]: session-6.scope: Deactivated successfully. Oct 31 14:02:43.909145 systemd-logind[926]: Session 6 logged out. Waiting for processes to exit. Oct 31 14:02:43.910085 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58266). Oct 31 14:02:43.910659 systemd-logind[926]: Removed session 6. Oct 31 14:02:43.950853 sshd[1026]: Accepted publickey for core from 10.0.0.1 port 58266 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:02:43.951775 sshd[1026]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:43.954239 kernel: audit: type=1006 audit(1761919363.950:57): pid=1026 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 31 14:02:43.955094 systemd-logind[926]: New session 7 of user core. Oct 31 14:02:43.969253 systemd[1]: Started Session 7 of User core. Oct 31 14:02:44.020720 sudo[1029]: 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 31 14:02:46.996457 update_engine[1277]: Oct 31 14:02:46.996457 update_engine[1277]: Oct 31 14:02:46.996457 update_engine[1277]: Oct 31 14:02:46.996457 update_engine[1277]: Oct 31 14:02:46.996457 update_engine[1277]: Oct 31 14:02:46.996457 update_engine[1277]: Oct 31 14:02:46.996457 update_engine[1277]: Oct 31 14:02:46.996457 update_engine[1277]: Oct 31 14:02:46.996457 update_engine[1277]: I1031 14:02:46.996456 1277 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 31 14:02:47.006307 update_engine[1277]: I1031 14:02:47.006275 1277 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 31 14:02:47.006524 update_engine[1277]: I1031 14:02:47.006498 1277 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 31 14:02:47.007854 update_engine[1277]: I1031 14:02:47.007830 1277 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 31 14:02:47.007950 update_engine[1277]: I1031 14:02:47.007913 1277 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Oct 31 14:02:47.007950 update_engine[1277]: I1031 14:02:47.007922 1277 omaha_request_action.cc:619] Omaha request response: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.007950 update_engine[1277]: Oct 31 14:02:47.012418 update_engine[1277]: I1031 14:02:47.012385 1277 omaha_request_action.cc:447] Omaha Response manifest version = Oct 31 14:02:47.012418 update_engine[1277]: I1031 14:02:47.012419 1277 omaha_request_action.cc:470] Found 1 url(s) Oct 31 14:02:47.012517 update_engine[1277]: I1031 14:02:47.012431 1277 omaha_request_action.cc:506] Processing first of 1 package(s) Oct 31 14:02:47.012517 update_engine[1277]: I1031 14:02:47.012435 1277 omaha_request_action.cc:513] Omaha Response package name = update.gz Oct 31 14:02:47.012517 update_engine[1277]: I1031 14:02:47.012439 1277 omaha_request_action.cc:529] Url0: http://10.0.0.2:34567/packages/update.gz Oct 31 14:02:47.012517 update_engine[1277]: I1031 14:02:47.012454 1277 omaha_request_action.cc:541] Payload size = 426251591 bytes Oct 31 14:02:47.012517 update_engine[1277]: I1031 14:02:47.012465 1277 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Oct 31 14:02:47.012517 update_engine[1277]: I1031 14:02:47.012478 1277 payload_state.cc:51] Resetting all persisted state as this is a new response Oct 31 14:02:47.012517 update_engine[1277]: I1031 14:02:47.012481 1277 payload_state.cc:360] Current Response Signature = Oct 31 14:02:47.012517 update_engine[1277]: NumURLs = 1 Oct 31 14:02:47.012517 update_engine[1277]: Url0 = http://10.0.0.2:34567/packages/update.gz Oct 31 14:02:47.012517 update_engine[1277]: Payload Size = 426251591 Oct 31 14:02:47.012517 update_engine[1277]: Payload Sha256 Hash = qwysf93IzFKZIIEIOAr8FB/Ur4+2vzR6efmfEvB0U+Q= Oct 31 14:02:47.012517 update_engine[1277]: Is Delta Payload = 0 Oct 31 14:02:47.012517 update_engine[1277]: Max Failure Count Per Url = 10 Oct 31 14:02:47.012517 update_engine[1277]: Disable Payload Backoff = 1 Oct 31 14:02:47.012739 update_engine[1277]: I1031 14:02:47.012566 1277 payload_state.cc:381] Payload Attempt Number = 0 Oct 31 14:02:47.012739 update_engine[1277]: I1031 14:02:47.012598 1277 payload_state.cc:404] Current URL Index = 0 Oct 31 14:02:47.012739 update_engine[1277]: I1031 14:02:47.012629 1277 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Oct 31 14:02:47.012739 update_engine[1277]: I1031 14:02:47.012655 1277 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Oct 31 14:02:47.012739 update_engine[1277]: I1031 14:02:47.012660 1277 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 31 14:02:47.012739 update_engine[1277]: I1031 14:02:47.012688 1277 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Oct 31 14:02:47.012739 update_engine[1277]: I1031 14:02:47.012703 1277 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 31 14:02:47.012739 update_engine[1277]: I1031 14:02:47.012707 1277 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Oct 31 14:02:47.012739 update_engine[1277]: I1031 14:02:47.012722 1277 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Oct 31 14:02:47.012739 update_engine[1277]: E1031 14:02:47.012725 1277 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Oct 31 14:02:47.013201 update_engine[1277]: I1031 14:02:47.013173 1277 omaha_response_handler_action.cc:85] Using this install plan: Oct 31 14:02:47.013201 update_engine[1277]: I1031 14:02:47.013190 1277 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.2:34567/packages/update.gz, payload size: 426251591, payload hash: qwysf93IzFKZIIEIOAr8FB/Ur4+2vzR6efmfEvB0U+Q=, 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 31 14:02:47.013327 update_engine[1277]: I1031 14:02:47.013299 1277 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Oct 31 14:02:47.015083 update_engine[1277]: I1031 14:02:47.015053 1277 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Oct 31 14:02:47.033324 sshd[1291]: Accepted publickey for core from 10.0.0.1 port 58430 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:02:47.034408 sshd[1291]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:47.037237 systemd-logind[926]: New session 25 of user core. Oct 31 14:02:47.044380 systemd[1]: Started Session 25 of User core. Oct 31 14:02:47.109062 sshd[1291]: pam_unix(sshd:session): session closed for user core Oct 31 14:02:47.112343 systemd[1]: sshd@24-10.0.0.3:22-10.0.0.1:58430.service: Deactivated successfully. Oct 31 14:02:47.113069 systemd[1]: session-25.scope: Deactivated successfully. Oct 31 14:02:47.113861 systemd-logind[926]: Session 25 logged out. Waiting for processes to exit. Oct 31 14:02:47.114529 systemd-logind[926]: Removed session 25. Oct 31 14:02:48.835833 update_engine[1277]: I1031 14:02:48.835793 1277 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Oct 31 14:02:48.835833 update_engine[1277]: I1031 14:02:48.835835 1277 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Oct 31 14:02:48.891620 update_engine[1277]: E1031 14:02:48.891551 1277 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Oct 31 14:02:48.891620 update_engine[1277]: I1031 14:02:48.891597 1277 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Oct 31 14:02:48.891620 update_engine[1277]: I1031 14:02:48.891602 1277 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Oct 31 14:02:48.891620 update_engine[1277]: I1031 14:02:48.891605 1277 update_attempter.cc:302] Processing Done. Oct 31 14:02:48.891805 update_engine[1277]: E1031 14:02:48.891652 1277 update_attempter.cc:615] Update failed. Oct 31 14:02:48.891805 update_engine[1277]: I1031 14:02:48.891656 1277 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Oct 31 14:02:48.891805 update_engine[1277]: I1031 14:02:48.891660 1277 payload_state.cc:276] Incrementing the URL failure count Oct 31 14:02:48.891805 update_engine[1277]: I1031 14:02:48.891664 1277 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 31 14:02:48.892015 update_engine[1277]: I1031 14:02:48.891844 1277 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 31 14:02:48.892015 update_engine[1277]: I1031 14:02:48.891867 1277 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.2:34567/v1/update Oct 31 14:02:48.892015 update_engine[1277]: I1031 14:02:48.891871 1277 omaha_request_action.cc:269] Request: Oct 31 14:02:48.892015 update_engine[1277]: Oct 31 14:02:48.892015 update_engine[1277]: Oct 31 14:02:48.892015 update_engine[1277]: Oct 31 14:02:48.892015 update_engine[1277]: Oct 31 14:02:48.892015 update_engine[1277]: Oct 31 14:02:48.892015 update_engine[1277]: Oct 31 14:02:48.892015 update_engine[1277]: I1031 14:02:48.891875 1277 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 31 14:02:48.892015 update_engine[1277]: I1031 14:02:48.891952 1277 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 31 14:02:48.892270 update_engine[1277]: I1031 14:02:48.892060 1277 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 31 14:02:49.078632 update_engine[1277]: I1031 14:02:49.078588 1277 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 31 14:02:49.078845 update_engine[1277]: I1031 14:02:49.078671 1277 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Oct 31 14:02:49.078845 update_engine[1277]: I1031 14:02:49.078678 1277 omaha_request_action.cc:619] Omaha request response: Oct 31 14:02:49.078845 update_engine[1277]: Oct 31 14:02:49.078845 update_engine[1277]: Oct 31 14:02:49.078845 update_engine[1277]: Oct 31 14:02:49.078845 update_engine[1277]: Oct 31 14:02:49.078845 update_engine[1277]: Oct 31 14:02:49.078845 update_engine[1277]: Oct 31 14:02:49.078845 update_engine[1277]: E1031 14:02:49.078693 1277 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Oct 31 14:02:49.078845 update_engine[1277]: I1031 14:02:49.078697 1277 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 31 14:02:49.078845 update_engine[1277]: I1031 14:02:49.078701 1277 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 31 14:02:49.078845 update_engine[1277]: I1031 14:02:49.078703 1277 update_attempter.cc:302] Processing Done. Oct 31 14:02:49.078845 update_engine[1277]: I1031 14:02:49.078708 1277 update_attempter.cc:306] Error event sent. Oct 31 14:02:57.123745 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52502). Oct 31 14:02:57.167869 sshd[1312]: Accepted publickey for core from 10.0.0.1 port 52502 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:02:57.169713 kernel: kauditd_printk_skb: 287 callbacks suppressed Oct 31 14:02:57.169779 kernel: audit: type=1006 audit(1761919377.167:353): pid=1312 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 31 14:02:57.169879 sshd[1312]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:57.172883 systemd-logind[926]: New session 26 of user core. Oct 31 14:02:57.189347 systemd[1]: Started Session 26 of User core. Oct 31 14:02:57.244870 sshd[1312]: pam_unix(sshd:session): session closed for user core Oct 31 14:02:57.252785 systemd[1]: sshd@25-10.0.0.3:22-10.0.0.1:52502.service: Deactivated successfully. Oct 31 14:02:57.253369 systemd[1]: session-26.scope: Deactivated successfully. Oct 31 14:02:57.253919 systemd-logind[926]: Session 26 logged out. Waiting for processes to exit. Oct 31 14:02:57.254842 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52508). Oct 31 14:02:57.255525 systemd-logind[926]: Removed session 26. Oct 31 14:02:57.299982 sshd[1319]: Accepted publickey for core from 10.0.0.1 port 52508 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:02:57.301342 sshd[1319]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:57.304131 systemd-logind[926]: New session 27 of user core. Oct 31 14:02:57.304247 kernel: audit: type=1006 audit(1761919377.299:354): pid=1319 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 31 14:02:57.323566 systemd[1]: Started Session 27 of User core. Oct 31 14:02:57.379131 sshd[1319]: pam_unix(sshd:session): session closed for user core Oct 31 14:02:57.396620 systemd[1]: sshd@26-10.0.0.3:22-10.0.0.1:52508.service: Deactivated successfully. Oct 31 14:02:57.397105 systemd[1]: session-27.scope: Deactivated successfully. Oct 31 14:02:57.397637 systemd-logind[926]: Session 27 logged out. Waiting for processes to exit. Oct 31 14:02:57.398540 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52512). Oct 31 14:02:57.399169 systemd-logind[926]: Removed session 27. Oct 31 14:02:57.442866 sshd[1327]: Accepted publickey for core from 10.0.0.1 port 52512 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:02:57.446236 kernel: audit: type=1006 audit(1761919377.442:355): pid=1327 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 31 14:02:57.446267 sshd[1327]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:57.448631 systemd-logind[926]: New session 28 of user core. Oct 31 14:02:57.458447 systemd[1]: Started Session 28 of User core. Oct 31 14:02:57.507318 sshd[1327]: pam_unix(sshd:session): session closed for user core Oct 31 14:02:57.525325 systemd[1]: sshd@27-10.0.0.3:22-10.0.0.1:52512.service: Deactivated successfully. Oct 31 14:02:57.525855 systemd[1]: session-28.scope: Deactivated successfully. Oct 31 14:02:57.526434 systemd-logind[926]: Session 28 logged out. Waiting for processes to exit. Oct 31 14:02:57.527365 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52518). Oct 31 14:02:57.528043 systemd-logind[926]: Removed session 28. Oct 31 14:02:57.569694 sshd[1333]: Accepted publickey for core from 10.0.0.1 port 52518 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:02:57.570744 sshd[1333]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:57.573354 kernel: audit: type=1006 audit(1761919377.569:356): pid=1333 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 31 14:02:57.574800 systemd-logind[926]: New session 29 of user core. Oct 31 14:02:57.588006 systemd[1]: Started Session 29 of User core. Oct 31 14:02:57.637750 sshd[1333]: pam_unix(sshd:session): session closed for user core Oct 31 14:02:57.645762 systemd[1]: sshd@28-10.0.0.3:22-10.0.0.1:52518.service: Deactivated successfully. Oct 31 14:02:57.646268 systemd[1]: session-29.scope: Deactivated successfully. Oct 31 14:02:57.646787 systemd-logind[926]: Session 29 logged out. Waiting for processes to exit. Oct 31 14:02:57.647747 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52520). Oct 31 14:02:57.648355 systemd-logind[926]: Removed session 29. Oct 31 14:02:57.690648 sshd[1339]: Accepted publickey for core from 10.0.0.1 port 52520 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:02:57.691820 sshd[1339]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:02:57.694333 kernel: audit: type=1006 audit(1761919377.690:357): pid=1339 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 31 14:02:57.696247 systemd-logind[926]: New session 30 of user core. Oct 31 14:02:57.706355 systemd[1]: Started Session 30 of User core. Oct 31 14:02:57.758990 sudo[1343]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Oct 31 14:02:57.759192 sudo[1343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 14:02:57.764846 systemd[1]: sshd.socket: Deactivated successfully. Oct 31 14:02:57.765179 systemd[1]: Closed OpenSSH Server Socket. Oct 31 14:02:57.766445 sudo[1343]: pam_unix(sudo:session): session closed for user root Oct 31 14:02:57.769864 sudo[1342]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Oct 31 14:02:57.770065 sudo[1342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 14:02:57.786090 sshd[959]: pam_unix(sshd:session): session closed for user core Oct 31 14:02:57.786407 systemd[1]: Stopping Session 1 of User core... Oct 31 14:02:57.787273 sshd[959]: pam_systemd(sshd:session): Failed to release session: Interrupted system call Oct 31 14:02:57.787517 systemd[1]: Stopping Session 30 of User core... Oct 31 14:02:57.787737 sshd[1339]: pam_unix(sshd:session): session closed for user core Oct 31 14:02:57.788097 sudo[1342]: pam_unix(sudo:session): session closed for user root Oct 31 14:02:57.789325 systemd[1]: Removed slice Slice /system/addon-config. Oct 31 14:02:57.790561 systemd[1]: Removed slice Slice /system/addon-run. Oct 31 14:02:57.791754 systemd[1]: Removed slice Slice /system/modprobe. Oct 31 14:02:57.792958 systemd[1]: Removed slice Slice /system/system-cloudinit. Oct 31 14:02:57.794046 systemd[1]: Stopped target Multi-User System. Oct 31 14:02:57.794959 systemd[1]: Stopped target Login Prompts. Oct 31 14:02:57.795799 systemd[1]: Stopped target Containers. Oct 31 14:02:57.796598 systemd[1]: Stopped target Host and Network Name Lookups. Oct 31 14:02:57.797576 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 31 14:02:57.798519 systemd[1]: Stopped target Timer Units. Oct 31 14:02:57.799272 systemd[1]: logrotate.timer: Deactivated successfully. Oct 31 14:02:57.799319 systemd[1]: Stopped Daily Log Rotation. Oct 31 14:02:57.800050 systemd[1]: mdadm.timer: Deactivated successfully. Oct 31 14:02:57.800088 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Oct 31 14:02:57.801250 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 31 14:02:57.801292 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Oct 31 14:02:57.802267 systemd[1]: Stopped target System Time Set. Oct 31 14:02:57.803067 systemd[1]: Stopped target Load user-provided cloud configs. Oct 31 14:02:57.804297 systemd[1]: Stopped target Load system-provided cloud configs. Oct 31 14:02:57.805313 systemd[1]: systemd-coredump.socket: Deactivated successfully. Oct 31 14:02:57.805415 systemd[1]: Closed Process Core Dump Socket. Oct 31 14:02:57.810732 systemd[1]: Unmounting Boot partition... Oct 31 14:02:57.811980 systemd[1]: Stopping Getty on tty1... Oct 31 14:02:57.813140 systemd[1]: Stopping Serial Getty on ttyAMA0... Oct 31 14:02:57.814114 systemd[1]: Stopping OpenSSH per-connection server daemon... Oct 31 14:02:57.815159 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:52520)... Oct 31 14:02:57.816226 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Oct 31 14:02:57.815244 sshd[1339]: pam_systemd(sshd:session): Failed to release session: Interrupted system call Oct 31 14:02:57.816435 systemd[1]: Stopped Commit a transient machine-id on disk. Oct 31 14:02:57.817680 systemd[1]: Stopped target First Boot Complete. Oct 31 14:02:57.819539 systemd[1]: Stopping Load/Save Random Seed... Oct 31 14:02:57.820460 systemd[1]: Stopping Update Engine... Oct 31 14:02:57.824000 systemd[1]: getty@tty1.service: Deactivated successfully. Oct 31 14:02:57.824203 systemd[1]: Stopped Getty on tty1. Oct 31 14:02:57.825352 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Oct 31 14:02:57.825562 systemd[1]: Stopped Serial Getty on ttyAMA0. Oct 31 14:02:57.826867 systemd[1]: sshd@0-10.0.0.3:22-10.0.0.1:58240.service: Deactivated successfully. Oct 31 14:02:57.827060 systemd[1]: Stopped OpenSSH per-connection server daemon. Oct 31 14:02:57.828510 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Oct 31 14:02:57.828581 systemd[1]: update-engine.service: Failed with result 'exit-code'. Oct 31 14:02:57.828832 systemd[1]: Stopped Update Engine. Oct 31 14:02:57.829711 systemd[1]: update-engine.service: Consumed 2.373s CPU time. Oct 31 14:02:57.829944 systemd[1]: sshd@29-10.0.0.3:22-10.0.0.1:52520.service: Deactivated successfully. Oct 31 14:02:57.830120 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:52520). Oct 31 14:02:57.832190 systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 31 14:02:57.832517 systemd[1]: Stopped Load/Save Random Seed. Oct 31 14:02:57.833733 systemd[1]: session-30.scope: Deactivated successfully. Oct 31 14:02:57.833949 systemd[1]: Stopped Session 30 of User core. Oct 31 14:02:57.835001 systemd[1]: session-1.scope: Deactivated successfully. Oct 31 14:02:57.835249 systemd[1]: Stopped Session 1 of User core. Oct 31 14:02:57.836561 systemd-logind[926]: Session 1 logged out. Waiting for processes to exit. Oct 31 14:02:57.837634 systemd-logind[926]: Session 30 logged out. Waiting for processes to exit. Oct 31 14:02:57.837878 systemd[1]: Removed slice Slice /system/getty. Oct 31 14:02:57.839163 systemd[1]: Removed slice Slice /system/serial-getty. Oct 31 14:02:57.840439 systemd[1]: Removed slice Slice /system/sshd. Oct 31 14:02:57.841469 systemd[1]: sshd-keygen.service: Deactivated successfully. Oct 31 14:02:57.841579 systemd[1]: Stopped Generate sshd host keys. Oct 31 14:02:57.842601 systemd[1]: Stopping User Login Management... Oct 31 14:02:57.843655 systemd[1]: Stopping User Manager for UID 500... Oct 31 14:02:57.844449 systemd[962]: Stopped target Main User Target. Oct 31 14:02:57.844466 systemd[962]: Stopped target Basic System. Oct 31 14:02:57.844475 systemd[962]: Stopped target Paths. Oct 31 14:02:57.844482 systemd[962]: Stopped target Sockets. Oct 31 14:02:57.844498 systemd[962]: Reached target Shutdown. Oct 31 14:02:57.844504 systemd[962]: Stopped target Timers. Oct 31 14:02:57.844609 systemd[962]: Finished Exit the Session. Oct 31 14:02:57.844632 systemd[962]: Reached target Exit the Session. Oct 31 14:02:57.844922 systemd[1]: boot.mount: Deactivated successfully. Oct 31 14:02:57.845168 systemd[1]: Unmounted Boot partition. Oct 31 14:02:57.846203 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Oct 31 14:02:57.846354 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Oct 31 14:02:57.848079 systemd-logind[926]: Removed session 1. Oct 31 14:02:57.849586 systemd[1]: user@500.service: Deactivated successfully. Oct 31 14:02:57.849764 systemd[1]: Stopped User Manager for UID 500. Oct 31 14:02:57.852076 systemd[1]: Stopping User Runtime Directory /run/user/500... Oct 31 14:02:57.857226 systemd[1]: run-user-500.mount: Deactivated successfully. Oct 31 14:02:57.857290 systemd[1]: Unmounted /run/user/500. Oct 31 14:02:57.858459 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Oct 31 14:02:57.858661 systemd[1]: Stopped User Runtime Directory /run/user/500. Oct 31 14:02:57.860362 systemd[1]: Removed slice User Slice of UID 500. Oct 31 14:02:57.862046 systemd[1]: Stopping D-Bus System Message Bus... Oct 31 14:02:57.863703 systemd[1]: Stopping Permit User Sessions... Oct 31 14:02:57.865077 systemd[1]: systemd-logind.service: Deactivated successfully. Oct 31 14:02:57.865353 systemd[1]: Stopped User Login Management. Oct 31 14:02:57.866639 systemd[1]: dbus.service: Deactivated successfully. Oct 31 14:02:57.866856 systemd[1]: Stopped D-Bus System Message Bus. Oct 31 14:02:57.867993 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Oct 31 14:02:57.868197 systemd[1]: Stopped Permit User Sessions. Oct 31 14:02:57.869068 systemd[1]: Stopped target Basic System. Oct 31 14:02:57.868000 audit: BPF prog-id=52 op=UNLOAD Oct 31 14:02:57.870735 systemd[1]: Stopped target Network. Oct 31 14:02:57.871235 kernel: audit: type=1334 audit(1761919377.868:358): prog-id=52 op=UNLOAD Oct 31 14:02:57.871445 systemd[1]: Stopped target Path Units. Oct 31 14:02:57.872150 systemd[1]: motdgen.path: Deactivated successfully. Oct 31 14:02:57.879215 systemd[1]: Stopped Watch for update engine configuration changes. Oct 31 14:02:57.880903 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Oct 31 14:02:57.892104 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 31 14:02:57.893887 systemd[1]: Stopped target Remote File Systems. Oct 31 14:02:57.894817 systemd[1]: Stopped target Slice Units. Oct 31 14:02:57.895864 systemd[1]: Removed slice User and Session Slice. Oct 31 14:02:57.896736 systemd[1]: Stopped target Socket Units. Oct 31 14:02:57.897542 systemd[1]: dbus.socket: Deactivated successfully. Oct 31 14:02:57.897595 systemd[1]: Closed D-Bus System Message Bus Socket. Oct 31 14:02:57.898559 systemd[1]: docker.socket: Deactivated successfully. Oct 31 14:02:57.898794 systemd[1]: Closed Docker Socket for the API. Oct 31 14:02:57.899651 systemd[1]: Stopped target System Initialization. Oct 31 14:02:57.900522 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Oct 31 14:02:57.900643 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Oct 31 14:02:57.901727 systemd[1]: Stopped target Local Verity Protected Volumes. Oct 31 14:02:57.903516 systemd[1]: Stopping Load Security Auditing Rules... Oct 31 14:02:57.904509 systemd[1]: Stopping Network Name Resolution... Oct 31 14:02:57.905352 auditctl[1358]: No rules Oct 31 14:02:57.905667 systemd[1]: Stopping Network Time Synchronization... Oct 31 14:02:57.906605 systemd[1]: systemd-update-done.service: Deactivated successfully. Oct 31 14:02:57.906684 systemd[1]: Stopped Update is Completed. Oct 31 14:02:57.907518 systemd[1]: ldconfig.service: Deactivated successfully. Oct 31 14:02:57.907579 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Oct 31 14:02:57.908443 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Oct 31 14:02:57.908505 systemd[1]: Stopped Rebuild Hardware Database. Oct 31 14:02:57.909371 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Oct 31 14:02:57.909447 systemd[1]: Stopped Rebuild Journal Catalog. Oct 31 14:02:57.911825 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Oct 31 14:02:57.914416 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 31 14:02:57.914662 systemd[1]: Stopped Network Name Resolution. Oct 31 14:02:57.915792 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Oct 31 14:02:57.916020 systemd[1]: Stopped Network Time Synchronization. Oct 31 14:02:57.917235 systemd[1]: audit-rules.service: Deactivated successfully. Oct 31 14:02:57.917455 systemd[1]: Stopped Load Security Auditing Rules. Oct 31 14:02:57.919877 systemd[1]: Stopping Network Configuration... Oct 31 14:02:57.919000 audit: BPF prog-id=50 op=UNLOAD Oct 31 14:02:57.921776 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Oct 31 14:02:57.921993 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Oct 31 14:02:57.922233 kernel: audit: type=1334 audit(1761919377.919:359): prog-id=50 op=UNLOAD Oct 31 14:02:57.922000 audit: BPF prog-id=58 op=UNLOAD Oct 31 14:02:57.923257 systemd-networkd[847]: eth0: DHCPv6 lease lost Oct 31 14:02:57.924405 kernel: audit: type=1334 audit(1761919377.922:360): prog-id=58 op=UNLOAD Oct 31 14:02:57.924725 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 31 14:02:57.924967 systemd[1]: Stopped Network Configuration. Oct 31 14:02:57.925978 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 31 14:02:57.926030 systemd[1]: Closed Network Service Netlink Socket. Oct 31 14:02:57.926955 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 31 14:02:57.927018 systemd[1]: Stopped Apply Kernel Variables. Oct 31 14:02:57.927966 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 31 14:02:57.928026 systemd[1]: Stopped Create Volatile Files and Directories. Oct 31 14:02:57.929012 systemd[1]: Stopped target Local File Systems. Oct 31 14:02:57.930711 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Oct 31 14:02:57.930000 audit: BPF prog-id=51 op=UNLOAD Oct 31 14:02:57.933099 systemd[1]: Unmounting External Media Directory... Oct 31 14:02:57.933225 kernel: audit: type=1334 audit(1761919377.930:361): prog-id=51 op=UNLOAD Oct 31 14:02:57.934833 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Oct 31 14:02:57.936599 systemd[1]: Unmounting /run/torcx/unpack... Oct 31 14:02:57.937895 systemd[1]: Unmounting Temporary Directory /tmp... Oct 31 14:02:57.939325 systemd[1]: Unmounting /usr/share/oem... Oct 31 14:02:57.940857 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Oct 31 14:02:57.943427 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 31 14:02:57.943657 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Oct 31 14:02:57.944945 systemd[1]: media.mount: Deactivated successfully. Oct 31 14:02:57.945199 systemd[1]: Unmounted External Media Directory. Oct 31 14:02:57.946142 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Oct 31 14:02:57.946411 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Oct 31 14:02:57.947648 systemd[1]: tmp.mount: Deactivated successfully. Oct 31 14:02:57.947904 systemd[1]: Unmounted Temporary Directory /tmp. Oct 31 14:02:57.948898 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 31 14:02:57.949152 systemd[1]: Unmounted /usr/share/oem. Oct 31 14:02:57.961510 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 31 14:02:57.961818 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Oct 31 14:02:57.963411 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Oct 31 14:02:57.963644 systemd[1]: Unmounted /run/torcx/unpack. Oct 31 14:02:57.964705 systemd[1]: Stopped target Swaps. Oct 31 14:02:57.965439 systemd[1]: Reached target Unmount All Filesystems. Oct 31 14:02:57.966289 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Oct 31 14:02:57.966368 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Oct 31 14:02:57.967644 systemd[1]: Removed slice Slice /system/systemd-fsck. Oct 31 14:02:57.968568 systemd[1]: Stopped target Preparation for Local File Systems. Oct 31 14:02:57.969570 systemd[1]: lvm2-activation.service: Deactivated successfully. Oct 31 14:02:57.969645 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 31 14:02:57.970604 systemd[1]: Stopped target Local Encrypted Volumes. Oct 31 14:02:57.971476 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 31 14:02:57.971530 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 31 14:02:57.972606 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Oct 31 14:02:57.979278 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Oct 31 14:02:57.980769 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Oct 31 14:02:57.980853 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 31 14:02:57.981891 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 31 14:02:57.981957 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 31 14:02:57.982990 systemd[1]: systemd-sysusers.service: Deactivated successfully. Oct 31 14:02:57.983053 systemd[1]: Stopped Create System Users. Oct 31 14:02:57.983901 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Oct 31 14:02:57.983962 systemd[1]: Stopped Remount Root and Kernel File Systems. Oct 31 14:02:57.985041 systemd[1]: Reached target System Shutdown. Oct 31 14:02:57.985892 systemd[1]: Reached target Late Shutdown Services. Oct 31 14:02:57.986835 systemd[1]: systemd-reboot.service: Deactivated successfully. Oct 31 14:02:57.986877 systemd[1]: Finished System Reboot. Oct 31 14:02:57.987650 systemd[1]: Reached target System Reboot. Oct 31 14:02:57.988429 systemd[1]: Shutting down. Oct 31 14:02:57.989000 audit: BPF prog-id=54 op=UNLOAD Oct 31 14:02:57.989000 audit: BPF prog-id=53 op=UNLOAD Oct 31 14:02:57.990000 audit: BPF prog-id=57 op=UNLOAD Oct 31 14:02:57.990000 audit: BPF prog-id=56 op=UNLOAD Oct 31 14:02:57.990000 audit: BPF prog-id=55 op=UNLOAD Oct 31 14:02:57.992227 kernel: audit: type=1334 audit(1761919377.989:362): prog-id=54 op=UNLOAD Oct 31 14:02:57.991000 audit: BPF prog-id=61 op=UNLOAD Oct 31 14:02:57.991000 audit: BPF prog-id=60 op=UNLOAD Oct 31 14:02:57.991000 audit: BPF prog-id=59 op=UNLOAD Oct 31 14:02:58.031229 systemd-shutdown[1]: Syncing filesystems and block devices. Oct 31 14:02:58.036631 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Oct 31 14:02:58.036688 systemd-journald[810]: Journal stopped -- Reboot -- Oct 31 14:03:06.666784 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 31 14:03:06.666801 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 31 14:03:06.666810 kernel: efi: EFI v2.70 by EDK II Oct 31 14:03:06.666816 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 31 14:03:06.666821 kernel: efi: seeding entropy pool Oct 31 14:03:06.666826 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 31 14:03:06.666832 kernel: ACPI: Early table checksum verification disabled Oct 31 14:03:06.666837 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 31 14:03:06.666842 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 31 14:03:06.666847 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:03:06.666853 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:03:06.666858 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:03:06.666863 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:03:06.666868 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:03:06.666874 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:03:06.666880 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:03:06.666886 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:03:06.666892 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 14:03:06.666897 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 31 14:03:06.666902 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 31 14:03:06.666908 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 14:03:06.666913 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 31 14:03:06.666918 kernel: Zone ranges: Oct 31 14:03:06.666923 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 14:03:06.666929 kernel: DMA32 empty Oct 31 14:03:06.666934 kernel: Normal empty Oct 31 14:03:06.666939 kernel: Movable zone start for each node Oct 31 14:03:06.666945 kernel: Early memory node ranges Oct 31 14:03:06.666950 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 31 14:03:06.666956 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 31 14:03:06.666961 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 31 14:03:06.666966 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 31 14:03:06.666971 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 31 14:03:06.666977 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 31 14:03:06.666982 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 31 14:03:06.666987 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 31 14:03:06.666992 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 31 14:03:06.666998 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 31 14:03:06.667003 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 31 14:03:06.667008 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 14:03:06.667017 kernel: On node 0 totalpages: 643072 Oct 31 14:03:06.667023 kernel: DMA zone: 10048 pages used for memmap Oct 31 14:03:06.667028 kernel: DMA zone: 0 pages reserved Oct 31 14:03:06.667034 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 31 14:03:06.667039 kernel: psci: probing for conduit method from ACPI. Oct 31 14:03:06.667045 kernel: psci: PSCIv1.1 detected in firmware. Oct 31 14:03:06.667050 kernel: psci: Using standard PSCI v0.2 function IDs Oct 31 14:03:06.667056 kernel: psci: Trusted OS migration not required Oct 31 14:03:06.667063 kernel: psci: SMC Calling Convention v1.1 Oct 31 14:03:06.667068 kernel: ACPI: SRAT not present Oct 31 14:03:06.667078 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 31 14:03:06.667085 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 31 14:03:06.667091 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 31 14:03:06.667096 kernel: Detected PIPT I-cache on CPU0 Oct 31 14:03:06.667102 kernel: CPU features: detected: GIC system register CPU interface Oct 31 14:03:06.667108 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 31 14:03:06.667114 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 31 14:03:06.667119 kernel: CPU features: detected: Hardware dirty bit management Oct 31 14:03:06.667125 kernel: CPU features: detected: Spectre-v4 Oct 31 14:03:06.667132 kernel: CPU features: detected: ARM erratum 1418040 Oct 31 14:03:06.667138 kernel: CPU features: detected: Spectre-BHB Oct 31 14:03:06.667144 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 31 14:03:06.667149 kernel: Policy zone: DMA Oct 31 14:03:06.667156 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 31 14:03:06.667169 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 31 14:03:06.667175 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 31 14:03:06.667181 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 31 14:03:06.667187 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 31 14:03:06.667193 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 31 14:03:06.667198 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 31 14:03:06.667205 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 31 14:03:06.667212 kernel: rcu: RCU event tracing is enabled. Oct 31 14:03:06.667218 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 31 14:03:06.667227 kernel: Trampoline variant of Tasks RCU enabled. Oct 31 14:03:06.667234 kernel: Tracing variant of Tasks RCU enabled. Oct 31 14:03:06.667241 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 31 14:03:06.667249 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 31 14:03:06.667256 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 31 14:03:06.667264 kernel: GICv3: 256 SPIs implemented Oct 31 14:03:06.667271 kernel: GICv3: 0 Extended SPIs implemented Oct 31 14:03:06.667279 kernel: GICv3: Distributor has no Range Selector support Oct 31 14:03:06.667286 kernel: GICv3: 16 PPIs implemented Oct 31 14:03:06.667295 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 31 14:03:06.667301 kernel: ACPI: SRAT not present Oct 31 14:03:06.667306 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 31 14:03:06.667312 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 31 14:03:06.667318 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 31 14:03:06.667325 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 31 14:03:06.667331 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 31 14:03:06.667337 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 14:03:06.667342 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 31 14:03:06.667348 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 31 14:03:06.667354 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 31 14:03:06.667360 kernel: arm-pv: using stolen time PV Oct 31 14:03:06.667367 kernel: Console: colour dummy device 80x25 Oct 31 14:03:06.667373 kernel: ACPI: Core revision 20200925 Oct 31 14:03:06.667379 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 31 14:03:06.667385 kernel: pid_max: default: 32768 minimum: 301 Oct 31 14:03:06.667390 kernel: LSM: Security Framework initializing Oct 31 14:03:06.667397 kernel: SELinux: Initializing. Oct 31 14:03:06.667402 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 14:03:06.667408 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 14:03:06.667414 kernel: rcu: Hierarchical SRCU implementation. Oct 31 14:03:06.667421 kernel: Platform MSI: ITS@0x8080000 domain created Oct 31 14:03:06.667427 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 31 14:03:06.667433 kernel: Remapping and enabling EFI services. Oct 31 14:03:06.667439 kernel: smp: Bringing up secondary CPUs ... Oct 31 14:03:06.667444 kernel: Detected PIPT I-cache on CPU1 Oct 31 14:03:06.667450 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 31 14:03:06.667456 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 31 14:03:06.667462 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 14:03:06.667468 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 31 14:03:06.667474 kernel: Detected PIPT I-cache on CPU2 Oct 31 14:03:06.667481 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 31 14:03:06.667487 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 31 14:03:06.667493 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 14:03:06.667499 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 31 14:03:06.667505 kernel: Detected PIPT I-cache on CPU3 Oct 31 14:03:06.667514 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 31 14:03:06.667521 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 31 14:03:06.667528 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 14:03:06.667534 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 31 14:03:06.667540 kernel: smp: Brought up 1 node, 4 CPUs Oct 31 14:03:06.667546 kernel: SMP: Total of 4 processors activated. Oct 31 14:03:06.667552 kernel: CPU features: detected: Privileged Access Never Oct 31 14:03:06.667558 kernel: CPU features: detected: LSE atomic instructions Oct 31 14:03:06.667564 kernel: CPU features: detected: User Access Override Oct 31 14:03:06.667570 kernel: CPU features: detected: 32-bit EL0 Support Oct 31 14:03:06.667576 kernel: CPU features: detected: Common not Private translations Oct 31 14:03:06.667582 kernel: CPU features: detected: RAS Extension Support Oct 31 14:03:06.667589 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 31 14:03:06.667596 kernel: CPU features: detected: CRC32 instructions Oct 31 14:03:06.667602 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 31 14:03:06.667608 kernel: CPU: All CPU(s) started at EL1 Oct 31 14:03:06.667614 kernel: alternatives: patching kernel code Oct 31 14:03:06.667621 kernel: devtmpfs: initialized Oct 31 14:03:06.667644 kernel: KASLR enabled Oct 31 14:03:06.667652 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 31 14:03:06.667658 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 31 14:03:06.667664 kernel: pinctrl core: initialized pinctrl subsystem Oct 31 14:03:06.667670 kernel: SMBIOS 3.0.0 present. Oct 31 14:03:06.667676 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 31 14:03:06.667683 kernel: NET: Registered protocol family 16 Oct 31 14:03:06.667689 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 31 14:03:06.667695 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 31 14:03:06.667702 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 31 14:03:06.667709 kernel: audit: initializing netlink subsys (disabled) Oct 31 14:03:06.667715 kernel: audit: type=2000 audit(0.029:1): state=initialized audit_enabled=0 res=1 Oct 31 14:03:06.667721 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 31 14:03:06.667727 kernel: cpuidle: using governor menu Oct 31 14:03:06.667733 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 31 14:03:06.667740 kernel: ASID allocator initialised with 32768 entries Oct 31 14:03:06.667746 kernel: ACPI: bus type PCI registered Oct 31 14:03:06.667752 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 31 14:03:06.667759 kernel: Serial: AMBA PL011 UART driver Oct 31 14:03:06.667765 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 31 14:03:06.667772 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 31 14:03:06.667778 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 31 14:03:06.667784 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 31 14:03:06.667790 kernel: cryptd: max_cpu_qlen set to 1000 Oct 31 14:03:06.667796 kernel: ACPI: Added _OSI(Module Device) Oct 31 14:03:06.667802 kernel: ACPI: Added _OSI(Processor Device) Oct 31 14:03:06.667808 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 31 14:03:06.667814 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 31 14:03:06.667822 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 31 14:03:06.667828 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 31 14:03:06.667834 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 31 14:03:06.667840 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 31 14:03:06.667846 kernel: ACPI: Interpreter enabled Oct 31 14:03:06.667852 kernel: ACPI: Using GIC for interrupt routing Oct 31 14:03:06.667858 kernel: ACPI: MCFG table detected, 1 entries Oct 31 14:03:06.667866 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 31 14:03:06.667872 kernel: printk: console [ttyAMA0] enabled Oct 31 14:03:06.667880 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 31 14:03:06.667984 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 31 14:03:06.668039 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 31 14:03:06.668091 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 31 14:03:06.668142 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 31 14:03:06.668204 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 31 14:03:06.668213 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 31 14:03:06.668221 kernel: PCI host bridge to bus 0000:00 Oct 31 14:03:06.668278 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 31 14:03:06.668327 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 31 14:03:06.668374 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 31 14:03:06.668420 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 31 14:03:06.668488 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 31 14:03:06.668606 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 31 14:03:06.668690 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 31 14:03:06.668761 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 31 14:03:06.668817 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 31 14:03:06.668871 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 31 14:03:06.668961 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 31 14:03:06.669018 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 31 14:03:06.669067 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 31 14:03:06.669118 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 31 14:03:06.669175 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 31 14:03:06.669184 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 31 14:03:06.669190 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 31 14:03:06.669197 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 31 14:03:06.669203 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 31 14:03:06.669209 kernel: vgaarb: loaded Oct 31 14:03:06.669229 kernel: Registered efivars operations Oct 31 14:03:06.669236 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 31 14:03:06.669244 kernel: VFS: Disk quotas dquot_6.6.0 Oct 31 14:03:06.669250 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 31 14:03:06.669256 kernel: pnp: PnP ACPI init Oct 31 14:03:06.669317 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 31 14:03:06.669368 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 31 14:03:06.669376 kernel: pnp: PnP ACPI: found 1 devices Oct 31 14:03:06.669383 kernel: NET: Registered protocol family 2 Oct 31 14:03:06.669389 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 31 14:03:06.669397 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 31 14:03:06.669403 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 31 14:03:06.669409 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 31 14:03:06.669415 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 31 14:03:06.669422 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 14:03:06.669428 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 14:03:06.669434 kernel: NET: Registered protocol family 1 Oct 31 14:03:06.669440 kernel: PCI: CLS 0 bytes, default 64 Oct 31 14:03:06.669446 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 31 14:03:06.669463 kernel: kvm [1]: HYP mode not available Oct 31 14:03:06.669470 kernel: Initialise system trusted keyrings Oct 31 14:03:06.669476 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 31 14:03:06.669482 kernel: Key type asymmetric registered Oct 31 14:03:06.669489 kernel: Asymmetric key parser 'x509' registered Oct 31 14:03:06.669495 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 31 14:03:06.669501 kernel: io scheduler mq-deadline registered Oct 31 14:03:06.669507 kernel: io scheduler kyber registered Oct 31 14:03:06.669513 kernel: io scheduler bfq registered Oct 31 14:03:06.669521 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 31 14:03:06.669527 kernel: ACPI: Power Button [PWRB] Oct 31 14:03:06.669534 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 31 14:03:06.669589 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 31 14:03:06.669597 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 31 14:03:06.669604 kernel: msm_serial: driver initialized Oct 31 14:03:06.669610 kernel: random: fast init done Oct 31 14:03:06.669616 kernel: random: crng init done Oct 31 14:03:06.669622 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 31 14:03:06.669645 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 31 14:03:06.669653 kernel: thunder_xcv, ver 1.0 Oct 31 14:03:06.669659 kernel: thunder_bgx, ver 1.0 Oct 31 14:03:06.669665 kernel: nicpf, ver 1.0 Oct 31 14:03:06.669671 kernel: nicvf, ver 1.0 Oct 31 14:03:06.669738 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 31 14:03:06.669788 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-31T14:03:06 UTC (1761919386) Oct 31 14:03:06.669797 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 31 14:03:06.669803 kernel: NET: Registered protocol family 10 Oct 31 14:03:06.669810 kernel: Segment Routing with IPv6 Oct 31 14:03:06.669817 kernel: NET: Registered protocol family 17 Oct 31 14:03:06.669823 kernel: Key type dns_resolver registered Oct 31 14:03:06.669829 kernel: registered taskstats version 1 Oct 31 14:03:06.669835 kernel: Loading compiled-in X.509 certificates Oct 31 14:03:06.669841 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 31 14:03:06.669848 kernel: Key type ._fscrypt registered Oct 31 14:03:06.669854 kernel: Key type .fscrypt registered Oct 31 14:03:06.669860 kernel: Key type fscrypt-provisioning registered Oct 31 14:03:06.669867 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 31 14:03:06.669873 kernel: ima: Allocated hash algorithm: sha1 Oct 31 14:03:06.669879 kernel: ima: No architecture policies found Oct 31 14:03:06.669885 kernel: Freeing unused kernel memory: 34240K Oct 31 14:03:06.669892 kernel: Run /init as init process Oct 31 14:03:06.669898 kernel: with arguments: Oct 31 14:03:06.669903 kernel: /init Oct 31 14:03:06.669910 kernel: with environment: Oct 31 14:03:06.669916 kernel: HOME=/ Oct 31 14:03:06.669922 kernel: TERM=linux Oct 31 14:03:06.669929 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 31 14:03:06.669937 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 31 14:03:06.669945 systemd[1]: Detected virtualization kvm. Oct 31 14:03:06.669952 systemd[1]: Detected architecture arm64. Oct 31 14:03:06.669958 systemd[1]: Running in initial RAM disk. Oct 31 14:03:06.669965 systemd[1]: No hostname configured, using default hostname. Oct 31 14:03:06.669971 systemd[1]: Hostname set to . Oct 31 14:03:06.669979 systemd[1]: Initializing machine ID from VM UUID. Oct 31 14:03:06.669985 systemd[1]: Queued start job for default target Initrd Default Target. Oct 31 14:03:06.669992 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 31 14:03:06.669998 systemd[1]: Reached target Local Encrypted Volumes. Oct 31 14:03:06.670005 systemd[1]: Reached target Path Units. Oct 31 14:03:06.670011 systemd[1]: Reached target Slice Units. Oct 31 14:03:06.670018 systemd[1]: Reached target Swaps. Oct 31 14:03:06.670024 systemd[1]: Reached target Timer Units. Oct 31 14:03:06.670032 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 31 14:03:06.670038 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 31 14:03:06.670045 systemd[1]: Listening on Journal Audit Socket. Oct 31 14:03:06.670051 systemd[1]: Listening on Journal Socket (/dev/log). Oct 31 14:03:06.670058 systemd[1]: Listening on Journal Socket. Oct 31 14:03:06.670064 systemd[1]: Listening on udev Control Socket. Oct 31 14:03:06.670070 systemd[1]: Listening on udev Kernel Socket. Oct 31 14:03:06.670077 systemd[1]: Reached target Socket Units. Oct 31 14:03:06.670083 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 31 14:03:06.670091 systemd[1]: Starting Create List of Static Device Nodes... Oct 31 14:03:06.670098 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 31 14:03:06.670105 systemd[1]: Starting Journal Service... Oct 31 14:03:06.670111 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 31 14:03:06.670118 systemd[1]: Starting Apply Kernel Variables... Oct 31 14:03:06.670124 systemd[1]: Starting Setup Virtual Console... Oct 31 14:03:06.670131 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 31 14:03:06.670137 kernel: SCSI subsystem initialized Oct 31 14:03:06.670143 systemd[1]: Finished Create List of Static Device Nodes. Oct 31 14:03:06.670150 systemd[1]: Finished Apply Kernel Variables. Oct 31 14:03:06.670157 kernel: Loading iSCSI transport class v2.0-870. Oct 31 14:03:06.670170 systemd[1]: Finished Setup Virtual Console. Oct 31 14:03:06.670183 systemd-journald[270]: Journal started Oct 31 14:03:06.670222 systemd-journald[270]: Runtime Journal (/run/log/journal/d8d9977182e744b4adbf916ed31da156) is 6.0M, max 48.5M, 42.4M free. Oct 31 14:03:06.672050 systemd[1]: Started Journal Service. Oct 31 14:03:06.672851 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 31 14:03:06.674192 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 31 14:03:06.677191 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 31 14:03:06.685939 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 31 14:03:06.687528 systemd[1]: Starting dracut cmdline hook... Oct 31 14:03:06.695837 dracut-cmdline[289]: dracut-dracut-053 Oct 31 14:03:06.697998 dracut-cmdline[289]: 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 31 14:03:06.751661 kernel: iscsi: registered transport (tcp) Oct 31 14:03:06.762655 kernel: iscsi: registered transport (qla4xxx) Oct 31 14:03:06.762684 kernel: QLogic iSCSI HBA Driver Oct 31 14:03:06.771815 systemd[1]: Finished dracut cmdline hook. Oct 31 14:03:06.773111 systemd[1]: Starting dracut pre-udev hook... Oct 31 14:03:06.774259 systemd[1]: Starting Open-iSCSI... Oct 31 14:03:06.776414 iscsid[375]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 31 14:03:06.776414 iscsid[375]: 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 31 14:03:06.776414 iscsid[375]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 31 14:03:06.776414 iscsid[375]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 31 14:03:06.776414 iscsid[375]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 31 14:03:06.776414 iscsid[375]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 31 14:03:06.789256 kernel: device-mapper: uevent: version 1.0.3 Oct 31 14:03:06.789273 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 31 14:03:06.779879 systemd[1]: Started Open-iSCSI. Oct 31 14:03:06.822652 kernel: raid6: neonx8 gen() 13781 MB/s Oct 31 14:03:06.839647 kernel: raid6: neonx8 xor() 10743 MB/s Oct 31 14:03:06.856642 kernel: raid6: neonx4 gen() 13574 MB/s Oct 31 14:03:06.873646 kernel: raid6: neonx4 xor() 11569 MB/s Oct 31 14:03:06.890641 kernel: raid6: neonx2 gen() 13015 MB/s Oct 31 14:03:06.907647 kernel: raid6: neonx2 xor() 9877 MB/s Oct 31 14:03:06.924647 kernel: raid6: neonx1 gen() 10917 MB/s Oct 31 14:03:06.941649 kernel: raid6: neonx1 xor() 8805 MB/s Oct 31 14:03:06.958647 kernel: raid6: int64x8 gen() 6814 MB/s Oct 31 14:03:06.975657 kernel: raid6: int64x8 xor() 3797 MB/s Oct 31 14:03:06.992655 kernel: raid6: int64x4 gen() 6914 MB/s Oct 31 14:03:07.009657 kernel: raid6: int64x4 xor() 3833 MB/s Oct 31 14:03:07.026655 kernel: raid6: int64x2 gen() 6194 MB/s Oct 31 14:03:07.043655 kernel: raid6: int64x2 xor() 3325 MB/s Oct 31 14:03:07.060653 kernel: raid6: int64x1 gen() 4998 MB/s Oct 31 14:03:07.077740 kernel: raid6: int64x1 xor() 2597 MB/s Oct 31 14:03:07.077755 kernel: raid6: using algorithm neonx8 gen() 13781 MB/s Oct 31 14:03:07.077763 kernel: raid6: .... xor() 10743 MB/s, rmw enabled Oct 31 14:03:07.078853 kernel: raid6: using neon recovery algorithm Oct 31 14:03:07.089169 kernel: xor: measuring software checksum speed Oct 31 14:03:07.089183 kernel: 8regs : 17195 MB/sec Oct 31 14:03:07.091006 kernel: 32regs : 20765 MB/sec Oct 31 14:03:07.091017 kernel: arm64_neon : 29173 MB/sec Oct 31 14:03:07.091025 kernel: xor: using function: arm64_neon (29173 MB/sec) Oct 31 14:03:07.135654 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 31 14:03:07.145194 systemd[1]: Finished dracut pre-udev hook. Oct 31 14:03:07.145000 audit: BPF prog-id=6 op=LOAD Oct 31 14:03:07.146000 audit: BPF prog-id=7 op=LOAD Oct 31 14:03:07.147841 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 31 14:03:07.150150 kernel: audit: type=1334 audit(1761919387.145:2): prog-id=6 op=LOAD Oct 31 14:03:07.150176 kernel: audit: type=1334 audit(1761919387.146:3): prog-id=7 op=LOAD Oct 31 14:03:07.150185 kernel: audit: type=1334 audit(1761919387.146:4): prog-id=8 op=LOAD Oct 31 14:03:07.146000 audit: BPF prog-id=8 op=LOAD Oct 31 14:03:07.160033 systemd-udevd[407]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 31 14:03:07.161727 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 31 14:03:07.164013 systemd[1]: Starting dracut pre-trigger hook... Oct 31 14:03:07.174230 dracut-pre-trigger[415]: rd.md=0: removing MD RAID activation Oct 31 14:03:07.195407 systemd[1]: Finished dracut pre-trigger hook. Oct 31 14:03:07.196810 systemd[1]: Starting Coldplug All udev Devices... Oct 31 14:03:07.205822 systemd-udevd[407]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 31 14:03:07.224509 systemd[1]: Finished Coldplug All udev Devices. Oct 31 14:03:07.225978 systemd[1]: Starting dracut initqueue hook... Oct 31 14:03:07.235736 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 31 14:03:07.235862 kernel: vda: detected capacity change from 0 to 4756340736 Oct 31 14:03:07.245756 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 14:03:07.253864 systemd-udevd[461]: Using default interface naming scheme 'v249'. Oct 31 14:03:07.265658 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (458) Oct 31 14:03:07.270430 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 31 14:03:07.272614 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 31 14:03:07.273658 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 31 14:03:07.279838 systemd[1]: Finished dracut initqueue hook. Oct 31 14:03:07.280697 systemd[1]: Reached target Initrd Root Device. Oct 31 14:03:07.281995 systemd[1]: Reached target Preparation for Remote File Systems. Oct 31 14:03:07.283548 systemd[1]: Reached target Remote Encrypted Volumes. Oct 31 14:03:07.285211 systemd[1]: Reached target Remote File Systems. Oct 31 14:03:07.287176 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 31 14:03:07.288804 systemd[1]: Starting dracut pre-mount hook... Oct 31 14:03:07.290006 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 31 14:03:07.290108 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 31 14:03:07.291475 systemd[1]: Reached target Preparation for Local File Systems. Oct 31 14:03:07.294126 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 31 14:03:07.296861 systemd[1]: Finished dracut pre-mount hook. Oct 31 14:03:07.298223 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 31 14:03:07.306066 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 31 14:03:07.306894 systemd-fsck[501]: ROOT: clean, 641/553792 files, 42011/553472 blocks Oct 31 14:03:07.309418 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 31 14:03:07.311826 systemd[1]: Mounting /sysroot... Oct 31 14:03:07.326731 systemd[1]: Found device /dev/mapper/usr. Oct 31 14:03:07.328624 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 31 14:03:07.332735 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 31 14:03:07.339312 systemd-fsck[515]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 31 14:03:07.339312 systemd-fsck[515]: You must have r/w access to the filesystem or be root Oct 31 14:03:07.345686 systemd-fsck[510]: fsck failed with exit status 8. Oct 31 14:03:07.345692 systemd-fsck[510]: Ignoring error. Oct 31 14:03:07.347977 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 31 14:03:07.359380 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 31 14:03:07.359406 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 31 14:03:07.359416 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 31 14:03:07.349521 systemd[1]: Mounting /sysusr/usr... Oct 31 14:03:07.356817 systemd[1]: Mounted /sysusr/usr. Oct 31 14:03:07.360531 systemd[1]: Mounted /sysroot. Oct 31 14:03:07.361333 systemd[1]: Reached target Initrd Root File System. Oct 31 14:03:07.362596 systemd[1]: Reached target Local File Systems. Oct 31 14:03:07.363808 systemd[1]: Reached target System Initialization. Oct 31 14:03:07.365277 systemd[1]: Reached target Basic System. Oct 31 14:03:07.367461 systemd[1]: Mounting /sysroot/usr... Oct 31 14:03:07.369244 systemd[1]: Starting Reload Configuration from the Real Root... Oct 31 14:03:07.370692 systemd[1]: Mounted /sysroot/usr. Oct 31 14:03:07.373203 systemd[1]: Reloading. Oct 31 14:03:07.374000 audit: BPF prog-id=3 op=UNLOAD Oct 31 14:03:07.375647 kernel: audit: type=1334 audit(1761919387.374:5): prog-id=3 op=UNLOAD Oct 31 14:03:07.376000 audit: BPF prog-id=6 op=UNLOAD Oct 31 14:03:07.378641 kernel: audit: type=1334 audit(1761919387.376:6): prog-id=6 op=UNLOAD Oct 31 14:03:07.458000 audit: BPF prog-id=9 op=LOAD Oct 31 14:03:07.459000 audit: BPF prog-id=10 op=LOAD Oct 31 14:03:07.460826 kernel: audit: type=1334 audit(1761919387.458:7): prog-id=9 op=LOAD Oct 31 14:03:07.460846 kernel: audit: type=1334 audit(1761919387.459:8): prog-id=10 op=LOAD Oct 31 14:03:07.460000 audit: BPF prog-id=11 op=LOAD Oct 31 14:03:07.461701 kernel: audit: type=1334 audit(1761919387.460:9): prog-id=11 op=LOAD Oct 31 14:03:07.461000 audit: BPF prog-id=4 op=UNLOAD Oct 31 14:03:07.461000 audit: BPF prog-id=5 op=UNLOAD Oct 31 14:03:07.462649 kernel: audit: type=1334 audit(1761919387.461:10): prog-id=4 op=UNLOAD Oct 31 14:03:07.462000 audit: BPF prog-id=12 op=LOAD Oct 31 14:03:07.462000 audit: BPF prog-id=13 op=LOAD Oct 31 14:03:07.462000 audit: BPF prog-id=14 op=LOAD Oct 31 14:03:07.462000 audit: BPF prog-id=7 op=UNLOAD Oct 31 14:03:07.462000 audit: BPF prog-id=8 op=UNLOAD Oct 31 14:03:07.476178 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 31 14:03:07.476292 systemd[1]: Finished Reload Configuration from the Real Root. Oct 31 14:03:07.478122 systemd[1]: Reached target Initrd File Systems. Oct 31 14:03:07.479562 systemd[1]: Reached target Initrd Default Target. Oct 31 14:03:07.481058 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 31 14:03:07.481703 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 31 14:03:07.491351 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 31 14:03:07.492940 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 31 14:03:07.498430 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 31 14:03:07.499423 systemd[1]: Stopped target Timer Units. Oct 31 14:03:07.500794 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 31 14:03:07.500901 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 31 14:03:07.502269 systemd[1]: Stopped target Initrd Default Target. Oct 31 14:03:07.503774 systemd[1]: Stopped target Basic System. Oct 31 14:03:07.505229 systemd[1]: Stopped target Initrd Root Device. Oct 31 14:03:07.506614 systemd[1]: Stopped target Path Units. Oct 31 14:03:07.507900 systemd[1]: Stopped target Remote File Systems. Oct 31 14:03:07.509347 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 31 14:03:07.510884 systemd[1]: Stopped target Slice Units. Oct 31 14:03:07.512188 systemd[1]: Stopped target Socket Units. Oct 31 14:03:07.513705 systemd[1]: Stopped target System Initialization. Oct 31 14:03:07.515050 systemd[1]: Stopped target Local File Systems. Oct 31 14:03:07.516361 systemd[1]: Stopped target Preparation for Local File Systems. Oct 31 14:03:07.517912 systemd[1]: Stopped target Swaps. Oct 31 14:03:07.519115 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 31 14:03:07.519235 systemd[1]: Stopped dracut pre-mount hook. Oct 31 14:03:07.520660 systemd[1]: Stopped target Local Encrypted Volumes. Oct 31 14:03:07.521850 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 31 14:03:07.523674 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 31 14:03:07.525182 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 31 14:03:07.530544 iscsid[375]: iscsid shutting down. Oct 31 14:03:07.525285 systemd[1]: Stopped dracut initqueue hook. Oct 31 14:03:07.526878 systemd[1]: Stopping Open-iSCSI... Oct 31 14:03:07.529670 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 31 14:03:07.529797 systemd[1]: Stopped Apply Kernel Variables. Oct 31 14:03:07.531356 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 31 14:03:07.531453 systemd[1]: Stopped Coldplug All udev Devices. Oct 31 14:03:07.532568 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 31 14:03:07.532702 systemd[1]: Stopped dracut pre-trigger hook. Oct 31 14:03:07.533985 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 31 14:03:07.536031 systemd[1]: iscsid.service: Deactivated successfully. Oct 31 14:03:07.536142 systemd[1]: Stopped Open-iSCSI. Oct 31 14:03:07.540079 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 31 14:03:07.540188 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 31 14:03:07.541400 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 31 14:03:07.551000 audit: BPF prog-id=12 op=UNLOAD Oct 31 14:03:07.541517 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 31 14:03:07.543271 systemd[1]: iscsid.socket: Deactivated successfully. Oct 31 14:03:07.543300 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 31 14:03:07.544433 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 31 14:03:07.544473 systemd[1]: Closed udev Control Socket. Oct 31 14:03:07.546005 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 31 14:03:07.546039 systemd[1]: Closed udev Kernel Socket. Oct 31 14:03:07.547373 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 31 14:03:07.547417 systemd[1]: Stopped dracut pre-udev hook. Oct 31 14:03:07.548585 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 31 14:03:07.548641 systemd[1]: Stopped dracut cmdline hook. Oct 31 14:03:07.550101 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 31 14:03:07.550144 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 31 14:03:07.552398 systemd[1]: Starting Cleanup udev Database... Oct 31 14:03:07.553323 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 31 14:03:07.555706 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 31 14:03:07.556979 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 31 14:03:07.574000 audit: BPF prog-id=9 op=UNLOAD Oct 31 14:03:07.557042 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 31 14:03:07.558493 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 31 14:03:07.558536 systemd[1]: Stopped Create List of Static Device Nodes. Oct 31 14:03:07.560206 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 14:03:07.560250 systemd[1]: Stopped Setup Virtual Console. Oct 31 14:03:07.561558 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 31 14:03:07.561693 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 31 14:03:07.562965 systemd[1]: rngd.service: Deactivated successfully. Oct 31 14:03:07.563065 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 31 14:03:07.564512 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 31 14:03:07.564609 systemd[1]: Finished Cleanup udev Database. Oct 31 14:03:07.565984 systemd[1]: Reached target Switch Root. Oct 31 14:03:07.567192 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 31 14:03:07.567231 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 31 14:03:07.569242 systemd[1]: Starting Switch Root... Oct 31 14:03:07.574441 systemd[1]: Switching root. Oct 31 14:03:07.592181 systemd-journald[270]: Journal stopped Oct 31 14:03:09.646431 systemd-journald[270]: Received SIGTERM from PID 1 (systemd). Oct 31 14:03:09.646472 kernel: SELinux: policy capability network_peer_controls=1 Oct 31 14:03:09.646483 kernel: SELinux: policy capability open_perms=1 Oct 31 14:03:09.646498 kernel: SELinux: policy capability extended_socket_class=1 Oct 31 14:03:09.646508 kernel: SELinux: policy capability always_check_network=0 Oct 31 14:03:09.646517 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 31 14:03:09.646526 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 31 14:03:09.646535 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 31 14:03:09.646545 systemd[1]: Successfully loaded SELinux policy in 30.616ms. Oct 31 14:03:09.646558 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.076ms. Oct 31 14:03:09.646569 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 31 14:03:09.646578 systemd[1]: Detected virtualization kvm. Oct 31 14:03:09.646589 systemd[1]: Detected architecture arm64. Oct 31 14:03:09.646602 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 31 14:03:09.646614 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 31 14:03:09.646624 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 31 14:03:09.646661 systemd[1]: Stopped Switch Root. Oct 31 14:03:09.646671 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 31 14:03:09.646681 systemd[1]: Created slice Slice /system/addon-config. Oct 31 14:03:09.646691 systemd[1]: Created slice Slice /system/addon-run. Oct 31 14:03:09.646700 systemd[1]: Created slice Slice /system/getty. Oct 31 14:03:09.646711 systemd[1]: Created slice Slice /system/modprobe. Oct 31 14:03:09.646721 systemd[1]: Created slice Slice /system/serial-getty. Oct 31 14:03:09.646730 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 31 14:03:09.646739 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 31 14:03:09.646749 systemd[1]: Created slice User and Session Slice. Oct 31 14:03:09.646759 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 31 14:03:09.646768 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 31 14:03:09.646778 systemd[1]: Set up automount Boot partition Automount Point. Oct 31 14:03:09.646787 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 31 14:03:09.646798 systemd[1]: Stopped target Switch Root. Oct 31 14:03:09.646808 systemd[1]: Stopped target Initrd File Systems. Oct 31 14:03:09.646817 systemd[1]: Stopped target Initrd Root File System. Oct 31 14:03:09.646827 systemd[1]: Reached target Remote Encrypted Volumes. Oct 31 14:03:09.646837 systemd[1]: Reached target Remote File Systems. Oct 31 14:03:09.646847 systemd[1]: Reached target Slice Units. Oct 31 14:03:09.646857 systemd[1]: Reached target Swaps. Oct 31 14:03:09.646867 systemd[1]: Reached target Verify torcx succeeded. Oct 31 14:03:09.646876 systemd[1]: Reached target Local Verity Protected Volumes. Oct 31 14:03:09.646886 systemd[1]: Listening on Process Core Dump Socket. Oct 31 14:03:09.646897 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 31 14:03:09.646907 systemd[1]: Listening on Network Service Netlink Socket. Oct 31 14:03:09.646916 systemd[1]: Listening on udev Control Socket. Oct 31 14:03:09.646926 systemd[1]: Listening on udev Kernel Socket. Oct 31 14:03:09.646935 systemd[1]: Mounting Huge Pages File System... Oct 31 14:03:09.646945 systemd[1]: Mounting POSIX Message Queue File System... Oct 31 14:03:09.646954 systemd[1]: Mounting External Media Directory... Oct 31 14:03:09.646963 systemd[1]: Mounting Kernel Debug File System... Oct 31 14:03:09.646973 systemd[1]: Mounting Kernel Trace File System... Oct 31 14:03:09.646983 systemd[1]: Mounting Temporary Directory /tmp... Oct 31 14:03:09.646993 systemd[1]: Starting Create missing system files... Oct 31 14:03:09.647002 systemd[1]: Starting Create List of Static Device Nodes... Oct 31 14:03:09.647012 systemd[1]: Starting Load Kernel Module configfs... Oct 31 14:03:09.647021 systemd[1]: Starting Load Kernel Module drm... Oct 31 14:03:09.647030 systemd[1]: Starting Load Kernel Module fuse... Oct 31 14:03:09.647041 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 31 14:03:09.647051 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 31 14:03:09.647062 systemd[1]: Stopped File System Check on Root Device. Oct 31 14:03:09.647073 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 31 14:03:09.647083 systemd[1]: Stopped systemd-fsck-usr.service. Oct 31 14:03:09.647092 kernel: fuse: init (API version 7.32) Oct 31 14:03:09.647102 systemd[1]: Stopped Journal Service. Oct 31 14:03:09.647112 systemd[1]: Starting Journal Service... Oct 31 14:03:09.647122 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 31 14:03:09.647132 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 31 14:03:09.647141 systemd[1]: Starting Apply Kernel Variables... Oct 31 14:03:09.647156 systemd[1]: Starting Coldplug All udev Devices... Oct 31 14:03:09.647167 systemd[1]: verity-setup.service: Deactivated successfully. Oct 31 14:03:09.647178 systemd[1]: Stopped verity-setup.service. Oct 31 14:03:09.647189 systemd-journald[692]: Journal started Oct 31 14:03:09.647225 systemd-journald[692]: Runtime Journal (/run/log/journal/d8d9977182e744b4adbf916ed31da156) is 6.0M, max 48.5M, 42.4M free. Oct 31 14:03:07.661000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 31 14:03:07.686000 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 31 14:03:07.686000 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 31 14:03:07.686000 audit: BPF prog-id=15 op=LOAD Oct 31 14:03:07.686000 audit: BPF prog-id=15 op=UNLOAD Oct 31 14:03:07.686000 audit: BPF prog-id=16 op=LOAD Oct 31 14:03:07.686000 audit: BPF prog-id=16 op=UNLOAD Oct 31 14:03:07.720000 audit[645]: AVC avc: denied { associate } for pid=645 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 31 14:03:09.500000 audit: BPF prog-id=17 op=LOAD Oct 31 14:03:09.500000 audit: BPF prog-id=18 op=LOAD Oct 31 14:03:09.500000 audit: BPF prog-id=19 op=LOAD Oct 31 14:03:09.500000 audit: BPF prog-id=10 op=UNLOAD Oct 31 14:03:09.500000 audit: BPF prog-id=11 op=UNLOAD Oct 31 14:03:09.516000 audit: BPF prog-id=20 op=LOAD Oct 31 14:03:09.516000 audit: BPF prog-id=17 op=UNLOAD Oct 31 14:03:09.516000 audit: BPF prog-id=21 op=LOAD Oct 31 14:03:09.517000 audit: BPF prog-id=22 op=LOAD Oct 31 14:03:09.517000 audit: BPF prog-id=18 op=UNLOAD Oct 31 14:03:09.517000 audit: BPF prog-id=19 op=UNLOAD Oct 31 14:03:09.523000 audit: BPF prog-id=20 op=UNLOAD Oct 31 14:03:09.631000 audit: BPF prog-id=23 op=LOAD Oct 31 14:03:09.632000 audit: BPF prog-id=24 op=LOAD Oct 31 14:03:09.632000 audit: BPF prog-id=25 op=LOAD Oct 31 14:03:09.632000 audit: BPF prog-id=21 op=UNLOAD Oct 31 14:03:09.632000 audit: BPF prog-id=22 op=UNLOAD Oct 31 14:03:07.718584 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" 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 31 14:03:09.500213 systemd[1]: Queued start job for default target Multi-User System. Oct 31 14:03:07.718890 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 31 14:03:09.518278 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 31 14:03:07.718909 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 31 14:03:07.719213 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 31 14:03:07.719225 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 31 14:03:07.719260 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 31 14:03:07.719273 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 31 14:03:07.719605 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 31 14:03:07.719660 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 31 14:03:07.719675 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 31 14:03:07.720031 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" 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 31 14:03:07.720060 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" 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 31 14:03:07.720080 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" 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 31 14:03:09.644000 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 31 14:03:07.720095 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 31 14:03:07.720208 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" 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 31 14:03:07.720225 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:07Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 31 14:03:09.408357 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:09Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 14:03:09.408625 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:09Z" 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 31 14:03:09.408739 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:09Z" 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 31 14:03:09.408858 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:09Z" 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 31 14:03:09.408952 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:09Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 31 14:03:09.409009 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-31T14:03:09Z" 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 31 14:03:09.649660 systemd[1]: Started Journal Service. Oct 31 14:03:09.650025 systemd[1]: Mounted Huge Pages File System. Oct 31 14:03:09.651012 systemd[1]: Mounted POSIX Message Queue File System. Oct 31 14:03:09.652006 systemd[1]: Mounted External Media Directory. Oct 31 14:03:09.652819 systemd[1]: Mounted Kernel Debug File System. Oct 31 14:03:09.653712 systemd[1]: Mounted Kernel Trace File System. Oct 31 14:03:09.654563 systemd[1]: Mounted Temporary Directory /tmp. Oct 31 14:03:09.655738 systemd[1]: Finished Create missing system files. Oct 31 14:03:09.656881 systemd[1]: Finished Create List of Static Device Nodes. Oct 31 14:03:09.658034 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 31 14:03:09.658237 systemd[1]: Finished Load Kernel Module configfs. Oct 31 14:03:09.659370 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 14:03:09.659540 systemd[1]: Finished Load Kernel Module drm. Oct 31 14:03:09.660540 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 31 14:03:09.660729 systemd[1]: Finished Load Kernel Module fuse. Oct 31 14:03:09.661702 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 31 14:03:09.662924 systemd[1]: Finished Apply Kernel Variables. Oct 31 14:03:09.692000 systemd[1]: Mounting FUSE Control File System... Oct 31 14:03:09.693931 systemd[1]: Mounting Kernel Configuration File System... Oct 31 14:03:09.694793 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 31 14:03:09.695793 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 31 14:03:09.696750 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 31 14:03:09.697711 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 31 14:03:09.698778 systemd[1]: Starting Load/Save Random Seed... Oct 31 14:03:09.699609 systemd[1]: Condition check resulted in Create System Users being skipped. Oct 31 14:03:09.701067 systemd-journald[692]: Time spent on flushing to /var/log/journal/d8d9977182e744b4adbf916ed31da156 is 10.847ms for 661 entries. Oct 31 14:03:09.701067 systemd-journald[692]: System Journal (/var/log/journal/d8d9977182e744b4adbf916ed31da156) is 16.0M, max 203.0M, 187.0M free. Oct 31 14:03:09.718000 audit: BPF prog-id=26 op=LOAD Oct 31 14:03:09.718000 audit: BPF prog-id=27 op=LOAD Oct 31 14:03:09.718000 audit: BPF prog-id=28 op=LOAD Oct 31 14:03:09.718000 audit: BPF prog-id=13 op=UNLOAD Oct 31 14:03:09.718000 audit: BPF prog-id=14 op=UNLOAD Oct 31 14:03:09.700603 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 31 14:03:09.703696 systemd[1]: Mounted FUSE Control File System. Oct 31 14:03:09.704700 systemd[1]: Mounted Kernel Configuration File System. Oct 31 14:03:09.706670 systemd[1]: Finished Load/Save Random Seed. Oct 31 14:03:09.707656 systemd[1]: Condition check resulted in First Boot Complete being skipped. Oct 31 14:03:09.709778 systemd[1]: Finished Coldplug All udev Devices. Oct 31 14:03:09.716461 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 31 14:03:09.717817 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 31 14:03:09.720217 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 31 14:03:09.727585 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 31 14:03:09.742837 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 31 14:03:09.744000 audit: BPF prog-id=29 op=LOAD Oct 31 14:03:09.745908 systemd[1]: Starting Network Configuration... Oct 31 14:03:09.772882 systemd-networkd[713]: lo: Link UP Oct 31 14:03:09.772890 systemd-networkd[713]: lo: Gained carrier Oct 31 14:03:09.773220 systemd-networkd[713]: Enumeration completed Oct 31 14:03:09.773309 systemd[1]: Started Network Configuration. Oct 31 14:03:09.782503 systemd-udevd[716]: Using default interface naming scheme 'v249'. Oct 31 14:03:09.805826 udevadm[703]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 31 14:03:09.811749 systemd-networkd[713]: eth0: Link UP Oct 31 14:03:09.826681 systemd-networkd[713]: eth0: Gained carrier Oct 31 14:03:09.832518 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 31 14:03:09.840838 systemd-networkd[713]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Oct 31 14:03:09.845793 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 31 14:03:09.854343 lvm[732]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 14:03:09.897699 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 31 14:03:09.899129 systemd[1]: Reached target Local Encrypted Volumes. Oct 31 14:03:09.911977 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 31 14:03:09.916085 lvm[734]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 14:03:09.947796 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 31 14:03:09.949295 systemd[1]: Reached target Preparation for Local File Systems. Oct 31 14:03:09.950589 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 31 14:03:09.950619 systemd[1]: Reached target Containers. Oct 31 14:03:09.963083 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 31 14:03:09.977192 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 31 14:03:09.979285 systemd[1]: Mounting /usr/share/oem... Oct 31 14:03:09.985123 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 31 14:03:09.985178 kernel: BTRFS info (device vda6): has skinny extents Oct 31 14:03:09.987309 systemd[1]: Mounted /usr/share/oem. Oct 31 14:03:09.988120 systemd[1]: Reached target Local File Systems. Oct 31 14:03:09.989043 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Oct 31 14:03:09.989406 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 31 14:03:09.989441 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 31 14:03:09.989469 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 31 14:03:09.990347 systemd[1]: Starting Create Volatile Files and Directories... Oct 31 14:03:09.996030 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 31 14:03:09.997048 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 31 14:03:10.023847 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Oct 31 14:03:10.023858 systemd-tmpfiles[757]: Skipping /boot Oct 31 14:03:10.029213 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Oct 31 14:03:10.029229 systemd-tmpfiles[757]: Skipping /boot Oct 31 14:03:10.057121 systemd[1]: Finished Create Volatile Files and Directories. Oct 31 14:03:10.072805 systemd[1]: Starting Load Security Auditing Rules... Oct 31 14:03:10.074679 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 31 14:03:10.075746 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Oct 31 14:03:10.075000 audit: BPF prog-id=30 op=LOAD Oct 31 14:03:10.077381 systemd[1]: Starting Network Name Resolution... Oct 31 14:03:10.078000 audit: BPF prog-id=31 op=LOAD Oct 31 14:03:10.079659 systemd[1]: Starting Network Time Synchronization... Oct 31 14:03:10.080590 systemd[1]: Condition check resulted in Update is Completed being skipped. Oct 31 14:03:10.081593 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 31 14:03:10.083176 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 31 14:03:10.084429 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 31 14:03:10.091988 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 31 14:03:10.101000 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 31 14:03:10.102197 augenrules[778]: No rules Oct 31 14:03:10.103114 systemd[1]: Finished Load Security Auditing Rules. Oct 31 14:03:10.115842 systemd-resolved[761]: Positive Trust Anchors: Oct 31 14:03:10.115854 systemd-resolved[761]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 14:03:10.115882 systemd-resolved[761]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 31 14:03:10.116666 systemd-resolved[761]: Defaulting to hostname 'linux'. Oct 31 14:03:08.966072 systemd-timesyncd[767]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 31 14:03:08.966589 systemd-resolved[761]: Clock change detected. Flushing caches. Oct 31 14:03:08.968103 systemd[1]: Started Network Time Synchronization. Oct 31 14:03:08.969240 systemd[1]: Started Network Name Resolution. Oct 31 14:03:08.970238 systemd[1]: Reached target Network. Oct 31 14:03:08.971052 systemd[1]: Reached target Host and Network Name Lookups. Oct 31 14:03:08.973371 systemd[1]: Reached target System Initialization. Oct 31 14:03:08.974374 systemd[1]: Started Watch for update engine configuration changes. Oct 31 14:03:08.975509 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 31 14:03:08.976831 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 31 14:03:08.977857 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 31 14:03:08.977886 systemd[1]: Reached target Path Units. Oct 31 14:03:08.978728 systemd[1]: Reached target System Time Set. Oct 31 14:03:08.979777 systemd[1]: Started Daily Log Rotation. Oct 31 14:03:08.980691 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 31 14:03:08.981866 systemd[1]: Reached target Timer Units. Oct 31 14:03:08.983284 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 31 14:03:09.002158 systemd[1]: Starting Docker Socket for the API... Oct 31 14:03:09.006125 systemd[1]: Listening on OpenSSH Server Socket. Oct 31 14:03:09.007476 systemd[1]: Listening on Docker Socket for the API. Oct 31 14:03:09.008491 systemd[1]: Reached target Socket Units. Oct 31 14:03:09.009312 systemd[1]: Reached target Basic System. Oct 31 14:03:09.010145 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 31 14:03:09.010176 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 31 14:03:09.011114 systemd[1]: Started D-Bus System Message Bus. Oct 31 14:03:09.014909 systemd[1]: Starting Extend Filesystems... Oct 31 14:03:09.015881 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 31 14:03:09.016927 systemd[1]: Starting Generate /run/flatcar/motd... Oct 31 14:03:09.018815 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 31 14:03:09.020917 systemd[1]: Starting Generate sshd host keys... Oct 31 14:03:09.022454 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 31 14:03:09.022493 systemd[1]: Reached target Load system-provided cloud configs. Oct 31 14:03:09.025884 systemd[1]: Starting User Login Management... Oct 31 14:03:09.027629 extend-filesystems[790]: Found vda Oct 31 14:03:09.027629 extend-filesystems[790]: Found vda1 Oct 31 14:03:09.027629 extend-filesystems[790]: Found vda2 Oct 31 14:03:09.027629 extend-filesystems[790]: Found vda3 Oct 31 14:03:09.027629 extend-filesystems[790]: Found usr Oct 31 14:03:09.027629 extend-filesystems[790]: Found vda4 Oct 31 14:03:09.027629 extend-filesystems[790]: Found vda6 Oct 31 14:03:09.027629 extend-filesystems[790]: Found vda7 Oct 31 14:03:09.027629 extend-filesystems[790]: Found vda9 Oct 31 14:03:09.027629 extend-filesystems[790]: Checking size of /dev/vda9 Oct 31 14:03:09.059947 extend-filesystems[790]: Old size kept for /dev/vda9 Oct 31 14:03:09.028028 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 31 14:03:09.029747 systemd[1]: Starting Update Engine... Oct 31 14:03:09.030528 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 31 14:03:09.030556 systemd[1]: Reached target Load user-provided cloud configs. Oct 31 14:03:09.032538 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 31 14:03:09.032771 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 31 14:03:09.034155 systemd[1]: motdgen.service: Deactivated successfully. Oct 31 14:03:09.034347 systemd[1]: Finished Generate /run/flatcar/motd. Oct 31 14:03:09.035799 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 31 14:03:09.036019 systemd[1]: Finished Extend Filesystems. Oct 31 14:03:09.068509 systemd-logind[803]: Watching system buttons on /dev/input/event0 (Power Button) Oct 31 14:03:09.068813 systemd-logind[803]: New seat seat0. Oct 31 14:03:09.070521 systemd[1]: Started User Login Management. Oct 31 14:03:09.078091 systemd[1]: Finished Generate sshd host keys. Oct 31 14:03:09.081989 update_engine[807]: I1031 14:03:09.081718 807 main.cc:89] Flatcar Update Engine starting Oct 31 14:03:09.087158 update_engine[807]: I1031 14:03:09.082657 807 payload_state.cc:360] Current Response Signature = Oct 31 14:03:09.087158 update_engine[807]: NumURLs = 1 Oct 31 14:03:09.087158 update_engine[807]: Url0 = http://10.0.0.2:34567/packages/update.gz Oct 31 14:03:09.087158 update_engine[807]: Payload Size = 426251591 Oct 31 14:03:09.087158 update_engine[807]: Payload Sha256 Hash = qwysf93IzFKZIIEIOAr8FB/Ur4+2vzR6efmfEvB0U+Q= Oct 31 14:03:09.087158 update_engine[807]: Is Delta Payload = 0 Oct 31 14:03:09.087158 update_engine[807]: Max Failure Count Per Url = 10 Oct 31 14:03:09.087158 update_engine[807]: Disable Payload Backoff = 1 Oct 31 14:03:09.087158 update_engine[807]: I1031 14:03:09.082846 807 payload_state.cc:381] Payload Attempt Number = 0 Oct 31 14:03:09.087158 update_engine[807]: I1031 14:03:09.083016 807 payload_state.cc:404] Current URL Index = 0 Oct 31 14:03:09.087158 update_engine[807]: I1031 14:03:09.083176 807 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 31 14:03:09.087158 update_engine[807]: I1031 14:03:09.083343 807 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 31 14:03:09.087158 update_engine[807]: I1031 14:03:09.085139 807 update_check_scheduler.cc:74] Next update check in 5m15s Oct 31 14:03:09.087911 systemd[1]: Starting Generate /run/issue... Oct 31 14:03:09.088803 systemd[1]: Started Update Engine. Oct 31 14:03:09.092215 systemd[1]: issuegen.service: Deactivated successfully. Oct 31 14:03:09.092424 systemd[1]: Finished Generate /run/issue. Oct 31 14:03:09.094414 systemd[1]: Starting Permit User Sessions... Oct 31 14:03:09.098521 systemd[1]: Finished Permit User Sessions. Oct 31 14:03:09.100558 systemd[1]: Started Getty on tty1. Oct 31 14:03:09.102406 systemd[1]: Started Serial Getty on ttyAMA0. Oct 31 14:03:09.103410 systemd[1]: Reached target Login Prompts. Oct 31 14:03:09.104313 systemd[1]: Reached target Multi-User System. Oct 31 14:03:09.106320 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 31 14:03:09.111010 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 31 14:03:09.111213 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 31 14:03:09.112231 systemd[1]: Startup finished in 682ms (kernel) + 1.073s (initrd) + 2.635s (userspace) = 4.391s. Oct 31 14:03:09.809705 systemd-networkd[713]: eth0: Gained IPv6LL Oct 31 14:03:18.870196 systemd[1]: Created slice Slice /system/sshd. Oct 31 14:03:18.871231 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:38664). Oct 31 14:03:18.917018 sshd[829]: Accepted publickey for core from 10.0.0.1 port 38664 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:03:18.919606 sshd[829]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:03:18.937160 systemd[1]: Created slice User Slice of UID 500. Oct 31 14:03:18.938270 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 31 14:03:18.939807 systemd-logind[803]: New session 1 of user core. Oct 31 14:03:18.944047 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 31 14:03:18.945156 systemd[1]: Starting User Manager for UID 500... Oct 31 14:03:18.947260 systemd[832]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:03:18.992085 systemd[832]: Queued start job for default target Main User Target. Oct 31 14:03:18.992226 systemd[832]: Reached target Paths. Oct 31 14:03:18.992240 systemd[832]: Reached target Sockets. Oct 31 14:03:18.992250 systemd[832]: Reached target Timers. Oct 31 14:03:18.992259 systemd[832]: Reached target Basic System. Oct 31 14:03:18.992341 systemd[1]: Started User Manager for UID 500. Oct 31 14:03:18.992959 systemd[832]: Reached target Main User Target. Oct 31 14:03:18.992984 systemd[832]: Startup finished in 42ms. Oct 31 14:03:19.012922 systemd[1]: Started Session 1 of User core. Oct 31 14:03:19.069318 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:38678). Oct 31 14:03:19.112027 sshd[841]: Accepted publickey for core from 10.0.0.1 port 38678 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:03:19.113568 sshd[841]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:03:19.116438 systemd-logind[803]: New session 2 of user core. Oct 31 14:03:19.134286 systemd[1]: Started Session 2 of User core. Oct 31 14:03:19.188470 sshd[841]: pam_unix(sshd:session): session closed for user core Oct 31 14:03:19.205748 systemd[1]: sshd@1-10.0.0.3:22-10.0.0.1:38678.service: Deactivated successfully. Oct 31 14:03:19.206286 systemd[1]: session-2.scope: Deactivated successfully. Oct 31 14:03:19.206806 systemd-logind[803]: Session 2 logged out. Waiting for processes to exit. Oct 31 14:03:19.207724 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:38682). Oct 31 14:03:19.208303 systemd-logind[803]: Removed session 2. Oct 31 14:03:19.249440 sshd[847]: Accepted publickey for core from 10.0.0.1 port 38682 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:03:19.250854 sshd[847]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:03:19.253807 systemd-logind[803]: New session 3 of user core. Oct 31 14:03:19.265111 systemd[1]: Started Session 3 of User core. Oct 31 14:03:19.314513 sshd[847]: pam_unix(sshd:session): session closed for user core Oct 31 14:03:19.322277 systemd[1]: sshd@2-10.0.0.3:22-10.0.0.1:38682.service: Deactivated successfully. Oct 31 14:03:19.322773 systemd[1]: session-3.scope: Deactivated successfully. Oct 31 14:03:19.323300 systemd-logind[803]: Session 3 logged out. Waiting for processes to exit. Oct 31 14:03:19.324179 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:51190). Oct 31 14:03:19.324733 systemd-logind[803]: Removed session 3. Oct 31 14:03:19.366702 sshd[853]: Accepted publickey for core from 10.0.0.1 port 51190 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:03:19.368195 sshd[853]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:03:19.371555 systemd-logind[803]: New session 4 of user core. Oct 31 14:03:19.381405 systemd[1]: Started Session 4 of User core. Oct 31 14:03:19.434745 sshd[853]: pam_unix(sshd:session): session closed for user core Oct 31 14:03:19.455296 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:51194). Oct 31 14:03:19.455729 systemd[1]: sshd@3-10.0.0.3:22-10.0.0.1:51190.service: Deactivated successfully. Oct 31 14:03:19.456282 systemd[1]: session-4.scope: Deactivated successfully. Oct 31 14:03:19.456838 systemd-logind[803]: Session 4 logged out. Waiting for processes to exit. Oct 31 14:03:19.457529 systemd-logind[803]: Removed session 4. Oct 31 14:03:19.497638 sshd[858]: Accepted publickey for core from 10.0.0.1 port 51194 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:03:19.498719 sshd[858]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:03:19.501432 systemd-logind[803]: New session 5 of user core. Oct 31 14:03:19.513217 systemd[1]: Started Session 5 of User core. Oct 31 14:03:19.570623 sudo[862]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 31 14:03:19.570819 sudo[862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 14:03:19.590894 sudo[862]: pam_unix(sudo:session): session closed for user root Oct 31 14:03:19.593826 sshd[858]: pam_unix(sshd:session): session closed for user core Oct 31 14:03:19.603454 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:51196). Oct 31 14:03:19.604614 dbus-daemon[789]: [system] Reloaded configuration Oct 31 14:03:19.606772 systemd[1]: sshd@4-10.0.0.3:22-10.0.0.1:51194.service: Deactivated successfully. Oct 31 14:03:19.607437 systemd[1]: session-5.scope: Deactivated successfully. Oct 31 14:03:19.608084 systemd-logind[803]: Session 5 logged out. Waiting for processes to exit. Oct 31 14:03:19.608684 systemd-logind[803]: Removed session 5. Oct 31 14:03:19.647277 sshd[865]: Accepted publickey for core from 10.0.0.1 port 51196 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:03:19.648723 sshd[865]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:03:19.652372 systemd-logind[803]: New session 6 of user core. Oct 31 14:03:19.665290 systemd[1]: Started Session 6 of User core. Oct 31 14:03:19.717096 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 31 14:03:19.717294 sudo[871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 14:03:19.719326 sudo[871]: pam_unix(sudo:session): session closed for user root Oct 31 14:03:19.722823 sudo[870]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 31 14:03:19.723046 sudo[870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 14:03:19.742904 systemd[1]: Stopping Load Security Auditing Rules... Oct 31 14:03:19.742000 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 31 14:03:19.744617 auditctl[874]: No rules Oct 31 14:03:19.745067 systemd[1]: audit-rules.service: Deactivated successfully. Oct 31 14:03:19.745200 kernel: kauditd_printk_skb: 43 callbacks suppressed Oct 31 14:03:19.745236 kernel: audit: type=1305 audit(1761919399.742:54): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 31 14:03:19.745338 systemd[1]: Stopped Load Security Auditing Rules. Oct 31 14:03:19.747003 systemd[1]: Starting Load Security Auditing Rules... Oct 31 14:03:19.761104 augenrules[891]: No rules Oct 31 14:03:19.761751 systemd[1]: Finished Load Security Auditing Rules. Oct 31 14:03:19.762743 sudo[870]: pam_unix(sudo:session): session closed for user root Oct 31 14:03:19.763767 sshd[865]: pam_unix(sshd:session): session closed for user core Oct 31 14:03:19.776749 systemd[1]: sshd@5-10.0.0.3:22-10.0.0.1:51196.service: Deactivated successfully. Oct 31 14:03:19.777363 systemd[1]: session-6.scope: Deactivated successfully. Oct 31 14:03:19.777922 systemd-logind[803]: Session 6 logged out. Waiting for processes to exit. Oct 31 14:03:19.778855 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:51210). Oct 31 14:03:19.779518 systemd-logind[803]: Removed session 6. Oct 31 14:03:19.821178 sshd[897]: Accepted publickey for core from 10.0.0.1 port 51210 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:03:19.823953 kernel: audit: type=1006 audit(1761919399.819:55): pid=897 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 31 14:03:19.824049 sshd[897]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:03:19.826328 systemd-logind[803]: New session 7 of user core. Oct 31 14:03:19.841023 systemd[1]: Started Session 7 of User core. Oct 31 14:03:19.890964 sshd[897]: pam_unix(sshd:session): session closed for user core Oct 31 14:03:19.905719 systemd[1]: sshd@6-10.0.0.3:22-10.0.0.1:51210.service: Deactivated successfully. Oct 31 14:03:19.906306 systemd[1]: session-7.scope: Deactivated successfully. Oct 31 14:03:19.906805 systemd-logind[803]: Session 7 logged out. Waiting for processes to exit. Oct 31 14:03:19.907772 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:51218). Oct 31 14:03:19.908408 systemd-logind[803]: Removed session 7. Oct 31 14:03:19.951996 sshd[903]: Accepted publickey for core from 10.0.0.1 port 51218 ssh2: RSA SHA256:tPuvItKgDVC/fj/TG0B9MTGkH3f/DMrVPXQJkbSm8EY Oct 31 14:03:19.953469 sshd[903]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 14:03:19.955978 kernel: audit: type=1006 audit(1761919399.951:56): pid=903 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 31 14:03:19.956250 systemd-logind[803]: New session 8 of user core. Oct 31 14:03:19.965075 systemd[1]: Started Session 8 of User core. Oct 31 14:03:20.013791 sshd[903]: pam_unix(sshd:session): session closed for user core Oct 31 14:03:20.016164 systemd[1]: sshd@7-10.0.0.3:22-10.0.0.1:51218.service: Deactivated successfully. Oct 31 14:03:20.016787 systemd[1]: session-8.scope: Deactivated successfully. Oct 31 14:03:20.017314 systemd-logind[803]: Session 8 logged out. Waiting for processes to exit. Oct 31 14:03:20.017936 systemd-logind[803]: Removed session 8.