Oct 28 12:55:49.682124 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 28 12:55:49.682140 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 28 12:55:49.682149 kernel: efi: EFI v2.70 by EDK II Oct 28 12:55:49.682154 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 28 12:55:49.682158 kernel: efi: seeding entropy pool Oct 28 12:55:49.682163 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 28 12:55:49.682170 kernel: ACPI: Early table checksum verification disabled Oct 28 12:55:49.682174 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 28 12:55:49.682179 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 28 12:55:49.682209 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:55:49.682216 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:55:49.682221 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:55:49.682226 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:55:49.682231 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:55:49.682237 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:55:49.682243 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:55:49.682249 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:55:49.682255 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:55:49.682260 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 28 12:55:49.682265 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 28 12:55:49.682271 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 12:55:49.682276 kernel: NUMA: NODE_DATA [mem 0xdc95b900-0xdc960fff] Oct 28 12:55:49.682281 kernel: Zone ranges: Oct 28 12:55:49.682286 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 12:55:49.682291 kernel: DMA32 empty Oct 28 12:55:49.682296 kernel: Normal empty Oct 28 12:55:49.682302 kernel: Movable zone start for each node Oct 28 12:55:49.682308 kernel: Early memory node ranges Oct 28 12:55:49.682313 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 28 12:55:49.682318 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 28 12:55:49.682324 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 28 12:55:49.682329 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 28 12:55:49.682334 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 28 12:55:49.682339 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 28 12:55:49.682345 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 28 12:55:49.682350 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 28 12:55:49.682355 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 28 12:55:49.682360 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 28 12:55:49.682365 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 28 12:55:49.682371 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 12:55:49.682379 kernel: On node 0 totalpages: 643072 Oct 28 12:55:49.682384 kernel: DMA zone: 10048 pages used for memmap Oct 28 12:55:49.682390 kernel: DMA zone: 0 pages reserved Oct 28 12:55:49.682395 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 28 12:55:49.682401 kernel: psci: probing for conduit method from ACPI. Oct 28 12:55:49.682406 kernel: psci: PSCIv1.1 detected in firmware. Oct 28 12:55:49.682412 kernel: psci: Using standard PSCI v0.2 function IDs Oct 28 12:55:49.682417 kernel: psci: Trusted OS migration not required Oct 28 12:55:49.682424 kernel: psci: SMC Calling Convention v1.1 Oct 28 12:55:49.682429 kernel: ACPI: SRAT not present Oct 28 12:55:49.682435 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 28 12:55:49.682440 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 28 12:55:49.682446 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 28 12:55:49.682451 kernel: Detected PIPT I-cache on CPU0 Oct 28 12:55:49.682457 kernel: CPU features: detected: GIC system register CPU interface Oct 28 12:55:49.682462 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 28 12:55:49.682468 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 28 12:55:49.682473 kernel: CPU features: detected: Hardware dirty bit management Oct 28 12:55:49.682479 kernel: CPU features: detected: Spectre-v4 Oct 28 12:55:49.682485 kernel: CPU features: detected: ARM erratum 1418040 Oct 28 12:55:49.682491 kernel: CPU features: detected: Spectre-BHB Oct 28 12:55:49.682497 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 28 12:55:49.682502 kernel: Policy zone: DMA Oct 28 12:55:49.682509 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 28 12:55:49.682515 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 28 12:55:49.682521 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 28 12:55:49.682527 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 28 12:55:49.682532 kernel: Memory: 2452104K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120184K reserved, 0K cma-reserved) Oct 28 12:55:49.682538 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 28 12:55:49.682544 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 28 12:55:49.682550 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 28 12:55:49.682557 kernel: rcu: RCU event tracing is enabled. Oct 28 12:55:49.682562 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 28 12:55:49.682568 kernel: Trampoline variant of Tasks RCU enabled. Oct 28 12:55:49.682574 kernel: Tracing variant of Tasks RCU enabled. Oct 28 12:55:49.682579 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 28 12:55:49.682585 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 28 12:55:49.682591 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 28 12:55:49.682596 kernel: GICv3: 256 SPIs implemented Oct 28 12:55:49.682602 kernel: GICv3: 0 Extended SPIs implemented Oct 28 12:55:49.682607 kernel: GICv3: Distributor has no Range Selector support Oct 28 12:55:49.682613 kernel: GICv3: 16 PPIs implemented Oct 28 12:55:49.682620 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 28 12:55:49.682625 kernel: ACPI: SRAT not present Oct 28 12:55:49.682631 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 28 12:55:49.682637 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 28 12:55:49.682643 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 28 12:55:49.682648 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 28 12:55:49.682654 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 28 12:55:49.682660 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 12:55:49.682665 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 28 12:55:49.682671 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 28 12:55:49.682677 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 28 12:55:49.682683 kernel: arm-pv: using stolen time PV Oct 28 12:55:49.682690 kernel: Console: colour dummy device 80x25 Oct 28 12:55:49.682697 kernel: ACPI: Core revision 20200925 Oct 28 12:55:49.682703 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 28 12:55:49.682709 kernel: pid_max: default: 32768 minimum: 301 Oct 28 12:55:49.682715 kernel: LSM: Security Framework initializing Oct 28 12:55:49.682721 kernel: SELinux: Initializing. Oct 28 12:55:49.682746 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 12:55:49.682753 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 12:55:49.682759 kernel: rcu: Hierarchical SRCU implementation. Oct 28 12:55:49.682766 kernel: Platform MSI: ITS@0x8080000 domain created Oct 28 12:55:49.682772 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 28 12:55:49.682778 kernel: Remapping and enabling EFI services. Oct 28 12:55:49.682784 kernel: smp: Bringing up secondary CPUs ... Oct 28 12:55:49.682789 kernel: Detected PIPT I-cache on CPU1 Oct 28 12:55:49.682795 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 28 12:55:49.682801 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 28 12:55:49.682807 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 12:55:49.682813 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 28 12:55:49.682819 kernel: Detected PIPT I-cache on CPU2 Oct 28 12:55:49.682826 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 28 12:55:49.682832 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 28 12:55:49.682838 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 12:55:49.682843 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 28 12:55:49.682849 kernel: Detected PIPT I-cache on CPU3 Oct 28 12:55:49.682859 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 28 12:55:49.682865 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 28 12:55:49.682873 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 12:55:49.682879 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 28 12:55:49.682885 kernel: smp: Brought up 1 node, 4 CPUs Oct 28 12:55:49.682891 kernel: SMP: Total of 4 processors activated. Oct 28 12:55:49.682897 kernel: CPU features: detected: Privileged Access Never Oct 28 12:55:49.682903 kernel: CPU features: detected: LSE atomic instructions Oct 28 12:55:49.682910 kernel: CPU features: detected: User Access Override Oct 28 12:55:49.682916 kernel: CPU features: detected: 32-bit EL0 Support Oct 28 12:55:49.682922 kernel: CPU features: detected: Common not Private translations Oct 28 12:55:49.682928 kernel: CPU features: detected: RAS Extension Support Oct 28 12:55:49.682935 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 28 12:55:49.682941 kernel: CPU features: detected: CRC32 instructions Oct 28 12:55:49.682947 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 28 12:55:49.682953 kernel: CPU: All CPU(s) started at EL1 Oct 28 12:55:49.682959 kernel: alternatives: patching kernel code Oct 28 12:55:49.682966 kernel: devtmpfs: initialized Oct 28 12:55:49.682972 kernel: KASLR enabled Oct 28 12:55:49.682979 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 28 12:55:49.682985 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 28 12:55:49.682991 kernel: pinctrl core: initialized pinctrl subsystem Oct 28 12:55:49.682998 kernel: SMBIOS 3.0.0 present. Oct 28 12:55:49.683004 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 28 12:55:49.683010 kernel: NET: Registered protocol family 16 Oct 28 12:55:49.683016 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 28 12:55:49.683023 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 28 12:55:49.683030 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 28 12:55:49.683036 kernel: audit: initializing netlink subsys (disabled) Oct 28 12:55:49.683047 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Oct 28 12:55:49.683053 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 28 12:55:49.683059 kernel: cpuidle: using governor menu Oct 28 12:55:49.683066 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 28 12:55:49.683072 kernel: ASID allocator initialised with 32768 entries Oct 28 12:55:49.683078 kernel: ACPI: bus type PCI registered Oct 28 12:55:49.683084 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 28 12:55:49.683091 kernel: Serial: AMBA PL011 UART driver Oct 28 12:55:49.683097 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 28 12:55:49.683104 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 28 12:55:49.683110 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 28 12:55:49.683116 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 28 12:55:49.683122 kernel: cryptd: max_cpu_qlen set to 1000 Oct 28 12:55:49.683128 kernel: ACPI: Added _OSI(Module Device) Oct 28 12:55:49.683134 kernel: ACPI: Added _OSI(Processor Device) Oct 28 12:55:49.683140 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 28 12:55:49.683147 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 28 12:55:49.683153 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 28 12:55:49.683159 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 28 12:55:49.683165 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 28 12:55:49.683171 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 28 12:55:49.683177 kernel: ACPI: Interpreter enabled Oct 28 12:55:49.683190 kernel: ACPI: Using GIC for interrupt routing Oct 28 12:55:49.683196 kernel: ACPI: MCFG table detected, 1 entries Oct 28 12:55:49.683203 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 28 12:55:49.683209 kernel: printk: console [ttyAMA0] enabled Oct 28 12:55:49.683216 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 28 12:55:49.683325 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 28 12:55:49.683382 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 28 12:55:49.683435 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 28 12:55:49.683488 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 28 12:55:49.683545 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 28 12:55:49.683554 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 28 12:55:49.683562 kernel: PCI host bridge to bus 0000:00 Oct 28 12:55:49.683624 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 28 12:55:49.683677 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 28 12:55:49.683733 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 28 12:55:49.683789 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 28 12:55:49.683861 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 28 12:55:49.683923 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 28 12:55:49.683982 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 28 12:55:49.684047 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 28 12:55:49.684111 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 12:55:49.684168 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 12:55:49.684233 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 28 12:55:49.684288 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 28 12:55:49.684338 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 28 12:55:49.684388 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 28 12:55:49.684437 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 28 12:55:49.684445 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 28 12:55:49.684451 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 28 12:55:49.684457 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 28 12:55:49.684463 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 28 12:55:49.684469 kernel: vgaarb: loaded Oct 28 12:55:49.684475 kernel: Registered efivars operations Oct 28 12:55:49.684482 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 28 12:55:49.684489 kernel: VFS: Disk quotas dquot_6.6.0 Oct 28 12:55:49.684495 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 28 12:55:49.684501 kernel: pnp: PnP ACPI init Oct 28 12:55:49.684566 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 28 12:55:49.684617 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 28 12:55:49.684625 kernel: pnp: PnP ACPI: found 1 devices Oct 28 12:55:49.684632 kernel: NET: Registered protocol family 2 Oct 28 12:55:49.684639 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 28 12:55:49.684647 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 28 12:55:49.684653 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 28 12:55:49.684660 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 28 12:55:49.684666 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 28 12:55:49.684672 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 12:55:49.684679 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 12:55:49.684685 kernel: NET: Registered protocol family 1 Oct 28 12:55:49.684691 kernel: PCI: CLS 0 bytes, default 64 Oct 28 12:55:49.684697 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 28 12:55:49.684705 kernel: kvm [1]: HYP mode not available Oct 28 12:55:49.684711 kernel: Initialise system trusted keyrings Oct 28 12:55:49.684717 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 28 12:55:49.684724 kernel: Key type asymmetric registered Oct 28 12:55:49.684730 kernel: Asymmetric key parser 'x509' registered Oct 28 12:55:49.684736 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 28 12:55:49.684742 kernel: io scheduler mq-deadline registered Oct 28 12:55:49.684748 kernel: io scheduler kyber registered Oct 28 12:55:49.684754 kernel: io scheduler bfq registered Oct 28 12:55:49.684762 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 28 12:55:49.684768 kernel: ACPI: Power Button [PWRB] Oct 28 12:55:49.684774 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 28 12:55:49.684829 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 28 12:55:49.684837 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 28 12:55:49.684843 kernel: msm_serial: driver initialized Oct 28 12:55:49.684849 kernel: random: fast init done Oct 28 12:55:49.684855 kernel: random: crng init done Oct 28 12:55:49.684861 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 28 12:55:49.684867 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 28 12:55:49.684875 kernel: thunder_xcv, ver 1.0 Oct 28 12:55:49.684881 kernel: thunder_bgx, ver 1.0 Oct 28 12:55:49.684887 kernel: nicpf, ver 1.0 Oct 28 12:55:49.684893 kernel: nicvf, ver 1.0 Oct 28 12:55:49.684953 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 28 12:55:49.685006 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-28T12:55:49 UTC (1761656149) Oct 28 12:55:49.685014 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 28 12:55:49.685021 kernel: NET: Registered protocol family 10 Oct 28 12:55:49.685028 kernel: Segment Routing with IPv6 Oct 28 12:55:49.685034 kernel: NET: Registered protocol family 17 Oct 28 12:55:49.685040 kernel: Key type dns_resolver registered Oct 28 12:55:49.685054 kernel: registered taskstats version 1 Oct 28 12:55:49.685060 kernel: Loading compiled-in X.509 certificates Oct 28 12:55:49.685067 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 28 12:55:49.685073 kernel: Key type ._fscrypt registered Oct 28 12:55:49.685079 kernel: Key type .fscrypt registered Oct 28 12:55:49.685085 kernel: Key type fscrypt-provisioning registered Oct 28 12:55:49.685092 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 28 12:55:49.685099 kernel: ima: Allocated hash algorithm: sha1 Oct 28 12:55:49.685105 kernel: ima: No architecture policies found Oct 28 12:55:49.685111 kernel: Freeing unused kernel memory: 34240K Oct 28 12:55:49.685117 kernel: Run /init as init process Oct 28 12:55:49.685123 kernel: with arguments: Oct 28 12:55:49.685129 kernel: /init Oct 28 12:55:49.685135 kernel: with environment: Oct 28 12:55:49.685140 kernel: HOME=/ Oct 28 12:55:49.685146 kernel: TERM=linux Oct 28 12:55:49.685153 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 28 12:55:49.685161 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 28 12:55:49.685170 systemd[1]: Detected virtualization kvm. Oct 28 12:55:49.685176 systemd[1]: Detected architecture arm64. Oct 28 12:55:49.685196 systemd[1]: Running in initial RAM disk. Oct 28 12:55:49.685204 systemd[1]: No hostname configured, using default hostname. Oct 28 12:55:49.685210 systemd[1]: Hostname set to . Oct 28 12:55:49.685227 systemd[1]: Initializing machine ID from VM UUID. Oct 28 12:55:49.685234 systemd[1]: Queued start job for default target Initrd Default Target. Oct 28 12:55:49.685241 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 28 12:55:49.685248 systemd[1]: Reached target Local Encrypted Volumes. Oct 28 12:55:49.685254 systemd[1]: Reached target Path Units. Oct 28 12:55:49.685261 systemd[1]: Reached target Slice Units. Oct 28 12:55:49.685268 systemd[1]: Reached target Swaps. Oct 28 12:55:49.685274 systemd[1]: Reached target Timer Units. Oct 28 12:55:49.685283 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 28 12:55:49.685290 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 28 12:55:49.685297 systemd[1]: Listening on Journal Audit Socket. Oct 28 12:55:49.685303 systemd[1]: Listening on Journal Socket (/dev/log). Oct 28 12:55:49.685310 systemd[1]: Listening on Journal Socket. Oct 28 12:55:49.685316 systemd[1]: Listening on Network Service Netlink Socket. Oct 28 12:55:49.685323 systemd[1]: Listening on udev Control Socket. Oct 28 12:55:49.685330 systemd[1]: Listening on udev Kernel Socket. Oct 28 12:55:49.685336 systemd[1]: Reached target Socket Units. Oct 28 12:55:49.685344 systemd[1]: Starting Create List of Static Device Nodes... Oct 28 12:55:49.685350 systemd[1]: Finished Network Cleanup. Oct 28 12:55:49.685357 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 28 12:55:49.685363 systemd[1]: Starting Journal Service... Oct 28 12:55:49.685370 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 28 12:55:49.685376 systemd[1]: Starting Apply Kernel Variables... Oct 28 12:55:49.685383 systemd[1]: Starting Setup Virtual Console... Oct 28 12:55:49.685389 systemd[1]: Finished Create List of Static Device Nodes. Oct 28 12:55:49.685396 systemd[1]: Finished Apply Kernel Variables. Oct 28 12:55:49.685403 systemd[1]: Finished Setup Virtual Console. Oct 28 12:55:49.685410 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 28 12:55:49.685419 systemd-journald[279]: Journal started Oct 28 12:55:49.685457 systemd-journald[279]: Runtime Journal (/run/log/journal/37ecb6ddca8843d39a1a8cdb9e8a179b) is 6.0M, max 48.5M, 42.4M free. Oct 28 12:55:49.688212 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 28 12:55:49.689588 systemd[1]: Started Journal Service. Oct 28 12:55:49.689956 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 28 12:55:49.700866 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 28 12:55:49.702670 systemd[1]: Starting dracut cmdline hook... Oct 28 12:55:49.711367 dracut-cmdline[296]: dracut-dracut-053 Oct 28 12:55:49.713643 dracut-cmdline[296]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 28 12:55:49.772210 kernel: SCSI subsystem initialized Oct 28 12:55:49.776199 kernel: Loading iSCSI transport class v2.0-870. Oct 28 12:55:49.782221 kernel: iscsi: registered transport (tcp) Oct 28 12:55:49.793465 kernel: iscsi: registered transport (qla4xxx) Oct 28 12:55:49.793481 kernel: QLogic iSCSI HBA Driver Oct 28 12:55:49.803255 systemd[1]: Finished dracut cmdline hook. Oct 28 12:55:49.804833 systemd[1]: Starting dracut pre-udev hook... Oct 28 12:55:49.818337 kernel: device-mapper: uevent: version 1.0.3 Oct 28 12:55:49.818371 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 28 12:55:49.853217 kernel: raid6: neonx8 gen() 13770 MB/s Oct 28 12:55:49.870212 kernel: raid6: neonx8 xor() 10742 MB/s Oct 28 12:55:49.887206 kernel: raid6: neonx4 gen() 13617 MB/s Oct 28 12:55:49.904206 kernel: raid6: neonx4 xor() 11556 MB/s Oct 28 12:55:49.921209 kernel: raid6: neonx2 gen() 13006 MB/s Oct 28 12:55:49.938209 kernel: raid6: neonx2 xor() 9892 MB/s Oct 28 12:55:49.955218 kernel: raid6: neonx1 gen() 10974 MB/s Oct 28 12:55:49.972209 kernel: raid6: neonx1 xor() 8839 MB/s Oct 28 12:55:49.989208 kernel: raid6: int64x8 gen() 6833 MB/s Oct 28 12:55:50.006208 kernel: raid6: int64x8 xor() 3793 MB/s Oct 28 12:55:50.023209 kernel: raid6: int64x4 gen() 6914 MB/s Oct 28 12:55:50.040217 kernel: raid6: int64x4 xor() 3834 MB/s Oct 28 12:55:50.057209 kernel: raid6: int64x2 gen() 6191 MB/s Oct 28 12:55:50.074208 kernel: raid6: int64x2 xor() 3314 MB/s Oct 28 12:55:50.091211 kernel: raid6: int64x1 gen() 5012 MB/s Oct 28 12:55:50.108395 kernel: raid6: int64x1 xor() 2592 MB/s Oct 28 12:55:50.108406 kernel: raid6: using algorithm neonx8 gen() 13770 MB/s Oct 28 12:55:50.108414 kernel: raid6: .... xor() 10742 MB/s, rmw enabled Oct 28 12:55:50.109571 kernel: raid6: using neon recovery algorithm Oct 28 12:55:50.119211 kernel: xor: measuring software checksum speed Oct 28 12:55:50.120207 kernel: 8regs : 17152 MB/sec Oct 28 12:55:50.121207 kernel: 32regs : 20755 MB/sec Oct 28 12:55:50.122715 kernel: arm64_neon : 29018 MB/sec Oct 28 12:55:50.122726 kernel: xor: using function: arm64_neon (29018 MB/sec) Oct 28 12:55:50.167221 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 28 12:55:50.177491 systemd[1]: Finished dracut pre-udev hook. Oct 28 12:55:50.178000 audit: BPF prog-id=6 op=LOAD Oct 28 12:55:50.178000 audit: BPF prog-id=7 op=LOAD Oct 28 12:55:50.178000 audit: BPF prog-id=8 op=LOAD Oct 28 12:55:50.179357 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 28 12:55:50.183369 kernel: audit: type=1334 audit(1761656150.178:2): prog-id=6 op=LOAD Oct 28 12:55:50.183386 kernel: audit: type=1334 audit(1761656150.178:3): prog-id=7 op=LOAD Oct 28 12:55:50.183405 kernel: audit: type=1334 audit(1761656150.178:4): prog-id=8 op=LOAD Oct 28 12:55:50.192295 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 28 12:55:50.193957 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 28 12:55:50.195673 systemd[1]: Starting dracut pre-trigger hook... Oct 28 12:55:50.201000 audit: BPF prog-id=9 op=LOAD Oct 28 12:55:50.202127 systemd[1]: Starting Network Configuration... Oct 28 12:55:50.204250 kernel: audit: type=1334 audit(1761656150.201:5): prog-id=9 op=LOAD Oct 28 12:55:50.208314 dracut-pre-trigger[422]: rd.md=0: removing MD RAID activation Oct 28 12:55:50.219077 systemd-networkd[432]: lo: Link UP Oct 28 12:55:50.222384 kernel: audit: type=1334 audit(1761656150.220:6): prog-id=10 op=LOAD Oct 28 12:55:50.220000 audit: BPF prog-id=10 op=LOAD Oct 28 12:55:50.219085 systemd-networkd[432]: lo: Gained carrier Oct 28 12:55:50.219399 systemd-networkd[432]: Enumeration completed Oct 28 12:55:50.219467 systemd[1]: Started Network Configuration. Oct 28 12:55:50.221208 systemd[1]: Starting Network Name Resolution... Oct 28 12:55:50.231135 systemd[1]: Finished dracut pre-trigger hook. Oct 28 12:55:50.232648 systemd[1]: Starting Coldplug All udev Devices... Oct 28 12:55:50.241937 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 28 12:55:50.251991 systemd-resolved[451]: Positive Trust Anchors: Oct 28 12:55:50.252011 systemd-resolved[451]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 12:55:50.252048 systemd-resolved[451]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 28 12:55:50.252340 systemd-resolved[451]: Defaulting to hostname 'linux'. Oct 28 12:55:50.253282 systemd[1]: Started Network Name Resolution. Oct 28 12:55:50.255165 systemd[1]: Reached target Network. Oct 28 12:55:50.261860 systemd[1]: Reached target Host and Network Name Lookups. Oct 28 12:55:50.264845 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 28 12:55:50.266673 systemd[1]: Finished Coldplug All udev Devices. Oct 28 12:55:50.270351 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 28 12:55:50.271793 systemd[1]: Starting Open-iSCSI... Oct 28 12:55:50.274097 iscsid[483]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 28 12:55:50.274097 iscsid[483]: 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 28 12:55:50.274097 iscsid[483]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 28 12:55:50.274097 iscsid[483]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 28 12:55:50.274097 iscsid[483]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 28 12:55:50.285330 iscsid[483]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 28 12:55:50.277432 systemd[1]: Started Open-iSCSI. Oct 28 12:55:50.282351 systemd[1]: Starting dracut initqueue hook... Oct 28 12:55:50.294116 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 28 12:55:50.294265 kernel: vda: detected capacity change from 0 to 4756340736 Oct 28 12:55:50.309202 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 12:55:50.310714 systemd-udevd[470]: Using default interface naming scheme 'v249'. Oct 28 12:55:50.316460 systemd-networkd[432]: eth0: Link UP Oct 28 12:55:50.330203 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (463) Oct 28 12:55:50.335091 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 28 12:55:50.337432 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 28 12:55:50.338505 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 28 12:55:50.342163 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 28 12:55:50.343306 systemd[1]: Finished dracut initqueue hook. Oct 28 12:55:50.346489 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 28 12:55:50.347561 systemd[1]: Reached target Initrd Root Device. Oct 28 12:55:50.348961 systemd[1]: Reached target Preparation for Remote File Systems. Oct 28 12:55:50.350555 systemd[1]: Reached target Remote Encrypted Volumes. Oct 28 12:55:50.352142 systemd[1]: Reached target Remote File Systems. Oct 28 12:55:50.354327 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 28 12:55:50.355899 systemd[1]: Starting dracut pre-mount hook... Oct 28 12:55:50.362201 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 12:55:50.363956 systemd[1]: Finished dracut pre-mount hook. Oct 28 12:55:51.323607 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 28 12:55:51.323307 systemd-networkd[432]: eth0: Gained carrier Oct 28 12:55:51.333261 systemd-networkd[432]: eth0: DHCPv4 address 10.0.0.4/16 via 10.0.0.1 Oct 28 12:55:51.372824 disk-uuid[504]: The operation has completed successfully. Oct 28 12:55:51.374496 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 12:55:51.395118 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 28 12:55:51.395259 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 28 12:55:51.397076 systemd[1]: Starting Ignition (setup)... Oct 28 12:55:51.404809 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 28 12:55:51.404835 kernel: BTRFS info (device vda6): has skinny extents Oct 28 12:55:51.410454 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 28 12:55:51.416180 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 28 12:55:51.416310 systemd[1]: Finished Ignition (setup). Oct 28 12:55:51.418661 systemd[1]: Starting Ignition (disks)... Oct 28 12:55:51.428112 ignition[542]: Ignition v0.36.1-15-gde4e6cc9 Oct 28 12:55:51.428128 ignition[542]: Stage: disks Oct 28 12:55:51.428138 ignition[542]: reading system config file "/usr/lib/ignition/base.ign" Oct 28 12:55:51.428148 ignition[542]: no config at "/usr/lib/ignition/base.ign" Oct 28 12:55:51.428202 ignition[542]: parsed url from cmdline: "" Oct 28 12:55:51.428206 ignition[542]: no config URL provided Oct 28 12:55:51.428210 ignition[542]: reading system config file "/usr/lib/ignition/user.ign" Oct 28 12:55:51.428217 ignition[542]: no config at "/usr/lib/ignition/user.ign" Oct 28 12:55:51.428236 ignition[542]: op(1): [started] loading QEMU firmware config module Oct 28 12:55:51.428240 ignition[542]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 28 12:55:51.431394 ignition[542]: op(1): [finished] loading QEMU firmware config module Oct 28 12:55:51.443108 ignition[542]: parsing config with SHA512: 72af86d81b3f7234392b666144dc5cc5668c488ce0f032cd6bd21a2b62590c37fdfff08f8e6b6e0596522c99bd6fa8109ed12db2876ecb8c79992bed6925f91e Oct 28 12:55:51.445120 ignition[542]: disks: disks passed Oct 28 12:55:51.445132 ignition[542]: Ignition finished successfully Oct 28 12:55:51.445754 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 28 12:55:51.445869 systemd[1]: Finished Ignition (disks). Oct 28 12:55:51.447344 systemd[1]: Reached target Preparation for Local File Systems. Oct 28 12:55:51.449657 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 28 12:55:51.452018 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 28 12:55:51.459221 systemd-fsck[555]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 28 12:55:51.462271 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 28 12:55:51.463653 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 28 12:55:51.465379 systemd[1]: Mounting /sysroot... Oct 28 12:55:51.481212 systemd[1]: Found device /dev/mapper/usr. Oct 28 12:55:51.482606 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 28 12:55:51.483663 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 28 12:55:51.492400 systemd-fsck[572]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 28 12:55:51.492400 systemd-fsck[572]: You must have r/w access to the filesystem or be root Oct 28 12:55:51.493505 systemd-fsck[569]: fsck failed with exit status 8. Oct 28 12:55:51.493509 systemd-fsck[569]: Ignoring error. Oct 28 12:55:51.494027 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 28 12:55:51.496565 systemd[1]: Mounting /sysusr/usr... Oct 28 12:55:51.518468 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 28 12:55:51.518512 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 28 12:55:51.518999 systemd[1]: Mounted /sysroot. Oct 28 12:55:51.519826 systemd[1]: Reached target Initrd Root File System. Oct 28 12:55:51.527206 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 28 12:55:51.528098 systemd[1]: Mounted /sysusr/usr. Oct 28 12:55:51.528876 systemd[1]: Reached target Local File Systems. Oct 28 12:55:51.530127 systemd[1]: Reached target System Initialization. Oct 28 12:55:51.531623 systemd[1]: Reached target Basic System. Oct 28 12:55:51.534374 systemd[1]: Mounting /sysroot/usr... Oct 28 12:55:51.536480 systemd[1]: Mounted /sysroot/usr. Oct 28 12:55:51.537798 systemd[1]: Starting Root filesystem setup... Oct 28 12:55:51.562676 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 28 12:55:51.562802 systemd[1]: Finished Root filesystem setup. Oct 28 12:55:51.565211 systemd[1]: Starting Ignition (files)... Oct 28 12:55:51.566434 systemd[1]: Starting /sysroot/boot... Oct 28 12:55:51.568610 ignition[589]: Ignition v0.36.1-15-gde4e6cc9 Oct 28 12:55:51.568626 ignition[589]: Stage: files Oct 28 12:55:51.568635 ignition[589]: reading system config file "/usr/lib/ignition/base.ign" Oct 28 12:55:51.568644 ignition[589]: no config at "/usr/lib/ignition/base.ign" Oct 28 12:55:51.569125 ignition[589]: files: compiled without relabeling support, skipping Oct 28 12:55:51.583647 systemd[1]: Finished /sysroot/boot. Oct 28 12:55:51.587779 ignition[589]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 28 12:55:51.587790 ignition[589]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 28 12:55:51.589487 ignition[589]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 28 12:55:51.589497 ignition[589]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 28 12:55:51.592451 ignition[589]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 28 12:55:51.592475 ignition[589]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 28 12:55:51.593767 ignition[589]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 28 12:55:51.593774 ignition[589]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 28 12:55:51.593861 ignition[589]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 28 12:55:51.597501 systemd[1]: ignition-files.service: Deactivated successfully. Oct 28 12:55:51.596929 ignition[589]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 28 12:55:51.597609 systemd[1]: Finished Ignition (files). Oct 28 12:55:51.596934 ignition[589]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 28 12:55:51.598857 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 28 12:55:51.608000 audit: BPF prog-id=9 op=UNLOAD Oct 28 12:55:51.596939 ignition[589]: files: files passed Oct 28 12:55:51.599467 systemd[1]: Starting Ignition (record completion)... Oct 28 12:55:51.610951 kernel: audit: type=1334 audit(1761656151.608:7): prog-id=9 op=UNLOAD Oct 28 12:55:51.596942 ignition[589]: Ignition finished successfully Oct 28 12:55:51.601658 systemd[1]: Starting Reload Configuration from the Real Root... Oct 28 12:55:51.603380 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 28 12:55:51.609000 audit: BPF prog-id=3 op=UNLOAD Oct 28 12:55:51.603486 systemd[1]: Finished Ignition (record completion). Oct 28 12:55:51.615289 kernel: audit: type=1334 audit(1761656151.609:8): prog-id=3 op=UNLOAD Oct 28 12:55:51.606669 systemd[1]: Reloading. Oct 28 12:55:51.614000 audit: BPF prog-id=6 op=UNLOAD Oct 28 12:55:51.614000 audit: BPF prog-id=10 op=UNLOAD Oct 28 12:55:51.619120 kernel: audit: type=1334 audit(1761656151.614:9): prog-id=6 op=UNLOAD Oct 28 12:55:51.619149 kernel: audit: type=1334 audit(1761656151.614:10): prog-id=10 op=UNLOAD Oct 28 12:55:51.689000 audit: BPF prog-id=11 op=LOAD Oct 28 12:55:51.690000 audit: BPF prog-id=12 op=LOAD Oct 28 12:55:51.690000 audit: BPF prog-id=13 op=LOAD Oct 28 12:55:51.690000 audit: BPF prog-id=14 op=LOAD Oct 28 12:55:51.690000 audit: BPF prog-id=4 op=UNLOAD Oct 28 12:55:51.690000 audit: BPF prog-id=5 op=UNLOAD Oct 28 12:55:51.690000 audit: BPF prog-id=15 op=LOAD Oct 28 12:55:51.690000 audit: BPF prog-id=16 op=LOAD Oct 28 12:55:51.690000 audit: BPF prog-id=17 op=LOAD Oct 28 12:55:51.690000 audit: BPF prog-id=7 op=UNLOAD Oct 28 12:55:51.690000 audit: BPF prog-id=8 op=UNLOAD Oct 28 12:55:51.691000 audit: BPF prog-id=18 op=LOAD Oct 28 12:55:51.705041 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 28 12:55:51.705157 systemd[1]: Finished Reload Configuration from the Real Root. Oct 28 12:55:51.707213 systemd[1]: Reached target Initrd File Systems. Oct 28 12:55:51.708804 systemd[1]: Reached target Initrd Default Target. Oct 28 12:55:51.710284 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 28 12:55:51.710933 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 28 12:55:51.720994 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 28 12:55:51.722601 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 28 12:55:51.728394 systemd[1]: Stopped target Host and Network Name Lookups. Oct 28 12:55:51.729387 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 28 12:55:51.731012 systemd[1]: Stopped target Timer Units. Oct 28 12:55:51.732519 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 28 12:55:51.732622 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 28 12:55:51.734054 systemd[1]: Stopped target Initrd Default Target. Oct 28 12:55:51.735642 systemd[1]: Stopped target Basic System. Oct 28 12:55:51.737062 systemd[1]: Stopped target Initrd Root Device. Oct 28 12:55:51.738530 systemd[1]: Stopped target Path Units. Oct 28 12:55:51.739824 systemd[1]: Stopped target Remote File Systems. Oct 28 12:55:51.741367 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 28 12:55:51.742874 systemd[1]: Stopped target Slice Units. Oct 28 12:55:51.744219 systemd[1]: Stopped target Socket Units. Oct 28 12:55:51.745856 systemd[1]: Stopped target System Initialization. Oct 28 12:55:51.747248 systemd[1]: Stopped target Local File Systems. Oct 28 12:55:51.748609 systemd[1]: Stopped target Preparation for Local File Systems. Oct 28 12:55:51.750264 systemd[1]: Stopped target Swaps. Oct 28 12:55:51.751604 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 28 12:55:51.751708 systemd[1]: Stopped dracut pre-mount hook. Oct 28 12:55:51.753182 systemd[1]: Stopped target Local Encrypted Volumes. Oct 28 12:55:51.754447 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 28 12:55:51.758234 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 28 12:55:51.759366 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 28 12:55:51.759467 systemd[1]: Stopped dracut initqueue hook. Oct 28 12:55:51.762616 iscsid[483]: iscsid shutting down. Oct 28 12:55:51.762980 systemd[1]: Stopping Open-iSCSI... Oct 28 12:55:51.764558 systemd[1]: Stopping /sysroot/boot... Oct 28 12:55:51.765207 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 28 12:55:51.765336 systemd[1]: Stopped Coldplug All udev Devices. Oct 28 12:55:51.766651 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 28 12:55:51.766737 systemd[1]: Stopped dracut pre-trigger hook. Oct 28 12:55:51.769262 systemd[1]: iscsid.service: Deactivated successfully. Oct 28 12:55:51.769434 systemd[1]: Stopped Open-iSCSI. Oct 28 12:55:51.771246 systemd[1]: iscsid.socket: Deactivated successfully. Oct 28 12:55:51.771297 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 28 12:55:51.772383 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 28 12:55:51.776067 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 28 12:55:51.776171 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 28 12:55:51.777570 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 28 12:55:51.777668 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 28 12:55:51.779149 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 28 12:55:51.779271 systemd[1]: Stopped /sysroot/boot. Oct 28 12:55:51.781045 systemd[1]: Stopped target Network. Oct 28 12:55:51.781810 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 28 12:55:51.781872 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 28 12:55:51.783437 systemd[1]: Stopping Network Name Resolution... Oct 28 12:55:51.790615 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 28 12:55:51.790729 systemd[1]: Stopped Network Name Resolution. Oct 28 12:55:51.792533 systemd[1]: Stopping Network Configuration... Oct 28 12:55:51.797294 systemd-networkd[432]: eth0: DHCP lease lost Oct 28 12:55:51.797000 audit: BPF prog-id=18 op=UNLOAD Oct 28 12:55:51.805219 systemd-networkd[432]: eth0: DHCPv6 lease lost Oct 28 12:55:51.806216 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 28 12:55:51.806328 systemd[1]: Stopped Network Configuration. Oct 28 12:55:51.807999 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 28 12:55:51.808028 systemd[1]: Closed Network Service Netlink Socket. Oct 28 12:55:51.809860 systemd[1]: Stopping Network Cleanup... Oct 28 12:55:51.810711 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 28 12:55:51.810776 systemd[1]: Stopped Apply Kernel Variables. Oct 28 12:55:51.813690 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 28 12:55:51.818000 audit: BPF prog-id=11 op=UNLOAD Oct 28 12:55:51.818346 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 28 12:55:51.818467 systemd[1]: Stopped Network Cleanup. Oct 28 12:55:51.820955 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 28 12:55:51.821090 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 28 12:55:51.823072 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 28 12:55:51.823111 systemd[1]: Closed udev Control Socket. Oct 28 12:55:51.824568 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 28 12:55:51.824604 systemd[1]: Closed udev Kernel Socket. Oct 28 12:55:51.826086 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 28 12:55:51.830000 audit: BPF prog-id=15 op=UNLOAD Oct 28 12:55:51.826128 systemd[1]: Stopped dracut pre-udev hook. Oct 28 12:55:51.827477 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 28 12:55:51.827517 systemd[1]: Stopped dracut cmdline hook. Oct 28 12:55:51.828979 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 28 12:55:51.829019 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 28 12:55:51.831237 systemd[1]: Starting Cleanup udev Database... Oct 28 12:55:51.832172 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 28 12:55:51.833536 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 28 12:55:51.833596 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 28 12:55:51.835177 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 28 12:55:51.835236 systemd[1]: Stopped Create List of Static Device Nodes. Oct 28 12:55:51.836742 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 12:55:51.836785 systemd[1]: Stopped Setup Virtual Console. Oct 28 12:55:51.838537 systemd[1]: rngd.service: Deactivated successfully. Oct 28 12:55:51.838657 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 28 12:55:51.850000 audit: BPF prog-id=12 op=UNLOAD Oct 28 12:55:51.840279 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 28 12:55:51.840381 systemd[1]: Finished Cleanup udev Database. Oct 28 12:55:51.841583 systemd[1]: Reached target Switch Root. Oct 28 12:55:51.843572 systemd[1]: Starting Switch Root... Oct 28 12:55:51.848476 systemd[1]: Switching root. Oct 28 12:55:51.865493 systemd-journald[279]: Journal stopped Oct 28 12:55:54.018549 systemd-journald[279]: Received SIGTERM from PID 1 (systemd). Oct 28 12:55:54.018606 kernel: SELinux: policy capability network_peer_controls=1 Oct 28 12:55:54.018618 kernel: SELinux: policy capability open_perms=1 Oct 28 12:55:54.018627 kernel: SELinux: policy capability extended_socket_class=1 Oct 28 12:55:54.018640 kernel: SELinux: policy capability always_check_network=0 Oct 28 12:55:54.018649 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 28 12:55:54.018658 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 28 12:55:54.018667 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 28 12:55:54.018677 systemd[1]: Successfully loaded SELinux policy in 30.391ms. Oct 28 12:55:54.018691 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.245ms. Oct 28 12:55:54.018702 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 28 12:55:54.018712 systemd[1]: Detected virtualization kvm. Oct 28 12:55:54.018722 systemd[1]: Detected architecture arm64. Oct 28 12:55:54.018732 systemd[1]: Detected first boot. Oct 28 12:55:54.018742 systemd[1]: Initializing machine ID from VM UUID. Oct 28 12:55:54.018752 systemd[1]: Populated /etc with preset unit settings. Oct 28 12:55:54.018763 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 28 12:55:54.018776 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 28 12:55:54.018786 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 28 12:55:54.018795 systemd[1]: Stopped Switch Root. Oct 28 12:55:54.018805 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 28 12:55:54.018815 systemd[1]: Created slice Slice /system/addon-config. Oct 28 12:55:54.018824 systemd[1]: Created slice Slice /system/addon-run. Oct 28 12:55:54.018834 systemd[1]: Created slice Slice /system/getty. Oct 28 12:55:54.018843 systemd[1]: Created slice Slice /system/modprobe. Oct 28 12:55:54.018852 kernel: kauditd_printk_skb: 41 callbacks suppressed Oct 28 12:55:54.018863 kernel: audit: type=1334 audit(1761656153.900:52): prog-id=27 op=UNLOAD Oct 28 12:55:54.018872 systemd[1]: Created slice Slice /system/serial-getty. Oct 28 12:55:54.018881 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 28 12:55:54.018891 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 28 12:55:54.018901 systemd[1]: Created slice User and Session Slice. Oct 28 12:55:54.018910 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 28 12:55:54.018921 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 28 12:55:54.018930 systemd[1]: Set up automount Boot partition Automount Point. Oct 28 12:55:54.018940 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 28 12:55:54.018952 systemd[1]: Stopped target Switch Root. Oct 28 12:55:54.018961 systemd[1]: Stopped target Initrd File Systems. Oct 28 12:55:54.018971 systemd[1]: Stopped target Initrd Root File System. Oct 28 12:55:54.018981 systemd[1]: Reached target Remote Encrypted Volumes. Oct 28 12:55:54.018990 systemd[1]: Reached target Remote File Systems. Oct 28 12:55:54.019000 systemd[1]: Reached target Slice Units. Oct 28 12:55:54.019010 systemd[1]: Reached target Swaps. Oct 28 12:55:54.019019 systemd[1]: Reached target Verify torcx succeeded. Oct 28 12:55:54.019038 systemd[1]: Reached target Local Verity Protected Volumes. Oct 28 12:55:54.019050 systemd[1]: Listening on Process Core Dump Socket. Oct 28 12:55:54.019062 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 28 12:55:54.019072 systemd[1]: Listening on Network Service Netlink Socket. Oct 28 12:55:54.019081 systemd[1]: Listening on udev Control Socket. Oct 28 12:55:54.019090 systemd[1]: Listening on udev Kernel Socket. Oct 28 12:55:54.019100 systemd[1]: Mounting Huge Pages File System... Oct 28 12:55:54.019109 systemd[1]: Mounting POSIX Message Queue File System... Oct 28 12:55:54.019118 systemd[1]: Mounting External Media Directory... Oct 28 12:55:54.019128 systemd[1]: Mounting Kernel Debug File System... Oct 28 12:55:54.019137 systemd[1]: Mounting Kernel Trace File System... Oct 28 12:55:54.019146 systemd[1]: Mounting Temporary Directory /tmp... Oct 28 12:55:54.019157 systemd[1]: Starting Create missing system files... Oct 28 12:55:54.019166 systemd[1]: Starting Create List of Static Device Nodes... Oct 28 12:55:54.019176 systemd[1]: Starting Load Kernel Module configfs... Oct 28 12:55:54.019194 systemd[1]: Starting Load Kernel Module drm... Oct 28 12:55:54.019204 systemd[1]: Starting Load Kernel Module fuse... Oct 28 12:55:54.019214 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 28 12:55:54.019224 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 28 12:55:54.019233 systemd[1]: Stopped File System Check on Root Device. Oct 28 12:55:54.019243 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 28 12:55:54.019255 systemd[1]: Stopped systemd-fsck-usr.service. Oct 28 12:55:54.019264 kernel: fuse: init (API version 7.32) Oct 28 12:55:54.019273 systemd[1]: Stopped Journal Service. Oct 28 12:55:54.019282 kernel: audit: type=1334 audit(1761656154.002:53): prog-id=30 op=LOAD Oct 28 12:55:54.019291 kernel: audit: type=1334 audit(1761656154.003:54): prog-id=31 op=LOAD Oct 28 12:55:54.019300 kernel: audit: type=1334 audit(1761656154.004:55): prog-id=32 op=LOAD Oct 28 12:55:54.019309 systemd[1]: Starting Journal Service... Oct 28 12:55:54.019319 kernel: audit: type=1334 audit(1761656154.004:56): prog-id=28 op=UNLOAD Oct 28 12:55:54.019327 kernel: audit: type=1334 audit(1761656154.004:57): prog-id=29 op=UNLOAD Oct 28 12:55:54.019336 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 28 12:55:54.019347 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 28 12:55:54.019357 systemd[1]: Starting Apply Kernel Variables... Oct 28 12:55:54.019366 systemd[1]: Starting Coldplug All udev Devices... Oct 28 12:55:54.019375 kernel: audit: type=1305 audit(1761656154.016:58): op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 28 12:55:54.019386 systemd-journald[805]: Journal started Oct 28 12:55:54.019419 systemd-journald[805]: Runtime Journal (/run/log/journal/37ecb6ddca8843d39a1a8cdb9e8a179b) is 6.0M, max 48.5M, 42.4M free. Oct 28 12:55:51.927000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 28 12:55:51.957000 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 28 12:55:51.957000 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 28 12:55:51.957000 audit: BPF prog-id=19 op=LOAD Oct 28 12:55:51.957000 audit: BPF prog-id=19 op=UNLOAD Oct 28 12:55:51.957000 audit: BPF prog-id=20 op=LOAD Oct 28 12:55:51.957000 audit: BPF prog-id=20 op=UNLOAD Oct 28 12:55:51.989000 audit[754]: AVC avc: denied { associate } for pid=754 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 28 12:55:53.878000 audit: BPF prog-id=21 op=LOAD Oct 28 12:55:53.878000 audit: BPF prog-id=22 op=LOAD Oct 28 12:55:53.878000 audit: BPF prog-id=23 op=LOAD Oct 28 12:55:53.878000 audit: BPF prog-id=13 op=UNLOAD Oct 28 12:55:53.878000 audit: BPF prog-id=14 op=UNLOAD Oct 28 12:55:53.887000 audit: BPF prog-id=24 op=LOAD Oct 28 12:55:53.887000 audit: BPF prog-id=21 op=UNLOAD Oct 28 12:55:53.887000 audit: BPF prog-id=25 op=LOAD Oct 28 12:55:53.887000 audit: BPF prog-id=26 op=LOAD Oct 28 12:55:53.887000 audit: BPF prog-id=22 op=UNLOAD Oct 28 12:55:53.887000 audit: BPF prog-id=23 op=UNLOAD Oct 28 12:55:53.887000 audit: BPF prog-id=27 op=LOAD Oct 28 12:55:53.887000 audit: BPF prog-id=24 op=UNLOAD Oct 28 12:55:53.887000 audit: BPF prog-id=28 op=LOAD Oct 28 12:55:53.887000 audit: BPF prog-id=29 op=LOAD Oct 28 12:55:53.887000 audit: BPF prog-id=25 op=UNLOAD Oct 28 12:55:53.887000 audit: BPF prog-id=26 op=UNLOAD Oct 28 12:55:53.900000 audit: BPF prog-id=27 op=UNLOAD Oct 28 12:55:54.002000 audit: BPF prog-id=30 op=LOAD Oct 28 12:55:54.003000 audit: BPF prog-id=31 op=LOAD Oct 28 12:55:54.004000 audit: BPF prog-id=32 op=LOAD Oct 28 12:55:54.004000 audit: BPF prog-id=28 op=UNLOAD Oct 28 12:55:54.004000 audit: BPF prog-id=29 op=UNLOAD Oct 28 12:55:54.016000 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 28 12:55:53.877873 systemd[1]: Queued start job for default target Multi-User System. Oct 28 12:55:51.987860 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" 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 28 12:55:53.888428 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 28 12:55:51.988105 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 28 12:55:51.988123 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 28 12:55:51.988235 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 28 12:55:51.988246 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 28 12:55:51.988274 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 28 12:55:51.988287 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 28 12:55:51.988484 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 28 12:55:51.988518 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 28 12:55:51.988533 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 28 12:55:51.988885 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" 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 28 12:55:51.988915 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" 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 28 12:55:51.988935 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" 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 28 12:55:51.988951 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 28 12:55:51.988969 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" 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 28 12:55:51.988983 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:51Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 28 12:55:53.706897 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:53Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 12:55:53.707198 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:53Z" 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 28 12:55:53.707306 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:53Z" 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 28 12:55:53.707431 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:53Z" 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 28 12:55:53.707485 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:53Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 28 12:55:53.707551 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2025-10-28T12:55:53Z" 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 28 12:55:54.022862 systemd[1]: verity-setup.service: Deactivated successfully. Oct 28 12:55:54.022895 systemd[1]: Stopped verity-setup.service. Oct 28 12:55:54.026559 systemd[1]: Started Journal Service. Oct 28 12:55:54.027082 systemd[1]: Mounted Huge Pages File System. Oct 28 12:55:54.028096 systemd[1]: Mounted POSIX Message Queue File System. Oct 28 12:55:54.029240 systemd[1]: Mounted External Media Directory. Oct 28 12:55:54.030201 systemd[1]: Mounted Kernel Debug File System. Oct 28 12:55:54.031139 systemd[1]: Mounted Kernel Trace File System. Oct 28 12:55:54.032177 systemd[1]: Mounted Temporary Directory /tmp. Oct 28 12:55:54.033449 systemd[1]: Finished Create missing system files. Oct 28 12:55:54.034737 systemd[1]: Finished Create List of Static Device Nodes. Oct 28 12:55:54.036146 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 28 12:55:54.036369 systemd[1]: Finished Load Kernel Module configfs. Oct 28 12:55:54.037621 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 28 12:55:54.037825 systemd[1]: Finished Load Kernel Module drm. Oct 28 12:55:54.039068 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 28 12:55:54.039257 systemd[1]: Finished Load Kernel Module fuse. Oct 28 12:55:54.040433 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 28 12:55:54.041747 systemd[1]: Finished Apply Kernel Variables. Oct 28 12:55:54.052492 systemd[1]: Mounting FUSE Control File System... Oct 28 12:55:54.054267 systemd[1]: Mounting Kernel Configuration File System... Oct 28 12:55:54.055122 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 28 12:55:54.056610 systemd[1]: Starting Rebuild Hardware Database... Oct 28 12:55:54.058433 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 28 12:55:54.059535 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 28 12:55:54.060504 systemd[1]: Starting Load/Save Random Seed... Oct 28 12:55:54.063401 systemd-journald[805]: Time spent on flushing to /var/log/journal/37ecb6ddca8843d39a1a8cdb9e8a179b is 10.368ms for 780 entries. Oct 28 12:55:54.063401 systemd-journald[805]: System Journal (/var/log/journal/37ecb6ddca8843d39a1a8cdb9e8a179b) is 8.0M, max 203.0M, 195.0M free. Oct 28 12:55:54.062334 systemd[1]: Starting Create System Users... Oct 28 12:55:54.066477 systemd[1]: Mounted FUSE Control File System. Oct 28 12:55:54.067548 systemd[1]: Mounted Kernel Configuration File System. Oct 28 12:55:54.071396 systemd[1]: Finished Load/Save Random Seed. Oct 28 12:55:54.072463 systemd[1]: Reached target First Boot Complete. Oct 28 12:55:54.074766 systemd[1]: Finished Coldplug All udev Devices. Oct 28 12:55:54.084336 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 28 12:55:54.085619 systemd-sysusers[816]: Creating group sgx with gid 999. Oct 28 12:55:54.086610 systemd-sysusers[816]: Creating group systemd-oom with gid 998. Oct 28 12:55:54.087370 systemd-sysusers[816]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 28 12:55:54.088171 systemd-sysusers[816]: Creating group systemd-timesync with gid 997. Oct 28 12:55:54.088924 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 28 12:55:54.090284 systemd-sysusers[816]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 28 12:55:54.090961 systemd-sysusers[816]: Creating group systemd-coredump with gid 996. Oct 28 12:55:54.091500 systemd-sysusers[816]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 28 12:55:54.101423 systemd[1]: Finished Create System Users. Oct 28 12:55:54.103332 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 28 12:55:54.115750 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 28 12:55:54.302655 systemd[1]: Finished Rebuild Hardware Database. Oct 28 12:55:54.309000 audit: BPF prog-id=33 op=LOAD Oct 28 12:55:54.310000 audit: BPF prog-id=34 op=LOAD Oct 28 12:55:54.311639 kernel: audit: type=1334 audit(1761656154.309:59): prog-id=33 op=LOAD Oct 28 12:55:54.311680 kernel: audit: type=1334 audit(1761656154.310:60): prog-id=34 op=LOAD Oct 28 12:55:54.311696 kernel: audit: type=1334 audit(1761656154.311:61): prog-id=35 op=LOAD Oct 28 12:55:54.311000 audit: BPF prog-id=35 op=LOAD Oct 28 12:55:54.312251 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 28 12:55:54.311000 audit: BPF prog-id=16 op=UNLOAD Oct 28 12:55:54.311000 audit: BPF prog-id=17 op=UNLOAD Oct 28 12:55:54.336569 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 28 12:55:54.346000 audit: BPF prog-id=36 op=LOAD Oct 28 12:55:54.347330 systemd[1]: Starting Network Configuration... Oct 28 12:55:54.381706 systemd-udevd[831]: Using default interface naming scheme 'v249'. Oct 28 12:55:54.388497 systemd-networkd[841]: lo: Link UP Oct 28 12:55:54.388507 systemd-networkd[841]: lo: Gained carrier Oct 28 12:55:54.389344 systemd-networkd[841]: Enumeration completed Oct 28 12:55:54.389459 systemd[1]: Started Network Configuration. Oct 28 12:55:54.392017 systemd-networkd[841]: eth0: Link UP Oct 28 12:55:54.395165 udevadm[819]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 28 12:55:54.398249 systemd-networkd[841]: eth0: Gained carrier Oct 28 12:55:54.404346 systemd-networkd[841]: eth0: DHCPv4 address 10.0.0.4/16 via 10.0.0.1 Oct 28 12:55:54.422369 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 28 12:55:54.438078 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 28 12:55:54.445650 lvm[849]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 28 12:55:54.473019 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 28 12:55:54.474131 systemd[1]: Reached target Local Encrypted Volumes. Oct 28 12:55:54.488034 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 28 12:55:54.491057 lvm[850]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 28 12:55:54.517015 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 28 12:55:54.518093 systemd[1]: Reached target Preparation for Local File Systems. Oct 28 12:55:54.519176 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 28 12:55:54.519211 systemd[1]: Reached target Containers. Oct 28 12:55:54.535406 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 28 12:55:54.546729 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 28 12:55:54.548794 systemd[1]: Mounting /usr/share/oem... Oct 28 12:55:54.554605 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 28 12:55:54.554650 kernel: BTRFS info (device vda6): has skinny extents Oct 28 12:55:54.556694 systemd[1]: Mounted /usr/share/oem. Oct 28 12:55:54.557491 systemd[1]: Reached target Local File Systems. Oct 28 12:55:54.568951 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 28 12:55:54.570275 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 28 12:55:54.570333 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 28 12:55:54.571259 systemd[1]: Starting Commit a transient machine-id on disk... Oct 28 12:55:54.573199 systemd[1]: Starting Create Volatile Files and Directories... Oct 28 12:55:54.579605 systemd-tmpfiles[875]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 28 12:55:54.580734 systemd-tmpfiles[875]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 28 12:55:54.586082 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 28 12:55:54.587174 systemd[1]: Finished Commit a transient machine-id on disk. Oct 28 12:55:54.606304 systemd-tmpfiles[875]: Detected autofs mount point /boot during canonicalization of /boot. Oct 28 12:55:54.606314 systemd-tmpfiles[875]: Skipping /boot Oct 28 12:55:54.611054 systemd-tmpfiles[875]: Detected autofs mount point /boot during canonicalization of /boot. Oct 28 12:55:54.611068 systemd-tmpfiles[875]: Skipping /boot Oct 28 12:55:54.637450 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 28 12:55:54.645739 systemd[1]: Finished Create Volatile Files and Directories. Oct 28 12:55:54.658163 systemd[1]: Starting Load Security Auditing Rules... Oct 28 12:55:54.659957 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 28 12:55:54.663000 audit: BPF prog-id=37 op=LOAD Oct 28 12:55:54.661957 systemd[1]: Starting Rebuild Journal Catalog... Oct 28 12:55:54.664394 systemd[1]: Starting Network Name Resolution... Oct 28 12:55:54.666000 audit: BPF prog-id=38 op=LOAD Oct 28 12:55:54.667600 systemd[1]: Starting Network Time Synchronization... Oct 28 12:55:54.669373 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 28 12:55:54.671135 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 28 12:55:54.673977 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 28 12:55:54.676425 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 28 12:55:54.683562 systemd[1]: Finished Rebuild Journal Catalog. Oct 28 12:55:54.685569 systemd[1]: Starting Update is Completed... Oct 28 12:55:54.689905 systemd[1]: Finished Update is Completed. Oct 28 12:55:54.691000 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 28 12:55:54.692266 augenrules[897]: No rules Oct 28 12:55:54.692609 systemd[1]: Finished Load Security Auditing Rules. Oct 28 12:55:54.706336 systemd[1]: Started Network Time Synchronization. Oct 28 12:55:55.130808 systemd-timesyncd[886]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 28 12:55:55.131001 systemd[1]: Reached target System Initialization. Oct 28 12:55:55.131935 systemd[1]: Started Watch for update engine configuration changes. Oct 28 12:55:55.133198 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 28 12:55:55.134389 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 28 12:55:55.135540 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 28 12:55:55.135574 systemd[1]: Reached target Path Units. Oct 28 12:55:55.136380 systemd[1]: Reached target System Time Set. Oct 28 12:55:55.137136 systemd-resolved[885]: Positive Trust Anchors: Oct 28 12:55:55.137334 systemd-resolved[885]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 12:55:55.137411 systemd-resolved[885]: 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 28 12:55:55.137422 systemd[1]: Started Daily Log Rotation. Oct 28 12:55:55.138268 systemd-resolved[885]: Defaulting to hostname 'linux'. Oct 28 12:55:55.138475 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 28 12:55:55.139709 systemd[1]: Reached target Timer Units. Oct 28 12:55:55.140824 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 28 12:55:55.153195 systemd[1]: Starting Docker Socket for the API... Oct 28 12:55:55.156152 systemd[1]: Listening on OpenSSH Server Socket. Oct 28 12:55:55.157442 systemd[1]: Started Network Name Resolution. Oct 28 12:55:55.158447 systemd[1]: Listening on Docker Socket for the API. Oct 28 12:55:55.159464 systemd[1]: Reached target Network. Oct 28 12:55:55.160253 systemd[1]: Reached target Host and Network Name Lookups. Oct 28 12:55:55.161271 systemd[1]: Reached target Socket Units. Oct 28 12:55:55.162109 systemd[1]: Reached target Basic System. Oct 28 12:55:55.162984 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 28 12:55:55.163013 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 28 12:55:55.163990 systemd[1]: Started D-Bus System Message Bus. Oct 28 12:55:55.167108 systemd[1]: Starting Extend Filesystems... Oct 28 12:55:55.167963 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 28 12:55:55.168891 systemd[1]: Starting Generate /run/flatcar/motd... Oct 28 12:55:55.170662 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 28 12:55:55.173259 systemd[1]: Starting Generate sshd host keys... Oct 28 12:55:55.174364 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 28 12:55:55.174399 systemd[1]: Reached target Load system-provided cloud configs. Oct 28 12:55:55.175543 extend-filesystems[906]: Found vda Oct 28 12:55:55.175543 extend-filesystems[906]: Found vda1 Oct 28 12:55:55.175543 extend-filesystems[906]: Found vda2 Oct 28 12:55:55.177860 extend-filesystems[906]: Found vda3 Oct 28 12:55:55.177860 extend-filesystems[906]: Found usr Oct 28 12:55:55.177860 extend-filesystems[906]: Found vda4 Oct 28 12:55:55.177860 extend-filesystems[906]: Found vda6 Oct 28 12:55:55.177860 extend-filesystems[906]: Found vda7 Oct 28 12:55:55.177860 extend-filesystems[906]: Found vda9 Oct 28 12:55:55.177860 extend-filesystems[906]: Checking size of /dev/vda9 Oct 28 12:55:55.178586 systemd[1]: Starting User Login Management... Oct 28 12:55:55.179709 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 28 12:55:55.180522 systemd[1]: Starting Update Engine... Oct 28 12:55:55.181359 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 28 12:55:55.181392 systemd[1]: Reached target Load user-provided cloud configs. Oct 28 12:55:55.184538 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 28 12:55:55.184789 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 28 12:55:55.203546 extend-filesystems[906]: Old size kept for /dev/vda9 Oct 28 12:55:55.190062 systemd[1]: motdgen.service: Deactivated successfully. Oct 28 12:55:55.190283 systemd[1]: Finished Generate /run/flatcar/motd. Oct 28 12:55:55.200978 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 28 12:55:55.201189 systemd[1]: Finished Extend Filesystems. Oct 28 12:55:55.209995 update_engine[923]: I1028 12:55:55.209720 923 main.cc:89] Flatcar Update Engine starting Oct 28 12:55:55.211915 systemd[1]: Started Update Engine. Oct 28 12:55:55.214160 systemd[1]: Started Cluster reboot manager. Oct 28 12:55:55.216378 update_engine[923]: I1028 12:55:55.215999 923 update_check_scheduler.cc:74] Next update check in 4m15s Oct 28 12:55:55.220565 systemd-logind[919]: Watching system buttons on /dev/input/event0 (Power Button) Oct 28 12:55:55.220745 systemd-logind[919]: New seat seat0. Oct 28 12:55:55.223529 systemd[1]: Started User Login Management. Oct 28 12:55:55.250009 locksmithd[929]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 28 12:55:55.906076 systemd-networkd[841]: eth0: Gained IPv6LL Oct 28 12:55:56.223199 sshd_keygen[924]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 28 12:55:56.241061 systemd[1]: Finished Generate sshd host keys. Oct 28 12:55:56.253725 systemd[1]: Starting Generate /run/issue... Oct 28 12:55:56.257960 systemd[1]: issuegen.service: Deactivated successfully. Oct 28 12:55:56.258177 systemd[1]: Finished Generate /run/issue. Oct 28 12:55:56.260211 systemd[1]: Starting Permit User Sessions... Oct 28 12:55:56.264688 systemd[1]: Finished Permit User Sessions. Oct 28 12:55:56.266636 systemd[1]: Started Getty on tty1. Oct 28 12:55:56.268459 systemd[1]: Started Serial Getty on ttyAMA0. Oct 28 12:55:56.269456 systemd[1]: Reached target Login Prompts. Oct 28 12:55:56.270408 systemd[1]: Reached target Multi-User System. Oct 28 12:55:56.272453 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 28 12:55:56.277163 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 28 12:55:56.277379 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 28 12:55:56.278444 systemd[1]: Startup finished in 700ms (kernel) + 2.335s (initrd) + 3.959s (userspace) = 6.995s. Oct 28 12:56:01.808766 systemd[1]: Created slice Slice /system/sshd. Oct 28 12:56:01.810272 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50486). Oct 28 12:56:01.848192 sshd[950]: Accepted publickey for core from 10.0.0.1 port 50486 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:01.849757 sshd[950]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:01.862113 systemd[1]: Created slice User Slice of UID 500. Oct 28 12:56:01.863374 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 28 12:56:01.868535 systemd-logind[919]: New session 1 of user core. Oct 28 12:56:01.871450 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 28 12:56:01.872525 systemd[1]: Starting User Manager for UID 500... Oct 28 12:56:01.875640 systemd[953]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:01.921704 systemd[953]: Queued start job for default target Main User Target. Oct 28 12:56:01.921841 systemd[953]: Reached target Paths. Oct 28 12:56:01.921855 systemd[953]: Reached target Sockets. Oct 28 12:56:01.921866 systemd[953]: Reached target Timers. Oct 28 12:56:01.921877 systemd[953]: Reached target Basic System. Oct 28 12:56:01.921914 systemd[953]: Reached target Main User Target. Oct 28 12:56:01.921926 systemd[953]: Startup finished in 42ms. Oct 28 12:56:01.921982 systemd[1]: Started User Manager for UID 500. Oct 28 12:56:01.930811 systemd[1]: Started Session 1 of User core. Oct 28 12:56:01.989846 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50498). Oct 28 12:56:02.025761 sshd[962]: Accepted publickey for core from 10.0.0.1 port 50498 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:02.026708 sshd[962]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:02.029535 systemd-logind[919]: New session 2 of user core. Oct 28 12:56:02.038850 systemd[1]: Started Session 2 of User core. Oct 28 12:56:02.092460 sshd[962]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:02.105608 systemd[1]: sshd@1-10.0.0.4:22-10.0.0.1:50498.service: Deactivated successfully. Oct 28 12:56:02.106181 systemd[1]: session-2.scope: Deactivated successfully. Oct 28 12:56:02.106736 systemd-logind[919]: Session 2 logged out. Waiting for processes to exit. Oct 28 12:56:02.107738 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50500). Oct 28 12:56:02.108290 systemd-logind[919]: Removed session 2. Oct 28 12:56:02.141565 sshd[968]: Accepted publickey for core from 10.0.0.1 port 50500 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:02.142884 sshd[968]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:02.145459 systemd-logind[919]: New session 3 of user core. Oct 28 12:56:02.156631 systemd[1]: Started Session 3 of User core. Oct 28 12:56:02.205745 sshd[968]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:02.219210 systemd[1]: sshd@2-10.0.0.4:22-10.0.0.1:50500.service: Deactivated successfully. Oct 28 12:56:02.219774 systemd[1]: session-3.scope: Deactivated successfully. Oct 28 12:56:02.220298 systemd-logind[919]: Session 3 logged out. Waiting for processes to exit. Oct 28 12:56:02.221242 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50502). Oct 28 12:56:02.221797 systemd-logind[919]: Removed session 3. Oct 28 12:56:02.255062 sshd[975]: Accepted publickey for core from 10.0.0.1 port 50502 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:02.256377 sshd[975]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:02.259004 systemd-logind[919]: New session 4 of user core. Oct 28 12:56:02.268840 systemd[1]: Started Session 4 of User core. Oct 28 12:56:02.319829 sshd[975]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:02.331288 systemd[1]: sshd@3-10.0.0.4:22-10.0.0.1:50502.service: Deactivated successfully. Oct 28 12:56:02.331851 systemd[1]: session-4.scope: Deactivated successfully. Oct 28 12:56:02.332395 systemd-logind[919]: Session 4 logged out. Waiting for processes to exit. Oct 28 12:56:02.333349 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50508). Oct 28 12:56:02.333941 systemd-logind[919]: Removed session 4. Oct 28 12:56:02.367232 sshd[981]: Accepted publickey for core from 10.0.0.1 port 50508 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:02.368245 sshd[981]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:02.371103 systemd-logind[919]: New session 5 of user core. Oct 28 12:56:02.380975 systemd[1]: Started Session 5 of User core. Oct 28 12:56:02.437064 sudo[984]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 28 12:56:02.437278 sudo[984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 12:56:02.454797 sudo[984]: pam_unix(sudo:session): session closed for user root Oct 28 12:56:02.456819 sshd[981]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:02.469477 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50512). Oct 28 12:56:02.471713 dbus-daemon[905]: [system] Reloaded configuration Oct 28 12:56:02.474274 systemd[1]: sshd@4-10.0.0.4:22-10.0.0.1:50508.service: Deactivated successfully. Oct 28 12:56:02.474983 systemd[1]: session-5.scope: Deactivated successfully. Oct 28 12:56:02.475598 systemd-logind[919]: Session 5 logged out. Waiting for processes to exit. Oct 28 12:56:02.476213 systemd-logind[919]: Removed session 5. Oct 28 12:56:02.504755 sshd[987]: Accepted publickey for core from 10.0.0.1 port 50512 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:02.505718 sshd[987]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:02.508173 systemd-logind[919]: New session 6 of user core. Oct 28 12:56:02.517823 systemd[1]: Started Session 6 of User core. Oct 28 12:56:02.568013 sudo[993]: 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 28 12:56:02.568251 sudo[993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 12:56:02.570804 sudo[993]: pam_unix(sudo:session): session closed for user root Oct 28 12:56:02.574476 sudo[992]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 28 12:56:02.574715 sudo[992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 12:56:02.589615 systemd[1]: Stopping Load Security Auditing Rules... Oct 28 12:56:02.590000 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 28 12:56:02.592781 auditctl[996]: No rules Oct 28 12:56:02.593232 kernel: kauditd_printk_skb: 6 callbacks suppressed Oct 28 12:56:02.593271 kernel: audit: type=1305 audit(1761656162.590:68): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 28 12:56:02.593473 systemd[1]: audit-rules.service: Deactivated successfully. Oct 28 12:56:02.593733 systemd[1]: Stopped Load Security Auditing Rules. Oct 28 12:56:02.595097 systemd[1]: Starting Load Security Auditing Rules... Oct 28 12:56:02.609756 augenrules[1013]: No rules Oct 28 12:56:02.610399 systemd[1]: Finished Load Security Auditing Rules. Oct 28 12:56:02.611195 sudo[992]: pam_unix(sudo:session): session closed for user root Oct 28 12:56:02.612364 sshd[987]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:02.624697 systemd[1]: sshd@5-10.0.0.4:22-10.0.0.1:50512.service: Deactivated successfully. Oct 28 12:56:02.625296 systemd[1]: session-6.scope: Deactivated successfully. Oct 28 12:56:02.625898 systemd-logind[919]: Session 6 logged out. Waiting for processes to exit. Oct 28 12:56:02.626882 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50528). Oct 28 12:56:02.627505 systemd-logind[919]: Removed session 6. Oct 28 12:56:02.661035 sshd[1020]: Accepted publickey for core from 10.0.0.1 port 50528 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:02.661960 sshd[1020]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:02.664695 kernel: audit: type=1006 audit(1761656162.660:69): pid=1020 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 28 12:56:02.666180 systemd-logind[919]: New session 7 of user core. Oct 28 12:56:02.674811 systemd[1]: Started Session 7 of User core. Oct 28 12:56:02.724611 sudo[1023]: 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 28 12:56:05.483472 update_engine[1274]: Oct 28 12:56:05.483472 update_engine[1274]: Oct 28 12:56:05.483472 update_engine[1274]: Oct 28 12:56:05.483472 update_engine[1274]: Oct 28 12:56:05.483472 update_engine[1274]: Oct 28 12:56:05.483472 update_engine[1274]: Oct 28 12:56:05.483472 update_engine[1274]: Oct 28 12:56:05.483472 update_engine[1274]: Oct 28 12:56:05.483472 update_engine[1274]: I1028 12:56:05.483021 1274 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 28 12:56:05.488774 update_engine[1274]: I1028 12:56:05.488745 1274 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 28 12:56:05.488985 update_engine[1274]: I1028 12:56:05.488963 1274 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 28 12:56:05.492790 update_engine[1274]: I1028 12:56:05.492764 1274 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 28 12:56:05.492868 update_engine[1274]: I1028 12:56:05.492854 1274 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Oct 28 12:56:05.492868 update_engine[1274]: I1028 12:56:05.492866 1274 omaha_request_action.cc:619] Omaha request response: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.492868 update_engine[1274]: Oct 28 12:56:05.495732 update_engine[1274]: I1028 12:56:05.495708 1274 omaha_request_action.cc:447] Omaha Response manifest version = Oct 28 12:56:05.495771 update_engine[1274]: I1028 12:56:05.495738 1274 omaha_request_action.cc:470] Found 1 url(s) Oct 28 12:56:05.495771 update_engine[1274]: I1028 12:56:05.495750 1274 omaha_request_action.cc:506] Processing first of 1 package(s) Oct 28 12:56:05.495771 update_engine[1274]: I1028 12:56:05.495754 1274 omaha_request_action.cc:513] Omaha Response package name = update.gz Oct 28 12:56:05.495771 update_engine[1274]: I1028 12:56:05.495757 1274 omaha_request_action.cc:529] Url0: http://10.0.0.3:34567/packages/update.gz Oct 28 12:56:05.495771 update_engine[1274]: I1028 12:56:05.495765 1274 omaha_request_action.cc:541] Payload size = 435526319 bytes Oct 28 12:56:05.495860 update_engine[1274]: I1028 12:56:05.495775 1274 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Oct 28 12:56:05.495860 update_engine[1274]: I1028 12:56:05.495786 1274 payload_state.cc:51] Resetting all persisted state as this is a new response Oct 28 12:56:05.495860 update_engine[1274]: I1028 12:56:05.495790 1274 payload_state.cc:360] Current Response Signature = Oct 28 12:56:05.495860 update_engine[1274]: NumURLs = 1 Oct 28 12:56:05.495860 update_engine[1274]: Url0 = http://10.0.0.3:34567/packages/update.gz Oct 28 12:56:05.495860 update_engine[1274]: Payload Size = 435526319 Oct 28 12:56:05.495860 update_engine[1274]: Payload Sha256 Hash = MWe7Cz0hHAZPhG3RiToog9DXtPRhV9ko4O7ML90dZWg= Oct 28 12:56:05.495860 update_engine[1274]: Is Delta Payload = 0 Oct 28 12:56:05.495860 update_engine[1274]: Max Failure Count Per Url = 10 Oct 28 12:56:05.495860 update_engine[1274]: Disable Payload Backoff = 1 Oct 28 12:56:05.496024 update_engine[1274]: I1028 12:56:05.495883 1274 payload_state.cc:381] Payload Attempt Number = 0 Oct 28 12:56:05.496024 update_engine[1274]: I1028 12:56:05.495916 1274 payload_state.cc:404] Current URL Index = 0 Oct 28 12:56:05.496024 update_engine[1274]: I1028 12:56:05.495944 1274 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Oct 28 12:56:05.496024 update_engine[1274]: I1028 12:56:05.495968 1274 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Oct 28 12:56:05.496024 update_engine[1274]: I1028 12:56:05.495972 1274 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 28 12:56:05.496024 update_engine[1274]: I1028 12:56:05.496002 1274 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Oct 28 12:56:05.496024 update_engine[1274]: I1028 12:56:05.496018 1274 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 28 12:56:05.496024 update_engine[1274]: I1028 12:56:05.496022 1274 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Oct 28 12:56:05.496157 update_engine[1274]: I1028 12:56:05.496037 1274 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Oct 28 12:56:05.496157 update_engine[1274]: E1028 12:56:05.496040 1274 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Oct 28 12:56:05.496497 update_engine[1274]: I1028 12:56:05.496470 1274 omaha_response_handler_action.cc:85] Using this install plan: Oct 28 12:56:05.496497 update_engine[1274]: I1028 12:56:05.496492 1274 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.3:34567/packages/update.gz, payload size: 435526319, payload hash: MWe7Cz0hHAZPhG3RiToog9DXtPRhV9ko4O7ML90dZWg=, 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 28 12:56:05.496589 update_engine[1274]: I1028 12:56:05.496577 1274 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Oct 28 12:56:05.498404 update_engine[1274]: I1028 12:56:05.498374 1274 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Oct 28 12:56:05.520173 sshd[1295]: Accepted publickey for core from 10.0.0.1 port 50666 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:05.522315 sshd[1295]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:05.525011 systemd-logind[919]: New session 25 of user core. Oct 28 12:56:05.534822 systemd[1]: Started Session 25 of User core. Oct 28 12:56:05.588470 sshd[1295]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:05.590170 systemd[1]: session-25.scope: Deactivated successfully. Oct 28 12:56:05.590824 systemd-logind[919]: Session 25 logged out. Waiting for processes to exit. Oct 28 12:56:05.590907 systemd[1]: sshd@24-10.0.0.4:22-10.0.0.1:50666.service: Deactivated successfully. Oct 28 12:56:05.591788 systemd-logind[919]: Removed session 25. Oct 28 12:56:07.365410 update_engine[1274]: I1028 12:56:07.365376 1274 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Oct 28 12:56:07.365840 update_engine[1274]: I1028 12:56:07.365823 1274 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Oct 28 12:56:07.439342 update_engine[1274]: E1028 12:56:07.439291 1274 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Oct 28 12:56:07.439342 update_engine[1274]: I1028 12:56:07.439333 1274 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Oct 28 12:56:07.439342 update_engine[1274]: I1028 12:56:07.439338 1274 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Oct 28 12:56:07.439342 update_engine[1274]: I1028 12:56:07.439342 1274 update_attempter.cc:302] Processing Done. Oct 28 12:56:07.439580 update_engine[1274]: E1028 12:56:07.439388 1274 update_attempter.cc:615] Update failed. Oct 28 12:56:07.439580 update_engine[1274]: I1028 12:56:07.439393 1274 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Oct 28 12:56:07.439580 update_engine[1274]: I1028 12:56:07.439397 1274 payload_state.cc:276] Incrementing the URL failure count Oct 28 12:56:07.439580 update_engine[1274]: I1028 12:56:07.439400 1274 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 28 12:56:07.439662 update_engine[1274]: I1028 12:56:07.439586 1274 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 28 12:56:07.439662 update_engine[1274]: I1028 12:56:07.439651 1274 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.3:34567/v1/update Oct 28 12:56:07.439662 update_engine[1274]: I1028 12:56:07.439658 1274 omaha_request_action.cc:269] Request: Oct 28 12:56:07.439662 update_engine[1274]: Oct 28 12:56:07.439662 update_engine[1274]: Oct 28 12:56:07.439662 update_engine[1274]: Oct 28 12:56:07.439662 update_engine[1274]: Oct 28 12:56:07.439662 update_engine[1274]: Oct 28 12:56:07.439662 update_engine[1274]: Oct 28 12:56:07.439836 update_engine[1274]: I1028 12:56:07.439663 1274 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 28 12:56:07.439836 update_engine[1274]: I1028 12:56:07.439736 1274 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 28 12:56:07.439876 update_engine[1274]: I1028 12:56:07.439850 1274 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 28 12:56:07.571686 update_engine[1274]: I1028 12:56:07.571643 1274 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 28 12:56:07.571751 update_engine[1274]: I1028 12:56:07.571736 1274 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Oct 28 12:56:07.571751 update_engine[1274]: I1028 12:56:07.571743 1274 omaha_request_action.cc:619] Omaha request response: Oct 28 12:56:07.571751 update_engine[1274]: Oct 28 12:56:07.571751 update_engine[1274]: Oct 28 12:56:07.571751 update_engine[1274]: Oct 28 12:56:07.571751 update_engine[1274]: Oct 28 12:56:07.571751 update_engine[1274]: Oct 28 12:56:07.571751 update_engine[1274]: Oct 28 12:56:07.571884 update_engine[1274]: E1028 12:56:07.571758 1274 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Oct 28 12:56:07.571884 update_engine[1274]: I1028 12:56:07.571763 1274 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 28 12:56:07.571884 update_engine[1274]: I1028 12:56:07.571765 1274 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 28 12:56:07.571884 update_engine[1274]: I1028 12:56:07.571767 1274 update_attempter.cc:302] Processing Done. Oct 28 12:56:07.571884 update_engine[1274]: I1028 12:56:07.571772 1274 update_attempter.cc:306] Error event sent. Oct 28 12:56:15.597241 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39412). Oct 28 12:56:15.633938 sshd[1309]: Accepted publickey for core from 10.0.0.1 port 39412 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:15.637713 kernel: kauditd_printk_skb: 287 callbacks suppressed Oct 28 12:56:15.637780 kernel: audit: type=1006 audit(1761656175.634:365): pid=1309 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 28 12:56:15.637947 sshd[1309]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:15.640399 systemd-logind[919]: New session 26 of user core. Oct 28 12:56:15.650816 systemd[1]: Started Session 26 of User core. Oct 28 12:56:15.706431 sshd[1309]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:15.723283 systemd[1]: sshd@25-10.0.0.4:22-10.0.0.1:39412.service: Deactivated successfully. Oct 28 12:56:15.723858 systemd[1]: session-26.scope: Deactivated successfully. Oct 28 12:56:15.724402 systemd-logind[919]: Session 26 logged out. Waiting for processes to exit. Oct 28 12:56:15.725353 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39426). Oct 28 12:56:15.725956 systemd-logind[919]: Removed session 26. Oct 28 12:56:15.758848 sshd[1316]: Accepted publickey for core from 10.0.0.1 port 39426 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:15.759762 sshd[1316]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:15.761702 kernel: audit: type=1006 audit(1761656175.758:366): pid=1316 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 28 12:56:15.763391 systemd-logind[919]: New session 27 of user core. Oct 28 12:56:15.771793 systemd[1]: Started Session 27 of User core. Oct 28 12:56:15.825698 sshd[1316]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:15.833151 systemd[1]: sshd@26-10.0.0.4:22-10.0.0.1:39426.service: Deactivated successfully. Oct 28 12:56:15.833684 systemd[1]: session-27.scope: Deactivated successfully. Oct 28 12:56:15.834207 systemd-logind[919]: Session 27 logged out. Waiting for processes to exit. Oct 28 12:56:15.835131 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39432). Oct 28 12:56:15.835640 systemd-logind[919]: Removed session 27. Oct 28 12:56:15.869753 sshd[1324]: Accepted publickey for core from 10.0.0.1 port 39432 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:15.872129 sshd[1324]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:15.872696 kernel: audit: type=1006 audit(1761656175.869:367): pid=1324 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 28 12:56:15.874519 systemd-logind[919]: New session 28 of user core. Oct 28 12:56:15.882805 systemd[1]: Started Session 28 of User core. Oct 28 12:56:15.930666 sshd[1324]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:15.940107 systemd[1]: sshd@27-10.0.0.4:22-10.0.0.1:39432.service: Deactivated successfully. Oct 28 12:56:15.940617 systemd[1]: session-28.scope: Deactivated successfully. Oct 28 12:56:15.941195 systemd-logind[919]: Session 28 logged out. Waiting for processes to exit. Oct 28 12:56:15.942096 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39436). Oct 28 12:56:15.942738 systemd-logind[919]: Removed session 28. Oct 28 12:56:15.975407 sshd[1330]: Accepted publickey for core from 10.0.0.1 port 39436 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:15.976526 sshd[1330]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:15.978682 kernel: audit: type=1006 audit(1761656175.975:368): pid=1330 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 28 12:56:15.980403 systemd-logind[919]: New session 29 of user core. Oct 28 12:56:15.991805 systemd[1]: Started Session 29 of User core. Oct 28 12:56:16.040212 sshd[1330]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:16.048539 systemd[1]: sshd@28-10.0.0.4:22-10.0.0.1:39436.service: Deactivated successfully. Oct 28 12:56:16.049056 systemd[1]: session-29.scope: Deactivated successfully. Oct 28 12:56:16.049567 systemd-logind[919]: Session 29 logged out. Waiting for processes to exit. Oct 28 12:56:16.050548 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:39448). Oct 28 12:56:16.051204 systemd-logind[919]: Removed session 29. Oct 28 12:56:16.083645 sshd[1336]: Accepted publickey for core from 10.0.0.1 port 39448 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:16.084523 sshd[1336]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:16.086705 kernel: audit: type=1006 audit(1761656176.083:369): pid=1336 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 28 12:56:16.088469 systemd-logind[919]: New session 30 of user core. Oct 28 12:56:16.101975 systemd[1]: Started Session 30 of User core. Oct 28 12:56:16.155296 sudo[1340]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Oct 28 12:56:16.155522 sudo[1340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 12:56:16.161635 systemd[1]: sshd.socket: Deactivated successfully. Oct 28 12:56:16.161981 systemd[1]: Closed OpenSSH Server Socket. Oct 28 12:56:16.162863 sudo[1340]: pam_unix(sudo:session): session closed for user root Oct 28 12:56:16.166344 sudo[1339]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Oct 28 12:56:16.166800 sudo[1339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 12:56:16.180954 sshd[950]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:16.181020 systemd[1]: Stopping Session 1 of User core... Oct 28 12:56:16.182408 systemd[1]: Stopping Session 30 of User core... Oct 28 12:56:16.182751 sshd[1336]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:16.183257 sudo[1339]: pam_unix(sudo:session): session closed for user root Oct 28 12:56:16.183477 systemd[1]: Removed slice Slice /system/addon-config. Oct 28 12:56:16.184794 systemd[1]: Removed slice Slice /system/addon-run. Oct 28 12:56:16.185977 systemd[1]: Removed slice Slice /system/modprobe. Oct 28 12:56:16.187117 systemd[1]: Removed slice Slice /system/system-cloudinit. Oct 28 12:56:16.188146 systemd[1]: Stopped target Multi-User System. Oct 28 12:56:16.189009 systemd[1]: Stopped target Login Prompts. Oct 28 12:56:16.189797 systemd[1]: Stopped target Containers. Oct 28 12:56:16.190549 systemd[1]: Stopped target Host and Network Name Lookups. Oct 28 12:56:16.191614 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 28 12:56:16.192763 systemd[1]: Stopped target Timer Units. Oct 28 12:56:16.193502 systemd[1]: logrotate.timer: Deactivated successfully. Oct 28 12:56:16.193547 systemd[1]: Stopped Daily Log Rotation. Oct 28 12:56:16.194505 systemd[1]: mdadm.timer: Deactivated successfully. Oct 28 12:56:16.194543 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Oct 28 12:56:16.195682 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 28 12:56:16.195724 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Oct 28 12:56:16.196740 systemd[1]: Stopped target System Time Set. Oct 28 12:56:16.197548 systemd[1]: Stopped target Load user-provided cloud configs. Oct 28 12:56:16.198608 systemd[1]: Stopped target Load system-provided cloud configs. Oct 28 12:56:16.199705 systemd[1]: systemd-coredump.socket: Deactivated successfully. Oct 28 12:56:16.199798 systemd[1]: Closed Process Core Dump Socket. Oct 28 12:56:16.201255 systemd[1]: Unmounting Boot partition... Oct 28 12:56:16.202501 systemd[1]: Stopping Getty on tty1... Oct 28 12:56:16.203418 systemd[1]: Stopping Serial Getty on ttyAMA0... Oct 28 12:56:16.204340 systemd[1]: Stopping OpenSSH per-connection server daemon... Oct 28 12:56:16.205311 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:39448)... Oct 28 12:56:16.206285 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Oct 28 12:56:16.206426 systemd[1]: Stopped Commit a transient machine-id on disk. Oct 28 12:56:16.207528 systemd[1]: Stopped target First Boot Complete. Oct 28 12:56:16.214475 systemd[1]: Stopping Load/Save Random Seed... Oct 28 12:56:16.215478 systemd[1]: Stopping Update Engine... Oct 28 12:56:16.218637 systemd[1]: getty@tty1.service: Deactivated successfully. Oct 28 12:56:16.218847 systemd[1]: Stopped Getty on tty1. Oct 28 12:56:16.219963 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Oct 28 12:56:16.220145 systemd[1]: Stopped Serial Getty on ttyAMA0. Oct 28 12:56:16.221380 systemd[1]: sshd@0-10.0.0.4:22-10.0.0.1:50486.service: Deactivated successfully. Oct 28 12:56:16.221587 systemd[1]: Stopped OpenSSH per-connection server daemon. Oct 28 12:56:16.223047 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Oct 28 12:56:16.223114 systemd[1]: update-engine.service: Failed with result 'exit-code'. Oct 28 12:56:16.223348 systemd[1]: Stopped Update Engine. Oct 28 12:56:16.224253 systemd[1]: update-engine.service: Consumed 2.359s CPU time. Oct 28 12:56:16.224481 systemd[1]: sshd@29-10.0.0.4:22-10.0.0.1:39448.service: Deactivated successfully. Oct 28 12:56:16.224654 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:39448). Oct 28 12:56:16.235247 systemd[1]: boot.mount: Deactivated successfully. Oct 28 12:56:16.235497 systemd[1]: Unmounted Boot partition. Oct 28 12:56:16.236616 systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 28 12:56:16.236830 systemd[1]: Stopped Load/Save Random Seed. Oct 28 12:56:16.237848 systemd[1]: session-1.scope: Deactivated successfully. Oct 28 12:56:16.238082 systemd[1]: Stopped Session 1 of User core. Oct 28 12:56:16.239039 systemd[1]: session-30.scope: Deactivated successfully. Oct 28 12:56:16.239253 systemd[1]: Stopped Session 30 of User core. Oct 28 12:56:16.240588 systemd-logind[919]: Session 1 logged out. Waiting for processes to exit. Oct 28 12:56:16.241485 systemd-logind[919]: Session 30 logged out. Waiting for processes to exit. Oct 28 12:56:16.241748 systemd[1]: Removed slice Slice /system/getty. Oct 28 12:56:16.242895 systemd[1]: Removed slice Slice /system/serial-getty. Oct 28 12:56:16.244056 systemd[1]: Removed slice Slice /system/sshd. Oct 28 12:56:16.245200 systemd[1]: sshd-keygen.service: Deactivated successfully. Oct 28 12:56:16.245305 systemd[1]: Stopped Generate sshd host keys. Oct 28 12:56:16.246154 systemd[1]: sshd-keygen.service: Consumed 1.042s CPU time. Oct 28 12:56:16.246194 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Oct 28 12:56:16.246306 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Oct 28 12:56:16.247616 systemd[1]: Stopping User Login Management... Oct 28 12:56:16.248452 systemd[1]: Stopping User Manager for UID 500... Oct 28 12:56:16.249228 systemd[953]: Stopped target Main User Target. Oct 28 12:56:16.249248 systemd[953]: Stopped target Basic System. Oct 28 12:56:16.249257 systemd[953]: Stopped target Paths. Oct 28 12:56:16.249265 systemd[953]: Stopped target Sockets. Oct 28 12:56:16.249281 systemd[953]: Reached target Shutdown. Oct 28 12:56:16.249288 systemd[953]: Stopped target Timers. Oct 28 12:56:16.249394 systemd[953]: Finished Exit the Session. Oct 28 12:56:16.249417 systemd[953]: Reached target Exit the Session. Oct 28 12:56:16.250431 systemd-logind[919]: Removed session 1. Oct 28 12:56:16.251520 systemd[1]: user@500.service: Deactivated successfully. Oct 28 12:56:16.251706 systemd[1]: Stopped User Manager for UID 500. Oct 28 12:56:16.253600 systemd[1]: Stopping User Runtime Directory /run/user/500... Oct 28 12:56:16.258713 systemd[1]: run-user-500.mount: Deactivated successfully. Oct 28 12:56:16.258772 systemd[1]: Unmounted /run/user/500. Oct 28 12:56:16.259890 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Oct 28 12:56:16.260084 systemd[1]: Stopped User Runtime Directory /run/user/500. Oct 28 12:56:16.261701 systemd[1]: Removed slice User Slice of UID 500. Oct 28 12:56:16.263914 systemd[1]: Stopping D-Bus System Message Bus... Oct 28 12:56:16.273433 systemd[1]: Stopping Permit User Sessions... Oct 28 12:56:16.274708 systemd[1]: dbus.service: Deactivated successfully. Oct 28 12:56:16.274942 systemd[1]: Stopped D-Bus System Message Bus. Oct 28 12:56:16.275939 systemd[1]: systemd-logind.service: Deactivated successfully. Oct 28 12:56:16.276180 systemd[1]: Stopped User Login Management. Oct 28 12:56:16.277656 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Oct 28 12:56:16.277879 systemd[1]: Stopped Permit User Sessions. Oct 28 12:56:16.278706 systemd[1]: Stopped target Basic System. Oct 28 12:56:16.279422 systemd[1]: Stopped target Network. Oct 28 12:56:16.280156 systemd[1]: Stopped target Path Units. Oct 28 12:56:16.280870 systemd[1]: motdgen.path: Deactivated successfully. Oct 28 12:56:16.284000 audit: BPF prog-id=60 op=UNLOAD Oct 28 12:56:16.284887 systemd[1]: Stopped Watch for update engine configuration changes. Oct 28 12:56:16.286697 kernel: audit: type=1334 audit(1761656176.284:370): prog-id=60 op=UNLOAD Oct 28 12:56:16.286936 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Oct 28 12:56:16.291481 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 28 12:56:16.292728 systemd[1]: Stopped target Remote File Systems. Oct 28 12:56:16.293493 systemd[1]: Stopped target Slice Units. Oct 28 12:56:16.294529 systemd[1]: Removed slice User and Session Slice. Oct 28 12:56:16.295413 systemd[1]: Stopped target Socket Units. Oct 28 12:56:16.296224 systemd[1]: dbus.socket: Deactivated successfully. Oct 28 12:56:16.296277 systemd[1]: Closed D-Bus System Message Bus Socket. Oct 28 12:56:16.297252 systemd[1]: docker.socket: Deactivated successfully. Oct 28 12:56:16.297511 systemd[1]: Closed Docker Socket for the API. Oct 28 12:56:16.298357 systemd[1]: Stopped target System Initialization. Oct 28 12:56:16.299232 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Oct 28 12:56:16.299358 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Oct 28 12:56:16.300556 systemd[1]: Stopped target Local Verity Protected Volumes. Oct 28 12:56:16.302283 systemd[1]: Stopping Load Security Auditing Rules... Oct 28 12:56:16.303278 systemd[1]: Stopping Network Name Resolution... Oct 28 12:56:16.304358 auditctl[1354]: No rules Oct 28 12:56:16.304430 systemd[1]: Stopping Network Time Synchronization... Oct 28 12:56:16.305303 systemd[1]: systemd-update-done.service: Deactivated successfully. Oct 28 12:56:16.305379 systemd[1]: Stopped Update is Completed. Oct 28 12:56:16.306167 systemd[1]: ldconfig.service: Deactivated successfully. Oct 28 12:56:16.306227 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Oct 28 12:56:16.307785 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Oct 28 12:56:16.307845 systemd[1]: Stopped Rebuild Hardware Database. Oct 28 12:56:16.308656 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Oct 28 12:56:16.308739 systemd[1]: Stopped Rebuild Journal Catalog. Oct 28 12:56:16.310342 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Oct 28 12:56:16.312879 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 28 12:56:16.313140 systemd[1]: Stopped Network Name Resolution. Oct 28 12:56:16.314206 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Oct 28 12:56:16.314451 systemd[1]: Stopped Network Time Synchronization. Oct 28 12:56:16.315639 systemd[1]: audit-rules.service: Deactivated successfully. Oct 28 12:56:16.315932 systemd[1]: Stopped Load Security Auditing Rules. Oct 28 12:56:16.318247 systemd[1]: Stopping Network Configuration... Oct 28 12:56:16.318000 audit: BPF prog-id=59 op=UNLOAD Oct 28 12:56:16.318000 audit: BPF prog-id=63 op=UNLOAD Oct 28 12:56:16.320843 kernel: audit: type=1334 audit(1761656176.318:371): prog-id=59 op=UNLOAD Oct 28 12:56:16.320913 kernel: audit: type=1334 audit(1761656176.318:372): prog-id=63 op=UNLOAD Oct 28 12:56:16.321082 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Oct 28 12:56:16.321299 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Oct 28 12:56:16.321710 systemd-networkd[841]: eth0: DHCPv6 lease lost Oct 28 12:56:16.323096 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 28 12:56:16.323329 systemd[1]: Stopped Network Configuration. Oct 28 12:56:16.324265 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 28 12:56:16.324313 systemd[1]: Closed Network Service Netlink Socket. Oct 28 12:56:16.325198 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 28 12:56:16.325254 systemd[1]: Stopped Apply Kernel Variables. Oct 28 12:56:16.326051 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 28 12:56:16.326108 systemd[1]: Stopped Create Volatile Files and Directories. Oct 28 12:56:16.327047 systemd[1]: Stopped target Local File Systems. Oct 28 12:56:16.328763 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Oct 28 12:56:16.330180 systemd[1]: Unmounting External Media Directory... Oct 28 12:56:16.331000 audit: BPF prog-id=67 op=UNLOAD Oct 28 12:56:16.331924 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Oct 28 12:56:16.333711 kernel: audit: type=1334 audit(1761656176.331:373): prog-id=67 op=UNLOAD Oct 28 12:56:16.334397 systemd[1]: Unmounting /run/torcx/unpack... Oct 28 12:56:16.335711 systemd[1]: Unmounting Temporary Directory /tmp... Oct 28 12:56:16.337000 systemd[1]: Unmounting /usr/share/oem... Oct 28 12:56:16.338493 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Oct 28 12:56:16.341051 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 28 12:56:16.341274 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Oct 28 12:56:16.342558 systemd[1]: media.mount: Deactivated successfully. Oct 28 12:56:16.342853 systemd[1]: Unmounted External Media Directory. Oct 28 12:56:16.343792 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Oct 28 12:56:16.344018 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Oct 28 12:56:16.345236 systemd[1]: tmp.mount: Deactivated successfully. Oct 28 12:56:16.345493 systemd[1]: Unmounted Temporary Directory /tmp. Oct 28 12:56:16.346554 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 28 12:56:16.346809 systemd[1]: Unmounted /usr/share/oem. Oct 28 12:56:16.347666 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 28 12:56:16.347905 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Oct 28 12:56:16.349342 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Oct 28 12:56:16.349412 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Oct 28 12:56:16.350739 systemd[1]: Removed slice Slice /system/systemd-fsck. Oct 28 12:56:16.355422 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Oct 28 12:56:16.355647 systemd[1]: Unmounted /run/torcx/unpack. Oct 28 12:56:16.356746 systemd[1]: Stopped target Preparation for Local File Systems. Oct 28 12:56:16.357723 systemd[1]: Stopped target Swaps. Oct 28 12:56:16.358393 systemd[1]: Reached target Unmount All Filesystems. Oct 28 12:56:16.359444 systemd[1]: lvm2-activation.service: Deactivated successfully. Oct 28 12:56:16.359519 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 28 12:56:16.360494 systemd[1]: Stopped target Local Encrypted Volumes. Oct 28 12:56:16.361396 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 28 12:56:16.361489 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 28 12:56:16.362623 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Oct 28 12:56:16.366715 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Oct 28 12:56:16.367910 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Oct 28 12:56:16.367983 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 28 12:56:16.368911 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 28 12:56:16.368972 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 28 12:56:16.369912 systemd[1]: systemd-sysusers.service: Deactivated successfully. Oct 28 12:56:16.369975 systemd[1]: Stopped Create System Users. Oct 28 12:56:16.370772 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Oct 28 12:56:16.370831 systemd[1]: Stopped Remount Root and Kernel File Systems. Oct 28 12:56:16.372416 systemd[1]: Reached target System Shutdown. Oct 28 12:56:16.373382 systemd[1]: Reached target Late Shutdown Services. Oct 28 12:56:16.374347 systemd[1]: systemd-reboot.service: Deactivated successfully. Oct 28 12:56:16.374388 systemd[1]: Finished System Reboot. Oct 28 12:56:16.375144 systemd[1]: Reached target System Reboot. Oct 28 12:56:16.375987 systemd[1]: Shutting down. Oct 28 12:56:16.377000 audit: BPF prog-id=58 op=UNLOAD Oct 28 12:56:16.378687 kernel: audit: type=1334 audit(1761656176.377:374): prog-id=58 op=UNLOAD Oct 28 12:56:16.377000 audit: BPF prog-id=57 op=UNLOAD Oct 28 12:56:16.377000 audit: BPF prog-id=56 op=UNLOAD Oct 28 12:56:16.377000 audit: BPF prog-id=62 op=UNLOAD Oct 28 12:56:16.377000 audit: BPF prog-id=61 op=UNLOAD Oct 28 12:56:16.380000 audit: BPF prog-id=66 op=UNLOAD Oct 28 12:56:16.380000 audit: BPF prog-id=65 op=UNLOAD Oct 28 12:56:16.380000 audit: BPF prog-id=64 op=UNLOAD Oct 28 12:56:16.425710 systemd-shutdown[1]: Syncing filesystems and block devices. Oct 28 12:56:16.432510 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Oct 28 12:56:16.432600 systemd-journald[805]: Journal stopped -- Reboot -- Oct 28 12:56:23.675536 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 28 12:56:23.675552 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 28 12:56:23.675560 kernel: efi: EFI v2.70 by EDK II Oct 28 12:56:23.675565 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 28 12:56:23.675569 kernel: efi: seeding entropy pool Oct 28 12:56:23.675574 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 28 12:56:23.675580 kernel: ACPI: Early table checksum verification disabled Oct 28 12:56:23.675585 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 28 12:56:23.675590 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 28 12:56:23.675595 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:56:23.675602 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:56:23.675606 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:56:23.675611 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:56:23.675616 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:56:23.675622 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:56:23.675627 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:56:23.675634 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:56:23.675639 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:56:23.675644 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 28 12:56:23.675649 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 28 12:56:23.675654 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 12:56:23.675660 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 28 12:56:23.675665 kernel: Zone ranges: Oct 28 12:56:23.675670 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 12:56:23.675675 kernel: DMA32 empty Oct 28 12:56:23.675680 kernel: Normal empty Oct 28 12:56:23.675685 kernel: Movable zone start for each node Oct 28 12:56:23.675691 kernel: Early memory node ranges Oct 28 12:56:23.675696 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 28 12:56:23.675702 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 28 12:56:23.675707 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 28 12:56:23.675712 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 28 12:56:23.675717 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 28 12:56:23.675722 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 28 12:56:23.675727 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 28 12:56:23.675732 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 28 12:56:23.675737 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 28 12:56:23.675742 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 28 12:56:23.675747 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 28 12:56:23.675753 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 12:56:23.675761 kernel: On node 0 totalpages: 643072 Oct 28 12:56:23.675766 kernel: DMA zone: 10048 pages used for memmap Oct 28 12:56:23.675772 kernel: DMA zone: 0 pages reserved Oct 28 12:56:23.675777 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 28 12:56:23.675782 kernel: psci: probing for conduit method from ACPI. Oct 28 12:56:23.675788 kernel: psci: PSCIv1.1 detected in firmware. Oct 28 12:56:23.675793 kernel: psci: Using standard PSCI v0.2 function IDs Oct 28 12:56:23.675799 kernel: psci: Trusted OS migration not required Oct 28 12:56:23.675805 kernel: psci: SMC Calling Convention v1.1 Oct 28 12:56:23.675810 kernel: ACPI: SRAT not present Oct 28 12:56:23.675816 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 28 12:56:23.675821 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 28 12:56:23.675827 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 28 12:56:23.675832 kernel: Detected PIPT I-cache on CPU0 Oct 28 12:56:23.675838 kernel: CPU features: detected: GIC system register CPU interface Oct 28 12:56:23.675843 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 28 12:56:23.675849 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 28 12:56:23.675854 kernel: CPU features: detected: Hardware dirty bit management Oct 28 12:56:23.675859 kernel: CPU features: detected: Spectre-v4 Oct 28 12:56:23.675866 kernel: CPU features: detected: ARM erratum 1418040 Oct 28 12:56:23.675872 kernel: CPU features: detected: Spectre-BHB Oct 28 12:56:23.675877 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 28 12:56:23.675882 kernel: Policy zone: DMA Oct 28 12:56:23.675889 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 28 12:56:23.675895 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 28 12:56:23.675900 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 28 12:56:23.675906 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 28 12:56:23.675911 kernel: Memory: 2452096K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120192K reserved, 0K cma-reserved) Oct 28 12:56:23.675917 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 28 12:56:23.675922 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 28 12:56:23.675929 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 28 12:56:23.675935 kernel: rcu: RCU event tracing is enabled. Oct 28 12:56:23.675940 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 28 12:56:23.675946 kernel: Trampoline variant of Tasks RCU enabled. Oct 28 12:56:23.675951 kernel: Tracing variant of Tasks RCU enabled. Oct 28 12:56:23.675957 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 28 12:56:23.675963 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 28 12:56:23.675968 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 28 12:56:23.675973 kernel: GICv3: 256 SPIs implemented Oct 28 12:56:23.675979 kernel: GICv3: 0 Extended SPIs implemented Oct 28 12:56:23.675984 kernel: GICv3: Distributor has no Range Selector support Oct 28 12:56:23.675989 kernel: GICv3: 16 PPIs implemented Oct 28 12:56:23.675996 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 28 12:56:23.676001 kernel: ACPI: SRAT not present Oct 28 12:56:23.676007 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 28 12:56:23.676012 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 28 12:56:23.676018 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 28 12:56:23.676023 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 28 12:56:23.676029 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 28 12:56:23.676034 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 12:56:23.676040 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 28 12:56:23.676045 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 28 12:56:23.676051 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 28 12:56:23.676057 kernel: arm-pv: using stolen time PV Oct 28 12:56:23.676064 kernel: Console: colour dummy device 80x25 Oct 28 12:56:23.676078 kernel: ACPI: Core revision 20200925 Oct 28 12:56:23.676088 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 28 12:56:23.676094 kernel: pid_max: default: 32768 minimum: 301 Oct 28 12:56:23.676100 kernel: LSM: Security Framework initializing Oct 28 12:56:23.676106 kernel: SELinux: Initializing. Oct 28 12:56:23.676111 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 12:56:23.676117 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 12:56:23.676123 kernel: rcu: Hierarchical SRCU implementation. Oct 28 12:56:23.676130 kernel: Platform MSI: ITS@0x8080000 domain created Oct 28 12:56:23.676136 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 28 12:56:23.676142 kernel: Remapping and enabling EFI services. Oct 28 12:56:23.676147 kernel: smp: Bringing up secondary CPUs ... Oct 28 12:56:23.676153 kernel: Detected PIPT I-cache on CPU1 Oct 28 12:56:23.676159 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 28 12:56:23.676165 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 28 12:56:23.676170 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 12:56:23.676176 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 28 12:56:23.676182 kernel: Detected PIPT I-cache on CPU2 Oct 28 12:56:23.676189 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 28 12:56:23.676195 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 28 12:56:23.676200 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 12:56:23.676206 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 28 12:56:23.676212 kernel: Detected PIPT I-cache on CPU3 Oct 28 12:56:23.676222 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 28 12:56:23.676228 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 28 12:56:23.676235 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 12:56:23.676241 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 28 12:56:23.676247 kernel: smp: Brought up 1 node, 4 CPUs Oct 28 12:56:23.676252 kernel: SMP: Total of 4 processors activated. Oct 28 12:56:23.676258 kernel: CPU features: detected: Privileged Access Never Oct 28 12:56:23.676264 kernel: CPU features: detected: LSE atomic instructions Oct 28 12:56:23.676270 kernel: CPU features: detected: User Access Override Oct 28 12:56:23.676276 kernel: CPU features: detected: 32-bit EL0 Support Oct 28 12:56:23.676282 kernel: CPU features: detected: Common not Private translations Oct 28 12:56:23.676288 kernel: CPU features: detected: RAS Extension Support Oct 28 12:56:23.676295 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 28 12:56:23.676301 kernel: CPU features: detected: CRC32 instructions Oct 28 12:56:23.676307 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 28 12:56:23.676313 kernel: CPU: All CPU(s) started at EL1 Oct 28 12:56:23.676319 kernel: alternatives: patching kernel code Oct 28 12:56:23.676326 kernel: devtmpfs: initialized Oct 28 12:56:23.676332 kernel: KASLR enabled Oct 28 12:56:23.676338 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 28 12:56:23.676344 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 28 12:56:23.676350 kernel: pinctrl core: initialized pinctrl subsystem Oct 28 12:56:23.676356 kernel: SMBIOS 3.0.0 present. Oct 28 12:56:23.676376 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 28 12:56:23.676383 kernel: NET: Registered protocol family 16 Oct 28 12:56:23.676389 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 28 12:56:23.676395 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 28 12:56:23.676403 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 28 12:56:23.676409 kernel: audit: initializing netlink subsys (disabled) Oct 28 12:56:23.676415 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Oct 28 12:56:23.676421 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 28 12:56:23.676427 kernel: cpuidle: using governor menu Oct 28 12:56:23.676433 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 28 12:56:23.676439 kernel: ASID allocator initialised with 32768 entries Oct 28 12:56:23.676445 kernel: ACPI: bus type PCI registered Oct 28 12:56:23.676451 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 28 12:56:23.676458 kernel: Serial: AMBA PL011 UART driver Oct 28 12:56:23.676464 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 28 12:56:23.676470 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 28 12:56:23.676476 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 28 12:56:23.676482 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 28 12:56:23.676488 kernel: cryptd: max_cpu_qlen set to 1000 Oct 28 12:56:23.676494 kernel: ACPI: Added _OSI(Module Device) Oct 28 12:56:23.676500 kernel: ACPI: Added _OSI(Processor Device) Oct 28 12:56:23.676506 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 28 12:56:23.676513 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 28 12:56:23.676519 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 28 12:56:23.676525 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 28 12:56:23.676531 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 28 12:56:23.676537 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 28 12:56:23.676543 kernel: ACPI: Interpreter enabled Oct 28 12:56:23.676552 kernel: ACPI: Using GIC for interrupt routing Oct 28 12:56:23.676558 kernel: ACPI: MCFG table detected, 1 entries Oct 28 12:56:23.676564 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 28 12:56:23.676570 kernel: printk: console [ttyAMA0] enabled Oct 28 12:56:23.676577 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 28 12:56:23.676703 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 28 12:56:23.676782 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 28 12:56:23.676834 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 28 12:56:23.676885 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 28 12:56:23.676935 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 28 12:56:23.676943 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 28 12:56:23.676951 kernel: PCI host bridge to bus 0000:00 Oct 28 12:56:23.677007 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 28 12:56:23.677054 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 28 12:56:23.677110 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 28 12:56:23.677158 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 28 12:56:23.677222 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 28 12:56:23.677282 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 28 12:56:23.677339 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 28 12:56:23.677408 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 28 12:56:23.677464 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 12:56:23.677520 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 12:56:23.677581 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 28 12:56:23.677659 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 28 12:56:23.677709 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 28 12:56:23.677758 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 28 12:56:23.677827 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 28 12:56:23.677835 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 28 12:56:23.677842 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 28 12:56:23.677848 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 28 12:56:23.677854 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 28 12:56:23.677860 kernel: vgaarb: loaded Oct 28 12:56:23.677866 kernel: Registered efivars operations Oct 28 12:56:23.677872 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 28 12:56:23.677880 kernel: VFS: Disk quotas dquot_6.6.0 Oct 28 12:56:23.677886 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 28 12:56:23.677892 kernel: pnp: PnP ACPI init Oct 28 12:56:23.677950 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 28 12:56:23.677999 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 28 12:56:23.678007 kernel: pnp: PnP ACPI: found 1 devices Oct 28 12:56:23.678013 kernel: NET: Registered protocol family 2 Oct 28 12:56:23.678019 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 28 12:56:23.678027 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 28 12:56:23.678033 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 28 12:56:23.678039 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 28 12:56:23.678045 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 28 12:56:23.678051 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 12:56:23.678057 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 12:56:23.678063 kernel: NET: Registered protocol family 1 Oct 28 12:56:23.678076 kernel: PCI: CLS 0 bytes, default 64 Oct 28 12:56:23.678083 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 28 12:56:23.678090 kernel: kvm [1]: HYP mode not available Oct 28 12:56:23.678096 kernel: Initialise system trusted keyrings Oct 28 12:56:23.678102 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 28 12:56:23.678108 kernel: Key type asymmetric registered Oct 28 12:56:23.678114 kernel: Asymmetric key parser 'x509' registered Oct 28 12:56:23.678120 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 28 12:56:23.678126 kernel: io scheduler mq-deadline registered Oct 28 12:56:23.678132 kernel: io scheduler kyber registered Oct 28 12:56:23.678138 kernel: io scheduler bfq registered Oct 28 12:56:23.678146 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 28 12:56:23.678152 kernel: ACPI: Power Button [PWRB] Oct 28 12:56:23.678158 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 28 12:56:23.678214 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 28 12:56:23.678222 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 28 12:56:23.678228 kernel: msm_serial: driver initialized Oct 28 12:56:23.678234 kernel: random: fast init done Oct 28 12:56:23.678240 kernel: random: crng init done Oct 28 12:56:23.678246 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 28 12:56:23.678252 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 28 12:56:23.678259 kernel: thunder_xcv, ver 1.0 Oct 28 12:56:23.678265 kernel: thunder_bgx, ver 1.0 Oct 28 12:56:23.678271 kernel: nicpf, ver 1.0 Oct 28 12:56:23.678277 kernel: nicvf, ver 1.0 Oct 28 12:56:23.678341 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 28 12:56:23.678399 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-28T12:56:23 UTC (1761656183) Oct 28 12:56:23.678408 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 28 12:56:23.678414 kernel: NET: Registered protocol family 10 Oct 28 12:56:23.678421 kernel: Segment Routing with IPv6 Oct 28 12:56:23.678427 kernel: NET: Registered protocol family 17 Oct 28 12:56:23.678433 kernel: Key type dns_resolver registered Oct 28 12:56:23.678439 kernel: registered taskstats version 1 Oct 28 12:56:23.678445 kernel: Loading compiled-in X.509 certificates Oct 28 12:56:23.678451 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 28 12:56:23.678457 kernel: Key type ._fscrypt registered Oct 28 12:56:23.678463 kernel: Key type .fscrypt registered Oct 28 12:56:23.678469 kernel: Key type fscrypt-provisioning registered Oct 28 12:56:23.678476 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 28 12:56:23.678482 kernel: ima: Allocated hash algorithm: sha1 Oct 28 12:56:23.678488 kernel: ima: No architecture policies found Oct 28 12:56:23.678494 kernel: Freeing unused kernel memory: 34240K Oct 28 12:56:23.678500 kernel: Run /init as init process Oct 28 12:56:23.678506 kernel: with arguments: Oct 28 12:56:23.678512 kernel: /init Oct 28 12:56:23.678518 kernel: with environment: Oct 28 12:56:23.678523 kernel: HOME=/ Oct 28 12:56:23.678529 kernel: TERM=linux Oct 28 12:56:23.678536 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 28 12:56:23.678543 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 28 12:56:23.678552 systemd[1]: Detected virtualization kvm. Oct 28 12:56:23.678558 systemd[1]: Detected architecture arm64. Oct 28 12:56:23.678565 systemd[1]: Running in initial RAM disk. Oct 28 12:56:23.678571 systemd[1]: No hostname configured, using default hostname. Oct 28 12:56:23.678577 systemd[1]: Hostname set to . Oct 28 12:56:23.678585 systemd[1]: Initializing machine ID from VM UUID. Oct 28 12:56:23.678591 systemd[1]: Queued start job for default target Initrd Default Target. Oct 28 12:56:23.678598 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 28 12:56:23.678604 systemd[1]: Reached target Local Encrypted Volumes. Oct 28 12:56:23.678611 systemd[1]: Reached target Path Units. Oct 28 12:56:23.678617 systemd[1]: Reached target Slice Units. Oct 28 12:56:23.678623 systemd[1]: Reached target Swaps. Oct 28 12:56:23.678629 systemd[1]: Reached target Timer Units. Oct 28 12:56:23.678637 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 28 12:56:23.678644 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 28 12:56:23.678650 systemd[1]: Listening on Journal Audit Socket. Oct 28 12:56:23.678657 systemd[1]: Listening on Journal Socket (/dev/log). Oct 28 12:56:23.678663 systemd[1]: Listening on Journal Socket. Oct 28 12:56:23.678670 systemd[1]: Listening on udev Control Socket. Oct 28 12:56:23.678676 systemd[1]: Listening on udev Kernel Socket. Oct 28 12:56:23.678683 systemd[1]: Reached target Socket Units. Oct 28 12:56:23.678689 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 28 12:56:23.678697 systemd[1]: Starting Create List of Static Device Nodes... Oct 28 12:56:23.678704 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 28 12:56:23.678710 systemd[1]: Starting Journal Service... Oct 28 12:56:23.678717 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 28 12:56:23.678723 systemd[1]: Starting Apply Kernel Variables... Oct 28 12:56:23.678729 systemd[1]: Starting Setup Virtual Console... Oct 28 12:56:23.678736 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 28 12:56:23.678742 kernel: SCSI subsystem initialized Oct 28 12:56:23.678748 kernel: Loading iSCSI transport class v2.0-870. Oct 28 12:56:23.678754 systemd[1]: Finished Create List of Static Device Nodes. Oct 28 12:56:23.678764 systemd-journald[270]: Journal started Oct 28 12:56:23.678799 systemd-journald[270]: Runtime Journal (/run/log/journal/37ecb6ddca8843d39a1a8cdb9e8a179b) is 6.0M, max 48.5M, 42.4M free. Oct 28 12:56:23.680487 systemd[1]: Started Journal Service. Oct 28 12:56:23.680853 systemd[1]: Finished Apply Kernel Variables. Oct 28 12:56:23.681816 systemd[1]: Finished Setup Virtual Console. Oct 28 12:56:23.683733 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 28 12:56:23.685189 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 28 12:56:23.688237 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 28 12:56:23.699240 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 28 12:56:23.700989 systemd[1]: Starting dracut cmdline hook... Oct 28 12:56:23.709551 dracut-cmdline[289]: dracut-dracut-053 Oct 28 12:56:23.711795 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 28 12:56:23.768406 kernel: iscsi: registered transport (tcp) Oct 28 12:56:23.779560 kernel: iscsi: registered transport (qla4xxx) Oct 28 12:56:23.779576 kernel: QLogic iSCSI HBA Driver Oct 28 12:56:23.789063 systemd[1]: Finished dracut cmdline hook. Oct 28 12:56:23.790630 systemd[1]: Starting dracut pre-udev hook... Oct 28 12:56:23.791831 systemd[1]: Starting Open-iSCSI... Oct 28 12:56:23.794139 iscsid[375]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 28 12:56:23.794139 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 28 12:56:23.794139 iscsid[375]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 28 12:56:23.794139 iscsid[375]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 28 12:56:23.794139 iscsid[375]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 28 12:56:23.804594 iscsid[375]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 28 12:56:23.798455 systemd[1]: Started Open-iSCSI. Oct 28 12:56:23.808114 kernel: device-mapper: uevent: version 1.0.3 Oct 28 12:56:23.808140 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 28 12:56:23.842393 kernel: raid6: neonx8 gen() 13705 MB/s Oct 28 12:56:23.859386 kernel: raid6: neonx8 xor() 10727 MB/s Oct 28 12:56:23.876383 kernel: raid6: neonx4 gen() 13576 MB/s Oct 28 12:56:23.893385 kernel: raid6: neonx4 xor() 11551 MB/s Oct 28 12:56:23.910387 kernel: raid6: neonx2 gen() 12950 MB/s Oct 28 12:56:23.927385 kernel: raid6: neonx2 xor() 9858 MB/s Oct 28 12:56:23.944384 kernel: raid6: neonx1 gen() 10894 MB/s Oct 28 12:56:23.961384 kernel: raid6: neonx1 xor() 8822 MB/s Oct 28 12:56:23.978389 kernel: raid6: int64x8 gen() 6812 MB/s Oct 28 12:56:23.995386 kernel: raid6: int64x8 xor() 3791 MB/s Oct 28 12:56:24.012386 kernel: raid6: int64x4 gen() 6903 MB/s Oct 28 12:56:24.029396 kernel: raid6: int64x4 xor() 3826 MB/s Oct 28 12:56:24.046392 kernel: raid6: int64x2 gen() 6178 MB/s Oct 28 12:56:24.063395 kernel: raid6: int64x2 xor() 3312 MB/s Oct 28 12:56:24.080391 kernel: raid6: int64x1 gen() 5008 MB/s Oct 28 12:56:24.097524 kernel: raid6: int64x1 xor() 2597 MB/s Oct 28 12:56:24.097545 kernel: raid6: using algorithm neonx8 gen() 13705 MB/s Oct 28 12:56:24.097560 kernel: raid6: .... xor() 10727 MB/s, rmw enabled Oct 28 12:56:24.098654 kernel: raid6: using neon recovery algorithm Oct 28 12:56:24.108382 kernel: xor: measuring software checksum speed Oct 28 12:56:24.109384 kernel: 8regs : 17148 MB/sec Oct 28 12:56:24.110871 kernel: 32regs : 20755 MB/sec Oct 28 12:56:24.110892 kernel: arm64_neon : 28895 MB/sec Oct 28 12:56:24.110906 kernel: xor: using function: arm64_neon (28895 MB/sec) Oct 28 12:56:24.155389 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 28 12:56:24.165428 systemd[1]: Finished dracut pre-udev hook. Oct 28 12:56:24.166000 audit: BPF prog-id=6 op=LOAD Oct 28 12:56:24.167000 audit: BPF prog-id=7 op=LOAD Oct 28 12:56:24.168434 kernel: audit: type=1334 audit(1761656184.166:2): prog-id=6 op=LOAD Oct 28 12:56:24.168457 kernel: audit: type=1334 audit(1761656184.167:3): prog-id=7 op=LOAD Oct 28 12:56:24.168469 kernel: audit: type=1334 audit(1761656184.168:4): prog-id=8 op=LOAD Oct 28 12:56:24.168000 audit: BPF prog-id=8 op=LOAD Oct 28 12:56:24.168805 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 28 12:56:24.180600 systemd-udevd[407]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 28 12:56:24.182283 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 28 12:56:24.185431 systemd[1]: Starting dracut pre-trigger hook... Oct 28 12:56:24.195948 dracut-pre-trigger[418]: rd.md=0: removing MD RAID activation Oct 28 12:56:24.216375 systemd[1]: Finished dracut pre-trigger hook. Oct 28 12:56:24.217728 systemd[1]: Starting Coldplug All udev Devices... Oct 28 12:56:24.226763 systemd-udevd[407]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 28 12:56:24.245049 systemd[1]: Finished Coldplug All udev Devices. Oct 28 12:56:24.246500 systemd[1]: Starting dracut initqueue hook... Oct 28 12:56:24.261909 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 28 12:56:24.262046 kernel: vda: detected capacity change from 0 to 4756340736 Oct 28 12:56:24.275455 systemd-udevd[465]: Using default interface naming scheme 'v249'. Oct 28 12:56:24.279378 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 12:56:24.310200 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 28 12:56:24.311458 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 28 12:56:24.314503 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (460) Oct 28 12:56:24.315080 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 28 12:56:24.317532 systemd[1]: Reached target Initrd Root Device. Oct 28 12:56:24.318946 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 28 12:56:24.321693 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 28 12:56:24.321800 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 28 12:56:24.327037 systemd[1]: Finished dracut initqueue hook. Oct 28 12:56:24.327908 systemd[1]: Reached target Preparation for Local File Systems. Oct 28 12:56:24.329397 systemd[1]: Reached target Preparation for Remote File Systems. Oct 28 12:56:24.331194 systemd[1]: Reached target Remote Encrypted Volumes. Oct 28 12:56:24.332932 systemd[1]: Reached target Remote File Systems. Oct 28 12:56:24.334977 systemd[1]: Starting dracut pre-mount hook... Oct 28 12:56:24.336312 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 28 12:56:24.342680 systemd[1]: Finished dracut pre-mount hook. Oct 28 12:56:24.344140 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 28 12:56:24.350429 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 28 12:56:24.352970 systemd-fsck[502]: ROOT: clean, 640/553792 files, 39961/553472 blocks Oct 28 12:56:24.356038 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 28 12:56:24.357790 systemd[1]: Mounting /sysroot... Oct 28 12:56:24.369980 systemd[1]: Found device /dev/mapper/usr. Oct 28 12:56:24.371394 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 28 12:56:24.372401 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 28 12:56:24.380608 systemd-fsck[515]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 28 12:56:24.380608 systemd-fsck[515]: You must have r/w access to the filesystem or be root Oct 28 12:56:24.381150 systemd-fsck[512]: fsck failed with exit status 8. Oct 28 12:56:24.381153 systemd-fsck[512]: Ignoring error. Oct 28 12:56:24.384571 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 28 12:56:24.386401 systemd[1]: Mounting /sysusr/usr... Oct 28 12:56:24.409002 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 28 12:56:24.409039 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 28 12:56:24.409457 systemd[1]: Mounted /sysroot. Oct 28 12:56:24.410185 systemd[1]: Reached target Initrd Root File System. Oct 28 12:56:24.412001 systemd[1]: Starting Reload Configuration from the Real Root... Oct 28 12:56:24.416393 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 28 12:56:24.416666 systemd[1]: Mounted /sysusr/usr. Oct 28 12:56:24.417429 systemd[1]: Reloading. Oct 28 12:56:24.425000 audit: BPF prog-id=6 op=UNLOAD Oct 28 12:56:24.426000 audit: BPF prog-id=3 op=UNLOAD Oct 28 12:56:24.427828 kernel: audit: type=1334 audit(1761656184.425:5): prog-id=6 op=UNLOAD Oct 28 12:56:24.427855 kernel: audit: type=1334 audit(1761656184.426:6): prog-id=3 op=UNLOAD Oct 28 12:56:24.502000 audit: BPF prog-id=9 op=LOAD Oct 28 12:56:24.503000 audit: BPF prog-id=10 op=LOAD Oct 28 12:56:24.504548 kernel: audit: type=1334 audit(1761656184.502:7): prog-id=9 op=LOAD Oct 28 12:56:24.504571 kernel: audit: type=1334 audit(1761656184.503:8): prog-id=10 op=LOAD Oct 28 12:56:24.504580 kernel: audit: type=1334 audit(1761656184.504:9): prog-id=11 op=LOAD Oct 28 12:56:24.504000 audit: BPF prog-id=11 op=LOAD Oct 28 12:56:24.505370 kernel: audit: type=1334 audit(1761656184.504:10): prog-id=7 op=UNLOAD Oct 28 12:56:24.504000 audit: BPF prog-id=7 op=UNLOAD Oct 28 12:56:24.504000 audit: BPF prog-id=8 op=UNLOAD Oct 28 12:56:24.504000 audit: BPF prog-id=12 op=LOAD Oct 28 12:56:24.505000 audit: BPF prog-id=13 op=LOAD Oct 28 12:56:24.505000 audit: BPF prog-id=14 op=LOAD Oct 28 12:56:24.505000 audit: BPF prog-id=4 op=UNLOAD Oct 28 12:56:24.505000 audit: BPF prog-id=5 op=UNLOAD Oct 28 12:56:24.508224 systemd[1]: Reached target Local File Systems. Oct 28 12:56:24.509128 systemd[1]: Reached target System Initialization. Oct 28 12:56:24.510597 systemd[1]: Reached target Basic System. Oct 28 12:56:24.512864 systemd[1]: Mounting /sysroot/usr... Oct 28 12:56:24.515321 systemd[1]: Mounted /sysroot/usr. Oct 28 12:56:24.523758 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 28 12:56:24.523874 systemd[1]: Finished Reload Configuration from the Real Root. Oct 28 12:56:24.525823 systemd[1]: Reached target Initrd File Systems. Oct 28 12:56:24.527270 systemd[1]: Reached target Initrd Default Target. Oct 28 12:56:24.528804 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 28 12:56:24.529436 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 28 12:56:24.538970 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 28 12:56:24.540565 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 28 12:56:24.545862 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 28 12:56:24.546898 systemd[1]: Stopped target Timer Units. Oct 28 12:56:24.548307 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 28 12:56:24.548474 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 28 12:56:24.549835 systemd[1]: Stopped target Initrd Default Target. Oct 28 12:56:24.551417 systemd[1]: Stopped target Basic System. Oct 28 12:56:24.553006 systemd[1]: Stopped target Initrd Root Device. Oct 28 12:56:24.554482 systemd[1]: Stopped target Path Units. Oct 28 12:56:24.555886 systemd[1]: Stopped target Remote File Systems. Oct 28 12:56:24.557438 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 28 12:56:24.559007 systemd[1]: Stopped target Slice Units. Oct 28 12:56:24.560313 systemd[1]: Stopped target Socket Units. Oct 28 12:56:24.561934 systemd[1]: Stopped target System Initialization. Oct 28 12:56:24.563282 systemd[1]: Stopped target Local File Systems. Oct 28 12:56:24.564659 systemd[1]: Stopped target Preparation for Local File Systems. Oct 28 12:56:24.566277 systemd[1]: Stopped target Swaps. Oct 28 12:56:24.567554 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 28 12:56:24.567667 systemd[1]: Stopped dracut pre-mount hook. Oct 28 12:56:24.569148 systemd[1]: Stopped target Local Encrypted Volumes. Oct 28 12:56:24.570382 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 28 12:56:24.574398 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 28 12:56:24.575534 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 28 12:56:24.575639 systemd[1]: Stopped dracut initqueue hook. Oct 28 12:56:24.579153 iscsid[375]: iscsid shutting down. Oct 28 12:56:24.577303 systemd[1]: Stopping Open-iSCSI... Oct 28 12:56:24.578531 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 28 12:56:24.578656 systemd[1]: Stopped Apply Kernel Variables. Oct 28 12:56:24.579986 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 28 12:56:24.580083 systemd[1]: Stopped Coldplug All udev Devices. Oct 28 12:56:24.581260 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 28 12:56:24.581345 systemd[1]: Stopped dracut pre-trigger hook. Oct 28 12:56:24.582660 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 28 12:56:24.588987 systemd[1]: iscsid.service: Deactivated successfully. Oct 28 12:56:24.589103 systemd[1]: Stopped Open-iSCSI. Oct 28 12:56:24.591194 systemd[1]: iscsid.socket: Deactivated successfully. Oct 28 12:56:24.591239 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 28 12:56:24.594226 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 28 12:56:24.595545 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 28 12:56:24.595652 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 28 12:56:24.597090 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 28 12:56:24.597206 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 28 12:56:24.598978 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 28 12:56:24.605000 audit: BPF prog-id=9 op=UNLOAD Oct 28 12:56:24.599087 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 28 12:56:24.601055 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 28 12:56:24.601116 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 28 12:56:24.602047 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 28 12:56:24.602093 systemd[1]: Closed udev Control Socket. Oct 28 12:56:24.603607 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 28 12:56:24.603640 systemd[1]: Closed udev Kernel Socket. Oct 28 12:56:24.605153 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 28 12:56:24.605196 systemd[1]: Stopped dracut pre-udev hook. Oct 28 12:56:24.606851 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 28 12:56:24.606890 systemd[1]: Stopped dracut cmdline hook. Oct 28 12:56:24.608394 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 28 12:56:24.608434 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 28 12:56:24.610962 systemd[1]: Starting Cleanup udev Database... Oct 28 12:56:24.611844 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 28 12:56:24.613517 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 28 12:56:24.613576 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 28 12:56:24.615042 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 28 12:56:24.615096 systemd[1]: Stopped Create List of Static Device Nodes. Oct 28 12:56:24.631000 audit: BPF prog-id=12 op=UNLOAD Oct 28 12:56:24.616866 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 12:56:24.616907 systemd[1]: Stopped Setup Virtual Console. Oct 28 12:56:24.618408 systemd[1]: rngd.service: Deactivated successfully. Oct 28 12:56:24.618520 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 28 12:56:24.620331 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 28 12:56:24.620502 systemd[1]: Finished Cleanup udev Database. Oct 28 12:56:24.621796 systemd[1]: Reached target Switch Root. Oct 28 12:56:24.623821 systemd[1]: Starting Switch Root... Oct 28 12:56:24.630706 systemd[1]: Switching root. Oct 28 12:56:24.647638 systemd-journald[270]: Journal stopped Oct 28 12:56:26.701514 systemd-journald[270]: Received SIGTERM from PID 1 (systemd). Oct 28 12:56:26.701566 kernel: SELinux: policy capability network_peer_controls=1 Oct 28 12:56:26.701577 kernel: SELinux: policy capability open_perms=1 Oct 28 12:56:26.701588 kernel: SELinux: policy capability extended_socket_class=1 Oct 28 12:56:26.701599 kernel: SELinux: policy capability always_check_network=0 Oct 28 12:56:26.701610 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 28 12:56:26.701619 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 28 12:56:26.701628 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 28 12:56:26.701638 systemd[1]: Successfully loaded SELinux policy in 30.595ms. Oct 28 12:56:26.701654 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.103ms. Oct 28 12:56:26.701666 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 28 12:56:26.701677 systemd[1]: Detected virtualization kvm. Oct 28 12:56:26.701687 systemd[1]: Detected architecture arm64. Oct 28 12:56:26.701698 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 28 12:56:26.701709 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 28 12:56:26.701720 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 28 12:56:26.701729 systemd[1]: Stopped Switch Root. Oct 28 12:56:26.701739 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 28 12:56:26.701751 systemd[1]: Created slice Slice /system/addon-config. Oct 28 12:56:26.701761 systemd[1]: Created slice Slice /system/addon-run. Oct 28 12:56:26.701770 systemd[1]: Created slice Slice /system/getty. Oct 28 12:56:26.701780 systemd[1]: Created slice Slice /system/modprobe. Oct 28 12:56:26.701790 systemd[1]: Created slice Slice /system/serial-getty. Oct 28 12:56:26.701800 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 28 12:56:26.701811 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 28 12:56:26.701821 systemd[1]: Created slice User and Session Slice. Oct 28 12:56:26.701831 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 28 12:56:26.701843 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 28 12:56:26.701853 systemd[1]: Set up automount Boot partition Automount Point. Oct 28 12:56:26.701864 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 28 12:56:26.701873 systemd[1]: Stopped target Switch Root. Oct 28 12:56:26.701883 systemd[1]: Stopped target Initrd File Systems. Oct 28 12:56:26.701893 systemd[1]: Stopped target Initrd Root File System. Oct 28 12:56:26.701906 systemd[1]: Reached target Remote Encrypted Volumes. Oct 28 12:56:26.701917 systemd[1]: Reached target Remote File Systems. Oct 28 12:56:26.701927 systemd[1]: Reached target Slice Units. Oct 28 12:56:26.701938 systemd[1]: Reached target Swaps. Oct 28 12:56:26.701948 systemd[1]: Reached target Verify torcx succeeded. Oct 28 12:56:26.701964 systemd[1]: Reached target Local Verity Protected Volumes. Oct 28 12:56:26.701974 systemd[1]: Listening on Process Core Dump Socket. Oct 28 12:56:26.701983 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 28 12:56:26.701993 systemd[1]: Listening on Network Service Netlink Socket. Oct 28 12:56:26.702004 systemd[1]: Listening on udev Control Socket. Oct 28 12:56:26.702014 systemd[1]: Listening on udev Kernel Socket. Oct 28 12:56:26.702025 systemd[1]: Mounting Huge Pages File System... Oct 28 12:56:26.702035 systemd[1]: Mounting POSIX Message Queue File System... Oct 28 12:56:26.702047 systemd[1]: Mounting External Media Directory... Oct 28 12:56:26.702063 systemd[1]: Mounting Kernel Debug File System... Oct 28 12:56:26.702073 systemd[1]: Mounting Kernel Trace File System... Oct 28 12:56:26.702083 systemd[1]: Mounting Temporary Directory /tmp... Oct 28 12:56:26.702093 systemd[1]: Starting Create missing system files... Oct 28 12:56:26.702103 systemd[1]: Starting Create List of Static Device Nodes... Oct 28 12:56:26.702113 systemd[1]: Starting Load Kernel Module configfs... Oct 28 12:56:26.702123 systemd[1]: Starting Load Kernel Module drm... Oct 28 12:56:26.702132 systemd[1]: Starting Load Kernel Module fuse... Oct 28 12:56:26.702143 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 28 12:56:26.702154 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 28 12:56:26.702163 systemd[1]: Stopped File System Check on Root Device. Oct 28 12:56:26.702173 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 28 12:56:26.702183 systemd[1]: Stopped systemd-fsck-usr.service. Oct 28 12:56:26.702192 kernel: fuse: init (API version 7.32) Oct 28 12:56:26.702202 systemd[1]: Stopped Journal Service. Oct 28 12:56:26.702212 systemd[1]: Starting Journal Service... Oct 28 12:56:26.702222 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 28 12:56:26.702233 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 28 12:56:26.702244 systemd[1]: Starting Apply Kernel Variables... Oct 28 12:56:26.702254 systemd[1]: Starting Coldplug All udev Devices... Oct 28 12:56:26.702264 systemd[1]: verity-setup.service: Deactivated successfully. Oct 28 12:56:26.702274 systemd[1]: Stopped verity-setup.service. Oct 28 12:56:26.702285 systemd-journald[692]: Journal started Oct 28 12:56:26.702322 systemd-journald[692]: Runtime Journal (/run/log/journal/37ecb6ddca8843d39a1a8cdb9e8a179b) is 6.0M, max 48.5M, 42.4M free. Oct 28 12:56:24.720000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 28 12:56:24.746000 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 28 12:56:24.746000 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 28 12:56:24.746000 audit: BPF prog-id=15 op=LOAD Oct 28 12:56:24.746000 audit: BPF prog-id=15 op=UNLOAD Oct 28 12:56:24.746000 audit: BPF prog-id=16 op=LOAD Oct 28 12:56:24.746000 audit: BPF prog-id=16 op=UNLOAD Oct 28 12:56:24.781000 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 28 12:56:26.567000 audit: BPF prog-id=17 op=LOAD Oct 28 12:56:26.567000 audit: BPF prog-id=18 op=LOAD Oct 28 12:56:26.567000 audit: BPF prog-id=19 op=LOAD Oct 28 12:56:26.567000 audit: BPF prog-id=13 op=UNLOAD Oct 28 12:56:26.567000 audit: BPF prog-id=14 op=UNLOAD Oct 28 12:56:26.577000 audit: BPF prog-id=20 op=LOAD Oct 28 12:56:26.577000 audit: BPF prog-id=17 op=UNLOAD Oct 28 12:56:26.577000 audit: BPF prog-id=21 op=LOAD Oct 28 12:56:26.577000 audit: BPF prog-id=22 op=LOAD Oct 28 12:56:26.577000 audit: BPF prog-id=18 op=UNLOAD Oct 28 12:56:26.577000 audit: BPF prog-id=19 op=UNLOAD Oct 28 12:56:26.577000 audit: BPF prog-id=23 op=LOAD Oct 28 12:56:26.577000 audit: BPF prog-id=20 op=UNLOAD Oct 28 12:56:26.577000 audit: BPF prog-id=24 op=LOAD Oct 28 12:56:26.577000 audit: BPF prog-id=25 op=LOAD Oct 28 12:56:26.577000 audit: BPF prog-id=21 op=UNLOAD Oct 28 12:56:26.577000 audit: BPF prog-id=22 op=UNLOAD Oct 28 12:56:26.589000 audit: BPF prog-id=23 op=UNLOAD Oct 28 12:56:26.689000 audit: BPF prog-id=26 op=LOAD Oct 28 12:56:26.689000 audit: BPF prog-id=27 op=LOAD Oct 28 12:56:26.689000 audit: BPF prog-id=28 op=LOAD Oct 28 12:56:26.689000 audit: BPF prog-id=24 op=UNLOAD Oct 28 12:56:26.689000 audit: BPF prog-id=25 op=UNLOAD Oct 28 12:56:26.700000 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 28 12:56:26.566985 systemd[1]: Queued start job for default target Multi-User System. Oct 28 12:56:24.778859 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 28 12:56:26.579075 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 28 12:56:24.779725 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 28 12:56:24.779746 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 28 12:56:24.780083 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 28 12:56:24.780096 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 28 12:56:24.780136 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 28 12:56:24.780150 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 28 12:56:24.780528 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 28 12:56:24.780569 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 28 12:56:24.780585 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 28 12:56:24.780964 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 28 12:56:24.780996 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 28 12:56:24.781018 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 28 12:56:24.781034 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 28 12:56:24.781165 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 28 12:56:24.781183 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:24Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 28 12:56:26.478048 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:26Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 12:56:26.478323 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:26Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 12:56:26.478434 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:26Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 12:56:26.478555 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:26Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 12:56:26.478648 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:26Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 28 12:56:26.478701 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-10-28T12:56:26Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 28 12:56:26.706382 systemd[1]: Started Journal Service. Oct 28 12:56:26.706793 systemd[1]: Mounted Huge Pages File System. Oct 28 12:56:26.707773 systemd[1]: Mounted POSIX Message Queue File System. Oct 28 12:56:26.708844 systemd[1]: Mounted External Media Directory. Oct 28 12:56:26.709798 systemd[1]: Mounted Kernel Debug File System. Oct 28 12:56:26.710716 systemd[1]: Mounted Kernel Trace File System. Oct 28 12:56:26.711763 systemd[1]: Mounted Temporary Directory /tmp. Oct 28 12:56:26.712936 systemd[1]: Finished Create missing system files. Oct 28 12:56:26.714212 systemd[1]: Finished Create List of Static Device Nodes. Oct 28 12:56:26.715503 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 28 12:56:26.715680 systemd[1]: Finished Load Kernel Module configfs. Oct 28 12:56:26.716826 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 28 12:56:26.717017 systemd[1]: Finished Load Kernel Module drm. Oct 28 12:56:26.718145 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 28 12:56:26.718339 systemd[1]: Finished Load Kernel Module fuse. Oct 28 12:56:26.731617 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 28 12:56:26.732929 systemd[1]: Finished Apply Kernel Variables. Oct 28 12:56:26.735788 systemd[1]: Mounting FUSE Control File System... Oct 28 12:56:26.737572 systemd[1]: Mounting Kernel Configuration File System... Oct 28 12:56:26.738436 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 28 12:56:26.739544 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 28 12:56:26.740502 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 28 12:56:26.741632 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 28 12:56:26.742550 systemd[1]: Starting Load/Save Random Seed... Oct 28 12:56:26.745490 systemd-journald[692]: Time spent on flushing to /var/log/journal/37ecb6ddca8843d39a1a8cdb9e8a179b is 11.591ms for 666 entries. Oct 28 12:56:26.745490 systemd-journald[692]: System Journal (/var/log/journal/37ecb6ddca8843d39a1a8cdb9e8a179b) is 8.0M, max 203.0M, 195.0M free. Oct 28 12:56:26.765000 audit: BPF prog-id=29 op=LOAD Oct 28 12:56:26.765000 audit: BPF prog-id=30 op=LOAD Oct 28 12:56:26.765000 audit: BPF prog-id=31 op=LOAD Oct 28 12:56:26.765000 audit: BPF prog-id=10 op=UNLOAD Oct 28 12:56:26.765000 audit: BPF prog-id=11 op=UNLOAD Oct 28 12:56:26.743424 systemd[1]: Condition check resulted in Create System Users being skipped. Oct 28 12:56:26.744324 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 28 12:56:26.746159 systemd[1]: Mounted FUSE Control File System. Oct 28 12:56:26.747929 systemd[1]: Mounted Kernel Configuration File System. Oct 28 12:56:26.751677 systemd[1]: Finished Load/Save Random Seed. Oct 28 12:56:26.753178 systemd[1]: Condition check resulted in First Boot Complete being skipped. Oct 28 12:56:26.757223 systemd[1]: Finished Coldplug All udev Devices. Oct 28 12:56:26.761704 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 28 12:56:26.764089 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 28 12:56:26.766712 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 28 12:56:26.769912 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 28 12:56:26.791224 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 28 12:56:26.793000 audit: BPF prog-id=32 op=LOAD Oct 28 12:56:26.794225 systemd[1]: Starting Network Configuration... Oct 28 12:56:26.821669 systemd-networkd[715]: lo: Link UP Oct 28 12:56:26.821677 systemd-networkd[715]: lo: Gained carrier Oct 28 12:56:26.821964 systemd-networkd[715]: Enumeration completed Oct 28 12:56:26.822048 systemd[1]: Started Network Configuration. Oct 28 12:56:26.823278 systemd-udevd[707]: Using default interface naming scheme 'v249'. Oct 28 12:56:26.853533 udevadm[705]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 28 12:56:26.855211 systemd-networkd[715]: eth0: Link UP Oct 28 12:56:26.863405 systemd-networkd[715]: eth0: Gained carrier Oct 28 12:56:26.869469 systemd-networkd[715]: eth0: DHCPv4 address 10.0.0.4/16 via 10.0.0.1 Oct 28 12:56:26.872500 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 28 12:56:26.886279 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 28 12:56:26.893877 lvm[733]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 28 12:56:26.925293 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 28 12:56:26.926420 systemd[1]: Reached target Local Encrypted Volumes. Oct 28 12:56:26.939327 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 28 12:56:26.943123 lvm[734]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 28 12:56:26.971438 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 28 12:56:26.972498 systemd[1]: Reached target Preparation for Local File Systems. Oct 28 12:56:26.973530 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 28 12:56:26.973556 systemd[1]: Reached target Containers. Oct 28 12:56:26.985607 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 28 12:56:27.006108 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 28 12:56:27.020276 systemd[1]: Mounting /usr/share/oem... Oct 28 12:56:27.027531 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 28 12:56:27.027644 kernel: BTRFS info (device vda6): has skinny extents Oct 28 12:56:27.029812 systemd[1]: Mounted /usr/share/oem. Oct 28 12:56:27.030701 systemd[1]: Reached target Local File Systems. Oct 28 12:56:27.031643 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Oct 28 12:56:27.031985 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 28 12:56:27.032023 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 28 12:56:27.032053 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 28 12:56:27.039300 systemd[1]: Starting Create Volatile Files and Directories... Oct 28 12:56:27.046781 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 28 12:56:27.048244 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 28 12:56:27.083547 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Oct 28 12:56:27.083557 systemd-tmpfiles[757]: Skipping /boot Oct 28 12:56:27.090510 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Oct 28 12:56:27.090526 systemd-tmpfiles[757]: Skipping /boot Oct 28 12:56:27.122560 systemd[1]: Finished Create Volatile Files and Directories. Oct 28 12:56:27.134384 systemd[1]: Starting Load Security Auditing Rules... Oct 28 12:56:27.136293 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 28 12:56:27.137491 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Oct 28 12:56:27.137000 audit: BPF prog-id=33 op=LOAD Oct 28 12:56:27.138883 systemd[1]: Starting Network Name Resolution... Oct 28 12:56:27.139000 audit: BPF prog-id=34 op=LOAD Oct 28 12:56:27.141361 systemd[1]: Starting Network Time Synchronization... Oct 28 12:56:27.142271 systemd[1]: Condition check resulted in Update is Completed being skipped. Oct 28 12:56:27.143215 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 28 12:56:27.144710 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 28 12:56:27.146179 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 28 12:56:27.148973 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 28 12:56:27.160000 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 28 12:56:27.161526 augenrules[778]: No rules Oct 28 12:56:27.162457 systemd[1]: Finished Load Security Auditing Rules. Oct 28 12:56:27.178579 systemd-timesyncd[767]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 28 12:56:27.179165 systemd-resolved[761]: Positive Trust Anchors: Oct 28 12:56:27.179172 systemd-resolved[761]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 12:56:27.179199 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 28 12:56:27.179508 systemd[1]: Started Network Time Synchronization. Oct 28 12:56:27.180097 systemd-resolved[761]: Defaulting to hostname 'linux'. Oct 28 12:56:27.180651 systemd[1]: Reached target System Initialization. Oct 28 12:56:27.181662 systemd[1]: Started Watch for update engine configuration changes. Oct 28 12:56:27.182878 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 28 12:56:27.184072 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 28 12:56:27.185208 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 28 12:56:27.185243 systemd[1]: Reached target Path Units. Oct 28 12:56:27.186051 systemd[1]: Reached target System Time Set. Oct 28 12:56:27.187119 systemd[1]: Started Daily Log Rotation. Oct 28 12:56:27.188019 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 28 12:56:27.189209 systemd[1]: Reached target Timer Units. Oct 28 12:56:27.190426 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 28 12:56:27.205032 systemd[1]: Starting Docker Socket for the API... Oct 28 12:56:27.208230 systemd[1]: Listening on OpenSSH Server Socket. Oct 28 12:56:27.209507 systemd[1]: Started Network Name Resolution. Oct 28 12:56:27.210448 systemd[1]: Listening on Docker Socket for the API. Oct 28 12:56:27.211386 systemd[1]: Reached target Network. Oct 28 12:56:27.212193 systemd[1]: Reached target Host and Network Name Lookups. Oct 28 12:56:27.213230 systemd[1]: Reached target Socket Units. Oct 28 12:56:27.214038 systemd[1]: Reached target Basic System. Oct 28 12:56:27.214954 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 28 12:56:27.214987 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 28 12:56:27.215964 systemd[1]: Started D-Bus System Message Bus. Oct 28 12:56:27.219229 systemd[1]: Starting Extend Filesystems... Oct 28 12:56:27.220125 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 28 12:56:27.221066 systemd[1]: Starting Generate /run/flatcar/motd... Oct 28 12:56:27.222934 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 28 12:56:27.224955 systemd[1]: Starting Generate sshd host keys... Oct 28 12:56:27.226330 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 28 12:56:27.226376 systemd[1]: Reached target Load system-provided cloud configs. Oct 28 12:56:27.231007 extend-filesystems[788]: Found vda Oct 28 12:56:27.231007 extend-filesystems[788]: Found vda1 Oct 28 12:56:27.231007 extend-filesystems[788]: Found vda2 Oct 28 12:56:27.231007 extend-filesystems[788]: Found vda3 Oct 28 12:56:27.231007 extend-filesystems[788]: Found usr Oct 28 12:56:27.231007 extend-filesystems[788]: Found vda4 Oct 28 12:56:27.231007 extend-filesystems[788]: Found vda6 Oct 28 12:56:27.231007 extend-filesystems[788]: Found vda7 Oct 28 12:56:27.231007 extend-filesystems[788]: Found vda9 Oct 28 12:56:27.231007 extend-filesystems[788]: Checking size of /dev/vda9 Oct 28 12:56:27.251835 extend-filesystems[788]: Old size kept for /dev/vda9 Oct 28 12:56:27.231794 systemd[1]: Starting User Login Management... Oct 28 12:56:27.233005 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 28 12:56:27.233598 systemd[1]: Starting Update Engine... Oct 28 12:56:27.235126 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 28 12:56:27.235161 systemd[1]: Reached target Load user-provided cloud configs. Oct 28 12:56:27.238094 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 28 12:56:27.238304 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 28 12:56:27.242505 systemd[1]: motdgen.service: Deactivated successfully. Oct 28 12:56:27.242747 systemd[1]: Finished Generate /run/flatcar/motd. Oct 28 12:56:27.251950 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 28 12:56:27.252253 systemd[1]: Finished Extend Filesystems. Oct 28 12:56:27.270292 systemd-logind[802]: Watching system buttons on /dev/input/event0 (Power Button) Oct 28 12:56:27.270658 systemd-logind[802]: New seat seat0. Oct 28 12:56:27.272789 systemd[1]: Started User Login Management. Oct 28 12:56:27.289780 update_engine[803]: I1028 12:56:27.289538 803 main.cc:89] Flatcar Update Engine starting Oct 28 12:56:27.290418 update_engine[803]: I1028 12:56:27.290395 803 payload_state.cc:360] Current Response Signature = Oct 28 12:56:27.290418 update_engine[803]: NumURLs = 1 Oct 28 12:56:27.290418 update_engine[803]: Url0 = http://10.0.0.3:34567/packages/update.gz Oct 28 12:56:27.290418 update_engine[803]: Payload Size = 435526319 Oct 28 12:56:27.290418 update_engine[803]: Payload Sha256 Hash = MWe7Cz0hHAZPhG3RiToog9DXtPRhV9ko4O7ML90dZWg= Oct 28 12:56:27.290418 update_engine[803]: Is Delta Payload = 0 Oct 28 12:56:27.290418 update_engine[803]: Max Failure Count Per Url = 10 Oct 28 12:56:27.290418 update_engine[803]: Disable Payload Backoff = 1 Oct 28 12:56:27.290643 update_engine[803]: I1028 12:56:27.290601 803 payload_state.cc:381] Payload Attempt Number = 0 Oct 28 12:56:27.290765 update_engine[803]: I1028 12:56:27.290752 803 payload_state.cc:404] Current URL Index = 0 Oct 28 12:56:27.290972 update_engine[803]: I1028 12:56:27.290957 803 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 28 12:56:27.291436 update_engine[803]: I1028 12:56:27.291391 803 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 28 12:56:27.292485 systemd[1]: Finished Generate sshd host keys. Oct 28 12:56:27.293606 systemd[1]: Started Update Engine. Oct 28 12:56:27.293687 update_engine[803]: I1028 12:56:27.293660 803 update_check_scheduler.cc:74] Next update check in 3m28s Oct 28 12:56:27.305359 systemd[1]: Starting Generate /run/issue... Oct 28 12:56:27.309425 systemd[1]: issuegen.service: Deactivated successfully. Oct 28 12:56:27.309634 systemd[1]: Finished Generate /run/issue. Oct 28 12:56:27.311640 systemd[1]: Starting Permit User Sessions... Oct 28 12:56:27.315811 systemd[1]: Finished Permit User Sessions. Oct 28 12:56:27.317896 systemd[1]: Started Getty on tty1. Oct 28 12:56:27.319715 systemd[1]: Started Serial Getty on ttyAMA0. Oct 28 12:56:27.320720 systemd[1]: Reached target Login Prompts. Oct 28 12:56:27.321624 systemd[1]: Reached target Multi-User System. Oct 28 12:56:27.323510 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 28 12:56:27.328329 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 28 12:56:27.328557 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 28 12:56:27.329586 systemd[1]: Startup finished in 695ms (kernel) + 1.130s (initrd) + 2.641s (userspace) = 4.468s. Oct 28 12:56:28.788795 systemd-networkd[715]: eth0: Gained IPv6LL Oct 28 12:56:37.180309 systemd[1]: Created slice Slice /system/sshd. Oct 28 12:56:37.181633 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40142). Oct 28 12:56:37.220622 sshd[827]: Accepted publickey for core from 10.0.0.1 port 40142 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:37.222210 sshd[827]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:37.240818 systemd[1]: Created slice User Slice of UID 500. Oct 28 12:56:37.241698 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 28 12:56:37.243002 systemd-logind[802]: New session 1 of user core. Oct 28 12:56:37.247111 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 28 12:56:37.248172 systemd[1]: Starting User Manager for UID 500... Oct 28 12:56:37.250125 systemd[830]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:37.294663 systemd[830]: Queued start job for default target Main User Target. Oct 28 12:56:37.294794 systemd[830]: Reached target Paths. Oct 28 12:56:37.294808 systemd[830]: Reached target Sockets. Oct 28 12:56:37.294818 systemd[830]: Reached target Timers. Oct 28 12:56:37.294828 systemd[830]: Reached target Basic System. Oct 28 12:56:37.294863 systemd[830]: Reached target Main User Target. Oct 28 12:56:37.294875 systemd[830]: Startup finished in 41ms. Oct 28 12:56:37.294928 systemd[1]: Started User Manager for UID 500. Oct 28 12:56:37.302502 systemd[1]: Started Session 1 of User core. Oct 28 12:56:37.370798 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40144). Oct 28 12:56:37.414979 sshd[839]: Accepted publickey for core from 10.0.0.1 port 40144 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:37.416337 sshd[839]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:37.419037 systemd-logind[802]: New session 2 of user core. Oct 28 12:56:37.435205 systemd[1]: Started Session 2 of User core. Oct 28 12:56:37.490504 sshd[839]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:37.502218 systemd[1]: sshd@1-10.0.0.4:22-10.0.0.1:40144.service: Deactivated successfully. Oct 28 12:56:37.502771 systemd[1]: session-2.scope: Deactivated successfully. Oct 28 12:56:37.503344 systemd-logind[802]: Session 2 logged out. Waiting for processes to exit. Oct 28 12:56:37.504279 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40158). Oct 28 12:56:37.504923 systemd-logind[802]: Removed session 2. Oct 28 12:56:37.540070 sshd[845]: Accepted publickey for core from 10.0.0.1 port 40158 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:37.541401 sshd[845]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:37.543963 systemd-logind[802]: New session 3 of user core. Oct 28 12:56:37.559500 systemd[1]: Started Session 3 of User core. Oct 28 12:56:37.609921 sshd[845]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:37.623006 systemd[1]: sshd@2-10.0.0.4:22-10.0.0.1:40158.service: Deactivated successfully. Oct 28 12:56:37.623672 systemd[1]: session-3.scope: Deactivated successfully. Oct 28 12:56:37.624308 systemd-logind[802]: Session 3 logged out. Waiting for processes to exit. Oct 28 12:56:37.625525 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40172). Oct 28 12:56:37.626239 systemd-logind[802]: Removed session 3. Oct 28 12:56:37.659318 sshd[851]: Accepted publickey for core from 10.0.0.1 port 40172 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:37.660306 sshd[851]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:37.663181 systemd-logind[802]: New session 4 of user core. Oct 28 12:56:37.671530 systemd[1]: Started Session 4 of User core. Oct 28 12:56:37.723819 sshd[851]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:37.734759 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40188). Oct 28 12:56:37.735156 systemd[1]: sshd@3-10.0.0.4:22-10.0.0.1:40172.service: Deactivated successfully. Oct 28 12:56:37.735783 systemd[1]: session-4.scope: Deactivated successfully. Oct 28 12:56:37.736313 systemd-logind[802]: Session 4 logged out. Waiting for processes to exit. Oct 28 12:56:37.736982 systemd-logind[802]: Removed session 4. Oct 28 12:56:37.768661 sshd[856]: Accepted publickey for core from 10.0.0.1 port 40188 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:37.769859 sshd[856]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:37.772233 systemd-logind[802]: New session 5 of user core. Oct 28 12:56:37.780552 systemd[1]: Started Session 5 of User core. Oct 28 12:56:37.840493 sudo[861]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 28 12:56:37.840697 sudo[861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 12:56:37.849916 sudo[861]: pam_unix(sudo:session): session closed for user root Oct 28 12:56:37.851721 sshd[856]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:37.866771 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40202). Oct 28 12:56:37.870851 dbus-daemon[787]: [system] Reloaded configuration Oct 28 12:56:37.873371 systemd[1]: sshd@4-10.0.0.4:22-10.0.0.1:40188.service: Deactivated successfully. Oct 28 12:56:37.873953 systemd[1]: session-5.scope: Deactivated successfully. Oct 28 12:56:37.874573 systemd-logind[802]: Session 5 logged out. Waiting for processes to exit. Oct 28 12:56:37.875405 systemd-logind[802]: Removed session 5. Oct 28 12:56:37.902459 sshd[864]: Accepted publickey for core from 10.0.0.1 port 40202 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:37.903499 sshd[864]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:37.906204 systemd-logind[802]: New session 6 of user core. Oct 28 12:56:37.915565 systemd[1]: Started Session 6 of User core. Oct 28 12:56:37.967351 sudo[869]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 28 12:56:37.967636 sudo[869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 12:56:37.970839 sudo[869]: pam_unix(sudo:session): session closed for user root Oct 28 12:56:37.975085 sudo[868]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 28 12:56:37.975304 sudo[868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 12:56:37.996412 systemd[1]: Stopping Load Security Auditing Rules... Oct 28 12:56:37.997000 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 28 12:56:37.998484 auditctl[872]: No rules Oct 28 12:56:38.000081 kernel: kauditd_printk_skb: 49 callbacks suppressed Oct 28 12:56:38.000112 kernel: audit: type=1305 audit(1761656197.997:60): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 28 12:56:38.000320 systemd[1]: audit-rules.service: Deactivated successfully. Oct 28 12:56:38.000572 systemd[1]: Stopped Load Security Auditing Rules. Oct 28 12:56:38.002029 systemd[1]: Starting Load Security Auditing Rules... Oct 28 12:56:38.016084 augenrules[889]: No rules Oct 28 12:56:38.016702 systemd[1]: Finished Load Security Auditing Rules. Oct 28 12:56:38.017489 sudo[868]: pam_unix(sudo:session): session closed for user root Oct 28 12:56:38.018942 sshd[864]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:38.021565 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40210). Oct 28 12:56:38.022009 systemd[1]: sshd@5-10.0.0.4:22-10.0.0.1:40202.service: Deactivated successfully. Oct 28 12:56:38.022658 systemd[1]: session-6.scope: Deactivated successfully. Oct 28 12:56:38.023205 systemd-logind[802]: Session 6 logged out. Waiting for processes to exit. Oct 28 12:56:38.023823 systemd-logind[802]: Removed session 6. Oct 28 12:56:38.057668 sshd[894]: Accepted publickey for core from 10.0.0.1 port 40210 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:38.058794 sshd[894]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:38.061393 kernel: audit: type=1006 audit(1761656198.057:61): pid=894 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 28 12:56:38.063055 systemd-logind[802]: New session 7 of user core. Oct 28 12:56:38.070548 systemd[1]: Started Session 7 of User core. Oct 28 12:56:38.119040 sshd[894]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:38.126798 systemd[1]: sshd@6-10.0.0.4:22-10.0.0.1:40210.service: Deactivated successfully. Oct 28 12:56:38.127290 systemd[1]: session-7.scope: Deactivated successfully. Oct 28 12:56:38.127808 systemd-logind[802]: Session 7 logged out. Waiting for processes to exit. Oct 28 12:56:38.128712 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40222). Oct 28 12:56:38.129239 systemd-logind[802]: Removed session 7. Oct 28 12:56:38.164503 sshd[901]: Accepted publickey for core from 10.0.0.1 port 40222 ssh2: RSA SHA256:Q4zBqkjOftf5oY6yQU4ppzkAfKbQDSJ2xKxH6NvVZOI Oct 28 12:56:38.165626 sshd[901]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:56:38.168393 kernel: audit: type=1006 audit(1761656198.163:62): pid=901 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 28 12:56:38.169958 systemd-logind[802]: New session 8 of user core. Oct 28 12:56:38.189580 systemd[1]: Started Session 8 of User core. Oct 28 12:56:38.241509 sshd[901]: pam_unix(sshd:session): session closed for user core Oct 28 12:56:38.244439 systemd[1]: sshd@7-10.0.0.4:22-10.0.0.1:40222.service: Deactivated successfully. Oct 28 12:56:38.245034 systemd[1]: session-8.scope: Deactivated successfully. Oct 28 12:56:38.245657 systemd-logind[802]: Session 8 logged out. Waiting for processes to exit. Oct 28 12:56:38.246355 systemd-logind[802]: Removed session 8.