Oct 31 00:48:13.701403 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 31 00:48:13.701419 kernel: Linux version 5.10.107-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP PREEMPT Tue Mar 22 19:39:09 -00 2022 Oct 31 00:48:13.701427 kernel: efi: EFI v2.70 by EDK II Oct 31 00:48:13.701432 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 31 00:48:13.701437 kernel: efi: seeding entropy pool Oct 31 00:48:13.701442 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 31 00:48:13.701448 kernel: ACPI: Early table checksum verification disabled Oct 31 00:48:13.701453 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 31 00:48:13.701458 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 31 00:48:13.701463 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:13.701469 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:13.701474 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:13.701479 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:13.701484 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:13.701490 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:13.701496 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:13.701502 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:13.701508 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:13.701513 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 31 00:48:13.701518 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 31 00:48:13.701523 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 00:48:13.701528 kernel: NUMA: NODE_DATA [mem 0xdc959900-0xdc95efff] Oct 31 00:48:13.701534 kernel: Zone ranges: Oct 31 00:48:13.701539 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 00:48:13.701544 kernel: DMA32 empty Oct 31 00:48:13.701549 kernel: Normal empty Oct 31 00:48:13.701554 kernel: Movable zone start for each node Oct 31 00:48:13.701560 kernel: Early memory node ranges Oct 31 00:48:13.701565 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 31 00:48:13.701570 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 31 00:48:13.701575 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 31 00:48:13.701581 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 31 00:48:13.701586 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 31 00:48:13.701591 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 31 00:48:13.701596 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 31 00:48:13.701601 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 31 00:48:13.701606 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 31 00:48:13.701611 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 31 00:48:13.701616 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 31 00:48:13.701622 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 00:48:13.701630 kernel: On node 0 totalpages: 643072 Oct 31 00:48:13.701636 kernel: DMA zone: 10048 pages used for memmap Oct 31 00:48:13.701641 kernel: DMA zone: 0 pages reserved Oct 31 00:48:13.701646 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 31 00:48:13.701652 kernel: psci: probing for conduit method from ACPI. Oct 31 00:48:13.701657 kernel: psci: PSCIv1.1 detected in firmware. Oct 31 00:48:13.701663 kernel: psci: Using standard PSCI v0.2 function IDs Oct 31 00:48:13.701668 kernel: psci: Trusted OS migration not required Oct 31 00:48:13.701674 kernel: psci: SMC Calling Convention v1.1 Oct 31 00:48:13.701680 kernel: ACPI: SRAT not present Oct 31 00:48:13.701686 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 31 00:48:13.701691 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 31 00:48:13.701697 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 31 00:48:13.701702 kernel: Detected PIPT I-cache on CPU0 Oct 31 00:48:13.701707 kernel: CPU features: detected: GIC system register CPU interface Oct 31 00:48:13.701713 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 31 00:48:13.701718 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 31 00:48:13.701724 kernel: CPU features: detected: Hardware dirty bit management Oct 31 00:48:13.701737 kernel: CPU features: detected: Spectre-v4 Oct 31 00:48:13.701744 kernel: CPU features: detected: ARM erratum 1418040 Oct 31 00:48:13.701750 kernel: CPU features: detected: Spectre-BHB Oct 31 00:48:13.701755 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 31 00:48:13.701761 kernel: Policy zone: DMA Oct 31 00:48:13.701767 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 31 00:48:13.701773 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 31 00:48:13.701779 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 31 00:48:13.701784 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 31 00:48:13.701790 kernel: Memory: 2452096K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120192K reserved, 0K cma-reserved) Oct 31 00:48:13.701796 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 31 00:48:13.701801 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 31 00:48:13.701808 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 31 00:48:13.701814 kernel: rcu: RCU event tracing is enabled. Oct 31 00:48:13.701820 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 31 00:48:13.701825 kernel: Trampoline variant of Tasks RCU enabled. Oct 31 00:48:13.701831 kernel: Tracing variant of Tasks RCU enabled. Oct 31 00:48:13.701836 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 31 00:48:13.701842 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 31 00:48:13.701847 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 31 00:48:13.701853 kernel: GICv3: 256 SPIs implemented Oct 31 00:48:13.701858 kernel: GICv3: 0 Extended SPIs implemented Oct 31 00:48:13.701863 kernel: GICv3: Distributor has no Range Selector support Oct 31 00:48:13.701869 kernel: GICv3: 16 PPIs implemented Oct 31 00:48:13.701875 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 31 00:48:13.701881 kernel: ACPI: SRAT not present Oct 31 00:48:13.701886 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 31 00:48:13.701891 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 31 00:48:13.701897 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 31 00:48:13.701903 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 31 00:48:13.701908 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 31 00:48:13.701914 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 00:48:13.701919 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 31 00:48:13.701925 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 31 00:48:13.701931 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 31 00:48:13.701937 kernel: arm-pv: using stolen time PV Oct 31 00:48:13.701944 kernel: Console: colour dummy device 80x25 Oct 31 00:48:13.701949 kernel: ACPI: Core revision 20200925 Oct 31 00:48:13.701955 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 31 00:48:13.701961 kernel: pid_max: default: 32768 minimum: 301 Oct 31 00:48:13.701966 kernel: LSM: Security Framework initializing Oct 31 00:48:13.701972 kernel: SELinux: Initializing. Oct 31 00:48:13.701978 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 00:48:13.701984 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 00:48:13.701989 kernel: rcu: Hierarchical SRCU implementation. Oct 31 00:48:13.701996 kernel: Platform MSI: ITS@0x8080000 domain created Oct 31 00:48:13.702002 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 31 00:48:13.702007 kernel: Remapping and enabling EFI services. Oct 31 00:48:13.702013 kernel: smp: Bringing up secondary CPUs ... Oct 31 00:48:13.702019 kernel: Detected PIPT I-cache on CPU1 Oct 31 00:48:13.702024 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 31 00:48:13.702030 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 31 00:48:13.702036 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 00:48:13.702042 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 31 00:48:13.702048 kernel: Detected PIPT I-cache on CPU2 Oct 31 00:48:13.702055 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 31 00:48:13.702060 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 31 00:48:13.702066 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 00:48:13.702072 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 31 00:48:13.702078 kernel: Detected PIPT I-cache on CPU3 Oct 31 00:48:13.702087 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 31 00:48:13.702094 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 31 00:48:13.702101 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 00:48:13.702107 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 31 00:48:13.702112 kernel: smp: Brought up 1 node, 4 CPUs Oct 31 00:48:13.702118 kernel: SMP: Total of 4 processors activated. Oct 31 00:48:13.702124 kernel: CPU features: detected: Privileged Access Never Oct 31 00:48:13.702130 kernel: CPU features: detected: LSE atomic instructions Oct 31 00:48:13.702136 kernel: CPU features: detected: User Access Override Oct 31 00:48:13.702142 kernel: CPU features: detected: 32-bit EL0 Support Oct 31 00:48:13.702148 kernel: CPU features: detected: Common not Private translations Oct 31 00:48:13.702163 kernel: CPU features: detected: RAS Extension Support Oct 31 00:48:13.702173 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 31 00:48:13.702179 kernel: CPU features: detected: CRC32 instructions Oct 31 00:48:13.702185 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 31 00:48:13.702191 kernel: CPU: All CPU(s) started at EL1 Oct 31 00:48:13.702210 kernel: alternatives: patching kernel code Oct 31 00:48:13.702218 kernel: devtmpfs: initialized Oct 31 00:48:13.702224 kernel: KASLR enabled Oct 31 00:48:13.702230 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 31 00:48:13.702236 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 31 00:48:13.702242 kernel: pinctrl core: initialized pinctrl subsystem Oct 31 00:48:13.702248 kernel: SMBIOS 3.0.0 present. Oct 31 00:48:13.702254 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 31 00:48:13.702260 kernel: NET: Registered protocol family 16 Oct 31 00:48:13.702266 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 31 00:48:13.702272 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 31 00:48:13.702280 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 31 00:48:13.702286 kernel: audit: initializing netlink subsys (disabled) Oct 31 00:48:13.702292 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Oct 31 00:48:13.702298 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 31 00:48:13.702304 kernel: cpuidle: using governor menu Oct 31 00:48:13.702310 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 31 00:48:13.702316 kernel: ASID allocator initialised with 32768 entries Oct 31 00:48:13.702322 kernel: ACPI: bus type PCI registered Oct 31 00:48:13.702328 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 31 00:48:13.702335 kernel: Serial: AMBA PL011 UART driver Oct 31 00:48:13.702341 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 31 00:48:13.702348 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 31 00:48:13.702353 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 31 00:48:13.702359 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 31 00:48:13.702365 kernel: cryptd: max_cpu_qlen set to 1000 Oct 31 00:48:13.702371 kernel: ACPI: Added _OSI(Module Device) Oct 31 00:48:13.702377 kernel: ACPI: Added _OSI(Processor Device) Oct 31 00:48:13.702383 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 31 00:48:13.702390 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 31 00:48:13.702396 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 31 00:48:13.702402 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 31 00:48:13.702408 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 31 00:48:13.702414 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 31 00:48:13.702420 kernel: ACPI: Interpreter enabled Oct 31 00:48:13.702426 kernel: ACPI: Using GIC for interrupt routing Oct 31 00:48:13.702432 kernel: ACPI: MCFG table detected, 1 entries Oct 31 00:48:13.702438 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 31 00:48:13.702444 kernel: printk: console [ttyAMA0] enabled Oct 31 00:48:13.702452 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 31 00:48:13.702560 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 31 00:48:13.702617 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 31 00:48:13.702670 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 31 00:48:13.702722 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 31 00:48:13.702784 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 31 00:48:13.702792 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 31 00:48:13.702801 kernel: PCI host bridge to bus 0000:00 Oct 31 00:48:13.702862 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 31 00:48:13.702912 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 31 00:48:13.702960 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 31 00:48:13.703007 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 31 00:48:13.703073 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 31 00:48:13.703136 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 31 00:48:13.703209 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 31 00:48:13.703267 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 31 00:48:13.703329 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 31 00:48:13.703386 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 31 00:48:13.703440 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 31 00:48:13.703494 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 31 00:48:13.703543 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 31 00:48:13.703597 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 31 00:48:13.703647 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 31 00:48:13.703655 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 31 00:48:13.703661 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 31 00:48:13.703668 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 31 00:48:13.703674 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 31 00:48:13.703680 kernel: vgaarb: loaded Oct 31 00:48:13.703686 kernel: Registered efivars operations Oct 31 00:48:13.703692 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 31 00:48:13.703700 kernel: VFS: Disk quotas dquot_6.6.0 Oct 31 00:48:13.703706 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 31 00:48:13.703712 kernel: pnp: PnP ACPI init Oct 31 00:48:13.703788 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 31 00:48:13.703839 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 31 00:48:13.703847 kernel: pnp: PnP ACPI: found 1 devices Oct 31 00:48:13.703853 kernel: NET: Registered protocol family 2 Oct 31 00:48:13.703860 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 31 00:48:13.703868 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 31 00:48:13.703874 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 31 00:48:13.703880 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 31 00:48:13.703886 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 31 00:48:13.703892 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 00:48:13.703898 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 00:48:13.703904 kernel: NET: Registered protocol family 1 Oct 31 00:48:13.703910 kernel: PCI: CLS 0 bytes, default 64 Oct 31 00:48:13.703916 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 31 00:48:13.703924 kernel: kvm [1]: HYP mode not available Oct 31 00:48:13.703930 kernel: Initialise system trusted keyrings Oct 31 00:48:13.703936 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 31 00:48:13.703942 kernel: Key type asymmetric registered Oct 31 00:48:13.703948 kernel: Asymmetric key parser 'x509' registered Oct 31 00:48:13.703954 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 31 00:48:13.703960 kernel: io scheduler mq-deadline registered Oct 31 00:48:13.703966 kernel: io scheduler kyber registered Oct 31 00:48:13.703972 kernel: io scheduler bfq registered Oct 31 00:48:13.703979 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 31 00:48:13.703985 kernel: ACPI: Power Button [PWRB] Oct 31 00:48:13.703991 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 31 00:48:13.704046 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 31 00:48:13.704054 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 31 00:48:13.704060 kernel: msm_serial: driver initialized Oct 31 00:48:13.704066 kernel: random: fast init done Oct 31 00:48:13.704072 kernel: random: crng init done Oct 31 00:48:13.704078 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 31 00:48:13.704084 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 31 00:48:13.704092 kernel: thunder_xcv, ver 1.0 Oct 31 00:48:13.704098 kernel: thunder_bgx, ver 1.0 Oct 31 00:48:13.704103 kernel: nicpf, ver 1.0 Oct 31 00:48:13.704109 kernel: nicvf, ver 1.0 Oct 31 00:48:13.704180 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 31 00:48:13.704233 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-31T00:48:13 UTC (1761871693) Oct 31 00:48:13.704241 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 31 00:48:13.704248 kernel: NET: Registered protocol family 10 Oct 31 00:48:13.704256 kernel: Segment Routing with IPv6 Oct 31 00:48:13.704262 kernel: NET: Registered protocol family 17 Oct 31 00:48:13.704268 kernel: Key type dns_resolver registered Oct 31 00:48:13.704274 kernel: registered taskstats version 1 Oct 31 00:48:13.704280 kernel: Loading compiled-in X.509 certificates Oct 31 00:48:13.704286 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 31 00:48:13.704292 kernel: Key type ._fscrypt registered Oct 31 00:48:13.704298 kernel: Key type .fscrypt registered Oct 31 00:48:13.704304 kernel: Key type fscrypt-provisioning registered Oct 31 00:48:13.704311 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 31 00:48:13.704317 kernel: ima: Allocated hash algorithm: sha1 Oct 31 00:48:13.704323 kernel: ima: No architecture policies found Oct 31 00:48:13.704329 kernel: Freeing unused kernel memory: 34240K Oct 31 00:48:13.704335 kernel: Run /init as init process Oct 31 00:48:13.704341 kernel: with arguments: Oct 31 00:48:13.704347 kernel: /init Oct 31 00:48:13.704353 kernel: with environment: Oct 31 00:48:13.704358 kernel: HOME=/ Oct 31 00:48:13.704364 kernel: TERM=linux Oct 31 00:48:13.704371 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 31 00:48:13.704379 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 31 00:48:13.704388 systemd[1]: Detected virtualization kvm. Oct 31 00:48:13.704394 systemd[1]: Detected architecture arm64. Oct 31 00:48:13.704400 systemd[1]: Running in initial RAM disk. Oct 31 00:48:13.704407 systemd[1]: No hostname configured, using default hostname. Oct 31 00:48:13.704413 systemd[1]: Hostname set to . Oct 31 00:48:13.704421 systemd[1]: Initializing machine ID from VM UUID. Oct 31 00:48:13.704428 kernel: hrtimer: interrupt took 3860280 ns Oct 31 00:48:13.704434 systemd[1]: Queued start job for default target Initrd Default Target. Oct 31 00:48:13.704440 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 31 00:48:13.704447 systemd[1]: Reached target Local Encrypted Volumes. Oct 31 00:48:13.704453 systemd[1]: Reached target Path Units. Oct 31 00:48:13.704459 systemd[1]: Reached target Slice Units. Oct 31 00:48:13.704466 systemd[1]: Reached target Swaps. Oct 31 00:48:13.704472 systemd[1]: Reached target Timer Units. Oct 31 00:48:13.704480 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 31 00:48:13.704486 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 31 00:48:13.704492 systemd[1]: Listening on Journal Audit Socket. Oct 31 00:48:13.704499 systemd[1]: Listening on Journal Socket (/dev/log). Oct 31 00:48:13.704505 systemd[1]: Listening on Journal Socket. Oct 31 00:48:13.704512 systemd[1]: Listening on Network Service Netlink Socket. Oct 31 00:48:13.704518 systemd[1]: Listening on udev Control Socket. Oct 31 00:48:13.704524 systemd[1]: Listening on udev Kernel Socket. Oct 31 00:48:13.704532 systemd[1]: Reached target Socket Units. Oct 31 00:48:13.704538 systemd[1]: Starting Create List of Static Device Nodes... Oct 31 00:48:13.704545 systemd[1]: Finished Network Cleanup. Oct 31 00:48:13.704551 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 31 00:48:13.704558 systemd[1]: Starting Journal Service... Oct 31 00:48:13.704564 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 31 00:48:13.704571 systemd[1]: Starting Apply Kernel Variables... Oct 31 00:48:13.704577 systemd[1]: Starting Setup Virtual Console... Oct 31 00:48:13.704583 systemd[1]: Finished Create List of Static Device Nodes. Oct 31 00:48:13.704590 systemd[1]: Finished Apply Kernel Variables. Oct 31 00:48:13.704598 systemd[1]: Finished Setup Virtual Console. Oct 31 00:48:13.704605 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 31 00:48:13.704613 systemd-journald[278]: Journal started Oct 31 00:48:13.704647 systemd-journald[278]: Runtime Journal (/run/log/journal/e6bb737c0f5e4438bdeb343d8ca8882b) is 6.0M, max 48.5M, 42.4M free. Oct 31 00:48:13.707859 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 31 00:48:13.709382 systemd[1]: Started Journal Service. Oct 31 00:48:13.709748 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 31 00:48:13.721480 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 31 00:48:13.723242 systemd[1]: Starting dracut cmdline hook... Oct 31 00:48:13.731993 dracut-cmdline[296]: dracut-dracut-053 Oct 31 00:48:13.734339 dracut-cmdline[296]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 31 00:48:13.797205 kernel: SCSI subsystem initialized Oct 31 00:48:13.801173 kernel: Loading iSCSI transport class v2.0-870. Oct 31 00:48:13.810693 kernel: iscsi: registered transport (tcp) Oct 31 00:48:13.821629 kernel: iscsi: registered transport (qla4xxx) Oct 31 00:48:13.821677 kernel: QLogic iSCSI HBA Driver Oct 31 00:48:13.832140 systemd[1]: Finished dracut cmdline hook. Oct 31 00:48:13.833789 systemd[1]: Starting dracut pre-udev hook... Oct 31 00:48:13.847600 kernel: device-mapper: uevent: version 1.0.3 Oct 31 00:48:13.847646 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 31 00:48:13.883224 kernel: raid6: neonx8 gen() 13728 MB/s Oct 31 00:48:13.900214 kernel: raid6: neonx8 xor() 10767 MB/s Oct 31 00:48:13.917198 kernel: raid6: neonx4 gen() 13601 MB/s Oct 31 00:48:13.934207 kernel: raid6: neonx4 xor() 11576 MB/s Oct 31 00:48:13.951266 kernel: raid6: neonx2 gen() 13012 MB/s Oct 31 00:48:13.968191 kernel: raid6: neonx2 xor() 9852 MB/s Oct 31 00:48:13.985183 kernel: raid6: neonx1 gen() 10867 MB/s Oct 31 00:48:14.002188 kernel: raid6: neonx1 xor() 8842 MB/s Oct 31 00:48:14.019187 kernel: raid6: int64x8 gen() 6827 MB/s Oct 31 00:48:14.036211 kernel: raid6: int64x8 xor() 3792 MB/s Oct 31 00:48:14.053208 kernel: raid6: int64x4 gen() 6915 MB/s Oct 31 00:48:14.070187 kernel: raid6: int64x4 xor() 3836 MB/s Oct 31 00:48:14.087199 kernel: raid6: int64x2 gen() 6187 MB/s Oct 31 00:48:14.104192 kernel: raid6: int64x2 xor() 3322 MB/s Oct 31 00:48:14.121187 kernel: raid6: int64x1 gen() 5005 MB/s Oct 31 00:48:14.138410 kernel: raid6: int64x1 xor() 2594 MB/s Oct 31 00:48:14.138427 kernel: raid6: using algorithm neonx8 gen() 13728 MB/s Oct 31 00:48:14.138436 kernel: raid6: .... xor() 10767 MB/s, rmw enabled Oct 31 00:48:14.139785 kernel: raid6: using neon recovery algorithm Oct 31 00:48:14.150409 kernel: xor: measuring software checksum speed Oct 31 00:48:14.150442 kernel: 8regs : 17148 MB/sec Oct 31 00:48:14.151489 kernel: 32regs : 20749 MB/sec Oct 31 00:48:14.152403 kernel: arm64_neon : 28977 MB/sec Oct 31 00:48:14.152415 kernel: xor: using function: arm64_neon (28977 MB/sec) Oct 31 00:48:14.198203 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 31 00:48:14.208608 systemd[1]: Finished dracut pre-udev hook. Oct 31 00:48:14.209000 audit: BPF prog-id=6 op=LOAD Oct 31 00:48:14.211000 audit: BPF prog-id=7 op=LOAD Oct 31 00:48:14.211997 kernel: audit: type=1334 audit(1761871694.209:2): prog-id=6 op=LOAD Oct 31 00:48:14.212028 kernel: audit: type=1334 audit(1761871694.211:3): prog-id=7 op=LOAD Oct 31 00:48:14.212037 kernel: audit: type=1334 audit(1761871694.211:4): prog-id=8 op=LOAD Oct 31 00:48:14.211000 audit: BPF prog-id=8 op=LOAD Oct 31 00:48:14.212371 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 31 00:48:14.230965 systemd-udevd[416]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 31 00:48:14.234831 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 31 00:48:14.237088 systemd[1]: Starting dracut pre-trigger hook... Oct 31 00:48:14.238000 audit: BPF prog-id=9 op=LOAD Oct 31 00:48:14.239837 systemd[1]: Starting Network Configuration... Oct 31 00:48:14.242938 kernel: audit: type=1334 audit(1761871694.238:5): prog-id=9 op=LOAD Oct 31 00:48:14.255566 dracut-pre-trigger[424]: rd.md=0: removing MD RAID activation Oct 31 00:48:14.260142 systemd-networkd[426]: lo: Link UP Oct 31 00:48:14.260149 systemd-networkd[426]: lo: Gained carrier Oct 31 00:48:14.260459 systemd-networkd[426]: Enumeration completed Oct 31 00:48:14.261304 systemd[1]: Started Network Configuration. Oct 31 00:48:14.263635 systemd[1]: Starting Network Name Resolution... Oct 31 00:48:14.266476 kernel: audit: type=1334 audit(1761871694.262:6): prog-id=10 op=LOAD Oct 31 00:48:14.262000 audit: BPF prog-id=10 op=LOAD Oct 31 00:48:14.292906 systemd-resolved[446]: Positive Trust Anchors: Oct 31 00:48:14.292924 systemd-resolved[446]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 00:48:14.292953 systemd-resolved[446]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 31 00:48:14.293267 systemd-resolved[446]: Defaulting to hostname 'linux'. Oct 31 00:48:14.293755 systemd[1]: Finished dracut pre-trigger hook. Oct 31 00:48:14.294920 systemd[1]: Started Network Name Resolution. Oct 31 00:48:14.297033 systemd[1]: Reached target Network. Oct 31 00:48:14.303371 systemd[1]: Reached target Host and Network Name Lookups. Oct 31 00:48:14.305943 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 31 00:48:14.307531 systemd[1]: Starting Coldplug All udev Devices... Oct 31 00:48:14.312360 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 31 00:48:14.314084 systemd[1]: Starting Open-iSCSI... Oct 31 00:48:14.318071 systemd-udevd[416]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 31 00:48:14.319673 iscsid[467]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 31 00:48:14.319673 iscsid[467]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 31 00:48:14.319673 iscsid[467]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 31 00:48:14.319673 iscsid[467]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 31 00:48:14.319673 iscsid[467]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 31 00:48:14.319673 iscsid[467]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 31 00:48:14.323115 systemd[1]: Started Open-iSCSI. Oct 31 00:48:14.348238 systemd[1]: Finished Coldplug All udev Devices. Oct 31 00:48:14.349998 systemd[1]: Starting dracut initqueue hook... Oct 31 00:48:14.368209 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 31 00:48:14.368337 kernel: vda: detected capacity change from 0 to 4756340736 Oct 31 00:48:14.381226 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 00:48:14.384920 systemd-udevd[471]: Using default interface naming scheme 'v249'. Oct 31 00:48:14.391217 systemd-networkd[426]: eth0: Link UP Oct 31 00:48:14.402461 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 31 00:48:14.405914 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 31 00:48:14.407295 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (471) Oct 31 00:48:14.410243 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 31 00:48:14.413120 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 31 00:48:14.417374 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 31 00:48:14.418594 systemd[1]: Finished dracut initqueue hook. Oct 31 00:48:14.420069 systemd[1]: Reached target Initrd Root Device. Oct 31 00:48:14.421587 systemd[1]: Reached target Preparation for Remote File Systems. Oct 31 00:48:14.423389 systemd[1]: Reached target Remote Encrypted Volumes. Oct 31 00:48:14.425106 systemd[1]: Reached target Remote File Systems. Oct 31 00:48:14.427569 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 31 00:48:14.429265 systemd[1]: Starting dracut pre-mount hook... Oct 31 00:48:14.437175 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 00:48:14.438445 systemd[1]: Finished dracut pre-mount hook. Oct 31 00:48:15.434210 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 31 00:48:15.434175 systemd-networkd[426]: eth0: Gained carrier Oct 31 00:48:15.440223 systemd-networkd[426]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 31 00:48:15.444646 disk-uuid[508]: The operation has completed successfully. Oct 31 00:48:15.446591 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 00:48:15.468778 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 31 00:48:15.470060 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 31 00:48:15.472896 systemd[1]: Starting Ignition (setup)... Oct 31 00:48:15.481178 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 31 00:48:15.481211 kernel: BTRFS info (device vda6): has skinny extents Oct 31 00:48:15.487254 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 31 00:48:15.492473 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 31 00:48:15.492592 systemd[1]: Finished Ignition (setup). Oct 31 00:48:15.494816 systemd[1]: Starting Ignition (disks)... Oct 31 00:48:15.507778 ignition[543]: Ignition v0.36.1-15-gde4e6cc9 Oct 31 00:48:15.507794 ignition[543]: Stage: disks Oct 31 00:48:15.507805 ignition[543]: reading system config file "/usr/lib/ignition/base.ign" Oct 31 00:48:15.507815 ignition[543]: no config at "/usr/lib/ignition/base.ign" Oct 31 00:48:15.507859 ignition[543]: parsed url from cmdline: "" Oct 31 00:48:15.507863 ignition[543]: no config URL provided Oct 31 00:48:15.507867 ignition[543]: reading system config file "/usr/lib/ignition/user.ign" Oct 31 00:48:15.507874 ignition[543]: no config at "/usr/lib/ignition/user.ign" Oct 31 00:48:15.507894 ignition[543]: op(1): [started] loading QEMU firmware config module Oct 31 00:48:15.507899 ignition[543]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 31 00:48:15.509030 ignition[543]: op(1): [finished] loading QEMU firmware config module Oct 31 00:48:15.525239 ignition[543]: parsing config with SHA512: 6b0a9b8aea2519c17801d3e49abc96407f55f89961e1bceadbc354774dc83d95c450ac8e088a8312b8562b167d98879398a6f9beaea8af2ea9bda766e5939965 Oct 31 00:48:15.527725 ignition[543]: disks: disks passed Oct 31 00:48:15.527741 ignition[543]: Ignition finished successfully Oct 31 00:48:15.528425 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 31 00:48:15.528542 systemd[1]: Finished Ignition (disks). Oct 31 00:48:15.530030 systemd[1]: Reached target Preparation for Local File Systems. Oct 31 00:48:15.532493 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 31 00:48:15.534860 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 31 00:48:15.546177 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 31 00:48:15.546911 systemd-fsck[558]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 31 00:48:15.551612 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 31 00:48:15.553509 systemd[1]: Mounting /sysroot... Oct 31 00:48:15.568952 systemd[1]: Found device /dev/mapper/usr. Oct 31 00:48:15.571138 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 31 00:48:15.573368 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 31 00:48:15.581258 systemd-fsck[570]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 31 00:48:15.581258 systemd-fsck[570]: You must have r/w access to the filesystem or be root Oct 31 00:48:15.585298 systemd-fsck[567]: fsck failed with exit status 8. Oct 31 00:48:15.585309 systemd-fsck[567]: Ignoring error. Oct 31 00:48:15.586088 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 31 00:48:15.588244 systemd[1]: Mounting /sysusr/usr... Oct 31 00:48:15.601540 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 31 00:48:15.601586 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 31 00:48:15.602138 systemd[1]: Mounted /sysroot. Oct 31 00:48:15.603527 systemd[1]: Reached target Initrd Root File System. Oct 31 00:48:15.619188 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 31 00:48:15.619427 systemd[1]: Mounted /sysusr/usr. Oct 31 00:48:15.620179 systemd[1]: Reached target Local File Systems. Oct 31 00:48:15.621615 systemd[1]: Reached target System Initialization. Oct 31 00:48:15.623066 systemd[1]: Reached target Basic System. Oct 31 00:48:15.625747 systemd[1]: Mounting /sysroot/usr... Oct 31 00:48:15.628089 systemd[1]: Mounted /sysroot/usr. Oct 31 00:48:15.629642 systemd[1]: Starting Root filesystem setup... Oct 31 00:48:15.659297 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 31 00:48:15.659446 systemd[1]: Finished Root filesystem setup. Oct 31 00:48:15.661924 systemd[1]: Starting Ignition (files)... Oct 31 00:48:15.663331 systemd[1]: Starting /sysroot/boot... Oct 31 00:48:15.665573 ignition[587]: Ignition v0.36.1-15-gde4e6cc9 Oct 31 00:48:15.665590 ignition[587]: Stage: files Oct 31 00:48:15.665601 ignition[587]: reading system config file "/usr/lib/ignition/base.ign" Oct 31 00:48:15.665612 ignition[587]: no config at "/usr/lib/ignition/base.ign" Oct 31 00:48:15.666130 ignition[587]: files: compiled without relabeling support, skipping Oct 31 00:48:15.679923 systemd[1]: Finished /sysroot/boot. Oct 31 00:48:15.684745 ignition[587]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 31 00:48:15.684759 ignition[587]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 31 00:48:15.686133 ignition[587]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 31 00:48:15.686140 ignition[587]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 31 00:48:15.687384 ignition[587]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 31 00:48:15.688790 systemd[1]: ignition-files.service: Deactivated successfully. Oct 31 00:48:15.687409 ignition[587]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 31 00:48:15.688898 systemd[1]: Finished Ignition (files). Oct 31 00:48:15.687672 ignition[587]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 31 00:48:15.689915 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 31 00:48:15.687679 ignition[587]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 31 00:48:15.690569 systemd[1]: Starting Ignition (record completion)... Oct 31 00:48:15.702000 audit: BPF prog-id=3 op=UNLOAD Oct 31 00:48:15.687782 ignition[587]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 31 00:48:15.705257 kernel: audit: type=1334 audit(1761871695.702:7): prog-id=3 op=UNLOAD Oct 31 00:48:15.692564 systemd[1]: Starting Reload Configuration from the Real Root... Oct 31 00:48:15.688214 ignition[587]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 31 00:48:15.696986 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 31 00:48:15.711064 kernel: audit: type=1334 audit(1761871695.708:8): prog-id=9 op=UNLOAD Oct 31 00:48:15.711083 kernel: audit: type=1334 audit(1761871695.708:9): prog-id=10 op=UNLOAD Oct 31 00:48:15.711097 kernel: audit: type=1334 audit(1761871695.709:10): prog-id=6 op=UNLOAD Oct 31 00:48:15.708000 audit: BPF prog-id=9 op=UNLOAD Oct 31 00:48:15.708000 audit: BPF prog-id=10 op=UNLOAD Oct 31 00:48:15.709000 audit: BPF prog-id=6 op=UNLOAD Oct 31 00:48:15.688221 ignition[587]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 31 00:48:15.697103 systemd[1]: Finished Ignition (record completion). Oct 31 00:48:15.688226 ignition[587]: files: files passed Oct 31 00:48:15.699046 systemd[1]: Reloading. Oct 31 00:48:15.688230 ignition[587]: Ignition finished successfully Oct 31 00:48:15.792000 audit: BPF prog-id=11 op=LOAD Oct 31 00:48:15.792000 audit: BPF prog-id=12 op=LOAD Oct 31 00:48:15.792000 audit: BPF prog-id=13 op=LOAD Oct 31 00:48:15.792000 audit: BPF prog-id=4 op=UNLOAD Oct 31 00:48:15.792000 audit: BPF prog-id=5 op=UNLOAD Oct 31 00:48:15.793000 audit: BPF prog-id=14 op=LOAD Oct 31 00:48:15.793000 audit: BPF prog-id=15 op=LOAD Oct 31 00:48:15.793000 audit: BPF prog-id=16 op=LOAD Oct 31 00:48:15.793000 audit: BPF prog-id=17 op=LOAD Oct 31 00:48:15.793000 audit: BPF prog-id=18 op=LOAD Oct 31 00:48:15.793000 audit: BPF prog-id=7 op=UNLOAD Oct 31 00:48:15.793000 audit: BPF prog-id=8 op=UNLOAD Oct 31 00:48:15.808548 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 31 00:48:15.808691 systemd[1]: Finished Reload Configuration from the Real Root. Oct 31 00:48:15.810764 systemd[1]: Reached target Initrd File Systems. Oct 31 00:48:15.812437 systemd[1]: Reached target Initrd Default Target. Oct 31 00:48:15.814104 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 31 00:48:15.814821 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 31 00:48:15.825224 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 31 00:48:15.826936 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 31 00:48:15.833222 systemd[1]: Stopped target Host and Network Name Lookups. Oct 31 00:48:15.834369 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 31 00:48:15.836196 systemd[1]: Stopped target Timer Units. Oct 31 00:48:15.837781 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 31 00:48:15.837906 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 31 00:48:15.839363 systemd[1]: Stopped target Initrd Default Target. Oct 31 00:48:15.841116 systemd[1]: Stopped target Basic System. Oct 31 00:48:15.842689 systemd[1]: Stopped target Initrd Root Device. Oct 31 00:48:15.844114 systemd[1]: Stopped target Path Units. Oct 31 00:48:15.845441 systemd[1]: Stopped target Remote File Systems. Oct 31 00:48:15.846981 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 31 00:48:15.848549 systemd[1]: Stopped target Slice Units. Oct 31 00:48:15.849958 systemd[1]: Stopped target Socket Units. Oct 31 00:48:15.851677 systemd[1]: Stopped target System Initialization. Oct 31 00:48:15.853149 systemd[1]: Stopped target Local File Systems. Oct 31 00:48:15.854643 systemd[1]: Stopped target Preparation for Local File Systems. Oct 31 00:48:15.856358 systemd[1]: Stopped target Swaps. Oct 31 00:48:15.857750 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 31 00:48:15.857868 systemd[1]: Stopped dracut pre-mount hook. Oct 31 00:48:15.859458 systemd[1]: Stopped target Local Encrypted Volumes. Oct 31 00:48:15.860851 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 31 00:48:15.864192 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 31 00:48:15.865708 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 31 00:48:15.865822 systemd[1]: Stopped dracut initqueue hook. Oct 31 00:48:15.869627 iscsid[467]: iscsid shutting down. Oct 31 00:48:15.868323 systemd[1]: Stopping Open-iSCSI... Oct 31 00:48:15.869671 systemd[1]: Stopping /sysroot/boot... Oct 31 00:48:15.870328 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 31 00:48:15.870467 systemd[1]: Stopped Coldplug All udev Devices. Oct 31 00:48:15.871766 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 31 00:48:15.871850 systemd[1]: Stopped dracut pre-trigger hook. Oct 31 00:48:15.874006 systemd[1]: iscsid.service: Deactivated successfully. Oct 31 00:48:15.874129 systemd[1]: Stopped Open-iSCSI. Oct 31 00:48:15.876058 systemd[1]: iscsid.socket: Deactivated successfully. Oct 31 00:48:15.876119 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 31 00:48:15.877368 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 31 00:48:15.880738 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 31 00:48:15.880852 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 31 00:48:15.882331 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 31 00:48:15.882450 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 31 00:48:15.884100 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 31 00:48:15.884211 systemd[1]: Stopped /sysroot/boot. Oct 31 00:48:15.896000 audit: BPF prog-id=15 op=UNLOAD Oct 31 00:48:15.885931 systemd[1]: Stopped target Network. Oct 31 00:48:15.886739 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 31 00:48:15.886809 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 31 00:48:15.888240 systemd[1]: Stopping Network Name Resolution... Oct 31 00:48:15.891696 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 31 00:48:15.891824 systemd[1]: Stopped Network Name Resolution. Oct 31 00:48:15.893453 systemd[1]: Stopping Network Configuration... Oct 31 00:48:15.895282 systemd-networkd[426]: eth0: DHCP lease lost Oct 31 00:48:15.902201 systemd-networkd[426]: eth0: DHCPv6 lease lost Oct 31 00:48:15.903284 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 31 00:48:15.903404 systemd[1]: Stopped Network Configuration. Oct 31 00:48:15.904848 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 31 00:48:15.904880 systemd[1]: Closed Network Service Netlink Socket. Oct 31 00:48:15.907024 systemd[1]: Stopping Network Cleanup... Oct 31 00:48:15.913000 audit: BPF prog-id=14 op=UNLOAD Oct 31 00:48:15.907726 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 31 00:48:15.907805 systemd[1]: Stopped Apply Kernel Variables. Oct 31 00:48:15.909484 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 31 00:48:15.915442 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 31 00:48:15.915558 systemd[1]: Stopped Network Cleanup. Oct 31 00:48:15.918380 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 31 00:48:15.918498 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 31 00:48:15.920473 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 31 00:48:15.925000 audit: BPF prog-id=16 op=UNLOAD Oct 31 00:48:15.920511 systemd[1]: Closed udev Control Socket. Oct 31 00:48:15.921628 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 31 00:48:15.921663 systemd[1]: Closed udev Kernel Socket. Oct 31 00:48:15.923179 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 31 00:48:15.923228 systemd[1]: Stopped dracut pre-udev hook. Oct 31 00:48:15.924874 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 31 00:48:15.924918 systemd[1]: Stopped dracut cmdline hook. Oct 31 00:48:15.926544 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 31 00:48:15.926591 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 31 00:48:15.928823 systemd[1]: Starting Cleanup udev Database... Oct 31 00:48:15.929989 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 31 00:48:15.931553 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 31 00:48:15.931615 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 31 00:48:15.933302 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 31 00:48:15.933348 systemd[1]: Stopped Create List of Static Device Nodes. Oct 31 00:48:15.934841 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 00:48:15.934884 systemd[1]: Stopped Setup Virtual Console. Oct 31 00:48:15.936693 systemd[1]: rngd.service: Deactivated successfully. Oct 31 00:48:15.949000 audit: BPF prog-id=11 op=UNLOAD Oct 31 00:48:15.936820 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 31 00:48:15.938277 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 31 00:48:15.938381 systemd[1]: Finished Cleanup udev Database. Oct 31 00:48:15.939934 systemd[1]: Reached target Switch Root. Oct 31 00:48:15.942142 systemd[1]: Starting Switch Root... Oct 31 00:48:15.947276 systemd[1]: Switching root. Oct 31 00:48:15.959432 systemd-journald[278]: Journal stopped Oct 31 00:48:18.149630 systemd-journald[278]: Received SIGTERM from PID 1 (systemd). Oct 31 00:48:18.149684 kernel: SELinux: policy capability network_peer_controls=1 Oct 31 00:48:18.149707 kernel: SELinux: policy capability open_perms=1 Oct 31 00:48:18.149719 kernel: SELinux: policy capability extended_socket_class=1 Oct 31 00:48:18.149729 kernel: SELinux: policy capability always_check_network=0 Oct 31 00:48:18.149739 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 31 00:48:18.149749 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 31 00:48:18.149766 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 31 00:48:18.149776 systemd[1]: Successfully loaded SELinux policy in 31.244ms. Oct 31 00:48:18.149793 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.701ms. Oct 31 00:48:18.149805 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 31 00:48:18.149816 systemd[1]: Detected virtualization kvm. Oct 31 00:48:18.149827 systemd[1]: Detected architecture arm64. Oct 31 00:48:18.149837 systemd[1]: Detected first boot. Oct 31 00:48:18.149848 systemd[1]: Initializing machine ID from VM UUID. Oct 31 00:48:18.149858 systemd[1]: Populated /etc with preset unit settings. Oct 31 00:48:18.149872 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 31 00:48:18.149884 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 31 00:48:18.149894 kernel: kauditd_printk_skb: 24 callbacks suppressed Oct 31 00:48:18.149905 kernel: audit: type=1334 audit(1761871698.011:35): prog-id=21 op=LOAD Oct 31 00:48:18.149915 kernel: audit: type=1334 audit(1761871698.012:36): prog-id=22 op=LOAD Oct 31 00:48:18.149924 kernel: audit: type=1334 audit(1761871698.012:37): prog-id=23 op=LOAD Oct 31 00:48:18.149933 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 31 00:48:18.149944 kernel: audit: type=1334 audit(1761871698.012:38): prog-id=12 op=UNLOAD Oct 31 00:48:18.149955 systemd[1]: Stopped Switch Root. Oct 31 00:48:18.149966 kernel: audit: type=1334 audit(1761871698.012:39): prog-id=13 op=UNLOAD Oct 31 00:48:18.149976 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 31 00:48:18.149987 systemd[1]: Created slice Slice /system/addon-config. Oct 31 00:48:18.149998 systemd[1]: Created slice Slice /system/addon-run. Oct 31 00:48:18.150008 systemd[1]: Created slice Slice /system/getty. Oct 31 00:48:18.150018 kernel: audit: type=1334 audit(1761871698.026:40): prog-id=21 op=UNLOAD Oct 31 00:48:18.150028 systemd[1]: Created slice Slice /system/modprobe. Oct 31 00:48:18.150039 systemd[1]: Created slice Slice /system/serial-getty. Oct 31 00:48:18.150050 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 31 00:48:18.150060 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 31 00:48:18.150071 systemd[1]: Created slice User and Session Slice. Oct 31 00:48:18.150081 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 31 00:48:18.150092 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 31 00:48:18.150102 systemd[1]: Set up automount Boot partition Automount Point. Oct 31 00:48:18.150112 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 31 00:48:18.150123 systemd[1]: Stopped target Switch Root. Oct 31 00:48:18.150133 systemd[1]: Stopped target Initrd File Systems. Oct 31 00:48:18.150143 systemd[1]: Stopped target Initrd Root File System. Oct 31 00:48:18.150168 systemd[1]: Reached target Remote Encrypted Volumes. Oct 31 00:48:18.150183 systemd[1]: Reached target Remote File Systems. Oct 31 00:48:18.150193 systemd[1]: Reached target Slice Units. Oct 31 00:48:18.150204 systemd[1]: Reached target Swaps. Oct 31 00:48:18.150213 systemd[1]: Reached target Verify torcx succeeded. Oct 31 00:48:18.150223 systemd[1]: Reached target Local Verity Protected Volumes. Oct 31 00:48:18.150234 systemd[1]: Listening on Process Core Dump Socket. Oct 31 00:48:18.150244 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 31 00:48:18.150255 systemd[1]: Listening on Network Service Netlink Socket. Oct 31 00:48:18.150265 systemd[1]: Listening on udev Control Socket. Oct 31 00:48:18.150278 systemd[1]: Listening on udev Kernel Socket. Oct 31 00:48:18.150289 systemd[1]: Mounting Huge Pages File System... Oct 31 00:48:18.150299 systemd[1]: Mounting POSIX Message Queue File System... Oct 31 00:48:18.150310 systemd[1]: Mounting External Media Directory... Oct 31 00:48:18.150321 systemd[1]: Mounting Kernel Debug File System... Oct 31 00:48:18.150331 systemd[1]: Mounting Kernel Trace File System... Oct 31 00:48:18.150341 systemd[1]: Mounting Temporary Directory /tmp... Oct 31 00:48:18.150351 systemd[1]: Starting Create missing system files... Oct 31 00:48:18.150361 systemd[1]: Starting Create List of Static Device Nodes... Oct 31 00:48:18.150372 systemd[1]: Starting Load Kernel Module configfs... Oct 31 00:48:18.150383 systemd[1]: Starting Load Kernel Module drm... Oct 31 00:48:18.150394 systemd[1]: Starting Load Kernel Module fuse... Oct 31 00:48:18.150404 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 31 00:48:18.150414 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 31 00:48:18.150425 systemd[1]: Stopped File System Check on Root Device. Oct 31 00:48:18.150436 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 31 00:48:18.150447 systemd[1]: Stopped systemd-fsck-usr.service. Oct 31 00:48:18.150458 kernel: fuse: init (API version 7.32) Oct 31 00:48:18.150470 systemd[1]: Stopped Journal Service. Oct 31 00:48:18.150480 kernel: audit: type=1334 audit(1761871698.134:41): prog-id=24 op=LOAD Oct 31 00:48:18.150490 kernel: audit: type=1334 audit(1761871698.135:42): prog-id=25 op=LOAD Oct 31 00:48:18.150500 kernel: audit: type=1334 audit(1761871698.136:43): prog-id=26 op=LOAD Oct 31 00:48:18.150510 systemd[1]: Starting Journal Service... Oct 31 00:48:18.150521 kernel: audit: type=1334 audit(1761871698.136:44): prog-id=22 op=UNLOAD Oct 31 00:48:18.150531 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 31 00:48:18.150541 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 31 00:48:18.150553 systemd[1]: Starting Apply Kernel Variables... Oct 31 00:48:18.150563 systemd[1]: Starting Coldplug All udev Devices... Oct 31 00:48:18.150573 systemd[1]: verity-setup.service: Deactivated successfully. Oct 31 00:48:18.150585 systemd-journald[803]: Journal started Oct 31 00:48:18.150622 systemd-journald[803]: Runtime Journal (/run/log/journal/e6bb737c0f5e4438bdeb343d8ca8882b) is 6.0M, max 48.5M, 42.4M free. Oct 31 00:48:16.023000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 31 00:48:16.054000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 31 00:48:16.054000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 31 00:48:16.054000 audit: BPF prog-id=19 op=LOAD Oct 31 00:48:16.054000 audit: BPF prog-id=19 op=UNLOAD Oct 31 00:48:16.054000 audit: BPF prog-id=20 op=LOAD Oct 31 00:48:16.054000 audit: BPF prog-id=20 op=UNLOAD Oct 31 00:48:16.091000 audit[752]: AVC avc: denied { associate } for pid=752 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 31 00:48:18.011000 audit: BPF prog-id=21 op=LOAD Oct 31 00:48:18.012000 audit: BPF prog-id=22 op=LOAD Oct 31 00:48:18.012000 audit: BPF prog-id=23 op=LOAD Oct 31 00:48:18.012000 audit: BPF prog-id=12 op=UNLOAD Oct 31 00:48:18.012000 audit: BPF prog-id=13 op=UNLOAD Oct 31 00:48:18.026000 audit: BPF prog-id=21 op=UNLOAD Oct 31 00:48:18.134000 audit: BPF prog-id=24 op=LOAD Oct 31 00:48:18.135000 audit: BPF prog-id=25 op=LOAD Oct 31 00:48:18.136000 audit: BPF prog-id=26 op=LOAD Oct 31 00:48:18.136000 audit: BPF prog-id=22 op=UNLOAD Oct 31 00:48:18.136000 audit: BPF prog-id=23 op=UNLOAD Oct 31 00:48:18.147000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 31 00:48:17.997838 systemd[1]: Queued start job for default target Multi-User System. Oct 31 00:48:16.089968 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 31 00:48:18.013595 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 31 00:48:16.090260 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 31 00:48:16.090279 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 31 00:48:16.090429 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 31 00:48:16.090441 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 31 00:48:16.090473 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 31 00:48:16.090486 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 31 00:48:16.090683 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 31 00:48:18.151877 systemd[1]: Stopped verity-setup.service. Oct 31 00:48:16.090733 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 31 00:48:16.090748 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 31 00:48:16.091124 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 31 00:48:16.091168 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 31 00:48:16.091194 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 31 00:48:16.091210 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 31 00:48:16.091228 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 31 00:48:16.091242 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:16Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 31 00:48:17.819506 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:17Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 00:48:17.819794 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:17Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 00:48:17.819899 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:17Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 00:48:17.820024 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:17Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 00:48:17.820078 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:17Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 31 00:48:17.820134 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-31T00:48:17Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 31 00:48:18.155589 systemd[1]: Started Journal Service. Oct 31 00:48:18.156137 systemd[1]: Mounted Huge Pages File System. Oct 31 00:48:18.157131 systemd[1]: Mounted POSIX Message Queue File System. Oct 31 00:48:18.158269 systemd[1]: Mounted External Media Directory. Oct 31 00:48:18.159268 systemd[1]: Mounted Kernel Debug File System. Oct 31 00:48:18.160239 systemd[1]: Mounted Kernel Trace File System. Oct 31 00:48:18.161188 systemd[1]: Mounted Temporary Directory /tmp. Oct 31 00:48:18.162432 systemd[1]: Finished Create missing system files. Oct 31 00:48:18.163779 systemd[1]: Finished Create List of Static Device Nodes. Oct 31 00:48:18.165044 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 31 00:48:18.165261 systemd[1]: Finished Load Kernel Module configfs. Oct 31 00:48:18.166382 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 00:48:18.166591 systemd[1]: Finished Load Kernel Module drm. Oct 31 00:48:18.167796 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 31 00:48:18.167984 systemd[1]: Finished Load Kernel Module fuse. Oct 31 00:48:18.169302 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 31 00:48:18.178651 systemd[1]: Finished Apply Kernel Variables. Oct 31 00:48:18.181834 systemd[1]: Mounting FUSE Control File System... Oct 31 00:48:18.183788 systemd[1]: Mounting Kernel Configuration File System... Oct 31 00:48:18.184748 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 31 00:48:18.186270 systemd[1]: Starting Rebuild Hardware Database... Oct 31 00:48:18.188329 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 31 00:48:18.189396 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 31 00:48:18.190480 systemd[1]: Starting Load/Save Random Seed... Oct 31 00:48:18.192440 systemd-journald[803]: Time spent on flushing to /var/log/journal/e6bb737c0f5e4438bdeb343d8ca8882b is 8.154ms for 772 entries. Oct 31 00:48:18.192440 systemd-journald[803]: System Journal (/var/log/journal/e6bb737c0f5e4438bdeb343d8ca8882b) is 8.0M, max 203.0M, 195.0M free. Oct 31 00:48:18.192446 systemd[1]: Starting Create System Users... Oct 31 00:48:18.196098 systemd[1]: Mounted FUSE Control File System. Oct 31 00:48:18.197268 systemd[1]: Mounted Kernel Configuration File System. Oct 31 00:48:18.198637 systemd[1]: Finished Load/Save Random Seed. Oct 31 00:48:18.199917 systemd[1]: Reached target First Boot Complete. Oct 31 00:48:18.202946 systemd-sysusers[815]: Creating group sgx with gid 999. Oct 31 00:48:18.203912 systemd-sysusers[815]: Creating group systemd-oom with gid 998. Oct 31 00:48:18.211378 systemd[1]: Finished Coldplug All udev Devices. Oct 31 00:48:18.212828 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 31 00:48:18.215093 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 31 00:48:18.216728 systemd-sysusers[815]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 31 00:48:18.217433 systemd-sysusers[815]: Creating group systemd-timesync with gid 997. Oct 31 00:48:18.217954 systemd-sysusers[815]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 31 00:48:18.218640 systemd-sysusers[815]: Creating group systemd-coredump with gid 996. Oct 31 00:48:18.219200 systemd-sysusers[815]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 31 00:48:18.228195 systemd[1]: Finished Create System Users. Oct 31 00:48:18.230257 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 31 00:48:18.244277 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 31 00:48:18.438148 systemd[1]: Finished Rebuild Hardware Database. Oct 31 00:48:18.444000 audit: BPF prog-id=27 op=LOAD Oct 31 00:48:18.444000 audit: BPF prog-id=28 op=LOAD Oct 31 00:48:18.444000 audit: BPF prog-id=29 op=LOAD Oct 31 00:48:18.444000 audit: BPF prog-id=17 op=UNLOAD Oct 31 00:48:18.444000 audit: BPF prog-id=18 op=UNLOAD Oct 31 00:48:18.445643 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 31 00:48:18.469505 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 31 00:48:18.476000 audit: BPF prog-id=30 op=LOAD Oct 31 00:48:18.477644 systemd[1]: Starting Network Configuration... Oct 31 00:48:18.504664 systemd-networkd[840]: lo: Link UP Oct 31 00:48:18.504674 systemd-networkd[840]: lo: Gained carrier Oct 31 00:48:18.504986 systemd-networkd[840]: Enumeration completed Oct 31 00:48:18.505070 systemd[1]: Started Network Configuration. Oct 31 00:48:18.529255 systemd-udevd[837]: Using default interface naming scheme 'v249'. Oct 31 00:48:18.545525 systemd-networkd[840]: eth0: Link UP Oct 31 00:48:18.552222 systemd-networkd[840]: eth0: Gained carrier Oct 31 00:48:18.557292 systemd-networkd[840]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 31 00:48:18.582360 udevadm[818]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 31 00:48:18.608453 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 31 00:48:18.619307 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 31 00:48:18.627625 lvm[848]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 00:48:18.654284 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 31 00:48:18.655419 systemd[1]: Reached target Local Encrypted Volumes. Oct 31 00:48:18.669347 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 31 00:48:18.672935 lvm[849]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 00:48:18.701327 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 31 00:48:18.702470 systemd[1]: Reached target Preparation for Local File Systems. Oct 31 00:48:18.704055 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 31 00:48:18.704083 systemd[1]: Reached target Containers. Oct 31 00:48:18.718472 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 31 00:48:18.732714 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 31 00:48:18.735334 systemd[1]: Mounting /usr/share/oem... Oct 31 00:48:18.742486 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 31 00:48:18.742559 kernel: BTRFS info (device vda6): has skinny extents Oct 31 00:48:18.745277 systemd[1]: Mounted /usr/share/oem. Oct 31 00:48:18.746145 systemd[1]: Reached target Local File Systems. Oct 31 00:48:18.757119 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 31 00:48:18.758598 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 31 00:48:18.758653 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 31 00:48:18.759660 systemd[1]: Starting Commit a transient machine-id on disk... Oct 31 00:48:18.761971 systemd[1]: Starting Create Volatile Files and Directories... Oct 31 00:48:18.772837 systemd-tmpfiles[874]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 31 00:48:18.774284 systemd-tmpfiles[874]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 31 00:48:18.774617 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 31 00:48:18.775489 systemd[1]: Finished Commit a transient machine-id on disk. Oct 31 00:48:18.827614 systemd-tmpfiles[874]: Detected autofs mount point /boot during canonicalization of /boot. Oct 31 00:48:18.827623 systemd-tmpfiles[874]: Skipping /boot Oct 31 00:48:18.835338 systemd-tmpfiles[874]: Detected autofs mount point /boot during canonicalization of /boot. Oct 31 00:48:18.835351 systemd-tmpfiles[874]: Skipping /boot Oct 31 00:48:18.838133 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 31 00:48:18.869438 systemd[1]: Finished Create Volatile Files and Directories. Oct 31 00:48:18.882292 systemd[1]: Starting Load Security Auditing Rules... Oct 31 00:48:18.884609 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 31 00:48:18.887237 systemd[1]: Starting Rebuild Journal Catalog... Oct 31 00:48:18.890000 audit: BPF prog-id=31 op=LOAD Oct 31 00:48:18.891964 systemd[1]: Starting Network Name Resolution... Oct 31 00:48:18.893000 audit: BPF prog-id=32 op=LOAD Oct 31 00:48:18.894583 systemd[1]: Starting Network Time Synchronization... Oct 31 00:48:18.896909 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 31 00:48:18.898868 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 31 00:48:18.900459 systemd[1]: Finished Rebuild Journal Catalog. Oct 31 00:48:18.904244 systemd[1]: Starting Update is Completed... Oct 31 00:48:18.905124 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 31 00:48:18.905880 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 31 00:48:18.909370 systemd[1]: Finished Update is Completed. Oct 31 00:48:18.912000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 31 00:48:18.912527 augenrules[896]: No rules Oct 31 00:48:18.913414 systemd[1]: Finished Load Security Auditing Rules. Oct 31 00:48:18.940185 systemd[1]: Started Network Time Synchronization. Oct 31 00:48:18.940897 systemd-timesyncd[887]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 31 00:48:18.941670 systemd[1]: Reached target System Initialization. Oct 31 00:48:18.942085 systemd-resolved[885]: Positive Trust Anchors: Oct 31 00:48:18.942326 systemd-resolved[885]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 00:48:18.942408 systemd-resolved[885]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 31 00:48:18.943301 systemd[1]: Started Watch for update engine configuration changes. Oct 31 00:48:18.943503 systemd-resolved[885]: Defaulting to hostname 'linux'. Oct 31 00:48:18.944532 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 31 00:48:18.945986 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 31 00:48:18.947308 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 31 00:48:18.947384 systemd[1]: Reached target Path Units. Oct 31 00:48:18.948359 systemd[1]: Reached target System Time Set. Oct 31 00:48:18.949791 systemd[1]: Started Daily Log Rotation. Oct 31 00:48:18.950850 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 31 00:48:18.952277 systemd[1]: Reached target Timer Units. Oct 31 00:48:18.953588 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 31 00:48:18.963018 systemd[1]: Starting Docker Socket for the API... Oct 31 00:48:18.966629 systemd[1]: Listening on OpenSSH Server Socket. Oct 31 00:48:18.968091 systemd[1]: Started Network Name Resolution. Oct 31 00:48:18.969241 systemd[1]: Listening on Docker Socket for the API. Oct 31 00:48:18.970283 systemd[1]: Reached target Network. Oct 31 00:48:18.971103 systemd[1]: Reached target Host and Network Name Lookups. Oct 31 00:48:18.972276 systemd[1]: Reached target Socket Units. Oct 31 00:48:18.973145 systemd[1]: Reached target Basic System. Oct 31 00:48:18.974101 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 31 00:48:18.974135 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 31 00:48:18.975373 systemd[1]: Started D-Bus System Message Bus. Oct 31 00:48:18.978933 systemd[1]: Starting Extend Filesystems... Oct 31 00:48:18.980391 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 31 00:48:18.982038 systemd[1]: Starting Generate /run/flatcar/motd... Oct 31 00:48:18.984402 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 31 00:48:18.987022 systemd[1]: Starting Generate sshd host keys... Oct 31 00:48:18.988245 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 31 00:48:18.988282 systemd[1]: Reached target Load system-provided cloud configs. Oct 31 00:48:18.989781 extend-filesystems[905]: Found vda Oct 31 00:48:18.990862 extend-filesystems[905]: Found vda1 Oct 31 00:48:18.991896 extend-filesystems[905]: Found vda2 Oct 31 00:48:18.993012 extend-filesystems[905]: Found vda3 Oct 31 00:48:18.993857 extend-filesystems[905]: Found usr Oct 31 00:48:18.994665 systemd[1]: Starting User Login Management... Oct 31 00:48:18.994850 extend-filesystems[905]: Found vda4 Oct 31 00:48:19.009383 extend-filesystems[905]: Found vda6 Oct 31 00:48:19.009383 extend-filesystems[905]: Found vda7 Oct 31 00:48:19.009383 extend-filesystems[905]: Found vda9 Oct 31 00:48:19.009383 extend-filesystems[905]: Checking size of /dev/vda9 Oct 31 00:48:18.996594 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 31 00:48:18.997357 systemd[1]: Starting Update Engine... Oct 31 00:48:18.999392 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 31 00:48:18.999433 systemd[1]: Reached target Load user-provided cloud configs. Oct 31 00:48:19.001610 systemd[1]: motdgen.service: Deactivated successfully. Oct 31 00:48:19.001990 systemd[1]: Finished Generate /run/flatcar/motd. Oct 31 00:48:19.003820 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 31 00:48:19.004463 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 31 00:48:19.020587 extend-filesystems[905]: Old size kept for /dev/vda9 Oct 31 00:48:19.018993 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 31 00:48:19.019257 systemd[1]: Finished Extend Filesystems. Oct 31 00:48:19.036000 update_engine[921]: I1031 00:48:19.035558 921 main.cc:89] Flatcar Update Engine starting Oct 31 00:48:19.038458 systemd-logind[919]: Watching system buttons on /dev/input/event0 (Power Button) Oct 31 00:48:19.039151 systemd-logind[919]: New seat seat0. Oct 31 00:48:19.041236 systemd[1]: Started Update Engine. Oct 31 00:48:19.043280 update_engine[921]: I1031 00:48:19.043236 921 update_check_scheduler.cc:74] Next update check in 8m21s Oct 31 00:48:19.053607 systemd[1]: Started Cluster reboot manager. Oct 31 00:48:19.055189 systemd[1]: Started User Login Management. Oct 31 00:48:19.083675 locksmithd[928]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 31 00:48:19.373342 systemd[1]: Created slice Slice /system/sshd. Oct 31 00:48:20.027833 sshd_keygen[920]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 31 00:48:20.047477 systemd[1]: Finished Generate sshd host keys. Oct 31 00:48:20.059401 systemd[1]: Starting Generate /run/issue... Oct 31 00:48:20.061283 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:59908). Oct 31 00:48:20.063861 systemd[1]: issuegen.service: Deactivated successfully. Oct 31 00:48:20.064089 systemd[1]: Finished Generate /run/issue. Oct 31 00:48:20.066406 systemd[1]: Starting Permit User Sessions... Oct 31 00:48:20.071181 systemd[1]: Finished Permit User Sessions. Oct 31 00:48:20.073445 systemd[1]: Started Getty on tty1. Oct 31 00:48:20.075621 systemd[1]: Started Serial Getty on ttyAMA0. Oct 31 00:48:20.077133 systemd[1]: Reached target Login Prompts. Oct 31 00:48:20.078461 systemd[1]: Reached target Multi-User System. Oct 31 00:48:20.080605 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 31 00:48:20.086912 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 31 00:48:20.087157 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 31 00:48:20.088484 systemd[1]: Startup finished in 716ms (kernel) + 2.431s (initrd) + 4.098s (userspace) = 7.246s. Oct 31 00:48:20.106124 sshd[943]: Accepted publickey for core from 10.0.0.1 port 59908 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:20.108038 sshd[943]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:20.125709 systemd[1]: Created slice User Slice of UID 500. Oct 31 00:48:20.126804 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 31 00:48:20.128269 systemd-logind[919]: New session 1 of user core. Oct 31 00:48:20.133616 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 31 00:48:20.134904 systemd[1]: Starting User Manager for UID 500... Oct 31 00:48:20.137247 systemd[952]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:20.186670 systemd[952]: Queued start job for default target Main User Target. Oct 31 00:48:20.186844 systemd[952]: Reached target Paths. Oct 31 00:48:20.186861 systemd[952]: Reached target Sockets. Oct 31 00:48:20.186873 systemd[952]: Reached target Timers. Oct 31 00:48:20.186883 systemd[952]: Reached target Basic System. Oct 31 00:48:20.186927 systemd[952]: Reached target Main User Target. Oct 31 00:48:20.186941 systemd[952]: Startup finished in 45ms. Oct 31 00:48:20.187161 systemd[1]: Started User Manager for UID 500. Oct 31 00:48:20.196375 systemd[1]: Started Session 1 of User core. Oct 31 00:48:20.260010 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36658). Oct 31 00:48:20.297025 sshd[961]: Accepted publickey for core from 10.0.0.1 port 36658 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:20.297960 sshd[961]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:20.300881 systemd-logind[919]: New session 2 of user core. Oct 31 00:48:20.311373 systemd[1]: Started Session 2 of User core. Oct 31 00:48:20.367310 sshd[961]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:20.378897 systemd[1]: sshd@1-10.0.0.5:22-10.0.0.1:36658.service: Deactivated successfully. Oct 31 00:48:20.379566 systemd[1]: session-2.scope: Deactivated successfully. Oct 31 00:48:20.380123 systemd-logind[919]: Session 2 logged out. Waiting for processes to exit. Oct 31 00:48:20.381099 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36660). Oct 31 00:48:20.381821 systemd-logind[919]: Removed session 2. Oct 31 00:48:20.415037 sshd[967]: Accepted publickey for core from 10.0.0.1 port 36660 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:20.415993 sshd[967]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:20.418606 systemd-logind[919]: New session 3 of user core. Oct 31 00:48:20.432508 systemd[1]: Started Session 3 of User core. Oct 31 00:48:20.482186 sshd[967]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:20.491270 systemd[1]: sshd@2-10.0.0.5:22-10.0.0.1:36660.service: Deactivated successfully. Oct 31 00:48:20.492051 systemd[1]: session-3.scope: Deactivated successfully. Oct 31 00:48:20.492687 systemd-logind[919]: Session 3 logged out. Waiting for processes to exit. Oct 31 00:48:20.493722 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36668). Oct 31 00:48:20.494439 systemd-logind[919]: Removed session 3. Oct 31 00:48:20.527762 sshd[973]: Accepted publickey for core from 10.0.0.1 port 36668 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:20.528693 sshd[973]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:20.531279 systemd-logind[919]: New session 4 of user core. Oct 31 00:48:20.543367 systemd[1]: Started Session 4 of User core. Oct 31 00:48:20.596623 sshd[973]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:20.605986 systemd[1]: sshd@3-10.0.0.5:22-10.0.0.1:36668.service: Deactivated successfully. Oct 31 00:48:20.606774 systemd[1]: session-4.scope: Deactivated successfully. Oct 31 00:48:20.607391 systemd-logind[919]: Session 4 logged out. Waiting for processes to exit. Oct 31 00:48:20.608774 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36680). Oct 31 00:48:20.609523 systemd-logind[919]: Removed session 4. Oct 31 00:48:20.617532 systemd-networkd[840]: eth0: Gained IPv6LL Oct 31 00:48:20.643141 sshd[979]: Accepted publickey for core from 10.0.0.1 port 36680 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:20.644137 sshd[979]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:20.647095 systemd-logind[919]: New session 5 of user core. Oct 31 00:48:20.655394 systemd[1]: Started Session 5 of User core. Oct 31 00:48:20.711805 sudo[982]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 31 00:48:20.712022 sudo[982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 00:48:20.727715 sudo[982]: pam_unix(sudo:session): session closed for user root Oct 31 00:48:20.730880 sshd[979]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:20.743811 dbus-daemon[904]: [system] Reloaded configuration Oct 31 00:48:20.744301 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36692). Oct 31 00:48:20.747542 systemd[1]: sshd@4-10.0.0.5:22-10.0.0.1:36680.service: Deactivated successfully. Oct 31 00:48:20.748357 systemd[1]: session-5.scope: Deactivated successfully. Oct 31 00:48:20.750219 systemd-logind[919]: Session 5 logged out. Waiting for processes to exit. Oct 31 00:48:20.750965 systemd-logind[919]: Removed session 5. Oct 31 00:48:20.778615 sshd[985]: Accepted publickey for core from 10.0.0.1 port 36692 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:20.779648 sshd[985]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:20.782309 systemd-logind[919]: New session 6 of user core. Oct 31 00:48:20.792379 systemd[1]: Started Session 6 of User core. Oct 31 00:48:20.845877 sudo[990]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 31 00:48:20.846090 sudo[990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 00:48:20.848961 sudo[990]: pam_unix(sudo:session): session closed for user root Oct 31 00:48:20.853495 sudo[989]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 31 00:48:20.853729 sudo[989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 00:48:20.870272 systemd[1]: Stopping Load Security Auditing Rules... Oct 31 00:48:20.870000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 31 00:48:20.871524 auditctl[993]: No rules Oct 31 00:48:20.871831 systemd[1]: audit-rules.service: Deactivated successfully. Oct 31 00:48:20.872083 systemd[1]: Stopped Load Security Auditing Rules. Oct 31 00:48:20.873475 systemd[1]: Starting Load Security Auditing Rules... Oct 31 00:48:20.888577 augenrules[1010]: No rules Oct 31 00:48:20.889619 systemd[1]: Finished Load Security Auditing Rules. Oct 31 00:48:20.890525 sudo[989]: pam_unix(sudo:session): session closed for user root Oct 31 00:48:20.891732 sshd[985]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:20.895079 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36696). Oct 31 00:48:20.895546 systemd[1]: sshd@5-10.0.0.5:22-10.0.0.1:36692.service: Deactivated successfully. Oct 31 00:48:20.896373 systemd-logind[919]: Session 6 logged out. Waiting for processes to exit. Oct 31 00:48:20.896430 systemd[1]: session-6.scope: Deactivated successfully. Oct 31 00:48:20.897099 systemd-logind[919]: Removed session 6. Oct 31 00:48:20.929288 sshd[1015]: Accepted publickey for core from 10.0.0.1 port 36696 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:20.930410 sshd[1015]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:20.933254 systemd-logind[919]: New session 7 of user core. Oct 31 00:48:20.944381 systemd[1]: Started Session 7 of User core. Oct 31 00:48:20.997105 sudo[1019]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/bash -c cat >/etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem </etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem < Oct 31 00:48:24.243137 update_engine[1269]: Oct 31 00:48:24.243137 update_engine[1269]: Oct 31 00:48:24.243137 update_engine[1269]: Oct 31 00:48:24.243137 update_engine[1269]: Oct 31 00:48:24.243137 update_engine[1269]: Oct 31 00:48:24.243137 update_engine[1269]: Oct 31 00:48:24.243137 update_engine[1269]: Oct 31 00:48:24.243137 update_engine[1269]: Oct 31 00:48:24.243137 update_engine[1269]: I1031 00:48:24.243127 1269 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 31 00:48:24.245953 update_engine[1269]: I1031 00:48:24.245914 1269 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 31 00:48:24.246223 update_engine[1269]: I1031 00:48:24.246196 1269 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 31 00:48:24.248437 update_engine[1269]: I1031 00:48:24.248402 1269 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 31 00:48:24.248522 update_engine[1269]: I1031 00:48:24.248502 1269 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Oct 31 00:48:24.248522 update_engine[1269]: I1031 00:48:24.248510 1269 omaha_request_action.cc:619] Omaha request response: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.248522 update_engine[1269]: Oct 31 00:48:24.250906 update_engine[1269]: I1031 00:48:24.250867 1269 omaha_request_action.cc:447] Omaha Response manifest version = Oct 31 00:48:24.250906 update_engine[1269]: I1031 00:48:24.250906 1269 omaha_request_action.cc:470] Found 1 url(s) Oct 31 00:48:24.251009 update_engine[1269]: I1031 00:48:24.250921 1269 omaha_request_action.cc:506] Processing first of 1 package(s) Oct 31 00:48:24.251009 update_engine[1269]: I1031 00:48:24.250926 1269 omaha_request_action.cc:513] Omaha Response package name = update.gz Oct 31 00:48:24.251009 update_engine[1269]: I1031 00:48:24.250929 1269 omaha_request_action.cc:529] Url0: http://10.0.0.4:34567/packages/update.gz Oct 31 00:48:24.251009 update_engine[1269]: I1031 00:48:24.250939 1269 omaha_request_action.cc:541] Payload size = 435657005 bytes Oct 31 00:48:24.251009 update_engine[1269]: I1031 00:48:24.250950 1269 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Oct 31 00:48:24.251009 update_engine[1269]: I1031 00:48:24.250967 1269 payload_state.cc:51] Resetting all persisted state as this is a new response Oct 31 00:48:24.251009 update_engine[1269]: I1031 00:48:24.250970 1269 payload_state.cc:360] Current Response Signature = Oct 31 00:48:24.251009 update_engine[1269]: NumURLs = 1 Oct 31 00:48:24.251009 update_engine[1269]: Url0 = http://10.0.0.4:34567/packages/update.gz Oct 31 00:48:24.251009 update_engine[1269]: Payload Size = 435657005 Oct 31 00:48:24.251009 update_engine[1269]: Payload Sha256 Hash = 0Tv8tyv9InfnN/xh6P9vNO30csjNJVYNFdwS7QDmeqo= Oct 31 00:48:24.251009 update_engine[1269]: Is Delta Payload = 0 Oct 31 00:48:24.251009 update_engine[1269]: Max Failure Count Per Url = 10 Oct 31 00:48:24.251009 update_engine[1269]: Disable Payload Backoff = 1 Oct 31 00:48:24.251279 update_engine[1269]: I1031 00:48:24.251073 1269 payload_state.cc:381] Payload Attempt Number = 0 Oct 31 00:48:24.251279 update_engine[1269]: I1031 00:48:24.251109 1269 payload_state.cc:404] Current URL Index = 0 Oct 31 00:48:24.251279 update_engine[1269]: I1031 00:48:24.251138 1269 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Oct 31 00:48:24.251279 update_engine[1269]: I1031 00:48:24.251166 1269 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Oct 31 00:48:24.251279 update_engine[1269]: I1031 00:48:24.251170 1269 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 31 00:48:24.251279 update_engine[1269]: I1031 00:48:24.251261 1269 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Oct 31 00:48:24.251390 update_engine[1269]: I1031 00:48:24.251286 1269 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 31 00:48:24.251390 update_engine[1269]: I1031 00:48:24.251291 1269 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Oct 31 00:48:24.251390 update_engine[1269]: I1031 00:48:24.251307 1269 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Oct 31 00:48:24.251390 update_engine[1269]: E1031 00:48:24.251312 1269 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Oct 31 00:48:24.251852 update_engine[1269]: I1031 00:48:24.251821 1269 omaha_response_handler_action.cc:85] Using this install plan: Oct 31 00:48:24.251852 update_engine[1269]: I1031 00:48:24.251842 1269 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.4:34567/packages/update.gz, payload size: 435657005, payload hash: 0Tv8tyv9InfnN/xh6P9vNO30csjNJVYNFdwS7QDmeqo=, partition_path: /dev/vda4, kernel_path: /boot/flatcar/vmlinuz-b, pcr_policy_path: /var/lib/update_engine/pcrs-b.zip, old_partition_path: /dev/vda3, old_kernel_path: /boot/flatcar/vmlinuz-a Oct 31 00:48:24.251952 update_engine[1269]: I1031 00:48:24.251935 1269 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Oct 31 00:48:24.253092 update_engine[1269]: I1031 00:48:24.253059 1269 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Oct 31 00:48:24.320885 sshd[1282]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:24.326978 systemd[1]: sshd@24-10.0.0.5:22-10.0.0.1:36880.service: Deactivated successfully. Oct 31 00:48:24.327747 systemd[1]: session-25.scope: Deactivated successfully. Oct 31 00:48:24.328380 systemd-logind[919]: Session 25 logged out. Waiting for processes to exit. Oct 31 00:48:24.331571 systemd-logind[919]: Removed session 25. Oct 31 00:48:26.367594 update_engine[1269]: I1031 00:48:26.366211 1269 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Oct 31 00:48:26.367594 update_engine[1269]: I1031 00:48:26.366255 1269 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Oct 31 00:48:26.454264 update_engine[1269]: E1031 00:48:26.454180 1269 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Oct 31 00:48:26.454264 update_engine[1269]: I1031 00:48:26.454229 1269 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Oct 31 00:48:26.454264 update_engine[1269]: I1031 00:48:26.454233 1269 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Oct 31 00:48:26.454264 update_engine[1269]: I1031 00:48:26.454237 1269 update_attempter.cc:302] Processing Done. Oct 31 00:48:26.454264 update_engine[1269]: E1031 00:48:26.454281 1269 update_attempter.cc:615] Update failed. Oct 31 00:48:26.454264 update_engine[1269]: I1031 00:48:26.454285 1269 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Oct 31 00:48:26.454574 update_engine[1269]: I1031 00:48:26.454290 1269 payload_state.cc:276] Incrementing the URL failure count Oct 31 00:48:26.454574 update_engine[1269]: I1031 00:48:26.454293 1269 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 31 00:48:26.454574 update_engine[1269]: I1031 00:48:26.454473 1269 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 31 00:48:26.454574 update_engine[1269]: I1031 00:48:26.454494 1269 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.4:34567/v1/update Oct 31 00:48:26.454574 update_engine[1269]: I1031 00:48:26.454499 1269 omaha_request_action.cc:269] Request: Oct 31 00:48:26.454574 update_engine[1269]: Oct 31 00:48:26.454574 update_engine[1269]: Oct 31 00:48:26.454574 update_engine[1269]: Oct 31 00:48:26.454574 update_engine[1269]: Oct 31 00:48:26.454574 update_engine[1269]: Oct 31 00:48:26.454574 update_engine[1269]: Oct 31 00:48:26.454574 update_engine[1269]: I1031 00:48:26.454502 1269 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 31 00:48:26.454574 update_engine[1269]: I1031 00:48:26.454563 1269 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 31 00:48:26.454831 update_engine[1269]: I1031 00:48:26.454671 1269 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 31 00:48:26.620191 update_engine[1269]: I1031 00:48:26.619664 1269 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 31 00:48:26.620191 update_engine[1269]: I1031 00:48:26.619760 1269 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Oct 31 00:48:26.620191 update_engine[1269]: I1031 00:48:26.619766 1269 omaha_request_action.cc:619] Omaha request response: Oct 31 00:48:26.620191 update_engine[1269]: Oct 31 00:48:26.620191 update_engine[1269]: Oct 31 00:48:26.620191 update_engine[1269]: Oct 31 00:48:26.620191 update_engine[1269]: Oct 31 00:48:26.620191 update_engine[1269]: Oct 31 00:48:26.620191 update_engine[1269]: Oct 31 00:48:26.620191 update_engine[1269]: E1031 00:48:26.619781 1269 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Oct 31 00:48:26.620191 update_engine[1269]: I1031 00:48:26.619786 1269 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 31 00:48:26.620191 update_engine[1269]: I1031 00:48:26.619788 1269 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 31 00:48:26.620191 update_engine[1269]: I1031 00:48:26.619791 1269 update_attempter.cc:302] Processing Done. Oct 31 00:48:26.620191 update_engine[1269]: I1031 00:48:26.619796 1269 update_attempter.cc:306] Error event sent. Oct 31 00:48:34.454863 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43074). Oct 31 00:48:34.493332 sshd[1302]: Accepted publickey for core from 10.0.0.1 port 43074 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:34.497560 kernel: kauditd_printk_skb: 136 callbacks suppressed Oct 31 00:48:34.497621 kernel: audit: type=1006 audit(1761871714.493:353): 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 31 00:48:34.498089 sshd[1302]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:34.501289 systemd-logind[919]: New session 26 of user core. Oct 31 00:48:34.508349 systemd[1]: Started Session 26 of User core. Oct 31 00:48:34.571950 sshd[1302]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:34.584086 systemd[1]: sshd@25-10.0.0.5:22-10.0.0.1:43074.service: Deactivated successfully. Oct 31 00:48:34.584743 systemd[1]: session-26.scope: Deactivated successfully. Oct 31 00:48:34.585357 systemd-logind[919]: Session 26 logged out. Waiting for processes to exit. Oct 31 00:48:34.586536 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43078). Oct 31 00:48:34.587240 systemd-logind[919]: Removed session 26. Oct 31 00:48:34.620659 sshd[1309]: Accepted publickey for core from 10.0.0.1 port 43078 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:34.624178 kernel: audit: type=1006 audit(1761871714.620:354): 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 31 00:48:34.624191 sshd[1309]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:34.626832 systemd-logind[919]: New session 27 of user core. Oct 31 00:48:34.642914 systemd[1]: Started Session 27 of User core. Oct 31 00:48:34.699413 sshd[1309]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:34.707625 systemd[1]: sshd@26-10.0.0.5:22-10.0.0.1:43078.service: Deactivated successfully. Oct 31 00:48:34.708334 systemd[1]: session-27.scope: Deactivated successfully. Oct 31 00:48:34.709613 systemd-logind[919]: Session 27 logged out. Waiting for processes to exit. Oct 31 00:48:34.710820 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43094). Oct 31 00:48:34.711568 systemd-logind[919]: Removed session 27. Oct 31 00:48:34.746565 sshd[1317]: Accepted publickey for core from 10.0.0.1 port 43094 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:34.748511 sshd[1317]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:34.750189 kernel: audit: type=1006 audit(1761871714.746:355): 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 31 00:48:34.753753 systemd-logind[919]: New session 28 of user core. Oct 31 00:48:34.763477 systemd[1]: Started Session 28 of User core. Oct 31 00:48:34.814522 sshd[1317]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:34.822911 systemd[1]: sshd@27-10.0.0.5:22-10.0.0.1:43094.service: Deactivated successfully. Oct 31 00:48:34.823579 systemd[1]: session-28.scope: Deactivated successfully. Oct 31 00:48:34.824212 systemd-logind[919]: Session 28 logged out. Waiting for processes to exit. Oct 31 00:48:34.825270 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43100). Oct 31 00:48:34.825931 systemd-logind[919]: Removed session 28. Oct 31 00:48:34.858861 sshd[1323]: Accepted publickey for core from 10.0.0.1 port 43100 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:34.862188 kernel: audit: type=1006 audit(1761871714.858:356): 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 31 00:48:34.862461 sshd[1323]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:34.865259 systemd-logind[919]: New session 29 of user core. Oct 31 00:48:34.874355 systemd[1]: Started Session 29 of User core. Oct 31 00:48:34.922962 sshd[1323]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:34.932345 systemd[1]: sshd@28-10.0.0.5:22-10.0.0.1:43100.service: Deactivated successfully. Oct 31 00:48:34.933177 systemd[1]: session-29.scope: Deactivated successfully. Oct 31 00:48:34.934009 systemd-logind[919]: Session 29 logged out. Waiting for processes to exit. Oct 31 00:48:34.935435 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43104). Oct 31 00:48:34.936199 systemd-logind[919]: Removed session 29. Oct 31 00:48:34.969422 sshd[1329]: Accepted publickey for core from 10.0.0.1 port 43104 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:34.973183 kernel: audit: type=1006 audit(1761871714.969:357): 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 31 00:48:34.973363 sshd[1329]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:34.976200 systemd-logind[919]: New session 30 of user core. Oct 31 00:48:34.987355 systemd[1]: Started Session 30 of User core. Oct 31 00:48:35.041650 sudo[1333]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Oct 31 00:48:35.041866 sudo[1333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 00:48:35.048520 systemd[1]: sshd.socket: Deactivated successfully. Oct 31 00:48:35.048878 systemd[1]: Closed OpenSSH Server Socket. Oct 31 00:48:35.050309 sudo[1333]: pam_unix(sudo:session): session closed for user root Oct 31 00:48:35.059295 sudo[1332]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Oct 31 00:48:35.059507 sudo[1332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 00:48:35.093834 systemd[1]: Stopping Session 1 of User core... Oct 31 00:48:35.094326 sshd[943]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:35.095218 systemd[1]: Stopping Session 30 of User core... Oct 31 00:48:35.095571 sshd[1329]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:35.095832 sudo[1332]: pam_unix(sudo:session): session closed for user root Oct 31 00:48:35.096411 systemd[1]: Removed slice Slice /system/addon-config. Oct 31 00:48:35.097833 systemd[1]: Removed slice Slice /system/addon-run. Oct 31 00:48:35.099249 systemd[1]: Removed slice Slice /system/modprobe. Oct 31 00:48:35.100559 systemd[1]: Removed slice Slice /system/system-cloudinit. Oct 31 00:48:35.101848 systemd[1]: Stopped target Multi-User System. Oct 31 00:48:35.102898 systemd[1]: Stopped target Login Prompts. Oct 31 00:48:35.103978 systemd[1]: Stopped target Containers. Oct 31 00:48:35.104826 systemd[1]: Stopped target Host and Network Name Lookups. Oct 31 00:48:35.105877 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 31 00:48:35.106916 systemd[1]: Stopped target Timer Units. Oct 31 00:48:35.107730 systemd[1]: logrotate.timer: Deactivated successfully. Oct 31 00:48:35.107775 systemd[1]: Stopped Daily Log Rotation. Oct 31 00:48:35.108801 systemd[1]: mdadm.timer: Deactivated successfully. Oct 31 00:48:35.108838 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Oct 31 00:48:35.109992 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 31 00:48:35.110036 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Oct 31 00:48:35.111126 systemd[1]: Stopped target System Time Set. Oct 31 00:48:35.112112 systemd[1]: Stopped target Load user-provided cloud configs. Oct 31 00:48:35.113254 systemd[1]: Stopped target Load system-provided cloud configs. Oct 31 00:48:35.114343 systemd[1]: systemd-coredump.socket: Deactivated successfully. Oct 31 00:48:35.114477 systemd[1]: Closed Process Core Dump Socket. Oct 31 00:48:35.116154 systemd[1]: Unmounting Boot partition... Oct 31 00:48:35.117501 systemd[1]: Stopping Getty on tty1... Oct 31 00:48:35.118397 systemd[1]: Stopping Serial Getty on ttyAMA0... Oct 31 00:48:35.119283 systemd[1]: Stopping OpenSSH per-connection server daemon... Oct 31 00:48:35.120324 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:43104)... Oct 31 00:48:35.121458 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Oct 31 00:48:35.121613 systemd[1]: Stopped Commit a transient machine-id on disk. Oct 31 00:48:35.122775 systemd[1]: Stopped target First Boot Complete. Oct 31 00:48:35.124756 systemd[1]: Stopping Load/Save Random Seed... Oct 31 00:48:35.125754 systemd[1]: Stopping Update Engine... Oct 31 00:48:35.129068 systemd[1]: sshd@0-10.0.0.5:22-10.0.0.1:59908.service: Deactivated successfully. Oct 31 00:48:35.129300 systemd[1]: Stopped OpenSSH per-connection server daemon. Oct 31 00:48:35.131059 systemd[1]: getty@tty1.service: Deactivated successfully. Oct 31 00:48:35.131280 systemd[1]: Stopped Getty on tty1. Oct 31 00:48:35.132625 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Oct 31 00:48:35.132837 systemd[1]: Stopped Serial Getty on ttyAMA0. Oct 31 00:48:35.134168 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Oct 31 00:48:35.134244 systemd[1]: update-engine.service: Failed with result 'exit-code'. Oct 31 00:48:35.134499 systemd[1]: Stopped Update Engine. Oct 31 00:48:35.135272 systemd[1]: update-engine.service: Consumed 2.472s CPU time. Oct 31 00:48:35.135505 systemd[1]: sshd@29-10.0.0.5:22-10.0.0.1:43104.service: Deactivated successfully. Oct 31 00:48:35.135699 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:43104). Oct 31 00:48:35.148979 systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 31 00:48:35.149229 systemd[1]: Stopped Load/Save Random Seed. Oct 31 00:48:35.150439 systemd[1]: session-30.scope: Deactivated successfully. Oct 31 00:48:35.150682 systemd[1]: Stopped Session 30 of User core. Oct 31 00:48:35.151855 systemd[1]: boot.mount: Deactivated successfully. Oct 31 00:48:35.152107 systemd[1]: Unmounted Boot partition. Oct 31 00:48:35.153207 systemd[1]: session-1.scope: Deactivated successfully. Oct 31 00:48:35.153451 systemd[1]: Stopped Session 1 of User core. Oct 31 00:48:35.154898 systemd-logind[919]: Session 1 logged out. Waiting for processes to exit. Oct 31 00:48:35.155851 systemd-logind[919]: Session 30 logged out. Waiting for processes to exit. Oct 31 00:48:35.156102 systemd[1]: Removed slice Slice /system/getty. Oct 31 00:48:35.157599 systemd[1]: Removed slice Slice /system/serial-getty. Oct 31 00:48:35.158846 systemd[1]: Removed slice Slice /system/sshd. Oct 31 00:48:35.159769 systemd[1]: sshd-keygen.service: Deactivated successfully. Oct 31 00:48:35.159890 systemd[1]: Stopped Generate sshd host keys. Oct 31 00:48:35.161034 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Oct 31 00:48:35.161154 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Oct 31 00:48:35.162512 systemd[1]: Stopping User Login Management... Oct 31 00:48:35.163741 systemd[1]: Stopping User Manager for UID 500... Oct 31 00:48:35.164623 systemd[952]: Stopped target Main User Target. Oct 31 00:48:35.164643 systemd[952]: Stopped target Basic System. Oct 31 00:48:35.164653 systemd[952]: Stopped target Paths. Oct 31 00:48:35.164661 systemd[952]: Stopped target Sockets. Oct 31 00:48:35.164677 systemd[952]: Reached target Shutdown. Oct 31 00:48:35.164684 systemd[952]: Stopped target Timers. Oct 31 00:48:35.164796 systemd[952]: Finished Exit the Session. Oct 31 00:48:35.164819 systemd[952]: Reached target Exit the Session. Oct 31 00:48:35.165944 systemd-logind[919]: Removed session 1. Oct 31 00:48:35.167943 systemd[1]: user@500.service: Deactivated successfully. Oct 31 00:48:35.168270 systemd[1]: Stopped User Manager for UID 500. Oct 31 00:48:35.170356 systemd[1]: Stopping User Runtime Directory /run/user/500... Oct 31 00:48:35.175749 systemd[1]: run-user-500.mount: Deactivated successfully. Oct 31 00:48:35.175826 systemd[1]: Unmounted /run/user/500. Oct 31 00:48:35.177288 systemd[1]: systemd-logind.service: Deactivated successfully. Oct 31 00:48:35.177557 systemd[1]: Stopped User Login Management. Oct 31 00:48:35.178872 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Oct 31 00:48:35.179103 systemd[1]: Stopped User Runtime Directory /run/user/500. Oct 31 00:48:35.180881 systemd[1]: Removed slice User Slice of UID 500. Oct 31 00:48:35.182869 systemd[1]: Stopping D-Bus System Message Bus... Oct 31 00:48:35.192000 audit: BPF prog-id=51 op=UNLOAD Oct 31 00:48:35.195092 systemd[1]: Stopping Permit User Sessions... Oct 31 00:48:35.195795 kernel: audit: type=1334 audit(1761871715.192:358): prog-id=51 op=UNLOAD Oct 31 00:48:35.196551 systemd[1]: dbus.service: Deactivated successfully. Oct 31 00:48:35.196802 systemd[1]: Stopped D-Bus System Message Bus. Oct 31 00:48:35.200112 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Oct 31 00:48:35.200346 systemd[1]: Stopped Permit User Sessions. Oct 31 00:48:35.201278 systemd[1]: Stopped target Basic System. Oct 31 00:48:35.202058 systemd[1]: Stopped target Network. Oct 31 00:48:35.202820 systemd[1]: Stopped target Path Units. Oct 31 00:48:35.203658 systemd[1]: motdgen.path: Deactivated successfully. Oct 31 00:48:35.207200 systemd[1]: Stopped Watch for update engine configuration changes. Oct 31 00:48:35.208246 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Oct 31 00:48:35.214224 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 31 00:48:35.215364 systemd[1]: Stopped target Remote File Systems. Oct 31 00:48:35.216199 systemd[1]: Stopped target Slice Units. Oct 31 00:48:35.217279 systemd[1]: Removed slice User and Session Slice. Oct 31 00:48:35.218135 systemd[1]: Stopped target Socket Units. Oct 31 00:48:35.218939 systemd[1]: dbus.socket: Deactivated successfully. Oct 31 00:48:35.219000 systemd[1]: Closed D-Bus System Message Bus Socket. Oct 31 00:48:35.220144 systemd[1]: docker.socket: Deactivated successfully. Oct 31 00:48:35.220401 systemd[1]: Closed Docker Socket for the API. Oct 31 00:48:35.221249 systemd[1]: Stopped target System Initialization. Oct 31 00:48:35.222097 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Oct 31 00:48:35.222263 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Oct 31 00:48:35.223399 systemd[1]: Stopped target Local Verity Protected Volumes. Oct 31 00:48:35.225284 systemd[1]: Stopping Load Security Auditing Rules... Oct 31 00:48:35.226291 systemd[1]: Stopping Network Name Resolution... Oct 31 00:48:35.227139 systemd[1]: Stopping Network Time Synchronization... Oct 31 00:48:35.228091 systemd[1]: systemd-update-done.service: Deactivated successfully. Oct 31 00:48:35.228212 systemd[1]: Stopped Update is Completed. Oct 31 00:48:35.228572 auditctl[1349]: No rules Oct 31 00:48:35.229035 systemd[1]: ldconfig.service: Deactivated successfully. Oct 31 00:48:35.229095 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Oct 31 00:48:35.230045 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Oct 31 00:48:35.230104 systemd[1]: Stopped Rebuild Hardware Database. Oct 31 00:48:35.231008 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Oct 31 00:48:35.231072 systemd[1]: Stopped Rebuild Journal Catalog. Oct 31 00:48:35.232813 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Oct 31 00:48:35.235513 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 31 00:48:35.235767 systemd[1]: Stopped Network Name Resolution. Oct 31 00:48:35.237022 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Oct 31 00:48:35.237353 systemd[1]: Stopped Network Time Synchronization. Oct 31 00:48:35.238550 systemd[1]: audit-rules.service: Deactivated successfully. Oct 31 00:48:35.238763 systemd[1]: Stopped Load Security Auditing Rules. Oct 31 00:48:35.241393 systemd[1]: Stopping Network Configuration... Oct 31 00:48:35.242339 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Oct 31 00:48:35.242579 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Oct 31 00:48:35.242000 audit: BPF prog-id=55 op=UNLOAD Oct 31 00:48:35.245183 kernel: audit: type=1334 audit(1761871715.242:359): prog-id=55 op=UNLOAD Oct 31 00:48:35.245283 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 31 00:48:35.245384 systemd[1]: Stopped Create Volatile Files and Directories. Oct 31 00:48:35.245000 audit: BPF prog-id=54 op=UNLOAD Oct 31 00:48:35.247398 systemd[1]: Stopped target Local File Systems. Oct 31 00:48:35.248182 kernel: audit: type=1334 audit(1761871715.245:360): prog-id=54 op=UNLOAD Oct 31 00:48:35.248347 systemd-networkd[840]: eth0: DHCPv6 lease lost Oct 31 00:48:35.250230 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Oct 31 00:48:35.251752 systemd[1]: Unmounting External Media Directory... Oct 31 00:48:35.253530 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Oct 31 00:48:35.255418 systemd[1]: Unmounting /run/torcx/unpack... Oct 31 00:48:35.256710 systemd[1]: Unmounting Temporary Directory /tmp... Oct 31 00:48:35.258135 systemd[1]: Unmounting /usr/share/oem... Oct 31 00:48:35.259766 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Oct 31 00:48:35.262739 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 31 00:48:35.263001 systemd[1]: Stopped Network Configuration. Oct 31 00:48:35.264129 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 31 00:48:35.264428 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Oct 31 00:48:35.265820 systemd[1]: media.mount: Deactivated successfully. Oct 31 00:48:35.266096 systemd[1]: Unmounted External Media Directory. Oct 31 00:48:35.267253 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Oct 31 00:48:35.267525 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Oct 31 00:48:35.268891 systemd[1]: tmp.mount: Deactivated successfully. Oct 31 00:48:35.269177 systemd[1]: Unmounted Temporary Directory /tmp. Oct 31 00:48:35.270231 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 31 00:48:35.270517 systemd[1]: Unmounted /usr/share/oem. Oct 31 00:48:35.271490 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 31 00:48:35.271746 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Oct 31 00:48:35.273588 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 31 00:48:35.273646 systemd[1]: Closed Network Service Netlink Socket. Oct 31 00:48:35.274599 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Oct 31 00:48:35.274668 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Oct 31 00:48:35.278000 audit: BPF prog-id=50 op=UNLOAD Oct 31 00:48:35.280560 systemd[1]: Removed slice Slice /system/systemd-fsck. Oct 31 00:48:35.281410 kernel: audit: type=1334 audit(1761871715.278:361): prog-id=50 op=UNLOAD Oct 31 00:48:35.281514 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 31 00:48:35.281586 systemd[1]: Stopped Apply Kernel Variables. Oct 31 00:48:35.282635 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Oct 31 00:48:35.282878 systemd[1]: Unmounted /run/torcx/unpack. Oct 31 00:48:35.283954 systemd[1]: Stopped target Preparation for Local File Systems. Oct 31 00:48:35.285035 systemd[1]: Stopped target Swaps. Oct 31 00:48:35.285781 systemd[1]: Reached target Unmount All Filesystems. Oct 31 00:48:35.286742 systemd[1]: lvm2-activation.service: Deactivated successfully. Oct 31 00:48:35.286811 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 31 00:48:35.288720 systemd[1]: Stopped target Local Encrypted Volumes. Oct 31 00:48:35.289750 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 31 00:48:35.289830 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 31 00:48:35.290990 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Oct 31 00:48:35.295322 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Oct 31 00:48:35.296507 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Oct 31 00:48:35.296584 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 31 00:48:35.297598 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 31 00:48:35.297661 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 31 00:48:35.298634 systemd[1]: systemd-sysusers.service: Deactivated successfully. Oct 31 00:48:35.298692 systemd[1]: Stopped Create System Users. Oct 31 00:48:35.299529 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Oct 31 00:48:35.299587 systemd[1]: Stopped Remount Root and Kernel File Systems. Oct 31 00:48:35.300606 systemd[1]: Reached target System Shutdown. Oct 31 00:48:35.301458 systemd[1]: Reached target Late Shutdown Services. Oct 31 00:48:35.302400 systemd[1]: systemd-reboot.service: Deactivated successfully. Oct 31 00:48:35.302437 systemd[1]: Finished System Reboot. Oct 31 00:48:35.303156 systemd[1]: Reached target System Reboot. Oct 31 00:48:35.303931 systemd[1]: Shutting down. Oct 31 00:48:35.305000 audit: BPF prog-id=53 op=UNLOAD Oct 31 00:48:35.306000 audit: BPF prog-id=52 op=UNLOAD Oct 31 00:48:35.308176 kernel: audit: type=1334 audit(1761871715.305:362): prog-id=53 op=UNLOAD Oct 31 00:48:35.309000 audit: BPF prog-id=58 op=UNLOAD Oct 31 00:48:35.309000 audit: BPF prog-id=57 op=UNLOAD Oct 31 00:48:35.309000 audit: BPF prog-id=56 op=UNLOAD Oct 31 00:48:35.309000 audit: BPF prog-id=61 op=UNLOAD Oct 31 00:48:35.309000 audit: BPF prog-id=60 op=UNLOAD Oct 31 00:48:35.309000 audit: BPF prog-id=59 op=UNLOAD Oct 31 00:48:35.340190 systemd-shutdown[1]: Syncing filesystems and block devices. Oct 31 00:48:35.346189 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Oct 31 00:48:35.347827 systemd-journald[803]: Journal stopped -- Reboot -- Oct 31 00:48:42.688755 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 31 00:48:42.688772 kernel: Linux version 5.10.107-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP PREEMPT Tue Mar 22 19:39:09 -00 2022 Oct 31 00:48:42.688781 kernel: efi: EFI v2.70 by EDK II Oct 31 00:48:42.688787 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 31 00:48:42.688794 kernel: efi: seeding entropy pool Oct 31 00:48:42.688799 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 31 00:48:42.688806 kernel: ACPI: Early table checksum verification disabled Oct 31 00:48:42.688811 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 31 00:48:42.688816 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 31 00:48:42.688821 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:42.688828 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:42.688833 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:42.688838 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:42.688844 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:42.688850 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:42.688856 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:42.688863 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:42.688869 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 00:48:42.688875 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 31 00:48:42.688880 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 31 00:48:42.688886 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 00:48:42.688891 kernel: NUMA: NODE_DATA [mem 0xdc95b900-0xdc960fff] Oct 31 00:48:42.688897 kernel: Zone ranges: Oct 31 00:48:42.688902 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 00:48:42.688908 kernel: DMA32 empty Oct 31 00:48:42.688914 kernel: Normal empty Oct 31 00:48:42.688919 kernel: Movable zone start for each node Oct 31 00:48:42.688926 kernel: Early memory node ranges Oct 31 00:48:42.688931 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 31 00:48:42.688937 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 31 00:48:42.688942 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 31 00:48:42.688948 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 31 00:48:42.688953 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 31 00:48:42.688959 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 31 00:48:42.688964 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 31 00:48:42.688970 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 31 00:48:42.688975 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 31 00:48:42.688981 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 31 00:48:42.688986 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 31 00:48:42.688992 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 00:48:42.689001 kernel: On node 0 totalpages: 643072 Oct 31 00:48:42.689007 kernel: DMA zone: 10048 pages used for memmap Oct 31 00:48:42.689013 kernel: DMA zone: 0 pages reserved Oct 31 00:48:42.689018 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 31 00:48:42.689024 kernel: psci: probing for conduit method from ACPI. Oct 31 00:48:42.689030 kernel: psci: PSCIv1.1 detected in firmware. Oct 31 00:48:42.689035 kernel: psci: Using standard PSCI v0.2 function IDs Oct 31 00:48:42.689041 kernel: psci: Trusted OS migration not required Oct 31 00:48:42.689048 kernel: psci: SMC Calling Convention v1.1 Oct 31 00:48:42.689053 kernel: ACPI: SRAT not present Oct 31 00:48:42.689059 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 31 00:48:42.689065 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 31 00:48:42.689071 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 31 00:48:42.689086 kernel: Detected PIPT I-cache on CPU0 Oct 31 00:48:42.689092 kernel: CPU features: detected: GIC system register CPU interface Oct 31 00:48:42.689098 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 31 00:48:42.689104 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 31 00:48:42.689110 kernel: CPU features: detected: Hardware dirty bit management Oct 31 00:48:42.689116 kernel: CPU features: detected: Spectre-v4 Oct 31 00:48:42.689123 kernel: CPU features: detected: ARM erratum 1418040 Oct 31 00:48:42.689129 kernel: CPU features: detected: Spectre-BHB Oct 31 00:48:42.689135 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 31 00:48:42.689141 kernel: Policy zone: DMA Oct 31 00:48:42.689147 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 31 00:48:42.689154 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 31 00:48:42.689160 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 31 00:48:42.689170 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 31 00:48:42.689176 kernel: Memory: 2452104K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120184K reserved, 0K cma-reserved) Oct 31 00:48:42.689182 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 31 00:48:42.689188 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 31 00:48:42.689196 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 31 00:48:42.689203 kernel: rcu: RCU event tracing is enabled. Oct 31 00:48:42.689208 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 31 00:48:42.689214 kernel: Trampoline variant of Tasks RCU enabled. Oct 31 00:48:42.689220 kernel: Tracing variant of Tasks RCU enabled. Oct 31 00:48:42.689226 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 31 00:48:42.689232 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 31 00:48:42.689238 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 31 00:48:42.689244 kernel: GICv3: 256 SPIs implemented Oct 31 00:48:42.689250 kernel: GICv3: 0 Extended SPIs implemented Oct 31 00:48:42.689255 kernel: GICv3: Distributor has no Range Selector support Oct 31 00:48:42.689261 kernel: GICv3: 16 PPIs implemented Oct 31 00:48:42.689269 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 31 00:48:42.689274 kernel: ACPI: SRAT not present Oct 31 00:48:42.689280 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 31 00:48:42.689286 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 31 00:48:42.689292 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 31 00:48:42.689298 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 31 00:48:42.689304 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 31 00:48:42.689309 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 00:48:42.689315 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 31 00:48:42.689321 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 31 00:48:42.689327 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 31 00:48:42.689334 kernel: arm-pv: using stolen time PV Oct 31 00:48:42.689341 kernel: Console: colour dummy device 80x25 Oct 31 00:48:42.689347 kernel: ACPI: Core revision 20200925 Oct 31 00:48:42.689353 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 31 00:48:42.689359 kernel: pid_max: default: 32768 minimum: 301 Oct 31 00:48:42.689365 kernel: LSM: Security Framework initializing Oct 31 00:48:42.689371 kernel: SELinux: Initializing. Oct 31 00:48:42.689377 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 00:48:42.689383 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 00:48:42.689389 kernel: rcu: Hierarchical SRCU implementation. Oct 31 00:48:42.689397 kernel: Platform MSI: ITS@0x8080000 domain created Oct 31 00:48:42.689403 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 31 00:48:42.689409 kernel: Remapping and enabling EFI services. Oct 31 00:48:42.689415 kernel: smp: Bringing up secondary CPUs ... Oct 31 00:48:42.689421 kernel: Detected PIPT I-cache on CPU1 Oct 31 00:48:42.689427 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 31 00:48:42.689433 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 31 00:48:42.689439 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 00:48:42.689446 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 31 00:48:42.689453 kernel: Detected PIPT I-cache on CPU2 Oct 31 00:48:42.689460 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 31 00:48:42.689466 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 31 00:48:42.689473 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 00:48:42.689479 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 31 00:48:42.689486 kernel: Detected PIPT I-cache on CPU3 Oct 31 00:48:42.689495 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 31 00:48:42.689502 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 31 00:48:42.689510 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 00:48:42.689516 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 31 00:48:42.689523 kernel: smp: Brought up 1 node, 4 CPUs Oct 31 00:48:42.689529 kernel: SMP: Total of 4 processors activated. Oct 31 00:48:42.689535 kernel: CPU features: detected: Privileged Access Never Oct 31 00:48:42.689542 kernel: CPU features: detected: LSE atomic instructions Oct 31 00:48:42.689548 kernel: CPU features: detected: User Access Override Oct 31 00:48:42.689555 kernel: CPU features: detected: 32-bit EL0 Support Oct 31 00:48:42.689562 kernel: CPU features: detected: Common not Private translations Oct 31 00:48:42.689568 kernel: CPU features: detected: RAS Extension Support Oct 31 00:48:42.689576 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 31 00:48:42.689582 kernel: CPU features: detected: CRC32 instructions Oct 31 00:48:42.689588 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 31 00:48:42.689595 kernel: CPU: All CPU(s) started at EL1 Oct 31 00:48:42.689601 kernel: alternatives: patching kernel code Oct 31 00:48:42.689609 kernel: devtmpfs: initialized Oct 31 00:48:42.689615 kernel: KASLR enabled Oct 31 00:48:42.689621 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 31 00:48:42.689628 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 31 00:48:42.689634 kernel: pinctrl core: initialized pinctrl subsystem Oct 31 00:48:42.689640 kernel: SMBIOS 3.0.0 present. Oct 31 00:48:42.689647 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 31 00:48:42.689653 kernel: NET: Registered protocol family 16 Oct 31 00:48:42.689659 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 31 00:48:42.689666 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 31 00:48:42.689673 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 31 00:48:42.689679 kernel: audit: initializing netlink subsys (disabled) Oct 31 00:48:42.689686 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Oct 31 00:48:42.689692 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 31 00:48:42.689698 kernel: cpuidle: using governor menu Oct 31 00:48:42.689704 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 31 00:48:42.689711 kernel: ASID allocator initialised with 32768 entries Oct 31 00:48:42.689717 kernel: ACPI: bus type PCI registered Oct 31 00:48:42.689723 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 31 00:48:42.689731 kernel: Serial: AMBA PL011 UART driver Oct 31 00:48:42.689737 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 31 00:48:42.689744 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 31 00:48:42.689750 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 31 00:48:42.689757 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 31 00:48:42.689763 kernel: cryptd: max_cpu_qlen set to 1000 Oct 31 00:48:42.689769 kernel: ACPI: Added _OSI(Module Device) Oct 31 00:48:42.689775 kernel: ACPI: Added _OSI(Processor Device) Oct 31 00:48:42.689782 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 31 00:48:42.689790 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 31 00:48:42.689796 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 31 00:48:42.689802 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 31 00:48:42.689809 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 31 00:48:42.689815 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 31 00:48:42.689821 kernel: ACPI: Interpreter enabled Oct 31 00:48:42.689828 kernel: ACPI: Using GIC for interrupt routing Oct 31 00:48:42.689834 kernel: ACPI: MCFG table detected, 1 entries Oct 31 00:48:42.689840 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 31 00:48:42.689847 kernel: printk: console [ttyAMA0] enabled Oct 31 00:48:42.689854 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 31 00:48:42.689998 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 31 00:48:42.690067 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 31 00:48:42.690133 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 31 00:48:42.690197 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 31 00:48:42.690251 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 31 00:48:42.690260 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 31 00:48:42.690269 kernel: PCI host bridge to bus 0000:00 Oct 31 00:48:42.690594 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 31 00:48:42.690655 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 31 00:48:42.690708 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 31 00:48:42.690760 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 31 00:48:42.690829 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 31 00:48:42.690897 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 31 00:48:42.690964 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 31 00:48:42.691024 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 31 00:48:42.691123 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 31 00:48:42.691199 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 31 00:48:42.691273 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 31 00:48:42.691333 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 31 00:48:42.691387 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 31 00:48:42.691447 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 31 00:48:42.691501 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 31 00:48:42.691510 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 31 00:48:42.691517 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 31 00:48:42.691524 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 31 00:48:42.691531 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 31 00:48:42.691537 kernel: vgaarb: loaded Oct 31 00:48:42.691544 kernel: Registered efivars operations Oct 31 00:48:42.691551 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 31 00:48:42.691560 kernel: VFS: Disk quotas dquot_6.6.0 Oct 31 00:48:42.691567 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 31 00:48:42.691573 kernel: pnp: PnP ACPI init Oct 31 00:48:42.691699 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 31 00:48:42.691777 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 31 00:48:42.691786 kernel: pnp: PnP ACPI: found 1 devices Oct 31 00:48:42.691793 kernel: NET: Registered protocol family 2 Oct 31 00:48:42.691800 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 31 00:48:42.691810 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 31 00:48:42.691817 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 31 00:48:42.691823 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 31 00:48:42.691830 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 31 00:48:42.691837 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 00:48:42.691843 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 00:48:42.691850 kernel: NET: Registered protocol family 1 Oct 31 00:48:42.691856 kernel: PCI: CLS 0 bytes, default 64 Oct 31 00:48:42.691863 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 31 00:48:42.691871 kernel: kvm [1]: HYP mode not available Oct 31 00:48:42.691878 kernel: Initialise system trusted keyrings Oct 31 00:48:42.691884 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 31 00:48:42.691891 kernel: Key type asymmetric registered Oct 31 00:48:42.691898 kernel: Asymmetric key parser 'x509' registered Oct 31 00:48:42.691904 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 31 00:48:42.691911 kernel: io scheduler mq-deadline registered Oct 31 00:48:42.691917 kernel: io scheduler kyber registered Oct 31 00:48:42.691924 kernel: io scheduler bfq registered Oct 31 00:48:42.691932 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 31 00:48:42.691938 kernel: ACPI: Power Button [PWRB] Oct 31 00:48:42.691945 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 31 00:48:42.692008 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 31 00:48:42.692017 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 31 00:48:42.692024 kernel: msm_serial: driver initialized Oct 31 00:48:42.692031 kernel: random: fast init done Oct 31 00:48:42.692037 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 31 00:48:42.692044 kernel: random: crng init done Oct 31 00:48:42.692051 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 31 00:48:42.692059 kernel: thunder_xcv, ver 1.0 Oct 31 00:48:42.692066 kernel: thunder_bgx, ver 1.0 Oct 31 00:48:42.692072 kernel: nicpf, ver 1.0 Oct 31 00:48:42.692090 kernel: nicvf, ver 1.0 Oct 31 00:48:42.692172 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 31 00:48:42.692234 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-31T00:48:42 UTC (1761871722) Oct 31 00:48:42.692243 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 31 00:48:42.692250 kernel: NET: Registered protocol family 10 Oct 31 00:48:42.692259 kernel: Segment Routing with IPv6 Oct 31 00:48:42.692265 kernel: NET: Registered protocol family 17 Oct 31 00:48:42.692272 kernel: Key type dns_resolver registered Oct 31 00:48:42.692278 kernel: registered taskstats version 1 Oct 31 00:48:42.692285 kernel: Loading compiled-in X.509 certificates Oct 31 00:48:42.692291 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 31 00:48:42.692298 kernel: Key type ._fscrypt registered Oct 31 00:48:42.692304 kernel: Key type .fscrypt registered Oct 31 00:48:42.692310 kernel: Key type fscrypt-provisioning registered Oct 31 00:48:42.692318 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 31 00:48:42.692325 kernel: ima: Allocated hash algorithm: sha1 Oct 31 00:48:42.692331 kernel: ima: No architecture policies found Oct 31 00:48:42.692338 kernel: Freeing unused kernel memory: 34240K Oct 31 00:48:42.692344 kernel: Run /init as init process Oct 31 00:48:42.692350 kernel: with arguments: Oct 31 00:48:42.692357 kernel: /init Oct 31 00:48:42.692363 kernel: with environment: Oct 31 00:48:42.692369 kernel: HOME=/ Oct 31 00:48:42.692375 kernel: TERM=linux Oct 31 00:48:42.692383 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 31 00:48:42.692391 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 31 00:48:42.692406 systemd[1]: Detected virtualization kvm. Oct 31 00:48:42.692413 systemd[1]: Detected architecture arm64. Oct 31 00:48:42.692420 systemd[1]: Running in initial RAM disk. Oct 31 00:48:42.692427 systemd[1]: No hostname configured, using default hostname. Oct 31 00:48:42.692433 systemd[1]: Hostname set to . Oct 31 00:48:42.692442 systemd[1]: Initializing machine ID from VM UUID. Oct 31 00:48:42.692449 systemd[1]: Queued start job for default target Initrd Default Target. Oct 31 00:48:42.692456 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 31 00:48:42.692463 systemd[1]: Reached target Local Encrypted Volumes. Oct 31 00:48:42.692469 systemd[1]: Reached target Path Units. Oct 31 00:48:42.692476 systemd[1]: Reached target Slice Units. Oct 31 00:48:42.692483 systemd[1]: Reached target Swaps. Oct 31 00:48:42.692490 systemd[1]: Reached target Timer Units. Oct 31 00:48:42.692500 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 31 00:48:42.692508 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 31 00:48:42.692514 systemd[1]: Listening on Journal Audit Socket. Oct 31 00:48:42.692521 systemd[1]: Listening on Journal Socket (/dev/log). Oct 31 00:48:42.692528 systemd[1]: Listening on Journal Socket. Oct 31 00:48:42.692535 systemd[1]: Listening on udev Control Socket. Oct 31 00:48:42.692543 systemd[1]: Listening on udev Kernel Socket. Oct 31 00:48:42.692550 systemd[1]: Reached target Socket Units. Oct 31 00:48:42.692556 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 31 00:48:42.692566 systemd[1]: Starting Create List of Static Device Nodes... Oct 31 00:48:42.692573 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 31 00:48:42.692580 systemd[1]: Starting Journal Service... Oct 31 00:48:42.692587 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 31 00:48:42.692594 systemd[1]: Starting Apply Kernel Variables... Oct 31 00:48:42.692601 systemd[1]: Starting Setup Virtual Console... Oct 31 00:48:42.692608 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 31 00:48:42.692614 systemd[1]: Finished Create List of Static Device Nodes. Oct 31 00:48:42.692621 systemd[1]: Finished Apply Kernel Variables. Oct 31 00:48:42.692629 systemd[1]: Finished Setup Virtual Console. Oct 31 00:48:42.692636 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 31 00:48:42.692646 systemd-journald[268]: Journal started Oct 31 00:48:42.692686 systemd-journald[268]: Runtime Journal (/run/log/journal/e6bb737c0f5e4438bdeb343d8ca8882b) is 6.0M, max 48.5M, 42.4M free. Oct 31 00:48:42.695953 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 31 00:48:42.697111 systemd[1]: Started Journal Service. Oct 31 00:48:42.697125 kernel: SCSI subsystem initialized Oct 31 00:48:42.698703 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 31 00:48:42.703093 kernel: Loading iSCSI transport class v2.0-870. Oct 31 00:48:42.713966 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 31 00:48:42.715915 systemd[1]: Starting dracut cmdline hook... Oct 31 00:48:42.725258 dracut-cmdline[292]: dracut-dracut-053 Oct 31 00:48:42.727767 dracut-cmdline[292]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 31 00:48:42.790112 kernel: iscsi: registered transport (tcp) Oct 31 00:48:42.801340 kernel: iscsi: registered transport (qla4xxx) Oct 31 00:48:42.801376 kernel: QLogic iSCSI HBA Driver Oct 31 00:48:42.811930 systemd[1]: Finished dracut cmdline hook. Oct 31 00:48:42.813588 systemd[1]: Starting dracut pre-udev hook... Oct 31 00:48:42.814948 systemd[1]: Starting Open-iSCSI... Oct 31 00:48:42.817407 iscsid[377]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 31 00:48:42.817407 iscsid[377]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 31 00:48:42.817407 iscsid[377]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 31 00:48:42.817407 iscsid[377]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 31 00:48:42.817407 iscsid[377]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 31 00:48:42.829508 iscsid[377]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 31 00:48:42.821195 systemd[1]: Started Open-iSCSI. Oct 31 00:48:42.833465 kernel: device-mapper: uevent: version 1.0.3 Oct 31 00:48:42.833486 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 31 00:48:42.868106 kernel: raid6: neonx8 gen() 13765 MB/s Oct 31 00:48:42.885102 kernel: raid6: neonx8 xor() 10740 MB/s Oct 31 00:48:42.902101 kernel: raid6: neonx4 gen() 13599 MB/s Oct 31 00:48:42.919103 kernel: raid6: neonx4 xor() 11553 MB/s Oct 31 00:48:42.936121 kernel: raid6: neonx2 gen() 13007 MB/s Oct 31 00:48:42.953105 kernel: raid6: neonx2 xor() 9881 MB/s Oct 31 00:48:42.970101 kernel: raid6: neonx1 gen() 10901 MB/s Oct 31 00:48:42.987099 kernel: raid6: neonx1 xor() 8772 MB/s Oct 31 00:48:43.004099 kernel: raid6: int64x8 gen() 6834 MB/s Oct 31 00:48:43.021095 kernel: raid6: int64x8 xor() 3798 MB/s Oct 31 00:48:43.038096 kernel: raid6: int64x4 gen() 6900 MB/s Oct 31 00:48:43.055096 kernel: raid6: int64x4 xor() 3828 MB/s Oct 31 00:48:43.072101 kernel: raid6: int64x2 gen() 6183 MB/s Oct 31 00:48:43.089108 kernel: raid6: int64x2 xor() 3319 MB/s Oct 31 00:48:43.106105 kernel: raid6: int64x1 gen() 5006 MB/s Oct 31 00:48:43.123351 kernel: raid6: int64x1 xor() 2603 MB/s Oct 31 00:48:43.123376 kernel: raid6: using algorithm neonx8 gen() 13765 MB/s Oct 31 00:48:43.123402 kernel: raid6: .... xor() 10740 MB/s, rmw enabled Oct 31 00:48:43.124580 kernel: raid6: using neon recovery algorithm Oct 31 00:48:43.134106 kernel: xor: measuring software checksum speed Oct 31 00:48:43.135102 kernel: 8regs : 17145 MB/sec Oct 31 00:48:43.136101 kernel: 32regs : 20770 MB/sec Oct 31 00:48:43.137658 kernel: arm64_neon : 29018 MB/sec Oct 31 00:48:43.137670 kernel: xor: using function: arm64_neon (29018 MB/sec) Oct 31 00:48:43.182115 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 31 00:48:43.192462 systemd[1]: Finished dracut pre-udev hook. Oct 31 00:48:43.193000 audit: BPF prog-id=6 op=LOAD Oct 31 00:48:43.194000 audit: BPF prog-id=7 op=LOAD Oct 31 00:48:43.195956 kernel: audit: type=1334 audit(1761871723.193:2): prog-id=6 op=LOAD Oct 31 00:48:43.195979 kernel: audit: type=1334 audit(1761871723.194:3): prog-id=7 op=LOAD Oct 31 00:48:43.195991 kernel: audit: type=1334 audit(1761871723.195:4): prog-id=8 op=LOAD Oct 31 00:48:43.195000 audit: BPF prog-id=8 op=LOAD Oct 31 00:48:43.196370 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 31 00:48:43.208426 systemd-udevd[409]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 31 00:48:43.210383 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 31 00:48:43.213861 systemd[1]: Starting dracut pre-trigger hook... Oct 31 00:48:43.224606 dracut-pre-trigger[421]: rd.md=0: removing MD RAID activation Oct 31 00:48:43.247165 systemd[1]: Finished dracut pre-trigger hook. Oct 31 00:48:43.248626 systemd[1]: Starting Coldplug All udev Devices... Oct 31 00:48:43.258238 systemd-udevd[409]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 31 00:48:43.278367 systemd[1]: Finished Coldplug All udev Devices. Oct 31 00:48:43.279942 systemd[1]: Starting dracut initqueue hook... Oct 31 00:48:43.293715 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 31 00:48:43.293858 kernel: vda: detected capacity change from 0 to 4756340736 Oct 31 00:48:43.308890 systemd-udevd[467]: Using default interface naming scheme 'v249'. Oct 31 00:48:43.310215 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 00:48:43.331940 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 31 00:48:43.335107 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (463) Oct 31 00:48:43.335088 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 31 00:48:43.339173 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 31 00:48:43.349769 systemd[1]: Finished dracut initqueue hook. Oct 31 00:48:43.352812 systemd[1]: Reached target Initrd Root Device. Oct 31 00:48:43.354631 systemd[1]: Reached target Preparation for Remote File Systems. Oct 31 00:48:43.358120 systemd[1]: Reached target Remote Encrypted Volumes. Oct 31 00:48:43.361296 systemd[1]: Reached target Remote File Systems. Oct 31 00:48:43.365916 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 31 00:48:43.367769 systemd[1]: Starting dracut pre-mount hook... Oct 31 00:48:43.370450 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 31 00:48:43.370571 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 31 00:48:43.372614 systemd[1]: Reached target Preparation for Local File Systems. Oct 31 00:48:43.375246 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 31 00:48:43.376587 systemd[1]: Finished dracut pre-mount hook. Oct 31 00:48:43.378823 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 31 00:48:43.389336 systemd-fsck[501]: ROOT: clean, 640/553792 files, 39961/553472 blocks Oct 31 00:48:43.391982 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 31 00:48:43.394289 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 31 00:48:43.396532 systemd[1]: Mounting /sysroot... Oct 31 00:48:43.413357 systemd[1]: Found device /dev/mapper/usr. Oct 31 00:48:43.415536 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 31 00:48:43.418776 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 31 00:48:43.426324 systemd-fsck[514]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 31 00:48:43.426324 systemd-fsck[514]: You must have r/w access to the filesystem or be root Oct 31 00:48:43.427370 systemd-fsck[511]: fsck failed with exit status 8. Oct 31 00:48:43.427374 systemd-fsck[511]: Ignoring error. Oct 31 00:48:43.428266 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 31 00:48:43.434502 systemd[1]: Mounting /sysusr/usr... Oct 31 00:48:43.446850 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 31 00:48:43.446895 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 31 00:48:43.446905 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 31 00:48:43.447802 systemd[1]: Mounted /sysusr/usr. Oct 31 00:48:43.448652 systemd[1]: Reached target Local File Systems. Oct 31 00:48:43.450042 systemd[1]: Reached target System Initialization. Oct 31 00:48:43.451726 systemd[1]: Reached target Basic System. Oct 31 00:48:43.453371 systemd[1]: Mounted /sysroot. Oct 31 00:48:43.454463 systemd[1]: Reached target Initrd Root File System. Oct 31 00:48:43.457017 systemd[1]: Mounting /sysroot/usr... Oct 31 00:48:43.458390 systemd[1]: Starting Reload Configuration from the Real Root... Oct 31 00:48:43.460032 systemd[1]: Mounted /sysroot/usr. Oct 31 00:48:43.462716 systemd[1]: Reloading. Oct 31 00:48:43.464000 audit: BPF prog-id=6 op=UNLOAD Oct 31 00:48:43.466102 kernel: audit: type=1334 audit(1761871723.464:5): prog-id=6 op=UNLOAD Oct 31 00:48:43.471000 audit: BPF prog-id=3 op=UNLOAD Oct 31 00:48:43.473110 kernel: audit: type=1334 audit(1761871723.471:6): prog-id=3 op=UNLOAD Oct 31 00:48:43.549000 audit: BPF prog-id=9 op=LOAD Oct 31 00:48:43.550000 audit: BPF prog-id=10 op=LOAD Oct 31 00:48:43.551466 kernel: audit: type=1334 audit(1761871723.549:7): prog-id=9 op=LOAD Oct 31 00:48:43.551490 kernel: audit: type=1334 audit(1761871723.550:8): prog-id=10 op=LOAD Oct 31 00:48:43.551499 kernel: audit: type=1334 audit(1761871723.551:9): prog-id=11 op=LOAD Oct 31 00:48:43.551000 audit: BPF prog-id=11 op=LOAD Oct 31 00:48:43.552422 kernel: audit: type=1334 audit(1761871723.551:10): prog-id=7 op=UNLOAD Oct 31 00:48:43.551000 audit: BPF prog-id=7 op=UNLOAD Oct 31 00:48:43.551000 audit: BPF prog-id=8 op=UNLOAD Oct 31 00:48:43.552000 audit: BPF prog-id=12 op=LOAD Oct 31 00:48:43.553000 audit: BPF prog-id=13 op=LOAD Oct 31 00:48:43.553000 audit: BPF prog-id=14 op=LOAD Oct 31 00:48:43.553000 audit: BPF prog-id=4 op=UNLOAD Oct 31 00:48:43.553000 audit: BPF prog-id=5 op=UNLOAD Oct 31 00:48:43.567813 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 31 00:48:43.567936 systemd[1]: Finished Reload Configuration from the Real Root. Oct 31 00:48:43.570069 systemd[1]: Reached target Initrd File Systems. Oct 31 00:48:43.571832 systemd[1]: Reached target Initrd Default Target. Oct 31 00:48:43.573561 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 31 00:48:43.574246 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 31 00:48:43.584172 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 31 00:48:43.585793 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 31 00:48:43.592308 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 31 00:48:43.593359 systemd[1]: Stopped target Timer Units. Oct 31 00:48:43.594846 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 31 00:48:43.594968 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 31 00:48:43.596466 systemd[1]: Stopped target Initrd Default Target. Oct 31 00:48:43.598000 systemd[1]: Stopped target Basic System. Oct 31 00:48:43.599572 systemd[1]: Stopped target Initrd Root Device. Oct 31 00:48:43.601042 systemd[1]: Stopped target Path Units. Oct 31 00:48:43.602403 systemd[1]: Stopped target Remote File Systems. Oct 31 00:48:43.603978 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 31 00:48:43.605619 systemd[1]: Stopped target Slice Units. Oct 31 00:48:43.607011 systemd[1]: Stopped target Socket Units. Oct 31 00:48:43.608658 systemd[1]: Stopped target System Initialization. Oct 31 00:48:43.610030 systemd[1]: Stopped target Local File Systems. Oct 31 00:48:43.611471 systemd[1]: Stopped target Preparation for Local File Systems. Oct 31 00:48:43.613207 systemd[1]: Stopped target Swaps. Oct 31 00:48:43.614561 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 31 00:48:43.614679 systemd[1]: Stopped dracut pre-mount hook. Oct 31 00:48:43.616199 systemd[1]: Stopped target Local Encrypted Volumes. Oct 31 00:48:43.617557 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 31 00:48:43.621173 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 31 00:48:43.622314 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 31 00:48:43.622420 systemd[1]: Stopped dracut initqueue hook. Oct 31 00:48:43.627753 iscsid[377]: iscsid shutting down. Oct 31 00:48:43.624208 systemd[1]: Stopping Open-iSCSI... Oct 31 00:48:43.627006 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 31 00:48:43.627150 systemd[1]: Stopped Apply Kernel Variables. Oct 31 00:48:43.628597 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 31 00:48:43.628686 systemd[1]: Stopped Coldplug All udev Devices. Oct 31 00:48:43.629980 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 31 00:48:43.630067 systemd[1]: Stopped dracut pre-trigger hook. Oct 31 00:48:43.631437 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 31 00:48:43.636007 systemd[1]: iscsid.service: Deactivated successfully. Oct 31 00:48:43.636166 systemd[1]: Stopped Open-iSCSI. Oct 31 00:48:43.637927 systemd[1]: iscsid.socket: Deactivated successfully. Oct 31 00:48:43.637987 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 31 00:48:43.639147 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 31 00:48:43.641043 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 31 00:48:43.651000 audit: BPF prog-id=9 op=UNLOAD Oct 31 00:48:43.641205 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 31 00:48:43.642637 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 31 00:48:43.642741 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 31 00:48:43.644468 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 31 00:48:43.644587 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 31 00:48:43.646949 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 31 00:48:43.646995 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 31 00:48:43.648457 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 31 00:48:43.648496 systemd[1]: Closed udev Control Socket. Oct 31 00:48:43.649852 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 31 00:48:43.649885 systemd[1]: Closed udev Kernel Socket. Oct 31 00:48:43.651404 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 31 00:48:43.651449 systemd[1]: Stopped dracut pre-udev hook. Oct 31 00:48:43.652921 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 31 00:48:43.652961 systemd[1]: Stopped dracut cmdline hook. Oct 31 00:48:43.654502 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 31 00:48:43.654543 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 31 00:48:43.656931 systemd[1]: Starting Cleanup udev Database... Oct 31 00:48:43.657790 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 31 00:48:43.659528 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 31 00:48:43.678000 audit: BPF prog-id=12 op=UNLOAD Oct 31 00:48:43.659582 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 31 00:48:43.661313 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 31 00:48:43.661362 systemd[1]: Stopped Create List of Static Device Nodes. Oct 31 00:48:43.663011 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 00:48:43.663051 systemd[1]: Stopped Setup Virtual Console. Oct 31 00:48:43.664684 systemd[1]: rngd.service: Deactivated successfully. Oct 31 00:48:43.664796 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 31 00:48:43.666475 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 31 00:48:43.666578 systemd[1]: Finished Cleanup udev Database. Oct 31 00:48:43.667873 systemd[1]: Reached target Switch Root. Oct 31 00:48:43.670169 systemd[1]: Starting Switch Root... Oct 31 00:48:43.676327 systemd[1]: Switching root. Oct 31 00:48:43.691368 systemd-journald[268]: Journal stopped Oct 31 00:48:45.749438 systemd-journald[268]: Received SIGTERM from PID 1 (systemd). Oct 31 00:48:45.749490 kernel: SELinux: policy capability network_peer_controls=1 Oct 31 00:48:45.749502 kernel: SELinux: policy capability open_perms=1 Oct 31 00:48:45.749511 kernel: SELinux: policy capability extended_socket_class=1 Oct 31 00:48:45.749521 kernel: SELinux: policy capability always_check_network=0 Oct 31 00:48:45.749532 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 31 00:48:45.749541 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 31 00:48:45.749550 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 31 00:48:45.749559 systemd[1]: Successfully loaded SELinux policy in 30.712ms. Oct 31 00:48:45.749574 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.686ms. Oct 31 00:48:45.749585 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 31 00:48:45.749596 systemd[1]: Detected virtualization kvm. Oct 31 00:48:45.749605 systemd[1]: Detected architecture arm64. Oct 31 00:48:45.749616 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 31 00:48:45.749628 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 31 00:48:45.749638 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 31 00:48:45.749648 systemd[1]: Stopped Switch Root. Oct 31 00:48:45.749657 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 31 00:48:45.749666 systemd[1]: Created slice Slice /system/addon-config. Oct 31 00:48:45.749676 systemd[1]: Created slice Slice /system/addon-run. Oct 31 00:48:45.749685 systemd[1]: Created slice Slice /system/getty. Oct 31 00:48:45.749695 systemd[1]: Created slice Slice /system/modprobe. Oct 31 00:48:45.749706 systemd[1]: Created slice Slice /system/serial-getty. Oct 31 00:48:45.749716 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 31 00:48:45.749725 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 31 00:48:45.749735 systemd[1]: Created slice User and Session Slice. Oct 31 00:48:45.749744 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 31 00:48:45.749754 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 31 00:48:45.749764 systemd[1]: Set up automount Boot partition Automount Point. Oct 31 00:48:45.749773 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 31 00:48:45.749782 systemd[1]: Stopped target Switch Root. Oct 31 00:48:45.749794 systemd[1]: Stopped target Initrd File Systems. Oct 31 00:48:45.749805 systemd[1]: Stopped target Initrd Root File System. Oct 31 00:48:45.749815 systemd[1]: Reached target Remote Encrypted Volumes. Oct 31 00:48:45.749825 systemd[1]: Reached target Remote File Systems. Oct 31 00:48:45.749835 systemd[1]: Reached target Slice Units. Oct 31 00:48:45.749844 systemd[1]: Reached target Swaps. Oct 31 00:48:45.749853 systemd[1]: Reached target Verify torcx succeeded. Oct 31 00:48:45.749863 systemd[1]: Reached target Local Verity Protected Volumes. Oct 31 00:48:45.749874 systemd[1]: Listening on Process Core Dump Socket. Oct 31 00:48:45.749883 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 31 00:48:45.749894 systemd[1]: Listening on Network Service Netlink Socket. Oct 31 00:48:45.749904 systemd[1]: Listening on udev Control Socket. Oct 31 00:48:45.749914 systemd[1]: Listening on udev Kernel Socket. Oct 31 00:48:45.749923 systemd[1]: Mounting Huge Pages File System... Oct 31 00:48:45.749933 systemd[1]: Mounting POSIX Message Queue File System... Oct 31 00:48:45.749942 systemd[1]: Mounting External Media Directory... Oct 31 00:48:45.749953 systemd[1]: Mounting Kernel Debug File System... Oct 31 00:48:45.749962 systemd[1]: Mounting Kernel Trace File System... Oct 31 00:48:45.749972 systemd[1]: Mounting Temporary Directory /tmp... Oct 31 00:48:45.749981 systemd[1]: Starting Create missing system files... Oct 31 00:48:45.749992 systemd[1]: Starting Create List of Static Device Nodes... Oct 31 00:48:45.750002 systemd[1]: Starting Load Kernel Module configfs... Oct 31 00:48:45.750012 systemd[1]: Starting Load Kernel Module drm... Oct 31 00:48:45.750021 systemd[1]: Starting Load Kernel Module fuse... Oct 31 00:48:45.750030 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 31 00:48:45.750040 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 31 00:48:45.750049 systemd[1]: Stopped File System Check on Root Device. Oct 31 00:48:45.750059 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 31 00:48:45.750068 systemd[1]: Stopped systemd-fsck-usr.service. Oct 31 00:48:45.750085 kernel: fuse: init (API version 7.32) Oct 31 00:48:45.750096 systemd[1]: Stopped Journal Service. Oct 31 00:48:45.750105 systemd[1]: Starting Journal Service... Oct 31 00:48:45.750115 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 31 00:48:45.750124 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 31 00:48:45.750133 systemd[1]: Starting Apply Kernel Variables... Oct 31 00:48:45.750149 systemd[1]: Starting Coldplug All udev Devices... Oct 31 00:48:45.750160 systemd[1]: verity-setup.service: Deactivated successfully. Oct 31 00:48:45.750170 systemd[1]: Stopped verity-setup.service. Oct 31 00:48:45.750180 systemd-journald[690]: Journal started Oct 31 00:48:45.750218 systemd-journald[690]: Runtime Journal (/run/log/journal/e6bb737c0f5e4438bdeb343d8ca8882b) is 6.0M, max 48.5M, 42.4M free. Oct 31 00:48:43.763000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 31 00:48:43.792000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 31 00:48:43.792000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 31 00:48:43.792000 audit: BPF prog-id=15 op=LOAD Oct 31 00:48:43.792000 audit: BPF prog-id=15 op=UNLOAD Oct 31 00:48:43.792000 audit: BPF prog-id=16 op=LOAD Oct 31 00:48:43.792000 audit: BPF prog-id=16 op=UNLOAD Oct 31 00:48:43.826000 audit[642]: AVC avc: denied { associate } for pid=642 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 31 00:48:45.623000 audit: BPF prog-id=17 op=LOAD Oct 31 00:48:45.623000 audit: BPF prog-id=18 op=LOAD Oct 31 00:48:45.623000 audit: BPF prog-id=19 op=LOAD Oct 31 00:48:45.623000 audit: BPF prog-id=13 op=UNLOAD Oct 31 00:48:45.623000 audit: BPF prog-id=14 op=UNLOAD Oct 31 00:48:45.634000 audit: BPF prog-id=20 op=LOAD Oct 31 00:48:45.634000 audit: BPF prog-id=17 op=UNLOAD Oct 31 00:48:45.634000 audit: BPF prog-id=21 op=LOAD Oct 31 00:48:45.634000 audit: BPF prog-id=22 op=LOAD Oct 31 00:48:45.634000 audit: BPF prog-id=18 op=UNLOAD Oct 31 00:48:45.634000 audit: BPF prog-id=19 op=UNLOAD Oct 31 00:48:45.635000 audit: BPF prog-id=23 op=LOAD Oct 31 00:48:45.635000 audit: BPF prog-id=20 op=UNLOAD Oct 31 00:48:45.635000 audit: BPF prog-id=24 op=LOAD Oct 31 00:48:45.635000 audit: BPF prog-id=25 op=LOAD Oct 31 00:48:45.635000 audit: BPF prog-id=21 op=UNLOAD Oct 31 00:48:45.635000 audit: BPF prog-id=22 op=UNLOAD Oct 31 00:48:45.651000 audit: BPF prog-id=23 op=UNLOAD Oct 31 00:48:45.735000 audit: BPF prog-id=26 op=LOAD Oct 31 00:48:45.735000 audit: BPF prog-id=27 op=LOAD Oct 31 00:48:45.735000 audit: BPF prog-id=28 op=LOAD Oct 31 00:48:45.735000 audit: BPF prog-id=24 op=UNLOAD Oct 31 00:48:45.735000 audit: BPF prog-id=25 op=UNLOAD Oct 31 00:48:45.747000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 31 00:48:45.622398 systemd[1]: Queued start job for default target Multi-User System. Oct 31 00:48:43.824639 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 31 00:48:45.636283 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 31 00:48:43.824887 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 31 00:48:43.824905 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 31 00:48:43.825211 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 31 00:48:43.825223 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 31 00:48:43.825256 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 31 00:48:43.825269 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 31 00:48:43.825584 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 31 00:48:43.825618 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 31 00:48:43.825633 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 31 00:48:43.825950 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 31 00:48:43.825980 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 31 00:48:43.825999 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 31 00:48:43.826014 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 31 00:48:43.826103 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 31 00:48:43.826118 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:43Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 31 00:48:45.537552 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:45Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 00:48:45.537822 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:45Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 00:48:45.537917 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:45Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 00:48:45.538038 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:45Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 00:48:45.538159 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:45Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 31 00:48:45.538219 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-31T00:48:45Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 31 00:48:45.754704 systemd[1]: Started Journal Service. Oct 31 00:48:45.755212 systemd[1]: Mounted Huge Pages File System. Oct 31 00:48:45.756075 systemd[1]: Mounted POSIX Message Queue File System. Oct 31 00:48:45.757049 systemd[1]: Mounted External Media Directory. Oct 31 00:48:45.758008 systemd[1]: Mounted Kernel Debug File System. Oct 31 00:48:45.758989 systemd[1]: Mounted Kernel Trace File System. Oct 31 00:48:45.759945 systemd[1]: Mounted Temporary Directory /tmp. Oct 31 00:48:45.761165 systemd[1]: Finished Create missing system files. Oct 31 00:48:45.762361 systemd[1]: Finished Create List of Static Device Nodes. Oct 31 00:48:45.763576 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 31 00:48:45.763751 systemd[1]: Finished Load Kernel Module configfs. Oct 31 00:48:45.764839 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 00:48:45.765009 systemd[1]: Finished Load Kernel Module drm. Oct 31 00:48:45.766115 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 31 00:48:45.766300 systemd[1]: Finished Load Kernel Module fuse. Oct 31 00:48:45.779588 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 31 00:48:45.780899 systemd[1]: Finished Apply Kernel Variables. Oct 31 00:48:45.783746 systemd[1]: Mounting FUSE Control File System... Oct 31 00:48:45.785566 systemd[1]: Mounting Kernel Configuration File System... Oct 31 00:48:45.786480 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 31 00:48:45.787806 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 31 00:48:45.788732 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 31 00:48:45.790007 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 31 00:48:45.790896 systemd[1]: Starting Load/Save Random Seed... Oct 31 00:48:45.794470 systemd-journald[690]: Time spent on flushing to /var/log/journal/e6bb737c0f5e4438bdeb343d8ca8882b is 11.512ms for 666 entries. Oct 31 00:48:45.794470 systemd-journald[690]: System Journal (/var/log/journal/e6bb737c0f5e4438bdeb343d8ca8882b) is 8.0M, max 203.0M, 195.0M free. Oct 31 00:48:45.811000 audit: BPF prog-id=29 op=LOAD Oct 31 00:48:45.812000 audit: BPF prog-id=30 op=LOAD Oct 31 00:48:45.812000 audit: BPF prog-id=31 op=LOAD Oct 31 00:48:45.812000 audit: BPF prog-id=10 op=UNLOAD Oct 31 00:48:45.812000 audit: BPF prog-id=11 op=UNLOAD Oct 31 00:48:45.791840 systemd[1]: Condition check resulted in Create System Users being skipped. Oct 31 00:48:45.792732 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 31 00:48:45.794649 systemd[1]: Mounted FUSE Control File System. Oct 31 00:48:45.797105 systemd[1]: Mounted Kernel Configuration File System. Oct 31 00:48:45.800389 systemd[1]: Finished Load/Save Random Seed. Oct 31 00:48:45.801736 systemd[1]: Condition check resulted in First Boot Complete being skipped. Oct 31 00:48:45.803754 systemd[1]: Finished Coldplug All udev Devices. Oct 31 00:48:45.808926 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 31 00:48:45.810333 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 31 00:48:45.812693 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 31 00:48:45.820943 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 31 00:48:45.838116 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 31 00:48:45.840000 audit: BPF prog-id=32 op=LOAD Oct 31 00:48:45.841295 systemd[1]: Starting Network Configuration... Oct 31 00:48:45.869243 systemd-networkd[712]: lo: Link UP Oct 31 00:48:45.869252 systemd-networkd[712]: lo: Gained carrier Oct 31 00:48:45.869556 systemd-networkd[712]: Enumeration completed Oct 31 00:48:45.869633 systemd[1]: Started Network Configuration. Oct 31 00:48:45.871664 systemd-udevd[716]: Using default interface naming scheme 'v249'. Oct 31 00:48:45.895897 systemd-networkd[712]: eth0: Link UP Oct 31 00:48:45.900119 systemd-networkd[712]: eth0: Gained carrier Oct 31 00:48:45.904322 systemd-networkd[712]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 31 00:48:45.904558 udevadm[702]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 31 00:48:45.929171 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 31 00:48:45.940019 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 31 00:48:45.951104 lvm[731]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 00:48:45.975060 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 31 00:48:45.976176 systemd[1]: Reached target Local Encrypted Volumes. Oct 31 00:48:45.988009 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 31 00:48:45.991407 lvm[732]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 00:48:46.021206 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 31 00:48:46.022265 systemd[1]: Reached target Preparation for Local File Systems. Oct 31 00:48:46.023299 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 31 00:48:46.023322 systemd[1]: Reached target Containers. Oct 31 00:48:46.039318 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 31 00:48:46.051075 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 31 00:48:46.053294 systemd[1]: Mounting /usr/share/oem... Oct 31 00:48:46.059203 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 31 00:48:46.059254 kernel: BTRFS info (device vda6): has skinny extents Oct 31 00:48:46.061369 systemd[1]: Mounted /usr/share/oem. Oct 31 00:48:46.062220 systemd[1]: Reached target Local File Systems. Oct 31 00:48:46.063159 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Oct 31 00:48:46.063504 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 31 00:48:46.063541 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 31 00:48:46.063569 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 31 00:48:46.064532 systemd[1]: Starting Create Volatile Files and Directories... Oct 31 00:48:46.070569 systemd-tmpfiles[755]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 31 00:48:46.071616 systemd-tmpfiles[755]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 31 00:48:46.101782 systemd-tmpfiles[755]: Detected autofs mount point /boot during canonicalization of /boot. Oct 31 00:48:46.101794 systemd-tmpfiles[755]: Skipping /boot Oct 31 00:48:46.107897 systemd-tmpfiles[755]: Detected autofs mount point /boot during canonicalization of /boot. Oct 31 00:48:46.107914 systemd-tmpfiles[755]: Skipping /boot Oct 31 00:48:46.136607 systemd[1]: Finished Create Volatile Files and Directories. Oct 31 00:48:46.146103 systemd[1]: Starting Load Security Auditing Rules... Oct 31 00:48:46.147932 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 31 00:48:46.149174 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Oct 31 00:48:46.149000 audit: BPF prog-id=33 op=LOAD Oct 31 00:48:46.150576 systemd[1]: Starting Network Name Resolution... Oct 31 00:48:46.151000 audit: BPF prog-id=34 op=LOAD Oct 31 00:48:46.152753 systemd[1]: Starting Network Time Synchronization... Oct 31 00:48:46.153769 systemd[1]: Condition check resulted in Update is Completed being skipped. Oct 31 00:48:46.154671 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 31 00:48:46.156153 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 31 00:48:46.157490 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 31 00:48:46.169452 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 31 00:48:46.177000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 31 00:48:46.177443 augenrules[777]: No rules Oct 31 00:48:46.182487 systemd[1]: Finished Load Security Auditing Rules. Oct 31 00:48:46.189354 systemd-timesyncd[765]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 31 00:48:46.192094 systemd-resolved[759]: Positive Trust Anchors: Oct 31 00:48:46.192101 systemd-resolved[759]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 00:48:46.192128 systemd-resolved[759]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 31 00:48:46.192969 systemd-resolved[759]: Defaulting to hostname 'linux'. Oct 31 00:48:46.198216 systemd[1]: Started Network Time Synchronization. Oct 31 00:48:46.199313 systemd[1]: Started Network Name Resolution. Oct 31 00:48:46.200215 systemd[1]: Reached target Network. Oct 31 00:48:46.200912 systemd[1]: Reached target Host and Network Name Lookups. Oct 31 00:48:46.201937 systemd[1]: Reached target System Initialization. Oct 31 00:48:46.202974 systemd[1]: Started Watch for update engine configuration changes. Oct 31 00:48:46.204161 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 31 00:48:46.205316 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 31 00:48:46.206429 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 31 00:48:46.206468 systemd[1]: Reached target Path Units. Oct 31 00:48:46.207321 systemd[1]: Reached target System Time Set. Oct 31 00:48:46.208546 systemd[1]: Started Daily Log Rotation. Oct 31 00:48:46.209455 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 31 00:48:46.210651 systemd[1]: Reached target Timer Units. Oct 31 00:48:46.211927 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 31 00:48:46.221749 systemd[1]: Starting Docker Socket for the API... Oct 31 00:48:46.225005 systemd[1]: Listening on OpenSSH Server Socket. Oct 31 00:48:46.226350 systemd[1]: Listening on Docker Socket for the API. Oct 31 00:48:46.227295 systemd[1]: Reached target Socket Units. Oct 31 00:48:46.228049 systemd[1]: Reached target Basic System. Oct 31 00:48:46.228849 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 31 00:48:46.228880 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 31 00:48:46.229816 systemd[1]: Started D-Bus System Message Bus. Oct 31 00:48:46.232919 systemd[1]: Starting Extend Filesystems... Oct 31 00:48:46.233799 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 31 00:48:46.234733 systemd[1]: Starting Generate /run/flatcar/motd... Oct 31 00:48:46.236531 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 31 00:48:46.238541 systemd[1]: Starting Generate sshd host keys... Oct 31 00:48:46.239406 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 31 00:48:46.239436 systemd[1]: Reached target Load system-provided cloud configs. Oct 31 00:48:46.242706 systemd[1]: Starting User Login Management... Oct 31 00:48:46.244198 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 31 00:48:46.244771 systemd[1]: Starting Update Engine... Oct 31 00:48:46.245570 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 31 00:48:46.245599 systemd[1]: Reached target Load user-provided cloud configs. Oct 31 00:48:46.247515 systemd[1]: motdgen.service: Deactivated successfully. Oct 31 00:48:46.247732 systemd[1]: Finished Generate /run/flatcar/motd. Oct 31 00:48:46.249102 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 31 00:48:46.249314 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 31 00:48:46.252301 extend-filesystems[786]: Found vda Oct 31 00:48:46.258106 extend-filesystems[786]: Found vda1 Oct 31 00:48:46.258106 extend-filesystems[786]: Found vda2 Oct 31 00:48:46.258106 extend-filesystems[786]: Found vda3 Oct 31 00:48:46.258106 extend-filesystems[786]: Found usr Oct 31 00:48:46.258106 extend-filesystems[786]: Found vda4 Oct 31 00:48:46.258106 extend-filesystems[786]: Found vda6 Oct 31 00:48:46.258106 extend-filesystems[786]: Found vda7 Oct 31 00:48:46.258106 extend-filesystems[786]: Found vda9 Oct 31 00:48:46.258106 extend-filesystems[786]: Checking size of /dev/vda9 Oct 31 00:48:46.282104 extend-filesystems[786]: Old size kept for /dev/vda9 Oct 31 00:48:46.282411 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 31 00:48:46.282620 systemd[1]: Finished Extend Filesystems. Oct 31 00:48:46.288872 systemd-logind[800]: Watching system buttons on /dev/input/event0 (Power Button) Oct 31 00:48:46.290407 systemd-logind[800]: New seat seat0. Oct 31 00:48:46.295259 systemd[1]: Started User Login Management. Oct 31 00:48:46.305053 update_engine[801]: I1031 00:48:46.304737 801 main.cc:89] Flatcar Update Engine starting Oct 31 00:48:46.305571 update_engine[801]: I1031 00:48:46.305552 801 payload_state.cc:360] Current Response Signature = Oct 31 00:48:46.305571 update_engine[801]: NumURLs = 1 Oct 31 00:48:46.305571 update_engine[801]: Url0 = http://10.0.0.4:34567/packages/update.gz Oct 31 00:48:46.305571 update_engine[801]: Payload Size = 435657005 Oct 31 00:48:46.305571 update_engine[801]: Payload Sha256 Hash = 0Tv8tyv9InfnN/xh6P9vNO30csjNJVYNFdwS7QDmeqo= Oct 31 00:48:46.305571 update_engine[801]: Is Delta Payload = 0 Oct 31 00:48:46.305571 update_engine[801]: Max Failure Count Per Url = 10 Oct 31 00:48:46.305571 update_engine[801]: Disable Payload Backoff = 1 Oct 31 00:48:46.305809 update_engine[801]: I1031 00:48:46.305753 801 payload_state.cc:381] Payload Attempt Number = 0 Oct 31 00:48:46.305951 update_engine[801]: I1031 00:48:46.305939 801 payload_state.cc:404] Current URL Index = 0 Oct 31 00:48:46.306011 systemd[1]: Finished Generate sshd host keys. Oct 31 00:48:46.306176 update_engine[801]: I1031 00:48:46.306164 801 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 31 00:48:46.306397 update_engine[801]: I1031 00:48:46.306368 801 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 31 00:48:46.308225 update_engine[801]: I1031 00:48:46.308195 801 update_check_scheduler.cc:74] Next update check in 11m57s Oct 31 00:48:46.314028 systemd[1]: Starting Generate /run/issue... Oct 31 00:48:46.314976 systemd[1]: Started Update Engine. Oct 31 00:48:46.318219 systemd[1]: issuegen.service: Deactivated successfully. Oct 31 00:48:46.318427 systemd[1]: Finished Generate /run/issue. Oct 31 00:48:46.320419 systemd[1]: Starting Permit User Sessions... Oct 31 00:48:46.324835 systemd[1]: Finished Permit User Sessions. Oct 31 00:48:46.326917 systemd[1]: Started Getty on tty1. Oct 31 00:48:46.328807 systemd[1]: Started Serial Getty on ttyAMA0. Oct 31 00:48:46.329844 systemd[1]: Reached target Login Prompts. Oct 31 00:48:46.330759 systemd[1]: Reached target Multi-User System. Oct 31 00:48:46.332670 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 31 00:48:46.337480 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 31 00:48:46.337696 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 31 00:48:46.338822 systemd[1]: Startup finished in 727ms (kernel) + 1.172s (initrd) + 2.608s (userspace) = 4.508s. Oct 31 00:48:46.923993 systemd[1]: Created slice Slice /system/sshd. Oct 31 00:48:46.924949 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49512). Oct 31 00:48:46.961834 sshd[828]: Accepted publickey for core from 10.0.0.1 port 49512 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:46.963473 sshd[828]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:46.990527 systemd[1]: Created slice User Slice of UID 500. Oct 31 00:48:46.991456 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 31 00:48:46.993047 systemd-logind[800]: New session 1 of user core. Oct 31 00:48:46.997429 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 31 00:48:46.998583 systemd[1]: Starting User Manager for UID 500... Oct 31 00:48:47.001070 systemd[831]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:47.048165 systemd[831]: Queued start job for default target Main User Target. Oct 31 00:48:47.048307 systemd[831]: Reached target Paths. Oct 31 00:48:47.048321 systemd[831]: Reached target Sockets. Oct 31 00:48:47.048332 systemd[831]: Reached target Timers. Oct 31 00:48:47.048342 systemd[831]: Reached target Basic System. Oct 31 00:48:47.048382 systemd[831]: Reached target Main User Target. Oct 31 00:48:47.048395 systemd[831]: Startup finished in 43ms. Oct 31 00:48:47.048793 systemd[1]: Started User Manager for UID 500. Oct 31 00:48:47.060357 systemd[1]: Started Session 1 of User core. Oct 31 00:48:47.121256 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49530). Oct 31 00:48:47.156089 sshd[840]: Accepted publickey for core from 10.0.0.1 port 49530 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:47.157196 sshd[840]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:47.159938 systemd-logind[800]: New session 2 of user core. Oct 31 00:48:47.171269 systemd[1]: Started Session 2 of User core. Oct 31 00:48:47.225322 sshd[840]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:47.233806 systemd[1]: sshd@1-10.0.0.5:22-10.0.0.1:49530.service: Deactivated successfully. Oct 31 00:48:47.234466 systemd[1]: session-2.scope: Deactivated successfully. Oct 31 00:48:47.235075 systemd-logind[800]: Session 2 logged out. Waiting for processes to exit. Oct 31 00:48:47.236057 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49540). Oct 31 00:48:47.236691 systemd-logind[800]: Removed session 2. Oct 31 00:48:47.269978 sshd[846]: Accepted publickey for core from 10.0.0.1 port 49540 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:47.271281 sshd[846]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:47.273980 systemd-logind[800]: New session 3 of user core. Oct 31 00:48:47.283277 systemd[1]: Started Session 3 of User core. Oct 31 00:48:47.332221 sshd[846]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:47.341280 systemd[1]: sshd@2-10.0.0.5:22-10.0.0.1:49540.service: Deactivated successfully. Oct 31 00:48:47.341961 systemd[1]: session-3.scope: Deactivated successfully. Oct 31 00:48:47.342700 systemd-logind[800]: Session 3 logged out. Waiting for processes to exit. Oct 31 00:48:47.343786 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49542). Oct 31 00:48:47.344304 systemd-logind[800]: Removed session 3. Oct 31 00:48:47.378359 sshd[852]: Accepted publickey for core from 10.0.0.1 port 49542 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:47.379311 sshd[852]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:47.382058 systemd-logind[800]: New session 4 of user core. Oct 31 00:48:47.395298 systemd[1]: Started Session 4 of User core. Oct 31 00:48:47.456764 sshd[852]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:47.466285 systemd[1]: sshd@3-10.0.0.5:22-10.0.0.1:49542.service: Deactivated successfully. Oct 31 00:48:47.467176 systemd[1]: session-4.scope: Deactivated successfully. Oct 31 00:48:47.467953 systemd-logind[800]: Session 4 logged out. Waiting for processes to exit. Oct 31 00:48:47.469602 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49556). Oct 31 00:48:47.470315 systemd-logind[800]: Removed session 4. Oct 31 00:48:47.503182 sshd[859]: Accepted publickey for core from 10.0.0.1 port 49556 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:47.504326 sshd[859]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:47.507420 systemd-logind[800]: New session 5 of user core. Oct 31 00:48:47.515261 systemd[1]: Started Session 5 of User core. Oct 31 00:48:47.574211 sudo[862]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 31 00:48:47.574693 sudo[862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 00:48:47.586688 sudo[862]: pam_unix(sudo:session): session closed for user root Oct 31 00:48:47.588714 sshd[859]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:47.602011 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49570). Oct 31 00:48:47.604241 dbus-daemon[785]: [system] Reloaded configuration Oct 31 00:48:47.607012 systemd[1]: sshd@4-10.0.0.5:22-10.0.0.1:49556.service: Deactivated successfully. Oct 31 00:48:47.607681 systemd[1]: session-5.scope: Deactivated successfully. Oct 31 00:48:47.608330 systemd-logind[800]: Session 5 logged out. Waiting for processes to exit. Oct 31 00:48:47.609128 systemd-logind[800]: Removed session 5. Oct 31 00:48:47.637596 sshd[865]: Accepted publickey for core from 10.0.0.1 port 49570 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:47.638627 sshd[865]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:47.641561 systemd-logind[800]: New session 6 of user core. Oct 31 00:48:47.649301 systemd[1]: Started Session 6 of User core. Oct 31 00:48:47.703189 sudo[870]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 31 00:48:47.703399 sudo[870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 00:48:47.705713 sudo[870]: pam_unix(sudo:session): session closed for user root Oct 31 00:48:47.709391 sudo[869]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 31 00:48:47.709600 sudo[869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 00:48:47.728136 systemd[1]: Stopping Load Security Auditing Rules... Oct 31 00:48:47.728000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 31 00:48:47.729583 auditctl[873]: No rules Oct 31 00:48:47.729985 systemd[1]: audit-rules.service: Deactivated successfully. Oct 31 00:48:47.730232 systemd[1]: Stopped Load Security Auditing Rules. Oct 31 00:48:47.732142 kernel: kauditd_printk_skb: 49 callbacks suppressed Oct 31 00:48:47.732210 kernel: audit: type=1305 audit(1761871727.728:60): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 31 00:48:47.731567 systemd[1]: Starting Load Security Auditing Rules... Oct 31 00:48:47.746068 augenrules[890]: No rules Oct 31 00:48:47.746983 systemd[1]: Finished Load Security Auditing Rules. Oct 31 00:48:47.748316 sudo[869]: pam_unix(sudo:session): session closed for user root Oct 31 00:48:47.749649 sshd[865]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:47.752636 systemd[1]: sshd@5-10.0.0.5:22-10.0.0.1:49570.service: Deactivated successfully. Oct 31 00:48:47.753219 systemd[1]: session-6.scope: Deactivated successfully. Oct 31 00:48:47.754335 systemd-logind[800]: Session 6 logged out. Waiting for processes to exit. Oct 31 00:48:47.755313 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49578). Oct 31 00:48:47.756003 systemd-logind[800]: Removed session 6. Oct 31 00:48:47.789513 sshd[896]: Accepted publickey for core from 10.0.0.1 port 49578 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:47.790547 sshd[896]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:47.793138 kernel: audit: type=1006 audit(1761871727.789:61): pid=896 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=7 res=1 Oct 31 00:48:47.793514 systemd-logind[800]: New session 7 of user core. Oct 31 00:48:47.802280 systemd[1]: Started Session 7 of User core. Oct 31 00:48:47.852703 sshd[896]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:47.867148 systemd[1]: sshd@6-10.0.0.5:22-10.0.0.1:49578.service: Deactivated successfully. Oct 31 00:48:47.867869 systemd[1]: session-7.scope: Deactivated successfully. Oct 31 00:48:47.868580 systemd-logind[800]: Session 7 logged out. Waiting for processes to exit. Oct 31 00:48:47.869846 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49582). Oct 31 00:48:47.870596 systemd-logind[800]: Removed session 7. Oct 31 00:48:47.903816 sshd[902]: Accepted publickey for core from 10.0.0.1 port 49582 ssh2: RSA SHA256:bq+gehPWjz1A+9upyCJMuODzpiFomMih+uniC1j+4yA Oct 31 00:48:47.905485 sshd[902]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:48:47.907127 kernel: audit: type=1006 audit(1761871727.904:62): pid=902 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=8 res=1 Oct 31 00:48:47.908335 systemd-logind[800]: New session 8 of user core. Oct 31 00:48:47.921300 systemd[1]: Started Session 8 of User core. Oct 31 00:48:47.941366 systemd-networkd[712]: eth0: Gained IPv6LL Oct 31 00:48:47.971895 sshd[902]: pam_unix(sshd:session): session closed for user core Oct 31 00:48:47.973873 systemd[1]: session-8.scope: Deactivated successfully. Oct 31 00:48:47.974580 systemd-logind[800]: Session 8 logged out. Waiting for processes to exit. Oct 31 00:48:47.974747 systemd[1]: sshd@7-10.0.0.5:22-10.0.0.1:49582.service: Deactivated successfully. Oct 31 00:48:47.975754 systemd-logind[800]: Removed session 8.