Oct 31 13:46:08.696603 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 31 13:46:08.696620 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 13:46:08.696628 kernel: efi: EFI v2.70 by EDK II Oct 31 13:46:08.696633 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 31 13:46:08.696638 kernel: efi: seeding entropy pool Oct 31 13:46:08.696643 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 31 13:46:08.696650 kernel: ACPI: Early table checksum verification disabled Oct 31 13:46:08.696655 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 31 13:46:08.696660 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 31 13:46:08.696665 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:08.696671 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:08.696676 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:08.696681 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:08.696686 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:08.696693 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:08.696698 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:08.696704 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:08.696710 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:08.696715 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 31 13:46:08.696721 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 31 13:46:08.696726 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 13:46:08.696732 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 31 13:46:08.696737 kernel: Zone ranges: Oct 31 13:46:08.696742 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 13:46:08.696747 kernel: DMA32 empty Oct 31 13:46:08.696753 kernel: Normal empty Oct 31 13:46:08.696758 kernel: Movable zone start for each node Oct 31 13:46:08.696764 kernel: Early memory node ranges Oct 31 13:46:08.696770 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 31 13:46:08.696775 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 31 13:46:08.696780 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 31 13:46:08.696786 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 31 13:46:08.696791 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 31 13:46:08.696796 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 31 13:46:08.696801 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 31 13:46:08.696807 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 31 13:46:08.696812 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 31 13:46:08.696817 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 31 13:46:08.696822 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 31 13:46:08.696828 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 13:46:08.696837 kernel: On node 0 totalpages: 643072 Oct 31 13:46:08.696843 kernel: DMA zone: 10048 pages used for memmap Oct 31 13:46:08.696848 kernel: DMA zone: 0 pages reserved Oct 31 13:46:08.696854 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 31 13:46:08.696859 kernel: psci: probing for conduit method from ACPI. Oct 31 13:46:08.696865 kernel: psci: PSCIv1.1 detected in firmware. Oct 31 13:46:08.696871 kernel: psci: Using standard PSCI v0.2 function IDs Oct 31 13:46:08.696876 kernel: psci: Trusted OS migration not required Oct 31 13:46:08.696883 kernel: psci: SMC Calling Convention v1.1 Oct 31 13:46:08.696889 kernel: ACPI: SRAT not present Oct 31 13:46:08.696894 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 31 13:46:08.696900 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 31 13:46:08.696905 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 31 13:46:08.696911 kernel: Detected PIPT I-cache on CPU0 Oct 31 13:46:08.696917 kernel: CPU features: detected: GIC system register CPU interface Oct 31 13:46:08.696922 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 31 13:46:08.696928 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 31 13:46:08.696933 kernel: CPU features: detected: Hardware dirty bit management Oct 31 13:46:08.696939 kernel: CPU features: detected: Spectre-v4 Oct 31 13:46:08.696946 kernel: CPU features: detected: ARM erratum 1418040 Oct 31 13:46:08.696951 kernel: CPU features: detected: Spectre-BHB Oct 31 13:46:08.696957 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 31 13:46:08.696962 kernel: Policy zone: DMA Oct 31 13:46:08.696969 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 13:46:08.696975 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 31 13:46:08.696981 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 31 13:46:08.696986 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 31 13:46:08.696992 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 31 13:46:08.696998 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 31 13:46:08.697004 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 31 13:46:08.697011 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 31 13:46:08.697017 kernel: rcu: RCU event tracing is enabled. Oct 31 13:46:08.697023 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 31 13:46:08.697028 kernel: Trampoline variant of Tasks RCU enabled. Oct 31 13:46:08.697034 kernel: Tracing variant of Tasks RCU enabled. Oct 31 13:46:08.697040 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 31 13:46:08.697045 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 31 13:46:08.697051 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 31 13:46:08.697057 kernel: GICv3: 256 SPIs implemented Oct 31 13:46:08.697062 kernel: GICv3: 0 Extended SPIs implemented Oct 31 13:46:08.697068 kernel: GICv3: Distributor has no Range Selector support Oct 31 13:46:08.697073 kernel: GICv3: 16 PPIs implemented Oct 31 13:46:08.697080 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 31 13:46:08.697085 kernel: ACPI: SRAT not present Oct 31 13:46:08.697091 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 31 13:46:08.697096 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 31 13:46:08.697102 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 31 13:46:08.697108 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 31 13:46:08.697114 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 31 13:46:08.697119 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 13:46:08.697125 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 31 13:46:08.697131 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 31 13:46:08.697137 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 31 13:46:08.697143 kernel: arm-pv: using stolen time PV Oct 31 13:46:08.697150 kernel: Console: colour dummy device 80x25 Oct 31 13:46:08.697155 kernel: ACPI: Core revision 20200925 Oct 31 13:46:08.697161 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 31 13:46:08.697167 kernel: pid_max: default: 32768 minimum: 301 Oct 31 13:46:08.697173 kernel: LSM: Security Framework initializing Oct 31 13:46:08.697179 kernel: SELinux: Initializing. Oct 31 13:46:08.697185 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 13:46:08.697229 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 13:46:08.697236 kernel: rcu: Hierarchical SRCU implementation. Oct 31 13:46:08.697244 kernel: Platform MSI: ITS@0x8080000 domain created Oct 31 13:46:08.697250 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 31 13:46:08.697256 kernel: Remapping and enabling EFI services. Oct 31 13:46:08.697262 kernel: smp: Bringing up secondary CPUs ... Oct 31 13:46:08.697268 kernel: Detected PIPT I-cache on CPU1 Oct 31 13:46:08.697274 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 31 13:46:08.697280 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 31 13:46:08.697286 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 13:46:08.697291 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 31 13:46:08.697297 kernel: Detected PIPT I-cache on CPU2 Oct 31 13:46:08.697305 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 31 13:46:08.697311 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 31 13:46:08.697317 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 13:46:08.697322 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 31 13:46:08.697328 kernel: Detected PIPT I-cache on CPU3 Oct 31 13:46:08.697338 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 31 13:46:08.697345 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 31 13:46:08.697352 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 13:46:08.697358 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 31 13:46:08.697364 kernel: smp: Brought up 1 node, 4 CPUs Oct 31 13:46:08.697370 kernel: SMP: Total of 4 processors activated. Oct 31 13:46:08.697376 kernel: CPU features: detected: Privileged Access Never Oct 31 13:46:08.697382 kernel: CPU features: detected: LSE atomic instructions Oct 31 13:46:08.697388 kernel: CPU features: detected: User Access Override Oct 31 13:46:08.697394 kernel: CPU features: detected: 32-bit EL0 Support Oct 31 13:46:08.697400 kernel: CPU features: detected: Common not Private translations Oct 31 13:46:08.697406 kernel: CPU features: detected: RAS Extension Support Oct 31 13:46:08.697413 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 31 13:46:08.697420 kernel: CPU features: detected: CRC32 instructions Oct 31 13:46:08.697426 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 31 13:46:08.697432 kernel: CPU: All CPU(s) started at EL1 Oct 31 13:46:08.697438 kernel: alternatives: patching kernel code Oct 31 13:46:08.697445 kernel: devtmpfs: initialized Oct 31 13:46:08.697452 kernel: KASLR enabled Oct 31 13:46:08.697458 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 31 13:46:08.697464 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 31 13:46:08.697470 kernel: pinctrl core: initialized pinctrl subsystem Oct 31 13:46:08.697476 kernel: SMBIOS 3.0.0 present. Oct 31 13:46:08.697482 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 31 13:46:08.697488 kernel: NET: Registered protocol family 16 Oct 31 13:46:08.697495 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 31 13:46:08.697501 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 31 13:46:08.697514 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 31 13:46:08.697540 kernel: audit: initializing netlink subsys (disabled) Oct 31 13:46:08.697547 kernel: audit: type=2000 audit(0.042:1): state=initialized audit_enabled=0 res=1 Oct 31 13:46:08.697553 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 31 13:46:08.697559 kernel: cpuidle: using governor menu Oct 31 13:46:08.697565 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 31 13:46:08.697572 kernel: ASID allocator initialised with 32768 entries Oct 31 13:46:08.697578 kernel: ACPI: bus type PCI registered Oct 31 13:46:08.697584 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 31 13:46:08.697592 kernel: Serial: AMBA PL011 UART driver Oct 31 13:46:08.697598 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 31 13:46:08.697605 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 31 13:46:08.697611 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 31 13:46:08.697617 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 31 13:46:08.697623 kernel: cryptd: max_cpu_qlen set to 1000 Oct 31 13:46:08.697629 kernel: ACPI: Added _OSI(Module Device) Oct 31 13:46:08.697635 kernel: ACPI: Added _OSI(Processor Device) Oct 31 13:46:08.697641 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 31 13:46:08.697648 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 31 13:46:08.697655 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 31 13:46:08.697661 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 31 13:46:08.697667 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 31 13:46:08.697673 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 31 13:46:08.697679 kernel: ACPI: Interpreter enabled Oct 31 13:46:08.697685 kernel: ACPI: Using GIC for interrupt routing Oct 31 13:46:08.697691 kernel: ACPI: MCFG table detected, 1 entries Oct 31 13:46:08.697698 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 31 13:46:08.697704 kernel: printk: console [ttyAMA0] enabled Oct 31 13:46:08.697711 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 31 13:46:08.697818 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 31 13:46:08.697879 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 31 13:46:08.697937 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 31 13:46:08.697989 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 31 13:46:08.698041 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 31 13:46:08.698049 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 31 13:46:08.698057 kernel: PCI host bridge to bus 0000:00 Oct 31 13:46:08.698119 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 31 13:46:08.698172 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 31 13:46:08.698266 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 31 13:46:08.698319 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 31 13:46:08.698386 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 31 13:46:08.698450 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 31 13:46:08.698526 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 31 13:46:08.698586 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 31 13:46:08.698642 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 31 13:46:08.698696 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 31 13:46:08.698751 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 31 13:46:08.698806 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 31 13:46:08.698856 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 31 13:46:08.698906 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 31 13:46:08.698955 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 31 13:46:08.698963 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 31 13:46:08.698969 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 31 13:46:08.698976 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 31 13:46:08.698982 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 31 13:46:08.698988 kernel: vgaarb: loaded Oct 31 13:46:08.698994 kernel: Registered efivars operations Oct 31 13:46:08.699000 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 31 13:46:08.699008 kernel: VFS: Disk quotas dquot_6.6.0 Oct 31 13:46:08.699015 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 31 13:46:08.699021 kernel: pnp: PnP ACPI init Oct 31 13:46:08.699085 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 31 13:46:08.699136 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 31 13:46:08.699144 kernel: pnp: PnP ACPI: found 1 devices Oct 31 13:46:08.699150 kernel: NET: Registered protocol family 2 Oct 31 13:46:08.699157 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 31 13:46:08.699165 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 31 13:46:08.699172 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 31 13:46:08.699178 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 31 13:46:08.699184 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 31 13:46:08.699191 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 13:46:08.699266 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 13:46:08.699273 kernel: NET: Registered protocol family 1 Oct 31 13:46:08.699279 kernel: PCI: CLS 0 bytes, default 64 Oct 31 13:46:08.699285 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 31 13:46:08.699294 kernel: kvm [1]: HYP mode not available Oct 31 13:46:08.699300 kernel: Initialise system trusted keyrings Oct 31 13:46:08.699306 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 31 13:46:08.699312 kernel: Key type asymmetric registered Oct 31 13:46:08.699318 kernel: Asymmetric key parser 'x509' registered Oct 31 13:46:08.699325 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 31 13:46:08.699331 kernel: io scheduler mq-deadline registered Oct 31 13:46:08.699337 kernel: io scheduler kyber registered Oct 31 13:46:08.699343 kernel: io scheduler bfq registered Oct 31 13:46:08.699351 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 31 13:46:08.699357 kernel: ACPI: Power Button [PWRB] Oct 31 13:46:08.699363 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 31 13:46:08.699432 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 31 13:46:08.699442 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 31 13:46:08.699448 kernel: msm_serial: driver initialized Oct 31 13:46:08.699454 kernel: random: fast init done Oct 31 13:46:08.699460 kernel: random: crng init done Oct 31 13:46:08.699466 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 31 13:46:08.699473 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 31 13:46:08.699481 kernel: thunder_xcv, ver 1.0 Oct 31 13:46:08.699487 kernel: thunder_bgx, ver 1.0 Oct 31 13:46:08.699493 kernel: nicpf, ver 1.0 Oct 31 13:46:08.699499 kernel: nicvf, ver 1.0 Oct 31 13:46:08.699589 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 31 13:46:08.699642 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-31T13:46:08 UTC (1761918368) Oct 31 13:46:08.699651 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 31 13:46:08.699657 kernel: NET: Registered protocol family 10 Oct 31 13:46:08.699665 kernel: Segment Routing with IPv6 Oct 31 13:46:08.699671 kernel: NET: Registered protocol family 17 Oct 31 13:46:08.699677 kernel: Key type dns_resolver registered Oct 31 13:46:08.699683 kernel: registered taskstats version 1 Oct 31 13:46:08.699689 kernel: Loading compiled-in X.509 certificates Oct 31 13:46:08.699696 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 31 13:46:08.699702 kernel: Key type ._fscrypt registered Oct 31 13:46:08.699708 kernel: Key type .fscrypt registered Oct 31 13:46:08.699714 kernel: Key type fscrypt-provisioning registered Oct 31 13:46:08.699721 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 31 13:46:08.699727 kernel: ima: Allocated hash algorithm: sha1 Oct 31 13:46:08.699734 kernel: ima: No architecture policies found Oct 31 13:46:08.699740 kernel: Freeing unused kernel memory: 34240K Oct 31 13:46:08.699746 kernel: Run /init as init process Oct 31 13:46:08.699752 kernel: with arguments: Oct 31 13:46:08.699758 kernel: /init Oct 31 13:46:08.699764 kernel: with environment: Oct 31 13:46:08.699770 kernel: HOME=/ Oct 31 13:46:08.699776 kernel: TERM=linux Oct 31 13:46:08.699782 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 31 13:46:08.699790 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 13:46:08.699799 systemd[1]: Detected virtualization kvm. Oct 31 13:46:08.699805 systemd[1]: Detected architecture arm64. Oct 31 13:46:08.699812 systemd[1]: Running in initial RAM disk. Oct 31 13:46:08.699818 systemd[1]: No hostname configured, using default hostname. Oct 31 13:46:08.699825 systemd[1]: Hostname set to . Oct 31 13:46:08.699833 systemd[1]: Initializing machine ID from VM UUID. Oct 31 13:46:08.699839 systemd[1]: Queued start job for default target Initrd Default Target. Oct 31 13:46:08.699846 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 31 13:46:08.699853 systemd[1]: Reached target Local Encrypted Volumes. Oct 31 13:46:08.699859 systemd[1]: Reached target Path Units. Oct 31 13:46:08.699865 systemd[1]: Reached target Slice Units. Oct 31 13:46:08.699872 systemd[1]: Reached target Swaps. Oct 31 13:46:08.699878 systemd[1]: Reached target Timer Units. Oct 31 13:46:08.699886 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 31 13:46:08.699893 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 31 13:46:08.699900 systemd[1]: Listening on Journal Audit Socket. Oct 31 13:46:08.699906 systemd[1]: Listening on Journal Socket (/dev/log). Oct 31 13:46:08.699913 systemd[1]: Listening on Journal Socket. Oct 31 13:46:08.699919 systemd[1]: Listening on Network Service Netlink Socket. Oct 31 13:46:08.699926 systemd[1]: Listening on udev Control Socket. Oct 31 13:46:08.699932 systemd[1]: Listening on udev Kernel Socket. Oct 31 13:46:08.699939 systemd[1]: Reached target Socket Units. Oct 31 13:46:08.699946 systemd[1]: Starting Create List of Static Device Nodes... Oct 31 13:46:08.699953 systemd[1]: Finished Network Cleanup. Oct 31 13:46:08.699960 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 31 13:46:08.699966 systemd[1]: Starting Journal Service... Oct 31 13:46:08.699973 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 31 13:46:08.699979 systemd[1]: Starting Apply Kernel Variables... Oct 31 13:46:08.699986 systemd[1]: Starting Setup Virtual Console... Oct 31 13:46:08.699992 systemd[1]: Finished Create List of Static Device Nodes. Oct 31 13:46:08.699999 systemd[1]: Finished Apply Kernel Variables. Oct 31 13:46:08.700005 systemd[1]: Finished Setup Virtual Console. Oct 31 13:46:08.700013 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 31 13:46:08.700021 systemd-journald[279]: Journal started Oct 31 13:46:08.700056 systemd-journald[279]: Runtime Journal (/run/log/journal/8001e9a37b864cd180f895518023a4b7) is 6.0M, max 48.5M, 42.4M free. Oct 31 13:46:08.702220 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 31 13:46:08.703577 systemd[1]: Started Journal Service. Oct 31 13:46:08.703919 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 31 13:46:08.713933 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 31 13:46:08.715583 systemd[1]: Starting dracut cmdline hook... Oct 31 13:46:08.723988 dracut-cmdline[295]: dracut-dracut-053 Oct 31 13:46:08.726191 dracut-cmdline[295]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 31 13:46:08.784538 kernel: SCSI subsystem initialized Oct 31 13:46:08.788530 kernel: Loading iSCSI transport class v2.0-870. Oct 31 13:46:08.793538 kernel: iscsi: registered transport (tcp) Oct 31 13:46:08.804930 kernel: iscsi: registered transport (qla4xxx) Oct 31 13:46:08.804945 kernel: QLogic iSCSI HBA Driver Oct 31 13:46:08.814699 systemd[1]: Finished dracut cmdline hook. Oct 31 13:46:08.816755 systemd[1]: Starting dracut pre-udev hook... Oct 31 13:46:08.829950 kernel: device-mapper: uevent: version 1.0.3 Oct 31 13:46:08.829979 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 31 13:46:08.864547 kernel: raid6: neonx8 gen() 13709 MB/s Oct 31 13:46:08.881552 kernel: raid6: neonx8 xor() 10694 MB/s Oct 31 13:46:08.898544 kernel: raid6: neonx4 gen() 13580 MB/s Oct 31 13:46:08.915541 kernel: raid6: neonx4 xor() 11548 MB/s Oct 31 13:46:08.932546 kernel: raid6: neonx2 gen() 12963 MB/s Oct 31 13:46:08.949547 kernel: raid6: neonx2 xor() 9878 MB/s Oct 31 13:46:08.966549 kernel: raid6: neonx1 gen() 10842 MB/s Oct 31 13:46:08.983550 kernel: raid6: neonx1 xor() 8815 MB/s Oct 31 13:46:09.000555 kernel: raid6: int64x8 gen() 6811 MB/s Oct 31 13:46:09.017551 kernel: raid6: int64x8 xor() 3782 MB/s Oct 31 13:46:09.034555 kernel: raid6: int64x4 gen() 6871 MB/s Oct 31 13:46:09.051559 kernel: raid6: int64x4 xor() 3821 MB/s Oct 31 13:46:09.068556 kernel: raid6: int64x2 gen() 6156 MB/s Oct 31 13:46:09.085581 kernel: raid6: int64x2 xor() 3161 MB/s Oct 31 13:46:09.102548 kernel: raid6: int64x1 gen() 3678 MB/s Oct 31 13:46:09.120391 kernel: raid6: int64x1 xor() 2474 MB/s Oct 31 13:46:09.120412 kernel: raid6: using algorithm neonx8 gen() 13709 MB/s Oct 31 13:46:09.120428 kernel: raid6: .... xor() 10694 MB/s, rmw enabled Oct 31 13:46:09.121672 kernel: raid6: using neon recovery algorithm Oct 31 13:46:09.135549 kernel: xor: measuring software checksum speed Oct 31 13:46:09.136543 kernel: 8regs : 17159 MB/sec Oct 31 13:46:09.137543 kernel: 32regs : 20760 MB/sec Oct 31 13:46:09.138840 kernel: arm64_neon : 29163 MB/sec Oct 31 13:46:09.138851 kernel: xor: using function: arm64_neon (29163 MB/sec) Oct 31 13:46:09.193758 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 31 13:46:09.204716 systemd[1]: Finished dracut pre-udev hook. Oct 31 13:46:09.209559 kernel: audit: type=1334 audit(1761918369.205:2): prog-id=6 op=LOAD Oct 31 13:46:09.209598 kernel: audit: type=1334 audit(1761918369.205:3): prog-id=7 op=LOAD Oct 31 13:46:09.209609 kernel: audit: type=1334 audit(1761918369.205:4): prog-id=8 op=LOAD Oct 31 13:46:09.205000 audit: BPF prog-id=6 op=LOAD Oct 31 13:46:09.205000 audit: BPF prog-id=7 op=LOAD Oct 31 13:46:09.205000 audit: BPF prog-id=8 op=LOAD Oct 31 13:46:09.206637 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 31 13:46:09.220682 systemd-udevd[413]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 31 13:46:09.222410 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 31 13:46:09.225027 systemd[1]: Starting dracut pre-trigger hook... Oct 31 13:46:09.228804 systemd[1]: Starting Network Configuration... Oct 31 13:46:09.231381 kernel: audit: type=1334 audit(1761918369.225:5): prog-id=9 op=LOAD Oct 31 13:46:09.225000 audit: BPF prog-id=9 op=LOAD Oct 31 13:46:09.235680 dracut-pre-trigger[422]: rd.md=0: removing MD RAID activation Oct 31 13:46:09.248884 systemd-networkd[430]: lo: Link UP Oct 31 13:46:09.249844 systemd-networkd[430]: lo: Gained carrier Oct 31 13:46:09.250953 systemd-networkd[430]: Enumeration completed Oct 31 13:46:09.251876 systemd[1]: Started Network Configuration. Oct 31 13:46:09.252000 audit: BPF prog-id=10 op=LOAD Oct 31 13:46:09.253701 systemd[1]: Starting Network Name Resolution... Oct 31 13:46:09.255778 kernel: audit: type=1334 audit(1761918369.252:6): prog-id=10 op=LOAD Oct 31 13:46:09.259688 systemd[1]: Finished dracut pre-trigger hook. Oct 31 13:46:09.261133 systemd[1]: Starting Coldplug All udev Devices... Oct 31 13:46:09.270820 systemd-udevd[413]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 31 13:46:09.280998 systemd-resolved[456]: Positive Trust Anchors: Oct 31 13:46:09.281013 systemd-resolved[456]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 13:46:09.281042 systemd-resolved[456]: 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 13:46:09.281310 systemd-resolved[456]: Defaulting to hostname 'linux'. Oct 31 13:46:09.288892 systemd[1]: Started Network Name Resolution. Oct 31 13:46:09.290024 systemd[1]: Finished Coldplug All udev Devices. Oct 31 13:46:09.290895 systemd[1]: Reached target Network. Oct 31 13:46:09.291571 systemd[1]: Reached target Host and Network Name Lookups. Oct 31 13:46:09.293197 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 31 13:46:09.299172 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 31 13:46:09.300732 systemd[1]: Starting Open-iSCSI... Oct 31 13:46:09.303238 iscsid[484]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 31 13:46:09.303238 iscsid[484]: 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 13:46:09.303238 iscsid[484]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 31 13:46:09.303238 iscsid[484]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 31 13:46:09.303238 iscsid[484]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 31 13:46:09.314923 iscsid[484]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 31 13:46:09.306910 systemd[1]: Started Open-iSCSI. Oct 31 13:46:09.311853 systemd[1]: Starting dracut initqueue hook... Oct 31 13:46:09.333158 systemd-udevd[471]: Using default interface naming scheme 'v249'. Oct 31 13:46:09.336948 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 31 13:46:09.337091 kernel: vda: detected capacity change from 0 to 4756340736 Oct 31 13:46:09.338219 systemd-networkd[430]: eth0: Link UP Oct 31 13:46:09.345533 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 13:46:09.367545 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (477) Oct 31 13:46:09.364022 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 31 13:46:09.365228 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 31 13:46:09.369296 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 31 13:46:09.376796 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 31 13:46:09.379452 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 31 13:46:09.380624 systemd[1]: Finished dracut initqueue hook. Oct 31 13:46:09.381910 systemd[1]: Reached target Initrd Root Device. Oct 31 13:46:09.383244 systemd[1]: Reached target Preparation for Remote File Systems. Oct 31 13:46:09.384953 systemd[1]: Reached target Remote Encrypted Volumes. Oct 31 13:46:09.386616 systemd[1]: Reached target Remote File Systems. Oct 31 13:46:09.388850 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 31 13:46:09.390432 systemd[1]: Starting dracut pre-mount hook... Oct 31 13:46:09.398986 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 13:46:09.398671 systemd[1]: Finished dracut pre-mount hook. Oct 31 13:46:10.371572 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 31 13:46:10.371590 systemd-networkd[430]: eth0: Gained carrier Oct 31 13:46:10.377608 systemd-networkd[430]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 31 13:46:10.405085 disk-uuid[506]: The operation has completed successfully. Oct 31 13:46:10.406336 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 13:46:10.428434 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 31 13:46:10.428578 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 31 13:46:10.431080 systemd[1]: Starting Ignition (setup)... Oct 31 13:46:10.438818 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 31 13:46:10.438844 kernel: BTRFS info (device vda6): has skinny extents Oct 31 13:46:10.444213 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 31 13:46:10.448569 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 31 13:46:10.448677 systemd[1]: Finished Ignition (setup). Oct 31 13:46:10.450769 systemd[1]: Starting Ignition (disks)... Oct 31 13:46:10.459780 ignition[541]: Ignition v0.36.1-15-gde4e6cc9 Oct 31 13:46:10.459796 ignition[541]: Stage: disks Oct 31 13:46:10.459806 ignition[541]: reading system config file "/usr/lib/ignition/base.ign" Oct 31 13:46:10.459815 ignition[541]: no config at "/usr/lib/ignition/base.ign" Oct 31 13:46:10.459857 ignition[541]: parsed url from cmdline: "" Oct 31 13:46:10.459860 ignition[541]: no config URL provided Oct 31 13:46:10.459865 ignition[541]: reading system config file "/usr/lib/ignition/user.ign" Oct 31 13:46:10.459872 ignition[541]: no config at "/usr/lib/ignition/user.ign" Oct 31 13:46:10.459890 ignition[541]: op(1): [started] loading QEMU firmware config module Oct 31 13:46:10.459894 ignition[541]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 31 13:46:10.460877 ignition[541]: op(1): [finished] loading QEMU firmware config module Oct 31 13:46:10.474455 ignition[541]: parsing config with SHA512: e8fbcf994d44fc297aa6d27b26c9e0999ac8e74f3138413e11b5eab9b6715fdb1acd416369f0c54ef21c3c1e9706ae16247590b92d5676dc6d82aad4fa02f64b Oct 31 13:46:10.476356 ignition[541]: disks: disks passed Oct 31 13:46:10.476364 ignition[541]: Ignition finished successfully Oct 31 13:46:10.476966 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 31 13:46:10.477071 systemd[1]: Finished Ignition (disks). Oct 31 13:46:10.477915 systemd[1]: Reached target Preparation for Local File Systems. Oct 31 13:46:10.479892 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 31 13:46:10.481481 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 31 13:46:10.490341 systemd-fsck[555]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 31 13:46:10.494551 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 31 13:46:10.493581 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 31 13:46:10.495883 systemd[1]: Mounting /sysroot... Oct 31 13:46:10.512625 systemd[1]: Found device /dev/mapper/usr. Oct 31 13:46:10.514238 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 31 13:46:10.517910 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 31 13:46:10.525082 systemd-fsck[573]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 31 13:46:10.525082 systemd-fsck[573]: You must have r/w access to the filesystem or be root Oct 31 13:46:10.527958 systemd-fsck[569]: fsck failed with exit status 8. Oct 31 13:46:10.527968 systemd-fsck[569]: Ignoring error. Oct 31 13:46:10.528638 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 31 13:46:10.530609 systemd[1]: Mounting /sysusr/usr... Oct 31 13:46:10.544951 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 31 13:46:10.544986 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 31 13:46:10.545656 systemd[1]: Mounted /sysroot. Oct 31 13:46:10.546337 systemd[1]: Reached target Initrd Root File System. Oct 31 13:46:10.561280 systemd[1]: Mounted /sysusr/usr. Oct 31 13:46:10.562440 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 31 13:46:10.562048 systemd[1]: Reached target Local File Systems. Oct 31 13:46:10.563237 systemd[1]: Reached target System Initialization. Oct 31 13:46:10.564713 systemd[1]: Reached target Basic System. Oct 31 13:46:10.567378 systemd[1]: Mounting /sysroot/usr... Oct 31 13:46:10.569358 systemd[1]: Mounted /sysroot/usr. Oct 31 13:46:10.570671 systemd[1]: Starting Root filesystem setup... Oct 31 13:46:10.593903 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 31 13:46:10.594021 systemd[1]: Finished Root filesystem setup. Oct 31 13:46:10.596229 systemd[1]: Starting Ignition (files)... Oct 31 13:46:10.597445 systemd[1]: Starting /sysroot/boot... Oct 31 13:46:10.599689 ignition[589]: Ignition v0.36.1-15-gde4e6cc9 Oct 31 13:46:10.599706 ignition[589]: Stage: files Oct 31 13:46:10.599715 ignition[589]: reading system config file "/usr/lib/ignition/base.ign" Oct 31 13:46:10.599724 ignition[589]: no config at "/usr/lib/ignition/base.ign" Oct 31 13:46:10.600200 ignition[589]: files: compiled without relabeling support, skipping Oct 31 13:46:10.615179 systemd[1]: Finished /sysroot/boot. Oct 31 13:46:10.617510 ignition[589]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 31 13:46:10.617533 ignition[589]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 31 13:46:10.619001 ignition[589]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 31 13:46:10.619013 ignition[589]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 31 13:46:10.621712 ignition[589]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 31 13:46:10.621735 ignition[589]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 31 13:46:10.623012 ignition[589]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 31 13:46:10.623019 ignition[589]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 31 13:46:10.623103 ignition[589]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 31 13:46:10.624691 ignition[589]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 31 13:46:10.627583 systemd[1]: ignition-files.service: Deactivated successfully. Oct 31 13:46:10.624697 ignition[589]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 31 13:46:10.627690 systemd[1]: Finished Ignition (files). Oct 31 13:46:10.624702 ignition[589]: files: files passed Oct 31 13:46:10.629680 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 31 13:46:10.624705 ignition[589]: Ignition finished successfully Oct 31 13:46:10.630275 systemd[1]: Starting Ignition (record completion)... Oct 31 13:46:10.632360 systemd[1]: Starting Reload Configuration from the Real Root... Oct 31 13:46:10.633733 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 31 13:46:10.642798 kernel: audit: type=1334 audit(1761918370.640:7): prog-id=6 op=UNLOAD Oct 31 13:46:10.642818 kernel: audit: type=1334 audit(1761918370.640:8): prog-id=3 op=UNLOAD Oct 31 13:46:10.640000 audit: BPF prog-id=6 op=UNLOAD Oct 31 13:46:10.640000 audit: BPF prog-id=3 op=UNLOAD Oct 31 13:46:10.633837 systemd[1]: Finished Ignition (record completion). Oct 31 13:46:10.636675 systemd[1]: Reloading. Oct 31 13:46:10.644000 audit: BPF prog-id=9 op=UNLOAD Oct 31 13:46:10.644000 audit: BPF prog-id=10 op=UNLOAD Oct 31 13:46:10.647960 kernel: audit: type=1334 audit(1761918370.644:9): prog-id=9 op=UNLOAD Oct 31 13:46:10.647987 kernel: audit: type=1334 audit(1761918370.644:10): prog-id=10 op=UNLOAD Oct 31 13:46:10.723000 audit: BPF prog-id=11 op=LOAD Oct 31 13:46:10.723000 audit: BPF prog-id=12 op=LOAD Oct 31 13:46:10.723000 audit: BPF prog-id=13 op=LOAD Oct 31 13:46:10.723000 audit: BPF prog-id=7 op=UNLOAD Oct 31 13:46:10.723000 audit: BPF prog-id=8 op=UNLOAD Oct 31 13:46:10.723000 audit: BPF prog-id=14 op=LOAD Oct 31 13:46:10.723000 audit: BPF prog-id=15 op=LOAD Oct 31 13:46:10.723000 audit: BPF prog-id=16 op=LOAD Oct 31 13:46:10.723000 audit: BPF prog-id=4 op=UNLOAD Oct 31 13:46:10.723000 audit: BPF prog-id=5 op=UNLOAD Oct 31 13:46:10.723000 audit: BPF prog-id=17 op=LOAD Oct 31 13:46:10.724000 audit: BPF prog-id=18 op=LOAD Oct 31 13:46:10.740968 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 31 13:46:10.741097 systemd[1]: Finished Reload Configuration from the Real Root. Oct 31 13:46:10.742998 systemd[1]: Reached target Initrd File Systems. Oct 31 13:46:10.744473 systemd[1]: Reached target Initrd Default Target. Oct 31 13:46:10.746029 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 31 13:46:10.746682 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 31 13:46:10.756404 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 31 13:46:10.757999 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 31 13:46:10.763608 systemd[1]: Stopped target Host and Network Name Lookups. Oct 31 13:46:10.764607 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 31 13:46:10.766339 systemd[1]: Stopped target Timer Units. Oct 31 13:46:10.767745 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 31 13:46:10.767843 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 31 13:46:10.769204 systemd[1]: Stopped target Initrd Default Target. Oct 31 13:46:10.770703 systemd[1]: Stopped target Basic System. Oct 31 13:46:10.772070 systemd[1]: Stopped target Initrd Root Device. Oct 31 13:46:10.773469 systemd[1]: Stopped target Path Units. Oct 31 13:46:10.774724 systemd[1]: Stopped target Remote File Systems. Oct 31 13:46:10.776116 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 31 13:46:10.777594 systemd[1]: Stopped target Slice Units. Oct 31 13:46:10.779019 systemd[1]: Stopped target Socket Units. Oct 31 13:46:10.780515 systemd[1]: Stopped target System Initialization. Oct 31 13:46:10.781867 systemd[1]: Stopped target Local File Systems. Oct 31 13:46:10.783193 systemd[1]: Stopped target Preparation for Local File Systems. Oct 31 13:46:10.784849 systemd[1]: Stopped target Swaps. Oct 31 13:46:10.786078 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 31 13:46:10.786178 systemd[1]: Stopped dracut pre-mount hook. Oct 31 13:46:10.787650 systemd[1]: Stopped target Local Encrypted Volumes. Oct 31 13:46:10.788938 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 31 13:46:10.792571 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 31 13:46:10.793664 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 31 13:46:10.793760 systemd[1]: Stopped dracut initqueue hook. Oct 31 13:46:10.797291 iscsid[484]: iscsid shutting down. Oct 31 13:46:10.795422 systemd[1]: Stopping Open-iSCSI... Oct 31 13:46:10.797279 systemd[1]: Stopping /sysroot/boot... Oct 31 13:46:10.797931 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 31 13:46:10.798056 systemd[1]: Stopped Coldplug All udev Devices. Oct 31 13:46:10.799373 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 31 13:46:10.799452 systemd[1]: Stopped dracut pre-trigger hook. Oct 31 13:46:10.801513 systemd[1]: iscsid.service: Deactivated successfully. Oct 31 13:46:10.801645 systemd[1]: Stopped Open-iSCSI. Oct 31 13:46:10.803196 systemd[1]: iscsid.socket: Deactivated successfully. Oct 31 13:46:10.803254 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 31 13:46:10.804452 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 31 13:46:10.807387 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 31 13:46:10.807497 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 31 13:46:10.808945 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 31 13:46:10.809053 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 31 13:46:10.810450 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 31 13:46:10.810576 systemd[1]: Stopped /sysroot/boot. Oct 31 13:46:10.812053 systemd[1]: Stopped target Network. Oct 31 13:46:10.812864 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 31 13:46:10.812927 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 31 13:46:10.814321 systemd[1]: Stopping Network Name Resolution... Oct 31 13:46:10.817625 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 31 13:46:10.817735 systemd[1]: Stopped Network Name Resolution. Oct 31 13:46:10.819271 systemd[1]: Stopping Network Configuration... Oct 31 13:46:10.821632 systemd-networkd[430]: eth0: DHCP lease lost Oct 31 13:46:10.827000 audit: BPF prog-id=18 op=UNLOAD Oct 31 13:46:10.827557 systemd-networkd[430]: eth0: DHCPv6 lease lost Oct 31 13:46:10.828409 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 31 13:46:10.828535 systemd[1]: Stopped Network Configuration. Oct 31 13:46:10.829804 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 31 13:46:10.829832 systemd[1]: Closed Network Service Netlink Socket. Oct 31 13:46:10.831841 systemd[1]: Stopping Network Cleanup... Oct 31 13:46:10.836000 audit: BPF prog-id=17 op=UNLOAD Oct 31 13:46:10.832435 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 31 13:46:10.832513 systemd[1]: Stopped Apply Kernel Variables. Oct 31 13:46:10.834194 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 31 13:46:10.840501 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 31 13:46:10.840681 systemd[1]: Stopped Network Cleanup. Oct 31 13:46:10.843072 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 31 13:46:10.843182 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 31 13:46:10.844858 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 31 13:46:10.844892 systemd[1]: Closed udev Control Socket. Oct 31 13:46:10.845942 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 31 13:46:10.845976 systemd[1]: Closed udev Kernel Socket. Oct 31 13:46:10.853000 audit: BPF prog-id=11 op=UNLOAD Oct 31 13:46:10.847320 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 31 13:46:10.847361 systemd[1]: Stopped dracut pre-udev hook. Oct 31 13:46:10.848970 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 31 13:46:10.849010 systemd[1]: Stopped dracut cmdline hook. Oct 31 13:46:10.850477 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 31 13:46:10.850548 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 31 13:46:10.852597 systemd[1]: Starting Cleanup udev Database... Oct 31 13:46:10.853657 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 31 13:46:10.854878 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 31 13:46:10.854931 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 31 13:46:10.856510 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 31 13:46:10.856569 systemd[1]: Stopped Create List of Static Device Nodes. Oct 31 13:46:10.857996 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 13:46:10.870000 audit: BPF prog-id=14 op=UNLOAD Oct 31 13:46:10.858035 systemd[1]: Stopped Setup Virtual Console. Oct 31 13:46:10.859743 systemd[1]: rngd.service: Deactivated successfully. Oct 31 13:46:10.859851 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 31 13:46:10.861115 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 31 13:46:10.861213 systemd[1]: Finished Cleanup udev Database. Oct 31 13:46:10.862618 systemd[1]: Reached target Switch Root. Oct 31 13:46:10.864748 systemd[1]: Starting Switch Root... Oct 31 13:46:10.869378 systemd[1]: Switching root. Oct 31 13:46:10.878836 systemd-journald[279]: Journal stopped Oct 31 13:46:13.015995 systemd-journald[279]: Received SIGTERM from PID 1 (systemd). Oct 31 13:46:13.016047 kernel: SELinux: policy capability network_peer_controls=1 Oct 31 13:46:13.016060 kernel: SELinux: policy capability open_perms=1 Oct 31 13:46:13.016070 kernel: SELinux: policy capability extended_socket_class=1 Oct 31 13:46:13.016080 kernel: SELinux: policy capability always_check_network=0 Oct 31 13:46:13.016089 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 31 13:46:13.016101 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 31 13:46:13.016110 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 31 13:46:13.016120 systemd[1]: Successfully loaded SELinux policy in 30.085ms. Oct 31 13:46:13.016134 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.246ms. Oct 31 13:46:13.016146 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 13:46:13.016156 systemd[1]: Detected virtualization kvm. Oct 31 13:46:13.016166 systemd[1]: Detected architecture arm64. Oct 31 13:46:13.016176 systemd[1]: Detected first boot. Oct 31 13:46:13.016186 systemd[1]: Initializing machine ID from VM UUID. Oct 31 13:46:13.016196 systemd[1]: Populated /etc with preset unit settings. Oct 31 13:46:13.016207 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 13:46:13.016220 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 31 13:46:13.016230 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 31 13:46:13.016240 systemd[1]: Stopped Switch Root. Oct 31 13:46:13.016251 kernel: kauditd_printk_skb: 29 callbacks suppressed Oct 31 13:46:13.016261 kernel: audit: type=1334 audit(1761918372.882:40): prog-id=24 op=LOAD Oct 31 13:46:13.016270 kernel: audit: type=1334 audit(1761918372.882:41): prog-id=21 op=UNLOAD Oct 31 13:46:13.016280 kernel: audit: type=1334 audit(1761918372.882:42): prog-id=25 op=LOAD Oct 31 13:46:13.016289 kernel: audit: type=1334 audit(1761918372.882:43): prog-id=26 op=LOAD Oct 31 13:46:13.016298 kernel: audit: type=1334 audit(1761918372.882:44): prog-id=22 op=UNLOAD Oct 31 13:46:13.016309 kernel: audit: type=1334 audit(1761918372.882:45): prog-id=23 op=UNLOAD Oct 31 13:46:13.016319 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 31 13:46:13.016329 systemd[1]: Created slice Slice /system/addon-config. Oct 31 13:46:13.016339 kernel: audit: type=1334 audit(1761918372.894:46): prog-id=24 op=UNLOAD Oct 31 13:46:13.016348 systemd[1]: Created slice Slice /system/addon-run. Oct 31 13:46:13.016359 systemd[1]: Created slice Slice /system/getty. Oct 31 13:46:13.016369 systemd[1]: Created slice Slice /system/modprobe. Oct 31 13:46:13.016379 systemd[1]: Created slice Slice /system/serial-getty. Oct 31 13:46:13.016389 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 31 13:46:13.016400 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 31 13:46:13.016411 systemd[1]: Created slice User and Session Slice. Oct 31 13:46:13.016437 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 31 13:46:13.016447 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 31 13:46:13.016457 systemd[1]: Set up automount Boot partition Automount Point. Oct 31 13:46:13.016476 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 31 13:46:13.016498 systemd[1]: Stopped target Switch Root. Oct 31 13:46:13.016509 systemd[1]: Stopped target Initrd File Systems. Oct 31 13:46:13.016529 systemd[1]: Stopped target Initrd Root File System. Oct 31 13:46:13.016542 systemd[1]: Reached target Remote Encrypted Volumes. Oct 31 13:46:13.016552 systemd[1]: Reached target Remote File Systems. Oct 31 13:46:13.016562 systemd[1]: Reached target Slice Units. Oct 31 13:46:13.016573 systemd[1]: Reached target Swaps. Oct 31 13:46:13.016583 systemd[1]: Reached target Verify torcx succeeded. Oct 31 13:46:13.016593 systemd[1]: Reached target Local Verity Protected Volumes. Oct 31 13:46:13.016603 systemd[1]: Listening on Process Core Dump Socket. Oct 31 13:46:13.016612 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 31 13:46:13.016622 systemd[1]: Listening on Network Service Netlink Socket. Oct 31 13:46:13.016635 systemd[1]: Listening on udev Control Socket. Oct 31 13:46:13.016646 systemd[1]: Listening on udev Kernel Socket. Oct 31 13:46:13.016657 systemd[1]: Mounting Huge Pages File System... Oct 31 13:46:13.016667 systemd[1]: Mounting POSIX Message Queue File System... Oct 31 13:46:13.016676 systemd[1]: Mounting External Media Directory... Oct 31 13:46:13.016686 systemd[1]: Mounting Kernel Debug File System... Oct 31 13:46:13.016696 systemd[1]: Mounting Kernel Trace File System... Oct 31 13:46:13.016707 systemd[1]: Mounting Temporary Directory /tmp... Oct 31 13:46:13.016717 systemd[1]: Starting Create missing system files... Oct 31 13:46:13.016727 systemd[1]: Starting Create List of Static Device Nodes... Oct 31 13:46:13.016737 systemd[1]: Starting Load Kernel Module configfs... Oct 31 13:46:13.016749 systemd[1]: Starting Load Kernel Module drm... Oct 31 13:46:13.016765 systemd[1]: Starting Load Kernel Module fuse... Oct 31 13:46:13.016775 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 31 13:46:13.016785 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 31 13:46:13.016795 systemd[1]: Stopped File System Check on Root Device. Oct 31 13:46:13.016805 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 31 13:46:13.016814 systemd[1]: Stopped systemd-fsck-usr.service. Oct 31 13:46:13.016824 kernel: fuse: init (API version 7.32) Oct 31 13:46:13.016834 systemd[1]: Stopped Journal Service. Oct 31 13:46:13.016845 kernel: audit: type=1334 audit(1761918373.001:47): prog-id=27 op=LOAD Oct 31 13:46:13.016854 kernel: audit: type=1334 audit(1761918373.002:48): prog-id=28 op=LOAD Oct 31 13:46:13.016863 kernel: audit: type=1334 audit(1761918373.003:49): prog-id=29 op=LOAD Oct 31 13:46:13.016873 systemd[1]: Starting Journal Service... Oct 31 13:46:13.016883 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 31 13:46:13.016893 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 31 13:46:13.016903 systemd[1]: Starting Apply Kernel Variables... Oct 31 13:46:13.016913 systemd[1]: Starting Coldplug All udev Devices... Oct 31 13:46:13.016925 systemd[1]: verity-setup.service: Deactivated successfully. Oct 31 13:46:13.016936 systemd-journald[804]: Journal started Oct 31 13:46:13.016970 systemd-journald[804]: Runtime Journal (/run/log/journal/8001e9a37b864cd180f895518023a4b7) is 6.0M, max 48.5M, 42.4M free. Oct 31 13:46:10.938000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 31 13:46:10.967000 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 13:46:10.967000 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 13:46:10.967000 audit: BPF prog-id=19 op=LOAD Oct 31 13:46:10.967000 audit: BPF prog-id=19 op=UNLOAD Oct 31 13:46:10.967000 audit: BPF prog-id=20 op=LOAD Oct 31 13:46:10.967000 audit: BPF prog-id=20 op=UNLOAD Oct 31 13:46:10.995000 audit[753]: AVC avc: denied { associate } for pid=753 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 31 13:46:12.872000 audit: BPF prog-id=21 op=LOAD Oct 31 13:46:12.872000 audit: BPF prog-id=22 op=LOAD Oct 31 13:46:12.872000 audit: BPF prog-id=23 op=LOAD Oct 31 13:46:12.872000 audit: BPF prog-id=15 op=UNLOAD Oct 31 13:46:12.872000 audit: BPF prog-id=16 op=UNLOAD Oct 31 13:46:12.882000 audit: BPF prog-id=24 op=LOAD Oct 31 13:46:12.882000 audit: BPF prog-id=21 op=UNLOAD Oct 31 13:46:12.882000 audit: BPF prog-id=25 op=LOAD Oct 31 13:46:12.882000 audit: BPF prog-id=26 op=LOAD Oct 31 13:46:12.882000 audit: BPF prog-id=22 op=UNLOAD Oct 31 13:46:12.882000 audit: BPF prog-id=23 op=UNLOAD Oct 31 13:46:12.894000 audit: BPF prog-id=24 op=UNLOAD Oct 31 13:46:13.001000 audit: BPF prog-id=27 op=LOAD Oct 31 13:46:13.002000 audit: BPF prog-id=28 op=LOAD Oct 31 13:46:13.003000 audit: BPF prog-id=29 op=LOAD Oct 31 13:46:13.003000 audit: BPF prog-id=25 op=UNLOAD Oct 31 13:46:13.003000 audit: BPF prog-id=26 op=UNLOAD Oct 31 13:46:13.015000 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 13:46:12.871812 systemd[1]: Queued start job for default target Multi-User System. Oct 31 13:46:10.993953 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" 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 13:46:12.883395 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 31 13:46:10.994280 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 31 13:46:10.994300 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 31 13:46:13.017959 systemd[1]: Stopped verity-setup.service. Oct 31 13:46:10.994431 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 31 13:46:10.994443 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 31 13:46:10.994472 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 31 13:46:10.994494 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 31 13:46:10.994710 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 31 13:46:10.994748 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 31 13:46:10.994762 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 31 13:46:10.995153 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" 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 13:46:10.995185 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" 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 13:46:10.995204 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" 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 13:46:10.995220 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" 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 13:46:10.995240 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" 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 13:46:10.995254 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:10Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 31 13:46:12.700293 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:12Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 13:46:12.700581 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:12Z" 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 13:46:12.700688 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:12Z" 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 13:46:12.700809 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:12Z" 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 13:46:12.700862 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:12Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 31 13:46:12.700917 /usr/lib/systemd/system-generators/torcx-generator[753]: time="2025-10-31T13:46:12Z" 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 13:46:13.021765 systemd[1]: Started Journal Service. Oct 31 13:46:13.022317 systemd[1]: Mounted Huge Pages File System. Oct 31 13:46:13.023378 systemd[1]: Mounted POSIX Message Queue File System. Oct 31 13:46:13.024548 systemd[1]: Mounted External Media Directory. Oct 31 13:46:13.025477 systemd[1]: Mounted Kernel Debug File System. Oct 31 13:46:13.026569 systemd[1]: Mounted Kernel Trace File System. Oct 31 13:46:13.027504 systemd[1]: Mounted Temporary Directory /tmp. Oct 31 13:46:13.028780 systemd[1]: Finished Create missing system files. Oct 31 13:46:13.030018 systemd[1]: Finished Create List of Static Device Nodes. Oct 31 13:46:13.031265 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 31 13:46:13.031474 systemd[1]: Finished Load Kernel Module configfs. Oct 31 13:46:13.032709 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 13:46:13.032895 systemd[1]: Finished Load Kernel Module drm. Oct 31 13:46:13.033954 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 31 13:46:13.034145 systemd[1]: Finished Load Kernel Module fuse. Oct 31 13:46:13.046969 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 31 13:46:13.048368 systemd[1]: Finished Apply Kernel Variables. Oct 31 13:46:13.051552 systemd[1]: Mounting FUSE Control File System... Oct 31 13:46:13.053394 systemd[1]: Mounting Kernel Configuration File System... Oct 31 13:46:13.054486 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 31 13:46:13.056281 systemd[1]: Starting Rebuild Hardware Database... Oct 31 13:46:13.058241 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 31 13:46:13.059431 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 31 13:46:13.060412 systemd[1]: Starting Load/Save Random Seed... Oct 31 13:46:13.061844 systemd-journald[804]: Time spent on flushing to /var/log/journal/8001e9a37b864cd180f895518023a4b7 is 8.277ms for 777 entries. Oct 31 13:46:13.061844 systemd-journald[804]: System Journal (/var/log/journal/8001e9a37b864cd180f895518023a4b7) is 8.0M, max 203.0M, 195.0M free. Oct 31 13:46:13.062442 systemd[1]: Starting Create System Users... Oct 31 13:46:13.065684 systemd[1]: Mounted FUSE Control File System. Oct 31 13:46:13.066911 systemd[1]: Mounted Kernel Configuration File System. Oct 31 13:46:13.070466 systemd[1]: Finished Load/Save Random Seed. Oct 31 13:46:13.071807 systemd[1]: Finished Coldplug All udev Devices. Oct 31 13:46:13.073002 systemd[1]: Reached target First Boot Complete. Oct 31 13:46:13.078543 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 31 13:46:13.081092 systemd-sysusers[815]: Creating group sgx with gid 999. Oct 31 13:46:13.082135 systemd-sysusers[815]: Creating group systemd-oom with gid 998. Oct 31 13:46:13.083613 systemd-sysusers[815]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 31 13:46:13.083783 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 31 13:46:13.085660 systemd-sysusers[815]: Creating group systemd-timesync with gid 997. Oct 31 13:46:13.086203 systemd-sysusers[815]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 31 13:46:13.087094 systemd-sysusers[815]: Creating group systemd-coredump with gid 996. Oct 31 13:46:13.087731 systemd-sysusers[815]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 31 13:46:13.097895 systemd[1]: Finished Create System Users. Oct 31 13:46:13.099887 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 31 13:46:13.112304 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 31 13:46:13.312750 systemd[1]: Finished Rebuild Hardware Database. Oct 31 13:46:13.321000 audit: BPF prog-id=30 op=LOAD Oct 31 13:46:13.321000 audit: BPF prog-id=31 op=LOAD Oct 31 13:46:13.321000 audit: BPF prog-id=32 op=LOAD Oct 31 13:46:13.322000 audit: BPF prog-id=12 op=UNLOAD Oct 31 13:46:13.322000 audit: BPF prog-id=13 op=UNLOAD Oct 31 13:46:13.323206 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 31 13:46:13.346337 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 31 13:46:13.354000 audit: BPF prog-id=33 op=LOAD Oct 31 13:46:13.355744 systemd[1]: Starting Network Configuration... Oct 31 13:46:13.382571 systemd-networkd[841]: lo: Link UP Oct 31 13:46:13.382829 systemd-networkd[841]: lo: Gained carrier Oct 31 13:46:13.383242 systemd-networkd[841]: Enumeration completed Oct 31 13:46:13.383420 systemd[1]: Started Network Configuration. Oct 31 13:46:13.394449 systemd-udevd[831]: Using default interface naming scheme 'v249'. Oct 31 13:46:13.419957 systemd-networkd[841]: eth0: Link UP Oct 31 13:46:13.427989 udevadm[818]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 31 13:46:13.427638 systemd-networkd[841]: eth0: Gained carrier Oct 31 13:46:13.442713 systemd-networkd[841]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 31 13:46:13.448694 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 31 13:46:13.456535 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 31 13:46:13.465071 lvm[849]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 13:46:13.491636 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 31 13:46:13.492771 systemd[1]: Reached target Local Encrypted Volumes. Oct 31 13:46:13.503651 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 31 13:46:13.507204 lvm[850]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 13:46:13.527502 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 31 13:46:13.528558 systemd[1]: Reached target Preparation for Local File Systems. Oct 31 13:46:13.529575 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 31 13:46:13.529596 systemd[1]: Reached target Containers. Oct 31 13:46:13.538760 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 31 13:46:13.550674 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 31 13:46:13.552839 systemd[1]: Mounting /usr/share/oem... Oct 31 13:46:13.558736 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 31 13:46:13.558801 kernel: BTRFS info (device vda6): has skinny extents Oct 31 13:46:13.561013 systemd[1]: Mounted /usr/share/oem. Oct 31 13:46:13.561824 systemd[1]: Reached target Local File Systems. Oct 31 13:46:13.574407 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 31 13:46:13.575795 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 31 13:46:13.575840 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 31 13:46:13.576857 systemd[1]: Starting Commit a transient machine-id on disk... Oct 31 13:46:13.578886 systemd[1]: Starting Create Volatile Files and Directories... Oct 31 13:46:13.585089 systemd-tmpfiles[875]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 31 13:46:13.586757 systemd-tmpfiles[875]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 31 13:46:13.592117 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 31 13:46:13.592776 systemd[1]: Finished Commit a transient machine-id on disk. Oct 31 13:46:13.621709 systemd-tmpfiles[875]: Detected autofs mount point /boot during canonicalization of /boot. Oct 31 13:46:13.621865 systemd-tmpfiles[875]: Skipping /boot Oct 31 13:46:13.629035 systemd-tmpfiles[875]: Detected autofs mount point /boot during canonicalization of /boot. Oct 31 13:46:13.629163 systemd-tmpfiles[875]: Skipping /boot Oct 31 13:46:13.653827 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 31 13:46:13.674871 systemd[1]: Finished Create Volatile Files and Directories. Oct 31 13:46:13.677073 systemd[1]: Starting Load Security Auditing Rules... Oct 31 13:46:13.678908 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 31 13:46:13.681086 systemd[1]: Starting Rebuild Journal Catalog... Oct 31 13:46:13.683000 audit: BPF prog-id=34 op=LOAD Oct 31 13:46:13.686021 systemd[1]: Starting Network Name Resolution... Oct 31 13:46:13.686000 audit: BPF prog-id=35 op=LOAD Oct 31 13:46:13.688418 systemd[1]: Starting Network Time Synchronization... Oct 31 13:46:13.690476 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 31 13:46:13.692091 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 31 13:46:13.693449 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 31 13:46:13.695729 systemd[1]: Finished Rebuild Journal Catalog. Oct 31 13:46:13.697703 systemd[1]: Starting Update is Completed... Oct 31 13:46:13.700532 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 31 13:46:13.702630 systemd[1]: Finished Update is Completed. Oct 31 13:46:13.711000 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 13:46:13.711739 augenrules[897]: No rules Oct 31 13:46:13.712488 systemd[1]: Finished Load Security Auditing Rules. Oct 31 13:46:13.726693 systemd[1]: Started Network Time Synchronization. Oct 31 13:46:13.727533 systemd-timesyncd[888]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 31 13:46:13.727867 systemd-resolved[885]: Positive Trust Anchors: Oct 31 13:46:13.727878 systemd-resolved[885]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 13:46:13.727906 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 13:46:13.727907 systemd[1]: Reached target System Initialization. Oct 31 13:46:13.728825 systemd-resolved[885]: Defaulting to hostname 'linux'. Oct 31 13:46:13.729134 systemd[1]: Started Watch for update engine configuration changes. Oct 31 13:46:13.730285 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 31 13:46:13.731498 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 31 13:46:13.732486 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 31 13:46:13.732534 systemd[1]: Reached target Path Units. Oct 31 13:46:13.733251 systemd[1]: Reached target System Time Set. Oct 31 13:46:13.734240 systemd[1]: Started Daily Log Rotation. Oct 31 13:46:13.735169 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 31 13:46:13.736321 systemd[1]: Reached target Timer Units. Oct 31 13:46:13.737470 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 31 13:46:13.747236 systemd[1]: Starting Docker Socket for the API... Oct 31 13:46:13.750393 systemd[1]: Listening on OpenSSH Server Socket. Oct 31 13:46:13.751734 systemd[1]: Started Network Name Resolution. Oct 31 13:46:13.752661 systemd[1]: Listening on Docker Socket for the API. Oct 31 13:46:13.753604 systemd[1]: Reached target Network. Oct 31 13:46:13.754308 systemd[1]: Reached target Host and Network Name Lookups. Oct 31 13:46:13.755316 systemd[1]: Reached target Socket Units. Oct 31 13:46:13.756118 systemd[1]: Reached target Basic System. Oct 31 13:46:13.756943 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 31 13:46:13.756974 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 31 13:46:13.757940 systemd[1]: Started D-Bus System Message Bus. Oct 31 13:46:13.761147 systemd[1]: Starting Extend Filesystems... Oct 31 13:46:13.762050 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 31 13:46:13.763000 systemd[1]: Starting Generate /run/flatcar/motd... Oct 31 13:46:13.764782 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 31 13:46:13.767887 systemd[1]: Starting Generate sshd host keys... Oct 31 13:46:13.768683 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 31 13:46:13.768722 systemd[1]: Reached target Load system-provided cloud configs. Oct 31 13:46:13.772042 systemd[1]: Starting User Login Management... Oct 31 13:46:13.773082 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 31 13:46:13.773700 systemd[1]: Starting Update Engine... Oct 31 13:46:13.774051 extend-filesystems[906]: Found vda Oct 31 13:46:13.774051 extend-filesystems[906]: Found vda1 Oct 31 13:46:13.774051 extend-filesystems[906]: Found vda2 Oct 31 13:46:13.774051 extend-filesystems[906]: Found vda3 Oct 31 13:46:13.778244 extend-filesystems[906]: Found usr Oct 31 13:46:13.778244 extend-filesystems[906]: Found vda4 Oct 31 13:46:13.778244 extend-filesystems[906]: Found vda6 Oct 31 13:46:13.778244 extend-filesystems[906]: Found vda7 Oct 31 13:46:13.778244 extend-filesystems[906]: Found vda9 Oct 31 13:46:13.778244 extend-filesystems[906]: Checking size of /dev/vda9 Oct 31 13:46:13.774419 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 31 13:46:13.774450 systemd[1]: Reached target Load user-provided cloud configs. Oct 31 13:46:13.778861 systemd[1]: motdgen.service: Deactivated successfully. Oct 31 13:46:13.779081 systemd[1]: Finished Generate /run/flatcar/motd. Oct 31 13:46:13.781538 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 31 13:46:13.781741 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 31 13:46:13.803422 extend-filesystems[906]: Old size kept for /dev/vda9 Oct 31 13:46:13.804729 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 31 13:46:13.804953 systemd[1]: Finished Extend Filesystems. Oct 31 13:46:13.808107 systemd-logind[919]: Watching system buttons on /dev/input/event0 (Power Button) Oct 31 13:46:13.808447 systemd-logind[919]: New seat seat0. Oct 31 13:46:13.814253 systemd[1]: Started User Login Management. Oct 31 13:46:13.817121 update_engine[920]: I1031 13:46:13.816828 920 main.cc:89] Flatcar Update Engine starting Oct 31 13:46:13.819016 systemd[1]: Started Update Engine. Oct 31 13:46:13.820220 update_engine[920]: I1031 13:46:13.819211 920 update_check_scheduler.cc:74] Next update check in 7m35s Oct 31 13:46:13.826803 systemd[1]: Started Cluster reboot manager. Oct 31 13:46:13.852580 locksmithd[929]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 31 13:46:14.393622 systemd[1]: Created slice Slice /system/sshd. Oct 31 13:46:15.018211 sshd_keygen[923]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 31 13:46:15.035814 systemd[1]: Finished Generate sshd host keys. Oct 31 13:46:15.042658 systemd-networkd[841]: eth0: Gained IPv6LL Oct 31 13:46:15.045553 systemd[1]: Starting Generate /run/issue... Oct 31 13:46:15.047266 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52710). Oct 31 13:46:15.050175 systemd[1]: issuegen.service: Deactivated successfully. Oct 31 13:46:15.050391 systemd[1]: Finished Generate /run/issue. Oct 31 13:46:15.052381 systemd[1]: Starting Permit User Sessions... Oct 31 13:46:15.056710 systemd[1]: Finished Permit User Sessions. Oct 31 13:46:15.058691 systemd[1]: Started Getty on tty1. Oct 31 13:46:15.060522 systemd[1]: Started Serial Getty on ttyAMA0. Oct 31 13:46:15.061493 systemd[1]: Reached target Login Prompts. Oct 31 13:46:15.062550 systemd[1]: Reached target Multi-User System. Oct 31 13:46:15.064382 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 31 13:46:15.069327 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 31 13:46:15.069569 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 31 13:46:15.070876 systemd[1]: Startup finished in 716ms (kernel) + 2.347s (initrd) + 4.163s (userspace) = 7.228s. Oct 31 13:46:15.092303 sshd[945]: Accepted publickey for core from 10.0.0.1 port 52710 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:15.094198 sshd[945]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:15.108000 systemd[1]: Created slice User Slice of UID 500. Oct 31 13:46:15.108902 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 31 13:46:15.112088 systemd-logind[919]: New session 1 of user core. Oct 31 13:46:15.114564 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 31 13:46:15.115681 systemd[1]: Starting User Manager for UID 500... Oct 31 13:46:15.117768 systemd[954]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:15.163618 systemd[954]: Queued start job for default target Main User Target. Oct 31 13:46:15.163784 systemd[954]: Reached target Paths. Oct 31 13:46:15.163799 systemd[954]: Reached target Sockets. Oct 31 13:46:15.163809 systemd[954]: Reached target Timers. Oct 31 13:46:15.163820 systemd[954]: Reached target Basic System. Oct 31 13:46:15.163863 systemd[954]: Reached target Main User Target. Oct 31 13:46:15.163877 systemd[954]: Startup finished in 42ms. Oct 31 13:46:15.163926 systemd[1]: Started User Manager for UID 500. Oct 31 13:46:15.172701 systemd[1]: Started Session 1 of User core. Oct 31 13:46:15.227919 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52722). Oct 31 13:46:15.269071 sshd[963]: Accepted publickey for core from 10.0.0.1 port 52722 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:15.270387 sshd[963]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:15.272896 systemd-logind[919]: New session 2 of user core. Oct 31 13:46:15.280678 systemd[1]: Started Session 2 of User core. Oct 31 13:46:15.332295 sshd[963]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:15.342034 systemd[1]: sshd@1-10.0.0.5:22-10.0.0.1:52722.service: Deactivated successfully. Oct 31 13:46:15.342591 systemd[1]: session-2.scope: Deactivated successfully. Oct 31 13:46:15.343148 systemd-logind[919]: Session 2 logged out. Waiting for processes to exit. Oct 31 13:46:15.344062 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52732). Oct 31 13:46:15.344701 systemd-logind[919]: Removed session 2. Oct 31 13:46:15.384670 sshd[969]: Accepted publickey for core from 10.0.0.1 port 52732 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:15.385583 sshd[969]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:15.388593 systemd-logind[919]: New session 3 of user core. Oct 31 13:46:15.404738 systemd[1]: Started Session 3 of User core. Oct 31 13:46:15.453481 sshd[969]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:15.473047 systemd[1]: sshd@2-10.0.0.5:22-10.0.0.1:52732.service: Deactivated successfully. Oct 31 13:46:15.473595 systemd[1]: session-3.scope: Deactivated successfully. Oct 31 13:46:15.474125 systemd-logind[919]: Session 3 logged out. Waiting for processes to exit. Oct 31 13:46:15.475071 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52736). Oct 31 13:46:15.475748 systemd-logind[919]: Removed session 3. Oct 31 13:46:15.514877 sshd[976]: Accepted publickey for core from 10.0.0.1 port 52736 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:15.515773 sshd[976]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:15.518458 systemd-logind[919]: New session 4 of user core. Oct 31 13:46:15.524666 systemd[1]: Started Session 4 of User core. Oct 31 13:46:15.576620 sshd[976]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:15.588442 systemd[1]: sshd@3-10.0.0.5:22-10.0.0.1:52736.service: Deactivated successfully. Oct 31 13:46:15.589109 systemd[1]: session-4.scope: Deactivated successfully. Oct 31 13:46:15.589819 systemd-logind[919]: Session 4 logged out. Waiting for processes to exit. Oct 31 13:46:15.591024 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52750). Oct 31 13:46:15.591705 systemd-logind[919]: Removed session 4. Oct 31 13:46:15.630485 sshd[982]: Accepted publickey for core from 10.0.0.1 port 52750 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:15.631365 sshd[982]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:15.634001 systemd-logind[919]: New session 5 of user core. Oct 31 13:46:15.647702 systemd[1]: Started Session 5 of User core. Oct 31 13:46:15.702212 sudo[985]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 31 13:46:15.702696 sudo[985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 13:46:15.710411 sudo[985]: pam_unix(sudo:session): session closed for user root Oct 31 13:46:15.712475 sshd[982]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:15.725717 dbus-daemon[905]: [system] Reloaded configuration Oct 31 13:46:15.726104 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52762). Oct 31 13:46:15.728255 systemd[1]: sshd@4-10.0.0.5:22-10.0.0.1:52750.service: Deactivated successfully. Oct 31 13:46:15.728924 systemd[1]: session-5.scope: Deactivated successfully. Oct 31 13:46:15.729552 systemd-logind[919]: Session 5 logged out. Waiting for processes to exit. Oct 31 13:46:15.730241 systemd-logind[919]: Removed session 5. Oct 31 13:46:15.765910 sshd[988]: Accepted publickey for core from 10.0.0.1 port 52762 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:15.766966 sshd[988]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:15.769266 systemd-logind[919]: New session 6 of user core. Oct 31 13:46:15.780643 systemd[1]: Started Session 6 of User core. Oct 31 13:46:15.832162 sudo[994]: 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 13:46:15.832373 sudo[994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 13:46:15.834422 sudo[994]: pam_unix(sudo:session): session closed for user root Oct 31 13:46:15.837784 sudo[993]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 31 13:46:15.837980 sudo[993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 13:46:15.855300 systemd[1]: Stopping Load Security Auditing Rules... Oct 31 13:46:15.854000 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 13:46:15.856428 auditctl[997]: No rules Oct 31 13:46:15.856677 systemd[1]: audit-rules.service: Deactivated successfully. Oct 31 13:46:15.856906 systemd[1]: Stopped Load Security Auditing Rules. Oct 31 13:46:15.858089 systemd[1]: Starting Load Security Auditing Rules... Oct 31 13:46:15.872209 augenrules[1014]: No rules Oct 31 13:46:15.872855 systemd[1]: Finished Load Security Auditing Rules. Oct 31 13:46:15.873616 sudo[993]: pam_unix(sudo:session): session closed for user root Oct 31 13:46:15.874679 sshd[988]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:15.883350 systemd[1]: sshd@5-10.0.0.5:22-10.0.0.1:52762.service: Deactivated successfully. Oct 31 13:46:15.883932 systemd[1]: session-6.scope: Deactivated successfully. Oct 31 13:46:15.884500 systemd-logind[919]: Session 6 logged out. Waiting for processes to exit. Oct 31 13:46:15.885431 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52768). Oct 31 13:46:15.886089 systemd-logind[919]: Removed session 6. Oct 31 13:46:15.925134 sshd[1020]: Accepted publickey for core from 10.0.0.1 port 52768 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:15.926079 sshd[1020]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:15.928792 systemd-logind[919]: New session 7 of user core. Oct 31 13:46:15.938698 systemd[1]: Started Session 7 of User core. Oct 31 13:46:15.989393 sudo[1023]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/bash -c cat >/etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem </etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem < Oct 31 13:46:18.852899 update_engine[1272]: Oct 31 13:46:18.852899 update_engine[1272]: Oct 31 13:46:18.852899 update_engine[1272]: Oct 31 13:46:18.852899 update_engine[1272]: Oct 31 13:46:18.852899 update_engine[1272]: Oct 31 13:46:18.852899 update_engine[1272]: Oct 31 13:46:18.852899 update_engine[1272]: Oct 31 13:46:18.852899 update_engine[1272]: Oct 31 13:46:18.852899 update_engine[1272]: I1031 13:46:18.852888 1272 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 31 13:46:18.856392 update_engine[1272]: I1031 13:46:18.856362 1272 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 31 13:46:18.857013 update_engine[1272]: I1031 13:46:18.856967 1272 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 31 13:46:18.857889 update_engine[1272]: I1031 13:46:18.857832 1272 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 31 13:46:18.857998 update_engine[1272]: I1031 13:46:18.857976 1272 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Oct 31 13:46:18.857998 update_engine[1272]: I1031 13:46:18.857991 1272 omaha_request_action.cc:619] Omaha request response: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.857998 update_engine[1272]: Oct 31 13:46:18.858369 systemd-logind[919]: Removed session 24. Oct 31 13:46:18.862386 update_engine[1272]: I1031 13:46:18.862355 1272 omaha_request_action.cc:447] Omaha Response manifest version = Oct 31 13:46:18.862386 update_engine[1272]: I1031 13:46:18.862385 1272 omaha_request_action.cc:470] Found 1 url(s) Oct 31 13:46:18.862486 update_engine[1272]: I1031 13:46:18.862398 1272 omaha_request_action.cc:506] Processing first of 1 package(s) Oct 31 13:46:18.862486 update_engine[1272]: I1031 13:46:18.862402 1272 omaha_request_action.cc:513] Omaha Response package name = update.gz Oct 31 13:46:18.862486 update_engine[1272]: I1031 13:46:18.862406 1272 omaha_request_action.cc:529] Url0: http://10.0.0.4:34567/packages/update.gz Oct 31 13:46:18.862486 update_engine[1272]: I1031 13:46:18.862413 1272 omaha_request_action.cc:541] Payload size = 426251591 bytes Oct 31 13:46:18.862486 update_engine[1272]: I1031 13:46:18.862423 1272 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Oct 31 13:46:18.862486 update_engine[1272]: I1031 13:46:18.862434 1272 payload_state.cc:51] Resetting all persisted state as this is a new response Oct 31 13:46:18.862486 update_engine[1272]: I1031 13:46:18.862438 1272 payload_state.cc:360] Current Response Signature = Oct 31 13:46:18.862486 update_engine[1272]: NumURLs = 1 Oct 31 13:46:18.862486 update_engine[1272]: Url0 = http://10.0.0.4:34567/packages/update.gz Oct 31 13:46:18.862486 update_engine[1272]: Payload Size = 426251591 Oct 31 13:46:18.862486 update_engine[1272]: Payload Sha256 Hash = qwysf93IzFKZIIEIOAr8FB/Ur4+2vzR6efmfEvB0U+Q= Oct 31 13:46:18.862486 update_engine[1272]: Is Delta Payload = 0 Oct 31 13:46:18.862486 update_engine[1272]: Max Failure Count Per Url = 10 Oct 31 13:46:18.862486 update_engine[1272]: Disable Payload Backoff = 1 Oct 31 13:46:18.862752 update_engine[1272]: I1031 13:46:18.862519 1272 payload_state.cc:381] Payload Attempt Number = 0 Oct 31 13:46:18.862752 update_engine[1272]: I1031 13:46:18.862582 1272 payload_state.cc:404] Current URL Index = 0 Oct 31 13:46:18.862752 update_engine[1272]: I1031 13:46:18.862613 1272 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Oct 31 13:46:18.862752 update_engine[1272]: I1031 13:46:18.862648 1272 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Oct 31 13:46:18.862752 update_engine[1272]: I1031 13:46:18.862651 1272 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 31 13:46:18.862752 update_engine[1272]: I1031 13:46:18.862684 1272 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Oct 31 13:46:18.862752 update_engine[1272]: I1031 13:46:18.862700 1272 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 31 13:46:18.862752 update_engine[1272]: I1031 13:46:18.862704 1272 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Oct 31 13:46:18.862752 update_engine[1272]: I1031 13:46:18.862718 1272 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Oct 31 13:46:18.862752 update_engine[1272]: E1031 13:46:18.862723 1272 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Oct 31 13:46:18.863200 update_engine[1272]: I1031 13:46:18.863183 1272 omaha_response_handler_action.cc:85] Using this install plan: Oct 31 13:46:18.863200 update_engine[1272]: I1031 13:46:18.863196 1272 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.4:34567/packages/update.gz, payload size: 426251591, payload hash: qwysf93IzFKZIIEIOAr8FB/Ur4+2vzR6efmfEvB0U+Q=, partition_path: /dev/vda4, kernel_path: /boot/flatcar/vmlinuz-b, pcr_policy_path: /var/lib/update_engine/pcrs-b.zip, old_partition_path: /dev/vda3, old_kernel_path: /boot/flatcar/vmlinuz-a Oct 31 13:46:18.863292 update_engine[1272]: I1031 13:46:18.863276 1272 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Oct 31 13:46:18.865372 update_engine[1272]: I1031 13:46:18.865337 1272 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Oct 31 13:46:18.894036 sshd[1294]: Accepted publickey for core from 10.0.0.1 port 52922 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:18.895030 sshd[1294]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:18.897693 systemd-logind[919]: New session 25 of user core. Oct 31 13:46:18.911732 systemd[1]: Started Session 25 of User core. Oct 31 13:46:18.966567 sshd[1294]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:18.970378 systemd[1]: sshd@24-10.0.0.5:22-10.0.0.1:52922.service: Deactivated successfully. Oct 31 13:46:18.971169 systemd[1]: session-25.scope: Deactivated successfully. Oct 31 13:46:18.971944 systemd-logind[919]: Session 25 logged out. Waiting for processes to exit. Oct 31 13:46:18.972590 systemd-logind[919]: Removed session 25. Oct 31 13:46:20.659858 update_engine[1272]: I1031 13:46:20.659817 1272 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Oct 31 13:46:20.659858 update_engine[1272]: I1031 13:46:20.659859 1272 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Oct 31 13:46:20.716038 update_engine[1272]: E1031 13:46:20.715986 1272 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Oct 31 13:46:20.716038 update_engine[1272]: I1031 13:46:20.716029 1272 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Oct 31 13:46:20.716038 update_engine[1272]: I1031 13:46:20.716034 1272 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Oct 31 13:46:20.716038 update_engine[1272]: I1031 13:46:20.716038 1272 update_attempter.cc:302] Processing Done. Oct 31 13:46:20.716238 update_engine[1272]: E1031 13:46:20.716080 1272 update_attempter.cc:615] Update failed. Oct 31 13:46:20.716238 update_engine[1272]: I1031 13:46:20.716085 1272 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Oct 31 13:46:20.716238 update_engine[1272]: I1031 13:46:20.716089 1272 payload_state.cc:276] Incrementing the URL failure count Oct 31 13:46:20.716238 update_engine[1272]: I1031 13:46:20.716092 1272 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 31 13:46:20.716362 update_engine[1272]: I1031 13:46:20.716267 1272 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 31 13:46:20.716362 update_engine[1272]: I1031 13:46:20.716289 1272 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.4:34567/v1/update Oct 31 13:46:20.716362 update_engine[1272]: I1031 13:46:20.716292 1272 omaha_request_action.cc:269] Request: Oct 31 13:46:20.716362 update_engine[1272]: Oct 31 13:46:20.716362 update_engine[1272]: Oct 31 13:46:20.716362 update_engine[1272]: Oct 31 13:46:20.716362 update_engine[1272]: Oct 31 13:46:20.716362 update_engine[1272]: Oct 31 13:46:20.716362 update_engine[1272]: Oct 31 13:46:20.716362 update_engine[1272]: I1031 13:46:20.716297 1272 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 31 13:46:20.716587 update_engine[1272]: I1031 13:46:20.716372 1272 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 31 13:46:20.716587 update_engine[1272]: I1031 13:46:20.716476 1272 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 31 13:46:20.905669 update_engine[1272]: I1031 13:46:20.905622 1272 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 31 13:46:20.905815 update_engine[1272]: I1031 13:46:20.905707 1272 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Oct 31 13:46:20.905815 update_engine[1272]: I1031 13:46:20.905714 1272 omaha_request_action.cc:619] Omaha request response: Oct 31 13:46:20.905815 update_engine[1272]: Oct 31 13:46:20.905815 update_engine[1272]: Oct 31 13:46:20.905815 update_engine[1272]: Oct 31 13:46:20.905815 update_engine[1272]: Oct 31 13:46:20.905815 update_engine[1272]: Oct 31 13:46:20.905815 update_engine[1272]: Oct 31 13:46:20.905815 update_engine[1272]: E1031 13:46:20.905728 1272 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Oct 31 13:46:20.905815 update_engine[1272]: I1031 13:46:20.905733 1272 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 31 13:46:20.905815 update_engine[1272]: I1031 13:46:20.905736 1272 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 31 13:46:20.905815 update_engine[1272]: I1031 13:46:20.905740 1272 update_attempter.cc:302] Processing Done. Oct 31 13:46:20.905815 update_engine[1272]: I1031 13:46:20.905745 1272 update_attempter.cc:306] Error event sent. Oct 31 13:46:29.002208 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57728). Oct 31 13:46:29.044373 sshd[1307]: Accepted publickey for core from 10.0.0.1 port 57728 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:29.045401 sshd[1307]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:29.045769 kernel: kauditd_printk_skb: 135 callbacks suppressed Oct 31 13:46:29.045804 kernel: audit: type=1006 audit(1761918389.043:359): pid=1307 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 13:46:29.049461 systemd-logind[919]: New session 26 of user core. Oct 31 13:46:29.060654 systemd[1]: Started Session 26 of User core. Oct 31 13:46:29.115429 sshd[1307]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:29.130010 systemd[1]: sshd@25-10.0.0.5:22-10.0.0.1:57728.service: Deactivated successfully. Oct 31 13:46:29.130623 systemd[1]: session-26.scope: Deactivated successfully. Oct 31 13:46:29.131184 systemd-logind[919]: Session 26 logged out. Waiting for processes to exit. Oct 31 13:46:29.132125 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57734). Oct 31 13:46:29.132708 systemd-logind[919]: Removed session 26. Oct 31 13:46:29.171620 sshd[1314]: Accepted publickey for core from 10.0.0.1 port 57734 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:29.174544 kernel: audit: type=1006 audit(1761918389.170:360): pid=1314 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 13:46:29.174672 sshd[1314]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:29.177324 systemd-logind[919]: New session 27 of user core. Oct 31 13:46:29.187694 systemd[1]: Started Session 27 of User core. Oct 31 13:46:29.241603 sshd[1314]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:29.252321 systemd[1]: sshd@26-10.0.0.5:22-10.0.0.1:57734.service: Deactivated successfully. Oct 31 13:46:29.252970 systemd[1]: session-27.scope: Deactivated successfully. Oct 31 13:46:29.253877 systemd-logind[919]: Session 27 logged out. Waiting for processes to exit. Oct 31 13:46:29.254896 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49344). Oct 31 13:46:29.255487 systemd-logind[919]: Removed session 27. Oct 31 13:46:29.294602 sshd[1322]: Accepted publickey for core from 10.0.0.1 port 49344 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:29.295494 sshd[1322]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:29.297541 kernel: audit: type=1006 audit(1761918389.293:361): pid=1322 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 13:46:29.299411 systemd-logind[919]: New session 28 of user core. Oct 31 13:46:29.309653 systemd[1]: Started Session 28 of User core. Oct 31 13:46:29.357394 sshd[1322]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:29.366005 systemd[1]: sshd@27-10.0.0.5:22-10.0.0.1:49344.service: Deactivated successfully. Oct 31 13:46:29.366599 systemd[1]: session-28.scope: Deactivated successfully. Oct 31 13:46:29.367113 systemd-logind[919]: Session 28 logged out. Waiting for processes to exit. Oct 31 13:46:29.368052 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49350). Oct 31 13:46:29.368621 systemd-logind[919]: Removed session 28. Oct 31 13:46:29.407499 sshd[1328]: Accepted publickey for core from 10.0.0.1 port 49350 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:29.408535 sshd[1328]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:29.410541 kernel: audit: type=1006 audit(1761918389.407:362): pid=1328 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 13:46:29.412657 systemd-logind[919]: New session 29 of user core. Oct 31 13:46:29.422729 systemd[1]: Started Session 29 of User core. Oct 31 13:46:29.470799 sshd[1328]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:29.479610 systemd[1]: sshd@28-10.0.0.5:22-10.0.0.1:49350.service: Deactivated successfully. Oct 31 13:46:29.480222 systemd[1]: session-29.scope: Deactivated successfully. Oct 31 13:46:29.480811 systemd-logind[919]: Session 29 logged out. Waiting for processes to exit. Oct 31 13:46:29.481872 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:49352). Oct 31 13:46:29.482480 systemd-logind[919]: Removed session 29. Oct 31 13:46:29.521766 sshd[1334]: Accepted publickey for core from 10.0.0.1 port 49352 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:29.522170 sshd[1334]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:29.524555 kernel: audit: type=1006 audit(1761918389.521:363): pid=1334 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 13:46:29.526383 systemd-logind[919]: New session 30 of user core. Oct 31 13:46:29.535686 systemd[1]: Started Session 30 of User core. Oct 31 13:46:29.586858 sudo[1338]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Oct 31 13:46:29.587077 sudo[1338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 13:46:29.592871 systemd[1]: sshd.socket: Deactivated successfully. Oct 31 13:46:29.593241 systemd[1]: Closed OpenSSH Server Socket. Oct 31 13:46:29.594217 sudo[1338]: pam_unix(sudo:session): session closed for user root Oct 31 13:46:29.598167 sudo[1337]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Oct 31 13:46:29.598381 sudo[1337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 13:46:29.613191 systemd[1]: Stopping Session 1 of User core... Oct 31 13:46:29.613471 sshd[945]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:29.614855 systemd[1]: Stopping Session 30 of User core... Oct 31 13:46:29.615097 sshd[1334]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:29.615499 sudo[1337]: pam_unix(sudo:session): session closed for user root Oct 31 13:46:29.616555 systemd[1]: Removed slice Slice /system/addon-config. Oct 31 13:46:29.617930 systemd[1]: Removed slice Slice /system/addon-run. Oct 31 13:46:29.619250 systemd[1]: Removed slice Slice /system/modprobe. Oct 31 13:46:29.620619 systemd[1]: Removed slice Slice /system/system-cloudinit. Oct 31 13:46:29.621872 systemd[1]: Stopped target Multi-User System. Oct 31 13:46:29.622879 systemd[1]: Stopped target Login Prompts. Oct 31 13:46:29.623803 systemd[1]: Stopped target Containers. Oct 31 13:46:29.624703 systemd[1]: Stopped target Host and Network Name Lookups. Oct 31 13:46:29.625927 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 31 13:46:29.627108 systemd[1]: Stopped target Timer Units. Oct 31 13:46:29.628018 systemd[1]: logrotate.timer: Deactivated successfully. Oct 31 13:46:29.628171 systemd[1]: Stopped Daily Log Rotation. Oct 31 13:46:29.629041 systemd[1]: mdadm.timer: Deactivated successfully. Oct 31 13:46:29.629186 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Oct 31 13:46:29.630432 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 31 13:46:29.630605 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Oct 31 13:46:29.631941 systemd[1]: Stopped target System Time Set. Oct 31 13:46:29.632914 systemd[1]: Stopped target Load user-provided cloud configs. Oct 31 13:46:29.634216 systemd[1]: Stopped target Load system-provided cloud configs. Oct 31 13:46:29.635427 systemd[1]: systemd-coredump.socket: Deactivated successfully. Oct 31 13:46:29.635650 systemd[1]: Closed Process Core Dump Socket. Oct 31 13:46:29.637334 systemd[1]: Unmounting Boot partition... Oct 31 13:46:29.638865 systemd[1]: Stopping Getty on tty1... Oct 31 13:46:29.639857 systemd[1]: Stopping Serial Getty on ttyAMA0... Oct 31 13:46:29.640829 systemd[1]: Stopping OpenSSH per-connection server daemon... Oct 31 13:46:29.641863 sshd[1334]: pam_systemd(sshd:session): Failed to release session: Interrupted system call Oct 31 13:46:29.642773 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:49352)... Oct 31 13:46:29.643873 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Oct 31 13:46:29.644019 systemd[1]: Stopped Commit a transient machine-id on disk. Oct 31 13:46:29.645136 systemd[1]: Stopped target First Boot Complete. Oct 31 13:46:29.647223 systemd[1]: Stopping Load/Save Random Seed... Oct 31 13:46:29.648267 systemd[1]: Stopping Update Engine... Oct 31 13:46:29.652480 systemd[1]: sshd@0-10.0.0.5:22-10.0.0.1:52710.service: Deactivated successfully. Oct 31 13:46:29.652723 systemd[1]: Stopped OpenSSH per-connection server daemon. Oct 31 13:46:29.654433 systemd[1]: getty@tty1.service: Deactivated successfully. Oct 31 13:46:29.654659 systemd[1]: Stopped Getty on tty1. Oct 31 13:46:29.655889 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Oct 31 13:46:29.656075 systemd[1]: Stopped Serial Getty on ttyAMA0. Oct 31 13:46:29.657368 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Oct 31 13:46:29.657437 systemd[1]: update-engine.service: Failed with result 'exit-code'. Oct 31 13:46:29.657702 systemd[1]: Stopped Update Engine. Oct 31 13:46:29.658446 systemd[1]: update-engine.service: Consumed 2.387s CPU time. Oct 31 13:46:29.658710 systemd[1]: sshd@29-10.0.0.5:22-10.0.0.1:49352.service: Deactivated successfully. Oct 31 13:46:29.658882 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:49352). Oct 31 13:46:29.667296 systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 31 13:46:29.667515 systemd[1]: Stopped Load/Save Random Seed. Oct 31 13:46:29.668689 systemd[1]: session-30.scope: Deactivated successfully. Oct 31 13:46:29.668925 systemd[1]: Stopped Session 30 of User core. Oct 31 13:46:29.670109 systemd[1]: boot.mount: Deactivated successfully. Oct 31 13:46:29.670356 systemd[1]: Unmounted Boot partition. Oct 31 13:46:29.671379 systemd[1]: session-1.scope: Deactivated successfully. Oct 31 13:46:29.671661 systemd[1]: Stopped Session 1 of User core. Oct 31 13:46:29.672950 systemd-logind[919]: Session 1 logged out. Waiting for processes to exit. Oct 31 13:46:29.674358 systemd-logind[919]: Session 30 logged out. Waiting for processes to exit. Oct 31 13:46:29.674729 systemd[1]: Removed slice Slice /system/getty. Oct 31 13:46:29.676102 systemd[1]: Removed slice Slice /system/serial-getty. Oct 31 13:46:29.677458 systemd[1]: Removed slice Slice /system/sshd. Oct 31 13:46:29.678417 systemd[1]: sshd-keygen.service: Deactivated successfully. Oct 31 13:46:29.678547 systemd[1]: Stopped Generate sshd host keys. Oct 31 13:46:29.679393 systemd[1]: sshd-keygen.service: Consumed 1.238s CPU time. Oct 31 13:46:29.679444 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Oct 31 13:46:29.679585 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Oct 31 13:46:29.680919 systemd[1]: Stopping User Login Management... Oct 31 13:46:29.681925 systemd[1]: Stopping User Manager for UID 500... Oct 31 13:46:29.684650 systemd-logind[919]: Removed session 30. Oct 31 13:46:29.684846 systemd[954]: Stopped target Main User Target. Oct 31 13:46:29.684867 systemd[954]: Stopped target Basic System. Oct 31 13:46:29.684877 systemd[954]: Stopped target Paths. Oct 31 13:46:29.684885 systemd[954]: Stopped target Sockets. Oct 31 13:46:29.684902 systemd[954]: Reached target Shutdown. Oct 31 13:46:29.684908 systemd[954]: Stopped target Timers. Oct 31 13:46:29.685028 systemd[954]: Finished Exit the Session. Oct 31 13:46:29.685054 systemd[954]: Reached target Exit the Session. Oct 31 13:46:29.690286 systemd[1]: user@500.service: Deactivated successfully. Oct 31 13:46:29.690467 systemd[1]: Stopped User Manager for UID 500. Oct 31 13:46:29.692434 systemd[1]: Stopping User Runtime Directory /run/user/500... Oct 31 13:46:29.697848 systemd[1]: run-user-500.mount: Deactivated successfully. Oct 31 13:46:29.697925 systemd[1]: Unmounted /run/user/500. Oct 31 13:46:29.699137 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Oct 31 13:46:29.699348 systemd[1]: Stopped User Runtime Directory /run/user/500. Oct 31 13:46:29.700793 systemd[1]: systemd-logind.service: Deactivated successfully. Oct 31 13:46:29.701044 systemd[1]: Stopped User Login Management. Oct 31 13:46:29.702623 systemd[1]: Removed slice User Slice of UID 500. Oct 31 13:46:29.704303 systemd[1]: Stopping D-Bus System Message Bus... Oct 31 13:46:29.706121 systemd[1]: Stopping Permit User Sessions... Oct 31 13:46:29.706000 audit: BPF prog-id=53 op=UNLOAD Oct 31 13:46:29.708562 kernel: audit: type=1334 audit(1761918389.706:364): prog-id=53 op=UNLOAD Oct 31 13:46:29.708784 systemd[1]: dbus.service: Deactivated successfully. Oct 31 13:46:29.709028 systemd[1]: Stopped D-Bus System Message Bus. Oct 31 13:46:29.711240 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Oct 31 13:46:29.711453 systemd[1]: Stopped Permit User Sessions. Oct 31 13:46:29.712410 systemd[1]: Stopped target Basic System. Oct 31 13:46:29.713252 systemd[1]: Stopped target Network. Oct 31 13:46:29.713987 systemd[1]: Stopped target Path Units. Oct 31 13:46:29.714743 systemd[1]: motdgen.path: Deactivated successfully. Oct 31 13:46:29.718563 systemd[1]: Stopped Watch for update engine configuration changes. Oct 31 13:46:29.719617 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Oct 31 13:46:29.723573 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 31 13:46:29.724689 systemd[1]: Stopped target Remote File Systems. Oct 31 13:46:29.725509 systemd[1]: Stopped target Slice Units. Oct 31 13:46:29.726549 systemd[1]: Removed slice User and Session Slice. Oct 31 13:46:29.727393 systemd[1]: Stopped target Socket Units. Oct 31 13:46:29.728251 systemd[1]: dbus.socket: Deactivated successfully. Oct 31 13:46:29.728309 systemd[1]: Closed D-Bus System Message Bus Socket. Oct 31 13:46:29.729421 systemd[1]: docker.socket: Deactivated successfully. Oct 31 13:46:29.729676 systemd[1]: Closed Docker Socket for the API. Oct 31 13:46:29.730503 systemd[1]: Stopped target System Initialization. Oct 31 13:46:29.731374 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Oct 31 13:46:29.731499 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Oct 31 13:46:29.732637 systemd[1]: Stopped target Local Verity Protected Volumes. Oct 31 13:46:29.734391 systemd[1]: Stopping Load Security Auditing Rules... Oct 31 13:46:29.735476 systemd[1]: Stopping Network Name Resolution... Oct 31 13:46:29.736636 systemd[1]: Stopping Network Time Synchronization... Oct 31 13:46:29.737465 systemd[1]: systemd-update-done.service: Deactivated successfully. Oct 31 13:46:29.737559 systemd[1]: Stopped Update is Completed. Oct 31 13:46:29.737637 auditctl[1352]: No rules Oct 31 13:46:29.738342 systemd[1]: ldconfig.service: Deactivated successfully. Oct 31 13:46:29.738406 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Oct 31 13:46:29.739451 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Oct 31 13:46:29.739513 systemd[1]: Stopped Rebuild Hardware Database. Oct 31 13:46:29.740350 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Oct 31 13:46:29.740411 systemd[1]: Stopped Rebuild Journal Catalog. Oct 31 13:46:29.742122 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Oct 31 13:46:29.744833 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 31 13:46:29.745072 systemd[1]: Stopped Network Name Resolution. Oct 31 13:46:29.746193 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Oct 31 13:46:29.746427 systemd[1]: Stopped Network Time Synchronization. Oct 31 13:46:29.747651 systemd[1]: audit-rules.service: Deactivated successfully. Oct 31 13:46:29.747870 systemd[1]: Stopped Load Security Auditing Rules. Oct 31 13:46:29.750195 systemd[1]: Stopping Network Configuration... Oct 31 13:46:29.754565 systemd-networkd[841]: eth0: DHCPv6 lease lost Oct 31 13:46:29.760000 audit: BPF prog-id=56 op=UNLOAD Oct 31 13:46:29.760967 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 31 13:46:29.761207 systemd[1]: Stopped Network Configuration. Oct 31 13:46:29.760000 audit: BPF prog-id=57 op=UNLOAD Oct 31 13:46:29.762543 kernel: audit: type=1334 audit(1761918389.760:365): prog-id=56 op=UNLOAD Oct 31 13:46:29.762569 kernel: audit: type=1334 audit(1761918389.760:366): prog-id=57 op=UNLOAD Oct 31 13:46:29.763474 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Oct 31 13:46:29.763717 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Oct 31 13:46:29.765303 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 31 13:46:29.765387 systemd[1]: Closed Network Service Netlink Socket. Oct 31 13:46:29.765000 audit: BPF prog-id=61 op=UNLOAD Oct 31 13:46:29.766402 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 31 13:46:29.766457 systemd[1]: Stopped Apply Kernel Variables. Oct 31 13:46:29.767547 kernel: audit: type=1334 audit(1761918389.765:367): prog-id=61 op=UNLOAD Oct 31 13:46:29.768188 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 31 13:46:29.768256 systemd[1]: Stopped Create Volatile Files and Directories. Oct 31 13:46:29.769263 systemd[1]: Stopped target Local File Systems. Oct 31 13:46:29.771019 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Oct 31 13:46:29.772538 systemd[1]: Unmounting External Media Directory... Oct 31 13:46:29.774242 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Oct 31 13:46:29.776092 systemd[1]: Unmounting /run/torcx/unpack... Oct 31 13:46:29.777379 systemd[1]: Unmounting Temporary Directory /tmp... Oct 31 13:46:29.778810 systemd[1]: Unmounting /usr/share/oem... Oct 31 13:46:29.780341 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Oct 31 13:46:29.783692 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 31 13:46:29.783961 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Oct 31 13:46:29.785255 systemd[1]: media.mount: Deactivated successfully. Oct 31 13:46:29.785513 systemd[1]: Unmounted External Media Directory. Oct 31 13:46:29.786488 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Oct 31 13:46:29.786795 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Oct 31 13:46:29.797678 systemd[1]: tmp.mount: Deactivated successfully. Oct 31 13:46:29.797944 systemd[1]: Unmounted Temporary Directory /tmp. Oct 31 13:46:29.798960 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Oct 31 13:46:29.799183 systemd[1]: Unmounted /run/torcx/unpack. Oct 31 13:46:29.800214 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 31 13:46:29.800457 systemd[1]: Unmounted /usr/share/oem. Oct 31 13:46:29.801411 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 31 13:46:29.801685 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Oct 31 13:46:29.803069 systemd[1]: Stopped target Swaps. Oct 31 13:46:29.803791 systemd[1]: Reached target Unmount All Filesystems. Oct 31 13:46:29.804710 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Oct 31 13:46:29.804795 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Oct 31 13:46:29.806092 systemd[1]: Removed slice Slice /system/systemd-fsck. Oct 31 13:46:29.807029 systemd[1]: Stopped target Preparation for Local File Systems. Oct 31 13:46:29.808068 systemd[1]: lvm2-activation.service: Deactivated successfully. Oct 31 13:46:29.808140 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 31 13:46:29.809297 systemd[1]: Stopped target Local Encrypted Volumes. Oct 31 13:46:29.810218 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 31 13:46:29.810261 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 31 13:46:29.811384 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Oct 31 13:46:29.815588 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Oct 31 13:46:29.816764 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Oct 31 13:46:29.816845 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 31 13:46:29.817781 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 31 13:46:29.817854 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 31 13:46:29.818785 systemd[1]: systemd-sysusers.service: Deactivated successfully. Oct 31 13:46:29.818855 systemd[1]: Stopped Create System Users. Oct 31 13:46:29.819635 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Oct 31 13:46:29.819693 systemd[1]: Stopped Remount Root and Kernel File Systems. Oct 31 13:46:29.820633 systemd[1]: Reached target System Shutdown. Oct 31 13:46:29.821422 systemd[1]: Reached target Late Shutdown Services. Oct 31 13:46:29.823073 systemd[1]: systemd-reboot.service: Deactivated successfully. Oct 31 13:46:29.823116 systemd[1]: Finished System Reboot. Oct 31 13:46:29.823981 systemd[1]: Reached target System Reboot. Oct 31 13:46:29.824806 systemd[1]: Shutting down. Oct 31 13:46:29.824000 audit: BPF prog-id=55 op=UNLOAD Oct 31 13:46:29.824000 audit: BPF prog-id=54 op=UNLOAD Oct 31 13:46:29.827547 kernel: audit: type=1334 audit(1761918389.824:368): prog-id=55 op=UNLOAD Oct 31 13:46:29.827000 audit: BPF prog-id=60 op=UNLOAD Oct 31 13:46:29.827000 audit: BPF prog-id=59 op=UNLOAD Oct 31 13:46:29.827000 audit: BPF prog-id=58 op=UNLOAD Oct 31 13:46:29.829000 audit: BPF prog-id=64 op=UNLOAD Oct 31 13:46:29.829000 audit: BPF prog-id=63 op=UNLOAD Oct 31 13:46:29.829000 audit: BPF prog-id=62 op=UNLOAD Oct 31 13:46:29.861586 systemd-shutdown[1]: Syncing filesystems and block devices. Oct 31 13:46:29.868199 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Oct 31 13:46:29.868263 systemd-journald[804]: Journal stopped -- Reboot -- Oct 31 13:46:37.681985 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 31 13:46:37.682006 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 13:46:37.682015 kernel: efi: EFI v2.70 by EDK II Oct 31 13:46:37.682021 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 31 13:46:37.682026 kernel: efi: seeding entropy pool Oct 31 13:46:37.682030 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 31 13:46:37.682037 kernel: ACPI: Early table checksum verification disabled Oct 31 13:46:37.682042 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 31 13:46:37.682047 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 31 13:46:37.682056 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:37.682063 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:37.682068 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:37.682074 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:37.682080 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:37.682086 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:37.682091 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:37.682097 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:37.682103 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 13:46:37.682108 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 31 13:46:37.682113 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 31 13:46:37.682119 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 13:46:37.682124 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 31 13:46:37.682129 kernel: Zone ranges: Oct 31 13:46:37.682134 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 13:46:37.682139 kernel: DMA32 empty Oct 31 13:46:37.682144 kernel: Normal empty Oct 31 13:46:37.682149 kernel: Movable zone start for each node Oct 31 13:46:37.682155 kernel: Early memory node ranges Oct 31 13:46:37.682161 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 31 13:46:37.682166 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 31 13:46:37.682171 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 31 13:46:37.682176 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 31 13:46:37.682182 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 31 13:46:37.682187 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 31 13:46:37.682192 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 31 13:46:37.682198 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 31 13:46:37.682203 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 31 13:46:37.682208 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 31 13:46:37.682213 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 31 13:46:37.682219 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 13:46:37.682227 kernel: On node 0 totalpages: 643072 Oct 31 13:46:37.682233 kernel: DMA zone: 10048 pages used for memmap Oct 31 13:46:37.682238 kernel: DMA zone: 0 pages reserved Oct 31 13:46:37.682244 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 31 13:46:37.682249 kernel: psci: probing for conduit method from ACPI. Oct 31 13:46:37.682255 kernel: psci: PSCIv1.1 detected in firmware. Oct 31 13:46:37.682260 kernel: psci: Using standard PSCI v0.2 function IDs Oct 31 13:46:37.682266 kernel: psci: Trusted OS migration not required Oct 31 13:46:37.682273 kernel: psci: SMC Calling Convention v1.1 Oct 31 13:46:37.682278 kernel: ACPI: SRAT not present Oct 31 13:46:37.682284 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 31 13:46:37.682289 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 31 13:46:37.682295 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 31 13:46:37.682300 kernel: Detected PIPT I-cache on CPU0 Oct 31 13:46:37.682306 kernel: CPU features: detected: GIC system register CPU interface Oct 31 13:46:37.682311 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 31 13:46:37.682317 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 31 13:46:37.682322 kernel: CPU features: detected: Hardware dirty bit management Oct 31 13:46:37.682328 kernel: CPU features: detected: Spectre-v4 Oct 31 13:46:37.682334 kernel: CPU features: detected: ARM erratum 1418040 Oct 31 13:46:37.682339 kernel: CPU features: detected: Spectre-BHB Oct 31 13:46:37.682345 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 31 13:46:37.682351 kernel: Policy zone: DMA Oct 31 13:46:37.682357 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 13:46:37.682363 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 31 13:46:37.682369 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 31 13:46:37.682374 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 31 13:46:37.682380 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 31 13:46:37.682386 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 31 13:46:37.682391 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 31 13:46:37.682404 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 31 13:46:37.682410 kernel: rcu: RCU event tracing is enabled. Oct 31 13:46:37.682415 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 31 13:46:37.682421 kernel: Trampoline variant of Tasks RCU enabled. Oct 31 13:46:37.682427 kernel: Tracing variant of Tasks RCU enabled. Oct 31 13:46:37.682432 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 31 13:46:37.682438 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 31 13:46:37.682443 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 31 13:46:37.682449 kernel: GICv3: 256 SPIs implemented Oct 31 13:46:37.682455 kernel: GICv3: 0 Extended SPIs implemented Oct 31 13:46:37.682460 kernel: GICv3: Distributor has no Range Selector support Oct 31 13:46:37.682465 kernel: GICv3: 16 PPIs implemented Oct 31 13:46:37.682472 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 31 13:46:37.682477 kernel: ACPI: SRAT not present Oct 31 13:46:37.682483 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 31 13:46:37.682488 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 31 13:46:37.682494 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 31 13:46:37.682500 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 31 13:46:37.682505 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 31 13:46:37.682511 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 13:46:37.682516 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 31 13:46:37.682522 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 31 13:46:37.682528 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 31 13:46:37.682541 kernel: arm-pv: using stolen time PV Oct 31 13:46:37.682548 kernel: Console: colour dummy device 80x25 Oct 31 13:46:37.682554 kernel: ACPI: Core revision 20200925 Oct 31 13:46:37.682560 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 31 13:46:37.682565 kernel: pid_max: default: 32768 minimum: 301 Oct 31 13:46:37.682571 kernel: LSM: Security Framework initializing Oct 31 13:46:37.682577 kernel: SELinux: Initializing. Oct 31 13:46:37.682583 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 13:46:37.682588 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 13:46:37.682594 kernel: rcu: Hierarchical SRCU implementation. Oct 31 13:46:37.682601 kernel: Platform MSI: ITS@0x8080000 domain created Oct 31 13:46:37.682606 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 31 13:46:37.682612 kernel: Remapping and enabling EFI services. Oct 31 13:46:37.682618 kernel: smp: Bringing up secondary CPUs ... Oct 31 13:46:37.682623 kernel: Detected PIPT I-cache on CPU1 Oct 31 13:46:37.682629 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 31 13:46:37.682635 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 31 13:46:37.682641 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 13:46:37.682646 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 31 13:46:37.682652 kernel: Detected PIPT I-cache on CPU2 Oct 31 13:46:37.682659 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 31 13:46:37.682665 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 31 13:46:37.682671 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 13:46:37.682677 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 31 13:46:37.682683 kernel: Detected PIPT I-cache on CPU3 Oct 31 13:46:37.682692 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 31 13:46:37.682699 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 31 13:46:37.682706 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 13:46:37.682712 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 31 13:46:37.682718 kernel: smp: Brought up 1 node, 4 CPUs Oct 31 13:46:37.682724 kernel: SMP: Total of 4 processors activated. Oct 31 13:46:37.682730 kernel: CPU features: detected: Privileged Access Never Oct 31 13:46:37.682736 kernel: CPU features: detected: LSE atomic instructions Oct 31 13:46:37.682742 kernel: CPU features: detected: User Access Override Oct 31 13:46:37.682749 kernel: CPU features: detected: 32-bit EL0 Support Oct 31 13:46:37.682755 kernel: CPU features: detected: Common not Private translations Oct 31 13:46:37.682760 kernel: CPU features: detected: RAS Extension Support Oct 31 13:46:37.682768 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 31 13:46:37.682774 kernel: CPU features: detected: CRC32 instructions Oct 31 13:46:37.682780 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 31 13:46:37.682786 kernel: CPU: All CPU(s) started at EL1 Oct 31 13:46:37.682792 kernel: alternatives: patching kernel code Oct 31 13:46:37.682799 kernel: devtmpfs: initialized Oct 31 13:46:37.682805 kernel: KASLR enabled Oct 31 13:46:37.682811 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 31 13:46:37.682817 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 31 13:46:37.682824 kernel: pinctrl core: initialized pinctrl subsystem Oct 31 13:46:37.682830 kernel: SMBIOS 3.0.0 present. Oct 31 13:46:37.682836 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 31 13:46:37.682877 kernel: NET: Registered protocol family 16 Oct 31 13:46:37.682901 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 31 13:46:37.682908 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 31 13:46:37.682916 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 31 13:46:37.682922 kernel: audit: initializing netlink subsys (disabled) Oct 31 13:46:37.682928 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Oct 31 13:46:37.682949 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 31 13:46:37.682955 kernel: cpuidle: using governor menu Oct 31 13:46:37.682961 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 31 13:46:37.682967 kernel: ASID allocator initialised with 32768 entries Oct 31 13:46:37.682974 kernel: ACPI: bus type PCI registered Oct 31 13:46:37.682982 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 31 13:46:37.682991 kernel: Serial: AMBA PL011 UART driver Oct 31 13:46:37.682997 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 31 13:46:37.683003 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 31 13:46:37.683010 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 31 13:46:37.683016 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 31 13:46:37.683022 kernel: cryptd: max_cpu_qlen set to 1000 Oct 31 13:46:37.683028 kernel: ACPI: Added _OSI(Module Device) Oct 31 13:46:37.683036 kernel: ACPI: Added _OSI(Processor Device) Oct 31 13:46:37.683054 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 31 13:46:37.683061 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 31 13:46:37.683069 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 31 13:46:37.683082 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 31 13:46:37.683090 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 31 13:46:37.683096 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 31 13:46:37.683102 kernel: ACPI: Interpreter enabled Oct 31 13:46:37.683108 kernel: ACPI: Using GIC for interrupt routing Oct 31 13:46:37.683114 kernel: ACPI: MCFG table detected, 1 entries Oct 31 13:46:37.683120 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 31 13:46:37.683126 kernel: printk: console [ttyAMA0] enabled Oct 31 13:46:37.683134 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 31 13:46:37.683239 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 31 13:46:37.683298 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 31 13:46:37.683353 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 31 13:46:37.683413 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 31 13:46:37.683466 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 31 13:46:37.683474 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 31 13:46:37.683483 kernel: PCI host bridge to bus 0000:00 Oct 31 13:46:37.683552 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 31 13:46:37.683603 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 31 13:46:37.683652 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 31 13:46:37.683738 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 31 13:46:37.683808 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 31 13:46:37.683870 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 31 13:46:37.683930 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 31 13:46:37.683986 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 31 13:46:37.684042 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 31 13:46:37.684097 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 31 13:46:37.684150 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 31 13:46:37.684205 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 31 13:46:37.684254 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 31 13:46:37.684303 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 31 13:46:37.684352 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 31 13:46:37.684360 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 31 13:46:37.684366 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 31 13:46:37.684372 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 31 13:46:37.684378 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 31 13:46:37.684384 kernel: vgaarb: loaded Oct 31 13:46:37.684390 kernel: Registered efivars operations Oct 31 13:46:37.684406 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 31 13:46:37.684414 kernel: VFS: Disk quotas dquot_6.6.0 Oct 31 13:46:37.684420 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 31 13:46:37.684426 kernel: pnp: PnP ACPI init Oct 31 13:46:37.684494 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 31 13:46:37.684565 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 31 13:46:37.684574 kernel: pnp: PnP ACPI: found 1 devices Oct 31 13:46:37.684580 kernel: NET: Registered protocol family 2 Oct 31 13:46:37.684587 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 31 13:46:37.684595 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 31 13:46:37.684602 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 31 13:46:37.684608 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 31 13:46:37.684614 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 31 13:46:37.684620 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 13:46:37.684626 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 13:46:37.684633 kernel: NET: Registered protocol family 1 Oct 31 13:46:37.684639 kernel: PCI: CLS 0 bytes, default 64 Oct 31 13:46:37.684645 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 31 13:46:37.684652 kernel: kvm [1]: HYP mode not available Oct 31 13:46:37.684658 kernel: Initialise system trusted keyrings Oct 31 13:46:37.684664 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 31 13:46:37.684670 kernel: Key type asymmetric registered Oct 31 13:46:37.684676 kernel: Asymmetric key parser 'x509' registered Oct 31 13:46:37.684682 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 31 13:46:37.684689 kernel: io scheduler mq-deadline registered Oct 31 13:46:37.684695 kernel: io scheduler kyber registered Oct 31 13:46:37.684701 kernel: io scheduler bfq registered Oct 31 13:46:37.684708 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 31 13:46:37.684714 kernel: ACPI: Power Button [PWRB] Oct 31 13:46:37.684720 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 31 13:46:37.684783 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 31 13:46:37.684792 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 31 13:46:37.684798 kernel: msm_serial: driver initialized Oct 31 13:46:37.684804 kernel: random: fast init done Oct 31 13:46:37.684810 kernel: random: crng init done Oct 31 13:46:37.684816 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 31 13:46:37.684822 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 31 13:46:37.684830 kernel: thunder_xcv, ver 1.0 Oct 31 13:46:37.684836 kernel: thunder_bgx, ver 1.0 Oct 31 13:46:37.684842 kernel: nicpf, ver 1.0 Oct 31 13:46:37.684848 kernel: nicvf, ver 1.0 Oct 31 13:46:37.684911 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 31 13:46:37.684963 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-31T13:46:37 UTC (1761918397) Oct 31 13:46:37.684971 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 31 13:46:37.684977 kernel: NET: Registered protocol family 10 Oct 31 13:46:37.684984 kernel: Segment Routing with IPv6 Oct 31 13:46:37.684990 kernel: NET: Registered protocol family 17 Oct 31 13:46:37.684996 kernel: Key type dns_resolver registered Oct 31 13:46:37.685002 kernel: registered taskstats version 1 Oct 31 13:46:37.685008 kernel: Loading compiled-in X.509 certificates Oct 31 13:46:37.685015 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 31 13:46:37.685021 kernel: Key type ._fscrypt registered Oct 31 13:46:37.685027 kernel: Key type .fscrypt registered Oct 31 13:46:37.685033 kernel: Key type fscrypt-provisioning registered Oct 31 13:46:37.685040 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 31 13:46:37.685046 kernel: ima: Allocated hash algorithm: sha1 Oct 31 13:46:37.685052 kernel: ima: No architecture policies found Oct 31 13:46:37.685058 kernel: Freeing unused kernel memory: 34240K Oct 31 13:46:37.685064 kernel: Run /init as init process Oct 31 13:46:37.685070 kernel: with arguments: Oct 31 13:46:37.685076 kernel: /init Oct 31 13:46:37.685082 kernel: with environment: Oct 31 13:46:37.685088 kernel: HOME=/ Oct 31 13:46:37.685094 kernel: TERM=linux Oct 31 13:46:37.685100 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 31 13:46:37.685109 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 13:46:37.685117 systemd[1]: Detected virtualization kvm. Oct 31 13:46:37.685124 systemd[1]: Detected architecture arm64. Oct 31 13:46:37.685130 systemd[1]: Running in initial RAM disk. Oct 31 13:46:37.685136 systemd[1]: No hostname configured, using default hostname. Oct 31 13:46:37.685143 systemd[1]: Hostname set to . Oct 31 13:46:37.685151 systemd[1]: Initializing machine ID from VM UUID. Oct 31 13:46:37.685157 systemd[1]: Queued start job for default target Initrd Default Target. Oct 31 13:46:37.685164 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 31 13:46:37.685170 systemd[1]: Reached target Local Encrypted Volumes. Oct 31 13:46:37.685176 systemd[1]: Reached target Path Units. Oct 31 13:46:37.685183 systemd[1]: Reached target Slice Units. Oct 31 13:46:37.685189 systemd[1]: Reached target Swaps. Oct 31 13:46:37.685195 systemd[1]: Reached target Timer Units. Oct 31 13:46:37.685203 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 31 13:46:37.685210 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 31 13:46:37.685216 systemd[1]: Listening on Journal Audit Socket. Oct 31 13:46:37.685223 systemd[1]: Listening on Journal Socket (/dev/log). Oct 31 13:46:37.685229 systemd[1]: Listening on Journal Socket. Oct 31 13:46:37.685236 systemd[1]: Listening on udev Control Socket. Oct 31 13:46:37.685242 systemd[1]: Listening on udev Kernel Socket. Oct 31 13:46:37.685249 systemd[1]: Reached target Socket Units. Oct 31 13:46:37.685255 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 31 13:46:37.685263 systemd[1]: Starting Create List of Static Device Nodes... Oct 31 13:46:37.685269 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 31 13:46:37.685276 systemd[1]: Starting Journal Service... Oct 31 13:46:37.685282 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 31 13:46:37.685288 systemd[1]: Starting Apply Kernel Variables... Oct 31 13:46:37.685295 systemd[1]: Starting Setup Virtual Console... Oct 31 13:46:37.685301 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 31 13:46:37.685307 kernel: SCSI subsystem initialized Oct 31 13:46:37.685314 kernel: Loading iSCSI transport class v2.0-870. Oct 31 13:46:37.685320 systemd[1]: Finished Create List of Static Device Nodes. Oct 31 13:46:37.685327 systemd[1]: Finished Apply Kernel Variables. Oct 31 13:46:37.685340 systemd-journald[269]: Journal started Oct 31 13:46:37.685376 systemd-journald[269]: Runtime Journal (/run/log/journal/8001e9a37b864cd180f895518023a4b7) is 6.0M, max 48.5M, 42.4M free. Oct 31 13:46:37.687573 systemd[1]: Started Journal Service. Oct 31 13:46:37.687933 systemd[1]: Finished Setup Virtual Console. Oct 31 13:46:37.689350 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 31 13:46:37.690917 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 31 13:46:37.694154 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 31 13:46:37.701190 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 31 13:46:37.702913 systemd[1]: Starting dracut cmdline hook... Oct 31 13:46:37.711563 dracut-cmdline[289]: dracut-dracut-053 Oct 31 13:46:37.713789 dracut-cmdline[289]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 31 13:46:37.770569 kernel: iscsi: registered transport (tcp) Oct 31 13:46:37.781571 kernel: iscsi: registered transport (qla4xxx) Oct 31 13:46:37.781612 kernel: QLogic iSCSI HBA Driver Oct 31 13:46:37.791362 systemd[1]: Finished dracut cmdline hook. Oct 31 13:46:37.792947 systemd[1]: Starting dracut pre-udev hook... Oct 31 13:46:37.794197 systemd[1]: Starting Open-iSCSI... Oct 31 13:46:37.796592 iscsid[375]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 31 13:46:37.796592 iscsid[375]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 31 13:46:37.796592 iscsid[375]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 31 13:46:37.796592 iscsid[375]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 31 13:46:37.796592 iscsid[375]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 31 13:46:37.808795 kernel: device-mapper: uevent: version 1.0.3 Oct 31 13:46:37.808821 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 31 13:46:37.808830 iscsid[375]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 31 13:46:37.800096 systemd[1]: Started Open-iSCSI. Oct 31 13:46:37.842558 kernel: raid6: neonx8 gen() 13801 MB/s Oct 31 13:46:37.859553 kernel: raid6: neonx8 xor() 10732 MB/s Oct 31 13:46:37.876558 kernel: raid6: neonx4 gen() 13601 MB/s Oct 31 13:46:37.893552 kernel: raid6: neonx4 xor() 11587 MB/s Oct 31 13:46:37.910553 kernel: raid6: neonx2 gen() 12993 MB/s Oct 31 13:46:37.927552 kernel: raid6: neonx2 xor() 9886 MB/s Oct 31 13:46:37.944548 kernel: raid6: neonx1 gen() 10989 MB/s Oct 31 13:46:37.961549 kernel: raid6: neonx1 xor() 8839 MB/s Oct 31 13:46:37.978550 kernel: raid6: int64x8 gen() 6829 MB/s Oct 31 13:46:37.995551 kernel: raid6: int64x8 xor() 3797 MB/s Oct 31 13:46:38.012548 kernel: raid6: int64x4 gen() 6914 MB/s Oct 31 13:46:38.029549 kernel: raid6: int64x4 xor() 3836 MB/s Oct 31 13:46:38.046552 kernel: raid6: int64x2 gen() 6168 MB/s Oct 31 13:46:38.063551 kernel: raid6: int64x2 xor() 3322 MB/s Oct 31 13:46:38.080552 kernel: raid6: int64x1 gen() 5009 MB/s Oct 31 13:46:38.097734 kernel: raid6: int64x1 xor() 2598 MB/s Oct 31 13:46:38.097749 kernel: raid6: using algorithm neonx8 gen() 13801 MB/s Oct 31 13:46:38.097758 kernel: raid6: .... xor() 10732 MB/s, rmw enabled Oct 31 13:46:38.098959 kernel: raid6: using neon recovery algorithm Oct 31 13:46:38.108555 kernel: xor: measuring software checksum speed Oct 31 13:46:38.109566 kernel: 8regs : 17148 MB/sec Oct 31 13:46:38.110551 kernel: 32regs : 20744 MB/sec Oct 31 13:46:38.111794 kernel: arm64_neon : 28957 MB/sec Oct 31 13:46:38.111811 kernel: xor: using function: arm64_neon (28957 MB/sec) Oct 31 13:46:38.156558 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 31 13:46:38.166309 systemd[1]: Finished dracut pre-udev hook. Oct 31 13:46:38.168254 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 31 13:46:38.171961 kernel: audit: type=1334 audit(1761918398.167:2): prog-id=6 op=LOAD Oct 31 13:46:38.171979 kernel: audit: type=1334 audit(1761918398.167:3): prog-id=7 op=LOAD Oct 31 13:46:38.171988 kernel: audit: type=1334 audit(1761918398.167:4): prog-id=8 op=LOAD Oct 31 13:46:38.167000 audit: BPF prog-id=6 op=LOAD Oct 31 13:46:38.167000 audit: BPF prog-id=7 op=LOAD Oct 31 13:46:38.167000 audit: BPF prog-id=8 op=LOAD Oct 31 13:46:38.181509 systemd-udevd[409]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 31 13:46:38.183152 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 31 13:46:38.185279 systemd[1]: Starting dracut pre-trigger hook... Oct 31 13:46:38.195864 dracut-pre-trigger[416]: rd.md=0: removing MD RAID activation Oct 31 13:46:38.217299 systemd[1]: Finished dracut pre-trigger hook. Oct 31 13:46:38.218767 systemd[1]: Starting Coldplug All udev Devices... Oct 31 13:46:38.228222 systemd-udevd[409]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 31 13:46:38.247280 systemd[1]: Finished Coldplug All udev Devices. Oct 31 13:46:38.248896 systemd[1]: Starting dracut initqueue hook... Oct 31 13:46:38.264831 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 31 13:46:38.264944 kernel: vda: detected capacity change from 0 to 4756340736 Oct 31 13:46:38.274871 systemd-udevd[454]: Using default interface naming scheme 'v249'. Oct 31 13:46:38.279556 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 13:46:38.301562 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (468) Oct 31 13:46:38.305952 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 31 13:46:38.307071 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 31 13:46:38.313284 systemd[1]: Finished dracut initqueue hook. Oct 31 13:46:38.316054 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 31 13:46:38.318646 systemd[1]: Reached target Initrd Root Device. Oct 31 13:46:38.319434 systemd[1]: Reached target Preparation for Remote File Systems. Oct 31 13:46:38.321044 systemd[1]: Reached target Remote Encrypted Volumes. Oct 31 13:46:38.322676 systemd[1]: Reached target Remote File Systems. Oct 31 13:46:38.324800 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 31 13:46:38.326337 systemd[1]: Starting dracut pre-mount hook... Oct 31 13:46:38.331659 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 31 13:46:38.331769 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 31 13:46:38.333588 systemd[1]: Reached target Preparation for Local File Systems. Oct 31 13:46:38.336011 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 31 13:46:38.337355 systemd[1]: Finished dracut pre-mount hook. Oct 31 13:46:38.339357 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 31 13:46:38.347832 systemd-fsck[500]: ROOT: clean, 640/553792 files, 39961/553472 blocks Oct 31 13:46:38.349411 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 31 13:46:38.352899 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 31 13:46:38.352582 systemd[1]: Mounting /sysroot... Oct 31 13:46:38.375626 systemd[1]: Found device /dev/mapper/usr. Oct 31 13:46:38.377000 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 31 13:46:38.379487 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 31 13:46:38.386682 systemd-fsck[516]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 31 13:46:38.386682 systemd-fsck[516]: You must have r/w access to the filesystem or be root Oct 31 13:46:38.390627 systemd-fsck[514]: fsck failed with exit status 8. Oct 31 13:46:38.390635 systemd-fsck[514]: Ignoring error. Oct 31 13:46:38.395730 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 31 13:46:38.395756 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 31 13:46:38.391497 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 31 13:46:38.393617 systemd[1]: Mounting /sysusr/usr... Oct 31 13:46:38.396976 systemd[1]: Mounted /sysroot. Oct 31 13:46:38.398099 systemd[1]: Reached target Initrd Root File System. Oct 31 13:46:38.399607 systemd[1]: Starting Reload Configuration from the Real Root... Oct 31 13:46:38.404946 systemd[1]: Reloading. Oct 31 13:46:38.407736 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 31 13:46:38.408000 audit: BPF prog-id=3 op=UNLOAD Oct 31 13:46:38.408000 audit: BPF prog-id=6 op=UNLOAD Oct 31 13:46:38.410675 kernel: audit: type=1334 audit(1761918398.408:5): prog-id=3 op=UNLOAD Oct 31 13:46:38.410696 kernel: audit: type=1334 audit(1761918398.408:6): prog-id=6 op=UNLOAD Oct 31 13:46:38.491000 audit: BPF prog-id=9 op=LOAD Oct 31 13:46:38.492000 audit: BPF prog-id=10 op=LOAD Oct 31 13:46:38.493725 kernel: audit: type=1334 audit(1761918398.491:7): prog-id=9 op=LOAD Oct 31 13:46:38.493749 kernel: audit: type=1334 audit(1761918398.492:8): prog-id=10 op=LOAD Oct 31 13:46:38.493758 kernel: audit: type=1334 audit(1761918398.493:9): prog-id=11 op=LOAD Oct 31 13:46:38.493000 audit: BPF prog-id=11 op=LOAD Oct 31 13:46:38.493000 audit: BPF prog-id=4 op=UNLOAD Oct 31 13:46:38.494556 kernel: audit: type=1334 audit(1761918398.493:10): prog-id=4 op=UNLOAD Oct 31 13:46:38.493000 audit: BPF prog-id=5 op=UNLOAD Oct 31 13:46:38.493000 audit: BPF prog-id=12 op=LOAD Oct 31 13:46:38.494000 audit: BPF prog-id=13 op=LOAD Oct 31 13:46:38.494000 audit: BPF prog-id=14 op=LOAD Oct 31 13:46:38.494000 audit: BPF prog-id=7 op=UNLOAD Oct 31 13:46:38.494000 audit: BPF prog-id=8 op=UNLOAD Oct 31 13:46:38.496441 systemd[1]: Mounted /sysusr/usr. Oct 31 13:46:38.498503 systemd[1]: Reached target Local File Systems. Oct 31 13:46:38.499333 systemd[1]: Reached target System Initialization. Oct 31 13:46:38.500834 systemd[1]: Reached target Basic System. Oct 31 13:46:38.503042 systemd[1]: Mounting /sysroot/usr... Oct 31 13:46:38.505382 systemd[1]: Mounted /sysroot/usr. Oct 31 13:46:38.513968 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 31 13:46:38.514083 systemd[1]: Finished Reload Configuration from the Real Root. Oct 31 13:46:38.516004 systemd[1]: Reached target Initrd File Systems. Oct 31 13:46:38.517465 systemd[1]: Reached target Initrd Default Target. Oct 31 13:46:38.519041 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 31 13:46:38.519666 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 31 13:46:38.529438 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 31 13:46:38.531084 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 31 13:46:38.536707 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 31 13:46:38.537687 systemd[1]: Stopped target Timer Units. Oct 31 13:46:38.539066 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 31 13:46:38.539170 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 31 13:46:38.540584 systemd[1]: Stopped target Initrd Default Target. Oct 31 13:46:38.542204 systemd[1]: Stopped target Basic System. Oct 31 13:46:38.543715 systemd[1]: Stopped target Initrd Root Device. Oct 31 13:46:38.545183 systemd[1]: Stopped target Path Units. Oct 31 13:46:38.546483 systemd[1]: Stopped target Remote File Systems. Oct 31 13:46:38.548025 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 31 13:46:38.549600 systemd[1]: Stopped target Slice Units. Oct 31 13:46:38.550995 systemd[1]: Stopped target Socket Units. Oct 31 13:46:38.552607 systemd[1]: Stopped target System Initialization. Oct 31 13:46:38.553999 systemd[1]: Stopped target Local File Systems. Oct 31 13:46:38.555396 systemd[1]: Stopped target Preparation for Local File Systems. Oct 31 13:46:38.557081 systemd[1]: Stopped target Swaps. Oct 31 13:46:38.558317 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 31 13:46:38.558437 systemd[1]: Stopped dracut pre-mount hook. Oct 31 13:46:38.559958 systemd[1]: Stopped target Local Encrypted Volumes. Oct 31 13:46:38.561209 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 31 13:46:38.565577 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 31 13:46:38.566733 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 31 13:46:38.566830 systemd[1]: Stopped dracut initqueue hook. Oct 31 13:46:38.570363 iscsid[375]: iscsid shutting down. Oct 31 13:46:38.568505 systemd[1]: Stopping Open-iSCSI... Oct 31 13:46:38.569745 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 31 13:46:38.569855 systemd[1]: Stopped Apply Kernel Variables. Oct 31 13:46:38.571173 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 31 13:46:38.571261 systemd[1]: Stopped Coldplug All udev Devices. Oct 31 13:46:38.572441 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 31 13:46:38.572528 systemd[1]: Stopped dracut pre-trigger hook. Oct 31 13:46:38.573789 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 31 13:46:38.578038 systemd[1]: iscsid.service: Deactivated successfully. Oct 31 13:46:38.578142 systemd[1]: Stopped Open-iSCSI. Oct 31 13:46:38.579752 systemd[1]: iscsid.socket: Deactivated successfully. Oct 31 13:46:38.579813 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 31 13:46:38.580954 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 31 13:46:38.591000 audit: BPF prog-id=12 op=UNLOAD Oct 31 13:46:38.583466 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 31 13:46:38.583584 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 31 13:46:38.584841 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 31 13:46:38.584950 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 31 13:46:38.586872 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 31 13:46:38.586978 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 31 13:46:38.588949 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 31 13:46:38.588996 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 31 13:46:38.590424 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 31 13:46:38.590462 systemd[1]: Closed udev Control Socket. Oct 31 13:46:38.591863 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 31 13:46:38.591894 systemd[1]: Closed udev Kernel Socket. Oct 31 13:46:38.593333 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 31 13:46:38.593374 systemd[1]: Stopped dracut pre-udev hook. Oct 31 13:46:38.594893 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 31 13:46:38.594931 systemd[1]: Stopped dracut cmdline hook. Oct 31 13:46:38.596285 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 31 13:46:38.596323 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 31 13:46:38.598824 systemd[1]: Starting Cleanup udev Database... Oct 31 13:46:38.599932 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 31 13:46:38.619000 audit: BPF prog-id=9 op=UNLOAD Oct 31 13:46:38.601563 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 31 13:46:38.601614 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 31 13:46:38.603411 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 31 13:46:38.603449 systemd[1]: Stopped Create List of Static Device Nodes. Oct 31 13:46:38.605190 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 13:46:38.605232 systemd[1]: Stopped Setup Virtual Console. Oct 31 13:46:38.606815 systemd[1]: rngd.service: Deactivated successfully. Oct 31 13:46:38.606918 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 31 13:46:38.608614 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 31 13:46:38.608709 systemd[1]: Finished Cleanup udev Database. Oct 31 13:46:38.609940 systemd[1]: Reached target Switch Root. Oct 31 13:46:38.611915 systemd[1]: Starting Switch Root... Oct 31 13:46:38.617343 systemd[1]: Switching root. Oct 31 13:46:38.632754 systemd-journald[269]: Journal stopped Oct 31 13:46:40.732440 systemd-journald[269]: Received SIGTERM from PID 1 (systemd). Oct 31 13:46:40.732482 kernel: SELinux: policy capability network_peer_controls=1 Oct 31 13:46:40.732494 kernel: SELinux: policy capability open_perms=1 Oct 31 13:46:40.732504 kernel: SELinux: policy capability extended_socket_class=1 Oct 31 13:46:40.732516 kernel: SELinux: policy capability always_check_network=0 Oct 31 13:46:40.732526 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 31 13:46:40.732550 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 31 13:46:40.732560 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 31 13:46:40.732570 systemd[1]: Successfully loaded SELinux policy in 30.519ms. Oct 31 13:46:40.732584 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 5.989ms. Oct 31 13:46:40.732596 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 13:46:40.732606 systemd[1]: Detected virtualization kvm. Oct 31 13:46:40.732617 systemd[1]: Detected architecture arm64. Oct 31 13:46:40.732629 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 13:46:40.732640 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 31 13:46:40.732650 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 31 13:46:40.732659 systemd[1]: Stopped Switch Root. Oct 31 13:46:40.732669 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 31 13:46:40.732679 systemd[1]: Created slice Slice /system/addon-config. Oct 31 13:46:40.732689 systemd[1]: Created slice Slice /system/addon-run. Oct 31 13:46:40.732699 systemd[1]: Created slice Slice /system/getty. Oct 31 13:46:40.732708 systemd[1]: Created slice Slice /system/modprobe. Oct 31 13:46:40.732718 systemd[1]: Created slice Slice /system/serial-getty. Oct 31 13:46:40.732727 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 31 13:46:40.732737 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 31 13:46:40.732746 systemd[1]: Created slice User and Session Slice. Oct 31 13:46:40.732756 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 31 13:46:40.732766 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 31 13:46:40.732775 systemd[1]: Set up automount Boot partition Automount Point. Oct 31 13:46:40.732786 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 31 13:46:40.732796 systemd[1]: Stopped target Switch Root. Oct 31 13:46:40.732807 systemd[1]: Stopped target Initrd File Systems. Oct 31 13:46:40.732817 systemd[1]: Stopped target Initrd Root File System. Oct 31 13:46:40.732827 systemd[1]: Reached target Remote Encrypted Volumes. Oct 31 13:46:40.732836 systemd[1]: Reached target Remote File Systems. Oct 31 13:46:40.732846 systemd[1]: Reached target Slice Units. Oct 31 13:46:40.732855 systemd[1]: Reached target Swaps. Oct 31 13:46:40.732865 systemd[1]: Reached target Verify torcx succeeded. Oct 31 13:46:40.732876 systemd[1]: Reached target Local Verity Protected Volumes. Oct 31 13:46:40.732886 systemd[1]: Listening on Process Core Dump Socket. Oct 31 13:46:40.732895 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 31 13:46:40.732904 systemd[1]: Listening on Network Service Netlink Socket. Oct 31 13:46:40.732914 systemd[1]: Listening on udev Control Socket. Oct 31 13:46:40.732924 systemd[1]: Listening on udev Kernel Socket. Oct 31 13:46:40.732933 systemd[1]: Mounting Huge Pages File System... Oct 31 13:46:40.732942 systemd[1]: Mounting POSIX Message Queue File System... Oct 31 13:46:40.732952 systemd[1]: Mounting External Media Directory... Oct 31 13:46:40.732964 systemd[1]: Mounting Kernel Debug File System... Oct 31 13:46:40.732975 systemd[1]: Mounting Kernel Trace File System... Oct 31 13:46:40.732985 systemd[1]: Mounting Temporary Directory /tmp... Oct 31 13:46:40.732994 systemd[1]: Starting Create missing system files... Oct 31 13:46:40.733004 systemd[1]: Starting Create List of Static Device Nodes... Oct 31 13:46:40.733014 systemd[1]: Starting Load Kernel Module configfs... Oct 31 13:46:40.733024 systemd[1]: Starting Load Kernel Module drm... Oct 31 13:46:40.733033 systemd[1]: Starting Load Kernel Module fuse... Oct 31 13:46:40.733043 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 31 13:46:40.733053 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 31 13:46:40.733064 systemd[1]: Stopped File System Check on Root Device. Oct 31 13:46:40.733073 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 31 13:46:40.733083 systemd[1]: Stopped systemd-fsck-usr.service. Oct 31 13:46:40.733092 systemd[1]: Stopped Journal Service. Oct 31 13:46:40.733101 kernel: fuse: init (API version 7.32) Oct 31 13:46:40.733110 systemd[1]: Starting Journal Service... Oct 31 13:46:40.733120 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 31 13:46:40.733129 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 31 13:46:40.733139 systemd[1]: Starting Apply Kernel Variables... Oct 31 13:46:40.733148 systemd[1]: Starting Coldplug All udev Devices... Oct 31 13:46:40.733158 systemd[1]: verity-setup.service: Deactivated successfully. Oct 31 13:46:40.733168 systemd[1]: Stopped verity-setup.service. Oct 31 13:46:40.733177 systemd[1]: Mounted Huge Pages File System. Oct 31 13:46:40.733191 systemd-journald[692]: Journal started Oct 31 13:46:40.733228 systemd-journald[692]: Runtime Journal (/run/log/journal/8001e9a37b864cd180f895518023a4b7) is 6.0M, max 48.5M, 42.4M free. Oct 31 13:46:38.703000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 31 13:46:38.729000 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 13:46:38.729000 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 13:46:38.729000 audit: BPF prog-id=15 op=LOAD Oct 31 13:46:38.729000 audit: BPF prog-id=15 op=UNLOAD Oct 31 13:46:38.729000 audit: BPF prog-id=16 op=LOAD Oct 31 13:46:38.729000 audit: BPF prog-id=16 op=UNLOAD Oct 31 13:46:38.763000 audit[644]: AVC avc: denied { associate } for pid=644 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 31 13:46:40.591000 audit: BPF prog-id=17 op=LOAD Oct 31 13:46:40.591000 audit: BPF prog-id=18 op=LOAD Oct 31 13:46:40.591000 audit: BPF prog-id=19 op=LOAD Oct 31 13:46:40.591000 audit: BPF prog-id=10 op=UNLOAD Oct 31 13:46:40.591000 audit: BPF prog-id=11 op=UNLOAD Oct 31 13:46:40.602000 audit: BPF prog-id=20 op=LOAD Oct 31 13:46:40.602000 audit: BPF prog-id=17 op=UNLOAD Oct 31 13:46:40.602000 audit: BPF prog-id=21 op=LOAD Oct 31 13:46:40.602000 audit: BPF prog-id=22 op=LOAD Oct 31 13:46:40.602000 audit: BPF prog-id=18 op=UNLOAD Oct 31 13:46:40.602000 audit: BPF prog-id=19 op=UNLOAD Oct 31 13:46:40.612000 audit: BPF prog-id=20 op=UNLOAD Oct 31 13:46:40.716000 audit: BPF prog-id=23 op=LOAD Oct 31 13:46:40.716000 audit: BPF prog-id=24 op=LOAD Oct 31 13:46:40.716000 audit: BPF prog-id=25 op=LOAD Oct 31 13:46:40.716000 audit: BPF prog-id=21 op=UNLOAD Oct 31 13:46:40.716000 audit: BPF prog-id=22 op=UNLOAD Oct 31 13:46:40.731000 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 13:46:40.590905 systemd[1]: Queued start job for default target Multi-User System. Oct 31 13:46:38.762180 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" 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 13:46:40.603406 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 31 13:46:38.762464 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 31 13:46:38.762484 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 31 13:46:38.762846 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 31 13:46:38.762858 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 31 13:46:38.762894 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 31 13:46:38.762908 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 31 13:46:38.763222 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 31 13:46:38.763262 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 31 13:46:38.763277 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 31 13:46:38.763685 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" 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 13:46:38.763714 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" 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 13:46:38.763734 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" 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 13:46:38.763750 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" 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 13:46:38.763842 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" 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 13:46:38.763859 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:38Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 31 13:46:40.449325 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:40Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 31 13:46:40.449641 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:40Z" 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 13:46:40.449749 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:40Z" 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 13:46:40.449883 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:40Z" 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 13:46:40.449979 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:40Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 31 13:46:40.450039 /usr/lib/systemd/system-generators/torcx-generator[644]: time="2025-10-31T13:46:40Z" 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 13:46:40.735691 systemd[1]: Started Journal Service. Oct 31 13:46:40.736218 systemd[1]: Mounted POSIX Message Queue File System. Oct 31 13:46:40.737340 systemd[1]: Mounted External Media Directory. Oct 31 13:46:40.738317 systemd[1]: Mounted Kernel Debug File System. Oct 31 13:46:40.739260 systemd[1]: Mounted Kernel Trace File System. Oct 31 13:46:40.740218 systemd[1]: Mounted Temporary Directory /tmp. Oct 31 13:46:40.741401 systemd[1]: Finished Create missing system files. Oct 31 13:46:40.742711 systemd[1]: Finished Create List of Static Device Nodes. Oct 31 13:46:40.743858 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 31 13:46:40.744058 systemd[1]: Finished Load Kernel Module configfs. Oct 31 13:46:40.745175 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 13:46:40.745392 systemd[1]: Finished Load Kernel Module drm. Oct 31 13:46:40.754883 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 31 13:46:40.755081 systemd[1]: Finished Load Kernel Module fuse. Oct 31 13:46:40.756323 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 31 13:46:40.757737 systemd[1]: Finished Apply Kernel Variables. Oct 31 13:46:40.760783 systemd[1]: Mounting FUSE Control File System... Oct 31 13:46:40.762521 systemd[1]: Mounting Kernel Configuration File System... Oct 31 13:46:40.763403 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 31 13:46:40.764308 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 31 13:46:40.765573 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 31 13:46:40.766696 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 31 13:46:40.767768 systemd[1]: Starting Load/Save Random Seed... Oct 31 13:46:40.768659 systemd[1]: Condition check resulted in Create System Users being skipped. Oct 31 13:46:40.769690 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 31 13:46:40.770614 systemd-journald[692]: Time spent on flushing to /var/log/journal/8001e9a37b864cd180f895518023a4b7 is 12.852ms for 662 entries. Oct 31 13:46:40.770614 systemd-journald[692]: System Journal (/var/log/journal/8001e9a37b864cd180f895518023a4b7) is 8.0M, max 203.0M, 195.0M free. Oct 31 13:46:40.793000 audit: BPF prog-id=26 op=LOAD Oct 31 13:46:40.794000 audit: BPF prog-id=27 op=LOAD Oct 31 13:46:40.794000 audit: BPF prog-id=28 op=LOAD Oct 31 13:46:40.794000 audit: BPF prog-id=13 op=UNLOAD Oct 31 13:46:40.794000 audit: BPF prog-id=14 op=UNLOAD Oct 31 13:46:40.771552 systemd[1]: Mounted FUSE Control File System. Oct 31 13:46:40.774316 systemd[1]: Mounted Kernel Configuration File System. Oct 31 13:46:40.777823 systemd[1]: Finished Load/Save Random Seed. Oct 31 13:46:40.779182 systemd[1]: Finished Coldplug All udev Devices. Oct 31 13:46:40.780257 systemd[1]: Condition check resulted in First Boot Complete being skipped. Oct 31 13:46:40.790333 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 31 13:46:40.792163 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 31 13:46:40.795188 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 31 13:46:40.798225 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 31 13:46:40.818905 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 31 13:46:40.820000 audit: BPF prog-id=29 op=LOAD Oct 31 13:46:40.821597 systemd[1]: Starting Network Configuration... Oct 31 13:46:40.851122 systemd-udevd[708]: Using default interface naming scheme 'v249'. Oct 31 13:46:40.854570 systemd-networkd[714]: lo: Link UP Oct 31 13:46:40.854577 systemd-networkd[714]: lo: Gained carrier Oct 31 13:46:40.854886 systemd-networkd[714]: Enumeration completed Oct 31 13:46:40.854972 systemd[1]: Started Network Configuration. Oct 31 13:46:40.856796 systemd-networkd[714]: eth0: Link UP Oct 31 13:46:40.863599 systemd-networkd[714]: eth0: Gained carrier Oct 31 13:46:40.869674 systemd-networkd[714]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Oct 31 13:46:40.893570 udevadm[705]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 31 13:46:40.921641 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 31 13:46:40.930471 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 31 13:46:40.939175 lvm[733]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 13:46:40.968439 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 31 13:46:40.969588 systemd[1]: Reached target Local Encrypted Volumes. Oct 31 13:46:40.982457 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 31 13:46:40.985631 lvm[734]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 13:46:41.010510 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 31 13:46:41.011585 systemd[1]: Reached target Preparation for Local File Systems. Oct 31 13:46:41.012593 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 31 13:46:41.012619 systemd[1]: Reached target Containers. Oct 31 13:46:41.023748 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 31 13:46:41.035975 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 31 13:46:41.038281 systemd[1]: Mounting /usr/share/oem... Oct 31 13:46:41.043557 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 31 13:46:41.043617 kernel: BTRFS info (device vda6): has skinny extents Oct 31 13:46:41.046456 systemd[1]: Mounted /usr/share/oem. Oct 31 13:46:41.047347 systemd[1]: Reached target Local File Systems. Oct 31 13:46:41.048389 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Oct 31 13:46:41.048747 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 31 13:46:41.048784 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 31 13:46:41.048813 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 31 13:46:41.049822 systemd[1]: Starting Create Volatile Files and Directories... Oct 31 13:46:41.055606 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 31 13:46:41.056617 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 31 13:46:41.083049 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Oct 31 13:46:41.083062 systemd-tmpfiles[757]: Skipping /boot Oct 31 13:46:41.088620 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Oct 31 13:46:41.088636 systemd-tmpfiles[757]: Skipping /boot Oct 31 13:46:41.116817 systemd[1]: Finished Create Volatile Files and Directories. Oct 31 13:46:41.124409 systemd[1]: Starting Load Security Auditing Rules... Oct 31 13:46:41.126295 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 31 13:46:41.127417 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Oct 31 13:46:41.127000 audit: BPF prog-id=30 op=LOAD Oct 31 13:46:41.129012 systemd[1]: Starting Network Name Resolution... Oct 31 13:46:41.129000 audit: BPF prog-id=31 op=LOAD Oct 31 13:46:41.132653 systemd[1]: Starting Network Time Synchronization... Oct 31 13:46:41.133509 systemd[1]: Condition check resulted in Update is Completed being skipped. Oct 31 13:46:41.134412 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 31 13:46:41.135843 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 31 13:46:41.137103 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 31 13:46:41.143714 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 31 13:46:41.148000 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 13:46:41.149077 augenrules[778]: No rules Oct 31 13:46:41.149936 systemd[1]: Finished Load Security Auditing Rules. Oct 31 13:46:41.169499 systemd[1]: Started Network Time Synchronization. Oct 31 13:46:41.170049 systemd-timesyncd[767]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 31 13:46:41.170668 systemd[1]: Reached target System Initialization. Oct 31 13:46:41.170677 systemd-resolved[761]: Positive Trust Anchors: Oct 31 13:46:41.170683 systemd-resolved[761]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 13:46:41.170710 systemd-resolved[761]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 31 13:46:41.171617 systemd-resolved[761]: Defaulting to hostname 'linux'. Oct 31 13:46:41.171788 systemd[1]: Started Watch for update engine configuration changes. Oct 31 13:46:41.172855 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 31 13:46:41.174003 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 31 13:46:41.174969 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 31 13:46:41.174999 systemd[1]: Reached target Path Units. Oct 31 13:46:41.175732 systemd[1]: Reached target System Time Set. Oct 31 13:46:41.176719 systemd[1]: Started Daily Log Rotation. Oct 31 13:46:41.177506 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 31 13:46:41.178615 systemd[1]: Reached target Timer Units. Oct 31 13:46:41.179648 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 31 13:46:41.181309 systemd[1]: Starting Docker Socket for the API... Oct 31 13:46:41.184262 systemd[1]: Listening on OpenSSH Server Socket. Oct 31 13:46:41.185527 systemd[1]: Started Network Name Resolution. Oct 31 13:46:41.186429 systemd[1]: Listening on Docker Socket for the API. Oct 31 13:46:41.187418 systemd[1]: Reached target Network. Oct 31 13:46:41.188175 systemd[1]: Reached target Host and Network Name Lookups. Oct 31 13:46:41.189133 systemd[1]: Reached target Socket Units. Oct 31 13:46:41.189899 systemd[1]: Reached target Basic System. Oct 31 13:46:41.190692 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 31 13:46:41.190724 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 31 13:46:41.191593 systemd[1]: Started D-Bus System Message Bus. Oct 31 13:46:41.194695 systemd[1]: Starting Extend Filesystems... Oct 31 13:46:41.195465 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 31 13:46:41.196380 systemd[1]: Starting Generate /run/flatcar/motd... Oct 31 13:46:41.198154 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 31 13:46:41.200107 systemd[1]: Starting Generate sshd host keys... Oct 31 13:46:41.201025 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 31 13:46:41.201060 systemd[1]: Reached target Load system-provided cloud configs. Oct 31 13:46:41.204058 systemd[1]: Starting User Login Management... Oct 31 13:46:41.205287 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 31 13:46:41.205885 systemd[1]: Starting Update Engine... Oct 31 13:46:41.206981 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 31 13:46:41.207010 systemd[1]: Reached target Load user-provided cloud configs. Oct 31 13:46:41.208867 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 31 13:46:41.209081 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 31 13:46:41.212102 extend-filesystems[788]: Found vda Oct 31 13:46:41.214610 extend-filesystems[788]: Found vda1 Oct 31 13:46:41.214610 extend-filesystems[788]: Found vda2 Oct 31 13:46:41.214610 extend-filesystems[788]: Found vda3 Oct 31 13:46:41.214610 extend-filesystems[788]: Found usr Oct 31 13:46:41.214610 extend-filesystems[788]: Found vda4 Oct 31 13:46:41.214610 extend-filesystems[788]: Found vda6 Oct 31 13:46:41.214610 extend-filesystems[788]: Found vda7 Oct 31 13:46:41.222480 extend-filesystems[788]: Found vda9 Oct 31 13:46:41.222480 extend-filesystems[788]: Checking size of /dev/vda9 Oct 31 13:46:41.220104 systemd[1]: motdgen.service: Deactivated successfully. Oct 31 13:46:41.220295 systemd[1]: Finished Generate /run/flatcar/motd. Oct 31 13:46:41.230754 extend-filesystems[788]: Old size kept for /dev/vda9 Oct 31 13:46:41.234916 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 31 13:46:41.235133 systemd[1]: Finished Extend Filesystems. Oct 31 13:46:41.253440 update_engine[803]: I1031 13:46:41.253171 803 main.cc:89] Flatcar Update Engine starting Oct 31 13:46:41.254480 update_engine[803]: I1031 13:46:41.254415 803 payload_state.cc:360] Current Response Signature = Oct 31 13:46:41.254480 update_engine[803]: NumURLs = 1 Oct 31 13:46:41.254480 update_engine[803]: Url0 = http://10.0.0.4:34567/packages/update.gz Oct 31 13:46:41.254480 update_engine[803]: Payload Size = 426251591 Oct 31 13:46:41.254480 update_engine[803]: Payload Sha256 Hash = qwysf93IzFKZIIEIOAr8FB/Ur4+2vzR6efmfEvB0U+Q= Oct 31 13:46:41.254480 update_engine[803]: Is Delta Payload = 0 Oct 31 13:46:41.254480 update_engine[803]: Max Failure Count Per Url = 10 Oct 31 13:46:41.254480 update_engine[803]: Disable Payload Backoff = 1 Oct 31 13:46:41.254771 systemd[1]: Finished Generate sshd host keys. Oct 31 13:46:41.255019 update_engine[803]: I1031 13:46:41.255005 803 payload_state.cc:381] Payload Attempt Number = 0 Oct 31 13:46:41.255014 systemd-logind[801]: Watching system buttons on /dev/input/event0 (Power Button) Oct 31 13:46:41.255199 systemd-logind[801]: New seat seat0. Oct 31 13:46:41.257059 update_engine[803]: I1031 13:46:41.257038 803 payload_state.cc:404] Current URL Index = 0 Oct 31 13:46:41.258824 update_engine[803]: I1031 13:46:41.258795 803 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 31 13:46:41.259041 update_engine[803]: I1031 13:46:41.259007 803 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 31 13:46:41.260372 systemd[1]: Starting Generate /run/issue... Oct 31 13:46:41.261376 update_engine[803]: I1031 13:46:41.261245 803 update_check_scheduler.cc:74] Next update check in 8m1s Oct 31 13:46:41.261434 systemd[1]: Started User Login Management. Oct 31 13:46:41.262436 systemd[1]: Started Update Engine. Oct 31 13:46:41.264587 systemd[1]: issuegen.service: Deactivated successfully. Oct 31 13:46:41.264787 systemd[1]: Finished Generate /run/issue. Oct 31 13:46:41.266699 systemd[1]: Starting Permit User Sessions... Oct 31 13:46:41.270583 systemd[1]: Finished Permit User Sessions. Oct 31 13:46:41.272458 systemd[1]: Started Getty on tty1. Oct 31 13:46:41.274380 systemd[1]: Started Serial Getty on ttyAMA0. Oct 31 13:46:41.275472 systemd[1]: Reached target Login Prompts. Oct 31 13:46:41.276423 systemd[1]: Reached target Multi-User System. Oct 31 13:46:41.278354 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 31 13:46:41.282679 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 31 13:46:41.282881 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 31 13:46:41.283863 systemd[1]: Startup finished in 699ms (kernel) + 1.111s (initrd) + 2.612s (userspace) = 4.424s. Oct 31 13:46:42.139778 systemd-networkd[714]: eth0: Gained IPv6LL Oct 31 13:46:50.787444 systemd[1]: Created slice Slice /system/sshd. Oct 31 13:46:50.788433 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:54808). Oct 31 13:46:50.831498 sshd[828]: Accepted publickey for core from 10.0.0.1 port 54808 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:50.833312 sshd[828]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:50.854017 systemd[1]: Created slice User Slice of UID 500. Oct 31 13:46:50.855048 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 31 13:46:50.856717 systemd-logind[801]: New session 1 of user core. Oct 31 13:46:50.861027 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 31 13:46:50.862207 systemd[1]: Starting User Manager for UID 500... Oct 31 13:46:50.864484 systemd[831]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:50.910727 systemd[831]: Queued start job for default target Main User Target. Oct 31 13:46:50.910892 systemd[831]: Reached target Paths. Oct 31 13:46:50.910905 systemd[831]: Reached target Sockets. Oct 31 13:46:50.910916 systemd[831]: Reached target Timers. Oct 31 13:46:50.910926 systemd[831]: Reached target Basic System. Oct 31 13:46:50.910970 systemd[831]: Reached target Main User Target. Oct 31 13:46:50.910983 systemd[831]: Startup finished in 42ms. Oct 31 13:46:50.911053 systemd[1]: Started User Manager for UID 500. Oct 31 13:46:50.921701 systemd[1]: Started Session 1 of User core. Oct 31 13:46:50.988773 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:54818). Oct 31 13:46:51.029375 sshd[840]: Accepted publickey for core from 10.0.0.1 port 54818 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:51.030415 sshd[840]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:51.033588 systemd-logind[801]: New session 2 of user core. Oct 31 13:46:51.043701 systemd[1]: Started Session 2 of User core. Oct 31 13:46:51.095864 sshd[840]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:51.108101 systemd[1]: sshd@1-10.0.0.5:22-10.0.0.1:54818.service: Deactivated successfully. Oct 31 13:46:51.108768 systemd[1]: session-2.scope: Deactivated successfully. Oct 31 13:46:51.109334 systemd-logind[801]: Session 2 logged out. Waiting for processes to exit. Oct 31 13:46:51.110322 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:54824). Oct 31 13:46:51.110930 systemd-logind[801]: Removed session 2. Oct 31 13:46:51.151220 sshd[846]: Accepted publickey for core from 10.0.0.1 port 54824 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:51.152234 sshd[846]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:51.155053 systemd-logind[801]: New session 3 of user core. Oct 31 13:46:51.160698 systemd[1]: Started Session 3 of User core. Oct 31 13:46:51.208312 sshd[846]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:51.226169 systemd[1]: sshd@2-10.0.0.5:22-10.0.0.1:54824.service: Deactivated successfully. Oct 31 13:46:51.226786 systemd[1]: session-3.scope: Deactivated successfully. Oct 31 13:46:51.227325 systemd-logind[801]: Session 3 logged out. Waiting for processes to exit. Oct 31 13:46:51.228321 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:54828). Oct 31 13:46:51.228936 systemd-logind[801]: Removed session 3. Oct 31 13:46:51.268405 sshd[852]: Accepted publickey for core from 10.0.0.1 port 54828 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:51.269975 sshd[852]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:51.272689 systemd-logind[801]: New session 4 of user core. Oct 31 13:46:51.283688 systemd[1]: Started Session 4 of User core. Oct 31 13:46:51.333951 sshd[852]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:51.345221 systemd[1]: sshd@3-10.0.0.5:22-10.0.0.1:54828.service: Deactivated successfully. Oct 31 13:46:51.345937 systemd[1]: session-4.scope: Deactivated successfully. Oct 31 13:46:51.346596 systemd-logind[801]: Session 4 logged out. Waiting for processes to exit. Oct 31 13:46:51.347679 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:54840). Oct 31 13:46:51.348329 systemd-logind[801]: Removed session 4. Oct 31 13:46:51.389414 sshd[858]: Accepted publickey for core from 10.0.0.1 port 54840 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:51.390648 sshd[858]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:51.393240 systemd-logind[801]: New session 5 of user core. Oct 31 13:46:51.403678 systemd[1]: Started Session 5 of User core. Oct 31 13:46:51.459435 sudo[861]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 31 13:46:51.459671 sudo[861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 13:46:51.467997 sudo[861]: pam_unix(sudo:session): session closed for user root Oct 31 13:46:51.469676 sshd[858]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:51.481982 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:54856). Oct 31 13:46:51.484240 dbus-daemon[787]: [system] Reloaded configuration Oct 31 13:46:51.486795 systemd[1]: sshd@4-10.0.0.5:22-10.0.0.1:54840.service: Deactivated successfully. Oct 31 13:46:51.487594 systemd[1]: session-5.scope: Deactivated successfully. Oct 31 13:46:51.488326 systemd-logind[801]: Session 5 logged out. Waiting for processes to exit. Oct 31 13:46:51.489187 systemd-logind[801]: Removed session 5. Oct 31 13:46:51.523042 sshd[864]: Accepted publickey for core from 10.0.0.1 port 54856 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:51.524043 sshd[864]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:51.526706 systemd-logind[801]: New session 6 of user core. Oct 31 13:46:51.535693 systemd[1]: Started Session 6 of User core. Oct 31 13:46:51.586664 sudo[869]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 31 13:46:51.586869 sudo[869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 13:46:51.589147 sudo[869]: pam_unix(sudo:session): session closed for user root Oct 31 13:46:51.592698 sudo[868]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 31 13:46:51.593123 sudo[868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 31 13:46:51.609451 systemd[1]: Stopping Load Security Auditing Rules... Oct 31 13:46:51.610000 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 13:46:51.611824 auditctl[872]: No rules Oct 31 13:46:51.612023 systemd[1]: audit-rules.service: Deactivated successfully. Oct 31 13:46:51.612256 systemd[1]: Stopped Load Security Auditing Rules. Oct 31 13:46:51.613096 kernel: kauditd_printk_skb: 43 callbacks suppressed Oct 31 13:46:51.613137 kernel: audit: type=1305 audit(1761918411.610:54): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 31 13:46:51.613570 systemd[1]: Starting Load Security Auditing Rules... Oct 31 13:46:51.627678 augenrules[889]: No rules Oct 31 13:46:51.629898 systemd[1]: Finished Load Security Auditing Rules. Oct 31 13:46:51.631054 sudo[868]: pam_unix(sudo:session): session closed for user root Oct 31 13:46:51.632247 sshd[864]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:51.634583 systemd[1]: sshd@5-10.0.0.5:22-10.0.0.1:54856.service: Deactivated successfully. Oct 31 13:46:51.635096 systemd[1]: session-6.scope: Deactivated successfully. Oct 31 13:46:51.635695 systemd-logind[801]: Session 6 logged out. Waiting for processes to exit. Oct 31 13:46:51.636637 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:54864). Oct 31 13:46:51.637237 systemd-logind[801]: Removed session 6. Oct 31 13:46:51.676395 sshd[895]: Accepted publickey for core from 10.0.0.1 port 54864 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:51.677313 sshd[895]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:51.679552 kernel: audit: type=1006 audit(1761918411.675:55): pid=895 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=7 res=1 Oct 31 13:46:51.681494 systemd-logind[801]: New session 7 of user core. Oct 31 13:46:51.687691 systemd[1]: Started Session 7 of User core. Oct 31 13:46:51.734967 sshd[895]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:51.743240 systemd[1]: sshd@6-10.0.0.5:22-10.0.0.1:54864.service: Deactivated successfully. Oct 31 13:46:51.743842 systemd[1]: session-7.scope: Deactivated successfully. Oct 31 13:46:51.744454 systemd-logind[801]: Session 7 logged out. Waiting for processes to exit. Oct 31 13:46:51.745479 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:54868). Oct 31 13:46:51.746054 systemd-logind[801]: Removed session 7. Oct 31 13:46:51.785425 sshd[901]: Accepted publickey for core from 10.0.0.1 port 54868 ssh2: RSA SHA256:RGa9sxHKD4I3RczgGURS4Vfpgc/qh1d4A7aamBEGjNI Oct 31 13:46:51.788903 sshd[901]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 31 13:46:51.789552 kernel: audit: type=1006 audit(1761918411.784:56): pid=901 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=8 res=1 Oct 31 13:46:51.791428 systemd-logind[801]: New session 8 of user core. Oct 31 13:46:51.809685 systemd[1]: Started Session 8 of User core. Oct 31 13:46:51.857755 sshd[901]: pam_unix(sshd:session): session closed for user core Oct 31 13:46:51.860282 systemd[1]: sshd@7-10.0.0.5:22-10.0.0.1:54868.service: Deactivated successfully. Oct 31 13:46:51.861139 systemd[1]: session-8.scope: Deactivated successfully. Oct 31 13:46:51.861879 systemd-logind[801]: Session 8 logged out. Waiting for processes to exit. Oct 31 13:46:51.862636 systemd-logind[801]: Removed session 8.