Oct 28 04:50:27.685352 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 28 04:50:27.685369 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 04:50:27.685387 kernel: efi: EFI v2.70 by EDK II Oct 28 04:50:27.685392 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 28 04:50:27.685397 kernel: efi: seeding entropy pool Oct 28 04:50:27.685402 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 28 04:50:27.685409 kernel: ACPI: Early table checksum verification disabled Oct 28 04:50:27.685413 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 28 04:50:27.685418 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 28 04:50:27.685423 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:27.685430 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:27.685435 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:27.685440 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:27.685445 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:27.685451 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:27.685457 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:27.685556 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:27.685564 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:27.685569 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 28 04:50:27.685575 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 28 04:50:27.685580 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 04:50:27.685586 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 28 04:50:27.685591 kernel: Zone ranges: Oct 28 04:50:27.685596 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 04:50:27.685602 kernel: DMA32 empty Oct 28 04:50:27.685607 kernel: Normal empty Oct 28 04:50:27.685612 kernel: Movable zone start for each node Oct 28 04:50:27.685620 kernel: Early memory node ranges Oct 28 04:50:27.685626 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 28 04:50:27.685631 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 28 04:50:27.685636 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 28 04:50:27.685642 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 28 04:50:27.685647 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 28 04:50:27.685652 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 28 04:50:27.685657 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 28 04:50:27.685663 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 28 04:50:27.685668 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 28 04:50:27.685673 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 28 04:50:27.685678 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 28 04:50:27.685684 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 04:50:27.685692 kernel: On node 0 totalpages: 643072 Oct 28 04:50:27.685698 kernel: DMA zone: 10048 pages used for memmap Oct 28 04:50:27.685703 kernel: DMA zone: 0 pages reserved Oct 28 04:50:27.685708 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 28 04:50:27.685714 kernel: psci: probing for conduit method from ACPI. Oct 28 04:50:27.685719 kernel: psci: PSCIv1.1 detected in firmware. Oct 28 04:50:27.685732 kernel: psci: Using standard PSCI v0.2 function IDs Oct 28 04:50:27.685738 kernel: psci: Trusted OS migration not required Oct 28 04:50:27.685745 kernel: psci: SMC Calling Convention v1.1 Oct 28 04:50:27.685751 kernel: ACPI: SRAT not present Oct 28 04:50:27.685757 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 28 04:50:27.685762 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 28 04:50:27.685768 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 28 04:50:27.685774 kernel: Detected PIPT I-cache on CPU0 Oct 28 04:50:27.685779 kernel: CPU features: detected: GIC system register CPU interface Oct 28 04:50:27.685785 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 28 04:50:27.685791 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 28 04:50:27.685797 kernel: CPU features: detected: Hardware dirty bit management Oct 28 04:50:27.685802 kernel: CPU features: detected: Spectre-v4 Oct 28 04:50:27.685809 kernel: CPU features: detected: ARM erratum 1418040 Oct 28 04:50:27.685815 kernel: CPU features: detected: Spectre-BHB Oct 28 04:50:27.685820 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 28 04:50:27.685826 kernel: Policy zone: DMA Oct 28 04:50:27.685833 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 04:50:27.685839 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 28 04:50:27.685845 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 28 04:50:27.685850 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 28 04:50:27.685856 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 28 04:50:27.685862 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 28 04:50:27.685868 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 28 04:50:27.685875 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 28 04:50:27.685881 kernel: rcu: RCU event tracing is enabled. Oct 28 04:50:27.685887 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 28 04:50:27.685893 kernel: Trampoline variant of Tasks RCU enabled. Oct 28 04:50:27.685900 kernel: Tracing variant of Tasks RCU enabled. Oct 28 04:50:27.685906 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 28 04:50:27.685912 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 28 04:50:27.685917 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 28 04:50:27.685923 kernel: GICv3: 256 SPIs implemented Oct 28 04:50:27.685928 kernel: GICv3: 0 Extended SPIs implemented Oct 28 04:50:27.685934 kernel: GICv3: Distributor has no Range Selector support Oct 28 04:50:27.685939 kernel: GICv3: 16 PPIs implemented Oct 28 04:50:27.685946 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 28 04:50:27.685952 kernel: ACPI: SRAT not present Oct 28 04:50:27.685957 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 28 04:50:27.685963 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 28 04:50:27.685969 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 28 04:50:27.685975 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 28 04:50:27.686068 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 28 04:50:27.686079 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 04:50:27.686085 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 28 04:50:27.686091 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 28 04:50:27.686097 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 28 04:50:27.686103 kernel: arm-pv: using stolen time PV Oct 28 04:50:27.686112 kernel: Console: colour dummy device 80x25 Oct 28 04:50:27.686117 kernel: ACPI: Core revision 20200925 Oct 28 04:50:27.686123 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 28 04:50:27.686129 kernel: pid_max: default: 32768 minimum: 301 Oct 28 04:50:27.686135 kernel: LSM: Security Framework initializing Oct 28 04:50:27.686141 kernel: SELinux: Initializing. Oct 28 04:50:27.686147 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 04:50:27.686152 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 04:50:27.686158 kernel: rcu: Hierarchical SRCU implementation. Oct 28 04:50:27.686165 kernel: Platform MSI: ITS@0x8080000 domain created Oct 28 04:50:27.686171 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 28 04:50:27.686176 kernel: Remapping and enabling EFI services. Oct 28 04:50:27.686182 kernel: smp: Bringing up secondary CPUs ... Oct 28 04:50:27.686188 kernel: Detected PIPT I-cache on CPU1 Oct 28 04:50:27.686194 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 28 04:50:27.686200 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 28 04:50:27.686205 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 04:50:27.686211 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 28 04:50:27.686217 kernel: Detected PIPT I-cache on CPU2 Oct 28 04:50:27.686224 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 28 04:50:27.686230 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 28 04:50:27.686236 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 04:50:27.686241 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 28 04:50:27.686248 kernel: Detected PIPT I-cache on CPU3 Oct 28 04:50:27.686257 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 28 04:50:27.686264 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 28 04:50:27.686271 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 04:50:27.686277 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 28 04:50:27.686283 kernel: smp: Brought up 1 node, 4 CPUs Oct 28 04:50:27.686288 kernel: SMP: Total of 4 processors activated. Oct 28 04:50:27.686295 kernel: CPU features: detected: Privileged Access Never Oct 28 04:50:27.686300 kernel: CPU features: detected: LSE atomic instructions Oct 28 04:50:27.686306 kernel: CPU features: detected: User Access Override Oct 28 04:50:27.686313 kernel: CPU features: detected: 32-bit EL0 Support Oct 28 04:50:27.686319 kernel: CPU features: detected: Common not Private translations Oct 28 04:50:27.686325 kernel: CPU features: detected: RAS Extension Support Oct 28 04:50:27.686332 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 28 04:50:27.686338 kernel: CPU features: detected: CRC32 instructions Oct 28 04:50:27.686344 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 28 04:50:27.686350 kernel: CPU: All CPU(s) started at EL1 Oct 28 04:50:27.686356 kernel: alternatives: patching kernel code Oct 28 04:50:27.686363 kernel: devtmpfs: initialized Oct 28 04:50:27.686369 kernel: KASLR enabled Oct 28 04:50:27.686387 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 28 04:50:27.686395 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 28 04:50:27.686401 kernel: pinctrl core: initialized pinctrl subsystem Oct 28 04:50:27.686407 kernel: SMBIOS 3.0.0 present. Oct 28 04:50:27.686413 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 28 04:50:27.686419 kernel: NET: Registered protocol family 16 Oct 28 04:50:27.686425 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 28 04:50:27.686431 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 28 04:50:27.686439 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 28 04:50:27.686445 kernel: audit: initializing netlink subsys (disabled) Oct 28 04:50:27.686452 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Oct 28 04:50:27.686458 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 28 04:50:27.686464 kernel: cpuidle: using governor menu Oct 28 04:50:27.686470 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 28 04:50:27.686551 kernel: ASID allocator initialised with 32768 entries Oct 28 04:50:27.686560 kernel: ACPI: bus type PCI registered Oct 28 04:50:27.686566 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 28 04:50:27.686575 kernel: Serial: AMBA PL011 UART driver Oct 28 04:50:27.686581 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 28 04:50:27.686587 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 28 04:50:27.686593 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 28 04:50:27.686599 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 28 04:50:27.686605 kernel: cryptd: max_cpu_qlen set to 1000 Oct 28 04:50:27.686611 kernel: ACPI: Added _OSI(Module Device) Oct 28 04:50:27.686617 kernel: ACPI: Added _OSI(Processor Device) Oct 28 04:50:27.686623 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 28 04:50:27.686630 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 28 04:50:27.686636 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 28 04:50:27.686643 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 28 04:50:27.686649 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 28 04:50:27.686655 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 28 04:50:27.686661 kernel: ACPI: Interpreter enabled Oct 28 04:50:27.686667 kernel: ACPI: Using GIC for interrupt routing Oct 28 04:50:27.686673 kernel: ACPI: MCFG table detected, 1 entries Oct 28 04:50:27.686679 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 28 04:50:27.686685 kernel: printk: console [ttyAMA0] enabled Oct 28 04:50:27.686693 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 28 04:50:27.686821 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 28 04:50:27.686881 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 28 04:50:27.686934 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 28 04:50:27.687100 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 28 04:50:27.687165 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 28 04:50:27.687211 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 28 04:50:27.687224 kernel: PCI host bridge to bus 0000:00 Oct 28 04:50:27.687339 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 28 04:50:27.687427 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 28 04:50:27.687477 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 28 04:50:27.687524 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 28 04:50:27.687591 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 28 04:50:27.687654 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 28 04:50:27.687714 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 28 04:50:27.687783 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 28 04:50:27.687840 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 04:50:27.687899 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 04:50:27.687954 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 28 04:50:27.688136 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 28 04:50:27.688190 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 28 04:50:27.688244 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 28 04:50:27.688297 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 28 04:50:27.688305 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 28 04:50:27.688311 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 28 04:50:27.688317 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 28 04:50:27.688323 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 28 04:50:27.688330 kernel: vgaarb: loaded Oct 28 04:50:27.688336 kernel: Registered efivars operations Oct 28 04:50:27.688343 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 28 04:50:27.688352 kernel: VFS: Disk quotas dquot_6.6.0 Oct 28 04:50:27.688358 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 28 04:50:27.688364 kernel: pnp: PnP ACPI init Oct 28 04:50:27.688539 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 28 04:50:27.688611 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 28 04:50:27.688621 kernel: pnp: PnP ACPI: found 1 devices Oct 28 04:50:27.688627 kernel: NET: Registered protocol family 2 Oct 28 04:50:27.688634 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 28 04:50:27.688644 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 28 04:50:27.688651 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 28 04:50:27.688657 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 28 04:50:27.688664 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 28 04:50:27.688670 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 04:50:27.688676 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 04:50:27.688683 kernel: NET: Registered protocol family 1 Oct 28 04:50:27.688689 kernel: PCI: CLS 0 bytes, default 64 Oct 28 04:50:27.688695 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 28 04:50:27.688703 kernel: kvm [1]: HYP mode not available Oct 28 04:50:27.688709 kernel: Initialise system trusted keyrings Oct 28 04:50:27.688716 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 28 04:50:27.688730 kernel: Key type asymmetric registered Oct 28 04:50:27.688737 kernel: Asymmetric key parser 'x509' registered Oct 28 04:50:27.688743 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 28 04:50:27.688749 kernel: io scheduler mq-deadline registered Oct 28 04:50:27.688755 kernel: io scheduler kyber registered Oct 28 04:50:27.688763 kernel: io scheduler bfq registered Oct 28 04:50:27.688783 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 28 04:50:27.688789 kernel: ACPI: Power Button [PWRB] Oct 28 04:50:27.688795 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 28 04:50:27.688860 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 28 04:50:27.688868 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 28 04:50:27.688875 kernel: msm_serial: driver initialized Oct 28 04:50:27.688881 kernel: random: fast init done Oct 28 04:50:27.688887 kernel: random: crng init done Oct 28 04:50:27.688893 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 28 04:50:27.688899 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 28 04:50:27.688907 kernel: thunder_xcv, ver 1.0 Oct 28 04:50:27.688914 kernel: thunder_bgx, ver 1.0 Oct 28 04:50:27.688920 kernel: nicpf, ver 1.0 Oct 28 04:50:27.688926 kernel: nicvf, ver 1.0 Oct 28 04:50:27.688990 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 28 04:50:27.689044 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-28T04:50:27 UTC (1761627027) Oct 28 04:50:27.689052 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 28 04:50:27.689058 kernel: NET: Registered protocol family 10 Oct 28 04:50:27.689066 kernel: Segment Routing with IPv6 Oct 28 04:50:27.689072 kernel: NET: Registered protocol family 17 Oct 28 04:50:27.689078 kernel: Key type dns_resolver registered Oct 28 04:50:27.689084 kernel: registered taskstats version 1 Oct 28 04:50:27.689091 kernel: Loading compiled-in X.509 certificates Oct 28 04:50:27.689097 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 28 04:50:27.689103 kernel: Key type ._fscrypt registered Oct 28 04:50:27.689109 kernel: Key type .fscrypt registered Oct 28 04:50:27.689115 kernel: Key type fscrypt-provisioning registered Oct 28 04:50:27.689123 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 28 04:50:27.689129 kernel: ima: Allocated hash algorithm: sha1 Oct 28 04:50:27.689135 kernel: ima: No architecture policies found Oct 28 04:50:27.689141 kernel: Freeing unused kernel memory: 34240K Oct 28 04:50:27.689147 kernel: Run /init as init process Oct 28 04:50:27.689153 kernel: with arguments: Oct 28 04:50:27.689159 kernel: /init Oct 28 04:50:27.689165 kernel: with environment: Oct 28 04:50:27.689171 kernel: HOME=/ Oct 28 04:50:27.689176 kernel: TERM=linux Oct 28 04:50:27.689183 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 28 04:50:27.689192 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 04:50:27.689200 systemd[1]: Detected virtualization kvm. Oct 28 04:50:27.689207 systemd[1]: Detected architecture arm64. Oct 28 04:50:27.689214 systemd[1]: Running in initial RAM disk. Oct 28 04:50:27.689220 systemd[1]: No hostname configured, using default hostname. Oct 28 04:50:27.689226 systemd[1]: Hostname set to . Oct 28 04:50:27.689235 systemd[1]: Initializing machine ID from VM UUID. Oct 28 04:50:27.689242 systemd[1]: Queued start job for default target Initrd Default Target. Oct 28 04:50:27.689248 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 28 04:50:27.689255 systemd[1]: Reached target Local Encrypted Volumes. Oct 28 04:50:27.689262 systemd[1]: Reached target Path Units. Oct 28 04:50:27.689268 systemd[1]: Reached target Slice Units. Oct 28 04:50:27.689275 systemd[1]: Reached target Swaps. Oct 28 04:50:27.689281 systemd[1]: Reached target Timer Units. Oct 28 04:50:27.689289 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 28 04:50:27.689296 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 28 04:50:27.689302 systemd[1]: Listening on Journal Audit Socket. Oct 28 04:50:27.689309 systemd[1]: Listening on Journal Socket (/dev/log). Oct 28 04:50:27.689316 systemd[1]: Listening on Journal Socket. Oct 28 04:50:27.689322 systemd[1]: Listening on Network Service Netlink Socket. Oct 28 04:50:27.689329 systemd[1]: Listening on udev Control Socket. Oct 28 04:50:27.689336 systemd[1]: Listening on udev Kernel Socket. Oct 28 04:50:27.689343 systemd[1]: Reached target Socket Units. Oct 28 04:50:27.689351 systemd[1]: Starting Create List of Static Device Nodes... Oct 28 04:50:27.689357 systemd[1]: Finished Network Cleanup. Oct 28 04:50:27.689364 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 28 04:50:27.689371 systemd[1]: Starting Journal Service... Oct 28 04:50:27.689391 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 28 04:50:27.689398 systemd[1]: Starting Apply Kernel Variables... Oct 28 04:50:27.689405 systemd[1]: Starting Setup Virtual Console... Oct 28 04:50:27.689412 systemd[1]: Finished Create List of Static Device Nodes. Oct 28 04:50:27.689419 systemd[1]: Finished Apply Kernel Variables. Oct 28 04:50:27.689425 systemd[1]: Finished Setup Virtual Console. Oct 28 04:50:27.689434 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 28 04:50:27.689441 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 28 04:50:27.689450 systemd-journald[278]: Journal started Oct 28 04:50:27.689489 systemd-journald[278]: Runtime Journal (/run/log/journal/334fdacc6f6b44bb87b75a7e6890f8f7) is 6.0M, max 48.5M, 42.4M free. Oct 28 04:50:27.691642 systemd[1]: Started Journal Service. Oct 28 04:50:27.692016 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 28 04:50:27.700897 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 28 04:50:27.702661 systemd[1]: Starting dracut cmdline hook... Oct 28 04:50:27.711480 dracut-cmdline[296]: dracut-dracut-053 Oct 28 04:50:27.713683 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 04:50:27.774414 kernel: SCSI subsystem initialized Oct 28 04:50:27.777394 kernel: Loading iSCSI transport class v2.0-870. Oct 28 04:50:27.783403 kernel: iscsi: registered transport (tcp) Oct 28 04:50:27.795414 kernel: iscsi: registered transport (qla4xxx) Oct 28 04:50:27.795463 kernel: QLogic iSCSI HBA Driver Oct 28 04:50:27.805134 systemd[1]: Finished dracut cmdline hook. Oct 28 04:50:27.806858 systemd[1]: Starting dracut pre-udev hook... Oct 28 04:50:27.819399 kernel: device-mapper: uevent: version 1.0.3 Oct 28 04:50:27.821402 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 28 04:50:27.855416 kernel: raid6: neonx8 gen() 13763 MB/s Oct 28 04:50:27.872404 kernel: raid6: neonx8 xor() 10759 MB/s Oct 28 04:50:27.889403 kernel: raid6: neonx4 gen() 13574 MB/s Oct 28 04:50:27.906401 kernel: raid6: neonx4 xor() 11581 MB/s Oct 28 04:50:27.923408 kernel: raid6: neonx2 gen() 13012 MB/s Oct 28 04:50:27.940402 kernel: raid6: neonx2 xor() 9892 MB/s Oct 28 04:50:27.957404 kernel: raid6: neonx1 gen() 10728 MB/s Oct 28 04:50:27.974396 kernel: raid6: neonx1 xor() 8828 MB/s Oct 28 04:50:27.991402 kernel: raid6: int64x8 gen() 6826 MB/s Oct 28 04:50:28.008394 kernel: raid6: int64x8 xor() 3795 MB/s Oct 28 04:50:28.025391 kernel: raid6: int64x4 gen() 6915 MB/s Oct 28 04:50:28.042402 kernel: raid6: int64x4 xor() 3837 MB/s Oct 28 04:50:28.059395 kernel: raid6: int64x2 gen() 6188 MB/s Oct 28 04:50:28.076397 kernel: raid6: int64x2 xor() 3317 MB/s Oct 28 04:50:28.093391 kernel: raid6: int64x1 gen() 5003 MB/s Oct 28 04:50:28.110575 kernel: raid6: int64x1 xor() 2603 MB/s Oct 28 04:50:28.110589 kernel: raid6: using algorithm neonx8 gen() 13763 MB/s Oct 28 04:50:28.110597 kernel: raid6: .... xor() 10759 MB/s, rmw enabled Oct 28 04:50:28.111746 kernel: raid6: using neon recovery algorithm Oct 28 04:50:28.121397 kernel: xor: measuring software checksum speed Oct 28 04:50:28.122400 kernel: 8regs : 17156 MB/sec Oct 28 04:50:28.123401 kernel: 32regs : 20760 MB/sec Oct 28 04:50:28.124656 kernel: arm64_neon : 29153 MB/sec Oct 28 04:50:28.124667 kernel: xor: using function: arm64_neon (29153 MB/sec) Oct 28 04:50:28.169402 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 28 04:50:28.178681 systemd[1]: Finished dracut pre-udev hook. Oct 28 04:50:28.179000 audit: BPF prog-id=6 op=LOAD Oct 28 04:50:28.180000 audit: BPF prog-id=7 op=LOAD Oct 28 04:50:28.181691 kernel: audit: type=1334 audit(1761627028.179:2): prog-id=6 op=LOAD Oct 28 04:50:28.181711 kernel: audit: type=1334 audit(1761627028.180:3): prog-id=7 op=LOAD Oct 28 04:50:28.181725 kernel: audit: type=1334 audit(1761627028.181:4): prog-id=8 op=LOAD Oct 28 04:50:28.181000 audit: BPF prog-id=8 op=LOAD Oct 28 04:50:28.182061 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 28 04:50:28.193882 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 28 04:50:28.195541 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 28 04:50:28.198388 systemd[1]: Starting dracut pre-trigger hook... Oct 28 04:50:28.200656 systemd[1]: Starting Network Configuration... Oct 28 04:50:28.199000 audit: BPF prog-id=9 op=LOAD Oct 28 04:50:28.203677 kernel: audit: type=1334 audit(1761627028.199:5): prog-id=9 op=LOAD Oct 28 04:50:28.209083 dracut-pre-trigger[425]: rd.md=0: removing MD RAID activation Oct 28 04:50:28.217008 systemd-networkd[427]: lo: Link UP Oct 28 04:50:28.217015 systemd-networkd[427]: lo: Gained carrier Oct 28 04:50:28.217286 systemd-networkd[427]: Enumeration completed Oct 28 04:50:28.217365 systemd[1]: Started Network Configuration. Oct 28 04:50:28.222039 kernel: audit: type=1334 audit(1761627028.218:6): prog-id=10 op=LOAD Oct 28 04:50:28.218000 audit: BPF prog-id=10 op=LOAD Oct 28 04:50:28.219429 systemd[1]: Starting Network Name Resolution... Oct 28 04:50:28.233762 systemd[1]: Finished dracut pre-trigger hook. Oct 28 04:50:28.235155 systemd[1]: Starting Coldplug All udev Devices... Oct 28 04:50:28.244536 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 28 04:50:28.248285 systemd-resolved[443]: Positive Trust Anchors: Oct 28 04:50:28.248302 systemd-resolved[443]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 04:50:28.248331 systemd-resolved[443]: 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 04:50:28.248602 systemd-resolved[443]: Defaulting to hostname 'linux'. Oct 28 04:50:28.254233 systemd[1]: Started Network Name Resolution. Oct 28 04:50:28.257796 systemd[1]: Reached target Network. Oct 28 04:50:28.259061 systemd[1]: Reached target Host and Network Name Lookups. Oct 28 04:50:28.261157 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 28 04:50:28.264636 systemd[1]: Finished Coldplug All udev Devices. Oct 28 04:50:28.267064 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 28 04:50:28.269016 systemd[1]: Starting Open-iSCSI... Oct 28 04:50:28.271454 iscsid[481]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 28 04:50:28.271454 iscsid[481]: 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 04:50:28.271454 iscsid[481]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 28 04:50:28.271454 iscsid[481]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 28 04:50:28.271454 iscsid[481]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 28 04:50:28.271454 iscsid[481]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 28 04:50:28.274703 systemd[1]: Started Open-iSCSI. Oct 28 04:50:28.281348 systemd[1]: Starting dracut initqueue hook... Oct 28 04:50:28.307154 systemd-udevd[468]: Using default interface naming scheme 'v249'. Oct 28 04:50:28.313797 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 28 04:50:28.313995 kernel: vda: detected capacity change from 0 to 4756340736 Oct 28 04:50:28.314085 systemd-networkd[427]: eth0: Link UP Oct 28 04:50:28.321394 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 04:50:28.338014 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 28 04:50:28.340957 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (462) Oct 28 04:50:28.339794 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 28 04:50:28.343784 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 28 04:50:28.346642 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 28 04:50:28.353581 systemd[1]: Finished dracut initqueue hook. Oct 28 04:50:28.356450 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 28 04:50:28.357299 systemd[1]: Reached target Initrd Root Device. Oct 28 04:50:28.358749 systemd[1]: Reached target Preparation for Remote File Systems. Oct 28 04:50:28.360340 systemd[1]: Reached target Remote Encrypted Volumes. Oct 28 04:50:28.362089 systemd[1]: Reached target Remote File Systems. Oct 28 04:50:28.364222 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 28 04:50:28.365813 systemd[1]: Starting dracut pre-mount hook... Oct 28 04:50:28.374802 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 04:50:28.373534 systemd[1]: Finished dracut pre-mount hook. Oct 28 04:50:28.377428 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 04:50:28.380392 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 04:50:29.369413 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 28 04:50:29.369443 systemd-networkd[427]: eth0: Gained carrier Oct 28 04:50:29.376439 systemd-networkd[427]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 28 04:50:29.383136 disk-uuid[503]: The operation has completed successfully. Oct 28 04:50:29.384395 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 04:50:29.403834 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 28 04:50:29.403951 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 28 04:50:29.406558 systemd[1]: Starting Ignition (setup)... Oct 28 04:50:29.414398 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 28 04:50:29.414424 kernel: BTRFS info (device vda6): has skinny extents Oct 28 04:50:29.419944 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 28 04:50:29.425443 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 28 04:50:29.425547 systemd[1]: Finished Ignition (setup). Oct 28 04:50:29.427701 systemd[1]: Starting Ignition (disks)... Oct 28 04:50:29.436905 ignition[538]: Ignition v0.36.1-15-gde4e6cc9 Oct 28 04:50:29.436921 ignition[538]: Stage: disks Oct 28 04:50:29.436931 ignition[538]: reading system config file "/usr/lib/ignition/base.ign" Oct 28 04:50:29.436941 ignition[538]: no config at "/usr/lib/ignition/base.ign" Oct 28 04:50:29.436983 ignition[538]: parsed url from cmdline: "" Oct 28 04:50:29.436986 ignition[538]: no config URL provided Oct 28 04:50:29.436991 ignition[538]: reading system config file "/usr/lib/ignition/user.ign" Oct 28 04:50:29.436997 ignition[538]: no config at "/usr/lib/ignition/user.ign" Oct 28 04:50:29.437015 ignition[538]: op(1): [started] loading QEMU firmware config module Oct 28 04:50:29.437019 ignition[538]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 28 04:50:29.437778 ignition[538]: op(1): [finished] loading QEMU firmware config module Oct 28 04:50:29.450580 ignition[538]: parsing config with SHA512: 0ed90c876eb7e1e0761ed032dfae571655635a995767d5e16383647350a203303bc65712d1f01830f864f1a812832bfebd488af7a84bb208ec01257dd5818d78 Oct 28 04:50:29.452492 ignition[538]: disks: disks passed Oct 28 04:50:29.452504 ignition[538]: Ignition finished successfully Oct 28 04:50:29.453122 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 28 04:50:29.453234 systemd[1]: Finished Ignition (disks). Oct 28 04:50:29.454727 systemd[1]: Reached target Preparation for Local File Systems. Oct 28 04:50:29.456962 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 28 04:50:29.458515 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 28 04:50:29.465965 systemd-fsck[551]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 28 04:50:29.469833 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 28 04:50:29.471964 systemd[1]: Mounting /sysroot... Oct 28 04:50:29.475394 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 28 04:50:29.492417 systemd[1]: Found device /dev/mapper/usr. Oct 28 04:50:29.493837 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 28 04:50:29.494924 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 28 04:50:29.503148 systemd-fsck[567]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 28 04:50:29.503148 systemd-fsck[567]: You must have r/w access to the filesystem or be root Oct 28 04:50:29.503706 systemd-fsck[563]: fsck failed with exit status 8. Oct 28 04:50:29.503709 systemd-fsck[563]: Ignoring error. Oct 28 04:50:29.504923 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 28 04:50:29.506628 systemd[1]: Mounting /sysusr/usr... Oct 28 04:50:29.517372 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 28 04:50:29.517407 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 28 04:50:29.517882 systemd[1]: Mounted /sysroot. Oct 28 04:50:29.518586 systemd[1]: Reached target Initrd Root File System. Oct 28 04:50:29.533398 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 28 04:50:29.533497 systemd[1]: Mounted /sysusr/usr. Oct 28 04:50:29.534197 systemd[1]: Reached target Local File Systems. Oct 28 04:50:29.535428 systemd[1]: Reached target System Initialization. Oct 28 04:50:29.536853 systemd[1]: Reached target Basic System. Oct 28 04:50:29.539320 systemd[1]: Mounting /sysroot/usr... Oct 28 04:50:29.541251 systemd[1]: Mounted /sysroot/usr. Oct 28 04:50:29.542590 systemd[1]: Starting Root filesystem setup... Oct 28 04:50:29.565968 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 28 04:50:29.566086 systemd[1]: Finished Root filesystem setup. Oct 28 04:50:29.568418 systemd[1]: Starting Ignition (files)... Oct 28 04:50:29.569634 systemd[1]: Starting /sysroot/boot... Oct 28 04:50:29.570847 ignition[584]: Ignition v0.36.1-15-gde4e6cc9 Oct 28 04:50:29.570853 ignition[584]: Stage: files Oct 28 04:50:29.570862 ignition[584]: reading system config file "/usr/lib/ignition/base.ign" Oct 28 04:50:29.570871 ignition[584]: no config at "/usr/lib/ignition/base.ign" Oct 28 04:50:29.571731 ignition[584]: files: compiled without relabeling support, skipping Oct 28 04:50:29.586458 systemd[1]: Finished /sysroot/boot. Oct 28 04:50:29.588441 ignition[584]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 28 04:50:29.588453 ignition[584]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 28 04:50:29.589532 ignition[584]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 28 04:50:29.589539 ignition[584]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 28 04:50:29.590728 ignition[584]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 28 04:50:29.590746 ignition[584]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 28 04:50:29.591016 ignition[584]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 28 04:50:29.591022 ignition[584]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 28 04:50:29.596587 systemd[1]: ignition-files.service: Deactivated successfully. Oct 28 04:50:29.591109 ignition[584]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 28 04:50:29.596690 systemd[1]: Finished Ignition (files). Oct 28 04:50:29.591522 ignition[584]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 28 04:50:29.597587 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 28 04:50:29.591528 ignition[584]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 28 04:50:29.611067 kernel: audit: type=1334 audit(1761627029.607:7): prog-id=9 op=UNLOAD Oct 28 04:50:29.611089 kernel: audit: type=1334 audit(1761627029.607:8): prog-id=3 op=UNLOAD Oct 28 04:50:29.611097 kernel: audit: type=1334 audit(1761627029.607:9): prog-id=6 op=UNLOAD Oct 28 04:50:29.611106 kernel: audit: type=1334 audit(1761627029.609:10): prog-id=10 op=UNLOAD Oct 28 04:50:29.607000 audit: BPF prog-id=9 op=UNLOAD Oct 28 04:50:29.607000 audit: BPF prog-id=3 op=UNLOAD Oct 28 04:50:29.607000 audit: BPF prog-id=6 op=UNLOAD Oct 28 04:50:29.609000 audit: BPF prog-id=10 op=UNLOAD Oct 28 04:50:29.598192 systemd[1]: Starting Ignition (record completion)... Oct 28 04:50:29.591533 ignition[584]: files: files passed Oct 28 04:50:29.599810 systemd[1]: Starting Reload Configuration from the Real Root... Oct 28 04:50:29.591536 ignition[584]: Ignition finished successfully Oct 28 04:50:29.601303 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 28 04:50:29.601428 systemd[1]: Finished Ignition (record completion). Oct 28 04:50:29.605004 systemd[1]: Reloading. Oct 28 04:50:29.692000 audit: BPF prog-id=11 op=LOAD Oct 28 04:50:29.693000 audit: BPF prog-id=12 op=LOAD Oct 28 04:50:29.693000 audit: BPF prog-id=13 op=LOAD Oct 28 04:50:29.693000 audit: BPF prog-id=14 op=LOAD Oct 28 04:50:29.693000 audit: BPF prog-id=4 op=UNLOAD Oct 28 04:50:29.693000 audit: BPF prog-id=5 op=UNLOAD Oct 28 04:50:29.693000 audit: BPF prog-id=15 op=LOAD Oct 28 04:50:29.693000 audit: BPF prog-id=16 op=LOAD Oct 28 04:50:29.693000 audit: BPF prog-id=17 op=LOAD Oct 28 04:50:29.693000 audit: BPF prog-id=7 op=UNLOAD Oct 28 04:50:29.693000 audit: BPF prog-id=8 op=UNLOAD Oct 28 04:50:29.694000 audit: BPF prog-id=18 op=LOAD Oct 28 04:50:29.707887 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 28 04:50:29.708001 systemd[1]: Finished Reload Configuration from the Real Root. Oct 28 04:50:29.709933 systemd[1]: Reached target Initrd File Systems. Oct 28 04:50:29.711449 systemd[1]: Reached target Initrd Default Target. Oct 28 04:50:29.712940 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 28 04:50:29.713566 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 28 04:50:29.723491 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 28 04:50:29.725016 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 28 04:50:29.730777 systemd[1]: Stopped target Host and Network Name Lookups. Oct 28 04:50:29.731851 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 28 04:50:29.733465 systemd[1]: Stopped target Timer Units. Oct 28 04:50:29.734899 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 28 04:50:29.735002 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 28 04:50:29.736450 systemd[1]: Stopped target Initrd Default Target. Oct 28 04:50:29.738109 systemd[1]: Stopped target Basic System. Oct 28 04:50:29.739588 systemd[1]: Stopped target Initrd Root Device. Oct 28 04:50:29.741081 systemd[1]: Stopped target Path Units. Oct 28 04:50:29.742341 systemd[1]: Stopped target Remote File Systems. Oct 28 04:50:29.743896 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 28 04:50:29.745402 systemd[1]: Stopped target Slice Units. Oct 28 04:50:29.746782 systemd[1]: Stopped target Socket Units. Oct 28 04:50:29.748289 systemd[1]: Stopped target System Initialization. Oct 28 04:50:29.749557 systemd[1]: Stopped target Local File Systems. Oct 28 04:50:29.750924 systemd[1]: Stopped target Preparation for Local File Systems. Oct 28 04:50:29.752566 systemd[1]: Stopped target Swaps. Oct 28 04:50:29.753845 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 28 04:50:29.753944 systemd[1]: Stopped dracut pre-mount hook. Oct 28 04:50:29.755412 systemd[1]: Stopped target Local Encrypted Volumes. Oct 28 04:50:29.756701 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 28 04:50:29.760412 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 28 04:50:29.761515 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 28 04:50:29.761611 systemd[1]: Stopped dracut initqueue hook. Oct 28 04:50:29.767076 iscsid[481]: iscsid shutting down. Oct 28 04:50:29.763316 systemd[1]: Stopping Open-iSCSI... Oct 28 04:50:29.767016 systemd[1]: Stopping /sysroot/boot... Oct 28 04:50:29.767641 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 28 04:50:29.767780 systemd[1]: Stopped Coldplug All udev Devices. Oct 28 04:50:29.769057 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 28 04:50:29.769139 systemd[1]: Stopped dracut pre-trigger hook. Oct 28 04:50:29.771216 systemd[1]: iscsid.service: Deactivated successfully. Oct 28 04:50:29.771333 systemd[1]: Stopped Open-iSCSI. Oct 28 04:50:29.773138 systemd[1]: iscsid.socket: Deactivated successfully. Oct 28 04:50:29.773198 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 28 04:50:29.774814 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 28 04:50:29.777459 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 28 04:50:29.777564 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 28 04:50:29.779017 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 28 04:50:29.779124 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 28 04:50:29.780500 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 28 04:50:29.780610 systemd[1]: Stopped /sysroot/boot. Oct 28 04:50:29.782167 systemd[1]: Stopped target Network. Oct 28 04:50:29.782970 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 28 04:50:29.783026 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 28 04:50:29.784415 systemd[1]: Stopping Network Name Resolution... Oct 28 04:50:29.791504 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 28 04:50:29.791613 systemd[1]: Stopped Network Name Resolution. Oct 28 04:50:29.793227 systemd[1]: Stopping Network Configuration... Oct 28 04:50:29.797586 systemd-networkd[427]: eth0: DHCP lease lost Oct 28 04:50:29.799000 audit: BPF prog-id=18 op=UNLOAD Oct 28 04:50:29.801411 systemd-networkd[427]: eth0: DHCPv6 lease lost Oct 28 04:50:29.802318 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 28 04:50:29.802440 systemd[1]: Stopped Network Configuration. Oct 28 04:50:29.804237 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 28 04:50:29.804265 systemd[1]: Closed Network Service Netlink Socket. Oct 28 04:50:29.806040 systemd[1]: Stopping Network Cleanup... Oct 28 04:50:29.809000 audit: BPF prog-id=11 op=UNLOAD Oct 28 04:50:29.806997 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 28 04:50:29.807063 systemd[1]: Stopped Apply Kernel Variables. Oct 28 04:50:29.808559 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 28 04:50:29.813747 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 28 04:50:29.813866 systemd[1]: Stopped Network Cleanup. Oct 28 04:50:29.817608 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 28 04:50:29.817744 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 28 04:50:29.819643 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 28 04:50:29.819679 systemd[1]: Closed udev Control Socket. Oct 28 04:50:29.821195 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 28 04:50:29.821227 systemd[1]: Closed udev Kernel Socket. Oct 28 04:50:29.822770 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 28 04:50:29.822810 systemd[1]: Stopped dracut pre-udev hook. Oct 28 04:50:29.824070 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 28 04:50:29.831000 audit: BPF prog-id=15 op=UNLOAD Oct 28 04:50:29.824110 systemd[1]: Stopped dracut cmdline hook. Oct 28 04:50:29.825672 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 28 04:50:29.825722 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 28 04:50:29.827786 systemd[1]: Starting Cleanup udev Database... Oct 28 04:50:29.828814 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 28 04:50:29.830213 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 28 04:50:29.830274 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 28 04:50:29.832005 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 28 04:50:29.832047 systemd[1]: Stopped Create List of Static Device Nodes. Oct 28 04:50:29.833482 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 04:50:29.833525 systemd[1]: Stopped Setup Virtual Console. Oct 28 04:50:29.835269 systemd[1]: rngd.service: Deactivated successfully. Oct 28 04:50:29.835396 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 28 04:50:29.837010 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 28 04:50:29.847000 audit: BPF prog-id=12 op=UNLOAD Oct 28 04:50:29.837111 systemd[1]: Finished Cleanup udev Database. Oct 28 04:50:29.838289 systemd[1]: Reached target Switch Root. Oct 28 04:50:29.840315 systemd[1]: Starting Switch Root... Oct 28 04:50:29.845106 systemd[1]: Switching root. Oct 28 04:50:29.861632 systemd-journald[278]: Journal stopped Oct 28 04:50:32.062873 systemd-journald[278]: Received SIGTERM from PID 1 (systemd). Oct 28 04:50:32.062932 kernel: SELinux: policy capability network_peer_controls=1 Oct 28 04:50:32.062944 kernel: SELinux: policy capability open_perms=1 Oct 28 04:50:32.062955 kernel: SELinux: policy capability extended_socket_class=1 Oct 28 04:50:32.062964 kernel: SELinux: policy capability always_check_network=0 Oct 28 04:50:32.062973 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 28 04:50:32.062982 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 28 04:50:32.062992 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 28 04:50:32.063001 systemd[1]: Successfully loaded SELinux policy in 31.418ms. Oct 28 04:50:32.063020 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.232ms. Oct 28 04:50:32.063031 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 04:50:32.063042 systemd[1]: Detected virtualization kvm. Oct 28 04:50:32.063051 systemd[1]: Detected architecture arm64. Oct 28 04:50:32.063061 systemd[1]: Detected first boot. Oct 28 04:50:32.063070 systemd[1]: Initializing machine ID from VM UUID. Oct 28 04:50:32.063080 systemd[1]: Populated /etc with preset unit settings. Oct 28 04:50:32.063091 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 04:50:32.063103 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 28 04:50:32.063113 kernel: kauditd_printk_skb: 24 callbacks suppressed Oct 28 04:50:32.063122 kernel: audit: type=1334 audit(1761627031.927:35): prog-id=21 op=LOAD Oct 28 04:50:32.063131 kernel: audit: type=1334 audit(1761627031.928:36): prog-id=22 op=LOAD Oct 28 04:50:32.063140 kernel: audit: type=1334 audit(1761627031.928:37): prog-id=23 op=LOAD Oct 28 04:50:32.063149 kernel: audit: type=1334 audit(1761627031.928:38): prog-id=13 op=UNLOAD Oct 28 04:50:32.063158 kernel: audit: type=1334 audit(1761627031.928:39): prog-id=14 op=UNLOAD Oct 28 04:50:32.063166 kernel: audit: type=1334 audit(1761627031.941:40): prog-id=24 op=LOAD Oct 28 04:50:32.063175 kernel: audit: type=1334 audit(1761627031.941:41): prog-id=21 op=UNLOAD Oct 28 04:50:32.063185 kernel: audit: type=1334 audit(1761627031.942:42): prog-id=25 op=LOAD Oct 28 04:50:32.063194 kernel: audit: type=1334 audit(1761627031.943:43): prog-id=26 op=LOAD Oct 28 04:50:32.063203 kernel: audit: type=1334 audit(1761627031.943:44): prog-id=22 op=UNLOAD Oct 28 04:50:32.063212 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 28 04:50:32.063223 systemd[1]: Stopped Switch Root. Oct 28 04:50:32.063233 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 28 04:50:32.063243 systemd[1]: Created slice Slice /system/addon-config. Oct 28 04:50:32.063253 systemd[1]: Created slice Slice /system/addon-run. Oct 28 04:50:32.063263 systemd[1]: Created slice Slice /system/getty. Oct 28 04:50:32.063273 systemd[1]: Created slice Slice /system/modprobe. Oct 28 04:50:32.063283 systemd[1]: Created slice Slice /system/serial-getty. Oct 28 04:50:32.063292 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 28 04:50:32.063302 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 28 04:50:32.063311 systemd[1]: Created slice User and Session Slice. Oct 28 04:50:32.063320 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 28 04:50:32.063330 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 28 04:50:32.063340 systemd[1]: Set up automount Boot partition Automount Point. Oct 28 04:50:32.063351 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 28 04:50:32.063364 systemd[1]: Stopped target Switch Root. Oct 28 04:50:32.063398 systemd[1]: Stopped target Initrd File Systems. Oct 28 04:50:32.063411 systemd[1]: Stopped target Initrd Root File System. Oct 28 04:50:32.063422 systemd[1]: Reached target Remote Encrypted Volumes. Oct 28 04:50:32.063432 systemd[1]: Reached target Remote File Systems. Oct 28 04:50:32.063442 systemd[1]: Reached target Slice Units. Oct 28 04:50:32.063452 systemd[1]: Reached target Swaps. Oct 28 04:50:32.063462 systemd[1]: Reached target Verify torcx succeeded. Oct 28 04:50:32.063475 systemd[1]: Reached target Local Verity Protected Volumes. Oct 28 04:50:32.063488 systemd[1]: Listening on Process Core Dump Socket. Oct 28 04:50:32.063498 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 28 04:50:32.063508 systemd[1]: Listening on Network Service Netlink Socket. Oct 28 04:50:32.063518 systemd[1]: Listening on udev Control Socket. Oct 28 04:50:32.063528 systemd[1]: Listening on udev Kernel Socket. Oct 28 04:50:32.063537 systemd[1]: Mounting Huge Pages File System... Oct 28 04:50:32.063547 systemd[1]: Mounting POSIX Message Queue File System... Oct 28 04:50:32.063556 systemd[1]: Mounting External Media Directory... Oct 28 04:50:32.063566 systemd[1]: Mounting Kernel Debug File System... Oct 28 04:50:32.063577 systemd[1]: Mounting Kernel Trace File System... Oct 28 04:50:32.063586 systemd[1]: Mounting Temporary Directory /tmp... Oct 28 04:50:32.063596 systemd[1]: Starting Create missing system files... Oct 28 04:50:32.063607 systemd[1]: Starting Create List of Static Device Nodes... Oct 28 04:50:32.063617 systemd[1]: Starting Load Kernel Module configfs... Oct 28 04:50:32.063626 systemd[1]: Starting Load Kernel Module drm... Oct 28 04:50:32.063636 systemd[1]: Starting Load Kernel Module fuse... Oct 28 04:50:32.063646 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 28 04:50:32.063655 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 28 04:50:32.063665 systemd[1]: Stopped File System Check on Root Device. Oct 28 04:50:32.063676 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 28 04:50:32.063685 systemd[1]: Stopped systemd-fsck-usr.service. Oct 28 04:50:32.063695 kernel: fuse: init (API version 7.32) Oct 28 04:50:32.063710 systemd[1]: Stopped Journal Service. Oct 28 04:50:32.063721 systemd[1]: Starting Journal Service... Oct 28 04:50:32.063731 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 28 04:50:32.063741 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 28 04:50:32.063750 systemd[1]: Starting Apply Kernel Variables... Oct 28 04:50:32.063761 systemd[1]: Starting Coldplug All udev Devices... Oct 28 04:50:32.063770 systemd[1]: verity-setup.service: Deactivated successfully. Oct 28 04:50:32.063782 systemd[1]: Stopped verity-setup.service. Oct 28 04:50:32.063795 systemd-journald[800]: Journal started Oct 28 04:50:32.063834 systemd-journald[800]: Runtime Journal (/run/log/journal/334fdacc6f6b44bb87b75a7e6890f8f7) is 6.0M, max 48.5M, 42.4M free. Oct 28 04:50:29.923000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 28 04:50:29.952000 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 04:50:29.952000 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 04:50:29.952000 audit: BPF prog-id=19 op=LOAD Oct 28 04:50:29.952000 audit: BPF prog-id=19 op=UNLOAD Oct 28 04:50:29.952000 audit: BPF prog-id=20 op=LOAD Oct 28 04:50:29.952000 audit: BPF prog-id=20 op=UNLOAD Oct 28 04:50:29.981000 audit[749]: AVC avc: denied { associate } for pid=749 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 28 04:50:31.927000 audit: BPF prog-id=21 op=LOAD Oct 28 04:50:31.928000 audit: BPF prog-id=22 op=LOAD Oct 28 04:50:31.928000 audit: BPF prog-id=23 op=LOAD Oct 28 04:50:31.928000 audit: BPF prog-id=13 op=UNLOAD Oct 28 04:50:31.928000 audit: BPF prog-id=14 op=UNLOAD Oct 28 04:50:31.941000 audit: BPF prog-id=24 op=LOAD Oct 28 04:50:31.941000 audit: BPF prog-id=21 op=UNLOAD Oct 28 04:50:31.942000 audit: BPF prog-id=25 op=LOAD Oct 28 04:50:31.943000 audit: BPF prog-id=26 op=LOAD Oct 28 04:50:31.943000 audit: BPF prog-id=22 op=UNLOAD Oct 28 04:50:31.943000 audit: BPF prog-id=23 op=UNLOAD Oct 28 04:50:31.944000 audit: BPF prog-id=27 op=LOAD Oct 28 04:50:31.944000 audit: BPF prog-id=24 op=UNLOAD Oct 28 04:50:31.945000 audit: BPF prog-id=28 op=LOAD Oct 28 04:50:31.946000 audit: BPF prog-id=29 op=LOAD Oct 28 04:50:31.946000 audit: BPF prog-id=25 op=UNLOAD Oct 28 04:50:31.946000 audit: BPF prog-id=26 op=UNLOAD Oct 28 04:50:31.954000 audit: BPF prog-id=27 op=UNLOAD Oct 28 04:50:32.048000 audit: BPF prog-id=30 op=LOAD Oct 28 04:50:32.049000 audit: BPF prog-id=31 op=LOAD Oct 28 04:50:32.049000 audit: BPF prog-id=32 op=LOAD Oct 28 04:50:32.049000 audit: BPF prog-id=28 op=UNLOAD Oct 28 04:50:32.049000 audit: BPF prog-id=29 op=UNLOAD Oct 28 04:50:32.060000 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 04:50:31.926947 systemd[1]: Queued start job for default target Multi-User System. Oct 28 04:50:29.980426 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 28 04:50:31.947313 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 28 04:50:29.980692 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 28 04:50:29.980718 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 28 04:50:29.980838 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 28 04:50:29.980848 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 28 04:50:29.980876 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 28 04:50:29.980889 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 28 04:50:29.981082 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 28 04:50:29.981114 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 28 04:50:29.981128 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 28 04:50:29.981502 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 28 04:50:29.981532 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 28 04:50:29.981551 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 28 04:50:29.981565 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 28 04:50:29.981582 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 28 04:50:29.981596 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:29Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 28 04:50:31.757099 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:31Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 04:50:31.757393 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:31Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 04:50:31.757490 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:31Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 04:50:31.757624 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:31Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 04:50:31.757686 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:31Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 28 04:50:31.757756 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2025-10-28T04:50:31Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 28 04:50:32.066747 systemd[1]: Started Journal Service. Oct 28 04:50:32.067246 systemd[1]: Mounted Huge Pages File System. Oct 28 04:50:32.068228 systemd[1]: Mounted POSIX Message Queue File System. Oct 28 04:50:32.069314 systemd[1]: Mounted External Media Directory. Oct 28 04:50:32.070336 systemd[1]: Mounted Kernel Debug File System. Oct 28 04:50:32.071351 systemd[1]: Mounted Kernel Trace File System. Oct 28 04:50:32.072309 systemd[1]: Mounted Temporary Directory /tmp. Oct 28 04:50:32.073623 systemd[1]: Finished Create missing system files. Oct 28 04:50:32.074910 systemd[1]: Finished Create List of Static Device Nodes. Oct 28 04:50:32.076126 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 28 04:50:32.076316 systemd[1]: Finished Load Kernel Module configfs. Oct 28 04:50:32.077528 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 28 04:50:32.077730 systemd[1]: Finished Load Kernel Module drm. Oct 28 04:50:32.078782 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 28 04:50:32.078964 systemd[1]: Finished Load Kernel Module fuse. Oct 28 04:50:32.080145 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 28 04:50:32.095962 systemd[1]: Finished Apply Kernel Variables. Oct 28 04:50:32.098855 systemd[1]: Mounting FUSE Control File System... Oct 28 04:50:32.100728 systemd[1]: Mounting Kernel Configuration File System... Oct 28 04:50:32.101609 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 28 04:50:32.103017 systemd[1]: Starting Rebuild Hardware Database... Oct 28 04:50:32.105004 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 28 04:50:32.106068 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 28 04:50:32.108853 systemd-journald[800]: Time spent on flushing to /var/log/journal/334fdacc6f6b44bb87b75a7e6890f8f7 is 8.227ms for 784 entries. Oct 28 04:50:32.108853 systemd-journald[800]: System Journal (/var/log/journal/334fdacc6f6b44bb87b75a7e6890f8f7) is 8.0M, max 203.0M, 195.0M free. Oct 28 04:50:32.107022 systemd[1]: Starting Load/Save Random Seed... Oct 28 04:50:32.108796 systemd[1]: Starting Create System Users... Oct 28 04:50:32.111941 systemd[1]: Mounted FUSE Control File System. Oct 28 04:50:32.113356 systemd[1]: Mounted Kernel Configuration File System. Oct 28 04:50:32.114592 systemd[1]: Finished Load/Save Random Seed. Oct 28 04:50:32.115590 systemd[1]: Reached target First Boot Complete. Oct 28 04:50:32.118765 systemd[1]: Finished Coldplug All udev Devices. Oct 28 04:50:32.123285 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 28 04:50:32.127621 systemd-sysusers[812]: Creating group sgx with gid 999. Oct 28 04:50:32.128634 systemd-sysusers[812]: Creating group systemd-oom with gid 998. Oct 28 04:50:32.129271 systemd-sysusers[812]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 28 04:50:32.130132 systemd-sysusers[812]: Creating group systemd-timesync with gid 997. Oct 28 04:50:32.130862 systemd-sysusers[812]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 28 04:50:32.131714 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 28 04:50:32.133343 systemd-sysusers[812]: Creating group systemd-coredump with gid 996. Oct 28 04:50:32.134025 systemd-sysusers[812]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 28 04:50:32.144098 systemd[1]: Finished Create System Users. Oct 28 04:50:32.146015 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 28 04:50:32.159297 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 28 04:50:32.362433 systemd[1]: Finished Rebuild Hardware Database. Oct 28 04:50:32.373000 audit: BPF prog-id=33 op=LOAD Oct 28 04:50:32.373000 audit: BPF prog-id=34 op=LOAD Oct 28 04:50:32.373000 audit: BPF prog-id=35 op=LOAD Oct 28 04:50:32.373000 audit: BPF prog-id=16 op=UNLOAD Oct 28 04:50:32.373000 audit: BPF prog-id=17 op=UNLOAD Oct 28 04:50:32.374733 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 28 04:50:32.397944 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 28 04:50:32.406000 audit: BPF prog-id=36 op=LOAD Oct 28 04:50:32.407590 systemd[1]: Starting Network Configuration... Oct 28 04:50:32.427941 systemd-udevd[833]: Using default interface naming scheme 'v249'. Oct 28 04:50:32.440041 systemd-networkd[837]: lo: Link UP Oct 28 04:50:32.440050 systemd-networkd[837]: lo: Gained carrier Oct 28 04:50:32.440410 systemd-networkd[837]: Enumeration completed Oct 28 04:50:32.440799 systemd[1]: Started Network Configuration. Oct 28 04:50:32.442105 systemd-networkd[837]: eth0: Link UP Oct 28 04:50:32.445468 systemd-networkd[837]: eth0: Gained carrier Oct 28 04:50:32.453539 systemd-networkd[837]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 28 04:50:32.455301 udevadm[814]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 28 04:50:32.484517 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 28 04:50:32.501323 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 28 04:50:32.509315 lvm[845]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 28 04:50:32.535418 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 28 04:50:32.536477 systemd[1]: Reached target Local Encrypted Volumes. Oct 28 04:50:32.549323 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 28 04:50:32.552846 lvm[846]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 28 04:50:32.581564 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 28 04:50:32.582649 systemd[1]: Reached target Preparation for Local File Systems. Oct 28 04:50:32.583709 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 28 04:50:32.583734 systemd[1]: Reached target Containers. Oct 28 04:50:32.598650 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 28 04:50:32.610581 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 28 04:50:32.612812 systemd[1]: Mounting /usr/share/oem... Oct 28 04:50:32.618897 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 28 04:50:32.618991 kernel: BTRFS info (device vda6): has skinny extents Oct 28 04:50:32.621175 systemd[1]: Mounted /usr/share/oem. Oct 28 04:50:32.622021 systemd[1]: Reached target Local File Systems. Oct 28 04:50:32.630360 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 28 04:50:32.631589 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 28 04:50:32.631633 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 28 04:50:32.632585 systemd[1]: Starting Commit a transient machine-id on disk... Oct 28 04:50:32.634564 systemd[1]: Starting Create Volatile Files and Directories... Oct 28 04:50:32.641979 systemd-tmpfiles[871]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 28 04:50:32.643048 systemd-tmpfiles[871]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 28 04:50:32.770456 systemd-tmpfiles[871]: Detected autofs mount point /boot during canonicalization of /boot. Oct 28 04:50:32.770468 systemd-tmpfiles[871]: Skipping /boot Oct 28 04:50:32.783787 systemd-tmpfiles[871]: Detected autofs mount point /boot during canonicalization of /boot. Oct 28 04:50:32.783801 systemd-tmpfiles[871]: Skipping /boot Oct 28 04:50:32.804046 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 28 04:50:32.805205 systemd[1]: Finished Commit a transient machine-id on disk. Oct 28 04:50:32.812782 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 28 04:50:32.827790 systemd[1]: Finished Create Volatile Files and Directories. Oct 28 04:50:32.829961 systemd[1]: Starting Load Security Auditing Rules... Oct 28 04:50:32.832151 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 28 04:50:32.834976 systemd[1]: Starting Rebuild Journal Catalog... Oct 28 04:50:32.836000 audit: BPF prog-id=37 op=LOAD Oct 28 04:50:32.837339 systemd[1]: Starting Network Name Resolution... Oct 28 04:50:32.838000 audit: BPF prog-id=38 op=LOAD Oct 28 04:50:32.839604 systemd[1]: Starting Network Time Synchronization... Oct 28 04:50:32.841428 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 28 04:50:32.843117 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 28 04:50:32.844624 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 28 04:50:32.855897 systemd[1]: Finished Rebuild Journal Catalog. Oct 28 04:50:32.858041 systemd[1]: Starting Update is Completed... Oct 28 04:50:32.860963 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 28 04:50:32.863172 systemd[1]: Finished Update is Completed. Oct 28 04:50:32.864000 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 04:50:32.865006 augenrules[894]: No rules Oct 28 04:50:32.865640 systemd[1]: Finished Load Security Auditing Rules. Oct 28 04:50:32.876722 systemd[1]: Started Network Time Synchronization. Oct 28 04:50:32.877475 systemd-timesyncd[883]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 28 04:50:32.877907 systemd[1]: Reached target System Initialization. Oct 28 04:50:32.878954 systemd[1]: Started Watch for update engine configuration changes. Oct 28 04:50:32.880098 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 28 04:50:32.881293 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 28 04:50:32.882369 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 28 04:50:32.882414 systemd[1]: Reached target Path Units. Oct 28 04:50:32.883166 systemd[1]: Reached target System Time Set. Oct 28 04:50:32.884265 systemd[1]: Started Daily Log Rotation. Oct 28 04:50:32.885220 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 28 04:50:32.886389 systemd[1]: Reached target Timer Units. Oct 28 04:50:32.887519 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 28 04:50:32.895003 systemd-resolved[882]: Positive Trust Anchors: Oct 28 04:50:32.895151 systemd-resolved[882]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 04:50:32.895179 systemd-resolved[882]: 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 04:50:32.896047 systemd-resolved[882]: Defaulting to hostname 'linux'. Oct 28 04:50:32.901114 systemd[1]: Starting Docker Socket for the API... Oct 28 04:50:32.904247 systemd[1]: Listening on OpenSSH Server Socket. Oct 28 04:50:32.905603 systemd[1]: Started Network Name Resolution. Oct 28 04:50:32.906532 systemd[1]: Listening on Docker Socket for the API. Oct 28 04:50:32.907455 systemd[1]: Reached target Network. Oct 28 04:50:32.908167 systemd[1]: Reached target Host and Network Name Lookups. Oct 28 04:50:32.909201 systemd[1]: Reached target Socket Units. Oct 28 04:50:32.910042 systemd[1]: Reached target Basic System. Oct 28 04:50:32.910887 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 28 04:50:32.910917 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 28 04:50:32.911891 systemd[1]: Started D-Bus System Message Bus. Oct 28 04:50:32.915202 systemd[1]: Starting Extend Filesystems... Oct 28 04:50:32.916099 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 28 04:50:32.917093 systemd[1]: Starting Generate /run/flatcar/motd... Oct 28 04:50:32.919073 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 28 04:50:32.921903 systemd[1]: Starting Generate sshd host keys... Oct 28 04:50:32.922739 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 28 04:50:32.922781 systemd[1]: Reached target Load system-provided cloud configs. Oct 28 04:50:32.926145 systemd[1]: Starting User Login Management... Oct 28 04:50:32.927401 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 28 04:50:32.928237 systemd[1]: Starting Update Engine... Oct 28 04:50:32.929190 extend-filesystems[903]: Found vda Oct 28 04:50:32.929190 extend-filesystems[903]: Found vda1 Oct 28 04:50:32.929190 extend-filesystems[903]: Found vda2 Oct 28 04:50:32.929190 extend-filesystems[903]: Found vda3 Oct 28 04:50:32.929190 extend-filesystems[903]: Found usr Oct 28 04:50:32.929190 extend-filesystems[903]: Found vda4 Oct 28 04:50:32.929083 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 28 04:50:32.949200 extend-filesystems[903]: Found vda6 Oct 28 04:50:32.949200 extend-filesystems[903]: Found vda7 Oct 28 04:50:32.949200 extend-filesystems[903]: Found vda9 Oct 28 04:50:32.949200 extend-filesystems[903]: Checking size of /dev/vda9 Oct 28 04:50:32.929128 systemd[1]: Reached target Load user-provided cloud configs. Oct 28 04:50:32.962160 extend-filesystems[903]: Old size kept for /dev/vda9 Oct 28 04:50:32.931893 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 28 04:50:32.932133 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 28 04:50:32.944616 systemd[1]: motdgen.service: Deactivated successfully. Oct 28 04:50:32.944914 systemd[1]: Finished Generate /run/flatcar/motd. Oct 28 04:50:32.956800 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 28 04:50:32.957027 systemd[1]: Finished Extend Filesystems. Oct 28 04:50:32.957121 systemd-logind[913]: Watching system buttons on /dev/input/event0 (Power Button) Oct 28 04:50:32.964164 systemd-logind[913]: New seat seat0. Oct 28 04:50:32.972007 systemd[1]: Started User Login Management. Oct 28 04:50:32.980995 update_engine[917]: I1028 04:50:32.980729 917 main.cc:89] Flatcar Update Engine starting Oct 28 04:50:32.982894 systemd[1]: Started Update Engine. Oct 28 04:50:32.984190 update_engine[917]: I1028 04:50:32.983109 917 update_check_scheduler.cc:74] Next update check in 2m14s Oct 28 04:50:32.995812 systemd[1]: Started Cluster reboot manager. Oct 28 04:50:33.020702 locksmithd[926]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 28 04:50:33.306443 systemd[1]: Created slice Slice /system/sshd. Oct 28 04:50:33.831990 sshd_keygen[921]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 28 04:50:33.849726 systemd[1]: Finished Generate sshd host keys. Oct 28 04:50:33.860472 systemd[1]: Starting Generate /run/issue... Oct 28 04:50:33.862074 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:44180). Oct 28 04:50:33.864828 systemd[1]: issuegen.service: Deactivated successfully. Oct 28 04:50:33.865092 systemd[1]: Finished Generate /run/issue. Oct 28 04:50:33.867341 systemd[1]: Starting Permit User Sessions... Oct 28 04:50:33.872766 systemd[1]: Finished Permit User Sessions. Oct 28 04:50:33.875072 systemd[1]: Started Getty on tty1. Oct 28 04:50:33.877127 systemd[1]: Started Serial Getty on ttyAMA0. Oct 28 04:50:33.878266 systemd[1]: Reached target Login Prompts. Oct 28 04:50:33.879358 systemd[1]: Reached target Multi-User System. Oct 28 04:50:33.881548 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 28 04:50:33.886840 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 28 04:50:33.887104 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 28 04:50:33.888333 systemd[1]: Startup finished in 714ms (kernel) + 2.334s (initrd) + 3.997s (userspace) = 7.046s. Oct 28 04:50:33.906577 sshd[942]: Accepted publickey for core from 10.0.0.1 port 44180 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:50:33.908202 sshd[942]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:33.922863 systemd[1]: Created slice User Slice of UID 500. Oct 28 04:50:33.923787 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 28 04:50:33.925722 systemd-logind[913]: New session 1 of user core. Oct 28 04:50:33.929744 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 28 04:50:33.930992 systemd[1]: Starting User Manager for UID 500... Oct 28 04:50:33.933160 systemd[953]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:33.978738 systemd[953]: Queued start job for default target Main User Target. Oct 28 04:50:33.978907 systemd[953]: Reached target Paths. Oct 28 04:50:33.978921 systemd[953]: Reached target Sockets. Oct 28 04:50:33.978931 systemd[953]: Reached target Timers. Oct 28 04:50:33.978941 systemd[953]: Reached target Basic System. Oct 28 04:50:33.978985 systemd[953]: Reached target Main User Target. Oct 28 04:50:33.978998 systemd[953]: Startup finished in 42ms. Oct 28 04:50:33.979083 systemd[1]: Started User Manager for UID 500. Oct 28 04:50:33.990562 systemd[1]: Started Session 1 of User core. Oct 28 04:50:34.050698 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37328). Oct 28 04:50:34.093796 sshd[962]: Accepted publickey for core from 10.0.0.1 port 37328 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:50:34.094757 sshd[962]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:34.097316 systemd-logind[913]: New session 2 of user core. Oct 28 04:50:34.102522 systemd[1]: Started Session 2 of User core. Oct 28 04:50:34.154148 sshd[962]: pam_unix(sshd:session): session closed for user core Oct 28 04:50:34.167097 systemd[1]: sshd@1-10.0.0.5:22-10.0.0.1:37328.service: Deactivated successfully. Oct 28 04:50:34.167826 systemd[1]: session-2.scope: Deactivated successfully. Oct 28 04:50:34.169085 systemd-logind[913]: Session 2 logged out. Waiting for processes to exit. Oct 28 04:50:34.171343 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37338). Oct 28 04:50:34.173344 systemd-logind[913]: Removed session 2. Oct 28 04:50:34.211194 sshd[968]: Accepted publickey for core from 10.0.0.1 port 37338 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:50:34.212150 sshd[968]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:34.214690 systemd-logind[913]: New session 3 of user core. Oct 28 04:50:34.221565 systemd[1]: Started Session 3 of User core. Oct 28 04:50:34.269494 sshd[968]: pam_unix(sshd:session): session closed for user core Oct 28 04:50:34.277053 systemd[1]: sshd@2-10.0.0.5:22-10.0.0.1:37338.service: Deactivated successfully. Oct 28 04:50:34.277951 systemd[1]: session-3.scope: Deactivated successfully. Oct 28 04:50:34.279261 systemd-logind[913]: Session 3 logged out. Waiting for processes to exit. Oct 28 04:50:34.281233 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37344). Oct 28 04:50:34.283010 systemd-logind[913]: Removed session 3. Oct 28 04:50:34.319403 sshd[974]: Accepted publickey for core from 10.0.0.1 port 37344 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:50:34.320261 sshd[974]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:34.322931 systemd-logind[913]: New session 4 of user core. Oct 28 04:50:34.332497 systemd[1]: Started Session 4 of User core. Oct 28 04:50:34.383346 sshd[974]: pam_unix(sshd:session): session closed for user core Oct 28 04:50:34.394968 systemd[1]: sshd@3-10.0.0.5:22-10.0.0.1:37344.service: Deactivated successfully. Oct 28 04:50:34.395492 systemd[1]: session-4.scope: Deactivated successfully. Oct 28 04:50:34.396331 systemd-logind[913]: Session 4 logged out. Waiting for processes to exit. Oct 28 04:50:34.397123 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37360). Oct 28 04:50:34.398273 systemd-logind[913]: Removed session 4. Oct 28 04:50:34.438759 sshd[980]: Accepted publickey for core from 10.0.0.1 port 37360 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:50:34.439099 sshd[980]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:34.441809 systemd-logind[913]: New session 5 of user core. Oct 28 04:50:34.453518 systemd[1]: Started Session 5 of User core. Oct 28 04:50:34.488702 systemd-networkd[837]: eth0: Gained IPv6LL Oct 28 04:50:34.510307 sudo[983]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 28 04:50:34.510580 sudo[983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 04:50:34.524871 sudo[983]: pam_unix(sudo:session): session closed for user root Oct 28 04:50:34.526876 sshd[980]: pam_unix(sshd:session): session closed for user core Oct 28 04:50:34.538697 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37370). Oct 28 04:50:34.540729 dbus-daemon[902]: [system] Reloaded configuration Oct 28 04:50:34.542988 systemd[1]: sshd@4-10.0.0.5:22-10.0.0.1:37360.service: Deactivated successfully. Oct 28 04:50:34.543602 systemd[1]: session-5.scope: Deactivated successfully. Oct 28 04:50:34.544736 systemd-logind[913]: Session 5 logged out. Waiting for processes to exit. Oct 28 04:50:34.545913 systemd-logind[913]: Removed session 5. Oct 28 04:50:34.583698 sshd[986]: Accepted publickey for core from 10.0.0.1 port 37370 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:50:34.585524 sshd[986]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:34.588263 systemd-logind[913]: New session 6 of user core. Oct 28 04:50:34.595495 systemd[1]: Started Session 6 of User core. Oct 28 04:50:34.645026 sudo[991]: 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 04:50:34.646001 sudo[991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 04:50:34.648087 sudo[991]: pam_unix(sudo:session): session closed for user root Oct 28 04:50:34.651383 sudo[990]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 28 04:50:34.651570 sudo[990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 04:50:34.663208 systemd[1]: Stopping Load Security Auditing Rules... Oct 28 04:50:34.664000 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 04:50:34.665384 auditctl[994]: No rules Oct 28 04:50:34.665758 systemd[1]: audit-rules.service: Deactivated successfully. Oct 28 04:50:34.665981 systemd[1]: Stopped Load Security Auditing Rules. Oct 28 04:50:34.667156 systemd[1]: Starting Load Security Auditing Rules... Oct 28 04:50:34.687222 augenrules[1011]: No rules Oct 28 04:50:34.688573 systemd[1]: Finished Load Security Auditing Rules. Oct 28 04:50:34.689295 sudo[990]: pam_unix(sudo:session): session closed for user root Oct 28 04:50:34.691956 sshd[986]: pam_unix(sshd:session): session closed for user core Oct 28 04:50:34.698805 systemd[1]: sshd@5-10.0.0.5:22-10.0.0.1:37370.service: Deactivated successfully. Oct 28 04:50:34.699370 systemd[1]: session-6.scope: Deactivated successfully. Oct 28 04:50:34.699976 systemd-logind[913]: Session 6 logged out. Waiting for processes to exit. Oct 28 04:50:34.700887 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37374). Oct 28 04:50:34.701416 systemd-logind[913]: Removed session 6. Oct 28 04:50:34.739715 sshd[1017]: Accepted publickey for core from 10.0.0.1 port 37374 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:50:34.740540 sshd[1017]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:34.742959 systemd-logind[913]: New session 7 of user core. Oct 28 04:50:34.752523 systemd[1]: Started Session 7 of User core. Oct 28 04:50:34.801301 sudo[1020]: 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 04:50:37.721827 update_engine[1266]: Oct 28 04:50:37.721827 update_engine[1266]: Oct 28 04:50:37.721827 update_engine[1266]: Oct 28 04:50:37.721827 update_engine[1266]: Oct 28 04:50:37.721827 update_engine[1266]: Oct 28 04:50:37.721827 update_engine[1266]: Oct 28 04:50:37.721827 update_engine[1266]: Oct 28 04:50:37.721827 update_engine[1266]: Oct 28 04:50:37.721827 update_engine[1266]: I1028 04:50:37.721825 1266 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 28 04:50:37.724860 update_engine[1266]: I1028 04:50:37.724828 1266 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 28 04:50:37.724976 systemd-logind[913]: Removed session 24. Oct 28 04:50:37.725111 update_engine[1266]: I1028 04:50:37.725073 1266 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 28 04:50:37.727205 update_engine[1266]: I1028 04:50:37.727154 1266 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 28 04:50:37.727305 update_engine[1266]: I1028 04:50:37.727246 1266 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Oct 28 04:50:37.727305 update_engine[1266]: I1028 04:50:37.727255 1266 omaha_request_action.cc:619] Omaha request response: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.727305 update_engine[1266]: Oct 28 04:50:37.729564 update_engine[1266]: I1028 04:50:37.729533 1266 omaha_request_action.cc:447] Omaha Response manifest version = Oct 28 04:50:37.729564 update_engine[1266]: I1028 04:50:37.729563 1266 omaha_request_action.cc:470] Found 1 url(s) Oct 28 04:50:37.729652 update_engine[1266]: I1028 04:50:37.729576 1266 omaha_request_action.cc:506] Processing first of 1 package(s) Oct 28 04:50:37.729652 update_engine[1266]: I1028 04:50:37.729580 1266 omaha_request_action.cc:513] Omaha Response package name = update.gz Oct 28 04:50:37.729652 update_engine[1266]: I1028 04:50:37.729583 1266 omaha_request_action.cc:529] Url0: http://10.0.0.4:34567/packages/update.gz Oct 28 04:50:37.729652 update_engine[1266]: I1028 04:50:37.729590 1266 omaha_request_action.cc:541] Payload size = 439465725 bytes Oct 28 04:50:37.729652 update_engine[1266]: I1028 04:50:37.729600 1266 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Oct 28 04:50:37.729652 update_engine[1266]: I1028 04:50:37.729612 1266 payload_state.cc:51] Resetting all persisted state as this is a new response Oct 28 04:50:37.729652 update_engine[1266]: I1028 04:50:37.729616 1266 payload_state.cc:360] Current Response Signature = Oct 28 04:50:37.729652 update_engine[1266]: NumURLs = 1 Oct 28 04:50:37.729652 update_engine[1266]: Url0 = http://10.0.0.4:34567/packages/update.gz Oct 28 04:50:37.729652 update_engine[1266]: Payload Size = 439465725 Oct 28 04:50:37.729652 update_engine[1266]: Payload Sha256 Hash = dykyx/Y4jQdfCP1f9j5W1sV+S815EIPi4QJ65LWeHik= Oct 28 04:50:37.729652 update_engine[1266]: Is Delta Payload = 0 Oct 28 04:50:37.729652 update_engine[1266]: Max Failure Count Per Url = 10 Oct 28 04:50:37.729652 update_engine[1266]: Disable Payload Backoff = 1 Oct 28 04:50:37.729881 update_engine[1266]: I1028 04:50:37.729694 1266 payload_state.cc:381] Payload Attempt Number = 0 Oct 28 04:50:37.729881 update_engine[1266]: I1028 04:50:37.729724 1266 payload_state.cc:404] Current URL Index = 0 Oct 28 04:50:37.729881 update_engine[1266]: I1028 04:50:37.729751 1266 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Oct 28 04:50:37.729881 update_engine[1266]: I1028 04:50:37.729779 1266 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Oct 28 04:50:37.729881 update_engine[1266]: I1028 04:50:37.729782 1266 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 28 04:50:37.729881 update_engine[1266]: I1028 04:50:37.729815 1266 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Oct 28 04:50:37.729881 update_engine[1266]: I1028 04:50:37.729832 1266 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 28 04:50:37.729881 update_engine[1266]: I1028 04:50:37.729835 1266 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Oct 28 04:50:37.729881 update_engine[1266]: I1028 04:50:37.729864 1266 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Oct 28 04:50:37.729881 update_engine[1266]: E1028 04:50:37.729871 1266 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Oct 28 04:50:37.732024 update_engine[1266]: I1028 04:50:37.732000 1266 omaha_response_handler_action.cc:85] Using this install plan: Oct 28 04:50:37.732024 update_engine[1266]: I1028 04:50:37.732014 1266 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.4:34567/packages/update.gz, payload size: 439465725, payload hash: dykyx/Y4jQdfCP1f9j5W1sV+S815EIPi4QJ65LWeHik=, 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 04:50:37.732116 update_engine[1266]: I1028 04:50:37.732091 1266 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Oct 28 04:50:37.733146 update_engine[1266]: I1028 04:50:37.733125 1266 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Oct 28 04:50:37.762274 sshd[1287]: Accepted publickey for core from 10.0.0.1 port 37518 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:50:37.763120 sshd[1287]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:37.767127 systemd-logind[913]: New session 25 of user core. Oct 28 04:50:37.772583 systemd[1]: Started Session 25 of User core. Oct 28 04:50:37.833612 sshd[1287]: pam_unix(sshd:session): session closed for user core Oct 28 04:50:37.838526 systemd-logind[913]: Session 25 logged out. Waiting for processes to exit. Oct 28 04:50:37.838867 systemd[1]: session-25.scope: Deactivated successfully. Oct 28 04:50:37.839596 systemd-logind[913]: Removed session 25. Oct 28 04:50:37.839932 systemd[1]: sshd@24-10.0.0.5:22-10.0.0.1:37518.service: Deactivated successfully. Oct 28 04:50:39.616280 update_engine[1266]: I1028 04:50:39.616242 1266 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Oct 28 04:50:39.616280 update_engine[1266]: I1028 04:50:39.616281 1266 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Oct 28 04:50:39.689988 update_engine[1266]: E1028 04:50:39.689920 1266 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Oct 28 04:50:39.689988 update_engine[1266]: I1028 04:50:39.689960 1266 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Oct 28 04:50:39.689988 update_engine[1266]: I1028 04:50:39.689965 1266 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Oct 28 04:50:39.689988 update_engine[1266]: I1028 04:50:39.689967 1266 update_attempter.cc:302] Processing Done. Oct 28 04:50:39.690225 update_engine[1266]: E1028 04:50:39.690009 1266 update_attempter.cc:615] Update failed. Oct 28 04:50:39.690225 update_engine[1266]: I1028 04:50:39.690014 1266 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Oct 28 04:50:39.690225 update_engine[1266]: I1028 04:50:39.690018 1266 payload_state.cc:276] Incrementing the URL failure count Oct 28 04:50:39.690225 update_engine[1266]: I1028 04:50:39.690021 1266 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 28 04:50:39.690225 update_engine[1266]: I1028 04:50:39.690189 1266 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 28 04:50:39.690317 update_engine[1266]: I1028 04:50:39.690240 1266 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.4:34567/v1/update Oct 28 04:50:39.690317 update_engine[1266]: I1028 04:50:39.690248 1266 omaha_request_action.cc:269] Request: Oct 28 04:50:39.690317 update_engine[1266]: Oct 28 04:50:39.690317 update_engine[1266]: Oct 28 04:50:39.690317 update_engine[1266]: Oct 28 04:50:39.690317 update_engine[1266]: Oct 28 04:50:39.690317 update_engine[1266]: Oct 28 04:50:39.690317 update_engine[1266]: Oct 28 04:50:39.690317 update_engine[1266]: I1028 04:50:39.690253 1266 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 28 04:50:39.690317 update_engine[1266]: I1028 04:50:39.690310 1266 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 28 04:50:39.690506 update_engine[1266]: I1028 04:50:39.690428 1266 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 28 04:50:39.856069 update_engine[1266]: I1028 04:50:39.856019 1266 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 28 04:50:39.856191 update_engine[1266]: I1028 04:50:39.856120 1266 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Oct 28 04:50:39.856191 update_engine[1266]: I1028 04:50:39.856128 1266 omaha_request_action.cc:619] Omaha request response: Oct 28 04:50:39.856191 update_engine[1266]: Oct 28 04:50:39.856191 update_engine[1266]: Oct 28 04:50:39.856191 update_engine[1266]: Oct 28 04:50:39.856191 update_engine[1266]: Oct 28 04:50:39.856191 update_engine[1266]: Oct 28 04:50:39.856191 update_engine[1266]: Oct 28 04:50:39.856191 update_engine[1266]: E1028 04:50:39.856142 1266 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Oct 28 04:50:39.856191 update_engine[1266]: I1028 04:50:39.856146 1266 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 28 04:50:39.856191 update_engine[1266]: I1028 04:50:39.856149 1266 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 28 04:50:39.856191 update_engine[1266]: I1028 04:50:39.856153 1266 update_attempter.cc:302] Processing Done. Oct 28 04:50:39.856191 update_engine[1266]: I1028 04:50:39.856158 1266 update_attempter.cc:306] Error event sent. Oct 28 04:50:47.804973 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36668). Oct 28 04:50:47.846825 sshd[1302]: Accepted publickey for core from 10.0.0.1 port 36668 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:50:47.847953 sshd[1302]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:47.850372 kernel: kauditd_printk_skb: 134 callbacks suppressed Oct 28 04:50:47.850450 kernel: audit: type=1006 audit(1761627047.846:365): pid=1302 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 04:50:47.851296 systemd-logind[913]: New session 26 of user core. Oct 28 04:50:47.855648 systemd[1]: Started Session 26 of User core. Oct 28 04:50:47.911293 sshd[1302]: pam_unix(sshd:session): session closed for user core Oct 28 04:50:47.919082 systemd[1]: sshd@25-10.0.0.5:22-10.0.0.1:36668.service: Deactivated successfully. Oct 28 04:50:47.919664 systemd[1]: session-26.scope: Deactivated successfully. Oct 28 04:50:47.920258 systemd-logind[913]: Session 26 logged out. Waiting for processes to exit. Oct 28 04:50:47.921234 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36682). Oct 28 04:50:47.921833 systemd-logind[913]: Removed session 26. Oct 28 04:50:47.959853 sshd[1309]: Accepted publickey for core from 10.0.0.1 port 36682 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:50:47.961185 sshd[1309]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:47.963392 kernel: audit: type=1006 audit(1761627047.959:366): pid=1309 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 04:50:47.965205 systemd-logind[913]: New session 27 of user core. Oct 28 04:50:47.974549 systemd[1]: Started Session 27 of User core. Oct 28 04:50:48.028117 sshd[1309]: pam_unix(sshd:session): session closed for user core Oct 28 04:50:48.039400 systemd[1]: sshd@26-10.0.0.5:22-10.0.0.1:36682.service: Deactivated successfully. Oct 28 04:50:48.039948 systemd[1]: session-27.scope: Deactivated successfully. Oct 28 04:50:48.040502 systemd-logind[913]: Session 27 logged out. Waiting for processes to exit. Oct 28 04:50:48.041519 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36684). Oct 28 04:50:48.042090 systemd-logind[913]: Removed session 27. Oct 28 04:50:48.081971 sshd[1317]: Accepted publickey for core from 10.0.0.1 port 36684 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:50:48.083546 sshd[1317]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:48.086410 kernel: audit: type=1006 audit(1761627048.082:367): pid=1317 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 04:50:48.087693 systemd-logind[913]: New session 28 of user core. Oct 28 04:50:48.101573 systemd[1]: Started Session 28 of User core. Oct 28 04:50:48.151247 sshd[1317]: pam_unix(sshd:session): session closed for user core Oct 28 04:50:48.158459 systemd[1]: sshd@27-10.0.0.5:22-10.0.0.1:36684.service: Deactivated successfully. Oct 28 04:50:48.159022 systemd[1]: session-28.scope: Deactivated successfully. Oct 28 04:50:48.159584 systemd-logind[913]: Session 28 logged out. Waiting for processes to exit. Oct 28 04:50:48.160643 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36688). Oct 28 04:50:48.161216 systemd-logind[913]: Removed session 28. Oct 28 04:50:48.200107 sshd[1323]: Accepted publickey for core from 10.0.0.1 port 36688 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:50:48.201421 sshd[1323]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:48.203397 kernel: audit: type=1006 audit(1761627048.200:368): pid=1323 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 04:50:48.206679 systemd-logind[913]: New session 29 of user core. Oct 28 04:50:48.225557 systemd[1]: Started Session 29 of User core. Oct 28 04:50:48.274046 sshd[1323]: pam_unix(sshd:session): session closed for user core Oct 28 04:50:48.286370 systemd[1]: sshd@28-10.0.0.5:22-10.0.0.1:36688.service: Deactivated successfully. Oct 28 04:50:48.286949 systemd[1]: session-29.scope: Deactivated successfully. Oct 28 04:50:48.287525 systemd-logind[913]: Session 29 logged out. Waiting for processes to exit. Oct 28 04:50:48.288561 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36692). Oct 28 04:50:48.289164 systemd-logind[913]: Removed session 29. Oct 28 04:50:48.327268 sshd[1329]: Accepted publickey for core from 10.0.0.1 port 36692 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:50:48.328574 sshd[1329]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:50:48.331410 kernel: audit: type=1006 audit(1761627048.326:369): pid=1329 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 04:50:48.332721 systemd-logind[913]: New session 30 of user core. Oct 28 04:50:48.339540 systemd[1]: Started Session 30 of User core. Oct 28 04:50:48.390552 sudo[1333]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Oct 28 04:50:48.390766 sudo[1333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 04:50:48.396545 systemd[1]: sshd.socket: Deactivated successfully. Oct 28 04:50:48.396891 systemd[1]: Closed OpenSSH Server Socket. Oct 28 04:50:48.397804 sudo[1333]: pam_unix(sudo:session): session closed for user root Oct 28 04:50:48.401621 sudo[1332]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Oct 28 04:50:48.401832 sudo[1332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 04:50:48.417723 systemd[1]: Stopping Session 1 of User core... Oct 28 04:50:48.418028 sshd[942]: pam_unix(sshd:session): session closed for user core Oct 28 04:50:48.419864 systemd[1]: Stopping Session 30 of User core... Oct 28 04:50:48.420282 sudo[1332]: pam_unix(sudo:session): session closed for user root Oct 28 04:50:48.420900 sshd[1329]: pam_unix(sshd:session): session closed for user core Oct 28 04:50:48.421718 systemd[1]: Removed slice Slice /system/addon-config. Oct 28 04:50:48.423298 systemd[1]: Removed slice Slice /system/addon-run. Oct 28 04:50:48.424770 systemd[1]: Removed slice Slice /system/modprobe. Oct 28 04:50:48.426157 systemd[1]: Removed slice Slice /system/system-cloudinit. Oct 28 04:50:48.427464 systemd[1]: Stopped target Multi-User System. Oct 28 04:50:48.428538 systemd[1]: Stopped target Login Prompts. Oct 28 04:50:48.429393 systemd[1]: Stopped target Containers. Oct 28 04:50:48.430200 systemd[1]: Stopped target Host and Network Name Lookups. Oct 28 04:50:48.433349 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 28 04:50:48.434406 systemd[1]: Stopped target Timer Units. Oct 28 04:50:48.435222 systemd[1]: logrotate.timer: Deactivated successfully. Oct 28 04:50:48.435283 systemd[1]: Stopped Daily Log Rotation. Oct 28 04:50:48.436221 systemd[1]: mdadm.timer: Deactivated successfully. Oct 28 04:50:48.436273 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Oct 28 04:50:48.438731 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 28 04:50:48.438774 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Oct 28 04:50:48.439857 systemd[1]: Stopped target System Time Set. Oct 28 04:50:48.440758 systemd[1]: Stopped target Load user-provided cloud configs. Oct 28 04:50:48.441836 systemd[1]: Stopped target Load system-provided cloud configs. Oct 28 04:50:48.442985 systemd[1]: systemd-coredump.socket: Deactivated successfully. Oct 28 04:50:48.443089 systemd[1]: Closed Process Core Dump Socket. Oct 28 04:50:48.449810 systemd[1]: Unmounting Boot partition... Oct 28 04:50:48.451053 systemd[1]: Stopping Getty on tty1... Oct 28 04:50:48.451929 systemd[1]: Stopping Serial Getty on ttyAMA0... Oct 28 04:50:48.453044 systemd[1]: Stopping OpenSSH per-connection server daemon... Oct 28 04:50:48.454108 sshd[1329]: pam_systemd(sshd:session): Failed to release session: Interrupted system call Oct 28 04:50:48.454156 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:36692)... Oct 28 04:50:48.455286 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Oct 28 04:50:48.455453 systemd[1]: Stopped Commit a transient machine-id on disk. Oct 28 04:50:48.456584 systemd[1]: Stopped target First Boot Complete. Oct 28 04:50:48.458611 systemd[1]: Stopping Load/Save Random Seed... Oct 28 04:50:48.459620 systemd[1]: Stopping Update Engine... Oct 28 04:50:48.462884 systemd[1]: sshd@0-10.0.0.5:22-10.0.0.1:44180.service: Deactivated successfully. Oct 28 04:50:48.463100 systemd[1]: Stopped OpenSSH per-connection server daemon. Oct 28 04:50:48.464790 systemd[1]: getty@tty1.service: Deactivated successfully. Oct 28 04:50:48.464990 systemd[1]: Stopped Getty on tty1. Oct 28 04:50:48.466261 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Oct 28 04:50:48.466479 systemd[1]: Stopped Serial Getty on ttyAMA0. Oct 28 04:50:48.467815 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Oct 28 04:50:48.467890 systemd[1]: update-engine.service: Failed with result 'exit-code'. Oct 28 04:50:48.468151 systemd[1]: Stopped Update Engine. Oct 28 04:50:48.469172 systemd[1]: update-engine.service: Consumed 2.363s CPU time. Oct 28 04:50:48.469458 systemd[1]: sshd@29-10.0.0.5:22-10.0.0.1:36692.service: Deactivated successfully. Oct 28 04:50:48.469675 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:36692). Oct 28 04:50:48.481150 systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 28 04:50:48.481411 systemd[1]: Stopped Load/Save Random Seed. Oct 28 04:50:48.482544 systemd[1]: boot.mount: Deactivated successfully. Oct 28 04:50:48.482805 systemd[1]: Unmounted Boot partition. Oct 28 04:50:48.483929 systemd[1]: session-30.scope: Deactivated successfully. Oct 28 04:50:48.484184 systemd[1]: Stopped Session 30 of User core. Oct 28 04:50:48.485347 systemd[1]: session-1.scope: Deactivated successfully. Oct 28 04:50:48.485634 systemd[1]: Stopped Session 1 of User core. Oct 28 04:50:48.487181 systemd-logind[913]: Session 1 logged out. Waiting for processes to exit. Oct 28 04:50:48.488211 systemd-logind[913]: Session 30 logged out. Waiting for processes to exit. Oct 28 04:50:48.488519 systemd[1]: Removed slice Slice /system/getty. Oct 28 04:50:48.489807 systemd[1]: Removed slice Slice /system/serial-getty. Oct 28 04:50:48.491130 systemd[1]: Removed slice Slice /system/sshd. Oct 28 04:50:48.492092 systemd[1]: sshd-keygen.service: Deactivated successfully. Oct 28 04:50:48.492213 systemd[1]: Stopped Generate sshd host keys. Oct 28 04:50:48.493203 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Oct 28 04:50:48.493330 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Oct 28 04:50:48.495258 systemd[1]: Stopping User Login Management... Oct 28 04:50:48.496134 systemd[1]: Stopping User Manager for UID 500... Oct 28 04:50:48.497005 systemd[953]: Stopped target Main User Target. Oct 28 04:50:48.497025 systemd[953]: Stopped target Basic System. Oct 28 04:50:48.497035 systemd[953]: Stopped target Paths. Oct 28 04:50:48.497042 systemd[953]: Stopped target Sockets. Oct 28 04:50:48.497059 systemd[953]: Reached target Shutdown. Oct 28 04:50:48.497066 systemd[953]: Stopped target Timers. Oct 28 04:50:48.497185 systemd[953]: Finished Exit the Session. Oct 28 04:50:48.497210 systemd[953]: Reached target Exit the Session. Oct 28 04:50:48.498807 systemd-logind[913]: Removed session 30. Oct 28 04:50:48.499416 systemd[1]: user@500.service: Deactivated successfully. Oct 28 04:50:48.499596 systemd[1]: Stopped User Manager for UID 500. Oct 28 04:50:48.501621 systemd[1]: Stopping User Runtime Directory /run/user/500... Oct 28 04:50:48.507045 systemd[1]: run-user-500.mount: Deactivated successfully. Oct 28 04:50:48.507117 systemd[1]: Unmounted /run/user/500. Oct 28 04:50:48.508317 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Oct 28 04:50:48.508555 systemd[1]: Stopped User Runtime Directory /run/user/500. Oct 28 04:50:48.510164 systemd[1]: systemd-logind.service: Deactivated successfully. Oct 28 04:50:48.510435 systemd[1]: Stopped User Login Management. Oct 28 04:50:48.511958 systemd[1]: Removed slice User Slice of UID 500. Oct 28 04:50:48.513749 systemd[1]: Stopping D-Bus System Message Bus... Oct 28 04:50:48.525000 audit: BPF prog-id=56 op=UNLOAD Oct 28 04:50:48.527445 systemd[1]: Stopping Permit User Sessions... Oct 28 04:50:48.528407 kernel: audit: type=1334 audit(1761627048.525:370): prog-id=56 op=UNLOAD Oct 28 04:50:48.528897 systemd[1]: dbus.service: Deactivated successfully. Oct 28 04:50:48.529164 systemd[1]: Stopped D-Bus System Message Bus. Oct 28 04:50:48.532098 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Oct 28 04:50:48.532333 systemd[1]: Stopped Permit User Sessions. Oct 28 04:50:48.533366 systemd[1]: Stopped target Basic System. Oct 28 04:50:48.534154 systemd[1]: Stopped target Network. Oct 28 04:50:48.535123 systemd[1]: Stopped target Path Units. Oct 28 04:50:48.536038 systemd[1]: motdgen.path: Deactivated successfully. Oct 28 04:50:48.539435 systemd[1]: Stopped Watch for update engine configuration changes. Oct 28 04:50:48.540610 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Oct 28 04:50:48.544421 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 28 04:50:48.545646 systemd[1]: Stopped target Remote File Systems. Oct 28 04:50:48.546552 systemd[1]: Stopped target Slice Units. Oct 28 04:50:48.547657 systemd[1]: Removed slice User and Session Slice. Oct 28 04:50:48.548551 systemd[1]: Stopped target Socket Units. Oct 28 04:50:48.549354 systemd[1]: dbus.socket: Deactivated successfully. Oct 28 04:50:48.549429 systemd[1]: Closed D-Bus System Message Bus Socket. Oct 28 04:50:48.550435 systemd[1]: docker.socket: Deactivated successfully. Oct 28 04:50:48.550676 systemd[1]: Closed Docker Socket for the API. Oct 28 04:50:48.551542 systemd[1]: Stopped target System Initialization. Oct 28 04:50:48.552406 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Oct 28 04:50:48.552549 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Oct 28 04:50:48.553665 systemd[1]: Stopped target Local Verity Protected Volumes. Oct 28 04:50:48.565310 systemd[1]: Stopping Load Security Auditing Rules... Oct 28 04:50:48.566505 systemd[1]: Stopping Network Name Resolution... Oct 28 04:50:48.567477 systemd[1]: Stopping Network Time Synchronization... Oct 28 04:50:48.568393 systemd[1]: systemd-update-done.service: Deactivated successfully. Oct 28 04:50:48.568469 systemd[1]: Stopped Update is Completed. Oct 28 04:50:48.569343 systemd[1]: ldconfig.service: Deactivated successfully. Oct 28 04:50:48.569427 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Oct 28 04:50:48.569470 auditctl[1347]: No rules Oct 28 04:50:48.570307 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Oct 28 04:50:48.570371 systemd[1]: Stopped Rebuild Hardware Database. Oct 28 04:50:48.571246 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Oct 28 04:50:48.571305 systemd[1]: Stopped Rebuild Journal Catalog. Oct 28 04:50:48.573189 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Oct 28 04:50:48.575879 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 28 04:50:48.576140 systemd[1]: Stopped Network Name Resolution. Oct 28 04:50:48.577281 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Oct 28 04:50:48.577538 systemd[1]: Stopped Network Time Synchronization. Oct 28 04:50:48.578776 systemd[1]: audit-rules.service: Deactivated successfully. Oct 28 04:50:48.579014 systemd[1]: Stopped Load Security Auditing Rules. Oct 28 04:50:48.581454 systemd[1]: Stopping Network Configuration... Oct 28 04:50:48.582426 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Oct 28 04:50:48.582659 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Oct 28 04:50:48.584401 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 28 04:50:48.584519 systemd[1]: Stopped Create Volatile Files and Directories. Oct 28 04:50:48.585586 systemd[1]: Stopped target Local File Systems. Oct 28 04:50:48.589441 systemd-networkd[837]: eth0: DHCPv6 lease lost Oct 28 04:50:48.597000 audit: BPF prog-id=67 op=UNLOAD Oct 28 04:50:48.597000 audit: BPF prog-id=60 op=UNLOAD Oct 28 04:50:48.598885 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Oct 28 04:50:48.599280 kernel: audit: type=1334 audit(1761627048.597:371): prog-id=67 op=UNLOAD Oct 28 04:50:48.599331 kernel: audit: type=1334 audit(1761627048.597:372): prog-id=60 op=UNLOAD Oct 28 04:50:48.600951 systemd[1]: Unmounting External Media Directory... Oct 28 04:50:48.603094 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Oct 28 04:50:48.605053 systemd[1]: Unmounting /run/torcx/unpack... Oct 28 04:50:48.606439 systemd[1]: Unmounting Temporary Directory /tmp... Oct 28 04:50:48.607875 systemd[1]: Unmounting /usr/share/oem... Oct 28 04:50:48.609600 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Oct 28 04:50:48.612801 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 28 04:50:48.613074 systemd[1]: Stopped Network Configuration. Oct 28 04:50:48.614462 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 28 04:50:48.614720 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Oct 28 04:50:48.617337 systemd[1]: media.mount: Deactivated successfully. Oct 28 04:50:48.617687 systemd[1]: Unmounted External Media Directory. Oct 28 04:50:48.618964 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Oct 28 04:50:48.619217 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Oct 28 04:50:48.620982 systemd[1]: tmp.mount: Deactivated successfully. Oct 28 04:50:48.621250 systemd[1]: Unmounted Temporary Directory /tmp. Oct 28 04:50:48.623122 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 28 04:50:48.623412 systemd[1]: Unmounted /usr/share/oem. Oct 28 04:50:48.624628 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 28 04:50:48.624880 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Oct 28 04:50:48.626000 audit: BPF prog-id=59 op=UNLOAD Oct 28 04:50:48.626934 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 28 04:50:48.626983 systemd[1]: Closed Network Service Netlink Socket. Oct 28 04:50:48.628403 kernel: audit: type=1334 audit(1761627048.626:373): prog-id=59 op=UNLOAD Oct 28 04:50:48.628875 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Oct 28 04:50:48.628956 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Oct 28 04:50:48.631034 systemd[1]: Removed slice Slice /system/systemd-fsck. Oct 28 04:50:48.632005 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 28 04:50:48.632076 systemd[1]: Stopped Apply Kernel Variables. Oct 28 04:50:48.633582 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Oct 28 04:50:48.634096 systemd[1]: Unmounted /run/torcx/unpack. Oct 28 04:50:48.635720 systemd[1]: Stopped target Preparation for Local File Systems. Oct 28 04:50:48.638647 systemd[1]: Stopped target Swaps. Oct 28 04:50:48.639458 systemd[1]: Reached target Unmount All Filesystems. Oct 28 04:50:48.640341 systemd[1]: lvm2-activation.service: Deactivated successfully. Oct 28 04:50:48.640590 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 28 04:50:48.641593 systemd[1]: Stopped target Local Encrypted Volumes. Oct 28 04:50:48.642496 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 28 04:50:48.642562 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 28 04:50:48.643657 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Oct 28 04:50:48.649461 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Oct 28 04:50:48.650660 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Oct 28 04:50:48.650742 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 28 04:50:48.651703 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 28 04:50:48.651764 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 28 04:50:48.652717 systemd[1]: systemd-sysusers.service: Deactivated successfully. Oct 28 04:50:48.652776 systemd[1]: Stopped Create System Users. Oct 28 04:50:48.653610 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Oct 28 04:50:48.653669 systemd[1]: Stopped Remount Root and Kernel File Systems. Oct 28 04:50:48.654725 systemd[1]: Reached target System Shutdown. Oct 28 04:50:48.655626 systemd[1]: Reached target Late Shutdown Services. Oct 28 04:50:48.656727 systemd[1]: systemd-reboot.service: Deactivated successfully. Oct 28 04:50:48.657007 systemd[1]: Finished System Reboot. Oct 28 04:50:48.657783 systemd[1]: Reached target System Reboot. Oct 28 04:50:48.658549 systemd[1]: Shutting down. Oct 28 04:50:48.659000 audit: BPF prog-id=58 op=UNLOAD Oct 28 04:50:48.659000 audit: BPF prog-id=57 op=UNLOAD Oct 28 04:50:48.661401 kernel: audit: type=1334 audit(1761627048.659:374): prog-id=58 op=UNLOAD Oct 28 04:50:48.662000 audit: BPF prog-id=63 op=UNLOAD Oct 28 04:50:48.662000 audit: BPF prog-id=62 op=UNLOAD Oct 28 04:50:48.662000 audit: BPF prog-id=61 op=UNLOAD Oct 28 04:50:48.663000 audit: BPF prog-id=66 op=UNLOAD Oct 28 04:50:48.663000 audit: BPF prog-id=65 op=UNLOAD Oct 28 04:50:48.663000 audit: BPF prog-id=64 op=UNLOAD Oct 28 04:50:48.704397 systemd-shutdown[1]: Syncing filesystems and block devices. Oct 28 04:50:48.711098 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Oct 28 04:50:48.711158 systemd-journald[800]: Journal stopped -- Reboot -- Oct 28 04:50:57.683029 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 28 04:50:57.683044 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 04:50:57.683053 kernel: efi: EFI v2.70 by EDK II Oct 28 04:50:57.683058 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 28 04:50:57.683063 kernel: efi: seeding entropy pool Oct 28 04:50:57.683068 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 28 04:50:57.683074 kernel: ACPI: Early table checksum verification disabled Oct 28 04:50:57.683134 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 28 04:50:57.683140 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 28 04:50:57.683145 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:57.683153 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:57.683158 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:57.683163 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:57.683168 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:57.683174 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:57.683180 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:57.683186 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:57.683192 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:50:57.683197 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 28 04:50:57.683202 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 28 04:50:57.683208 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 04:50:57.683213 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 28 04:50:57.683218 kernel: Zone ranges: Oct 28 04:50:57.683223 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 04:50:57.683228 kernel: DMA32 empty Oct 28 04:50:57.683233 kernel: Normal empty Oct 28 04:50:57.683238 kernel: Movable zone start for each node Oct 28 04:50:57.683244 kernel: Early memory node ranges Oct 28 04:50:57.683250 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 28 04:50:57.683255 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 28 04:50:57.683260 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 28 04:50:57.683265 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 28 04:50:57.683271 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 28 04:50:57.683276 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 28 04:50:57.683281 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 28 04:50:57.683286 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 28 04:50:57.683291 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 28 04:50:57.683296 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 28 04:50:57.683302 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 28 04:50:57.683307 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 04:50:57.683315 kernel: On node 0 totalpages: 643072 Oct 28 04:50:57.683321 kernel: DMA zone: 10048 pages used for memmap Oct 28 04:50:57.683326 kernel: DMA zone: 0 pages reserved Oct 28 04:50:57.683332 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 28 04:50:57.683337 kernel: psci: probing for conduit method from ACPI. Oct 28 04:50:57.683837 kernel: psci: PSCIv1.1 detected in firmware. Oct 28 04:50:57.683844 kernel: psci: Using standard PSCI v0.2 function IDs Oct 28 04:50:57.683850 kernel: psci: Trusted OS migration not required Oct 28 04:50:57.683860 kernel: psci: SMC Calling Convention v1.1 Oct 28 04:50:57.683865 kernel: ACPI: SRAT not present Oct 28 04:50:57.683871 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 28 04:50:57.683877 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 28 04:50:57.683882 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 28 04:50:57.683888 kernel: Detected PIPT I-cache on CPU0 Oct 28 04:50:57.683894 kernel: CPU features: detected: GIC system register CPU interface Oct 28 04:50:57.683900 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 28 04:50:57.683905 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 28 04:50:57.683911 kernel: CPU features: detected: Hardware dirty bit management Oct 28 04:50:57.683916 kernel: CPU features: detected: Spectre-v4 Oct 28 04:50:57.683924 kernel: CPU features: detected: ARM erratum 1418040 Oct 28 04:50:57.683930 kernel: CPU features: detected: Spectre-BHB Oct 28 04:50:57.683935 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 28 04:50:57.683941 kernel: Policy zone: DMA Oct 28 04:50:57.683948 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 04:50:57.683954 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 28 04:50:57.683960 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 28 04:50:57.683975 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 28 04:50:57.683981 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 28 04:50:57.683986 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 28 04:50:57.683992 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 28 04:50:57.683999 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 28 04:50:57.684005 kernel: rcu: RCU event tracing is enabled. Oct 28 04:50:57.684011 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 28 04:50:57.684018 kernel: Trampoline variant of Tasks RCU enabled. Oct 28 04:50:57.684024 kernel: Tracing variant of Tasks RCU enabled. Oct 28 04:50:57.684030 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 28 04:50:57.684037 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 28 04:50:57.684043 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 28 04:50:57.684048 kernel: GICv3: 256 SPIs implemented Oct 28 04:50:57.684054 kernel: GICv3: 0 Extended SPIs implemented Oct 28 04:50:57.684059 kernel: GICv3: Distributor has no Range Selector support Oct 28 04:50:57.684065 kernel: GICv3: 16 PPIs implemented Oct 28 04:50:57.684071 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 28 04:50:57.684124 kernel: ACPI: SRAT not present Oct 28 04:50:57.684132 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 28 04:50:57.684138 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 28 04:50:57.684144 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 28 04:50:57.684150 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 28 04:50:57.684155 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 28 04:50:57.684161 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 04:50:57.684167 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 28 04:50:57.684173 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 28 04:50:57.684179 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 28 04:50:57.684185 kernel: arm-pv: using stolen time PV Oct 28 04:50:57.684193 kernel: Console: colour dummy device 80x25 Oct 28 04:50:57.684199 kernel: ACPI: Core revision 20200925 Oct 28 04:50:57.684205 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 28 04:50:57.684211 kernel: pid_max: default: 32768 minimum: 301 Oct 28 04:50:57.684217 kernel: LSM: Security Framework initializing Oct 28 04:50:57.684223 kernel: SELinux: Initializing. Oct 28 04:50:57.684229 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 04:50:57.684235 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 04:50:57.684240 kernel: rcu: Hierarchical SRCU implementation. Oct 28 04:50:57.684248 kernel: Platform MSI: ITS@0x8080000 domain created Oct 28 04:50:57.684253 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 28 04:50:57.684259 kernel: Remapping and enabling EFI services. Oct 28 04:50:57.684265 kernel: smp: Bringing up secondary CPUs ... Oct 28 04:50:57.684271 kernel: Detected PIPT I-cache on CPU1 Oct 28 04:50:57.684277 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 28 04:50:57.684282 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 28 04:50:57.684288 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 04:50:57.684294 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 28 04:50:57.684300 kernel: Detected PIPT I-cache on CPU2 Oct 28 04:50:57.684307 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 28 04:50:57.684313 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 28 04:50:57.684319 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 04:50:57.684325 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 28 04:50:57.684331 kernel: Detected PIPT I-cache on CPU3 Oct 28 04:50:57.684377 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 28 04:50:57.684385 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 28 04:50:57.684393 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 04:50:57.684399 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 28 04:50:57.684411 kernel: smp: Brought up 1 node, 4 CPUs Oct 28 04:50:57.684417 kernel: SMP: Total of 4 processors activated. Oct 28 04:50:57.684423 kernel: CPU features: detected: Privileged Access Never Oct 28 04:50:57.684429 kernel: CPU features: detected: LSE atomic instructions Oct 28 04:50:57.684435 kernel: CPU features: detected: User Access Override Oct 28 04:50:57.684441 kernel: CPU features: detected: 32-bit EL0 Support Oct 28 04:50:57.684447 kernel: CPU features: detected: Common not Private translations Oct 28 04:50:57.684453 kernel: CPU features: detected: RAS Extension Support Oct 28 04:50:57.684461 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 28 04:50:57.684467 kernel: CPU features: detected: CRC32 instructions Oct 28 04:50:57.684473 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 28 04:50:57.684479 kernel: CPU: All CPU(s) started at EL1 Oct 28 04:50:57.684485 kernel: alternatives: patching kernel code Oct 28 04:50:57.684492 kernel: devtmpfs: initialized Oct 28 04:50:57.684498 kernel: KASLR enabled Oct 28 04:50:57.684505 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 28 04:50:57.684511 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 28 04:50:57.684517 kernel: pinctrl core: initialized pinctrl subsystem Oct 28 04:50:57.684523 kernel: SMBIOS 3.0.0 present. Oct 28 04:50:57.684529 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 28 04:50:57.684535 kernel: NET: Registered protocol family 16 Oct 28 04:50:57.684541 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 28 04:50:57.684548 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 28 04:50:57.684555 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 28 04:50:57.684561 kernel: audit: initializing netlink subsys (disabled) Oct 28 04:50:57.684568 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Oct 28 04:50:57.684574 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 28 04:50:57.684580 kernel: cpuidle: using governor menu Oct 28 04:50:57.684586 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 28 04:50:57.684592 kernel: ASID allocator initialised with 32768 entries Oct 28 04:50:57.684598 kernel: ACPI: bus type PCI registered Oct 28 04:50:57.684604 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 28 04:50:57.684611 kernel: Serial: AMBA PL011 UART driver Oct 28 04:50:57.684617 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 28 04:50:57.684624 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 28 04:50:57.684630 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 28 04:50:57.684636 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 28 04:50:57.684642 kernel: cryptd: max_cpu_qlen set to 1000 Oct 28 04:50:57.684648 kernel: ACPI: Added _OSI(Module Device) Oct 28 04:50:57.684654 kernel: ACPI: Added _OSI(Processor Device) Oct 28 04:50:57.684660 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 28 04:50:57.684667 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 28 04:50:57.684673 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 28 04:50:57.684679 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 28 04:50:57.684685 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 28 04:50:57.684691 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 28 04:50:57.684697 kernel: ACPI: Interpreter enabled Oct 28 04:50:57.684703 kernel: ACPI: Using GIC for interrupt routing Oct 28 04:50:57.684709 kernel: ACPI: MCFG table detected, 1 entries Oct 28 04:50:57.684715 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 28 04:50:57.684721 kernel: printk: console [ttyAMA0] enabled Oct 28 04:50:57.684729 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 28 04:50:57.684850 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 28 04:50:57.684993 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 28 04:50:57.685061 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 28 04:50:57.685177 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 28 04:50:57.685234 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 28 04:50:57.685242 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 28 04:50:57.685253 kernel: PCI host bridge to bus 0000:00 Oct 28 04:50:57.685313 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 28 04:50:57.685387 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 28 04:50:57.685459 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 28 04:50:57.685510 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 28 04:50:57.685578 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 28 04:50:57.685640 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 28 04:50:57.685701 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 28 04:50:57.685756 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 28 04:50:57.685811 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 04:50:57.685866 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 04:50:57.685920 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 28 04:50:57.685975 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 28 04:50:57.686024 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 28 04:50:57.686073 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 28 04:50:57.686187 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 28 04:50:57.686197 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 28 04:50:57.686203 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 28 04:50:57.686210 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 28 04:50:57.686216 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 28 04:50:57.686222 kernel: vgaarb: loaded Oct 28 04:50:57.686228 kernel: Registered efivars operations Oct 28 04:50:57.686234 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 28 04:50:57.686243 kernel: VFS: Disk quotas dquot_6.6.0 Oct 28 04:50:57.686249 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 28 04:50:57.686255 kernel: pnp: PnP ACPI init Oct 28 04:50:57.686321 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 28 04:50:57.686426 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 28 04:50:57.686437 kernel: pnp: PnP ACPI: found 1 devices Oct 28 04:50:57.686443 kernel: NET: Registered protocol family 2 Oct 28 04:50:57.686449 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 28 04:50:57.686459 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 28 04:50:57.686465 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 28 04:50:57.686471 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 28 04:50:57.686477 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 28 04:50:57.686483 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 04:50:57.686490 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 04:50:57.686496 kernel: NET: Registered protocol family 1 Oct 28 04:50:57.686502 kernel: PCI: CLS 0 bytes, default 64 Oct 28 04:50:57.686508 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 28 04:50:57.686515 kernel: kvm [1]: HYP mode not available Oct 28 04:50:57.686522 kernel: Initialise system trusted keyrings Oct 28 04:50:57.686528 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 28 04:50:57.686534 kernel: Key type asymmetric registered Oct 28 04:50:57.686540 kernel: Asymmetric key parser 'x509' registered Oct 28 04:50:57.686546 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 28 04:50:57.686552 kernel: io scheduler mq-deadline registered Oct 28 04:50:57.686558 kernel: io scheduler kyber registered Oct 28 04:50:57.686564 kernel: io scheduler bfq registered Oct 28 04:50:57.686571 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 28 04:50:57.686578 kernel: ACPI: Power Button [PWRB] Oct 28 04:50:57.686584 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 28 04:50:57.686641 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 28 04:50:57.686650 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 28 04:50:57.686656 kernel: msm_serial: driver initialized Oct 28 04:50:57.686662 kernel: random: fast init done Oct 28 04:50:57.686668 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 28 04:50:57.686674 kernel: random: crng init done Oct 28 04:50:57.686680 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 28 04:50:57.686688 kernel: thunder_xcv, ver 1.0 Oct 28 04:50:57.686694 kernel: thunder_bgx, ver 1.0 Oct 28 04:50:57.686700 kernel: nicpf, ver 1.0 Oct 28 04:50:57.686706 kernel: nicvf, ver 1.0 Oct 28 04:50:57.686775 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 28 04:50:57.686826 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-28T04:50:57 UTC (1761627057) Oct 28 04:50:57.686835 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 28 04:50:57.686841 kernel: NET: Registered protocol family 10 Oct 28 04:50:57.686848 kernel: Segment Routing with IPv6 Oct 28 04:50:57.686855 kernel: NET: Registered protocol family 17 Oct 28 04:50:57.686861 kernel: Key type dns_resolver registered Oct 28 04:50:57.686867 kernel: registered taskstats version 1 Oct 28 04:50:57.686873 kernel: Loading compiled-in X.509 certificates Oct 28 04:50:57.686879 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 28 04:50:57.686885 kernel: Key type ._fscrypt registered Oct 28 04:50:57.686891 kernel: Key type .fscrypt registered Oct 28 04:50:57.686897 kernel: Key type fscrypt-provisioning registered Oct 28 04:50:57.686905 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 28 04:50:57.686911 kernel: ima: Allocated hash algorithm: sha1 Oct 28 04:50:57.686917 kernel: ima: No architecture policies found Oct 28 04:50:57.686923 kernel: Freeing unused kernel memory: 34240K Oct 28 04:50:57.686929 kernel: Run /init as init process Oct 28 04:50:57.686935 kernel: with arguments: Oct 28 04:50:57.686940 kernel: /init Oct 28 04:50:57.686946 kernel: with environment: Oct 28 04:50:57.686952 kernel: HOME=/ Oct 28 04:50:57.686958 kernel: TERM=linux Oct 28 04:50:57.686965 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 28 04:50:57.686973 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 04:50:57.686982 systemd[1]: Detected virtualization kvm. Oct 28 04:50:57.686989 systemd[1]: Detected architecture arm64. Oct 28 04:50:57.686995 systemd[1]: Running in initial RAM disk. Oct 28 04:50:57.687001 systemd[1]: No hostname configured, using default hostname. Oct 28 04:50:57.687008 systemd[1]: Hostname set to . Oct 28 04:50:57.687016 systemd[1]: Initializing machine ID from VM UUID. Oct 28 04:50:57.687022 systemd[1]: Queued start job for default target Initrd Default Target. Oct 28 04:50:57.687029 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 28 04:50:57.687036 systemd[1]: Reached target Local Encrypted Volumes. Oct 28 04:50:57.687042 systemd[1]: Reached target Path Units. Oct 28 04:50:57.687048 systemd[1]: Reached target Slice Units. Oct 28 04:50:57.687055 systemd[1]: Reached target Swaps. Oct 28 04:50:57.687061 systemd[1]: Reached target Timer Units. Oct 28 04:50:57.687069 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 28 04:50:57.687076 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 28 04:50:57.687136 systemd[1]: Listening on Journal Audit Socket. Oct 28 04:50:57.687143 systemd[1]: Listening on Journal Socket (/dev/log). Oct 28 04:50:57.687149 systemd[1]: Listening on Journal Socket. Oct 28 04:50:57.687156 systemd[1]: Listening on udev Control Socket. Oct 28 04:50:57.687162 systemd[1]: Listening on udev Kernel Socket. Oct 28 04:50:57.687168 systemd[1]: Reached target Socket Units. Oct 28 04:50:57.687175 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 28 04:50:57.687184 systemd[1]: Starting Create List of Static Device Nodes... Oct 28 04:50:57.687190 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 28 04:50:57.687197 systemd[1]: Starting Journal Service... Oct 28 04:50:57.687203 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 28 04:50:57.687210 systemd[1]: Starting Apply Kernel Variables... Oct 28 04:50:57.687216 systemd[1]: Starting Setup Virtual Console... Oct 28 04:50:57.687223 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 28 04:50:57.687229 kernel: SCSI subsystem initialized Oct 28 04:50:57.687235 kernel: Loading iSCSI transport class v2.0-870. Oct 28 04:50:57.687242 systemd[1]: Finished Create List of Static Device Nodes. Oct 28 04:50:57.687249 systemd[1]: Finished Apply Kernel Variables. Oct 28 04:50:57.687258 systemd-journald[270]: Journal started Oct 28 04:50:57.687298 systemd-journald[270]: Runtime Journal (/run/log/journal/334fdacc6f6b44bb87b75a7e6890f8f7) is 6.0M, max 48.5M, 42.4M free. Oct 28 04:50:57.688912 systemd[1]: Started Journal Service. Oct 28 04:50:57.690012 systemd[1]: Finished Setup Virtual Console. Oct 28 04:50:57.691475 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 28 04:50:57.692939 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 28 04:50:57.696157 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 28 04:50:57.708883 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 28 04:50:57.710623 systemd[1]: Starting dracut cmdline hook... Oct 28 04:50:57.719452 dracut-cmdline[290]: dracut-dracut-053 Oct 28 04:50:57.721790 dracut-cmdline[290]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 28 04:50:57.778366 kernel: iscsi: registered transport (tcp) Oct 28 04:50:57.790367 kernel: iscsi: registered transport (qla4xxx) Oct 28 04:50:57.790383 kernel: QLogic iSCSI HBA Driver Oct 28 04:50:57.799778 systemd[1]: Finished dracut cmdline hook. Oct 28 04:50:57.801209 systemd[1]: Starting dracut pre-udev hook... Oct 28 04:50:57.802521 systemd[1]: Starting Open-iSCSI... Oct 28 04:50:57.804714 iscsid[376]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 28 04:50:57.804714 iscsid[376]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 28 04:50:57.804714 iscsid[376]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 28 04:50:57.804714 iscsid[376]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 28 04:50:57.804714 iscsid[376]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 28 04:50:57.816262 iscsid[376]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 28 04:50:57.818647 kernel: device-mapper: uevent: version 1.0.3 Oct 28 04:50:57.818664 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 28 04:50:57.808205 systemd[1]: Started Open-iSCSI. Oct 28 04:50:57.852370 kernel: raid6: neonx8 gen() 13735 MB/s Oct 28 04:50:57.869362 kernel: raid6: neonx8 xor() 10717 MB/s Oct 28 04:50:57.886364 kernel: raid6: neonx4 gen() 13552 MB/s Oct 28 04:50:57.903373 kernel: raid6: neonx4 xor() 11465 MB/s Oct 28 04:50:57.920366 kernel: raid6: neonx2 gen() 12941 MB/s Oct 28 04:50:57.937378 kernel: raid6: neonx2 xor() 9837 MB/s Oct 28 04:50:57.954384 kernel: raid6: neonx1 gen() 10845 MB/s Oct 28 04:50:57.971371 kernel: raid6: neonx1 xor() 8707 MB/s Oct 28 04:50:57.988372 kernel: raid6: int64x8 gen() 6777 MB/s Oct 28 04:50:58.005369 kernel: raid6: int64x8 xor() 3780 MB/s Oct 28 04:50:58.022371 kernel: raid6: int64x4 gen() 6877 MB/s Oct 28 04:50:58.039368 kernel: raid6: int64x4 xor() 3812 MB/s Oct 28 04:50:58.056369 kernel: raid6: int64x2 gen() 6158 MB/s Oct 28 04:50:58.073366 kernel: raid6: int64x2 xor() 3306 MB/s Oct 28 04:50:58.090369 kernel: raid6: int64x1 gen() 4986 MB/s Oct 28 04:50:58.107608 kernel: raid6: int64x1 xor() 2589 MB/s Oct 28 04:50:58.107639 kernel: raid6: using algorithm neonx8 gen() 13735 MB/s Oct 28 04:50:58.107656 kernel: raid6: .... xor() 10717 MB/s, rmw enabled Oct 28 04:50:58.108886 kernel: raid6: using neon recovery algorithm Oct 28 04:50:58.118369 kernel: xor: measuring software checksum speed Oct 28 04:50:58.120491 kernel: 8regs : 17174 MB/sec Oct 28 04:50:58.120503 kernel: 32regs : 20760 MB/sec Oct 28 04:50:58.121394 kernel: arm64_neon : 29173 MB/sec Oct 28 04:50:58.121430 kernel: xor: using function: arm64_neon (29173 MB/sec) Oct 28 04:50:58.167367 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 28 04:50:58.177588 systemd[1]: Finished dracut pre-udev hook. Oct 28 04:50:58.181641 kernel: audit: type=1334 audit(1761627058.178:2): prog-id=6 op=LOAD Oct 28 04:50:58.181659 kernel: audit: type=1334 audit(1761627058.179:3): prog-id=7 op=LOAD Oct 28 04:50:58.181668 kernel: audit: type=1334 audit(1761627058.180:4): prog-id=8 op=LOAD Oct 28 04:50:58.178000 audit: BPF prog-id=6 op=LOAD Oct 28 04:50:58.179000 audit: BPF prog-id=7 op=LOAD Oct 28 04:50:58.180000 audit: BPF prog-id=8 op=LOAD Oct 28 04:50:58.181148 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 28 04:50:58.192788 systemd-udevd[408]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 28 04:50:58.194478 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 28 04:50:58.196245 systemd[1]: Starting dracut pre-trigger hook... Oct 28 04:50:58.208381 dracut-pre-trigger[415]: rd.md=0: removing MD RAID activation Oct 28 04:50:58.230218 systemd[1]: Finished dracut pre-trigger hook. Oct 28 04:50:58.231729 systemd[1]: Starting Coldplug All udev Devices... Oct 28 04:50:58.241136 systemd-udevd[408]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 28 04:50:58.260809 systemd[1]: Finished Coldplug All udev Devices. Oct 28 04:50:58.262324 systemd[1]: Starting dracut initqueue hook... Oct 28 04:50:58.272791 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 28 04:50:58.272941 kernel: vda: detected capacity change from 0 to 4756340736 Oct 28 04:50:58.286940 systemd-udevd[454]: Using default interface naming scheme 'v249'. Oct 28 04:50:58.291372 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 04:50:58.312803 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 28 04:50:58.314798 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (457) Oct 28 04:50:58.313942 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 28 04:50:58.317605 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 28 04:50:58.325419 systemd[1]: Reached target Initrd Root Device. Oct 28 04:50:58.326882 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 28 04:50:58.328125 systemd[1]: Finished dracut initqueue hook. Oct 28 04:50:58.329819 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 28 04:50:58.329918 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 28 04:50:58.331552 systemd[1]: Reached target Preparation for Local File Systems. Oct 28 04:50:58.333169 systemd[1]: Reached target Preparation for Remote File Systems. Oct 28 04:50:58.335058 systemd[1]: Reached target Remote Encrypted Volumes. Oct 28 04:50:58.336765 systemd[1]: Reached target Remote File Systems. Oct 28 04:50:58.338993 systemd[1]: Starting dracut pre-mount hook... Oct 28 04:50:58.340460 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 28 04:50:58.347975 systemd[1]: Finished dracut pre-mount hook. Oct 28 04:50:58.349517 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 28 04:50:58.355397 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 28 04:50:58.358160 systemd-fsck[503]: ROOT: clean, 640/553792 files, 39961/553472 blocks Oct 28 04:50:58.361556 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 28 04:50:58.363545 systemd[1]: Mounting /sysroot... Oct 28 04:50:58.376055 systemd[1]: Found device /dev/mapper/usr. Oct 28 04:50:58.378112 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 28 04:50:58.380138 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 28 04:50:58.392769 systemd-fsck[517]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 28 04:50:58.392769 systemd-fsck[517]: You must have r/w access to the filesystem or be root Oct 28 04:50:58.393133 systemd-fsck[512]: fsck failed with exit status 8. Oct 28 04:50:58.393137 systemd-fsck[512]: Ignoring error. Oct 28 04:50:58.393752 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 28 04:50:58.395848 systemd[1]: Mounting /sysusr/usr... Oct 28 04:50:58.411765 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 28 04:50:58.411804 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 28 04:50:58.411937 systemd[1]: Mounted /sysroot. Oct 28 04:50:58.412723 systemd[1]: Reached target Initrd Root File System. Oct 28 04:50:58.414598 systemd[1]: Starting Reload Configuration from the Real Root... Oct 28 04:50:58.418760 systemd[1]: Reloading. Oct 28 04:50:58.426369 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 28 04:50:58.426000 audit: BPF prog-id=3 op=UNLOAD Oct 28 04:50:58.426000 audit: BPF prog-id=6 op=UNLOAD Oct 28 04:50:58.428806 kernel: audit: type=1334 audit(1761627058.426:5): prog-id=3 op=UNLOAD Oct 28 04:50:58.428830 kernel: audit: type=1334 audit(1761627058.426:6): prog-id=6 op=UNLOAD Oct 28 04:50:58.501000 audit: BPF prog-id=9 op=LOAD Oct 28 04:50:58.502000 audit: BPF prog-id=10 op=LOAD Oct 28 04:50:58.504182 kernel: audit: type=1334 audit(1761627058.501:7): prog-id=9 op=LOAD Oct 28 04:50:58.504206 kernel: audit: type=1334 audit(1761627058.502:8): prog-id=10 op=LOAD Oct 28 04:50:58.504215 kernel: audit: type=1334 audit(1761627058.503:9): prog-id=11 op=LOAD Oct 28 04:50:58.503000 audit: BPF prog-id=11 op=LOAD Oct 28 04:50:58.503000 audit: BPF prog-id=4 op=UNLOAD Oct 28 04:50:58.505995 kernel: audit: type=1334 audit(1761627058.503:10): prog-id=4 op=UNLOAD Oct 28 04:50:58.503000 audit: BPF prog-id=5 op=UNLOAD Oct 28 04:50:58.504000 audit: BPF prog-id=12 op=LOAD Oct 28 04:50:58.505000 audit: BPF prog-id=13 op=LOAD Oct 28 04:50:58.505000 audit: BPF prog-id=14 op=LOAD Oct 28 04:50:58.505000 audit: BPF prog-id=7 op=UNLOAD Oct 28 04:50:58.505000 audit: BPF prog-id=8 op=UNLOAD Oct 28 04:50:58.507188 systemd[1]: Mounted /sysusr/usr. Oct 28 04:50:58.509467 systemd[1]: Reached target Local File Systems. Oct 28 04:50:58.510308 systemd[1]: Reached target System Initialization. Oct 28 04:50:58.511879 systemd[1]: Reached target Basic System. Oct 28 04:50:58.514559 systemd[1]: Mounting /sysroot/usr... Oct 28 04:50:58.516949 systemd[1]: Mounted /sysroot/usr. Oct 28 04:50:58.526119 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 28 04:50:58.526250 systemd[1]: Finished Reload Configuration from the Real Root. Oct 28 04:50:58.528271 systemd[1]: Reached target Initrd File Systems. Oct 28 04:50:58.529883 systemd[1]: Reached target Initrd Default Target. Oct 28 04:50:58.531438 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 28 04:50:58.532117 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 28 04:50:58.542395 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 28 04:50:58.544032 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 28 04:50:58.549870 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 28 04:50:58.550980 systemd[1]: Stopped target Timer Units. Oct 28 04:50:58.552438 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 28 04:50:58.552553 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 28 04:50:58.554024 systemd[1]: Stopped target Initrd Default Target. Oct 28 04:50:58.555693 systemd[1]: Stopped target Basic System. Oct 28 04:50:58.557205 systemd[1]: Stopped target Initrd Root Device. Oct 28 04:50:58.558719 systemd[1]: Stopped target Path Units. Oct 28 04:50:58.560022 systemd[1]: Stopped target Remote File Systems. Oct 28 04:50:58.561543 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 28 04:50:58.563135 systemd[1]: Stopped target Slice Units. Oct 28 04:50:58.564587 systemd[1]: Stopped target Socket Units. Oct 28 04:50:58.566182 systemd[1]: Stopped target System Initialization. Oct 28 04:50:58.567599 systemd[1]: Stopped target Local File Systems. Oct 28 04:50:58.568983 systemd[1]: Stopped target Preparation for Local File Systems. Oct 28 04:50:58.570620 systemd[1]: Stopped target Swaps. Oct 28 04:50:58.571912 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 28 04:50:58.572021 systemd[1]: Stopped dracut pre-mount hook. Oct 28 04:50:58.573611 systemd[1]: Stopped target Local Encrypted Volumes. Oct 28 04:50:58.574909 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 28 04:50:58.576381 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 28 04:50:58.583157 iscsid[376]: iscsid shutting down. Oct 28 04:50:58.577595 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 28 04:50:58.577689 systemd[1]: Stopped dracut initqueue hook. Oct 28 04:50:58.579205 systemd[1]: Stopping Open-iSCSI... Oct 28 04:50:58.582145 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 28 04:50:58.582261 systemd[1]: Stopped Apply Kernel Variables. Oct 28 04:50:58.583995 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 28 04:50:58.584085 systemd[1]: Stopped Coldplug All udev Devices. Oct 28 04:50:58.585287 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 28 04:50:58.585393 systemd[1]: Stopped dracut pre-trigger hook. Oct 28 04:50:58.587129 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 28 04:50:58.591996 systemd[1]: iscsid.service: Deactivated successfully. Oct 28 04:50:58.592116 systemd[1]: Stopped Open-iSCSI. Oct 28 04:50:58.593887 systemd[1]: iscsid.socket: Deactivated successfully. Oct 28 04:50:58.593948 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 28 04:50:58.595043 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 28 04:50:58.597291 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 28 04:50:58.597432 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 28 04:50:58.613000 audit: BPF prog-id=12 op=UNLOAD Oct 28 04:50:58.598837 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 28 04:50:58.598953 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 28 04:50:58.600884 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 28 04:50:58.600997 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 28 04:50:58.602938 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 28 04:50:58.602991 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 28 04:50:58.604139 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 28 04:50:58.604181 systemd[1]: Closed udev Control Socket. Oct 28 04:50:58.605621 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 28 04:50:58.605655 systemd[1]: Closed udev Kernel Socket. Oct 28 04:50:58.606818 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 28 04:50:58.606864 systemd[1]: Stopped dracut pre-udev hook. Oct 28 04:50:58.608518 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 28 04:50:58.608571 systemd[1]: Stopped dracut cmdline hook. Oct 28 04:50:58.609892 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 28 04:50:58.632000 audit: BPF prog-id=9 op=UNLOAD Oct 28 04:50:58.609936 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 28 04:50:58.612200 systemd[1]: Starting Cleanup udev Database... Oct 28 04:50:58.613105 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 28 04:50:58.614672 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 28 04:50:58.614732 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 28 04:50:58.616517 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 28 04:50:58.616561 systemd[1]: Stopped Create List of Static Device Nodes. Oct 28 04:50:58.618297 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 04:50:58.618350 systemd[1]: Stopped Setup Virtual Console. Oct 28 04:50:58.620205 systemd[1]: rngd.service: Deactivated successfully. Oct 28 04:50:58.620317 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 28 04:50:58.621934 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 28 04:50:58.622038 systemd[1]: Finished Cleanup udev Database. Oct 28 04:50:58.623378 systemd[1]: Reached target Switch Root. Oct 28 04:50:58.625307 systemd[1]: Starting Switch Root... Oct 28 04:50:58.631054 systemd[1]: Switching root. Oct 28 04:50:58.648419 systemd-journald[270]: Journal stopped Oct 28 04:51:00.743635 systemd-journald[270]: Received SIGTERM from PID 1 (n/a). Oct 28 04:51:00.743681 kernel: SELinux: policy capability network_peer_controls=1 Oct 28 04:51:00.743692 kernel: SELinux: policy capability open_perms=1 Oct 28 04:51:00.743702 kernel: SELinux: policy capability extended_socket_class=1 Oct 28 04:51:00.743716 kernel: SELinux: policy capability always_check_network=0 Oct 28 04:51:00.743735 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 28 04:51:00.743748 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 28 04:51:00.743760 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 28 04:51:00.743770 systemd[1]: Successfully loaded SELinux policy in 31.550ms. Oct 28 04:51:00.743785 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.167ms. Oct 28 04:51:00.743815 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 04:51:00.743827 systemd[1]: Detected virtualization kvm. Oct 28 04:51:00.743837 systemd[1]: Detected architecture arm64. Oct 28 04:51:00.743916 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 04:51:00.743934 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 28 04:51:00.743945 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 28 04:51:00.743955 systemd[1]: Stopped Switch Root. Oct 28 04:51:00.743965 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 28 04:51:00.743975 systemd[1]: Created slice Slice /system/addon-config. Oct 28 04:51:00.743984 systemd[1]: Created slice Slice /system/addon-run. Oct 28 04:51:00.743994 systemd[1]: Created slice Slice /system/getty. Oct 28 04:51:00.744005 systemd[1]: Created slice Slice /system/modprobe. Oct 28 04:51:00.744016 systemd[1]: Created slice Slice /system/serial-getty. Oct 28 04:51:00.744026 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 28 04:51:00.744038 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 28 04:51:00.744048 systemd[1]: Created slice User and Session Slice. Oct 28 04:51:00.744058 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 28 04:51:00.744069 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 28 04:51:00.744086 systemd[1]: Set up automount Boot partition Automount Point. Oct 28 04:51:00.744096 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 28 04:51:00.744108 systemd[1]: Stopped target Switch Root. Oct 28 04:51:00.744118 systemd[1]: Stopped target Initrd File Systems. Oct 28 04:51:00.744128 systemd[1]: Stopped target Initrd Root File System. Oct 28 04:51:00.744138 systemd[1]: Reached target Remote Encrypted Volumes. Oct 28 04:51:00.744148 systemd[1]: Reached target Remote File Systems. Oct 28 04:51:00.744157 systemd[1]: Reached target Slice Units. Oct 28 04:51:00.744167 systemd[1]: Reached target Swaps. Oct 28 04:51:00.744177 systemd[1]: Reached target Verify torcx succeeded. Oct 28 04:51:00.744187 systemd[1]: Reached target Local Verity Protected Volumes. Oct 28 04:51:00.744197 systemd[1]: Listening on Process Core Dump Socket. Oct 28 04:51:00.744207 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 28 04:51:00.744217 systemd[1]: Listening on Network Service Netlink Socket. Oct 28 04:51:00.744227 systemd[1]: Listening on udev Control Socket. Oct 28 04:51:00.744236 systemd[1]: Listening on udev Kernel Socket. Oct 28 04:51:00.744246 systemd[1]: Mounting Huge Pages File System... Oct 28 04:51:00.744255 systemd[1]: Mounting POSIX Message Queue File System... Oct 28 04:51:00.744265 systemd[1]: Mounting External Media Directory... Oct 28 04:51:00.744274 systemd[1]: Mounting Kernel Debug File System... Oct 28 04:51:00.744284 systemd[1]: Mounting Kernel Trace File System... Oct 28 04:51:00.744296 systemd[1]: Mounting Temporary Directory /tmp... Oct 28 04:51:00.744306 systemd[1]: Starting Create missing system files... Oct 28 04:51:00.744316 systemd[1]: Starting Create List of Static Device Nodes... Oct 28 04:51:00.744325 systemd[1]: Starting Load Kernel Module configfs... Oct 28 04:51:00.744335 systemd[1]: Starting Load Kernel Module drm... Oct 28 04:51:00.744352 systemd[1]: Starting Load Kernel Module fuse... Oct 28 04:51:00.744370 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 28 04:51:00.744380 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 28 04:51:00.744397 systemd[1]: Stopped File System Check on Root Device. Oct 28 04:51:00.744408 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 28 04:51:00.744420 systemd[1]: Stopped systemd-fsck-usr.service. Oct 28 04:51:00.744429 kernel: fuse: init (API version 7.32) Oct 28 04:51:00.744439 systemd[1]: Stopped Journal Service. Oct 28 04:51:00.744448 systemd[1]: Starting Journal Service... Oct 28 04:51:00.744458 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 28 04:51:00.744468 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 28 04:51:00.744478 systemd[1]: Starting Apply Kernel Variables... Oct 28 04:51:00.744487 systemd[1]: Starting Coldplug All udev Devices... Oct 28 04:51:00.744498 systemd[1]: verity-setup.service: Deactivated successfully. Oct 28 04:51:00.744509 systemd[1]: Stopped verity-setup.service. Oct 28 04:51:00.744520 systemd-journald[696]: Journal started Oct 28 04:51:00.744560 systemd-journald[696]: Runtime Journal (/run/log/journal/334fdacc6f6b44bb87b75a7e6890f8f7) is 6.0M, max 48.5M, 42.4M free. Oct 28 04:50:58.718000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 28 04:50:58.745000 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 04:50:58.745000 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 04:50:58.745000 audit: BPF prog-id=15 op=LOAD Oct 28 04:50:58.745000 audit: BPF prog-id=15 op=UNLOAD Oct 28 04:50:58.745000 audit: BPF prog-id=16 op=LOAD Oct 28 04:50:58.745000 audit: BPF prog-id=16 op=UNLOAD Oct 28 04:50:58.785000 audit[647]: AVC avc: denied { associate } for pid=647 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 04:51:00.608000 audit: BPF prog-id=17 op=LOAD Oct 28 04:51:00.608000 audit: BPF prog-id=18 op=LOAD Oct 28 04:51:00.608000 audit: BPF prog-id=19 op=LOAD Oct 28 04:51:00.608000 audit: BPF prog-id=10 op=UNLOAD Oct 28 04:51:00.608000 audit: BPF prog-id=11 op=UNLOAD Oct 28 04:51:00.608000 audit: BPF prog-id=20 op=LOAD Oct 28 04:51:00.608000 audit: BPF prog-id=17 op=UNLOAD Oct 28 04:51:00.608000 audit: BPF prog-id=21 op=LOAD Oct 28 04:51:00.609000 audit: BPF prog-id=22 op=LOAD Oct 28 04:51:00.609000 audit: BPF prog-id=18 op=UNLOAD Oct 28 04:51:00.609000 audit: BPF prog-id=19 op=UNLOAD Oct 28 04:51:00.621000 audit: BPF prog-id=20 op=UNLOAD Oct 28 04:51:00.728000 audit: BPF prog-id=23 op=LOAD Oct 28 04:51:00.728000 audit: BPF prog-id=24 op=LOAD Oct 28 04:51:00.728000 audit: BPF prog-id=25 op=LOAD Oct 28 04:51:00.728000 audit: BPF prog-id=21 op=UNLOAD Oct 28 04:51:00.728000 audit: BPF prog-id=22 op=UNLOAD Oct 28 04:51:00.741000 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 04:51:00.598176 systemd[1]: Queued start job for default target Multi-User System. Oct 28 04:50:58.781811 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" 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 04:51:00.609872 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 28 04:50:58.782081 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 28 04:50:58.782099 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 28 04:50:58.782429 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 28 04:50:58.782443 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 28 04:50:58.782481 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 28 04:50:58.782494 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 28 04:50:58.782828 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 28 04:50:58.782866 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 28 04:50:58.782882 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 28 04:50:58.785286 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" 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 04:50:58.785320 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" 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 04:50:58.785363 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" 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 04:50:58.785380 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" 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 04:50:58.785497 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" 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 04:50:58.785515 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:50:58Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 28 04:51:00.511524 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:51:00Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 04:51:00.511827 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:51:00Z" 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 04:51:00.511931 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:51:00Z" 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 04:51:00.512057 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:51:00Z" 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 04:51:00.512149 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:51:00Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 28 04:51:00.512205 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-28T04:51:00Z" 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 04:51:00.746360 systemd[1]: Started Journal Service. Oct 28 04:51:00.746829 systemd[1]: Mounted Huge Pages File System. Oct 28 04:51:00.747839 systemd[1]: Mounted POSIX Message Queue File System. Oct 28 04:51:00.748969 systemd[1]: Mounted External Media Directory. Oct 28 04:51:00.749998 systemd[1]: Mounted Kernel Debug File System. Oct 28 04:51:00.751066 systemd[1]: Mounted Kernel Trace File System. Oct 28 04:51:00.752133 systemd[1]: Mounted Temporary Directory /tmp. Oct 28 04:51:00.753504 systemd[1]: Finished Create missing system files. Oct 28 04:51:00.754722 systemd[1]: Finished Create List of Static Device Nodes. Oct 28 04:51:00.755947 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 28 04:51:00.756134 systemd[1]: Finished Load Kernel Module configfs. Oct 28 04:51:00.757254 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 28 04:51:00.757515 systemd[1]: Finished Load Kernel Module drm. Oct 28 04:51:00.758580 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 28 04:51:00.758768 systemd[1]: Finished Load Kernel Module fuse. Oct 28 04:51:00.759952 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 28 04:51:00.761399 systemd[1]: Finished Apply Kernel Variables. Oct 28 04:51:00.778309 systemd[1]: Mounting FUSE Control File System... Oct 28 04:51:00.780173 systemd[1]: Mounting Kernel Configuration File System... Oct 28 04:51:00.781132 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 28 04:51:00.782172 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 28 04:51:00.783212 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 28 04:51:00.784547 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 28 04:51:00.785586 systemd[1]: Starting Load/Save Random Seed... Oct 28 04:51:00.788903 systemd-journald[696]: Time spent on flushing to /var/log/journal/334fdacc6f6b44bb87b75a7e6890f8f7 is 12.689ms for 660 entries. Oct 28 04:51:00.788903 systemd-journald[696]: System Journal (/var/log/journal/334fdacc6f6b44bb87b75a7e6890f8f7) is 8.0M, max 203.0M, 195.0M free. Oct 28 04:51:00.811000 audit: BPF prog-id=26 op=LOAD Oct 28 04:51:00.811000 audit: BPF prog-id=27 op=LOAD Oct 28 04:51:00.811000 audit: BPF prog-id=28 op=LOAD Oct 28 04:51:00.811000 audit: BPF prog-id=13 op=UNLOAD Oct 28 04:51:00.811000 audit: BPF prog-id=14 op=UNLOAD Oct 28 04:51:00.786497 systemd[1]: Condition check resulted in Create System Users being skipped. Oct 28 04:51:00.787509 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 28 04:51:00.789406 systemd[1]: Mounted FUSE Control File System. Oct 28 04:51:00.791386 systemd[1]: Mounted Kernel Configuration File System. Oct 28 04:51:00.794412 systemd[1]: Finished Load/Save Random Seed. Oct 28 04:51:00.795750 systemd[1]: Condition check resulted in First Boot Complete being skipped. Oct 28 04:51:00.797714 systemd[1]: Finished Coldplug All udev Devices. Oct 28 04:51:00.808419 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 28 04:51:00.809960 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 28 04:51:00.812571 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 28 04:51:00.814291 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 28 04:51:00.835866 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 28 04:51:00.837000 audit: BPF prog-id=29 op=LOAD Oct 28 04:51:00.838894 systemd[1]: Starting Network Configuration... Oct 28 04:51:00.868452 systemd-networkd[719]: lo: Link UP Oct 28 04:51:00.868461 systemd-networkd[719]: lo: Gained carrier Oct 28 04:51:00.868766 systemd-networkd[719]: Enumeration completed Oct 28 04:51:00.868848 systemd[1]: Started Network Configuration. Oct 28 04:51:00.879984 systemd-udevd[728]: Using default interface naming scheme 'v249'. Oct 28 04:51:00.899203 systemd-networkd[719]: eth0: Link UP Oct 28 04:51:00.911406 systemd-networkd[719]: eth0: Gained carrier Oct 28 04:51:00.914097 udevadm[709]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 28 04:51:00.917496 systemd-networkd[719]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 28 04:51:00.934516 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 28 04:51:00.952272 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 28 04:51:00.961200 lvm[738]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 28 04:51:00.989439 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 28 04:51:00.990569 systemd[1]: Reached target Local Encrypted Volumes. Oct 28 04:51:01.001272 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 28 04:51:01.004669 lvm[739]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 28 04:51:01.031433 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 28 04:51:01.032532 systemd[1]: Reached target Preparation for Local File Systems. Oct 28 04:51:01.033600 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 28 04:51:01.033624 systemd[1]: Reached target Containers. Oct 28 04:51:01.043644 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 28 04:51:01.055783 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 28 04:51:01.058029 systemd[1]: Mounting /usr/share/oem... Oct 28 04:51:01.064356 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 28 04:51:01.064425 kernel: BTRFS info (device vda6): has skinny extents Oct 28 04:51:01.066367 systemd[1]: Mounted /usr/share/oem. Oct 28 04:51:01.067163 systemd[1]: Reached target Local File Systems. Oct 28 04:51:01.068161 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Oct 28 04:51:01.068528 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 28 04:51:01.068562 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 28 04:51:01.068592 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 28 04:51:01.078242 systemd[1]: Starting Create Volatile Files and Directories... Oct 28 04:51:01.084257 systemd-tmpfiles[762]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 28 04:51:01.085313 systemd-tmpfiles[762]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 28 04:51:01.113618 systemd-tmpfiles[762]: Detected autofs mount point /boot during canonicalization of /boot. Oct 28 04:51:01.113629 systemd-tmpfiles[762]: Skipping /boot Oct 28 04:51:01.119201 systemd-tmpfiles[762]: Detected autofs mount point /boot during canonicalization of /boot. Oct 28 04:51:01.119219 systemd-tmpfiles[762]: Skipping /boot Oct 28 04:51:01.150765 systemd[1]: Finished Create Volatile Files and Directories. Oct 28 04:51:01.162294 systemd[1]: Starting Load Security Auditing Rules... Oct 28 04:51:01.164210 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 28 04:51:01.165499 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Oct 28 04:51:01.165000 audit: BPF prog-id=30 op=LOAD Oct 28 04:51:01.167008 systemd[1]: Starting Network Name Resolution... Oct 28 04:51:01.168000 audit: BPF prog-id=31 op=LOAD Oct 28 04:51:01.169519 systemd[1]: Starting Network Time Synchronization... Oct 28 04:51:01.170432 systemd[1]: Condition check resulted in Update is Completed being skipped. Oct 28 04:51:01.171632 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 28 04:51:01.173062 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 28 04:51:01.174492 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 28 04:51:01.180018 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 28 04:51:01.191000 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 04:51:01.191637 augenrules[783]: No rules Oct 28 04:51:01.192618 systemd[1]: Finished Load Security Auditing Rules. Oct 28 04:50:59.745097 systemd-timesyncd[772]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 28 04:50:59.745524 systemd-resolved[766]: Positive Trust Anchors: Oct 28 04:50:59.745531 systemd-resolved[766]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 04:50:59.745540 systemd[1]: Started Network Time Synchronization. Oct 28 04:50:59.745558 systemd-resolved[766]: 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 04:50:59.746811 systemd-resolved[766]: Defaulting to hostname 'linux'. Oct 28 04:50:59.746882 systemd[1]: Reached target System Initialization. Oct 28 04:50:59.748070 systemd[1]: Started Watch for update engine configuration changes. Oct 28 04:50:59.750996 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 28 04:50:59.752269 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 28 04:50:59.753373 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 28 04:50:59.753409 systemd[1]: Reached target Path Units. Oct 28 04:50:59.754195 systemd[1]: Reached target System Time Set. Oct 28 04:50:59.755422 systemd[1]: Started Daily Log Rotation. Oct 28 04:50:59.756267 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 28 04:50:59.757458 systemd[1]: Reached target Timer Units. Oct 28 04:50:59.758573 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 28 04:50:59.768979 systemd[1]: Starting Docker Socket for the API... Oct 28 04:50:59.772028 systemd[1]: Listening on OpenSSH Server Socket. Oct 28 04:50:59.773316 systemd[1]: Started Network Name Resolution. Oct 28 04:50:59.774342 systemd[1]: Listening on Docker Socket for the API. Oct 28 04:50:59.775366 systemd[1]: Reached target Network. Oct 28 04:50:59.776103 systemd[1]: Reached target Host and Network Name Lookups. Oct 28 04:50:59.777132 systemd[1]: Reached target Socket Units. Oct 28 04:50:59.778005 systemd[1]: Reached target Basic System. Oct 28 04:50:59.778914 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 28 04:50:59.778955 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 28 04:50:59.779948 systemd[1]: Started D-Bus System Message Bus. Oct 28 04:50:59.783681 systemd[1]: Starting Extend Filesystems... Oct 28 04:50:59.784577 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 28 04:50:59.785704 systemd[1]: Starting Generate /run/flatcar/motd... Oct 28 04:50:59.787735 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 28 04:50:59.790242 systemd[1]: Starting Generate sshd host keys... Oct 28 04:50:59.794113 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 28 04:50:59.794146 systemd[1]: Reached target Load system-provided cloud configs. Oct 28 04:50:59.796381 extend-filesystems[794]: Found vda Oct 28 04:50:59.797333 extend-filesystems[794]: Found vda1 Oct 28 04:50:59.797333 extend-filesystems[794]: Found vda2 Oct 28 04:50:59.797333 extend-filesystems[794]: Found vda3 Oct 28 04:50:59.797333 extend-filesystems[794]: Found usr Oct 28 04:50:59.797333 extend-filesystems[794]: Found vda4 Oct 28 04:50:59.797333 extend-filesystems[794]: Found vda6 Oct 28 04:50:59.797333 extend-filesystems[794]: Found vda7 Oct 28 04:50:59.797333 extend-filesystems[794]: Found vda9 Oct 28 04:50:59.797333 extend-filesystems[794]: Checking size of /dev/vda9 Oct 28 04:50:59.797246 systemd[1]: Starting User Login Management... Oct 28 04:50:59.798530 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 28 04:50:59.799508 systemd[1]: Starting Update Engine... Oct 28 04:50:59.800715 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 28 04:50:59.800754 systemd[1]: Reached target Load user-provided cloud configs. Oct 28 04:50:59.802930 systemd[1]: motdgen.service: Deactivated successfully. Oct 28 04:50:59.803186 systemd[1]: Finished Generate /run/flatcar/motd. Oct 28 04:50:59.805506 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 28 04:50:59.805737 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 28 04:50:59.814757 extend-filesystems[794]: Old size kept for /dev/vda9 Oct 28 04:50:59.819801 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 28 04:50:59.820041 systemd[1]: Finished Extend Filesystems. Oct 28 04:50:59.833697 systemd-logind[809]: Watching system buttons on /dev/input/event0 (Power Button) Oct 28 04:50:59.833920 systemd-logind[809]: New seat seat0. Oct 28 04:50:59.842915 systemd[1]: Started User Login Management. Oct 28 04:50:59.852319 systemd[1]: Finished Generate sshd host keys. Oct 28 04:50:59.854302 update_engine[810]: I1028 04:50:59.854056 810 main.cc:89] Flatcar Update Engine starting Oct 28 04:50:59.862652 update_engine[810]: I1028 04:50:59.854842 810 payload_state.cc:360] Current Response Signature = Oct 28 04:50:59.862652 update_engine[810]: NumURLs = 1 Oct 28 04:50:59.862652 update_engine[810]: Url0 = http://10.0.0.4:34567/packages/update.gz Oct 28 04:50:59.862652 update_engine[810]: Payload Size = 439465725 Oct 28 04:50:59.862652 update_engine[810]: Payload Sha256 Hash = dykyx/Y4jQdfCP1f9j5W1sV+S815EIPi4QJ65LWeHik= Oct 28 04:50:59.862652 update_engine[810]: Is Delta Payload = 0 Oct 28 04:50:59.862652 update_engine[810]: Max Failure Count Per Url = 10 Oct 28 04:50:59.862652 update_engine[810]: Disable Payload Backoff = 1 Oct 28 04:50:59.862652 update_engine[810]: I1028 04:50:59.855031 810 payload_state.cc:381] Payload Attempt Number = 0 Oct 28 04:50:59.862652 update_engine[810]: I1028 04:50:59.855204 810 payload_state.cc:404] Current URL Index = 0 Oct 28 04:50:59.862652 update_engine[810]: I1028 04:50:59.855340 810 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 28 04:50:59.862652 update_engine[810]: I1028 04:50:59.855490 810 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 28 04:50:59.862652 update_engine[810]: I1028 04:50:59.857287 810 update_check_scheduler.cc:74] Next update check in 4m48s Oct 28 04:50:59.863512 systemd[1]: Starting Generate /run/issue... Oct 28 04:50:59.864421 systemd[1]: Started Update Engine. Oct 28 04:50:59.867849 systemd[1]: issuegen.service: Deactivated successfully. Oct 28 04:50:59.868063 systemd[1]: Finished Generate /run/issue. Oct 28 04:50:59.870187 systemd[1]: Starting Permit User Sessions... Oct 28 04:50:59.874630 systemd[1]: Finished Permit User Sessions. Oct 28 04:50:59.876648 systemd[1]: Started Getty on tty1. Oct 28 04:50:59.878490 systemd[1]: Started Serial Getty on ttyAMA0. Oct 28 04:50:59.879542 systemd[1]: Reached target Login Prompts. Oct 28 04:50:59.880462 systemd[1]: Reached target Multi-User System. Oct 28 04:50:59.882371 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 28 04:50:59.887316 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 28 04:50:59.887555 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 28 04:50:59.888972 systemd[1]: Startup finished in 696ms (kernel) + 1.120s (initrd) + 2.664s (userspace) = 4.482s. Oct 28 04:51:00.557649 systemd-networkd[719]: eth0: Gained IPv6LL Oct 28 04:51:09.517304 systemd[1]: Created slice Slice /system/sshd. Oct 28 04:51:09.518289 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37164). Oct 28 04:51:09.562038 sshd[833]: Accepted publickey for core from 10.0.0.1 port 37164 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:51:09.563988 sshd[833]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:51:09.585953 systemd[1]: Created slice User Slice of UID 500. Oct 28 04:51:09.587068 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 28 04:51:09.588706 systemd-logind[809]: New session 1 of user core. Oct 28 04:51:09.593342 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 28 04:51:09.594629 systemd[1]: Starting User Manager for UID 500... Oct 28 04:51:09.596854 systemd[836]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:51:09.645270 systemd[836]: Queued start job for default target Main User Target. Oct 28 04:51:09.645453 systemd[836]: Reached target Paths. Oct 28 04:51:09.645467 systemd[836]: Reached target Sockets. Oct 28 04:51:09.645478 systemd[836]: Reached target Timers. Oct 28 04:51:09.645489 systemd[836]: Reached target Basic System. Oct 28 04:51:09.645533 systemd[836]: Reached target Main User Target. Oct 28 04:51:09.645546 systemd[836]: Startup finished in 44ms. Oct 28 04:51:09.645791 systemd[1]: Started User Manager for UID 500. Oct 28 04:51:09.658827 systemd[1]: Started Session 1 of User core. Oct 28 04:51:09.723188 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37166). Oct 28 04:51:09.761576 sshd[845]: Accepted publickey for core from 10.0.0.1 port 37166 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:51:09.762860 sshd[845]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:51:09.765619 systemd-logind[809]: New session 2 of user core. Oct 28 04:51:09.777796 systemd[1]: Started Session 2 of User core. Oct 28 04:51:09.831264 sshd[845]: pam_unix(sshd:session): session closed for user core Oct 28 04:51:09.839995 systemd[1]: sshd@1-10.0.0.5:22-10.0.0.1:37166.service: Deactivated successfully. Oct 28 04:51:09.840569 systemd[1]: session-2.scope: Deactivated successfully. Oct 28 04:51:09.841176 systemd-logind[809]: Session 2 logged out. Waiting for processes to exit. Oct 28 04:51:09.842103 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37176). Oct 28 04:51:09.842758 systemd-logind[809]: Removed session 2. Oct 28 04:51:09.880827 sshd[851]: Accepted publickey for core from 10.0.0.1 port 37176 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:51:09.882015 sshd[851]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:51:09.884357 systemd-logind[809]: New session 3 of user core. Oct 28 04:51:09.890563 systemd[1]: Started Session 3 of User core. Oct 28 04:51:09.938639 sshd[851]: pam_unix(sshd:session): session closed for user core Oct 28 04:51:09.947128 systemd[1]: sshd@2-10.0.0.5:22-10.0.0.1:37176.service: Deactivated successfully. Oct 28 04:51:09.947721 systemd[1]: session-3.scope: Deactivated successfully. Oct 28 04:51:09.948261 systemd-logind[809]: Session 3 logged out. Waiting for processes to exit. Oct 28 04:51:09.949283 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37184). Oct 28 04:51:09.949948 systemd-logind[809]: Removed session 3. Oct 28 04:51:09.988160 sshd[857]: Accepted publickey for core from 10.0.0.1 port 37184 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:51:09.989426 sshd[857]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:51:09.992086 systemd-logind[809]: New session 4 of user core. Oct 28 04:51:10.004593 systemd[1]: Started Session 4 of User core. Oct 28 04:51:10.056568 sshd[857]: pam_unix(sshd:session): session closed for user core Oct 28 04:51:10.064961 systemd[1]: sshd@3-10.0.0.5:22-10.0.0.1:37184.service: Deactivated successfully. Oct 28 04:51:10.065542 systemd[1]: session-4.scope: Deactivated successfully. Oct 28 04:51:10.066161 systemd-logind[809]: Session 4 logged out. Waiting for processes to exit. Oct 28 04:51:10.067153 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37192). Oct 28 04:51:10.067833 systemd-logind[809]: Removed session 4. Oct 28 04:51:10.106262 sshd[863]: Accepted publickey for core from 10.0.0.1 port 37192 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:51:10.107230 sshd[863]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:51:10.110145 systemd-logind[809]: New session 5 of user core. Oct 28 04:51:10.124602 systemd[1]: Started Session 5 of User core. Oct 28 04:51:10.181097 sudo[866]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 28 04:51:10.181319 sudo[866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 04:51:10.192892 sudo[866]: pam_unix(sudo:session): session closed for user root Oct 28 04:51:10.194741 sshd[863]: pam_unix(sshd:session): session closed for user core Oct 28 04:51:10.207179 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37202). Oct 28 04:51:10.209782 dbus-daemon[793]: [system] Reloaded configuration Oct 28 04:51:10.212148 systemd[1]: sshd@4-10.0.0.5:22-10.0.0.1:37192.service: Deactivated successfully. Oct 28 04:51:10.212821 systemd[1]: session-5.scope: Deactivated successfully. Oct 28 04:51:10.213386 systemd-logind[809]: Session 5 logged out. Waiting for processes to exit. Oct 28 04:51:10.214080 systemd-logind[809]: Removed session 5. Oct 28 04:51:10.246353 sshd[869]: Accepted publickey for core from 10.0.0.1 port 37202 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:51:10.247772 sshd[869]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:51:10.250465 systemd-logind[809]: New session 6 of user core. Oct 28 04:51:10.256576 systemd[1]: Started Session 6 of User core. Oct 28 04:51:10.306627 sudo[874]: 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 04:51:10.306838 sudo[874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 04:51:10.309081 sudo[874]: pam_unix(sudo:session): session closed for user root Oct 28 04:51:10.312873 sudo[873]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 28 04:51:10.313075 sudo[873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 04:51:10.324328 systemd[1]: Stopping Load Security Auditing Rules... Oct 28 04:51:10.325000 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 04:51:10.326672 auditctl[877]: No rules Oct 28 04:51:10.327910 kernel: kauditd_printk_skb: 43 callbacks suppressed Oct 28 04:51:10.327963 kernel: audit: type=1305 audit(1761627070.325:54): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 28 04:51:10.327009 systemd[1]: audit-rules.service: Deactivated successfully. Oct 28 04:51:10.327248 systemd[1]: Stopped Load Security Auditing Rules. Oct 28 04:51:10.328530 systemd[1]: Starting Load Security Auditing Rules... Oct 28 04:51:10.343004 augenrules[894]: No rules Oct 28 04:51:10.343882 systemd[1]: Finished Load Security Auditing Rules. Oct 28 04:51:10.344716 sudo[873]: pam_unix(sudo:session): session closed for user root Oct 28 04:51:10.345807 sshd[869]: pam_unix(sshd:session): session closed for user core Oct 28 04:51:10.357001 systemd[1]: sshd@5-10.0.0.5:22-10.0.0.1:37202.service: Deactivated successfully. Oct 28 04:51:10.357600 systemd[1]: session-6.scope: Deactivated successfully. Oct 28 04:51:10.358152 systemd-logind[809]: Session 6 logged out. Waiting for processes to exit. Oct 28 04:51:10.359126 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37206). Oct 28 04:51:10.359756 systemd-logind[809]: Removed session 6. Oct 28 04:51:10.397757 sshd[900]: Accepted publickey for core from 10.0.0.1 port 37206 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:51:10.398699 sshd[900]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:51:10.401438 kernel: audit: type=1006 audit(1761627070.398:55): pid=900 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 04:51:10.402581 systemd-logind[809]: New session 7 of user core. Oct 28 04:51:10.408576 systemd[1]: Started Session 7 of User core. Oct 28 04:51:10.457898 sshd[900]: pam_unix(sshd:session): session closed for user core Oct 28 04:51:10.472530 systemd[1]: sshd@6-10.0.0.5:22-10.0.0.1:37206.service: Deactivated successfully. Oct 28 04:51:10.473330 systemd[1]: session-7.scope: Deactivated successfully. Oct 28 04:51:10.474087 systemd-logind[809]: Session 7 logged out. Waiting for processes to exit. Oct 28 04:51:10.475708 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37216). Oct 28 04:51:10.476379 systemd-logind[809]: Removed session 7. Oct 28 04:51:10.514511 sshd[906]: Accepted publickey for core from 10.0.0.1 port 37216 ssh2: RSA SHA256:bAFbL+VzhLlryOgq5GbKezCGUXtHV9rBGotlRyD6B+E Oct 28 04:51:10.515494 sshd[906]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:51:10.517417 kernel: audit: type=1006 audit(1761627070.514:56): pid=906 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 04:51:10.519760 systemd-logind[809]: New session 8 of user core. Oct 28 04:51:10.533581 systemd[1]: Started Session 8 of User core. Oct 28 04:51:10.582704 sshd[906]: pam_unix(sshd:session): session closed for user core Oct 28 04:51:10.585187 systemd[1]: session-8.scope: Deactivated successfully. Oct 28 04:51:10.585868 systemd-logind[809]: Session 8 logged out. Waiting for processes to exit. Oct 28 04:51:10.586033 systemd[1]: sshd@7-10.0.0.5:22-10.0.0.1:37216.service: Deactivated successfully. Oct 28 04:51:10.586965 systemd-logind[809]: Removed session 8.