Oct 27 15:46:06.683761 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 27 15:46:06.683777 kernel: Linux version 5.10.107-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP PREEMPT Tue Mar 22 19:39:09 -00 2022 Oct 27 15:46:06.683786 kernel: efi: EFI v2.70 by EDK II Oct 27 15:46:06.683792 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 27 15:46:06.683797 kernel: efi: seeding entropy pool Oct 27 15:46:06.683802 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 27 15:46:06.683808 kernel: ACPI: Early table checksum verification disabled Oct 27 15:46:06.683813 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 27 15:46:06.683818 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 27 15:46:06.683822 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:06.683829 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:06.683834 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:06.683839 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:06.683844 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:06.683849 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:06.683855 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:06.683862 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:06.683867 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:06.683872 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 27 15:46:06.683877 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 27 15:46:06.683882 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 15:46:06.683887 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 27 15:46:06.683892 kernel: Zone ranges: Oct 27 15:46:06.683898 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 15:46:06.683903 kernel: DMA32 empty Oct 27 15:46:06.683908 kernel: Normal empty Oct 27 15:46:06.683913 kernel: Movable zone start for each node Oct 27 15:46:06.683919 kernel: Early memory node ranges Oct 27 15:46:06.683924 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 27 15:46:06.683929 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 27 15:46:06.683934 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 27 15:46:06.683939 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 27 15:46:06.683944 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 27 15:46:06.683949 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 27 15:46:06.683954 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 27 15:46:06.683959 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 27 15:46:06.683964 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 27 15:46:06.683970 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 27 15:46:06.683975 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 27 15:46:06.683982 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 15:46:06.683991 kernel: On node 0 totalpages: 643072 Oct 27 15:46:06.683996 kernel: DMA zone: 10048 pages used for memmap Oct 27 15:46:06.684002 kernel: DMA zone: 0 pages reserved Oct 27 15:46:06.684007 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 27 15:46:06.684012 kernel: psci: probing for conduit method from ACPI. Oct 27 15:46:06.684018 kernel: psci: PSCIv1.1 detected in firmware. Oct 27 15:46:06.684023 kernel: psci: Using standard PSCI v0.2 function IDs Oct 27 15:46:06.684028 kernel: psci: Trusted OS migration not required Oct 27 15:46:06.684035 kernel: psci: SMC Calling Convention v1.1 Oct 27 15:46:06.684040 kernel: ACPI: SRAT not present Oct 27 15:46:06.684046 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 27 15:46:06.684051 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 27 15:46:06.684057 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 27 15:46:06.684062 kernel: Detected PIPT I-cache on CPU0 Oct 27 15:46:06.684067 kernel: CPU features: detected: GIC system register CPU interface Oct 27 15:46:06.684073 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 27 15:46:06.684078 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 27 15:46:06.684084 kernel: CPU features: detected: Hardware dirty bit management Oct 27 15:46:06.684089 kernel: CPU features: detected: Spectre-v4 Oct 27 15:46:06.684095 kernel: CPU features: detected: ARM erratum 1418040 Oct 27 15:46:06.684101 kernel: CPU features: detected: Spectre-BHB Oct 27 15:46:06.684106 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 27 15:46:06.684112 kernel: Policy zone: DMA Oct 27 15:46:06.684118 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 27 15:46:06.684124 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 27 15:46:06.684129 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 27 15:46:06.684135 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 27 15:46:06.684149 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 27 15:46:06.684155 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 27 15:46:06.684160 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 27 15:46:06.684167 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 27 15:46:06.684173 kernel: rcu: RCU event tracing is enabled. Oct 27 15:46:06.684179 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 27 15:46:06.684192 kernel: Trampoline variant of Tasks RCU enabled. Oct 27 15:46:06.684198 kernel: Tracing variant of Tasks RCU enabled. Oct 27 15:46:06.684203 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 27 15:46:06.684209 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 27 15:46:06.684214 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 27 15:46:06.684220 kernel: GICv3: 256 SPIs implemented Oct 27 15:46:06.684225 kernel: GICv3: 0 Extended SPIs implemented Oct 27 15:46:06.684230 kernel: GICv3: Distributor has no Range Selector support Oct 27 15:46:06.684236 kernel: GICv3: 16 PPIs implemented Oct 27 15:46:06.684242 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 27 15:46:06.684248 kernel: ACPI: SRAT not present Oct 27 15:46:06.684253 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 27 15:46:06.684259 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 27 15:46:06.684264 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 27 15:46:06.684270 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 27 15:46:06.684275 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 27 15:46:06.684281 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:46:06.684286 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 27 15:46:06.684296 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 27 15:46:06.684303 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 27 15:46:06.684309 kernel: arm-pv: using stolen time PV Oct 27 15:46:06.684316 kernel: Console: colour dummy device 80x25 Oct 27 15:46:06.684321 kernel: ACPI: Core revision 20200925 Oct 27 15:46:06.684327 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 27 15:46:06.684333 kernel: pid_max: default: 32768 minimum: 301 Oct 27 15:46:06.684339 kernel: LSM: Security Framework initializing Oct 27 15:46:06.684344 kernel: SELinux: Initializing. Oct 27 15:46:06.684350 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 15:46:06.684356 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 15:46:06.684361 kernel: rcu: Hierarchical SRCU implementation. Oct 27 15:46:06.684368 kernel: Platform MSI: ITS@0x8080000 domain created Oct 27 15:46:06.684374 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 27 15:46:06.684379 kernel: Remapping and enabling EFI services. Oct 27 15:46:06.684385 kernel: smp: Bringing up secondary CPUs ... Oct 27 15:46:06.684391 kernel: Detected PIPT I-cache on CPU1 Oct 27 15:46:06.684396 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 27 15:46:06.684402 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 27 15:46:06.684408 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:46:06.684414 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 27 15:46:06.684419 kernel: Detected PIPT I-cache on CPU2 Oct 27 15:46:06.684426 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 27 15:46:06.684432 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 27 15:46:06.684438 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:46:06.684443 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 27 15:46:06.684449 kernel: Detected PIPT I-cache on CPU3 Oct 27 15:46:06.684459 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 27 15:46:06.684465 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 27 15:46:06.684472 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:46:06.684477 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 27 15:46:06.684483 kernel: smp: Brought up 1 node, 4 CPUs Oct 27 15:46:06.684489 kernel: SMP: Total of 4 processors activated. Oct 27 15:46:06.684495 kernel: CPU features: detected: Privileged Access Never Oct 27 15:46:06.684501 kernel: CPU features: detected: LSE atomic instructions Oct 27 15:46:06.684507 kernel: CPU features: detected: User Access Override Oct 27 15:46:06.684513 kernel: CPU features: detected: 32-bit EL0 Support Oct 27 15:46:06.684519 kernel: CPU features: detected: Common not Private translations Oct 27 15:46:06.684525 kernel: CPU features: detected: RAS Extension Support Oct 27 15:46:06.684532 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 27 15:46:06.684538 kernel: CPU features: detected: CRC32 instructions Oct 27 15:46:06.684544 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 27 15:46:06.684550 kernel: CPU: All CPU(s) started at EL1 Oct 27 15:46:06.684556 kernel: alternatives: patching kernel code Oct 27 15:46:06.684563 kernel: devtmpfs: initialized Oct 27 15:46:06.684568 kernel: KASLR enabled Oct 27 15:46:06.684575 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 27 15:46:06.684581 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 27 15:46:06.684586 kernel: pinctrl core: initialized pinctrl subsystem Oct 27 15:46:06.684592 kernel: SMBIOS 3.0.0 present. Oct 27 15:46:06.684598 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 27 15:46:06.684604 kernel: NET: Registered protocol family 16 Oct 27 15:46:06.684610 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 27 15:46:06.684616 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 27 15:46:06.684623 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 27 15:46:06.684629 kernel: audit: initializing netlink subsys (disabled) Oct 27 15:46:06.684635 kernel: audit: type=2000 audit(0.032:1): state=initialized audit_enabled=0 res=1 Oct 27 15:46:06.684641 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 27 15:46:06.684647 kernel: cpuidle: using governor menu Oct 27 15:46:06.684653 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 27 15:46:06.684659 kernel: ASID allocator initialised with 32768 entries Oct 27 15:46:06.684665 kernel: ACPI: bus type PCI registered Oct 27 15:46:06.684671 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 27 15:46:06.684678 kernel: Serial: AMBA PL011 UART driver Oct 27 15:46:06.684684 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 27 15:46:06.684690 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 27 15:46:06.684696 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 27 15:46:06.684702 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 27 15:46:06.684708 kernel: cryptd: max_cpu_qlen set to 1000 Oct 27 15:46:06.684714 kernel: ACPI: Added _OSI(Module Device) Oct 27 15:46:06.684719 kernel: ACPI: Added _OSI(Processor Device) Oct 27 15:46:06.684725 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 27 15:46:06.684732 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 27 15:46:06.684738 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 27 15:46:06.684744 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 27 15:46:06.684750 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 27 15:46:06.684756 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 27 15:46:06.684762 kernel: ACPI: Interpreter enabled Oct 27 15:46:06.684768 kernel: ACPI: Using GIC for interrupt routing Oct 27 15:46:06.684774 kernel: ACPI: MCFG table detected, 1 entries Oct 27 15:46:06.684780 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 27 15:46:06.684785 kernel: printk: console [ttyAMA0] enabled Oct 27 15:46:06.684793 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 27 15:46:06.684897 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 27 15:46:06.684969 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 27 15:46:06.685022 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 27 15:46:06.685075 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 27 15:46:06.685126 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 27 15:46:06.685134 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 27 15:46:06.685152 kernel: PCI host bridge to bus 0000:00 Oct 27 15:46:06.685218 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 27 15:46:06.685266 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 27 15:46:06.685323 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 27 15:46:06.685372 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 27 15:46:06.685438 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 27 15:46:06.685500 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 27 15:46:06.685558 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 27 15:46:06.685612 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 27 15:46:06.685665 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 27 15:46:06.685721 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 27 15:46:06.685778 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 27 15:46:06.685848 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 27 15:46:06.685905 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 27 15:46:06.685958 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 27 15:46:06.686012 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 27 15:46:06.686020 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 27 15:46:06.686041 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 27 15:46:06.686047 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 27 15:46:06.686053 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 27 15:46:06.686059 kernel: vgaarb: loaded Oct 27 15:46:06.686065 kernel: Registered efivars operations Oct 27 15:46:06.686071 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 27 15:46:06.686079 kernel: VFS: Disk quotas dquot_6.6.0 Oct 27 15:46:06.686085 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 27 15:46:06.686091 kernel: pnp: PnP ACPI init Oct 27 15:46:06.686163 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 27 15:46:06.686215 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 27 15:46:06.686224 kernel: pnp: PnP ACPI: found 1 devices Oct 27 15:46:06.686230 kernel: NET: Registered protocol family 2 Oct 27 15:46:06.686237 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 27 15:46:06.686245 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 27 15:46:06.686251 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 27 15:46:06.686257 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 27 15:46:06.686264 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 27 15:46:06.686270 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 15:46:06.686276 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 15:46:06.686282 kernel: NET: Registered protocol family 1 Oct 27 15:46:06.686288 kernel: PCI: CLS 0 bytes, default 64 Oct 27 15:46:06.686300 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 27 15:46:06.686308 kernel: kvm [1]: HYP mode not available Oct 27 15:46:06.686314 kernel: Initialise system trusted keyrings Oct 27 15:46:06.686320 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 27 15:46:06.686326 kernel: Key type asymmetric registered Oct 27 15:46:06.686332 kernel: Asymmetric key parser 'x509' registered Oct 27 15:46:06.686338 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 27 15:46:06.686344 kernel: io scheduler mq-deadline registered Oct 27 15:46:06.686350 kernel: io scheduler kyber registered Oct 27 15:46:06.686356 kernel: io scheduler bfq registered Oct 27 15:46:06.686364 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 27 15:46:06.686370 kernel: ACPI: Power Button [PWRB] Oct 27 15:46:06.686377 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 27 15:46:06.686438 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 27 15:46:06.686447 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 27 15:46:06.686453 kernel: msm_serial: driver initialized Oct 27 15:46:06.686459 kernel: random: fast init done Oct 27 15:46:06.686465 kernel: random: crng init done Oct 27 15:46:06.686471 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 27 15:46:06.686477 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 27 15:46:06.686484 kernel: thunder_xcv, ver 1.0 Oct 27 15:46:06.686490 kernel: thunder_bgx, ver 1.0 Oct 27 15:46:06.686496 kernel: nicpf, ver 1.0 Oct 27 15:46:06.686502 kernel: nicvf, ver 1.0 Oct 27 15:46:06.686562 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 27 15:46:06.686613 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-27T15:46:06 UTC (1761579966) Oct 27 15:46:06.686621 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 27 15:46:06.686627 kernel: NET: Registered protocol family 10 Oct 27 15:46:06.686634 kernel: Segment Routing with IPv6 Oct 27 15:46:06.686640 kernel: NET: Registered protocol family 17 Oct 27 15:46:06.686646 kernel: Key type dns_resolver registered Oct 27 15:46:06.686652 kernel: registered taskstats version 1 Oct 27 15:46:06.686658 kernel: Loading compiled-in X.509 certificates Oct 27 15:46:06.686664 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 27 15:46:06.686670 kernel: Key type ._fscrypt registered Oct 27 15:46:06.686676 kernel: Key type .fscrypt registered Oct 27 15:46:06.686682 kernel: Key type fscrypt-provisioning registered Oct 27 15:46:06.686689 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 27 15:46:06.686696 kernel: ima: Allocated hash algorithm: sha1 Oct 27 15:46:06.686702 kernel: ima: No architecture policies found Oct 27 15:46:06.686707 kernel: Freeing unused kernel memory: 34240K Oct 27 15:46:06.686714 kernel: Run /init as init process Oct 27 15:46:06.686719 kernel: with arguments: Oct 27 15:46:06.686725 kernel: /init Oct 27 15:46:06.686731 kernel: with environment: Oct 27 15:46:06.686737 kernel: HOME=/ Oct 27 15:46:06.686742 kernel: TERM=linux Oct 27 15:46:06.686750 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 27 15:46:06.686758 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 27 15:46:06.686766 systemd[1]: Detected virtualization kvm. Oct 27 15:46:06.686772 systemd[1]: Detected architecture arm64. Oct 27 15:46:06.686779 systemd[1]: Running in initial RAM disk. Oct 27 15:46:06.686785 systemd[1]: No hostname configured, using default hostname. Oct 27 15:46:06.686791 systemd[1]: Hostname set to . Oct 27 15:46:06.686799 systemd[1]: Initializing machine ID from VM UUID. Oct 27 15:46:06.686806 systemd[1]: Queued start job for default target Initrd Default Target. Oct 27 15:46:06.686812 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 27 15:46:06.686819 systemd[1]: Reached target Local Encrypted Volumes. Oct 27 15:46:06.686825 systemd[1]: Reached target Path Units. Oct 27 15:46:06.686831 systemd[1]: Reached target Slice Units. Oct 27 15:46:06.686837 systemd[1]: Reached target Swaps. Oct 27 15:46:06.686844 systemd[1]: Reached target Timer Units. Oct 27 15:46:06.686852 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 27 15:46:06.686858 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 27 15:46:06.686865 systemd[1]: Listening on Journal Audit Socket. Oct 27 15:46:06.686871 systemd[1]: Listening on Journal Socket (/dev/log). Oct 27 15:46:06.686878 systemd[1]: Listening on Journal Socket. Oct 27 15:46:06.686884 systemd[1]: Listening on Network Service Netlink Socket. Oct 27 15:46:06.686891 systemd[1]: Listening on udev Control Socket. Oct 27 15:46:06.686897 systemd[1]: Listening on udev Kernel Socket. Oct 27 15:46:06.686904 systemd[1]: Reached target Socket Units. Oct 27 15:46:06.686911 systemd[1]: Starting Create List of Static Device Nodes... Oct 27 15:46:06.686918 systemd[1]: Finished Network Cleanup. Oct 27 15:46:06.686924 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 27 15:46:06.686930 systemd[1]: Starting Journal Service... Oct 27 15:46:06.686937 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 27 15:46:06.686943 systemd[1]: Starting Apply Kernel Variables... Oct 27 15:46:06.686950 systemd[1]: Starting Setup Virtual Console... Oct 27 15:46:06.686956 systemd[1]: Finished Create List of Static Device Nodes. Oct 27 15:46:06.686962 systemd[1]: Finished Apply Kernel Variables. Oct 27 15:46:06.686970 systemd[1]: Finished Setup Virtual Console. Oct 27 15:46:06.686976 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 27 15:46:06.686984 systemd-journald[279]: Journal started Oct 27 15:46:06.687018 systemd-journald[279]: Runtime Journal (/run/log/journal/c7124dca645d438da9b16aa25dd9a4aa) is 6.0M, max 48.5M, 42.4M free. Oct 27 15:46:06.690655 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 27 15:46:06.692415 systemd[1]: Started Journal Service. Oct 27 15:46:06.692509 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 27 15:46:06.701451 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 27 15:46:06.703341 systemd[1]: Starting dracut cmdline hook... Oct 27 15:46:06.712525 dracut-cmdline[297]: dracut-dracut-053 Oct 27 15:46:06.715006 dracut-cmdline[297]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 27 15:46:06.777214 kernel: SCSI subsystem initialized Oct 27 15:46:06.781151 kernel: Loading iSCSI transport class v2.0-870. Oct 27 15:46:06.787161 kernel: iscsi: registered transport (tcp) Oct 27 15:46:06.799169 kernel: iscsi: registered transport (qla4xxx) Oct 27 15:46:06.799186 kernel: QLogic iSCSI HBA Driver Oct 27 15:46:06.809428 systemd[1]: Finished dracut cmdline hook. Oct 27 15:46:06.811092 systemd[1]: Starting dracut pre-udev hook... Oct 27 15:46:06.825141 kernel: device-mapper: uevent: version 1.0.3 Oct 27 15:46:06.825180 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 27 15:46:06.861193 kernel: raid6: neonx8 gen() 13790 MB/s Oct 27 15:46:06.878165 kernel: raid6: neonx8 xor() 10746 MB/s Oct 27 15:46:06.895167 kernel: raid6: neonx4 gen() 13650 MB/s Oct 27 15:46:06.912166 kernel: raid6: neonx4 xor() 11567 MB/s Oct 27 15:46:06.929165 kernel: raid6: neonx2 gen() 13013 MB/s Oct 27 15:46:06.946167 kernel: raid6: neonx2 xor() 9885 MB/s Oct 27 15:46:06.963167 kernel: raid6: neonx1 gen() 10974 MB/s Oct 27 15:46:06.980164 kernel: raid6: neonx1 xor() 8851 MB/s Oct 27 15:46:06.997166 kernel: raid6: int64x8 gen() 6832 MB/s Oct 27 15:46:07.014164 kernel: raid6: int64x8 xor() 3793 MB/s Oct 27 15:46:07.031164 kernel: raid6: int64x4 gen() 6908 MB/s Oct 27 15:46:07.048166 kernel: raid6: int64x4 xor() 3834 MB/s Oct 27 15:46:07.065165 kernel: raid6: int64x2 gen() 6188 MB/s Oct 27 15:46:07.082165 kernel: raid6: int64x2 xor() 3311 MB/s Oct 27 15:46:07.099164 kernel: raid6: int64x1 gen() 5005 MB/s Oct 27 15:46:07.116324 kernel: raid6: int64x1 xor() 2597 MB/s Oct 27 15:46:07.116337 kernel: raid6: using algorithm neonx8 gen() 13790 MB/s Oct 27 15:46:07.116346 kernel: raid6: .... xor() 10746 MB/s, rmw enabled Oct 27 15:46:07.117499 kernel: raid6: using neon recovery algorithm Oct 27 15:46:07.127166 kernel: xor: measuring software checksum speed Oct 27 15:46:07.129189 kernel: 8regs : 17163 MB/sec Oct 27 15:46:07.129206 kernel: 32regs : 20770 MB/sec Oct 27 15:46:07.130758 kernel: arm64_neon : 28875 MB/sec Oct 27 15:46:07.130775 kernel: xor: using function: arm64_neon (28875 MB/sec) Oct 27 15:46:07.176161 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 27 15:46:07.186483 systemd[1]: Finished dracut pre-udev hook. Oct 27 15:46:07.187000 audit: BPF prog-id=6 op=LOAD Oct 27 15:46:07.187000 audit: BPF prog-id=7 op=LOAD Oct 27 15:46:07.187000 audit: BPF prog-id=8 op=LOAD Oct 27 15:46:07.188419 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 27 15:46:07.191691 kernel: audit: type=1334 audit(1761579967.187:2): prog-id=6 op=LOAD Oct 27 15:46:07.191727 kernel: audit: type=1334 audit(1761579967.187:3): prog-id=7 op=LOAD Oct 27 15:46:07.191737 kernel: audit: type=1334 audit(1761579967.187:4): prog-id=8 op=LOAD Oct 27 15:46:07.202328 systemd-udevd[417]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 27 15:46:07.204018 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 27 15:46:07.205923 systemd[1]: Starting dracut pre-trigger hook... Oct 27 15:46:07.209000 audit: BPF prog-id=9 op=LOAD Oct 27 15:46:07.210484 systemd[1]: Starting Network Configuration... Oct 27 15:46:07.212940 kernel: audit: type=1334 audit(1761579967.209:5): prog-id=9 op=LOAD Oct 27 15:46:07.217973 dracut-pre-trigger[424]: rd.md=0: removing MD RAID activation Oct 27 15:46:07.228244 systemd-networkd[432]: lo: Link UP Oct 27 15:46:07.228252 systemd-networkd[432]: lo: Gained carrier Oct 27 15:46:07.228557 systemd-networkd[432]: Enumeration completed Oct 27 15:46:07.229000 audit: BPF prog-id=10 op=LOAD Oct 27 15:46:07.233184 kernel: audit: type=1334 audit(1761579967.229:6): prog-id=10 op=LOAD Oct 27 15:46:07.228657 systemd[1]: Started Network Configuration. Oct 27 15:46:07.230598 systemd[1]: Starting Network Name Resolution... Oct 27 15:46:07.247959 systemd[1]: Finished dracut pre-trigger hook. Oct 27 15:46:07.249583 systemd[1]: Starting Coldplug All udev Devices... Oct 27 15:46:07.259434 systemd-resolved[449]: Positive Trust Anchors: Oct 27 15:46:07.259448 systemd-resolved[449]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 27 15:46:07.259478 systemd-resolved[449]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 27 15:46:07.259788 systemd-resolved[449]: Defaulting to hostname 'linux'. Oct 27 15:46:07.260480 systemd[1]: Started Network Name Resolution. Oct 27 15:46:07.261134 systemd-udevd[417]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 27 15:46:07.263116 systemd[1]: Reached target Network. Oct 27 15:46:07.268601 systemd[1]: Reached target Host and Network Name Lookups. Oct 27 15:46:07.271323 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 27 15:46:07.277776 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 27 15:46:07.279348 systemd[1]: Starting Open-iSCSI... Oct 27 15:46:07.281824 iscsid[486]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 27 15:46:07.281824 iscsid[486]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 27 15:46:07.281824 iscsid[486]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 27 15:46:07.281824 iscsid[486]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 27 15:46:07.281824 iscsid[486]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 27 15:46:07.281824 iscsid[486]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 27 15:46:07.283676 systemd[1]: Finished Coldplug All udev Devices. Oct 27 15:46:07.289209 systemd[1]: Started Open-iSCSI. Oct 27 15:46:07.291265 systemd[1]: Starting dracut initqueue hook... Oct 27 15:46:07.326380 systemd-udevd[477]: Using default interface naming scheme 'v249'. Oct 27 15:46:07.330703 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 27 15:46:07.330870 kernel: vda: detected capacity change from 0 to 4756340736 Oct 27 15:46:07.332279 systemd-networkd[432]: eth0: Link UP Oct 27 15:46:07.341173 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 15:46:07.359163 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (473) Oct 27 15:46:07.364785 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 27 15:46:07.367595 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 27 15:46:07.370190 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 27 15:46:07.371660 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 27 15:46:07.373705 systemd[1]: Finished dracut initqueue hook. Oct 27 15:46:07.377045 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 27 15:46:07.378017 systemd[1]: Reached target Initrd Root Device. Oct 27 15:46:07.379412 systemd[1]: Reached target Preparation for Remote File Systems. Oct 27 15:46:07.380976 systemd[1]: Reached target Remote Encrypted Volumes. Oct 27 15:46:07.382684 systemd[1]: Reached target Remote File Systems. Oct 27 15:46:07.384810 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 27 15:46:07.386507 systemd[1]: Starting dracut pre-mount hook... Oct 27 15:46:07.394179 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 15:46:07.395446 systemd[1]: Finished dracut pre-mount hook. Oct 27 15:46:07.398168 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 15:46:08.355195 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 27 15:46:08.355089 systemd-networkd[432]: eth0: Gained carrier Oct 27 15:46:08.361212 systemd-networkd[432]: eth0: DHCPv4 address 10.0.0.8/16 via 10.0.0.1 Oct 27 15:46:08.417167 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 15:46:08.417616 disk-uuid[507]: The operation has completed successfully. Oct 27 15:46:08.436864 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 27 15:46:08.436989 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 27 15:46:08.438876 systemd[1]: Starting Ignition (setup)... Oct 27 15:46:08.447191 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 27 15:46:08.447225 kernel: BTRFS info (device vda6): has skinny extents Oct 27 15:46:08.453064 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 27 15:46:08.458080 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 27 15:46:08.458218 systemd[1]: Finished Ignition (setup). Oct 27 15:46:08.460518 systemd[1]: Starting Ignition (disks)... Oct 27 15:46:08.469901 ignition[542]: Ignition v0.36.1-15-gde4e6cc9 Oct 27 15:46:08.469918 ignition[542]: Stage: disks Oct 27 15:46:08.469927 ignition[542]: reading system config file "/usr/lib/ignition/base.ign" Oct 27 15:46:08.469937 ignition[542]: no config at "/usr/lib/ignition/base.ign" Oct 27 15:46:08.469979 ignition[542]: parsed url from cmdline: "" Oct 27 15:46:08.469983 ignition[542]: no config URL provided Oct 27 15:46:08.469990 ignition[542]: reading system config file "/usr/lib/ignition/user.ign" Oct 27 15:46:08.469997 ignition[542]: no config at "/usr/lib/ignition/user.ign" Oct 27 15:46:08.470016 ignition[542]: op(1): [started] loading QEMU firmware config module Oct 27 15:46:08.470020 ignition[542]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 27 15:46:08.470877 ignition[542]: op(1): [finished] loading QEMU firmware config module Oct 27 15:46:08.484407 ignition[542]: parsing config with SHA512: ba21d46d07384011e9664625f4283fd39fa058efbcc57a1ab1ec9f51d92fcbd7c0c1434d1dc1b80eb570385a692ef89174ee31733e7c4b8f37636bab45981a4f Oct 27 15:46:08.486388 ignition[542]: disks: disks passed Oct 27 15:46:08.486398 ignition[542]: Ignition finished successfully Oct 27 15:46:08.487314 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 27 15:46:08.487432 systemd[1]: Finished Ignition (disks). Oct 27 15:46:08.488685 systemd[1]: Reached target Preparation for Local File Systems. Oct 27 15:46:08.491014 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 27 15:46:08.493499 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 27 15:46:08.502614 systemd-fsck[554]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 27 15:46:08.509159 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 27 15:46:08.510441 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 27 15:46:08.512468 systemd[1]: Mounting /sysroot... Oct 27 15:46:08.531673 systemd[1]: Found device /dev/mapper/usr. Oct 27 15:46:08.533379 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 27 15:46:08.534430 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 27 15:46:08.544440 systemd-fsck[571]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 27 15:46:08.544440 systemd-fsck[571]: You must have r/w access to the filesystem or be root Oct 27 15:46:08.545428 systemd-fsck[569]: fsck failed with exit status 8. Oct 27 15:46:08.545431 systemd-fsck[569]: Ignoring error. Oct 27 15:46:08.546200 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 27 15:46:08.548877 systemd[1]: Mounting /sysusr/usr... Oct 27 15:46:08.563723 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 27 15:46:08.563766 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 27 15:46:08.564328 systemd[1]: Mounted /sysroot. Oct 27 15:46:08.565059 systemd[1]: Reached target Initrd Root File System. Oct 27 15:46:08.582172 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 27 15:46:08.582261 systemd[1]: Mounted /sysusr/usr. Oct 27 15:46:08.583026 systemd[1]: Reached target Local File Systems. Oct 27 15:46:08.584326 systemd[1]: Reached target System Initialization. Oct 27 15:46:08.585792 systemd[1]: Reached target Basic System. Oct 27 15:46:08.588737 systemd[1]: Mounting /sysroot/usr... Oct 27 15:46:08.590896 systemd[1]: Mounted /sysroot/usr. Oct 27 15:46:08.592257 systemd[1]: Starting Root filesystem setup... Oct 27 15:46:08.616862 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 27 15:46:08.616992 systemd[1]: Finished Root filesystem setup. Oct 27 15:46:08.619510 systemd[1]: Starting Ignition (files)... Oct 27 15:46:08.620881 systemd[1]: Starting /sysroot/boot... Oct 27 15:46:08.622964 ignition[588]: Ignition v0.36.1-15-gde4e6cc9 Oct 27 15:46:08.622982 ignition[588]: Stage: files Oct 27 15:46:08.622992 ignition[588]: reading system config file "/usr/lib/ignition/base.ign" Oct 27 15:46:08.623002 ignition[588]: no config at "/usr/lib/ignition/base.ign" Oct 27 15:46:08.623557 ignition[588]: files: compiled without relabeling support, skipping Oct 27 15:46:08.639645 ignition[588]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 27 15:46:08.639657 ignition[588]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 27 15:46:08.640066 systemd[1]: Finished /sysroot/boot. Oct 27 15:46:08.641897 ignition[588]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 27 15:46:08.641904 ignition[588]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 27 15:46:08.644206 ignition[588]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 27 15:46:08.644231 ignition[588]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 27 15:46:08.645187 ignition[588]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 27 15:46:08.647184 systemd[1]: ignition-files.service: Deactivated successfully. Oct 27 15:46:08.645194 ignition[588]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 27 15:46:08.647309 systemd[1]: Finished Ignition (files). Oct 27 15:46:08.645291 ignition[588]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 27 15:46:08.648929 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 27 15:46:08.660591 kernel: audit: type=1334 audit(1761579968.658:7): prog-id=6 op=UNLOAD Oct 27 15:46:08.660616 kernel: audit: type=1334 audit(1761579968.658:8): prog-id=10 op=UNLOAD Oct 27 15:46:08.660624 kernel: audit: type=1334 audit(1761579968.660:9): prog-id=3 op=UNLOAD Oct 27 15:46:08.658000 audit: BPF prog-id=6 op=UNLOAD Oct 27 15:46:08.658000 audit: BPF prog-id=10 op=UNLOAD Oct 27 15:46:08.660000 audit: BPF prog-id=3 op=UNLOAD Oct 27 15:46:08.646572 ignition[588]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 27 15:46:08.663905 kernel: audit: type=1334 audit(1761579968.660:10): prog-id=9 op=UNLOAD Oct 27 15:46:08.660000 audit: BPF prog-id=9 op=UNLOAD Oct 27 15:46:08.649636 systemd[1]: Starting Ignition (record completion)... Oct 27 15:46:08.646578 ignition[588]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 27 15:46:08.651700 systemd[1]: Starting Reload Configuration from the Real Root... Oct 27 15:46:08.646584 ignition[588]: files: files passed Oct 27 15:46:08.653244 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 27 15:46:08.646587 ignition[588]: Ignition finished successfully Oct 27 15:46:08.653361 systemd[1]: Finished Ignition (record completion). Oct 27 15:46:08.656365 systemd[1]: Reloading. Oct 27 15:46:08.741000 audit: BPF prog-id=11 op=LOAD Oct 27 15:46:08.741000 audit: BPF prog-id=12 op=LOAD Oct 27 15:46:08.741000 audit: BPF prog-id=13 op=LOAD Oct 27 15:46:08.741000 audit: BPF prog-id=7 op=UNLOAD Oct 27 15:46:08.741000 audit: BPF prog-id=8 op=UNLOAD Oct 27 15:46:08.741000 audit: BPF prog-id=14 op=LOAD Oct 27 15:46:08.742000 audit: BPF prog-id=15 op=LOAD Oct 27 15:46:08.742000 audit: BPF prog-id=16 op=LOAD Oct 27 15:46:08.742000 audit: BPF prog-id=17 op=LOAD Oct 27 15:46:08.742000 audit: BPF prog-id=4 op=UNLOAD Oct 27 15:46:08.742000 audit: BPF prog-id=5 op=UNLOAD Oct 27 15:46:08.742000 audit: BPF prog-id=18 op=LOAD Oct 27 15:46:08.756973 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 27 15:46:08.757090 systemd[1]: Finished Reload Configuration from the Real Root. Oct 27 15:46:08.759096 systemd[1]: Reached target Initrd File Systems. Oct 27 15:46:08.760666 systemd[1]: Reached target Initrd Default Target. Oct 27 15:46:08.762109 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 27 15:46:08.762810 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 27 15:46:08.772698 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 27 15:46:08.774297 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 27 15:46:08.780063 systemd[1]: Stopped target Host and Network Name Lookups. Oct 27 15:46:08.781124 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 27 15:46:08.782815 systemd[1]: Stopped target Timer Units. Oct 27 15:46:08.784189 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 27 15:46:08.784307 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 27 15:46:08.785694 systemd[1]: Stopped target Initrd Default Target. Oct 27 15:46:08.787208 systemd[1]: Stopped target Basic System. Oct 27 15:46:08.788650 systemd[1]: Stopped target Initrd Root Device. Oct 27 15:46:08.790033 systemd[1]: Stopped target Path Units. Oct 27 15:46:08.791295 systemd[1]: Stopped target Remote File Systems. Oct 27 15:46:08.792682 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 27 15:46:08.794223 systemd[1]: Stopped target Slice Units. Oct 27 15:46:08.795594 systemd[1]: Stopped target Socket Units. Oct 27 15:46:08.797101 systemd[1]: Stopped target System Initialization. Oct 27 15:46:08.798496 systemd[1]: Stopped target Local File Systems. Oct 27 15:46:08.799835 systemd[1]: Stopped target Preparation for Local File Systems. Oct 27 15:46:08.801441 systemd[1]: Stopped target Swaps. Oct 27 15:46:08.802691 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 27 15:46:08.802799 systemd[1]: Stopped dracut pre-mount hook. Oct 27 15:46:08.804253 systemd[1]: Stopped target Local Encrypted Volumes. Oct 27 15:46:08.805532 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 27 15:46:08.809170 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 27 15:46:08.810266 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 27 15:46:08.810378 systemd[1]: Stopped dracut initqueue hook. Oct 27 15:46:08.816005 iscsid[486]: iscsid shutting down. Oct 27 15:46:08.812186 systemd[1]: Stopping Open-iSCSI... Oct 27 15:46:08.816072 systemd[1]: Stopping /sysroot/boot... Oct 27 15:46:08.816786 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 27 15:46:08.816906 systemd[1]: Stopped Coldplug All udev Devices. Oct 27 15:46:08.818158 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 27 15:46:08.818245 systemd[1]: Stopped dracut pre-trigger hook. Oct 27 15:46:08.820333 systemd[1]: iscsid.service: Deactivated successfully. Oct 27 15:46:08.820459 systemd[1]: Stopped Open-iSCSI. Oct 27 15:46:08.822129 systemd[1]: iscsid.socket: Deactivated successfully. Oct 27 15:46:08.822250 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 27 15:46:08.823950 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 27 15:46:08.825193 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 27 15:46:08.825319 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 27 15:46:08.826827 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 27 15:46:08.826938 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 27 15:46:08.829079 systemd[1]: Stopped target Network. Oct 27 15:46:08.830528 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 27 15:46:08.830590 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 27 15:46:08.832070 systemd[1]: Stopping Network Name Resolution... Oct 27 15:46:08.833628 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 27 15:46:08.833731 systemd[1]: Stopped /sysroot/boot. Oct 27 15:46:08.838426 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 27 15:46:08.838538 systemd[1]: Stopped Network Name Resolution. Oct 27 15:46:08.840123 systemd[1]: Stopping Network Configuration... Oct 27 15:46:08.846000 audit: BPF prog-id=14 op=UNLOAD Oct 27 15:46:08.843247 systemd-networkd[432]: eth0: DHCP lease lost Oct 27 15:46:08.853193 systemd-networkd[432]: eth0: DHCPv6 lease lost Oct 27 15:46:08.854229 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 27 15:46:08.854360 systemd[1]: Stopped Network Configuration. Oct 27 15:46:08.856045 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 27 15:46:08.856075 systemd[1]: Closed Network Service Netlink Socket. Oct 27 15:46:08.857987 systemd[1]: Stopping Network Cleanup... Oct 27 15:46:08.858776 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 27 15:46:08.858848 systemd[1]: Stopped Apply Kernel Variables. Oct 27 15:46:08.860334 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 27 15:46:08.865000 audit: BPF prog-id=18 op=UNLOAD Oct 27 15:46:08.866370 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 27 15:46:08.866492 systemd[1]: Stopped Network Cleanup. Oct 27 15:46:08.868797 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 27 15:46:08.868927 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 27 15:46:08.870927 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 27 15:46:08.870966 systemd[1]: Closed udev Control Socket. Oct 27 15:46:08.872435 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 27 15:46:08.872469 systemd[1]: Closed udev Kernel Socket. Oct 27 15:46:08.873935 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 27 15:46:08.873982 systemd[1]: Stopped dracut pre-udev hook. Oct 27 15:46:08.875269 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 27 15:46:08.875327 systemd[1]: Stopped dracut cmdline hook. Oct 27 15:46:08.876831 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 27 15:46:08.876874 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 27 15:46:08.879029 systemd[1]: Starting Cleanup udev Database... Oct 27 15:46:08.880127 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 27 15:46:08.881449 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 27 15:46:08.881511 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 27 15:46:08.883166 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 27 15:46:08.883212 systemd[1]: Stopped Create List of Static Device Nodes. Oct 27 15:46:08.884667 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 27 15:46:08.895000 audit: BPF prog-id=11 op=UNLOAD Oct 27 15:46:08.884710 systemd[1]: Stopped Setup Virtual Console. Oct 27 15:46:08.886478 systemd[1]: rngd.service: Deactivated successfully. Oct 27 15:46:08.886598 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 27 15:46:08.888133 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 27 15:46:08.899000 audit: BPF prog-id=15 op=UNLOAD Oct 27 15:46:08.888264 systemd[1]: Finished Cleanup udev Database. Oct 27 15:46:08.889498 systemd[1]: Reached target Switch Root. Oct 27 15:46:08.891521 systemd[1]: Starting Switch Root... Oct 27 15:46:08.896823 systemd[1]: Switching root. Oct 27 15:46:08.912401 systemd-journald[279]: Journal stopped Oct 27 15:46:11.056805 systemd-journald[279]: Received SIGTERM from PID 1 (systemd). Oct 27 15:46:11.056865 kernel: SELinux: policy capability network_peer_controls=1 Oct 27 15:46:11.056877 kernel: SELinux: policy capability open_perms=1 Oct 27 15:46:11.056889 kernel: SELinux: policy capability extended_socket_class=1 Oct 27 15:46:11.056898 kernel: SELinux: policy capability always_check_network=0 Oct 27 15:46:11.056908 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 27 15:46:11.056917 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 27 15:46:11.056927 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 27 15:46:11.056936 systemd[1]: Successfully loaded SELinux policy in 30.953ms. Oct 27 15:46:11.056954 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.352ms. Oct 27 15:46:11.056965 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 27 15:46:11.056975 systemd[1]: Detected virtualization kvm. Oct 27 15:46:11.056985 systemd[1]: Detected architecture arm64. Oct 27 15:46:11.056995 systemd[1]: Detected first boot. Oct 27 15:46:11.057004 systemd[1]: Initializing machine ID from VM UUID. Oct 27 15:46:11.057014 systemd[1]: Populated /etc with preset unit settings. Oct 27 15:46:11.057025 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 27 15:46:11.057037 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 27 15:46:11.057047 kernel: kauditd_printk_skb: 24 callbacks suppressed Oct 27 15:46:11.057056 kernel: audit: type=1334 audit(1761579970.924:35): prog-id=21 op=LOAD Oct 27 15:46:11.057065 kernel: audit: type=1334 audit(1761579970.924:36): prog-id=22 op=LOAD Oct 27 15:46:11.057073 kernel: audit: type=1334 audit(1761579970.925:37): prog-id=23 op=LOAD Oct 27 15:46:11.057082 kernel: audit: type=1334 audit(1761579970.925:38): prog-id=16 op=UNLOAD Oct 27 15:46:11.057091 kernel: audit: type=1334 audit(1761579970.925:39): prog-id=17 op=UNLOAD Oct 27 15:46:11.057099 kernel: audit: type=1334 audit(1761579970.933:40): prog-id=24 op=LOAD Oct 27 15:46:11.057108 kernel: audit: type=1334 audit(1761579970.933:41): prog-id=21 op=UNLOAD Oct 27 15:46:11.057117 kernel: audit: type=1334 audit(1761579970.934:42): prog-id=25 op=LOAD Oct 27 15:46:11.057127 kernel: audit: type=1334 audit(1761579970.934:43): prog-id=26 op=LOAD Oct 27 15:46:11.057149 kernel: audit: type=1334 audit(1761579970.934:44): prog-id=22 op=UNLOAD Oct 27 15:46:11.057160 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 27 15:46:11.057170 systemd[1]: Stopped Switch Root. Oct 27 15:46:11.057180 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 27 15:46:11.057190 systemd[1]: Created slice Slice /system/addon-config. Oct 27 15:46:11.057199 systemd[1]: Created slice Slice /system/addon-run. Oct 27 15:46:11.057210 systemd[1]: Created slice Slice /system/getty. Oct 27 15:46:11.057220 systemd[1]: Created slice Slice /system/modprobe. Oct 27 15:46:11.057230 systemd[1]: Created slice Slice /system/serial-getty. Oct 27 15:46:11.057239 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 27 15:46:11.057249 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 27 15:46:11.057258 systemd[1]: Created slice User and Session Slice. Oct 27 15:46:11.057268 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 27 15:46:11.057284 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 27 15:46:11.057294 systemd[1]: Set up automount Boot partition Automount Point. Oct 27 15:46:11.057306 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 27 15:46:11.057316 systemd[1]: Stopped target Switch Root. Oct 27 15:46:11.057325 systemd[1]: Stopped target Initrd File Systems. Oct 27 15:46:11.057336 systemd[1]: Stopped target Initrd Root File System. Oct 27 15:46:11.057346 systemd[1]: Reached target Remote Encrypted Volumes. Oct 27 15:46:11.057356 systemd[1]: Reached target Remote File Systems. Oct 27 15:46:11.057367 systemd[1]: Reached target Slice Units. Oct 27 15:46:11.057377 systemd[1]: Reached target Swaps. Oct 27 15:46:11.057387 systemd[1]: Reached target Verify torcx succeeded. Oct 27 15:46:11.057397 systemd[1]: Reached target Local Verity Protected Volumes. Oct 27 15:46:11.057408 systemd[1]: Listening on Process Core Dump Socket. Oct 27 15:46:11.057417 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 27 15:46:11.057427 systemd[1]: Listening on Network Service Netlink Socket. Oct 27 15:46:11.057437 systemd[1]: Listening on udev Control Socket. Oct 27 15:46:11.057447 systemd[1]: Listening on udev Kernel Socket. Oct 27 15:46:11.057457 systemd[1]: Mounting Huge Pages File System... Oct 27 15:46:11.057466 systemd[1]: Mounting POSIX Message Queue File System... Oct 27 15:46:11.057476 systemd[1]: Mounting External Media Directory... Oct 27 15:46:11.057485 systemd[1]: Mounting Kernel Debug File System... Oct 27 15:46:11.057496 systemd[1]: Mounting Kernel Trace File System... Oct 27 15:46:11.057505 systemd[1]: Mounting Temporary Directory /tmp... Oct 27 15:46:11.057515 systemd[1]: Starting Create missing system files... Oct 27 15:46:11.057525 systemd[1]: Starting Create List of Static Device Nodes... Oct 27 15:46:11.057537 systemd[1]: Starting Load Kernel Module configfs... Oct 27 15:46:11.057547 systemd[1]: Starting Load Kernel Module drm... Oct 27 15:46:11.057556 systemd[1]: Starting Load Kernel Module fuse... Oct 27 15:46:11.057567 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 27 15:46:11.057577 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 27 15:46:11.057587 systemd[1]: Stopped File System Check on Root Device. Oct 27 15:46:11.057598 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 27 15:46:11.057608 systemd[1]: Stopped systemd-fsck-usr.service. Oct 27 15:46:11.057617 kernel: fuse: init (API version 7.32) Oct 27 15:46:11.057627 systemd[1]: Stopped Journal Service. Oct 27 15:46:11.057638 systemd[1]: Starting Journal Service... Oct 27 15:46:11.057648 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 27 15:46:11.057658 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 27 15:46:11.057668 systemd[1]: Starting Apply Kernel Variables... Oct 27 15:46:11.057678 systemd[1]: Starting Coldplug All udev Devices... Oct 27 15:46:11.057688 systemd[1]: verity-setup.service: Deactivated successfully. Oct 27 15:46:11.057699 systemd[1]: Stopped verity-setup.service. Oct 27 15:46:11.057710 systemd-journald[804]: Journal started Oct 27 15:46:11.057743 systemd-journald[804]: Runtime Journal (/run/log/journal/c7124dca645d438da9b16aa25dd9a4aa) is 6.0M, max 48.5M, 42.4M free. Oct 27 15:46:08.975000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 27 15:46:09.006000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 27 15:46:09.006000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 27 15:46:09.006000 audit: BPF prog-id=19 op=LOAD Oct 27 15:46:09.006000 audit: BPF prog-id=19 op=UNLOAD Oct 27 15:46:09.006000 audit: BPF prog-id=20 op=LOAD Oct 27 15:46:09.006000 audit: BPF prog-id=20 op=UNLOAD Oct 27 15:46:09.036000 audit[753]: AVC avc: denied { associate } for pid=753 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 27 15:46:10.924000 audit: BPF prog-id=21 op=LOAD Oct 27 15:46:10.924000 audit: BPF prog-id=22 op=LOAD Oct 27 15:46:10.925000 audit: BPF prog-id=23 op=LOAD Oct 27 15:46:10.925000 audit: BPF prog-id=16 op=UNLOAD Oct 27 15:46:10.925000 audit: BPF prog-id=17 op=UNLOAD Oct 27 15:46:10.933000 audit: BPF prog-id=24 op=LOAD Oct 27 15:46:10.933000 audit: BPF prog-id=21 op=UNLOAD Oct 27 15:46:10.934000 audit: BPF prog-id=25 op=LOAD Oct 27 15:46:10.934000 audit: BPF prog-id=26 op=LOAD Oct 27 15:46:10.934000 audit: BPF prog-id=22 op=UNLOAD Oct 27 15:46:10.934000 audit: BPF prog-id=23 op=UNLOAD Oct 27 15:46:10.935000 audit: BPF prog-id=27 op=LOAD Oct 27 15:46:10.935000 audit: BPF prog-id=24 op=UNLOAD Oct 27 15:46:10.936000 audit: BPF prog-id=28 op=LOAD Oct 27 15:46:10.937000 audit: BPF prog-id=29 op=LOAD Oct 27 15:46:10.937000 audit: BPF prog-id=25 op=UNLOAD Oct 27 15:46:10.937000 audit: BPF prog-id=26 op=UNLOAD Oct 27 15:46:10.945000 audit: BPF prog-id=27 op=UNLOAD Oct 27 15:46:11.041000 audit: BPF prog-id=30 op=LOAD Oct 27 15:46:11.042000 audit: BPF prog-id=31 op=LOAD Oct 27 15:46:11.042000 audit: BPF prog-id=32 op=LOAD Oct 27 15:46:11.042000 audit: BPF prog-id=28 op=UNLOAD Oct 27 15:46:11.042000 audit: BPF prog-id=29 op=UNLOAD Oct 27 15:46:11.055000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 27 15:46:10.923519 systemd[1]: Queued start job for default target Multi-User System. Oct 27 15:46:09.034896 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 27 15:46:10.938050 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 27 15:46:09.035181 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 27 15:46:09.035200 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 27 15:46:09.035327 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 27 15:46:09.035338 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 27 15:46:09.035370 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 27 15:46:09.035382 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 27 15:46:09.035581 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 27 15:46:09.035617 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 27 15:46:09.035631 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 27 15:46:09.036016 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 27 15:46:09.036048 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 27 15:46:09.036069 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 27 15:46:09.036084 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 27 15:46:09.036102 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 27 15:46:09.036116 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:09Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 27 15:46:10.748012 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:10Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 27 15:46:10.748312 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:10Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 27 15:46:10.748414 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:10Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 27 15:46:10.748536 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:10Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 27 15:46:10.748590 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:10Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 27 15:46:10.748647 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-27T15:46:10Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 27 15:46:11.060158 systemd[1]: Started Journal Service. Oct 27 15:46:11.060726 systemd[1]: Mounted Huge Pages File System. Oct 27 15:46:11.061733 systemd[1]: Mounted POSIX Message Queue File System. Oct 27 15:46:11.062803 systemd[1]: Mounted External Media Directory. Oct 27 15:46:11.063806 systemd[1]: Mounted Kernel Debug File System. Oct 27 15:46:11.064790 systemd[1]: Mounted Kernel Trace File System. Oct 27 15:46:11.065799 systemd[1]: Mounted Temporary Directory /tmp. Oct 27 15:46:11.067008 systemd[1]: Finished Create missing system files. Oct 27 15:46:11.068335 systemd[1]: Finished Create List of Static Device Nodes. Oct 27 15:46:11.069559 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 27 15:46:11.069758 systemd[1]: Finished Load Kernel Module configfs. Oct 27 15:46:11.070907 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 27 15:46:11.071104 systemd[1]: Finished Load Kernel Module drm. Oct 27 15:46:11.072238 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 27 15:46:11.072429 systemd[1]: Finished Load Kernel Module fuse. Oct 27 15:46:11.082666 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 27 15:46:11.084036 systemd[1]: Finished Apply Kernel Variables. Oct 27 15:46:11.087246 systemd[1]: Mounting FUSE Control File System... Oct 27 15:46:11.089151 systemd[1]: Mounting Kernel Configuration File System... Oct 27 15:46:11.090027 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 27 15:46:11.091956 systemd[1]: Starting Rebuild Hardware Database... Oct 27 15:46:11.093973 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 27 15:46:11.095108 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 27 15:46:11.096443 systemd[1]: Starting Load/Save Random Seed... Oct 27 15:46:11.098514 systemd-journald[804]: Time spent on flushing to /var/log/journal/c7124dca645d438da9b16aa25dd9a4aa is 8.175ms for 784 entries. Oct 27 15:46:11.098514 systemd-journald[804]: System Journal (/var/log/journal/c7124dca645d438da9b16aa25dd9a4aa) is 8.0M, max 203.0M, 195.0M free. Oct 27 15:46:11.098475 systemd[1]: Starting Create System Users... Oct 27 15:46:11.102532 systemd[1]: Mounted FUSE Control File System. Oct 27 15:46:11.103662 systemd[1]: Mounted Kernel Configuration File System. Oct 27 15:46:11.107655 systemd[1]: Finished Coldplug All udev Devices. Oct 27 15:46:11.120070 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 27 15:46:11.121723 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 27 15:46:11.123257 systemd[1]: Finished Load/Save Random Seed. Oct 27 15:46:11.124446 systemd[1]: Reached target First Boot Complete. Oct 27 15:46:11.126132 systemd-sysusers[815]: Creating group sgx with gid 999. Oct 27 15:46:11.127151 systemd-sysusers[815]: Creating group systemd-oom with gid 998. Oct 27 15:46:11.127840 systemd-sysusers[815]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 27 15:46:11.128574 systemd-sysusers[815]: Creating group systemd-timesync with gid 997. Oct 27 15:46:11.129123 systemd-sysusers[815]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 27 15:46:11.129838 systemd-sysusers[815]: Creating group systemd-coredump with gid 996. Oct 27 15:46:11.130404 systemd-sysusers[815]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 27 15:46:11.140290 systemd[1]: Finished Create System Users. Oct 27 15:46:11.150096 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 27 15:46:11.163771 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 27 15:46:11.345509 systemd[1]: Finished Rebuild Hardware Database. Oct 27 15:46:11.368000 audit: BPF prog-id=33 op=LOAD Oct 27 15:46:11.368000 audit: BPF prog-id=34 op=LOAD Oct 27 15:46:11.368000 audit: BPF prog-id=35 op=LOAD Oct 27 15:46:11.368000 audit: BPF prog-id=12 op=UNLOAD Oct 27 15:46:11.368000 audit: BPF prog-id=13 op=UNLOAD Oct 27 15:46:11.369744 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 27 15:46:11.393378 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 27 15:46:11.400000 audit: BPF prog-id=36 op=LOAD Oct 27 15:46:11.401439 systemd[1]: Starting Network Configuration... Oct 27 15:46:11.428263 systemd-networkd[839]: lo: Link UP Oct 27 15:46:11.428272 systemd-networkd[839]: lo: Gained carrier Oct 27 15:46:11.428577 systemd-networkd[839]: Enumeration completed Oct 27 15:46:11.428694 systemd[1]: Started Network Configuration. Oct 27 15:46:11.435995 systemd-udevd[838]: Using default interface naming scheme 'v249'. Oct 27 15:46:11.449653 systemd-networkd[839]: eth0: Link UP Oct 27 15:46:11.454221 systemd-networkd[839]: eth0: Gained carrier Oct 27 15:46:11.460308 systemd-networkd[839]: eth0: DHCPv4 address 10.0.0.8/16 via 10.0.0.1 Oct 27 15:46:11.466011 udevadm[818]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 27 15:46:11.497355 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 27 15:46:11.504179 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 27 15:46:11.512704 lvm[847]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 27 15:46:11.537247 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 27 15:46:11.538346 systemd[1]: Reached target Local Encrypted Volumes. Oct 27 15:46:11.551207 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 27 15:46:11.554269 lvm[848]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 27 15:46:11.580130 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 27 15:46:11.581196 systemd[1]: Reached target Preparation for Local File Systems. Oct 27 15:46:11.582227 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 27 15:46:11.582250 systemd[1]: Reached target Containers. Oct 27 15:46:11.596394 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 27 15:46:11.609097 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 27 15:46:11.611377 systemd[1]: Mounting /usr/share/oem... Oct 27 15:46:11.617166 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 27 15:46:11.617235 kernel: BTRFS info (device vda6): has skinny extents Oct 27 15:46:11.619170 systemd[1]: Mounted /usr/share/oem. Oct 27 15:46:11.619963 systemd[1]: Reached target Local File Systems. Oct 27 15:46:11.629002 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 27 15:46:11.630352 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 27 15:46:11.630426 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 27 15:46:11.631595 systemd[1]: Starting Commit a transient machine-id on disk... Oct 27 15:46:11.633705 systemd[1]: Starting Create Volatile Files and Directories... Oct 27 15:46:11.640567 systemd-tmpfiles[873]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 27 15:46:11.641588 systemd-tmpfiles[873]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 27 15:46:11.649133 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 27 15:46:11.649914 systemd[1]: Finished Commit a transient machine-id on disk. Oct 27 15:46:11.687640 systemd-tmpfiles[873]: Detected autofs mount point /boot during canonicalization of /boot. Oct 27 15:46:11.687784 systemd-tmpfiles[873]: Skipping /boot Oct 27 15:46:11.692557 systemd-tmpfiles[873]: Detected autofs mount point /boot during canonicalization of /boot. Oct 27 15:46:11.692663 systemd-tmpfiles[873]: Skipping /boot Oct 27 15:46:11.722181 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 27 15:46:11.735586 systemd[1]: Finished Create Volatile Files and Directories. Oct 27 15:46:11.737917 systemd[1]: Starting Load Security Auditing Rules... Oct 27 15:46:11.739844 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 27 15:46:11.742406 systemd[1]: Starting Rebuild Journal Catalog... Oct 27 15:46:11.743000 audit: BPF prog-id=37 op=LOAD Oct 27 15:46:11.744632 systemd[1]: Starting Network Name Resolution... Oct 27 15:46:11.747000 audit: BPF prog-id=38 op=LOAD Oct 27 15:46:11.748608 systemd[1]: Starting Network Time Synchronization... Oct 27 15:46:11.750918 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 27 15:46:11.752636 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 27 15:46:11.754048 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 27 15:46:11.757961 systemd[1]: Finished Rebuild Journal Catalog. Oct 27 15:46:11.765124 systemd[1]: Starting Update is Completed... Oct 27 15:46:11.769679 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 27 15:46:11.771054 systemd[1]: Finished Update is Completed. Oct 27 15:46:11.777808 augenrules[896]: No rules Oct 27 15:46:11.777000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 27 15:46:11.782578 systemd[1]: Finished Load Security Auditing Rules. Oct 27 15:46:11.785264 systemd-resolved[881]: Positive Trust Anchors: Oct 27 15:46:11.785284 systemd-resolved[881]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 27 15:46:11.785313 systemd-resolved[881]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 27 15:46:11.786039 systemd-resolved[881]: Defaulting to hostname 'linux'. Oct 27 15:46:11.787062 systemd[1]: Started Network Time Synchronization. Oct 27 15:46:11.788328 systemd-timesyncd[884]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 27 15:46:11.788435 systemd[1]: Started Network Name Resolution. Oct 27 15:46:11.789292 systemd[1]: Reached target Network. Oct 27 15:46:11.789982 systemd[1]: Reached target Host and Network Name Lookups. Oct 27 15:46:11.790990 systemd[1]: Reached target System Initialization. Oct 27 15:46:11.791977 systemd[1]: Started Watch for update engine configuration changes. Oct 27 15:46:11.793145 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 27 15:46:11.794241 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 27 15:46:11.795224 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 27 15:46:11.795258 systemd[1]: Reached target Path Units. Oct 27 15:46:11.795996 systemd[1]: Reached target System Time Set. Oct 27 15:46:11.797028 systemd[1]: Started Daily Log Rotation. Oct 27 15:46:11.798011 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 27 15:46:11.799180 systemd[1]: Reached target Timer Units. Oct 27 15:46:11.800334 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 27 15:46:11.802316 systemd[1]: Starting Docker Socket for the API... Oct 27 15:46:11.805380 systemd[1]: Listening on OpenSSH Server Socket. Oct 27 15:46:11.806706 systemd[1]: Listening on Docker Socket for the API. Oct 27 15:46:11.807677 systemd[1]: Reached target Socket Units. Oct 27 15:46:11.808491 systemd[1]: Reached target Basic System. Oct 27 15:46:11.809335 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 27 15:46:11.809366 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 27 15:46:11.810365 systemd[1]: Started D-Bus System Message Bus. Oct 27 15:46:11.813955 systemd[1]: Starting Extend Filesystems... Oct 27 15:46:11.814893 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 27 15:46:11.815982 systemd[1]: Starting Generate /run/flatcar/motd... Oct 27 15:46:11.817996 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 27 15:46:11.820181 systemd[1]: Starting Generate sshd host keys... Oct 27 15:46:11.821061 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 27 15:46:11.821105 systemd[1]: Reached target Load system-provided cloud configs. Oct 27 15:46:11.825311 systemd[1]: Starting User Login Management... Oct 27 15:46:11.826394 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 27 15:46:11.826991 systemd[1]: Starting Update Engine... Oct 27 15:46:11.831724 extend-filesystems[905]: Found vda Oct 27 15:46:11.831724 extend-filesystems[905]: Found vda1 Oct 27 15:46:11.831724 extend-filesystems[905]: Found vda2 Oct 27 15:46:11.831724 extend-filesystems[905]: Found vda3 Oct 27 15:46:11.831724 extend-filesystems[905]: Found usr Oct 27 15:46:11.831724 extend-filesystems[905]: Found vda4 Oct 27 15:46:11.831724 extend-filesystems[905]: Found vda6 Oct 27 15:46:11.831724 extend-filesystems[905]: Found vda7 Oct 27 15:46:11.831724 extend-filesystems[905]: Found vda9 Oct 27 15:46:11.831724 extend-filesystems[905]: Checking size of /dev/vda9 Oct 27 15:46:11.827765 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 27 15:46:11.867220 extend-filesystems[905]: Old size kept for /dev/vda9 Oct 27 15:46:11.827794 systemd[1]: Reached target Load user-provided cloud configs. Oct 27 15:46:11.829436 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 27 15:46:11.829655 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 27 15:46:11.844634 systemd[1]: motdgen.service: Deactivated successfully. Oct 27 15:46:11.844848 systemd[1]: Finished Generate /run/flatcar/motd. Oct 27 15:46:11.862477 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 27 15:46:11.862710 systemd[1]: Finished Extend Filesystems. Oct 27 15:46:11.873736 update_engine[920]: I1027 15:46:11.873371 920 main.cc:89] Flatcar Update Engine starting Oct 27 15:46:11.874559 systemd-logind[916]: Watching system buttons on /dev/input/event0 (Power Button) Oct 27 15:46:11.875548 systemd[1]: Started Update Engine. Oct 27 15:46:11.875725 update_engine[920]: I1027 15:46:11.875694 920 update_check_scheduler.cc:74] Next update check in 8m35s Oct 27 15:46:11.875845 systemd-logind[916]: New seat seat0. Oct 27 15:46:11.887471 systemd[1]: Started Cluster reboot manager. Oct 27 15:46:11.888788 systemd[1]: Started User Login Management. Oct 27 15:46:11.913350 locksmithd[928]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 27 15:46:12.414279 systemd[1]: Created slice Slice /system/sshd. Oct 27 15:46:12.500984 sshd_keygen[921]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 27 15:46:12.519069 systemd[1]: Finished Generate sshd host keys. Oct 27 15:46:12.531213 systemd[1]: Starting Generate /run/issue... Oct 27 15:46:12.532902 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:60106). Oct 27 15:46:12.535846 systemd[1]: issuegen.service: Deactivated successfully. Oct 27 15:46:12.536064 systemd[1]: Finished Generate /run/issue. Oct 27 15:46:12.538174 systemd[1]: Starting Permit User Sessions... Oct 27 15:46:12.543127 systemd[1]: Finished Permit User Sessions. Oct 27 15:46:12.545172 systemd[1]: Started Getty on tty1. Oct 27 15:46:12.547079 systemd[1]: Started Serial Getty on ttyAMA0. Oct 27 15:46:12.548119 systemd[1]: Reached target Login Prompts. Oct 27 15:46:12.548971 systemd[1]: Reached target Multi-User System. Oct 27 15:46:12.551042 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 27 15:46:12.556409 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 27 15:46:12.556636 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 27 15:46:12.557895 systemd[1]: Startup finished in 730ms (kernel) + 2.385s (initrd) + 3.615s (userspace) = 6.731s. Oct 27 15:46:12.579455 sshd[944]: Accepted publickey for core from 10.0.0.1 port 60106 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:12.580853 sshd[944]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:12.592615 systemd[1]: Created slice User Slice of UID 500. Oct 27 15:46:12.593617 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 27 15:46:12.595469 systemd-logind[916]: New session 1 of user core. Oct 27 15:46:12.599702 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 27 15:46:12.600907 systemd[1]: Starting User Manager for UID 500... Oct 27 15:46:12.603338 systemd[953]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:12.649110 systemd[953]: Queued start job for default target Main User Target. Oct 27 15:46:12.649316 systemd[953]: Reached target Paths. Oct 27 15:46:12.649331 systemd[953]: Reached target Sockets. Oct 27 15:46:12.649341 systemd[953]: Reached target Timers. Oct 27 15:46:12.649351 systemd[953]: Reached target Basic System. Oct 27 15:46:12.649402 systemd[953]: Reached target Main User Target. Oct 27 15:46:12.649416 systemd[953]: Startup finished in 42ms. Oct 27 15:46:12.649487 systemd[1]: Started User Manager for UID 500. Oct 27 15:46:12.658315 systemd[1]: Started Session 1 of User core. Oct 27 15:46:12.721176 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56116). Oct 27 15:46:12.764385 sshd[962]: Accepted publickey for core from 10.0.0.1 port 56116 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:12.765466 sshd[962]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:12.768198 systemd-logind[916]: New session 2 of user core. Oct 27 15:46:12.774330 systemd[1]: Started Session 2 of User core. Oct 27 15:46:12.825603 sshd[962]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:12.838780 systemd[1]: sshd@1-10.0.0.8:22-10.0.0.1:56116.service: Deactivated successfully. Oct 27 15:46:12.839393 systemd[1]: session-2.scope: Deactivated successfully. Oct 27 15:46:12.839998 systemd-logind[916]: Session 2 logged out. Waiting for processes to exit. Oct 27 15:46:12.840986 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56132). Oct 27 15:46:12.841672 systemd-logind[916]: Removed session 2. Oct 27 15:46:12.882238 sshd[968]: Accepted publickey for core from 10.0.0.1 port 56132 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:12.883202 sshd[968]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:12.885985 systemd-logind[916]: New session 3 of user core. Oct 27 15:46:12.898287 systemd[1]: Started Session 3 of User core. Oct 27 15:46:12.945519 sshd[968]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:12.959996 systemd[1]: sshd@2-10.0.0.8:22-10.0.0.1:56132.service: Deactivated successfully. Oct 27 15:46:12.960528 systemd[1]: session-3.scope: Deactivated successfully. Oct 27 15:46:12.961118 systemd-logind[916]: Session 3 logged out. Waiting for processes to exit. Oct 27 15:46:12.962022 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56136). Oct 27 15:46:12.962916 systemd-logind[916]: Removed session 3. Oct 27 15:46:13.003593 sshd[974]: Accepted publickey for core from 10.0.0.1 port 56136 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:13.004019 sshd[974]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:13.006520 systemd-logind[916]: New session 4 of user core. Oct 27 15:46:13.017299 systemd[1]: Started Session 4 of User core. Oct 27 15:46:13.067274 sshd[974]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:13.074641 systemd[1]: sshd@3-10.0.0.8:22-10.0.0.1:56136.service: Deactivated successfully. Oct 27 15:46:13.075179 systemd[1]: session-4.scope: Deactivated successfully. Oct 27 15:46:13.075751 systemd-logind[916]: Session 4 logged out. Waiting for processes to exit. Oct 27 15:46:13.076637 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56152). Oct 27 15:46:13.077221 systemd-logind[916]: Removed session 4. Oct 27 15:46:13.117365 sshd[980]: Accepted publickey for core from 10.0.0.1 port 56152 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:13.118499 sshd[980]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:13.120987 systemd-logind[916]: New session 5 of user core. Oct 27 15:46:13.131282 systemd[1]: Started Session 5 of User core. Oct 27 15:46:13.184334 sudo[983]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 27 15:46:13.184537 sudo[983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:46:13.200645 sudo[983]: pam_unix(sudo:session): session closed for user root Oct 27 15:46:13.203424 sshd[980]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:13.215454 dbus-daemon[904]: [system] Reloaded configuration Oct 27 15:46:13.215798 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56162). Oct 27 15:46:13.217871 systemd[1]: sshd@4-10.0.0.8:22-10.0.0.1:56152.service: Deactivated successfully. Oct 27 15:46:13.218489 systemd[1]: session-5.scope: Deactivated successfully. Oct 27 15:46:13.219083 systemd-logind[916]: Session 5 logged out. Waiting for processes to exit. Oct 27 15:46:13.219730 systemd-logind[916]: Removed session 5. Oct 27 15:46:13.258796 sshd[986]: Accepted publickey for core from 10.0.0.1 port 56162 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:13.259716 sshd[986]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:13.262255 systemd-logind[916]: New session 6 of user core. Oct 27 15:46:13.271292 systemd[1]: Started Session 6 of User core. Oct 27 15:46:13.320404 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 27 15:46:13.320628 sudo[991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:46:13.323016 sudo[991]: pam_unix(sudo:session): session closed for user root Oct 27 15:46:13.326796 sudo[990]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 27 15:46:13.326997 sudo[990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:46:13.344221 systemd[1]: Stopping Load Security Auditing Rules... Oct 27 15:46:13.344000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 27 15:46:13.345372 auditctl[994]: No rules Oct 27 15:46:13.345614 systemd[1]: audit-rules.service: Deactivated successfully. Oct 27 15:46:13.345842 systemd[1]: Stopped Load Security Auditing Rules. Oct 27 15:46:13.347192 systemd[1]: Starting Load Security Auditing Rules... Oct 27 15:46:13.361287 augenrules[1011]: No rules Oct 27 15:46:13.362161 systemd[1]: Finished Load Security Auditing Rules. Oct 27 15:46:13.363240 sudo[990]: pam_unix(sudo:session): session closed for user root Oct 27 15:46:13.364432 sshd[986]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:13.372111 systemd[1]: sshd@5-10.0.0.8:22-10.0.0.1:56162.service: Deactivated successfully. Oct 27 15:46:13.372698 systemd[1]: session-6.scope: Deactivated successfully. Oct 27 15:46:13.373338 systemd-logind[916]: Session 6 logged out. Waiting for processes to exit. Oct 27 15:46:13.374338 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56168). Oct 27 15:46:13.375223 systemd-logind[916]: Removed session 6. Oct 27 15:46:13.410361 systemd-networkd[839]: eth0: Gained IPv6LL Oct 27 15:46:13.415450 sshd[1017]: Accepted publickey for core from 10.0.0.1 port 56168 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:13.416462 sshd[1017]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:13.419521 systemd-logind[916]: New session 7 of user core. Oct 27 15:46:13.428365 systemd[1]: Started Session 7 of User core. Oct 27 15:46:13.478125 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 27 15:46:16.292937 update_engine[1269]: Oct 27 15:46:16.292937 update_engine[1269]: Oct 27 15:46:16.292937 update_engine[1269]: Oct 27 15:46:16.292937 update_engine[1269]: Oct 27 15:46:16.292937 update_engine[1269]: Oct 27 15:46:16.292937 update_engine[1269]: Oct 27 15:46:16.292937 update_engine[1269]: Oct 27 15:46:16.292937 update_engine[1269]: Oct 27 15:46:16.292937 update_engine[1269]: I1027 15:46:16.292929 1269 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 27 15:46:16.299414 update_engine[1269]: I1027 15:46:16.299314 1269 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 27 15:46:16.300657 update_engine[1269]: I1027 15:46:16.300618 1269 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 27 15:46:16.302267 update_engine[1269]: I1027 15:46:16.302239 1269 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 27 15:46:16.302351 update_engine[1269]: I1027 15:46:16.302335 1269 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Oct 27 15:46:16.302351 update_engine[1269]: I1027 15:46:16.302348 1269 omaha_request_action.cc:619] Omaha request response: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.302351 update_engine[1269]: Oct 27 15:46:16.305903 update_engine[1269]: I1027 15:46:16.305863 1269 omaha_request_action.cc:447] Omaha Response manifest version = Oct 27 15:46:16.305903 update_engine[1269]: I1027 15:46:16.305898 1269 omaha_request_action.cc:470] Found 1 url(s) Oct 27 15:46:16.305903 update_engine[1269]: I1027 15:46:16.305912 1269 omaha_request_action.cc:506] Processing first of 1 package(s) Oct 27 15:46:16.305998 update_engine[1269]: I1027 15:46:16.305915 1269 omaha_request_action.cc:513] Omaha Response package name = update.gz Oct 27 15:46:16.305998 update_engine[1269]: I1027 15:46:16.305918 1269 omaha_request_action.cc:529] Url0: http://10.0.0.7:34567/packages/update.gz Oct 27 15:46:16.305998 update_engine[1269]: I1027 15:46:16.305927 1269 omaha_request_action.cc:541] Payload size = 435420691 bytes Oct 27 15:46:16.305998 update_engine[1269]: I1027 15:46:16.305938 1269 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Oct 27 15:46:16.305998 update_engine[1269]: I1027 15:46:16.305992 1269 payload_state.cc:51] Resetting all persisted state as this is a new response Oct 27 15:46:16.305998 update_engine[1269]: I1027 15:46:16.305996 1269 payload_state.cc:360] Current Response Signature = Oct 27 15:46:16.305998 update_engine[1269]: NumURLs = 1 Oct 27 15:46:16.305998 update_engine[1269]: Url0 = http://10.0.0.7:34567/packages/update.gz Oct 27 15:46:16.305998 update_engine[1269]: Payload Size = 435420691 Oct 27 15:46:16.305998 update_engine[1269]: Payload Sha256 Hash = nLXXFElmOY/MZeJVU0GqyZXmGgOOWnMNMVrLO0s03g0= Oct 27 15:46:16.305998 update_engine[1269]: Is Delta Payload = 0 Oct 27 15:46:16.305998 update_engine[1269]: Max Failure Count Per Url = 10 Oct 27 15:46:16.305998 update_engine[1269]: Disable Payload Backoff = 1 Oct 27 15:46:16.306273 update_engine[1269]: I1027 15:46:16.306092 1269 payload_state.cc:381] Payload Attempt Number = 0 Oct 27 15:46:16.306273 update_engine[1269]: I1027 15:46:16.306149 1269 payload_state.cc:404] Current URL Index = 0 Oct 27 15:46:16.306273 update_engine[1269]: I1027 15:46:16.306186 1269 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Oct 27 15:46:16.306273 update_engine[1269]: I1027 15:46:16.306216 1269 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Oct 27 15:46:16.306273 update_engine[1269]: I1027 15:46:16.306218 1269 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 27 15:46:16.306273 update_engine[1269]: I1027 15:46:16.306250 1269 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Oct 27 15:46:16.306273 update_engine[1269]: I1027 15:46:16.306268 1269 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 27 15:46:16.306273 update_engine[1269]: I1027 15:46:16.306272 1269 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Oct 27 15:46:16.306402 update_engine[1269]: I1027 15:46:16.306288 1269 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Oct 27 15:46:16.306402 update_engine[1269]: E1027 15:46:16.306293 1269 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Oct 27 15:46:16.308933 update_engine[1269]: I1027 15:46:16.308898 1269 omaha_response_handler_action.cc:85] Using this install plan: Oct 27 15:46:16.308933 update_engine[1269]: I1027 15:46:16.308919 1269 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.7:34567/packages/update.gz, payload size: 435420691, payload hash: nLXXFElmOY/MZeJVU0GqyZXmGgOOWnMNMVrLO0s03g0=, partition_path: /dev/vda4, kernel_path: /boot/flatcar/vmlinuz-b, pcr_policy_path: /var/lib/update_engine/pcrs-b.zip, old_partition_path: /dev/vda3, old_kernel_path: /boot/flatcar/vmlinuz-a Oct 27 15:46:16.309042 update_engine[1269]: I1027 15:46:16.309023 1269 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Oct 27 15:46:16.311393 update_engine[1269]: I1027 15:46:16.311357 1269 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Oct 27 15:46:16.327263 sshd[1283]: Accepted publickey for core from 10.0.0.1 port 56332 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:16.330500 sshd[1283]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:16.331162 kernel: audit: type=1006 audit(1761579976.327:364): pid=1283 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=25 res=1 Oct 27 15:46:16.340560 systemd-logind[916]: New session 25 of user core. Oct 27 15:46:16.350333 systemd[1]: Started Session 25 of User core. Oct 27 15:46:16.405273 sshd[1283]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:16.410352 systemd[1]: sshd@24-10.0.0.8:22-10.0.0.1:56332.service: Deactivated successfully. Oct 27 15:46:16.410947 systemd[1]: session-25.scope: Deactivated successfully. Oct 27 15:46:16.411511 systemd-logind[916]: Session 25 logged out. Waiting for processes to exit. Oct 27 15:46:16.412591 systemd-logind[916]: Removed session 25. Oct 27 15:46:18.068605 update_engine[1269]: I1027 15:46:18.068567 1269 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Oct 27 15:46:18.068605 update_engine[1269]: I1027 15:46:18.068609 1269 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Oct 27 15:46:18.137127 update_engine[1269]: E1027 15:46:18.137061 1269 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Oct 27 15:46:18.137127 update_engine[1269]: I1027 15:46:18.137106 1269 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Oct 27 15:46:18.137127 update_engine[1269]: I1027 15:46:18.137111 1269 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Oct 27 15:46:18.137127 update_engine[1269]: I1027 15:46:18.137115 1269 update_attempter.cc:302] Processing Done. Oct 27 15:46:18.137359 update_engine[1269]: E1027 15:46:18.137176 1269 update_attempter.cc:615] Update failed. Oct 27 15:46:18.137359 update_engine[1269]: I1027 15:46:18.137180 1269 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Oct 27 15:46:18.137359 update_engine[1269]: I1027 15:46:18.137184 1269 payload_state.cc:276] Incrementing the URL failure count Oct 27 15:46:18.137359 update_engine[1269]: I1027 15:46:18.137187 1269 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 27 15:46:18.137477 update_engine[1269]: I1027 15:46:18.137449 1269 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 27 15:46:18.137509 update_engine[1269]: I1027 15:46:18.137478 1269 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.7:34567/v1/update Oct 27 15:46:18.137509 update_engine[1269]: I1027 15:46:18.137483 1269 omaha_request_action.cc:269] Request: Oct 27 15:46:18.137509 update_engine[1269]: Oct 27 15:46:18.137509 update_engine[1269]: Oct 27 15:46:18.137509 update_engine[1269]: Oct 27 15:46:18.137509 update_engine[1269]: Oct 27 15:46:18.137509 update_engine[1269]: Oct 27 15:46:18.137509 update_engine[1269]: Oct 27 15:46:18.137509 update_engine[1269]: I1027 15:46:18.137487 1269 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 27 15:46:18.137715 update_engine[1269]: I1027 15:46:18.137547 1269 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 27 15:46:18.137715 update_engine[1269]: I1027 15:46:18.137651 1269 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 27 15:46:18.249655 update_engine[1269]: I1027 15:46:18.249606 1269 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 27 15:46:18.249782 update_engine[1269]: I1027 15:46:18.249696 1269 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Oct 27 15:46:18.249782 update_engine[1269]: I1027 15:46:18.249703 1269 omaha_request_action.cc:619] Omaha request response: Oct 27 15:46:18.249782 update_engine[1269]: Oct 27 15:46:18.249782 update_engine[1269]: Oct 27 15:46:18.249782 update_engine[1269]: Oct 27 15:46:18.249782 update_engine[1269]: Oct 27 15:46:18.249782 update_engine[1269]: Oct 27 15:46:18.249782 update_engine[1269]: Oct 27 15:46:18.249782 update_engine[1269]: E1027 15:46:18.249717 1269 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Oct 27 15:46:18.249782 update_engine[1269]: I1027 15:46:18.249722 1269 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 27 15:46:18.249782 update_engine[1269]: I1027 15:46:18.249724 1269 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 27 15:46:18.249782 update_engine[1269]: I1027 15:46:18.249727 1269 update_attempter.cc:302] Processing Done. Oct 27 15:46:18.249782 update_engine[1269]: I1027 15:46:18.249732 1269 update_attempter.cc:306] Error event sent. Oct 27 15:46:26.329846 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:46652). Oct 27 15:46:26.373958 sshd[1302]: Accepted publickey for core from 10.0.0.1 port 46652 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:26.377151 kernel: audit: type=1006 audit(1761579986.373: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 27 15:46:26.377657 sshd[1302]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:26.380250 systemd-logind[916]: New session 26 of user core. Oct 27 15:46:26.397331 systemd[1]: Started Session 26 of User core. Oct 27 15:46:26.452785 sshd[1302]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:26.464772 systemd[1]: sshd@25-10.0.0.8:22-10.0.0.1:46652.service: Deactivated successfully. Oct 27 15:46:26.465403 systemd[1]: session-26.scope: Deactivated successfully. Oct 27 15:46:26.465987 systemd-logind[916]: Session 26 logged out. Waiting for processes to exit. Oct 27 15:46:26.466963 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:46656). Oct 27 15:46:26.467464 systemd-logind[916]: Removed session 26. Oct 27 15:46:26.508555 sshd[1309]: Accepted publickey for core from 10.0.0.1 port 46656 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:26.509645 sshd[1309]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:26.512153 kernel: audit: type=1006 audit(1761579986.509: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 27 15:46:26.513818 systemd-logind[916]: New session 27 of user core. Oct 27 15:46:26.530294 systemd[1]: Started Session 27 of User core. Oct 27 15:46:26.584394 sshd[1309]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:26.592673 systemd[1]: sshd@26-10.0.0.8:22-10.0.0.1:46656.service: Deactivated successfully. Oct 27 15:46:26.593302 systemd[1]: session-27.scope: Deactivated successfully. Oct 27 15:46:26.593871 systemd-logind[916]: Session 27 logged out. Waiting for processes to exit. Oct 27 15:46:26.594824 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:46662). Oct 27 15:46:26.595560 systemd-logind[916]: Removed session 27. Oct 27 15:46:26.635810 sshd[1317]: Accepted publickey for core from 10.0.0.1 port 46662 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:26.636809 sshd[1317]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:26.639168 kernel: audit: type=1006 audit(1761579986.635: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 27 15:46:26.641373 systemd-logind[916]: New session 28 of user core. Oct 27 15:46:26.651400 systemd[1]: Started Session 28 of User core. Oct 27 15:46:26.699944 sshd[1317]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:26.713240 systemd[1]: sshd@27-10.0.0.8:22-10.0.0.1:46662.service: Deactivated successfully. Oct 27 15:46:26.714157 systemd[1]: session-28.scope: Deactivated successfully. Oct 27 15:46:26.714959 systemd-logind[916]: Session 28 logged out. Waiting for processes to exit. Oct 27 15:46:26.715877 systemd-logind[916]: Removed session 28. Oct 27 15:46:26.717620 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:46668). Oct 27 15:46:26.758543 sshd[1323]: Accepted publickey for core from 10.0.0.1 port 46668 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:26.759814 sshd[1323]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:26.762157 kernel: audit: type=1006 audit(1761579986.759: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 27 15:46:26.763829 systemd-logind[916]: New session 29 of user core. Oct 27 15:46:26.776287 systemd[1]: Started Session 29 of User core. Oct 27 15:46:26.824252 sshd[1323]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:26.832122 systemd[1]: sshd@28-10.0.0.8:22-10.0.0.1:46668.service: Deactivated successfully. Oct 27 15:46:26.832671 systemd[1]: session-29.scope: Deactivated successfully. Oct 27 15:46:26.833304 systemd-logind[916]: Session 29 logged out. Waiting for processes to exit. Oct 27 15:46:26.834358 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:46672). Oct 27 15:46:26.835271 systemd-logind[916]: Removed session 29. Oct 27 15:46:26.875570 sshd[1329]: Accepted publickey for core from 10.0.0.1 port 46672 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:26.876460 sshd[1329]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:26.879182 kernel: audit: type=1006 audit(1761579986.875: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 27 15:46:26.880691 systemd-logind[916]: New session 30 of user core. Oct 27 15:46:26.888295 systemd[1]: Started Session 30 of User core. Oct 27 15:46:26.939746 sudo[1333]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Oct 27 15:46:26.939949 sudo[1333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:46:26.946073 systemd[1]: sshd.socket: Deactivated successfully. Oct 27 15:46:26.946450 systemd[1]: Closed OpenSSH Server Socket. Oct 27 15:46:26.947389 sudo[1333]: pam_unix(sudo:session): session closed for user root Oct 27 15:46:26.951449 sudo[1332]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Oct 27 15:46:26.951666 sudo[1332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:46:26.974984 systemd[1]: Stopping Session 1 of User core... Oct 27 15:46:26.976251 sshd[944]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:26.976497 sshd[1329]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:26.976320 systemd[1]: Stopping Session 30 of User core... Oct 27 15:46:26.977155 sudo[1332]: pam_unix(sudo:session): session closed for user root Oct 27 15:46:26.978185 systemd[1]: Removed slice Slice /system/addon-config. Oct 27 15:46:26.979465 systemd[1]: Removed slice Slice /system/addon-run. Oct 27 15:46:26.980757 systemd[1]: Removed slice Slice /system/modprobe. Oct 27 15:46:26.982015 systemd[1]: Removed slice Slice /system/system-cloudinit. Oct 27 15:46:26.983186 systemd[1]: Stopped target Multi-User System. Oct 27 15:46:26.984040 systemd[1]: Stopped target Login Prompts. Oct 27 15:46:26.984987 systemd[1]: Stopped target Containers. Oct 27 15:46:26.985826 systemd[1]: Stopped target Host and Network Name Lookups. Oct 27 15:46:26.986865 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 27 15:46:26.987879 systemd[1]: Stopped target Timer Units. Oct 27 15:46:26.988835 systemd[1]: logrotate.timer: Deactivated successfully. Oct 27 15:46:26.988878 systemd[1]: Stopped Daily Log Rotation. Oct 27 15:46:26.989717 systemd[1]: mdadm.timer: Deactivated successfully. Oct 27 15:46:26.989756 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Oct 27 15:46:26.991074 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 27 15:46:26.991115 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Oct 27 15:46:26.992396 systemd[1]: Stopped target System Time Set. Oct 27 15:46:26.993246 systemd[1]: Stopped target Load user-provided cloud configs. Oct 27 15:46:26.994263 systemd[1]: Stopped target Load system-provided cloud configs. Oct 27 15:46:26.995289 systemd[1]: systemd-coredump.socket: Deactivated successfully. Oct 27 15:46:26.995388 systemd[1]: Closed Process Core Dump Socket. Oct 27 15:46:26.996955 systemd[1]: Unmounting Boot partition... Oct 27 15:46:26.998059 systemd[1]: Stopping Getty on tty1... Oct 27 15:46:26.999041 systemd[1]: Stopping Serial Getty on ttyAMA0... Oct 27 15:46:27.001316 systemd[1]: Stopping OpenSSH per-connection server daemon... Oct 27 15:46:27.002280 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:46672)... Oct 27 15:46:27.003245 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Oct 27 15:46:27.003398 systemd[1]: Stopped Commit a transient machine-id on disk. Oct 27 15:46:27.004447 systemd[1]: Stopped target First Boot Complete. Oct 27 15:46:27.006327 systemd[1]: Stopping Load/Save Random Seed... Oct 27 15:46:27.007316 systemd[1]: Stopping Update Engine... Oct 27 15:46:27.010583 systemd[1]: sshd@0-10.0.0.8:22-10.0.0.1:60106.service: Deactivated successfully. Oct 27 15:46:27.010793 systemd[1]: Stopped OpenSSH per-connection server daemon. Oct 27 15:46:27.012443 systemd[1]: getty@tty1.service: Deactivated successfully. Oct 27 15:46:27.012630 systemd[1]: Stopped Getty on tty1. Oct 27 15:46:27.013789 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Oct 27 15:46:27.013974 systemd[1]: Stopped Serial Getty on ttyAMA0. Oct 27 15:46:27.015291 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Oct 27 15:46:27.015359 systemd[1]: update-engine.service: Failed with result 'exit-code'. Oct 27 15:46:27.015604 systemd[1]: Stopped Update Engine. Oct 27 15:46:27.016478 systemd[1]: update-engine.service: Consumed 2.337s CPU time. Oct 27 15:46:27.016700 systemd[1]: sshd@29-10.0.0.8:22-10.0.0.1:46672.service: Deactivated successfully. Oct 27 15:46:27.016874 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:46672). Oct 27 15:46:27.031748 systemd[1]: boot.mount: Deactivated successfully. Oct 27 15:46:27.032000 systemd[1]: Unmounted Boot partition. Oct 27 15:46:27.033264 systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 27 15:46:27.033477 systemd[1]: Stopped Load/Save Random Seed. Oct 27 15:46:27.034560 systemd[1]: session-1.scope: Deactivated successfully. Oct 27 15:46:27.034799 systemd[1]: Stopped Session 1 of User core. Oct 27 15:46:27.035840 systemd[1]: session-30.scope: Deactivated successfully. Oct 27 15:46:27.036067 systemd[1]: Stopped Session 30 of User core. Oct 27 15:46:27.038278 systemd-logind[916]: Session 1 logged out. Waiting for processes to exit. Oct 27 15:46:27.038589 systemd[1]: Removed slice Slice /system/getty. Oct 27 15:46:27.039769 systemd[1]: Removed slice Slice /system/serial-getty. Oct 27 15:46:27.041226 systemd[1]: Removed slice Slice /system/sshd. Oct 27 15:46:27.042091 systemd[1]: sshd-keygen.service: Deactivated successfully. Oct 27 15:46:27.042220 systemd[1]: Stopped Generate sshd host keys. Oct 27 15:46:27.043119 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Oct 27 15:46:27.043268 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Oct 27 15:46:27.044648 systemd[1]: Stopping User Login Management... Oct 27 15:46:27.045632 systemd[1]: Stopping User Manager for UID 500... Oct 27 15:46:27.046967 systemd[953]: Stopped target Main User Target. Oct 27 15:46:27.046988 systemd[953]: Stopped target Basic System. Oct 27 15:46:27.046998 systemd[953]: Stopped target Paths. Oct 27 15:46:27.047005 systemd[953]: Stopped target Sockets. Oct 27 15:46:27.047022 systemd[953]: Reached target Shutdown. Oct 27 15:46:27.047029 systemd[953]: Stopped target Timers. Oct 27 15:46:27.047160 systemd[953]: Finished Exit the Session. Oct 27 15:46:27.047186 systemd[953]: Reached target Exit the Session. Oct 27 15:46:27.048870 systemd-logind[916]: Session 30 logged out. Waiting for processes to exit. Oct 27 15:46:27.050425 systemd[1]: user@500.service: Deactivated successfully. Oct 27 15:46:27.050597 systemd[1]: Stopped User Manager for UID 500. Oct 27 15:46:27.052473 systemd[1]: Stopping User Runtime Directory /run/user/500... Oct 27 15:46:27.057644 systemd[1]: run-user-500.mount: Deactivated successfully. Oct 27 15:46:27.057705 systemd[1]: Unmounted /run/user/500. Oct 27 15:46:27.058928 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Oct 27 15:46:27.059167 systemd[1]: Stopped User Runtime Directory /run/user/500. Oct 27 15:46:27.060816 systemd[1]: Removed slice User Slice of UID 500. Oct 27 15:46:27.062727 systemd[1]: Stopping D-Bus System Message Bus... Oct 27 15:46:27.064434 systemd[1]: Stopping Permit User Sessions... Oct 27 15:46:27.065670 systemd[1]: systemd-logind.service: Deactivated successfully. Oct 27 15:46:27.065931 systemd[1]: Stopped User Login Management. Oct 27 15:46:27.067512 systemd[1]: dbus.service: Deactivated successfully. Oct 27 15:46:27.067750 systemd[1]: Stopped D-Bus System Message Bus. Oct 27 15:46:27.068932 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Oct 27 15:46:27.069202 systemd[1]: Stopped Permit User Sessions. Oct 27 15:46:27.070112 systemd[1]: Stopped target Basic System. Oct 27 15:46:27.071005 systemd[1]: Stopped target Network. Oct 27 15:46:27.071815 systemd[1]: Stopped target Path Units. Oct 27 15:46:27.072606 systemd[1]: motdgen.path: Deactivated successfully. Oct 27 15:46:27.074286 systemd[1]: Stopped Watch for update engine configuration changes. Oct 27 15:46:27.075327 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Oct 27 15:46:27.077000 audit: BPF prog-id=65 op=UNLOAD Oct 27 15:46:27.079192 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 27 15:46:27.080162 kernel: audit: type=1334 audit(1761579987.077:370): prog-id=65 op=UNLOAD Oct 27 15:46:27.080248 systemd[1]: Stopped target Remote File Systems. Oct 27 15:46:27.081042 systemd[1]: Stopped target Slice Units. Oct 27 15:46:27.082172 systemd[1]: Removed slice User and Session Slice. Oct 27 15:46:27.082997 systemd[1]: Stopped target Socket Units. Oct 27 15:46:27.083913 systemd[1]: dbus.socket: Deactivated successfully. Oct 27 15:46:27.083977 systemd[1]: Closed D-Bus System Message Bus Socket. Oct 27 15:46:27.085099 systemd[1]: docker.socket: Deactivated successfully. Oct 27 15:46:27.085364 systemd[1]: Closed Docker Socket for the API. Oct 27 15:46:27.086230 systemd[1]: Stopped target System Initialization. Oct 27 15:46:27.087074 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Oct 27 15:46:27.087227 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Oct 27 15:46:27.088342 systemd[1]: Stopped target Local Verity Protected Volumes. Oct 27 15:46:27.090258 systemd[1]: Stopping Load Security Auditing Rules... Oct 27 15:46:27.091271 systemd[1]: Stopping Network Name Resolution... Oct 27 15:46:27.092126 systemd[1]: Stopping Network Time Synchronization... Oct 27 15:46:27.093158 systemd[1]: systemd-update-done.service: Deactivated successfully. Oct 27 15:46:27.093231 systemd[1]: Stopped Update is Completed. Oct 27 15:46:27.094102 auditctl[1349]: No rules Oct 27 15:46:27.094117 systemd[1]: ldconfig.service: Deactivated successfully. Oct 27 15:46:27.094207 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Oct 27 15:46:27.095165 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Oct 27 15:46:27.095223 systemd[1]: Stopped Rebuild Hardware Database. Oct 27 15:46:27.096025 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Oct 27 15:46:27.096082 systemd[1]: Stopped Rebuild Journal Catalog. Oct 27 15:46:27.097711 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Oct 27 15:46:27.100251 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 27 15:46:27.100498 systemd[1]: Stopped Network Name Resolution. Oct 27 15:46:27.101549 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Oct 27 15:46:27.101777 systemd[1]: Stopped Network Time Synchronization. Oct 27 15:46:27.103008 systemd[1]: audit-rules.service: Deactivated successfully. Oct 27 15:46:27.103217 systemd[1]: Stopped Load Security Auditing Rules. Oct 27 15:46:27.105463 systemd[1]: Stopping Network Configuration... Oct 27 15:46:27.106416 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Oct 27 15:46:27.106630 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Oct 27 15:46:27.108297 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 27 15:46:27.108387 systemd[1]: Stopped Create Volatile Files and Directories. Oct 27 15:46:27.109463 systemd[1]: Stopped target Local File Systems. Oct 27 15:46:27.110170 systemd-networkd[839]: eth0: DHCPv6 lease lost Oct 27 15:46:27.117000 audit: BPF prog-id=61 op=UNLOAD Oct 27 15:46:27.117000 audit: BPF prog-id=60 op=UNLOAD Oct 27 15:46:27.120046 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Oct 27 15:46:27.120166 kernel: audit: type=1334 audit(1761579987.117:371): prog-id=61 op=UNLOAD Oct 27 15:46:27.120201 kernel: audit: type=1334 audit(1761579987.117:372): prog-id=60 op=UNLOAD Oct 27 15:46:27.121688 systemd[1]: Unmounting External Media Directory... Oct 27 15:46:27.123579 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Oct 27 15:46:27.125309 systemd[1]: Unmounting /run/torcx/unpack... Oct 27 15:46:27.126518 systemd[1]: Unmounting Temporary Directory /tmp... Oct 27 15:46:27.127816 systemd[1]: Unmounting /usr/share/oem... Oct 27 15:46:27.129359 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Oct 27 15:46:27.131872 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 27 15:46:27.132185 systemd[1]: Stopped Network Configuration. Oct 27 15:46:27.133095 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 27 15:46:27.133349 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Oct 27 15:46:27.134594 systemd[1]: media.mount: Deactivated successfully. Oct 27 15:46:27.134850 systemd[1]: Unmounted External Media Directory. Oct 27 15:46:27.135829 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Oct 27 15:46:27.136044 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Oct 27 15:46:27.145333 systemd[1]: tmp.mount: Deactivated successfully. Oct 27 15:46:27.145585 systemd[1]: Unmounted Temporary Directory /tmp. Oct 27 15:46:27.146582 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Oct 27 15:46:27.146815 systemd[1]: Unmounted /run/torcx/unpack. Oct 27 15:46:27.147829 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 27 15:46:27.148068 systemd[1]: Unmounted /usr/share/oem. Oct 27 15:46:27.149074 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 27 15:46:27.149338 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Oct 27 15:46:27.149000 audit: BPF prog-id=56 op=UNLOAD Oct 27 15:46:27.150724 systemd[1]: Stopped target Swaps. Oct 27 15:46:27.152173 kernel: audit: type=1334 audit(1761579987.149:373): prog-id=56 op=UNLOAD Oct 27 15:46:27.152335 systemd[1]: Reached target Unmount All Filesystems. Oct 27 15:46:27.153347 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 27 15:46:27.153474 systemd[1]: Closed Network Service Netlink Socket. Oct 27 15:46:27.154450 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Oct 27 15:46:27.154577 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Oct 27 15:46:27.155897 systemd[1]: Removed slice Slice /system/systemd-fsck. Oct 27 15:46:27.157112 systemd[1]: Stopped target Preparation for Local File Systems. Oct 27 15:46:27.158249 systemd[1]: lvm2-activation.service: Deactivated successfully. Oct 27 15:46:27.158392 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 27 15:46:27.159379 systemd[1]: Stopped target Local Encrypted Volumes. Oct 27 15:46:27.160493 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 27 15:46:27.160603 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 27 15:46:27.161753 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Oct 27 15:46:27.165202 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Oct 27 15:46:27.166314 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Oct 27 15:46:27.166387 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 27 15:46:27.167308 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 27 15:46:27.167369 systemd[1]: Stopped Apply Kernel Variables. Oct 27 15:46:27.168152 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 27 15:46:27.168213 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 27 15:46:27.169165 systemd[1]: systemd-sysusers.service: Deactivated successfully. Oct 27 15:46:27.169234 systemd[1]: Stopped Create System Users. Oct 27 15:46:27.170015 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Oct 27 15:46:27.170073 systemd[1]: Stopped Remount Root and Kernel File Systems. Oct 27 15:46:27.171080 systemd[1]: Reached target System Shutdown. Oct 27 15:46:27.171918 systemd[1]: Reached target Late Shutdown Services. Oct 27 15:46:27.172877 systemd[1]: systemd-reboot.service: Deactivated successfully. Oct 27 15:46:27.172918 systemd[1]: Finished System Reboot. Oct 27 15:46:27.173658 systemd[1]: Reached target System Reboot. Oct 27 15:46:27.174428 systemd[1]: Shutting down. Oct 27 15:46:27.175000 audit: BPF prog-id=67 op=UNLOAD Oct 27 15:46:27.175000 audit: BPF prog-id=66 op=UNLOAD Oct 27 15:46:27.175000 audit: BPF prog-id=59 op=UNLOAD Oct 27 15:46:27.175000 audit: BPF prog-id=58 op=UNLOAD Oct 27 15:46:27.175000 audit: BPF prog-id=57 op=UNLOAD Oct 27 15:46:27.178161 kernel: audit: type=1334 audit(1761579987.175:374): prog-id=67 op=UNLOAD Oct 27 15:46:27.177000 audit: BPF prog-id=64 op=UNLOAD Oct 27 15:46:27.177000 audit: BPF prog-id=63 op=UNLOAD Oct 27 15:46:27.177000 audit: BPF prog-id=62 op=UNLOAD Oct 27 15:46:27.214164 systemd-shutdown[1]: Syncing filesystems and block devices. Oct 27 15:46:27.222176 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Oct 27 15:46:27.222263 systemd-journald[804]: Journal stopped -- Reboot -- Oct 27 15:46:34.676697 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 27 15:46:34.676714 kernel: Linux version 5.10.107-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP PREEMPT Tue Mar 22 19:39:09 -00 2022 Oct 27 15:46:34.676722 kernel: efi: EFI v2.70 by EDK II Oct 27 15:46:34.676728 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 27 15:46:34.676732 kernel: efi: seeding entropy pool Oct 27 15:46:34.676738 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 27 15:46:34.676744 kernel: ACPI: Early table checksum verification disabled Oct 27 15:46:34.676749 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 27 15:46:34.676754 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 27 15:46:34.676759 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:34.676766 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:34.676771 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:34.676776 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:34.676781 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:34.676788 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:34.676793 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:34.676800 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:34.676805 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 15:46:34.676811 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 27 15:46:34.676816 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 27 15:46:34.676822 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 15:46:34.676827 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 27 15:46:34.676832 kernel: Zone ranges: Oct 27 15:46:34.676841 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 15:46:34.676847 kernel: DMA32 empty Oct 27 15:46:34.676852 kernel: Normal empty Oct 27 15:46:34.676857 kernel: Movable zone start for each node Oct 27 15:46:34.676863 kernel: Early memory node ranges Oct 27 15:46:34.676869 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 27 15:46:34.676874 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 27 15:46:34.676880 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 27 15:46:34.676885 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 27 15:46:34.676891 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 27 15:46:34.676900 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 27 15:46:34.676906 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 27 15:46:34.676913 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 27 15:46:34.676921 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 27 15:46:34.676927 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 27 15:46:34.676934 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 27 15:46:34.676940 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 15:46:34.676953 kernel: On node 0 totalpages: 643072 Oct 27 15:46:34.676959 kernel: DMA zone: 10048 pages used for memmap Oct 27 15:46:34.676966 kernel: DMA zone: 0 pages reserved Oct 27 15:46:34.676971 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 27 15:46:34.676977 kernel: psci: probing for conduit method from ACPI. Oct 27 15:46:34.676983 kernel: psci: PSCIv1.1 detected in firmware. Oct 27 15:46:34.676996 kernel: psci: Using standard PSCI v0.2 function IDs Oct 27 15:46:34.677003 kernel: psci: Trusted OS migration not required Oct 27 15:46:34.677010 kernel: psci: SMC Calling Convention v1.1 Oct 27 15:46:34.677015 kernel: ACPI: SRAT not present Oct 27 15:46:34.677496 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 27 15:46:34.677503 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 27 15:46:34.677509 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 27 15:46:34.677515 kernel: Detected PIPT I-cache on CPU0 Oct 27 15:46:34.677521 kernel: CPU features: detected: GIC system register CPU interface Oct 27 15:46:34.677526 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 27 15:46:34.677532 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 27 15:46:34.677538 kernel: CPU features: detected: Hardware dirty bit management Oct 27 15:46:34.677544 kernel: CPU features: detected: Spectre-v4 Oct 27 15:46:34.677553 kernel: CPU features: detected: ARM erratum 1418040 Oct 27 15:46:34.677559 kernel: CPU features: detected: Spectre-BHB Oct 27 15:46:34.677564 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 27 15:46:34.677570 kernel: Policy zone: DMA Oct 27 15:46:34.677577 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 27 15:46:34.677583 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 27 15:46:34.677589 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 27 15:46:34.677595 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 27 15:46:34.677601 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 27 15:46:34.677607 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 27 15:46:34.677613 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 27 15:46:34.677620 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 27 15:46:34.677626 kernel: rcu: RCU event tracing is enabled. Oct 27 15:46:34.677632 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 27 15:46:34.677638 kernel: Trampoline variant of Tasks RCU enabled. Oct 27 15:46:34.677644 kernel: Tracing variant of Tasks RCU enabled. Oct 27 15:46:34.677649 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 27 15:46:34.677655 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 27 15:46:34.677661 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 27 15:46:34.677667 kernel: GICv3: 256 SPIs implemented Oct 27 15:46:34.677672 kernel: GICv3: 0 Extended SPIs implemented Oct 27 15:46:34.677678 kernel: GICv3: Distributor has no Range Selector support Oct 27 15:46:34.677683 kernel: GICv3: 16 PPIs implemented Oct 27 15:46:34.677691 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 27 15:46:34.677696 kernel: ACPI: SRAT not present Oct 27 15:46:34.677702 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 27 15:46:34.677707 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 27 15:46:34.677713 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 27 15:46:34.677719 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 27 15:46:34.677725 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 27 15:46:34.677731 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:46:34.677736 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 27 15:46:34.677742 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 27 15:46:34.677748 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 27 15:46:34.677754 kernel: arm-pv: using stolen time PV Oct 27 15:46:34.677761 kernel: Console: colour dummy device 80x25 Oct 27 15:46:34.677767 kernel: ACPI: Core revision 20200925 Oct 27 15:46:34.677773 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 27 15:46:34.677779 kernel: pid_max: default: 32768 minimum: 301 Oct 27 15:46:34.677785 kernel: LSM: Security Framework initializing Oct 27 15:46:34.677791 kernel: SELinux: Initializing. Oct 27 15:46:34.677797 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 15:46:34.677803 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 15:46:34.677809 kernel: rcu: Hierarchical SRCU implementation. Oct 27 15:46:34.677816 kernel: Platform MSI: ITS@0x8080000 domain created Oct 27 15:46:34.677822 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 27 15:46:34.677828 kernel: Remapping and enabling EFI services. Oct 27 15:46:34.677834 kernel: smp: Bringing up secondary CPUs ... Oct 27 15:46:34.677840 kernel: Detected PIPT I-cache on CPU1 Oct 27 15:46:34.677846 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 27 15:46:34.677852 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 27 15:46:34.677862 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:46:34.677868 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 27 15:46:34.677875 kernel: Detected PIPT I-cache on CPU2 Oct 27 15:46:34.677882 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 27 15:46:34.677889 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 27 15:46:34.677895 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:46:34.677901 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 27 15:46:34.677907 kernel: Detected PIPT I-cache on CPU3 Oct 27 15:46:34.677916 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 27 15:46:34.677923 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 27 15:46:34.677930 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 15:46:34.677936 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 27 15:46:34.677955 kernel: smp: Brought up 1 node, 4 CPUs Oct 27 15:46:34.677961 kernel: SMP: Total of 4 processors activated. Oct 27 15:46:34.677967 kernel: CPU features: detected: Privileged Access Never Oct 27 15:46:34.677974 kernel: CPU features: detected: LSE atomic instructions Oct 27 15:46:34.677980 kernel: CPU features: detected: User Access Override Oct 27 15:46:34.677987 kernel: CPU features: detected: 32-bit EL0 Support Oct 27 15:46:34.678015 kernel: CPU features: detected: Common not Private translations Oct 27 15:46:34.678022 kernel: CPU features: detected: RAS Extension Support Oct 27 15:46:34.678030 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 27 15:46:34.678037 kernel: CPU features: detected: CRC32 instructions Oct 27 15:46:34.678043 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 27 15:46:34.678049 kernel: CPU: All CPU(s) started at EL1 Oct 27 15:46:34.678055 kernel: alternatives: patching kernel code Oct 27 15:46:34.678063 kernel: devtmpfs: initialized Oct 27 15:46:34.678069 kernel: KASLR enabled Oct 27 15:46:34.678075 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 27 15:46:34.678081 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 27 15:46:34.678088 kernel: pinctrl core: initialized pinctrl subsystem Oct 27 15:46:34.678094 kernel: SMBIOS 3.0.0 present. Oct 27 15:46:34.678100 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 27 15:46:34.678106 kernel: NET: Registered protocol family 16 Oct 27 15:46:34.678113 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 27 15:46:34.678119 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 27 15:46:34.678127 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 27 15:46:34.678133 kernel: audit: initializing netlink subsys (disabled) Oct 27 15:46:34.678140 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Oct 27 15:46:34.678146 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 27 15:46:34.678152 kernel: cpuidle: using governor menu Oct 27 15:46:34.678158 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 27 15:46:34.678165 kernel: ASID allocator initialised with 32768 entries Oct 27 15:46:34.678171 kernel: ACPI: bus type PCI registered Oct 27 15:46:34.678177 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 27 15:46:34.678184 kernel: Serial: AMBA PL011 UART driver Oct 27 15:46:34.678190 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 27 15:46:34.678197 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 27 15:46:34.678203 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 27 15:46:34.678209 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 27 15:46:34.678216 kernel: cryptd: max_cpu_qlen set to 1000 Oct 27 15:46:34.678222 kernel: ACPI: Added _OSI(Module Device) Oct 27 15:46:34.678228 kernel: ACPI: Added _OSI(Processor Device) Oct 27 15:46:34.678234 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 27 15:46:34.678242 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 27 15:46:34.678248 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 27 15:46:34.678254 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 27 15:46:34.678261 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 27 15:46:34.678267 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 27 15:46:34.678273 kernel: ACPI: Interpreter enabled Oct 27 15:46:34.678280 kernel: ACPI: Using GIC for interrupt routing Oct 27 15:46:34.678286 kernel: ACPI: MCFG table detected, 1 entries Oct 27 15:46:34.678292 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 27 15:46:34.678298 kernel: printk: console [ttyAMA0] enabled Oct 27 15:46:34.678306 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 27 15:46:34.678437 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 27 15:46:34.678497 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 27 15:46:34.678552 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 27 15:46:34.678605 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 27 15:46:34.678659 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 27 15:46:34.678668 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 27 15:46:34.678677 kernel: PCI host bridge to bus 0000:00 Oct 27 15:46:34.678736 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 27 15:46:34.678788 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 27 15:46:34.678837 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 27 15:46:34.678885 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 27 15:46:34.678952 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 27 15:46:34.679044 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 27 15:46:34.679106 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 27 15:46:34.679162 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 27 15:46:34.679219 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 27 15:46:34.679274 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 27 15:46:34.679330 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 27 15:46:34.679392 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 27 15:46:34.679449 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 27 15:46:34.679502 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 27 15:46:34.679552 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 27 15:46:34.679560 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 27 15:46:34.679566 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 27 15:46:34.679573 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 27 15:46:34.679579 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 27 15:46:34.679585 kernel: vgaarb: loaded Oct 27 15:46:34.679591 kernel: Registered efivars operations Oct 27 15:46:34.679597 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 27 15:46:34.679607 kernel: VFS: Disk quotas dquot_6.6.0 Oct 27 15:46:34.679613 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 27 15:46:34.679620 kernel: pnp: PnP ACPI init Oct 27 15:46:34.679685 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 27 15:46:34.679736 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 27 15:46:34.679744 kernel: pnp: PnP ACPI: found 1 devices Oct 27 15:46:34.679750 kernel: NET: Registered protocol family 2 Oct 27 15:46:34.679757 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 27 15:46:34.679765 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 27 15:46:34.679772 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 27 15:46:34.679778 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 27 15:46:34.679785 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 27 15:46:34.679791 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 15:46:34.679797 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 15:46:34.679804 kernel: NET: Registered protocol family 1 Oct 27 15:46:34.679810 kernel: PCI: CLS 0 bytes, default 64 Oct 27 15:46:34.679816 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 27 15:46:34.679824 kernel: kvm [1]: HYP mode not available Oct 27 15:46:34.679830 kernel: Initialise system trusted keyrings Oct 27 15:46:34.679836 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 27 15:46:34.679843 kernel: Key type asymmetric registered Oct 27 15:46:34.679849 kernel: Asymmetric key parser 'x509' registered Oct 27 15:46:34.679855 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 27 15:46:34.679861 kernel: io scheduler mq-deadline registered Oct 27 15:46:34.679867 kernel: io scheduler kyber registered Oct 27 15:46:34.679874 kernel: io scheduler bfq registered Oct 27 15:46:34.679881 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 27 15:46:34.679887 kernel: ACPI: Power Button [PWRB] Oct 27 15:46:34.679894 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 27 15:46:34.679950 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 27 15:46:34.679959 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 27 15:46:34.679965 kernel: msm_serial: driver initialized Oct 27 15:46:34.679971 kernel: random: fast init done Oct 27 15:46:34.679978 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 27 15:46:34.679984 kernel: random: crng init done Oct 27 15:46:34.680000 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 27 15:46:34.680009 kernel: thunder_xcv, ver 1.0 Oct 27 15:46:34.680015 kernel: thunder_bgx, ver 1.0 Oct 27 15:46:34.680021 kernel: nicpf, ver 1.0 Oct 27 15:46:34.680027 kernel: nicvf, ver 1.0 Oct 27 15:46:34.680092 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 27 15:46:34.680146 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-27T15:46:34 UTC (1761579994) Oct 27 15:46:34.680154 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 27 15:46:34.680161 kernel: NET: Registered protocol family 10 Oct 27 15:46:34.680169 kernel: Segment Routing with IPv6 Oct 27 15:46:34.680175 kernel: NET: Registered protocol family 17 Oct 27 15:46:34.680181 kernel: Key type dns_resolver registered Oct 27 15:46:34.680187 kernel: registered taskstats version 1 Oct 27 15:46:34.680194 kernel: Loading compiled-in X.509 certificates Oct 27 15:46:34.680200 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 27 15:46:34.680206 kernel: Key type ._fscrypt registered Oct 27 15:46:34.680213 kernel: Key type .fscrypt registered Oct 27 15:46:34.680219 kernel: Key type fscrypt-provisioning registered Oct 27 15:46:34.680226 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 27 15:46:34.680232 kernel: ima: Allocated hash algorithm: sha1 Oct 27 15:46:34.680239 kernel: ima: No architecture policies found Oct 27 15:46:34.680245 kernel: Freeing unused kernel memory: 34240K Oct 27 15:46:34.680251 kernel: Run /init as init process Oct 27 15:46:34.680257 kernel: with arguments: Oct 27 15:46:34.680263 kernel: /init Oct 27 15:46:34.680270 kernel: with environment: Oct 27 15:46:34.680275 kernel: HOME=/ Oct 27 15:46:34.680281 kernel: TERM=linux Oct 27 15:46:34.680288 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 27 15:46:34.680296 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 27 15:46:34.680305 systemd[1]: Detected virtualization kvm. Oct 27 15:46:34.680312 systemd[1]: Detected architecture arm64. Oct 27 15:46:34.680319 systemd[1]: Running in initial RAM disk. Oct 27 15:46:34.680325 systemd[1]: No hostname configured, using default hostname. Oct 27 15:46:34.680331 systemd[1]: Hostname set to . Oct 27 15:46:34.680339 systemd[1]: Initializing machine ID from VM UUID. Oct 27 15:46:34.680346 systemd[1]: Queued start job for default target Initrd Default Target. Oct 27 15:46:34.680353 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 27 15:46:34.680359 systemd[1]: Reached target Local Encrypted Volumes. Oct 27 15:46:34.680366 systemd[1]: Reached target Path Units. Oct 27 15:46:34.680372 systemd[1]: Reached target Slice Units. Oct 27 15:46:34.680379 systemd[1]: Reached target Swaps. Oct 27 15:46:34.680385 systemd[1]: Reached target Timer Units. Oct 27 15:46:34.680400 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 27 15:46:34.680407 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 27 15:46:34.680414 systemd[1]: Listening on Journal Audit Socket. Oct 27 15:46:34.680420 systemd[1]: Listening on Journal Socket (/dev/log). Oct 27 15:46:34.680427 systemd[1]: Listening on Journal Socket. Oct 27 15:46:34.680433 systemd[1]: Listening on udev Control Socket. Oct 27 15:46:34.680440 systemd[1]: Listening on udev Kernel Socket. Oct 27 15:46:34.680447 systemd[1]: Reached target Socket Units. Oct 27 15:46:34.680453 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 27 15:46:34.680461 systemd[1]: Starting Create List of Static Device Nodes... Oct 27 15:46:34.680468 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 27 15:46:34.680475 systemd[1]: Starting Journal Service... Oct 27 15:46:34.680482 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 27 15:46:34.680488 systemd[1]: Starting Apply Kernel Variables... Oct 27 15:46:34.680495 systemd[1]: Starting Setup Virtual Console... Oct 27 15:46:34.680502 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 27 15:46:34.680508 kernel: SCSI subsystem initialized Oct 27 15:46:34.680514 kernel: Loading iSCSI transport class v2.0-870. Oct 27 15:46:34.680520 systemd[1]: Finished Create List of Static Device Nodes. Oct 27 15:46:34.680528 systemd[1]: Finished Apply Kernel Variables. Oct 27 15:46:34.680537 systemd-journald[269]: Journal started Oct 27 15:46:34.680574 systemd-journald[269]: Runtime Journal (/run/log/journal/c7124dca645d438da9b16aa25dd9a4aa) is 6.0M, max 48.5M, 42.4M free. Oct 27 15:46:34.683022 systemd[1]: Started Journal Service. Oct 27 15:46:34.683353 systemd[1]: Finished Setup Virtual Console. Oct 27 15:46:34.684780 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 27 15:46:34.686467 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 27 15:46:34.689887 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 27 15:46:34.699241 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 27 15:46:34.701138 systemd[1]: Starting dracut cmdline hook... Oct 27 15:46:34.710171 dracut-cmdline[289]: dracut-dracut-053 Oct 27 15:46:34.712541 dracut-cmdline[289]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 27 15:46:34.770046 kernel: iscsi: registered transport (tcp) Oct 27 15:46:34.782029 kernel: iscsi: registered transport (qla4xxx) Oct 27 15:46:34.782045 kernel: QLogic iSCSI HBA Driver Oct 27 15:46:34.791622 systemd[1]: Finished dracut cmdline hook. Oct 27 15:46:34.793268 systemd[1]: Starting dracut pre-udev hook... Oct 27 15:46:34.794570 systemd[1]: Starting Open-iSCSI... Oct 27 15:46:34.797035 iscsid[374]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 27 15:46:34.797035 iscsid[374]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 27 15:46:34.797035 iscsid[374]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 27 15:46:34.797035 iscsid[374]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 27 15:46:34.807154 iscsid[374]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 27 15:46:34.807154 iscsid[374]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 27 15:46:34.810780 kernel: device-mapper: uevent: version 1.0.3 Oct 27 15:46:34.810801 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 27 15:46:34.800630 systemd[1]: Started Open-iSCSI. Oct 27 15:46:34.844023 kernel: raid6: neonx8 gen() 13784 MB/s Oct 27 15:46:34.860023 kernel: raid6: neonx8 xor() 10752 MB/s Oct 27 15:46:34.877024 kernel: raid6: neonx4 gen() 13589 MB/s Oct 27 15:46:34.894019 kernel: raid6: neonx4 xor() 11563 MB/s Oct 27 15:46:34.911015 kernel: raid6: neonx2 gen() 12916 MB/s Oct 27 15:46:34.928016 kernel: raid6: neonx2 xor() 9893 MB/s Oct 27 15:46:34.945017 kernel: raid6: neonx1 gen() 10995 MB/s Oct 27 15:46:34.962019 kernel: raid6: neonx1 xor() 8844 MB/s Oct 27 15:46:34.979018 kernel: raid6: int64x8 gen() 6827 MB/s Oct 27 15:46:34.996015 kernel: raid6: int64x8 xor() 3793 MB/s Oct 27 15:46:35.013007 kernel: raid6: int64x4 gen() 6909 MB/s Oct 27 15:46:35.030017 kernel: raid6: int64x4 xor() 3835 MB/s Oct 27 15:46:35.047015 kernel: raid6: int64x2 gen() 6178 MB/s Oct 27 15:46:35.064016 kernel: raid6: int64x2 xor() 3315 MB/s Oct 27 15:46:35.081018 kernel: raid6: int64x1 gen() 5014 MB/s Oct 27 15:46:35.098169 kernel: raid6: int64x1 xor() 2593 MB/s Oct 27 15:46:35.098188 kernel: raid6: using algorithm neonx8 gen() 13784 MB/s Oct 27 15:46:35.098197 kernel: raid6: .... xor() 10752 MB/s, rmw enabled Oct 27 15:46:35.099322 kernel: raid6: using neon recovery algorithm Oct 27 15:46:35.109021 kernel: xor: measuring software checksum speed Oct 27 15:46:35.110018 kernel: 8regs : 17159 MB/sec Oct 27 15:46:35.111017 kernel: 32regs : 20770 MB/sec Oct 27 15:46:35.112295 kernel: arm64_neon : 29132 MB/sec Oct 27 15:46:35.112312 kernel: xor: using function: arm64_neon (29132 MB/sec) Oct 27 15:46:35.157026 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 27 15:46:35.167419 systemd[1]: Finished dracut pre-udev hook. Oct 27 15:46:35.168000 audit: BPF prog-id=6 op=LOAD Oct 27 15:46:35.168000 audit: BPF prog-id=7 op=LOAD Oct 27 15:46:35.170550 kernel: audit: type=1334 audit(1761579995.168:2): prog-id=6 op=LOAD Oct 27 15:46:35.170571 kernel: audit: type=1334 audit(1761579995.168:3): prog-id=7 op=LOAD Oct 27 15:46:35.170581 kernel: audit: type=1334 audit(1761579995.169:4): prog-id=8 op=LOAD Oct 27 15:46:35.169000 audit: BPF prog-id=8 op=LOAD Oct 27 15:46:35.170937 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 27 15:46:35.182577 systemd-udevd[407]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 27 15:46:35.184539 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 27 15:46:35.187326 systemd[1]: Starting dracut pre-trigger hook... Oct 27 15:46:35.198795 dracut-pre-trigger[417]: rd.md=0: removing MD RAID activation Oct 27 15:46:35.222811 systemd[1]: Finished dracut pre-trigger hook. Oct 27 15:46:35.224451 systemd[1]: Starting Coldplug All udev Devices... Oct 27 15:46:35.234907 systemd-udevd[407]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 27 15:46:35.265146 systemd[1]: Finished Coldplug All udev Devices. Oct 27 15:46:35.266846 systemd[1]: Starting dracut initqueue hook... Oct 27 15:46:35.290435 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 27 15:46:35.290565 kernel: vda: detected capacity change from 0 to 4756340736 Oct 27 15:46:35.302952 systemd-udevd[456]: Using default interface naming scheme 'v249'. Oct 27 15:46:35.306013 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 15:46:35.320035 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (459) Oct 27 15:46:35.323435 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 27 15:46:35.327371 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 27 15:46:35.328526 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 27 15:46:35.333838 systemd[1]: Reached target Initrd Root Device. Oct 27 15:46:35.335865 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 27 15:46:35.337073 systemd[1]: Finished dracut initqueue hook. Oct 27 15:46:35.338770 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 27 15:46:35.338874 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 27 15:46:35.340356 systemd[1]: Reached target Preparation for Local File Systems. Oct 27 15:46:35.341962 systemd[1]: Reached target Preparation for Remote File Systems. Oct 27 15:46:35.343851 systemd[1]: Reached target Remote Encrypted Volumes. Oct 27 15:46:35.345577 systemd[1]: Reached target Remote File Systems. Oct 27 15:46:35.348066 systemd[1]: Starting dracut pre-mount hook... Oct 27 15:46:35.349411 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 27 15:46:35.356121 systemd[1]: Finished dracut pre-mount hook. Oct 27 15:46:35.357549 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 27 15:46:35.362036 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 27 15:46:35.366219 systemd-fsck[504]: ROOT: clean, 640/553792 files, 39961/553472 blocks Oct 27 15:46:35.368948 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 27 15:46:35.370907 systemd[1]: Mounting /sysroot... Oct 27 15:46:35.383690 systemd[1]: Found device /dev/mapper/usr. Oct 27 15:46:35.385199 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 27 15:46:35.386241 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 27 15:46:35.394727 systemd-fsck[515]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 27 15:46:35.394727 systemd-fsck[515]: You must have r/w access to the filesystem or be root Oct 27 15:46:35.395646 systemd-fsck[513]: fsck failed with exit status 8. Oct 27 15:46:35.395650 systemd-fsck[513]: Ignoring error. Oct 27 15:46:35.396275 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 27 15:46:35.397815 systemd[1]: Mounting /sysusr/usr... Oct 27 15:46:35.415524 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 27 15:46:35.415572 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 27 15:46:35.416376 systemd[1]: Mounted /sysroot. Oct 27 15:46:35.417140 systemd[1]: Reached target Initrd Root File System. Oct 27 15:46:35.419063 systemd[1]: Starting Reload Configuration from the Real Root... Oct 27 15:46:35.423491 systemd[1]: Reloading. Oct 27 15:46:35.426000 audit: BPF prog-id=3 op=UNLOAD Oct 27 15:46:35.429033 kernel: audit: type=1334 audit(1761579995.426:5): prog-id=3 op=UNLOAD Oct 27 15:46:35.433022 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 27 15:46:35.433000 audit: BPF prog-id=6 op=UNLOAD Oct 27 15:46:35.436148 kernel: audit: type=1334 audit(1761579995.433:6): prog-id=6 op=UNLOAD Oct 27 15:46:35.512000 audit: BPF prog-id=9 op=LOAD Oct 27 15:46:35.513000 audit: BPF prog-id=10 op=LOAD Oct 27 15:46:35.515451 kernel: audit: type=1334 audit(1761579995.512:7): prog-id=9 op=LOAD Oct 27 15:46:35.515475 kernel: audit: type=1334 audit(1761579995.513:8): prog-id=10 op=LOAD Oct 27 15:46:35.515484 kernel: audit: type=1334 audit(1761579995.514:9): prog-id=11 op=LOAD Oct 27 15:46:35.514000 audit: BPF prog-id=11 op=LOAD Oct 27 15:46:35.514000 audit: BPF prog-id=4 op=UNLOAD Oct 27 15:46:35.517094 kernel: audit: type=1334 audit(1761579995.514:10): prog-id=4 op=UNLOAD Oct 27 15:46:35.514000 audit: BPF prog-id=5 op=UNLOAD Oct 27 15:46:35.515000 audit: BPF prog-id=12 op=LOAD Oct 27 15:46:35.516000 audit: BPF prog-id=13 op=LOAD Oct 27 15:46:35.516000 audit: BPF prog-id=14 op=LOAD Oct 27 15:46:35.516000 audit: BPF prog-id=7 op=UNLOAD Oct 27 15:46:35.516000 audit: BPF prog-id=8 op=UNLOAD Oct 27 15:46:35.518227 systemd[1]: Mounted /sysusr/usr. Oct 27 15:46:35.520858 systemd[1]: Reached target Local File Systems. Oct 27 15:46:35.521790 systemd[1]: Reached target System Initialization. Oct 27 15:46:35.523301 systemd[1]: Reached target Basic System. Oct 27 15:46:35.525608 systemd[1]: Mounting /sysroot/usr... Oct 27 15:46:35.528050 systemd[1]: Mounted /sysroot/usr. Oct 27 15:46:35.536833 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 27 15:46:35.536972 systemd[1]: Finished Reload Configuration from the Real Root. Oct 27 15:46:35.539053 systemd[1]: Reached target Initrd File Systems. Oct 27 15:46:35.540734 systemd[1]: Reached target Initrd Default Target. Oct 27 15:46:35.542358 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 27 15:46:35.543077 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 27 15:46:35.553343 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 27 15:46:35.554981 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 27 15:46:35.560957 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 27 15:46:35.561950 systemd[1]: Stopped target Timer Units. Oct 27 15:46:35.563487 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 27 15:46:35.563594 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 27 15:46:35.565065 systemd[1]: Stopped target Initrd Default Target. Oct 27 15:46:35.566767 systemd[1]: Stopped target Basic System. Oct 27 15:46:35.568271 systemd[1]: Stopped target Initrd Root Device. Oct 27 15:46:35.569763 systemd[1]: Stopped target Path Units. Oct 27 15:46:35.571120 systemd[1]: Stopped target Remote File Systems. Oct 27 15:46:35.572705 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 27 15:46:35.574260 systemd[1]: Stopped target Slice Units. Oct 27 15:46:35.575731 systemd[1]: Stopped target Socket Units. Oct 27 15:46:35.577334 systemd[1]: Stopped target System Initialization. Oct 27 15:46:35.578742 systemd[1]: Stopped target Local File Systems. Oct 27 15:46:35.580169 systemd[1]: Stopped target Preparation for Local File Systems. Oct 27 15:46:35.581847 systemd[1]: Stopped target Swaps. Oct 27 15:46:35.583165 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 27 15:46:35.583276 systemd[1]: Stopped dracut pre-mount hook. Oct 27 15:46:35.584945 systemd[1]: Stopped target Local Encrypted Volumes. Oct 27 15:46:35.586348 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 27 15:46:35.590052 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 27 15:46:35.591170 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 27 15:46:35.591272 systemd[1]: Stopped dracut initqueue hook. Oct 27 15:46:35.596470 iscsid[374]: iscsid shutting down. Oct 27 15:46:35.592933 systemd[1]: Stopping Open-iSCSI... Oct 27 15:46:35.595786 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 27 15:46:35.595909 systemd[1]: Stopped Apply Kernel Variables. Oct 27 15:46:35.597270 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 27 15:46:35.597359 systemd[1]: Stopped Coldplug All udev Devices. Oct 27 15:46:35.598652 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 27 15:46:35.598741 systemd[1]: Stopped dracut pre-trigger hook. Oct 27 15:46:35.600019 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 27 15:46:35.604569 systemd[1]: iscsid.service: Deactivated successfully. Oct 27 15:46:35.604683 systemd[1]: Stopped Open-iSCSI. Oct 27 15:46:35.606204 systemd[1]: iscsid.socket: Deactivated successfully. Oct 27 15:46:35.606263 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 27 15:46:35.607713 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 27 15:46:35.610632 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 27 15:46:35.610754 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 27 15:46:35.612279 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 27 15:46:35.612400 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 27 15:46:35.613989 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 27 15:46:35.626000 audit: BPF prog-id=12 op=UNLOAD Oct 27 15:46:35.614131 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 27 15:46:35.616216 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 27 15:46:35.616264 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 27 15:46:35.617687 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 27 15:46:35.617727 systemd[1]: Closed udev Control Socket. Oct 27 15:46:35.619010 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 27 15:46:35.619048 systemd[1]: Closed udev Kernel Socket. Oct 27 15:46:35.620440 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 27 15:46:35.620480 systemd[1]: Stopped dracut pre-udev hook. Oct 27 15:46:35.621933 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 27 15:46:35.621973 systemd[1]: Stopped dracut cmdline hook. Oct 27 15:46:35.623586 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 27 15:46:35.623627 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 27 15:46:35.625964 systemd[1]: Starting Cleanup udev Database... Oct 27 15:46:35.626875 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 27 15:46:35.645000 audit: BPF prog-id=9 op=UNLOAD Oct 27 15:46:35.628631 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 27 15:46:35.628685 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 27 15:46:35.630420 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 27 15:46:35.630462 systemd[1]: Stopped Create List of Static Device Nodes. Oct 27 15:46:35.632058 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 27 15:46:35.632099 systemd[1]: Stopped Setup Virtual Console. Oct 27 15:46:35.633781 systemd[1]: rngd.service: Deactivated successfully. Oct 27 15:46:35.633897 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 27 15:46:35.635621 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 27 15:46:35.635730 systemd[1]: Finished Cleanup udev Database. Oct 27 15:46:35.636902 systemd[1]: Reached target Switch Root. Oct 27 15:46:35.639146 systemd[1]: Starting Switch Root... Oct 27 15:46:35.644803 systemd[1]: Switching root. Oct 27 15:46:35.660425 systemd-journald[269]: Journal stopped Oct 27 15:46:37.739631 systemd-journald[269]: Received SIGTERM from PID 1 (n/a). Oct 27 15:46:37.739683 kernel: SELinux: policy capability network_peer_controls=1 Oct 27 15:46:37.739695 kernel: SELinux: policy capability open_perms=1 Oct 27 15:46:37.739705 kernel: SELinux: policy capability extended_socket_class=1 Oct 27 15:46:37.739719 kernel: SELinux: policy capability always_check_network=0 Oct 27 15:46:37.739728 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 27 15:46:37.739738 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 27 15:46:37.739747 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 27 15:46:37.739758 systemd[1]: Successfully loaded SELinux policy in 32.315ms. Oct 27 15:46:37.739773 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.574ms. Oct 27 15:46:37.739785 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 27 15:46:37.739796 systemd[1]: Detected virtualization kvm. Oct 27 15:46:37.739806 systemd[1]: Detected architecture arm64. Oct 27 15:46:37.739819 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 27 15:46:37.739830 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 27 15:46:37.739841 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 27 15:46:37.739851 systemd[1]: Stopped Switch Root. Oct 27 15:46:37.739861 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 27 15:46:37.739871 systemd[1]: Created slice Slice /system/addon-config. Oct 27 15:46:37.739881 systemd[1]: Created slice Slice /system/addon-run. Oct 27 15:46:37.739892 systemd[1]: Created slice Slice /system/getty. Oct 27 15:46:37.739902 systemd[1]: Created slice Slice /system/modprobe. Oct 27 15:46:37.739912 systemd[1]: Created slice Slice /system/serial-getty. Oct 27 15:46:37.739922 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 27 15:46:37.739932 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 27 15:46:37.739942 systemd[1]: Created slice User and Session Slice. Oct 27 15:46:37.739952 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 27 15:46:37.739963 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 27 15:46:37.739973 systemd[1]: Set up automount Boot partition Automount Point. Oct 27 15:46:37.739983 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 27 15:46:37.740004 systemd[1]: Stopped target Switch Root. Oct 27 15:46:37.740014 systemd[1]: Stopped target Initrd File Systems. Oct 27 15:46:37.740037 systemd[1]: Stopped target Initrd Root File System. Oct 27 15:46:37.740047 systemd[1]: Reached target Remote Encrypted Volumes. Oct 27 15:46:37.740057 systemd[1]: Reached target Remote File Systems. Oct 27 15:46:37.740067 systemd[1]: Reached target Slice Units. Oct 27 15:46:37.740077 systemd[1]: Reached target Swaps. Oct 27 15:46:37.740087 systemd[1]: Reached target Verify torcx succeeded. Oct 27 15:46:37.740097 systemd[1]: Reached target Local Verity Protected Volumes. Oct 27 15:46:37.740109 systemd[1]: Listening on Process Core Dump Socket. Oct 27 15:46:37.740119 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 27 15:46:37.740129 systemd[1]: Listening on Network Service Netlink Socket. Oct 27 15:46:37.740138 systemd[1]: Listening on udev Control Socket. Oct 27 15:46:37.740148 systemd[1]: Listening on udev Kernel Socket. Oct 27 15:46:37.740158 systemd[1]: Mounting Huge Pages File System... Oct 27 15:46:37.740168 systemd[1]: Mounting POSIX Message Queue File System... Oct 27 15:46:37.740178 systemd[1]: Mounting External Media Directory... Oct 27 15:46:37.740188 systemd[1]: Mounting Kernel Debug File System... Oct 27 15:46:37.740197 systemd[1]: Mounting Kernel Trace File System... Oct 27 15:46:37.740208 systemd[1]: Mounting Temporary Directory /tmp... Oct 27 15:46:37.740219 systemd[1]: Starting Create missing system files... Oct 27 15:46:37.740229 systemd[1]: Starting Create List of Static Device Nodes... Oct 27 15:46:37.740239 systemd[1]: Starting Load Kernel Module configfs... Oct 27 15:46:37.740249 systemd[1]: Starting Load Kernel Module drm... Oct 27 15:46:37.740259 systemd[1]: Starting Load Kernel Module fuse... Oct 27 15:46:37.740269 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 27 15:46:37.740279 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 27 15:46:37.740289 systemd[1]: Stopped File System Check on Root Device. Oct 27 15:46:37.740300 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 27 15:46:37.740310 systemd[1]: Stopped systemd-fsck-usr.service. Oct 27 15:46:37.740319 kernel: fuse: init (API version 7.32) Oct 27 15:46:37.740329 systemd[1]: Stopped Journal Service. Oct 27 15:46:37.740339 systemd[1]: Starting Journal Service... Oct 27 15:46:37.740349 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 27 15:46:37.740359 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 27 15:46:37.740369 systemd[1]: Starting Apply Kernel Variables... Oct 27 15:46:37.740384 systemd[1]: Starting Coldplug All udev Devices... Oct 27 15:46:37.740398 systemd[1]: verity-setup.service: Deactivated successfully. Oct 27 15:46:37.740409 systemd[1]: Stopped verity-setup.service. Oct 27 15:46:37.740420 systemd-journald[692]: Journal started Oct 27 15:46:37.740459 systemd-journald[692]: Runtime Journal (/run/log/journal/c7124dca645d438da9b16aa25dd9a4aa) is 6.0M, max 48.5M, 42.4M free. Oct 27 15:46:35.735000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 27 15:46:35.762000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 27 15:46:35.762000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 27 15:46:35.762000 audit: BPF prog-id=15 op=LOAD Oct 27 15:46:35.762000 audit: BPF prog-id=15 op=UNLOAD Oct 27 15:46:35.762000 audit: BPF prog-id=16 op=LOAD Oct 27 15:46:35.762000 audit: BPF prog-id=16 op=UNLOAD Oct 27 15:46:35.797000 audit[644]: AVC avc: denied { associate } for pid=644 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 27 15:46:37.599000 audit: BPF prog-id=17 op=LOAD Oct 27 15:46:37.599000 audit: BPF prog-id=18 op=LOAD Oct 27 15:46:37.599000 audit: BPF prog-id=19 op=LOAD Oct 27 15:46:37.599000 audit: BPF prog-id=10 op=UNLOAD Oct 27 15:46:37.599000 audit: BPF prog-id=11 op=UNLOAD Oct 27 15:46:37.614000 audit: BPF prog-id=20 op=LOAD Oct 27 15:46:37.614000 audit: BPF prog-id=17 op=UNLOAD Oct 27 15:46:37.614000 audit: BPF prog-id=21 op=LOAD Oct 27 15:46:37.614000 audit: BPF prog-id=22 op=LOAD Oct 27 15:46:37.614000 audit: BPF prog-id=18 op=UNLOAD Oct 27 15:46:37.614000 audit: BPF prog-id=19 op=UNLOAD Oct 27 15:46:37.614000 audit: BPF prog-id=23 op=LOAD Oct 27 15:46:37.614000 audit: BPF prog-id=20 op=UNLOAD Oct 27 15:46:37.614000 audit: BPF prog-id=24 op=LOAD Oct 27 15:46:37.614000 audit: BPF prog-id=25 op=LOAD Oct 27 15:46:37.614000 audit: BPF prog-id=21 op=UNLOAD Oct 27 15:46:37.614000 audit: BPF prog-id=22 op=UNLOAD Oct 27 15:46:37.621000 audit: BPF prog-id=23 op=UNLOAD Oct 27 15:46:37.726000 audit: BPF prog-id=26 op=LOAD Oct 27 15:46:37.726000 audit: BPF prog-id=27 op=LOAD Oct 27 15:46:37.726000 audit: BPF prog-id=28 op=LOAD Oct 27 15:46:37.726000 audit: BPF prog-id=24 op=UNLOAD Oct 27 15:46:37.726000 audit: BPF prog-id=25 op=UNLOAD Oct 27 15:46:37.738000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 27 15:46:37.599842 systemd[1]: Queued start job for default target Multi-User System. Oct 27 15:46:35.796327 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 27 15:46:37.616278 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 27 15:46:35.796657 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 27 15:46:35.796678 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 27 15:46:35.797041 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 27 15:46:35.797055 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 27 15:46:35.797093 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 27 15:46:35.797106 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 27 15:46:35.797527 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 27 15:46:35.797583 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 27 15:46:35.797604 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 27 15:46:35.798030 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 27 15:46:35.798065 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 27 15:46:35.798085 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 27 15:46:35.798101 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 27 15:46:35.798225 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 27 15:46:35.798242 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:35Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 27 15:46:37.510233 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:37Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 27 15:46:37.510514 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:37Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 27 15:46:37.510617 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:37Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 27 15:46:37.510737 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:37Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 27 15:46:37.510842 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:37Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 27 15:46:37.510900 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-27T15:46:37Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 27 15:46:37.743631 systemd[1]: Started Journal Service. Oct 27 15:46:37.744122 systemd[1]: Mounted Huge Pages File System. Oct 27 15:46:37.744976 systemd[1]: Mounted POSIX Message Queue File System. Oct 27 15:46:37.745943 systemd[1]: Mounted External Media Directory. Oct 27 15:46:37.746922 systemd[1]: Mounted Kernel Debug File System. Oct 27 15:46:37.747904 systemd[1]: Mounted Kernel Trace File System. Oct 27 15:46:37.748876 systemd[1]: Mounted Temporary Directory /tmp. Oct 27 15:46:37.750112 systemd[1]: Finished Create missing system files. Oct 27 15:46:37.751292 systemd[1]: Finished Create List of Static Device Nodes. Oct 27 15:46:37.752481 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 27 15:46:37.752656 systemd[1]: Finished Load Kernel Module configfs. Oct 27 15:46:37.753723 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 27 15:46:37.753901 systemd[1]: Finished Load Kernel Module drm. Oct 27 15:46:37.754948 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 27 15:46:37.755148 systemd[1]: Finished Load Kernel Module fuse. Oct 27 15:46:37.764519 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 27 15:46:37.765839 systemd[1]: Finished Apply Kernel Variables. Oct 27 15:46:37.768715 systemd[1]: Mounting FUSE Control File System... Oct 27 15:46:37.770540 systemd[1]: Mounting Kernel Configuration File System... Oct 27 15:46:37.771433 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 27 15:46:37.772318 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 27 15:46:37.773255 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 27 15:46:37.774439 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 27 15:46:37.775342 systemd[1]: Starting Load/Save Random Seed... Oct 27 15:46:37.778720 systemd-journald[692]: Time spent on flushing to /var/log/journal/c7124dca645d438da9b16aa25dd9a4aa is 12.250ms for 666 entries. Oct 27 15:46:37.778720 systemd-journald[692]: System Journal (/var/log/journal/c7124dca645d438da9b16aa25dd9a4aa) is 8.0M, max 203.0M, 195.0M free. Oct 27 15:46:37.776171 systemd[1]: Condition check resulted in Create System Users being skipped. Oct 27 15:46:37.777091 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 27 15:46:37.778765 systemd[1]: Mounted FUSE Control File System. Oct 27 15:46:37.781344 systemd[1]: Mounted Kernel Configuration File System. Oct 27 15:46:37.783399 systemd[1]: Finished Load/Save Random Seed. Oct 27 15:46:37.784800 systemd[1]: Condition check resulted in First Boot Complete being skipped. Oct 27 15:46:37.797306 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 27 15:46:37.798621 systemd[1]: Finished Coldplug All udev Devices. Oct 27 15:46:37.800551 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 27 15:46:37.802000 audit: BPF prog-id=29 op=LOAD Oct 27 15:46:37.802000 audit: BPF prog-id=30 op=LOAD Oct 27 15:46:37.802000 audit: BPF prog-id=31 op=LOAD Oct 27 15:46:37.802000 audit: BPF prog-id=13 op=UNLOAD Oct 27 15:46:37.802000 audit: BPF prog-id=14 op=UNLOAD Oct 27 15:46:37.802982 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 27 15:46:37.805227 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 27 15:46:37.827013 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 27 15:46:37.829000 audit: BPF prog-id=32 op=LOAD Oct 27 15:46:37.829783 systemd[1]: Starting Network Configuration... Oct 27 15:46:37.859522 systemd-networkd[714]: lo: Link UP Oct 27 15:46:37.859534 systemd-networkd[714]: lo: Gained carrier Oct 27 15:46:37.860821 systemd-networkd[714]: Enumeration completed Oct 27 15:46:37.860914 systemd[1]: Started Network Configuration. Oct 27 15:46:37.870225 systemd-udevd[722]: Using default interface naming scheme 'v249'. Oct 27 15:46:37.884301 udevadm[705]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 27 15:46:37.894811 systemd-networkd[714]: eth0: Link UP Oct 27 15:46:37.904240 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 27 15:46:37.905031 systemd-networkd[714]: eth0: Gained carrier Oct 27 15:46:37.914146 systemd-networkd[714]: eth0: DHCPv4 address 10.0.0.8/16 via 10.0.0.1 Oct 27 15:46:37.914955 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 27 15:46:37.923210 lvm[733]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 27 15:46:37.950916 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 27 15:46:37.952068 systemd[1]: Reached target Local Encrypted Volumes. Oct 27 15:46:37.965041 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 27 15:46:37.968279 lvm[734]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 27 15:46:37.994902 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 27 15:46:37.995978 systemd[1]: Reached target Preparation for Local File Systems. Oct 27 15:46:37.996963 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 27 15:46:37.997010 systemd[1]: Reached target Containers. Oct 27 15:46:38.008223 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 27 15:46:38.020035 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 27 15:46:38.022090 systemd[1]: Mounting /usr/share/oem... Oct 27 15:46:38.028008 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 27 15:46:38.028069 kernel: BTRFS info (device vda6): has skinny extents Oct 27 15:46:38.030093 systemd[1]: Mounted /usr/share/oem. Oct 27 15:46:38.030925 systemd[1]: Reached target Local File Systems. Oct 27 15:46:38.031929 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Oct 27 15:46:38.032256 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 27 15:46:38.032291 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 27 15:46:38.032320 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 27 15:46:38.038839 systemd[1]: Starting Create Volatile Files and Directories... Oct 27 15:46:38.044572 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 27 15:46:38.045624 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 27 15:46:38.075188 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Oct 27 15:46:38.075201 systemd-tmpfiles[757]: Skipping /boot Oct 27 15:46:38.081067 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Oct 27 15:46:38.081083 systemd-tmpfiles[757]: Skipping /boot Oct 27 15:46:38.112215 systemd[1]: Finished Create Volatile Files and Directories. Oct 27 15:46:38.123978 systemd[1]: Starting Load Security Auditing Rules... Oct 27 15:46:38.125705 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 27 15:46:38.126810 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Oct 27 15:46:38.126000 audit: BPF prog-id=33 op=LOAD Oct 27 15:46:38.128202 systemd[1]: Starting Network Name Resolution... Oct 27 15:46:38.128000 audit: BPF prog-id=34 op=LOAD Oct 27 15:46:38.130257 systemd[1]: Starting Network Time Synchronization... Oct 27 15:46:38.131929 systemd[1]: Condition check resulted in Update is Completed being skipped. Oct 27 15:46:38.132861 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 27 15:46:38.134404 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 27 15:46:38.135747 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 27 15:46:38.138827 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 27 15:46:38.151000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 27 15:46:38.152541 augenrules[778]: No rules Oct 27 15:46:38.153248 systemd[1]: Finished Load Security Auditing Rules. Oct 27 15:46:38.168484 systemd-resolved[761]: Positive Trust Anchors: Oct 27 15:46:38.168499 systemd-resolved[761]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 27 15:46:38.168527 systemd-resolved[761]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 27 15:46:38.169146 systemd[1]: Started Network Time Synchronization. Oct 27 15:46:38.169258 systemd-resolved[761]: Defaulting to hostname 'linux'. Oct 27 15:46:38.170170 systemd-timesyncd[767]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 27 15:46:38.170311 systemd[1]: Reached target System Initialization. Oct 27 15:46:38.171337 systemd[1]: Started Watch for update engine configuration changes. Oct 27 15:46:38.172459 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 27 15:46:38.173631 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 27 15:46:38.174640 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 27 15:46:38.174672 systemd[1]: Reached target Path Units. Oct 27 15:46:38.175454 systemd[1]: Reached target System Time Set. Oct 27 15:46:38.176479 systemd[1]: Started Daily Log Rotation. Oct 27 15:46:38.177337 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 27 15:46:38.178438 systemd[1]: Reached target Timer Units. Oct 27 15:46:38.179522 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 27 15:46:38.189631 systemd[1]: Starting Docker Socket for the API... Oct 27 15:46:38.192780 systemd[1]: Listening on OpenSSH Server Socket. Oct 27 15:46:38.194109 systemd[1]: Started Network Name Resolution. Oct 27 15:46:38.195060 systemd[1]: Listening on Docker Socket for the API. Oct 27 15:46:38.195970 systemd[1]: Reached target Network. Oct 27 15:46:38.196710 systemd[1]: Reached target Host and Network Name Lookups. Oct 27 15:46:38.197721 systemd[1]: Reached target Socket Units. Oct 27 15:46:38.198556 systemd[1]: Reached target Basic System. Oct 27 15:46:38.199390 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 27 15:46:38.199419 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 27 15:46:38.200362 systemd[1]: Started D-Bus System Message Bus. Oct 27 15:46:38.203833 systemd[1]: Starting Extend Filesystems... Oct 27 15:46:38.204726 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 27 15:46:38.205800 systemd[1]: Starting Generate /run/flatcar/motd... Oct 27 15:46:38.207737 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 27 15:46:38.209958 systemd[1]: Starting Generate sshd host keys... Oct 27 15:46:38.211391 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 27 15:46:38.211430 systemd[1]: Reached target Load system-provided cloud configs. Oct 27 15:46:38.216153 systemd[1]: Starting User Login Management... Oct 27 15:46:38.217094 extend-filesystems[788]: Found vda Oct 27 15:46:38.218011 extend-filesystems[788]: Found vda1 Oct 27 15:46:38.217401 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 27 15:46:38.218015 systemd[1]: Starting Update Engine... Oct 27 15:46:38.218976 extend-filesystems[788]: Found vda2 Oct 27 15:46:38.219435 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 27 15:46:38.219468 systemd[1]: Reached target Load user-provided cloud configs. Oct 27 15:46:38.220184 extend-filesystems[788]: Found vda3 Oct 27 15:46:38.221775 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 27 15:46:38.221917 extend-filesystems[788]: Found usr Oct 27 15:46:38.222064 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 27 15:46:38.222758 extend-filesystems[788]: Found vda4 Oct 27 15:46:38.225910 extend-filesystems[788]: Found vda6 Oct 27 15:46:38.226021 systemd[1]: motdgen.service: Deactivated successfully. Oct 27 15:46:38.226329 systemd[1]: Finished Generate /run/flatcar/motd. Oct 27 15:46:38.227048 extend-filesystems[788]: Found vda7 Oct 27 15:46:38.231079 extend-filesystems[788]: Found vda9 Oct 27 15:46:38.231079 extend-filesystems[788]: Checking size of /dev/vda9 Oct 27 15:46:38.244133 systemd-logind[802]: Watching system buttons on /dev/input/event0 (Power Button) Oct 27 15:46:38.244319 systemd-logind[802]: New seat seat0. Oct 27 15:46:38.248874 systemd[1]: Started User Login Management. Oct 27 15:46:38.253357 extend-filesystems[788]: Old size kept for /dev/vda9 Oct 27 15:46:38.255420 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 27 15:46:38.255650 systemd[1]: Finished Extend Filesystems. Oct 27 15:46:38.273126 update_engine[803]: I1027 15:46:38.272773 803 main.cc:89] Flatcar Update Engine starting Oct 27 15:46:38.273787 update_engine[803]: I1027 15:46:38.273747 803 payload_state.cc:360] Current Response Signature = Oct 27 15:46:38.273787 update_engine[803]: NumURLs = 1 Oct 27 15:46:38.273787 update_engine[803]: Url0 = http://10.0.0.7:34567/packages/update.gz Oct 27 15:46:38.273787 update_engine[803]: Payload Size = 435420691 Oct 27 15:46:38.273787 update_engine[803]: Payload Sha256 Hash = nLXXFElmOY/MZeJVU0GqyZXmGgOOWnMNMVrLO0s03g0= Oct 27 15:46:38.273787 update_engine[803]: Is Delta Payload = 0 Oct 27 15:46:38.273787 update_engine[803]: Max Failure Count Per Url = 10 Oct 27 15:46:38.273787 update_engine[803]: Disable Payload Backoff = 1 Oct 27 15:46:38.273981 update_engine[803]: I1027 15:46:38.273953 803 payload_state.cc:381] Payload Attempt Number = 0 Oct 27 15:46:38.274176 update_engine[803]: I1027 15:46:38.274151 803 payload_state.cc:404] Current URL Index = 0 Oct 27 15:46:38.274343 update_engine[803]: I1027 15:46:38.274322 803 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 27 15:46:38.274529 update_engine[803]: I1027 15:46:38.274495 803 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 27 15:46:38.277348 systemd[1]: Started Update Engine. Oct 27 15:46:38.278611 update_engine[803]: I1027 15:46:38.278568 803 update_check_scheduler.cc:74] Next update check in 5m34s Oct 27 15:46:38.285611 systemd[1]: Finished Generate sshd host keys. Oct 27 15:46:38.296021 systemd[1]: Starting Generate /run/issue... Oct 27 15:46:38.300324 systemd[1]: issuegen.service: Deactivated successfully. Oct 27 15:46:38.300552 systemd[1]: Finished Generate /run/issue. Oct 27 15:46:38.302695 systemd[1]: Starting Permit User Sessions... Oct 27 15:46:38.307453 systemd[1]: Finished Permit User Sessions. Oct 27 15:46:38.309473 systemd[1]: Started Getty on tty1. Oct 27 15:46:38.311292 systemd[1]: Started Serial Getty on ttyAMA0. Oct 27 15:46:38.312273 systemd[1]: Reached target Login Prompts. Oct 27 15:46:38.313156 systemd[1]: Reached target Multi-User System. Oct 27 15:46:38.315034 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 27 15:46:38.319875 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 27 15:46:38.320155 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 27 15:46:38.321143 systemd[1]: Startup finished in 711ms (kernel) + 1.144s (initrd) + 2.620s (userspace) = 4.476s. Oct 27 15:46:39.316175 systemd-networkd[714]: eth0: Gained IPv6LL Oct 27 15:46:47.996987 systemd[1]: Created slice Slice /system/sshd. Oct 27 15:46:47.998023 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57872). Oct 27 15:46:48.043146 sshd[827]: Accepted publickey for core from 10.0.0.1 port 57872 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:48.045047 sshd[827]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:48.066493 systemd[1]: Created slice User Slice of UID 500. Oct 27 15:46:48.067549 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 27 15:46:48.069046 systemd-logind[802]: New session 1 of user core. Oct 27 15:46:48.073910 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 27 15:46:48.075135 systemd[1]: Starting User Manager for UID 500... Oct 27 15:46:48.077403 systemd[830]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:48.124162 systemd[830]: Queued start job for default target Main User Target. Oct 27 15:46:48.124333 systemd[830]: Reached target Paths. Oct 27 15:46:48.124346 systemd[830]: Reached target Sockets. Oct 27 15:46:48.124357 systemd[830]: Reached target Timers. Oct 27 15:46:48.124366 systemd[830]: Reached target Basic System. Oct 27 15:46:48.124408 systemd[830]: Reached target Main User Target. Oct 27 15:46:48.124420 systemd[830]: Startup finished in 42ms. Oct 27 15:46:48.124647 systemd[1]: Started User Manager for UID 500. Oct 27 15:46:48.131193 systemd[1]: Started Session 1 of User core. Oct 27 15:46:48.190841 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57888). Oct 27 15:46:48.232259 sshd[839]: Accepted publickey for core from 10.0.0.1 port 57888 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:48.233283 sshd[839]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:48.236479 systemd-logind[802]: New session 2 of user core. Oct 27 15:46:48.248175 systemd[1]: Started Session 2 of User core. Oct 27 15:46:48.299811 sshd[839]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:48.310641 systemd[1]: sshd@1-10.0.0.8:22-10.0.0.1:57888.service: Deactivated successfully. Oct 27 15:46:48.311264 systemd[1]: session-2.scope: Deactivated successfully. Oct 27 15:46:48.311897 systemd-logind[802]: Session 2 logged out. Waiting for processes to exit. Oct 27 15:46:48.312933 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57890). Oct 27 15:46:48.313519 systemd-logind[802]: Removed session 2. Oct 27 15:46:48.354427 sshd[845]: Accepted publickey for core from 10.0.0.1 port 57890 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:48.355877 sshd[845]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:48.358627 systemd-logind[802]: New session 3 of user core. Oct 27 15:46:48.368172 systemd[1]: Started Session 3 of User core. Oct 27 15:46:48.417026 sshd[845]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:48.427096 systemd[1]: sshd@2-10.0.0.8:22-10.0.0.1:57890.service: Deactivated successfully. Oct 27 15:46:48.427686 systemd[1]: session-3.scope: Deactivated successfully. Oct 27 15:46:48.428336 systemd-logind[802]: Session 3 logged out. Waiting for processes to exit. Oct 27 15:46:48.429464 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57904). Oct 27 15:46:48.430380 systemd-logind[802]: Removed session 3. Oct 27 15:46:48.470840 sshd[851]: Accepted publickey for core from 10.0.0.1 port 57904 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:48.471975 sshd[851]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:48.474779 systemd-logind[802]: New session 4 of user core. Oct 27 15:46:48.487166 systemd[1]: Started Session 4 of User core. Oct 27 15:46:48.539045 sshd[851]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:48.550055 systemd[1]: sshd@3-10.0.0.8:22-10.0.0.1:57904.service: Deactivated successfully. Oct 27 15:46:48.550649 systemd[1]: session-4.scope: Deactivated successfully. Oct 27 15:46:48.551290 systemd-logind[802]: Session 4 logged out. Waiting for processes to exit. Oct 27 15:46:48.552358 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57918). Oct 27 15:46:48.553278 systemd-logind[802]: Removed session 4. Oct 27 15:46:48.593639 sshd[858]: Accepted publickey for core from 10.0.0.1 port 57918 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:48.594692 sshd[858]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:48.597491 systemd-logind[802]: New session 5 of user core. Oct 27 15:46:48.605198 systemd[1]: Started Session 5 of User core. Oct 27 15:46:48.662166 sudo[861]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 27 15:46:48.662395 sudo[861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:46:48.678545 sudo[861]: pam_unix(sudo:session): session closed for user root Oct 27 15:46:48.680483 sshd[858]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:48.690840 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57924). Oct 27 15:46:48.693581 dbus-daemon[787]: [system] Reloaded configuration Oct 27 15:46:48.696402 systemd[1]: sshd@4-10.0.0.8:22-10.0.0.1:57918.service: Deactivated successfully. Oct 27 15:46:48.697175 systemd[1]: session-5.scope: Deactivated successfully. Oct 27 15:46:48.697798 systemd-logind[802]: Session 5 logged out. Waiting for processes to exit. Oct 27 15:46:48.698431 systemd-logind[802]: Removed session 5. Oct 27 15:46:48.732479 sshd[864]: Accepted publickey for core from 10.0.0.1 port 57924 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:48.733500 sshd[864]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:48.736087 systemd-logind[802]: New session 6 of user core. Oct 27 15:46:48.746178 systemd[1]: Started Session 6 of User core. Oct 27 15:46:48.796887 sudo[869]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 27 15:46:48.797133 sudo[869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:46:48.800033 sudo[869]: pam_unix(sudo:session): session closed for user root Oct 27 15:46:48.803786 sudo[868]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 27 15:46:48.804006 sudo[868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 27 15:46:48.821023 systemd[1]: Stopping Load Security Auditing Rules... Oct 27 15:46:48.821000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 27 15:46:48.822369 auditctl[872]: No rules Oct 27 15:46:48.822766 systemd[1]: audit-rules.service: Deactivated successfully. Oct 27 15:46:48.823014 systemd[1]: Stopped Load Security Auditing Rules. Oct 27 15:46:48.824509 kernel: kauditd_printk_skb: 49 callbacks suppressed Oct 27 15:46:48.824547 kernel: audit: type=1305 audit(1761580008.821:60): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 27 15:46:48.824346 systemd[1]: Starting Load Security Auditing Rules... Oct 27 15:46:48.839201 augenrules[889]: No rules Oct 27 15:46:48.839954 systemd[1]: Finished Load Security Auditing Rules. Oct 27 15:46:48.841124 sudo[868]: pam_unix(sudo:session): session closed for user root Oct 27 15:46:48.842313 sshd[864]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:48.851756 systemd[1]: sshd@5-10.0.0.8:22-10.0.0.1:57924.service: Deactivated successfully. Oct 27 15:46:48.852311 systemd[1]: session-6.scope: Deactivated successfully. Oct 27 15:46:48.852931 systemd-logind[802]: Session 6 logged out. Waiting for processes to exit. Oct 27 15:46:48.853946 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57934). Oct 27 15:46:48.854583 systemd-logind[802]: Removed session 6. Oct 27 15:46:48.895456 sshd[895]: Accepted publickey for core from 10.0.0.1 port 57934 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:48.896893 sshd[895]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:48.899011 kernel: audit: type=1006 audit(1761580008.895:61): pid=895 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=7 res=1 Oct 27 15:46:48.901273 systemd-logind[802]: New session 7 of user core. Oct 27 15:46:48.910182 systemd[1]: Started Session 7 of User core. Oct 27 15:46:48.959218 sshd[895]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:48.971755 systemd[1]: sshd@6-10.0.0.8:22-10.0.0.1:57934.service: Deactivated successfully. Oct 27 15:46:48.972406 systemd[1]: session-7.scope: Deactivated successfully. Oct 27 15:46:48.973079 systemd-logind[802]: Session 7 logged out. Waiting for processes to exit. Oct 27 15:46:48.974573 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57938). Oct 27 15:46:48.975379 systemd-logind[802]: Removed session 7. Oct 27 15:46:49.016270 sshd[901]: Accepted publickey for core from 10.0.0.1 port 57938 ssh2: RSA SHA256:14IyWNatYfevHJ46sO8iadtJMotA3G9xRpumQzWzHP0 Oct 27 15:46:49.017640 sshd[901]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 27 15:46:49.020013 kernel: audit: type=1006 audit(1761580009.016:62): pid=901 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=8 res=1 Oct 27 15:46:49.021839 systemd-logind[802]: New session 8 of user core. Oct 27 15:46:49.030160 systemd[1]: Started Session 8 of User core. Oct 27 15:46:49.079034 sshd[901]: pam_unix(sshd:session): session closed for user core Oct 27 15:46:49.081302 systemd[1]: sshd@7-10.0.0.8:22-10.0.0.1:57938.service: Deactivated successfully. Oct 27 15:46:49.081935 systemd[1]: session-8.scope: Deactivated successfully. Oct 27 15:46:49.082569 systemd-logind[802]: Session 8 logged out. Waiting for processes to exit. Oct 27 15:46:49.083307 systemd-logind[802]: Removed session 8.